Feb 22 12:24:15 zenith kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Feb 22 12:24:15 zenith 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 Feb 22 12:24:15 zenith kernel: KASLR enabled Feb 22 12:24:15 zenith kernel: random: crng init done Feb 22 12:24:15 zenith kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Feb 22 12:24:15 zenith kernel: efi: UEFI not found. Feb 22 12:24:15 zenith kernel: Reserved memory: created CMA memory pool at 0x000000003bc00000, size 64 MiB Feb 22 12:24:15 zenith kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 22 12:24:15 zenith kernel: OF: reserved mem: 0x000000003bc00000..0x000000003fbfffff (65536 KiB) map reusable linux,cma Feb 22 12:24:15 zenith kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Feb 22 12:24:15 zenith kernel: OF: reserved mem: 0x000000003fd23240..0x000000003fd23277 (0 KiB) nomap non-reusable nvram@0 Feb 22 12:24:15 zenith kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff] Feb 22 12:24:15 zenith kernel: Faking node 0 at [mem 0x0000000000000000-0x000000003fffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 1 at [mem 0x0000000040000000-0x000000007fffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 2 at [mem 0x0000000080000000-0x00000000bfffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 3 at [mem 0x00000000c0000000-0x00000000ffffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 4 at [mem 0x0000000100000000-0x000000013fffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 5 at [mem 0x0000000140000000-0x000000017fffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 6 at [mem 0x0000000180000000-0x00000001bfffffff] (1024MB) Feb 22 12:24:15 zenith kernel: Faking node 7 at [mem 0x00000001c0000000-0x00000001ffffffff] (1024MB) Feb 22 12:24:15 zenith kernel: NUMA: Initialized distance table, cnt=8 Feb 22 12:24:15 zenith kernel: NODE_DATA(0) allocated [mem 0x3bbfd300-0x3bbfffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(1) allocated [mem 0x7fffd300-0x7fffffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(2) allocated [mem 0xbfffd300-0xbfffffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(3) allocated [mem 0xffffd300-0xffffffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(4) allocated [mem 0x13fffd300-0x13fffffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(5) allocated [mem 0x17fffd300-0x17fffffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(6) allocated [mem 0x1bfffd300-0x1bfffffff] Feb 22 12:24:15 zenith kernel: NODE_DATA(7) allocated [mem 0x1fefa1300-0x1fefa3fff] Feb 22 12:24:15 zenith kernel: Zone ranges: Feb 22 12:24:15 zenith kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Feb 22 12:24:15 zenith kernel: DMA32 empty Feb 22 12:24:15 zenith kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Feb 22 12:24:15 zenith kernel: Movable zone start for each node Feb 22 12:24:15 zenith kernel: Early memory node ranges Feb 22 12:24:15 zenith kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Feb 22 12:24:15 zenith kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Feb 22 12:24:15 zenith kernel: node 1: [mem 0x0000000040000000-0x000000007fffffff] Feb 22 12:24:15 zenith kernel: node 2: [mem 0x0000000080000000-0x00000000bfffffff] Feb 22 12:24:15 zenith kernel: node 3: [mem 0x00000000c0000000-0x00000000ffffffff] Feb 22 12:24:15 zenith kernel: node 4: [mem 0x0000000100000000-0x000000013fffffff] Feb 22 12:24:15 zenith kernel: node 5: [mem 0x0000000140000000-0x000000017fffffff] Feb 22 12:24:15 zenith kernel: node 6: [mem 0x0000000180000000-0x00000001bfffffff] Feb 22 12:24:15 zenith kernel: node 7: [mem 0x00000001c0000000-0x00000001ffffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003fbfffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 1 [mem 0x0000000040000000-0x000000007fffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 2 [mem 0x0000000080000000-0x00000000bfffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 3 [mem 0x00000000c0000000-0x00000000ffffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 4 [mem 0x0000000100000000-0x000000013fffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 5 [mem 0x0000000140000000-0x000000017fffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 6 [mem 0x0000000180000000-0x00000001bfffffff] Feb 22 12:24:15 zenith kernel: Initmem setup node 7 [mem 0x00000001c0000000-0x00000001ffffffff] Feb 22 12:24:15 zenith kernel: On node 1, zone DMA: 1024 pages in unavailable ranges Feb 22 12:24:15 zenith kernel: psci: probing for conduit method from DT. Feb 22 12:24:15 zenith kernel: psci: PSCIv1.1 detected in firmware. Feb 22 12:24:15 zenith kernel: psci: Using standard PSCI v0.2 function IDs Feb 22 12:24:15 zenith kernel: psci: MIGRATE_INFO_TYPE not supported. Feb 22 12:24:15 zenith kernel: psci: SMC Calling Convention v1.2 Feb 22 12:24:15 zenith kernel: percpu: Embedded 33 pages/cpu s95192 r8192 d31784 u135168 Feb 22 12:24:15 zenith kernel: pcpu-alloc: s95192 r8192 d31784 u135168 alloc=33*4096 Feb 22 12:24:15 zenith kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 22 12:24:15 zenith kernel: Detected PIPT I-cache on CPU0 Feb 22 12:24:15 zenith kernel: CPU features: detected: Virtualization Host Extensions Feb 22 12:24:15 zenith kernel: CPU features: detected: Spectre-v4 Feb 22 12:24:15 zenith kernel: CPU features: detected: Spectre-BHB Feb 22 12:24:15 zenith kernel: CPU features: kernel page table isolation forced ON by KASLR Feb 22 12:24:15 zenith kernel: CPU features: detected: Kernel page table isolation (KPTI) Feb 22 12:24:15 zenith kernel: CPU features: detected: SSBS not fully self-synchronizing Feb 22 12:24:15 zenith kernel: alternatives: applying boot alternatives Feb 22 12:24:15 zenith kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=2C:CF:67:95:E5:67 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 arm_freq=2000 gpu_mem=64 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt pcie_aspm=on 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 Feb 22 12:24:15 zenith kernel: cgroup: Disabling memory control group subsystem Feb 22 12:24:15 zenith kernel: mempolicy: NUMA default policy overridden to 'interleave:0-7' Feb 22 12:24:15 zenith kernel: DMA IOMMU NUMA default policy overridden to 'interleave:0-7' Feb 22 12:24:15 zenith kernel: Unknown kernel command line parameters "splash nodebug arm_freq=2000 gpu_mem=64 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Feb 22 12:24:15 zenith kernel: Fallback order for Node 0: 0 1 2 3 4 5 6 7 Feb 22 12:24:15 zenith kernel: Fallback order for Node 1: 1 2 3 4 5 6 7 0 Feb 22 12:24:15 zenith kernel: Fallback order for Node 2: 2 3 4 5 6 7 0 1 Feb 22 12:24:15 zenith kernel: Fallback order for Node 3: 3 4 5 6 7 0 1 2 Feb 22 12:24:15 zenith kernel: Fallback order for Node 4: 4 5 6 7 0 1 2 3 Feb 22 12:24:15 zenith kernel: Fallback order for Node 5: 5 6 7 0 1 2 3 4 Feb 22 12:24:15 zenith kernel: Fallback order for Node 6: 6 7 0 1 2 3 4 5 Feb 22 12:24:15 zenith kernel: Fallback order for Node 7: 7 0 1 2 3 4 5 6 Feb 22 12:24:15 zenith kernel: Built 8 zonelists, mobility grouping on. Total pages: 2096128 Feb 22 12:24:15 zenith kernel: Policy zone: Normal Feb 22 12:24:15 zenith kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 22 12:24:15 zenith kernel: software IO TLB: area num 4. Feb 22 12:24:15 zenith kernel: software IO TLB: mapped [mem 0x00000000fae00000-0x00000000fee00000] (64MB) Feb 22 12:24:15 zenith kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8 Feb 22 12:24:15 zenith kernel: ftrace: allocating 45342 entries in 178 pages Feb 22 12:24:15 zenith kernel: ftrace: allocated 178 pages with 4 groups Feb 22 12:24:15 zenith kernel: rcu: Preemptible hierarchical RCU implementation. Feb 22 12:24:15 zenith kernel: rcu: RCU event tracing is enabled. Feb 22 12:24:15 zenith kernel: Trampoline variant of Tasks RCU enabled. Feb 22 12:24:15 zenith kernel: Rude variant of Tasks RCU enabled. Feb 22 12:24:15 zenith kernel: Tracing variant of Tasks RCU enabled. Feb 22 12:24:15 zenith kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Feb 22 12:24:15 zenith kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 22 12:24:15 zenith kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 22 12:24:15 zenith kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 22 12:24:15 zenith kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Feb 22 12:24:15 zenith kernel: Root IRQ handler: gic_handle_irq Feb 22 12:24:15 zenith kernel: GIC: Using split EOI/Deactivate mode Feb 22 12:24:15 zenith kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 22 12:24:15 zenith kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 22 12:24:15 zenith kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 22 12:24:15 zenith kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 22 12:24:15 zenith kernel: Console: colour dummy device 80x25 Feb 22 12:24:15 zenith kernel: printk: legacy console [tty1] enabled Feb 22 12:24:15 zenith kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Feb 22 12:24:15 zenith kernel: pid_max: default: 32768 minimum: 301 Feb 22 12:24:15 zenith kernel: LSM: initializing lsm=capability Feb 22 12:24:15 zenith kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: rcu: Hierarchical SRCU implementation. Feb 22 12:24:15 zenith kernel: rcu: Max phase no-delay instances is 1000. Feb 22 12:24:15 zenith kernel: Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level Feb 22 12:24:15 zenith kernel: EFI services will not be available. Feb 22 12:24:15 zenith kernel: smp: Bringing up secondary CPUs ... Feb 22 12:24:15 zenith kernel: Detected PIPT I-cache on CPU1 Feb 22 12:24:15 zenith kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Feb 22 12:24:15 zenith kernel: Detected PIPT I-cache on CPU2 Feb 22 12:24:15 zenith kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Feb 22 12:24:15 zenith kernel: Detected PIPT I-cache on CPU3 Feb 22 12:24:15 zenith kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Feb 22 12:24:15 zenith kernel: smp: Brought up 8 nodes, 4 CPUs Feb 22 12:24:15 zenith kernel: SMP: Total of 4 processors activated. Feb 22 12:24:15 zenith kernel: CPU: All CPU(s) started at EL2 Feb 22 12:24:15 zenith kernel: CPU features: detected: 32-bit EL0 Support Feb 22 12:24:15 zenith kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Feb 22 12:24:15 zenith kernel: CPU features: detected: Common not Private translations Feb 22 12:24:15 zenith kernel: CPU features: detected: CRC32 instructions Feb 22 12:24:15 zenith kernel: CPU features: detected: RCpc load-acquire (LDAPR) Feb 22 12:24:15 zenith kernel: CPU features: detected: LSE atomic instructions Feb 22 12:24:15 zenith kernel: CPU features: detected: Privileged Access Never Feb 22 12:24:15 zenith kernel: CPU features: detected: RAS Extension Support Feb 22 12:24:15 zenith kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Feb 22 12:24:15 zenith kernel: alternatives: applying system-wide alternatives Feb 22 12:24:15 zenith kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Feb 22 12:24:15 zenith kernel: Memory: 8012840K/8384512K available (14080K kernel code, 2406K rwdata, 4820K rodata, 5440K init, 577K bss, 278696K reserved, 65536K cma-reserved) Feb 22 12:24:15 zenith kernel: devtmpfs: initialized Feb 22 12:24:15 zenith kernel: Enabled cp15_barrier support Feb 22 12:24:15 zenith kernel: Enabled setend support Feb 22 12:24:15 zenith kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Feb 22 12:24:15 zenith kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Feb 22 12:24:15 zenith kernel: 0 pages in range for non-PLT usage Feb 22 12:24:15 zenith kernel: 517392 pages in range for PLT usage Feb 22 12:24:15 zenith kernel: pinctrl core: initialized pinctrl subsystem Feb 22 12:24:15 zenith kernel: DMI not present or invalid. Feb 22 12:24:15 zenith kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 22 12:24:15 zenith kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Feb 22 12:24:15 zenith kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Feb 22 12:24:15 zenith kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Feb 22 12:24:15 zenith kernel: audit: initializing netlink subsys (disabled) Feb 22 12:24:15 zenith kernel: audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 Feb 22 12:24:15 zenith kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 22 12:24:15 zenith kernel: cpuidle: using governor menu Feb 22 12:24:15 zenith kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Feb 22 12:24:15 zenith kernel: ASID allocator initialised with 32768 entries Feb 22 12:24:15 zenith kernel: Serial: AMBA PL011 UART driver Feb 22 12:24:15 zenith kernel: /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000 Feb 22 12:24:15 zenith kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Feb 22 12:24:15 zenith kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3 Feb 22 12:24:15 zenith kernel: printk: legacy console [ttyAMA10] enabled Feb 22 12:24:15 zenith kernel: raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-11-05T17:37:18, variant start_cd Feb 22 12:24:15 zenith kernel: raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 57db150d00000000000000000000000000000000 Feb 22 12:24:15 zenith kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Feb 22 12:24:15 zenith kernel: iommu: Default domain type: Translated Feb 22 12:24:15 zenith kernel: iommu: DMA domain TLB invalidation policy: strict mode Feb 22 12:24:15 zenith kernel: SCSI subsystem initialized Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver usbfs Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver hub Feb 22 12:24:15 zenith kernel: usbcore: registered new device driver usb Feb 22 12:24:15 zenith kernel: pps_core: LinuxPPS API ver. 1 registered Feb 22 12:24:15 zenith kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 22 12:24:15 zenith kernel: PTP clock support registered Feb 22 12:24:15 zenith kernel: vgaarb: loaded Feb 22 12:24:15 zenith kernel: clocksource: Switched to clocksource arch_sys_counter Feb 22 12:24:15 zenith kernel: VFS: Disk quotas dquot_6.6.0 Feb 22 12:24:15 zenith kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 22 12:24:15 zenith kernel: NET: Registered PF_INET protocol family Feb 22 12:24:15 zenith kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: TCP: Hash tables configured (established 65536 bind 65536) Feb 22 12:24:15 zenith kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, vmalloc) Feb 22 12:24:15 zenith kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 22 12:24:15 zenith kernel: RPC: Registered named UNIX socket transport module. Feb 22 12:24:15 zenith kernel: RPC: Registered udp transport module. Feb 22 12:24:15 zenith kernel: RPC: Registered tcp transport module. Feb 22 12:24:15 zenith kernel: RPC: Registered tcp-with-tls transport module. Feb 22 12:24:15 zenith kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 22 12:24:15 zenith kernel: PCI: CLS 0 bytes, default 64 Feb 22 12:24:15 zenith kernel: Trying to unpack rootfs image as initramfs... Feb 22 12:24:15 zenith kernel: kvm [1]: nv: 554 coarse grained trap handlers Feb 22 12:24:15 zenith kernel: kvm [1]: IPA Size Limit: 40 bits Feb 22 12:24:15 zenith kernel: kvm [1]: vgic interrupt IRQ9 Feb 22 12:24:15 zenith kernel: kvm [1]: VHE mode initialized successfully Feb 22 12:24:15 zenith kernel: Initialise system trusted keyrings Feb 22 12:24:15 zenith kernel: workingset: timestamp_bits=42 max_order=21 bucket_order=0 Feb 22 12:24:15 zenith kernel: NFS: Registering the id_resolver key type Feb 22 12:24:15 zenith kernel: Key type id_resolver registered Feb 22 12:24:15 zenith kernel: Key type id_legacy registered Feb 22 12:24:15 zenith kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 22 12:24:15 zenith kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 22 12:24:15 zenith kernel: Key type asymmetric registered Feb 22 12:24:15 zenith kernel: Asymmetric key parser 'x509' registered Feb 22 12:24:15 zenith kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 22 12:24:15 zenith kernel: io scheduler mq-deadline registered Feb 22 12:24:15 zenith kernel: io scheduler kyber registered Feb 22 12:24:15 zenith kernel: io scheduler bfq registered Feb 22 12:24:15 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27) Feb 22 12:24:15 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28) Feb 22 12:24:15 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29) Feb 22 12:24:15 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30) Feb 22 12:24:15 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31) Feb 22 12:24:15 zenith kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 22 12:24:15 zenith kernel: Freeing initrd memory: 35752K Feb 22 12:24:15 zenith kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Feb 22 12:24:15 zenith kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Feb 22 12:24:15 zenith kernel: serial serial0: tty port ttyS0 registered Feb 22 12:24:15 zenith kernel: iproc-rng200 107d208000.rng: hwrng registered Feb 22 12:24:15 zenith kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Feb 22 12:24:15 zenith kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Feb 22 12:24:15 zenith kernel: brd: module loaded Feb 22 12:24:15 zenith kernel: loop: module loaded Feb 22 12:24:15 zenith kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 22 12:24:15 zenith kernel: Loading iSCSI transport class v2.0-870. Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver lan78xx Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver smsc95xx Feb 22 12:24:15 zenith kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 22 12:24:15 zenith kernel: dwc_otg: FIQ enabled Feb 22 12:24:15 zenith kernel: dwc_otg: NAK holdoff enabled Feb 22 12:24:15 zenith kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 22 12:24:15 zenith kernel: Module dwc_common_port init Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver uas Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver usb-storage Feb 22 12:24:15 zenith kernel: mousedev: PS/2 mouse device common for all mice Feb 22 12:24:15 zenith kernel: rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0 Feb 22 12:24:15 zenith kernel: rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 2026-02-22T16:54:42 UTC (1771779282) Feb 22 12:24:15 zenith kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Feb 22 12:24:15 zenith kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 22 12:24:15 zenith kernel: sdhci: Secure Digital Host Controller Interface driver Feb 22 12:24:15 zenith kernel: sdhci: Copyright(c) Pierre Ossman Feb 22 12:24:15 zenith kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 22 12:24:15 zenith kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Feb 22 12:24:15 zenith kernel: hid: raw HID events driver (C) Jiri Kosina Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver usbhid Feb 22 12:24:15 zenith kernel: usbhid: USB HID core driver Feb 22 12:24:15 zenith kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available Feb 22 12:24:15 zenith kernel: NET: Registered PF_PACKET protocol family Feb 22 12:24:15 zenith kernel: Key type dns_resolver registered Feb 22 12:24:15 zenith kernel: registered taskstats version 1 Feb 22 12:24:15 zenith kernel: Loading compiled-in X.509 certificates Feb 22 12:24:15 zenith kernel: Demotion targets for Node 0: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 1: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 2: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 3: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 4: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 5: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 6: null Feb 22 12:24:15 zenith kernel: Demotion targets for Node 7: null Feb 22 12:24:15 zenith kernel: Key type .fscrypt registered Feb 22 12:24:15 zenith kernel: Key type fscrypt-provisioning registered Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges: Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@1000110000, using [bus 00-ff] Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00 Feb 22 12:24:15 zenith kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Feb 22 12:24:15 zenith kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Feb 22 12:24:15 zenith kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: PCI bridge to [bus 00] Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1bbfffffff] Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: clkreq-mode set to safe Feb 22 12:24:15 zenith kernel: brcm-pcie 1000110000.pcie: link up, 5.0 GT/s PCIe x1 (!SSC) Feb 22 12:24:15 zenith kernel: pci 0001:01:00.0: [144d:a808] type 00 class 0x010802 PCIe Endpoint Feb 22 12:24:15 zenith kernel: pci 0001:01:00.0: BAR 0 [mem 0x00000000-0x00003fff 64bit] Feb 22 12:24:15 zenith kernel: pci 0001:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0001:00:00.0 (capable of 31.504 Gb/s with 8.0 GT/s PCIe x4 link) Feb 22 12:24:15 zenith kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff]: assigned Feb 22 12:24:15 zenith kernel: pci 0001:01:00.0: BAR 0 [mem 0x1b80000000-0x1b80003fff 64bit]: assigned Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff] Feb 22 12:24:15 zenith kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff] Feb 22 12:24:15 zenith kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref] Feb 22 12:24:15 zenith kernel: pci_bus 0001:01: resource 1 [mem 0x1b80000000-0x1b800fffff] Feb 22 12:24:15 zenith kernel: pci 0001:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Feb 22 12:24:15 zenith kernel: pci 0001:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Feb 22 12:24:15 zenith kernel: pcieport 0001:00:00.0: enabling device (0000 -> 0002) Feb 22 12:24:15 zenith kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 38 Feb 22 12:24:15 zenith kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 38 Feb 22 12:24:15 zenith kernel: nvme nvme0: pci function 0001:01:00.0 Feb 22 12:24:15 zenith kernel: nvme 0001:01:00.0: enabling device (0000 -> 0002) Feb 22 12:24:15 zenith kernel: nvme nvme0: missing or invalid SUBNQN field. Feb 22 12:24:15 zenith kernel: nvme nvme0: D3 entry latency set to 8 seconds Feb 22 12:24:15 zenith kernel: hwmon hwmon1: temp1_input not attached to any thermal zone Feb 22 12:24:15 zenith kernel: hwmon hwmon1: temp2_input not attached to any thermal zone Feb 22 12:24:15 zenith kernel: hwmon hwmon1: temp3_input not attached to any thermal zone Feb 22 12:24:15 zenith kernel: nvme nvme0: 4/0/0 default/read/poll queues Feb 22 12:24:15 zenith kernel: nvme0n1: p1 p2 p3 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges: Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff] Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000 Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00 Feb 22 12:24:15 zenith kernel: pci_bus 0002:00: root bus resource [bus 00-ff] Feb 22 12:24:15 zenith kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Feb 22 12:24:15 zenith kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: PCI bridge to [bus 00] Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff] Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: PME# supported from D0 D3hot Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default Feb 22 12:24:15 zenith kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff] Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff] Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff] Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: supports D1 Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold Feb 22 12:24:15 zenith kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01 Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: PCI bridge to [bus 01] Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Feb 22 12:24:15 zenith kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb] Feb 22 12:24:15 zenith kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref] Feb 22 12:24:15 zenith kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff] Feb 22 12:24:15 zenith kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Feb 22 12:24:15 zenith kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Feb 22 12:24:15 zenith kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002) Feb 22 12:24:15 zenith kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 44 Feb 22 12:24:15 zenith kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 44 Feb 22 12:24:15 zenith kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Feb 22 12:24:15 zenith kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Feb 22 12:24:15 zenith kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002) Feb 22 12:24:15 zenith kernel: rp1 0002:01:00.0: chip_id 0x20001927 Feb 22 12:24:15 zenith kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 22 12:24:15 zenith kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Feb 22 12:24:15 zenith kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 22 12:24:15 zenith kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Feb 22 12:24:15 zenith kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 22 12:24:15 zenith kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Feb 22 12:24:15 zenith kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 106 Feb 22 12:24:15 zenith kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Feb 22 12:24:15 zenith kernel: mmc1: CQHCI version 5.10 Feb 22 12:24:15 zenith kernel: mmc0: CQHCI version 5.10 Feb 22 12:24:15 zenith kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 112 (2c:cf:67:95:e5:67) Feb 22 12:24:15 zenith kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: irq 137, io mem 0x1f00200000 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Feb 22 12:24:15 zenith kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Feb 22 12:24:15 zenith kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 22 12:24:15 zenith kernel: usb usb1: Product: xHCI Host Controller Feb 22 12:24:15 zenith kernel: usb usb1: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 22 12:24:15 zenith kernel: usb usb1: SerialNumber: xhci-hcd.0 Feb 22 12:24:15 zenith kernel: hub 1-0:1.0: USB hub found Feb 22 12:24:15 zenith kernel: hub 1-0:1.0: 2 ports detected Feb 22 12:24:15 zenith kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Feb 22 12:24:15 zenith kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 22 12:24:15 zenith kernel: usb usb2: Product: xHCI Host Controller Feb 22 12:24:15 zenith kernel: usb usb2: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 22 12:24:15 zenith kernel: usb usb2: SerialNumber: xhci-hcd.0 Feb 22 12:24:15 zenith kernel: hub 2-0:1.0: USB hub found Feb 22 12:24:15 zenith kernel: hub 2-0:1.0: 1 port detected Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: irq 142, io mem 0x1f00300000 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Feb 22 12:24:15 zenith kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Feb 22 12:24:15 zenith kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Feb 22 12:24:15 zenith kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 22 12:24:15 zenith kernel: usb usb3: Product: xHCI Host Controller Feb 22 12:24:15 zenith kernel: usb usb3: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 22 12:24:15 zenith kernel: usb usb3: SerialNumber: xhci-hcd.1 Feb 22 12:24:15 zenith kernel: hub 3-0:1.0: USB hub found Feb 22 12:24:15 zenith kernel: hub 3-0:1.0: 2 ports detected Feb 22 12:24:15 zenith kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Feb 22 12:24:15 zenith kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 22 12:24:15 zenith kernel: usb usb4: Product: xHCI Host Controller Feb 22 12:24:15 zenith kernel: usb usb4: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 22 12:24:15 zenith kernel: usb usb4: SerialNumber: xhci-hcd.1 Feb 22 12:24:15 zenith kernel: hub 4-0:1.0: USB hub found Feb 22 12:24:15 zenith kernel: hub 4-0:1.0: 1 port detected Feb 22 12:24:15 zenith kernel: of_cfs_init Feb 22 12:24:15 zenith kernel: of_cfs_init: OK Feb 22 12:24:15 zenith kernel: clk: Disabling unused clocks Feb 22 12:24:15 zenith kernel: PM: genpd: Disabling unused power domains Feb 22 12:24:15 zenith kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Feb 22 12:24:15 zenith kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Feb 22 12:24:15 zenith kernel: Freeing unused kernel memory: 5440K Feb 22 12:24:15 zenith kernel: Run /init as init process Feb 22 12:24:15 zenith kernel: with arguments: Feb 22 12:24:15 zenith kernel: /init Feb 22 12:24:15 zenith kernel: splash Feb 22 12:24:15 zenith kernel: nodebug Feb 22 12:24:15 zenith kernel: with environment: Feb 22 12:24:15 zenith kernel: HOME=/ Feb 22 12:24:15 zenith kernel: TERM=linux Feb 22 12:24:15 zenith kernel: arm_freq=2000 Feb 22 12:24:15 zenith kernel: gpu_mem=64 Feb 22 12:24:15 zenith kernel: imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 Feb 22 12:24:15 zenith kernel: imgfile=/volumio_current.sqsh Feb 22 12:24:15 zenith kernel: bootpart=UUID=3B89-0B23 Feb 22 12:24:15 zenith kernel: datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 Feb 22 12:24:15 zenith kernel: uuidconfig=cmdline.txt Feb 22 12:24:15 zenith kernel: bootdelay=7 Feb 22 12:24:15 zenith kernel: use_kmsg=no Feb 22 12:24:15 zenith kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Feb 22 12:24:15 zenith kernel: rp1-firmware rp1_firmware: RP1 Firmware version 23fca1cd776798b50bd3e640ecb242215778e7f8 Feb 22 12:24:15 zenith kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Feb 22 12:24:15 zenith kernel: rp1-pio 1f00178000.pio: Created instance as pio0 Feb 22 12:24:15 zenith kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Feb 22 12:24:15 zenith kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Feb 22 12:24:15 zenith kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform! Feb 22 12:24:15 zenith kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0 Feb 22 12:24:15 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1 Feb 22 12:24:15 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: Adding to iommu group 1 Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Feb 22 12:24:15 zenith kernel: Registered IR keymap rc-cec Feb 22 12:24:15 zenith kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0 Feb 22 12:24:15 zenith kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input1 Feb 22 12:24:15 zenith kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input2 Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Feb 22 12:24:15 zenith kernel: Registered IR keymap rc-cec Feb 22 12:24:15 zenith kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1 Feb 22 12:24:15 zenith kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input3 Feb 22 12:24:15 zenith kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input4 Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Feb 22 12:24:15 zenith kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1 Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 22 12:24:15 zenith kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 22 12:24:15 zenith kernel: fuse: init (API version 7.41) Feb 22 12:24:15 zenith kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 22 12:24:15 zenith kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Feb 22 12:24:15 zenith kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340 Feb 22 12:24:15 zenith kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r Feb 22 12:24:15 zenith kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18 Feb 22 12:24:15 zenith kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18 Feb 22 12:24:15 zenith kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green Feb 22 12:24:15 zenith kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v Feb 22 12:24:15 zenith kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13 Feb 22 12:24:15 zenith kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned. Feb 22 12:24:15 zenith kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d Feb 22 12:24:15 zenith kernel: EXT4-fs (nvme0n1p2): recovery complete Feb 22 12:24:15 zenith kernel: EXT4-fs (nvme0n1p2): mounted filesystem dafa3844-b779-48cd-9b4c-01ecfd09e0f4 r/w with ordered data mode. Quota mode: none. Feb 22 12:24:15 zenith kernel: loop0: detected capacity change from 0 to 1422424 Feb 22 12:24:15 zenith kernel: EXT4-fs (nvme0n1p3): recovery complete Feb 22 12:24:15 zenith kernel: EXT4-fs (nvme0n1p3): mounted filesystem 752d19ad-b702-471d-847a-f79ae83515d0 r/w with ordered data mode. Quota mode: none. Feb 22 12:24:15 zenith kernel: NET: Registered PF_INET6 protocol family Feb 22 12:24:15 zenith kernel: Segment Routing with IPv6 Feb 22 12:24:15 zenith kernel: In-situ OAM (IOAM) with IPv6 Feb 22 12:24:15 zenith 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) Feb 22 12:24:15 zenith systemd[1]: Detected architecture arm64. Feb 22 12:24:15 zenith systemd[1]: Hostname set to . Feb 22 12:24:15 zenith systemd[1]: Queued start job for default target graphical.target. Feb 22 12:24:15 zenith systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 22 12:24:15 zenith systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Feb 22 12:24:15 zenith systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Feb 22 12:24:15 zenith systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Feb 22 12:24:15 zenith systemd[1]: Created slice user.slice - User and Session Slice. Feb 22 12:24:15 zenith systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Feb 22 12:24:15 zenith systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Feb 22 12:24:15 zenith systemd[1]: Expecting device dev-disk-by\x2duuid-3B89\x2d0B23.device - /dev/disk/by-uuid/3B89-0B23... Feb 22 12:24:15 zenith systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10... Feb 22 12:24:15 zenith systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Feb 22 12:24:15 zenith systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 22 12:24:15 zenith systemd[1]: Reached target slices.target - Slice Units. Feb 22 12:24:15 zenith systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-journald.socket - Journal Socket. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 22 12:24:15 zenith systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Feb 22 12:24:15 zenith systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Feb 22 12:24:15 zenith systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Feb 22 12:24:15 zenith systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Feb 22 12:24:15 zenith systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 22 12:24:15 zenith systemd[1]: Reached target swap.target - Swaps. Feb 22 12:24:15 zenith systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Feb 22 12:24:15 zenith systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 22 12:24:15 zenith systemd[1]: Starting systemd-journald.service - Journal Service... Feb 22 12:24:15 zenith systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 22 12:24:15 zenith systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Feb 22 12:24:15 zenith systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 22 12:24:15 zenith systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Feb 22 12:24:15 zenith systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Feb 22 12:24:15 zenith systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Feb 22 12:24:15 zenith kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Feb 22 12:24:15 zenith systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Feb 22 12:24:15 zenith systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 22 12:24:15 zenith systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Feb 22 12:24:15 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 22 12:24:15 zenith systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 22 12:24:15 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 22 12:24:15 zenith systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Feb 22 12:24:15 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 22 12:24:15 zenith systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Feb 22 12:24:15 zenith systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Feb 22 12:24:15 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 22 12:24:15 zenith systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Feb 22 12:24:15 zenith systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Feb 22 12:24:15 zenith systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Feb 22 12:24:15 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 22 12:24:15 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 22 12:24:15 zenith systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Feb 22 12:24:15 zenith systemd[1]: Starting systemd-sysusers.service - Create System Users... Feb 22 12:24:15 zenith kernel: i2c_dev: i2c /dev entries driver Feb 22 12:24:15 zenith systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 22 12:24:15 zenith systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 22 12:24:15 zenith systemd[1]: dynamicswap.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Feb 22 12:24:15 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 22 12:24:15 zenith systemd-journald[522]: Journal started Feb 22 12:24:15 zenith systemd-journald[522]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.7M, max 30.0M, 26.2M free. Feb 22 12:24:15 zenith systemd-modules-load[525]: Inserted module 'i2c_dev' Feb 22 12:24:15 zenith fake-hwclock[524]: Sun Feb 22 17:24:15 UTC 2026 Feb 22 12:24:15 zenith systemd[1]: Started systemd-journald.service - Journal Service. Feb 22 12:24:15 zenith systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 22 12:24:15 zenith systemd[1]: Finished systemd-sysusers.service - Create System Users. Feb 22 12:24:15 zenith systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 22 12:24:15 zenith systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 22 12:24:15 zenith systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Feb 22 12:24:15 zenith systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 22 12:24:15 zenith systemd-udevd[544]: Using default interface naming scheme 'v252'. Feb 22 12:24:15 zenith systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 22 12:24:15 zenith systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 22 12:24:15 zenith systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Feb 22 12:24:15 zenith systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Feb 22 12:24:15 zenith systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Feb 22 12:24:15 zenith systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Feb 22 12:24:15 zenith 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). Feb 22 12:24:15 zenith systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Feb 22 12:24:15 zenith systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 22 12:24:15 zenith systemd[1]: Reached target paths.target - Path Units. Feb 22 12:24:15 zenith (udev-worker)[569]: Network interface NamePolicy= disabled on kernel command line. Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Feb 22 12:24:15 zenith kernel: mc: Linux media interface: v0.10 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Feb 22 12:24:15 zenith kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Feb 22 12:24:15 zenith systemd[1]: Found device dev-disk-by\x2duuid-3B89\x2d0B23.device - Samsung SSD 970 EVO Plus 250GB boot. Feb 22 12:24:15 zenith systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10. Feb 22 12:24:15 zenith kernel: videodev: Linux video capture interface: v2.00 Feb 22 12:24:15 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0 Feb 22 12:24:15 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Feb 22 12:24:15 zenith systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-3B89\x2d0B23.service - File System Check on /dev/disk/by-uuid/3B89-0B23... Feb 22 12:24:15 zenith systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Feb 22 12:24:15 zenith systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Feb 22 12:24:15 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0 Feb 22 12:24:15 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Feb 22 12:24:15 zenith kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19 Feb 22 12:24:15 zenith systemd-fsck[690]: fsck.fat 4.2 (2021-01-31) Feb 22 12:24:15 zenith systemd-fsck[690]: /dev/nvme0n1p1: 540 files, 24109/93500 clusters Feb 22 12:24:15 zenith systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-3B89\x2d0B23.service - File System Check on /dev/disk/by-uuid/3B89-0B23. Feb 22 12:24:15 zenith 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). Feb 22 12:24:15 zenith kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 22 12:24:15 zenith kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 22 12:24:15 zenith kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 22 12:24:15 zenith kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 22 12:24:15 zenith kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 22 12:24:15 zenith kernel: Bluetooth: Core ver 2.22 Feb 22 12:24:15 zenith kernel: NET: Registered PF_BLUETOOTH protocol family Feb 22 12:24:15 zenith kernel: Bluetooth: HCI device and connection manager initialized Feb 22 12:24:15 zenith kernel: Bluetooth: HCI socket layer initialized Feb 22 12:24:15 zenith kernel: Bluetooth: L2CAP socket layer initialized Feb 22 12:24:15 zenith kernel: Bluetooth: SCO socket layer initialized Feb 22 12:24:15 zenith kernel: Bluetooth: HCI UART driver ver 2.3 Feb 22 12:24:15 zenith kernel: Bluetooth: HCI UART protocol H4 registered Feb 22 12:24:15 zenith kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 22 12:24:15 zenith kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 22 12:24:15 zenith kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 22 12:24:15 zenith kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 22 12:24:15 zenith kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 22 12:24:15 zenith kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 22 12:24:15 zenith kernel: usbcore: registered new interface driver brcmfmac Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 22 12:24:15 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 22 12:24:15 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 22 12:24:15 zenith systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Feb 22 12:24:15 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 22 12:24:15 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 22 12:24:15 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 22 12:24:15 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 22 12:24:15 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 22 12:24:15 zenith systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 22 12:24:15 zenith 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). Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 22 12:24:15 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 22 12:24:15 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 22 12:24:15 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 22 12:24:15 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 22 12:24:15 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 22 12:24:15 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 22 12:24:15 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 22 12:24:15 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 22 12:24:15 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 22 12:24:15 zenith (udev-worker)[570]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Feb 22 12:24:15 zenith kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Feb 22 12:24:15 zenith 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 Feb 22 12:24:15 zenith systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Feb 22 12:24:15 zenith systemd[1]: Reached target bluetooth.target - Bluetooth Support. Feb 22 12:24:15 zenith systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Feb 22 12:24:15 zenith (udev-worker)[570]: Network interface NamePolicy= disabled on kernel command line. Feb 22 12:24:15 zenith systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Feb 22 12:24:15 zenith systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Feb 22 12:24:15 zenith kernel: Bluetooth: hci0: BCM: chip id 107 Feb 22 12:24:15 zenith kernel: Bluetooth: hci0: BCM: features 0x2f Feb 22 12:24:15 zenith kernel: Bluetooth: hci0: BCM4345C0 Feb 22 12:24:15 zenith kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Feb 22 12:24:15 zenith kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,5-model-b.hcd' Patch Feb 22 12:24:16 zenith systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Feb 22 12:24:16 zenith systemd[1]: Mounting boot.mount - /boot... Feb 22 12:24:16 zenith systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Feb 22 12:24:16 zenith systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 22 12:24:16 zenith systemd[1]: Mounting tmp.mount - /tmp... Feb 22 12:24:16 zenith systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 22 12:24:16 zenith systemd[1]: Mounting var-log.mount - /var/log... Feb 22 12:24:16 zenith systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Feb 22 12:24:16 zenith systemd[1]: Mounted boot.mount - /boot. Feb 22 12:24:16 zenith systemd[1]: Mounted tmp.mount - /tmp. Feb 22 12:24:16 zenith systemd[1]: Mounted var-log.mount - /var/log. Feb 22 12:24:16 zenith systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Feb 22 12:24:16 zenith systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Feb 22 12:24:16 zenith systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Feb 22 12:24:16 zenith systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Feb 22 12:24:16 zenith systemd[1]: Reached target local-fs.target - Local File Systems. Feb 22 12:24:16 zenith systemd[1]: Started ifup@eth0.service - ifup for eth0. Feb 22 12:24:16 zenith systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Feb 22 12:24:16 zenith systemd[1]: Starting networking.service - Raise network interfaces... Feb 22 12:24:16 zenith systemd-journald[522]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.7M, max 30.0M, 26.2M free. Feb 22 12:24:16 zenith systemd-journald[522]: Received client request to flush runtime journal. Feb 22 12:24:16 zenith systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Feb 22 12:24:16 zenith systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Feb 22 12:24:16 zenith 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). Feb 22 12:24:16 zenith systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Feb 22 12:24:16 zenith systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 22 12:24:16 zenith systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 848 (systemd-binfmt) Feb 22 12:24:16 zenith systemd[1]: Received SIGRTMIN+20 from PID 396 (plymouthd). Feb 22 12:24:16 zenith systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Feb 22 12:24:16 zenith kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Feb 22 12:24:16 zenith systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 22 12:24:16 zenith systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Feb 22 12:24:16 zenith systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Feb 22 12:24:16 zenith sh[883]: dhcpcd-9.4.1 starting Feb 22 12:24:16 zenith dhcpcd[883]: dhcpcd-9.4.1 starting Feb 22 12:24:16 zenith dhcpcd[895]: DUID 00:01:00:01:30:c6:00:2d:2c:cf:67:95:e5:69 Feb 22 12:24:16 zenith sh[883]: DUID 00:01:00:01:30:c6:00:2d:2c:cf:67:95:e5:69 Feb 22 12:24:16 zenith sh[883]: dhcp_vendor: No such process Feb 22 12:24:16 zenith dhcpcd[895]: dhcp_vendor: No such process Feb 22 12:24:16 zenith systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Feb 22 12:24:16 zenith kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 22 12:24:16 zenith haveged[884]: haveged: command socket is listening at fd 3 Feb 22 12:24:16 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 22 12:24:16 zenith kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Feb 22 12:24:16 zenith kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Feb 22 12:24:16 zenith kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Feb 22 12:24:16 zenith sh[883]: eth0: waiting for carrier Feb 22 12:24:16 zenith dhcpcd[895]: eth0: waiting for carrier Feb 22 12:24:16 zenith sh[972]: wlan0=wlan0 Feb 22 12:24:16 zenith systemd[1]: Finished networking.service - Raise network interfaces. Feb 22 12:24:16 zenith kernel: Bluetooth: hci0: BCM: features 0x2f Feb 22 12:24:16 zenith kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Feb 22 12:24:16 zenith kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Feb 22 12:24:16 zenith kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Feb 22 12:24:16 zenith haveged[884]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Feb 22 12:24:16 zenith haveged[884]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Feb 22 12:24:16 zenith haveged[884]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00418 Feb 22 12:24:16 zenith haveged[884]: haveged: fills: 0, generated: 0 Feb 22 12:24:17 zenith systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Feb 22 12:24:17 zenith systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Feb 22 12:24:17 zenith systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Feb 22 12:24:17 zenith systemd[1]: Reached target sysinit.target - System Initialization. Feb 22 12:24:17 zenith systemd[1]: Started apt-daily.timer - Daily apt download activities. Feb 22 12:24:17 zenith systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Feb 22 12:24:17 zenith systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Feb 22 12:24:17 zenith systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Feb 22 12:24:17 zenith systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Feb 22 12:24:17 zenith systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Feb 22 12:24:17 zenith systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Feb 22 12:24:17 zenith systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Feb 22 12:24:17 zenith systemd[1]: Reached target timers.target - Timer Units. Feb 22 12:24:17 zenith systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Feb 22 12:24:17 zenith systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Feb 22 12:24:17 zenith systemd[1]: Reached target sockets.target - Socket Units. Feb 22 12:24:17 zenith 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). Feb 22 12:24:17 zenith systemd[1]: Reached target basic.target - Basic System. Feb 22 12:24:17 zenith 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). Feb 22 12:24:17 zenith systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Feb 22 12:24:17 zenith systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 22 12:24:17 zenith systemd[1]: Starting dbus.service - D-Bus System Message Bus... Feb 22 12:24:17 zenith systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Feb 22 12:24:17 zenith 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). Feb 22 12:24:17 zenith systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Feb 22 12:24:17 zenith systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Feb 22 12:24:17 zenith systemd[1]: Started iptables.service - Volumio Iptables Module. Feb 22 12:24:17 zenith systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Feb 22 12:24:17 zenith systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Feb 22 12:24:17 zenith systemd[1]: Starting systemd-logind.service - User Login Management... Feb 22 12:24:17 zenith 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). Feb 22 12:24:17 zenith systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Feb 22 12:24:17 zenith systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Feb 22 12:24:17 zenith systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Feb 22 12:24:17 zenith systemd[1]: Starting wireless.service - Wireless Services... Feb 22 12:24:17 zenith systemd[1]: e2scrub_reap.service: Deactivated successfully. Feb 22 12:24:17 zenith systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Feb 22 12:24:17 zenith avahi-daemon[976]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Feb 22 12:24:17 zenith avahi-daemon[976]: Successfully dropped root privileges. Feb 22 12:24:17 zenith avahi-daemon[976]: avahi-daemon 0.8 starting up. Feb 22 12:24:17 zenith thd[987]: Unable to parse trigger line: Feb 22 12:24:17 zenith thd[987]: Unable to parse trigger line: Feb 22 12:24:17 zenith systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Feb 22 12:24:17 zenith dhcpcd[979]: dhcpcd-9.4.1 starting Feb 22 12:24:17 zenith rpi-eeprom-update[982]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0 Feb 22 12:24:17 zenith dhcpcd[1003]: dev: loaded udev Feb 22 12:24:17 zenith dhcpcd[1003]: DUID 00:01:00:01:30:c6:00:2d:2c:cf:67:95:e5:69 Feb 22 12:24:17 zenith alsactl[1000]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 12:24:17 zenith alsactl[1000]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 22 12:24:17 zenith alsactl[1000]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 22 12:24:17 zenith dhcpcd[1003]: dhcp_vendor: No such process Feb 22 12:24:17 zenith dhcpcd[979]: dhcp_vendor: No such process Feb 22 12:24:17 zenith systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Feb 22 12:24:17 zenith systemd-logind[986]: New seat seat0. Feb 22 12:24:17 zenith systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Feb 22 12:24:17 zenith systemd[1]: Started dbus.service - D-Bus System Message Bus. Feb 22 12:24:17 zenith systemd-logind[986]: Watching system buttons on /dev/input/event0 (pwr_button) Feb 22 12:24:17 zenith systemd-logind[986]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Feb 22 12:24:17 zenith systemd-logind[986]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Feb 22 12:24:17 zenith avahi-daemon[976]: Successfully called chroot(). Feb 22 12:24:17 zenith avahi-daemon[976]: Successfully dropped remaining capabilities. Feb 22 12:24:17 zenith systemd[1]: Reached target sound.target - Sound Card. Feb 22 12:24:17 zenith systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Feb 22 12:24:17 zenith dbus-daemon[977]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 22 12:24:17 zenith avahi-daemon[976]: Loading service file /services/volumio.service. Feb 22 12:24:17 zenith systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Feb 22 12:24:17 zenith systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 22 12:24:17 zenith systemd[1]: Started systemd-logind.service - User Login Management. Feb 22 12:24:17 zenith dhcpcd[1003]: eth0: waiting for carrier Feb 22 12:24:17 zenith dhcpcd[1003]: wlan0: waiting for carrier Feb 22 12:24:17 zenith avahi-daemon[976]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 22 12:24:17 zenith avahi-daemon[976]: New relevant interface lo.IPv4 for mDNS. Feb 22 12:24:17 zenith avahi-daemon[976]: Network interface enumeration completed. Feb 22 12:24:17 zenith avahi-daemon[976]: Registering new address record for 127.0.0.1 on lo.IPv4. Feb 22 12:24:17 zenith th-udev-rebind.sh[1034]: Rebinding /dev/input/event0 to thd using --add... Feb 22 12:24:17 zenith th-udev-rebind.sh[1034]: Rebinding /dev/input/event1 to thd using --add... Feb 22 12:24:17 zenith th-udev-rebind.sh[1034]: Rebinding /dev/input/event2 to thd using --add... Feb 22 12:24:17 zenith th-udev-rebind.sh[1034]: Rebinding /dev/input/event3 to thd using --add... Feb 22 12:24:17 zenith th-udev-rebind.sh[1034]: Rebinding /dev/input/event4 to thd using --add... Feb 22 12:24:17 zenith systemd[1]: th-udev-rebind.service: Deactivated successfully. Feb 22 12:24:17 zenith systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Feb 22 12:24:17 zenith loadcpufreq[981]: Loading cpufreq kernel modules...done (none). Feb 22 12:24:17 zenith systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Feb 22 12:24:17 zenith wpa_supplicant[1039]: Successfully initialized wpa_supplicant Feb 22 12:24:17 zenith systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Feb 22 12:24:17 zenith systemd[1]: Started wpa_supplicant.service - WPA supplicant. Feb 22 12:24:17 zenith systemd[1]: Reached target network.target - Network. Feb 22 12:24:17 zenith systemd[1]: Reached target network-online.target - Network is Online. Feb 22 12:24:17 zenith systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Feb 22 12:24:17 zenith systemd[1]: Starting ntpsec.service - Network Time Service... Feb 22 12:24:17 zenith systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Feb 22 12:24:17 zenith systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 22 12:24:17 zenith systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Feb 22 12:24:17 zenith systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Feb 22 12:24:17 zenith systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Feb 22 12:24:17 zenith systemd[1]: iptables.service: Deactivated successfully. Feb 22 12:24:17 zenith systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Feb 22 12:24:17 zenith systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Feb 22 12:24:17 zenith ntpd[1102]: INIT: ntpd ntpsec-1.2.2: Starting Feb 22 12:24:17 zenith ntp-systemd-wrapper[1102]: 2026-02-22T12:24:17 ntpd[1102]: INIT: ntpd ntpsec-1.2.2: Starting Feb 22 12:24:17 zenith ntp-systemd-wrapper[1102]: 2026-02-22T12:24:17 ntpd[1102]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Feb 22 12:24:17 zenith ntpd[1102]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Feb 22 12:24:17 zenith systemd[1]: Started ntpsec.service - Network Time Service. Feb 22 12:24:17 zenith ifplugd(eth0)[1140]: ifplugd 0.28 initializing. Feb 22 12:24:17 zenith ifplugd(eth0)[1140]: Using interface eth0/2C:CF:67:95:E5:67 with driver (version: 6.12.47-v8+) Feb 22 12:24:17 zenith ifplugd(eth0)[1140]: Using detection mode: SIOCETHTOOL Feb 22 12:24:17 zenith ifplugd(eth0)[1140]: Initialization complete, link beat not detected. Feb 22 12:24:17 zenith systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Feb 22 12:24:17 zenith cpufrequtils[1085]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 22 12:24:17 zenith systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Feb 22 12:24:17 zenith ntpd[1120]: INIT: precision = 0.389 usec (-21) Feb 22 12:24:17 zenith ntpd[1120]: INIT: successfully locked into RAM Feb 22 12:24:17 zenith ntpd[1120]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Feb 22 12:24:17 zenith ntpd[1120]: CONFIG: restrict nopeer ignored Feb 22 12:24:17 zenith rpi-eeprom-update[982]: BOOTLOADER: up to date Feb 22 12:24:17 zenith rpi-eeprom-update[982]: CURRENT: Wed Nov 5 17:37:18 UTC 2025 (1762364238) Feb 22 12:24:17 zenith rpi-eeprom-update[982]: LATEST: Wed Nov 5 17:37:18 UTC 2025 (1762364238) Feb 22 12:24:17 zenith rpi-eeprom-update[982]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default) Feb 22 12:24:17 zenith rpi-eeprom-update[982]: Use raspi-config to change the release. Feb 22 12:24:17 zenith ntpd[1120]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 22 12:24:17 zenith ntpd[1120]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Feb 22 12:24:17 zenith ntpd[1120]: INIT: Using SO_TIMESTAMPNS(ns) Feb 22 12:24:17 zenith ntpd[1120]: IO: Listen and drop on 0 v6wildcard [::]:123 Feb 22 12:24:17 zenith ntpd[1120]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 22 12:24:17 zenith ntpd[1120]: IO: Listen normally on 2 lo 127.0.0.1:123 Feb 22 12:24:17 zenith ntpd[1120]: IO: Listening on routing socket on fd #19 for interface updates Feb 22 12:24:17 zenith systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Feb 22 12:24:17 zenith ntpd[1120]: INIT: This system has a 32-bit time_t. Feb 22 12:24:17 zenith ntpd[1120]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Feb 22 12:24:17 zenith ntpd[1120]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 22 12:24:17 zenith ntpd[1120]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Feb 22 12:24:17 zenith ntpd[1120]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Feb 22 12:24:17 zenith ntpd[1120]: NTSc: Using system default root certificates. Feb 22 12:24:17 zenith ifplugd[1089]: Network Interface Plugging Daemon...start eth0...done. Feb 22 12:24:17 zenith ntpd[1120]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Feb 22 12:24:17 zenith systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Feb 22 12:24:17 zenith sshd[1160]: Server listening on 0.0.0.0 port 22. Feb 22 12:24:17 zenith sshd[1160]: Server listening on :: port 22. Feb 22 12:24:17 zenith systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Feb 22 12:24:17 zenith volumio-remote-updater[988]: Volumio OTA Client: 1.7.0 Feb 22 12:24:17 zenith volumio-remote-updater[988]: Error: No active session Feb 22 12:24:17 zenith volumio-remote-updater[988]: [2026-02-22 12:24:17] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 22 12:24:17 zenith volumio-remote-updater[988]: [2026-02-22 12:24:17] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 22 12:24:17 zenith volumio-remote-updater[988]: [2026-02-22 12:24:17] [error] handle_connect error: Connection refused Feb 22 12:24:17 zenith wireless.js[989]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Feb 22 12:24:17 zenith wireless.js[989]: WIRELESS.JS: Wireless.js initializing wireless flow Feb 22 12:24:17 zenith wireless.js[989]: WIRELESS.JS: Cleaning previous... Feb 22 12:24:17 zenith sudo[1187]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 22 12:24:17 zenith sudo[1187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:17 zenith sudo[1187]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:17 zenith sudo[1189]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 22 12:24:17 zenith sudo[1189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:17 zenith avahi-daemon[976]: Server startup complete. Host name is zenith.local. Local service cookie is 1258742970. Feb 22 12:24:18 zenith sudo[1189]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:18 zenith wireless.js[989]: WIRELESS.JS: Stopped aP Feb 22 12:24:18 zenith sudo[1197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 12:24:18 zenith sudo[1197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:18 zenith sudo[1197]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:18 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 22 12:24:18 zenith ntpd[1120]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:18 zenith ntpd[1120]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 22 12:24:18 zenith ntpd[1120]: DNS: dns_check: DNS error: -11, System error Feb 22 12:24:18 zenith ntpd[1120]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Feb 22 12:24:18 zenith sudo[1199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 22 12:24:18 zenith sudo[1199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:18 zenith sudo[1199]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:18 zenith sudo[1208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 12:24:18 zenith sudo[1208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:18 zenith sudo[1208]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:18 zenith sudo[1210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 22 12:24:18 zenith sudo[1210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:18 zenith avahi-daemon[976]: Service "Zenith" (/services/volumio.service) successfully established. Feb 22 12:24:19 zenith ntpd[1120]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:19 zenith ntpd[1120]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 22 12:24:19 zenith ntpd[1120]: DNS: dns_check: DNS error: -11, System error Feb 22 12:24:19 zenith ntpd[1120]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Feb 22 12:24:20 zenith ntpd[1120]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:20 zenith ntpd[1120]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 22 12:24:20 zenith ntpd[1120]: DNS: dns_check: DNS error: -11, System error Feb 22 12:24:20 zenith ntpd[1120]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Feb 22 12:24:20 zenith systemd[1]: systemd-rfkill.service: Deactivated successfully. Feb 22 12:24:21 zenith sudo[1210]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:21 zenith wireless.js[989]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Feb 22 12:24:21 zenith sudo[1236]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 12:24:21 zenith sudo[1236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:21 zenith sudo[1236]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:21 zenith sudo[1238]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Feb 22 12:24:21 zenith sudo[1238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:21 zenith sudo[1238]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:21 zenith wireless.js[989]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Feb 22 12:24:21 zenith wireless.js[989]: WIRELESS.JS: Start wireless flow Feb 22 12:24:21 zenith wireless.js[989]: WIRELESS.JS: Stopped hotspot (if there).. Feb 22 12:24:21 zenith sudo[1249]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 22 12:24:21 zenith sudo[1249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:21 zenith sudo[1249]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:21 zenith sudo[1251]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 22 12:24:21 zenith sudo[1251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:21 zenith ntpd[1120]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:21 zenith ntpd[1120]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 22 12:24:21 zenith ntpd[1120]: DNS: dns_check: DNS error: -11, System error Feb 22 12:24:21 zenith ntpd[1120]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Feb 22 12:24:21 zenith sudo[1251]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:21 zenith wireless.js[989]: WIRELESS.JS: DHCP IP fallback Feb 22 12:24:21 zenith wpa_supplicant[1257]: Successfully initialized wpa_supplicant Feb 22 12:24:21 zenith wireless.js[989]: WIRELESS.JS: Start ap Feb 22 12:24:21 zenith sudo[1258]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 22 12:24:21 zenith sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:21 zenith dhcpcd[1003]: control_free: No such file or directory Feb 22 12:24:21 zenith sudo[1258]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:21 zenith dhcpcd[1003]: control command: /sbin/dhcpcd Feb 22 12:24:21 zenith dhcpcd[1003]: lo: IAID 00:00:00:00 Feb 22 12:24:21 zenith dhcpcd[1003]: lo: adding address fe80::6430:6751:43e0:3f69 Feb 22 12:24:21 zenith dhcpcd[1003]: ipv6_addaddr1: Permission denied Feb 22 12:24:21 zenith dhcpcd[1003]: lo: using static address 127.0.0.1/8 Feb 22 12:24:21 zenith dhcpcd[1003]: lo: adding route to 127.0.0.0/8 Feb 22 12:24:21 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 22 12:24:21 zenith systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Feb 22 12:24:21 zenith wpa_supplicant[1257]: nl80211: kernel reports: Registration to specific type not supported Feb 22 12:24:22 zenith volumio-remote-updater[988]: [2026-02-22 12:24:22] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 22 12:24:22 zenith volumio-remote-updater[988]: [2026-02-22 12:24:22] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 22 12:24:22 zenith volumio-remote-updater[988]: [2026-02-22 12:24:22] [error] handle_connect error: Connection refused Feb 22 12:24:22 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:22 zenith sudo[1290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:22 zenith sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:22 zenith sudo[1290]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:22 zenith bash[1096]: setdatetime-helper: all HTTPS Date fallbacks failed Feb 22 12:24:22 zenith systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 12:24:22 zenith systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 12:24:23 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:23 zenith sudo[1295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:23 zenith sudo[1295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:23 zenith sudo[1295]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:24 zenith wpa_supplicant[1287]: wlan0: Trying to associate with d8:8e:d4:11:15:e7 (SSID='18070Farm' freq=5500 MHz) Feb 22 12:24:24 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:24 zenith sudo[1299]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:24 zenith sudo[1299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:24 zenith sudo[1299]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:24 zenith wpa_supplicant[1287]: wlan0: Associated with d8:8e:d4:11:15:e7 Feb 22 12:24:24 zenith wpa_supplicant[1287]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 22 12:24:24 zenith wpa_supplicant[1287]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Feb 22 12:24:24 zenith wpa_supplicant[1287]: wlan0: WPA: Key negotiation completed with d8:8e:d4:11:15:e7 [PTK=CCMP GTK=CCMP] Feb 22 12:24:24 zenith wpa_supplicant[1287]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:8e:d4:11:15:e7 completed [id=0 id_str=] Feb 22 12:24:24 zenith dhcpcd[1003]: wlan0: carrier acquired Feb 22 12:24:24 zenith dhcpcd[1003]: wlan0: connected to Access Point: 18070Farm Feb 22 12:24:24 zenith dhcpcd[1003]: wlan0: IAID 67:95:e5:69 Feb 22 12:24:24 zenith dhcpcd[1003]: wlan0: adding address fe80::422e:83e0:3e32:602d Feb 22 12:24:24 zenith dhcpcd[1003]: ipv6_addaddr1: Permission denied Feb 22 12:24:25 zenith dhcpcd[1003]: wlan0: soliciting an IPv6 router Feb 22 12:24:25 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:25 zenith sudo[1304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:25 zenith sudo[1304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:25 zenith sudo[1304]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:26 zenith dhcpcd[1003]: wlan0: rebinding lease of 192.168.133.234 Feb 22 12:24:26 zenith dhcpcd[1003]: wlan0: probing address 192.168.133.234/24 Feb 22 12:24:26 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:26 zenith sudo[1310]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:26 zenith sudo[1310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:26 zenith sudo[1310]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:27 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:27 zenith sudo[1314]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:27 zenith sudo[1314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:27 zenith sudo[1314]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:28 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:28 zenith sudo[1318]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:28 zenith sudo[1318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:28 zenith sudo[1318]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:29 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:29 zenith sudo[1322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:29 zenith sudo[1322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:29 zenith sudo[1322]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:30 zenith volumio-remote-updater[988]: [2026-02-22 12:24:30] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 22 12:24:30 zenith volumio-remote-updater[988]: [2026-02-22 12:24:30] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 22 12:24:30 zenith volumio-remote-updater[988]: [2026-02-22 12:24:30] [error] handle_connect error: Connection refused Feb 22 12:24:30 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:30 zenith sudo[1326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:30 zenith sudo[1326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:30 zenith sudo[1326]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:31 zenith dhcpcd[1003]: wlan0: leased 192.168.133.234 for 86400 seconds Feb 22 12:24:31 zenith avahi-daemon[976]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.133.234. Feb 22 12:24:31 zenith avahi-daemon[976]: New relevant interface wlan0.IPv4 for mDNS. Feb 22 12:24:31 zenith avahi-daemon[976]: Registering new address record for 192.168.133.234 on wlan0.IPv4. Feb 22 12:24:31 zenith dhcpcd[1003]: wlan0: adding route to 192.168.133.0/24 Feb 22 12:24:31 zenith dhcpcd[1003]: wlan0: adding default route via 192.168.133.1 Feb 22 12:24:31 zenith systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 22 12:24:31 zenith wireless.js[989]: WIRELESS.JS: trying... Feb 22 12:24:31 zenith sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 12:24:31 zenith sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:31 zenith sudo[1351]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:31 zenith wireless.js[989]: WIRELESS.JS: Connected to: ----18070Farm---- Feb 22 12:24:31 zenith sudo[1354]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 12:24:31 zenith sudo[1354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:31 zenith sudo[1354]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:31 zenith wireless.js[989]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.133.234, ipV6 is undefined Feb 22 12:24:31 zenith wireless.js[989]: WIRELESS.JS: It's done! AP Feb 22 12:24:31 zenith wireless.js[989]: WIRELESS.JS: Restarting avahi-daemon... Feb 22 12:24:31 zenith avahi-daemon[976]: Got SIGTERM, quitting. Feb 22 12:24:31 zenith avahi-daemon[976]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.133.234. Feb 22 12:24:31 zenith avahi-daemon[976]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 22 12:24:31 zenith systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 22 12:24:31 zenith avahi-daemon[976]: avahi-daemon 0.8 exiting. Feb 22 12:24:31 zenith systemd[1]: avahi-daemon.service: Deactivated successfully. Feb 22 12:24:31 zenith systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 22 12:24:31 zenith systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 22 12:24:31 zenith avahi-daemon[1360]: Process 976 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Feb 22 12:24:31 zenith avahi-daemon[1360]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Feb 22 12:24:31 zenith avahi-daemon[1360]: Successfully dropped root privileges. Feb 22 12:24:31 zenith wireless.js[989]: WIRELESS.JS: Notified systemd about wireless ready Feb 22 12:24:31 zenith avahi-daemon[1360]: avahi-daemon 0.8 starting up. Feb 22 12:24:31 zenith systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 22 12:24:31 zenith avahi-daemon[1360]: Successfully called chroot(). Feb 22 12:24:31 zenith avahi-daemon[1360]: Successfully dropped remaining capabilities. Feb 22 12:24:31 zenith avahi-daemon[1360]: Loading service file /services/volumio.service. Feb 22 12:24:31 zenith avahi-daemon[1360]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.133.234. Feb 22 12:24:31 zenith avahi-daemon[1360]: New relevant interface wlan0.IPv4 for mDNS. Feb 22 12:24:31 zenith avahi-daemon[1360]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 22 12:24:31 zenith avahi-daemon[1360]: New relevant interface lo.IPv4 for mDNS. Feb 22 12:24:31 zenith avahi-daemon[1360]: Network interface enumeration completed. Feb 22 12:24:31 zenith avahi-daemon[1360]: Registering new address record for 192.168.133.234 on wlan0.IPv4. Feb 22 12:24:31 zenith avahi-daemon[1360]: Registering new address record for 127.0.0.1 on lo.IPv4. Feb 22 12:24:31 zenith systemd[1]: Started wireless.service - Wireless Services. Feb 22 12:24:31 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 22 12:24:31 zenith systemd[1]: Started volumio.service - Volumio Backend Module. Feb 22 12:24:31 zenith systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 22 12:24:31 zenith systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 22 12:24:31 zenith systemd[1]: Received SIGRTMIN+21 from PID 396 (plymouthd). Feb 22 12:24:31 zenith volumio-cpu-tweak[1366]: Setting RT Priority for mpd Feb 22 12:24:31 zenith volumio-cpu-tweak[1380]: pid 35's current scheduling policy: SCHED_OTHER Feb 22 12:24:31 zenith volumio-cpu-tweak[1380]: pid 35's current scheduling priority: 0 Feb 22 12:24:31 zenith volumio-cpu-tweak[1366]: Setting MPD Affinity Feb 22 12:24:31 zenith volumio-cpu-tweak[1381]: pid 3's current affinity mask: f Feb 22 12:24:31 zenith volumio-cpu-tweak[1366]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 22 12:24:31 zenith volumio-cpu-tweak[1366]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Feb 22 12:24:31 zenith systemd[1]: Received SIGRTMIN+21 from PID 396 (plymouthd). Feb 22 12:24:31 zenith systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 22 12:24:31 zenith systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 22 12:24:31 zenith systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 22 12:24:31 zenith systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Feb 22 12:24:31 zenith systemd[1]: Reached target getty.target - Login Prompts. Feb 22 12:24:31 zenith systemd[1]: Reached target multi-user.target - Multi-User System. Feb 22 12:24:31 zenith systemd[1]: Reached target graphical.target - Graphical Interface. Feb 22 12:24:31 zenith systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 22 12:24:31 zenith systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 22 12:24:31 zenith systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 22 12:24:31 zenith systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 22 12:24:31 zenith systemd[1]: Startup finished in 10.562s (kernel) + 17.280s (userspace) = 27.843s. Feb 22 12:24:32 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:32 zenith volumio[1364]: info: ----- Volumio3 ---- Feb 22 12:24:32 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:32 zenith volumio[1364]: info: ----- System startup ---- Feb 22 12:24:32 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:32 zenith ntpd[1120]: IO: Listen normally on 3 wlan0 192.168.133.234:123 Feb 22 12:24:32 zenith ntpd[1120]: IO: new interface(s) found: waking up resolver Feb 22 12:24:32 zenith ntpd[1120]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:32 zenith ntpd[1120]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 22 12:24:32 zenith ntpd[1120]: DNS: Pool taking: 96.19.94.82 Feb 22 12:24:32 zenith ntpd[1120]: DNS: Pool taking: 172.233.155.39 Feb 22 12:24:32 zenith ntpd[1120]: DNS: Pool taking: 66.187.4.132 Feb 22 12:24:32 zenith ntpd[1120]: DNS: Pool taking: 198.71.50.75 Feb 22 12:24:32 zenith ntpd[1120]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 22 12:24:32 zenith volumio[1364]: info: MYVOLUMIO Environment detected Feb 22 12:24:32 zenith volumio[1364]: info: Plugin folders cleanup Feb 22 12:24:32 zenith volumio[1364]: info: Scanning into folder /volumio/app/plugins/ Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category audio_interface Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category miscellanea Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category music_service Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category plugins.json Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category system_controller Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category user_interface Feb 22 12:24:32 zenith volumio[1364]: info: Scanning into folder /data/plugins/ Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category music_service Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category system_controller Feb 22 12:24:32 zenith volumio[1364]: info: Scanning category user_interface Feb 22 12:24:32 zenith volumio[1364]: info: Plugin folders cleanup completed Feb 22 12:24:32 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:32 zenith volumio[1364]: info: ----- Core plugins startup ---- Feb 22 12:24:32 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:32 zenith volumio[1364]: info: Loading plugins from folder /volumio/app/plugins/ Feb 22 12:24:32 zenith volumio[1364]: info: Adding plugin upnp to MyMusic Plugins Feb 22 12:24:32 zenith volumio[1364]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 22 12:24:32 zenith volumio[1364]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 22 12:24:32 zenith volumio[1364]: info: Loading plugins from folder /data/plugins/ Feb 22 12:24:32 zenith volumio[1364]: info: Loading plugin "system"... Feb 22 12:24:32 zenith volumio[1364]: info: Loading plugin "appearance"... Feb 22 12:24:32 zenith avahi-daemon[1360]: Server startup complete. Host name is zenith.local. Local service cookie is 4075689001. Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "network"... Feb 22 12:24:33 zenith volumio[1364]: info: Refreshing Cached IP Addresses Feb 22 12:24:33 zenith sudo[1404]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 12:24:33 zenith sudo[1404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:33 zenith sudo[1404]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:33 zenith sudo[1406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 12:24:33 zenith sudo[1406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "services"... Feb 22 12:24:33 zenith sudo[1406]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "alsa_controller"... Feb 22 12:24:33 zenith sudo[1414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 12:24:33 zenith sudo[1414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:33 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "wizard"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "networkfs"... Feb 22 12:24:33 zenith volumio[1364]: info: Starting Udev Watcher for removable devices Feb 22 12:24:33 zenith sudo[1440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=michel,password=mx68BL&BYF,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //library/data/media/music /mnt/NAS/Library Feb 22 12:24:33 zenith sudo[1440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:33 zenith volumio[1364]: info: Ignoring mount for partition: boot Feb 22 12:24:33 zenith volumio[1364]: info: Ignoring mount for partition: volumio Feb 22 12:24:33 zenith volumio[1364]: info: Ignoring mount for partition: volumio_data Feb 22 12:24:33 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "volumio_command_line_client"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "upnp"... Feb 22 12:24:33 zenith volumio[1364]: info: [1771781073195] Starting Upmpd Daemon Feb 22 12:24:33 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "my_music"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "mpd"... Feb 22 12:24:33 zenith kernel: netfs: FS-Cache loaded Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "upnp_browser"... Feb 22 12:24:33 zenith kernel: Key type cifs.spnego registered Feb 22 12:24:33 zenith kernel: Key type cifs.idmap registered Feb 22 12:24:33 zenith kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 22 12:24:33 zenith kernel: CIFS: Attempting to mount //library/data/media/music Feb 22 12:24:33 zenith ntpd[1120]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:33 zenith ntpd[1120]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 207.58.172.126 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 216.229.4.69 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 139.177.202.26 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 23.168.24.210 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 2602:fb95:16::123 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 2605:6f01:2000:13::71b2:e47d Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 2001:5a8:601:35::2ad7 Feb 22 12:24:33 zenith ntpd[1120]: DNS: Pool taking: 2602:f9f3:1:2f::4:123 Feb 22 12:24:33 zenith ntpd[1120]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 22 12:24:33 zenith volumio[1364]: info: Starting UPNP Browser Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "alarm-clock"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "airplay_emulation"... Feb 22 12:24:33 zenith volumio[1364]: info: Starting Shairport Sync Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "last_100"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "webradio"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "i2s_dacs"... Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "volumiodiscovery"... Feb 22 12:24:33 zenith volumio[1364]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 12:24:33 zenith volumio[1364]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 12:24:33 zenith volumio[1364]: *** WARNING *** For more information see Feb 22 12:24:33 zenith volumio[1364]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 12:24:33 zenith volumio[1364]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 12:24:33 zenith volumio[1364]: *** WARNING *** For more information see Feb 22 12:24:33 zenith node[1364]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 12:24:33 zenith node[1364]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 12:24:33 zenith node[1364]: *** WARNING *** For more information see Feb 22 12:24:33 zenith node[1364]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 12:24:33 zenith node[1364]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 12:24:33 zenith node[1364]: *** WARNING *** For more information see Feb 22 12:24:33 zenith volumio[1364]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 22 12:24:33 zenith volumio[1364]: info: Discovery: Started advertising with name: Zenith Feb 22 12:24:33 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 12:24:33 zenith volumio[1364]: info: Loading plugin "spop"... Feb 22 12:24:33 zenith avahi-daemon[1360]: Service "Zenith" (/services/volumio.service) successfully established. Feb 22 12:24:33 zenith sudo[1440]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "outputs"... Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "albumart"... Feb 22 12:24:34 zenith volumio[1364]: info: Plugin example_plugin is not enabled Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "inputs"... Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "updater_comm"... Feb 22 12:24:34 zenith volumio[1364]: info: Plugin mpdemulation is not enabled Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "rest_api"... Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "websocket"... Feb 22 12:24:34 zenith volumio[1364]: info: Starting Socket.io Server version 1.7.4 Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "backup_restore"... Feb 22 12:24:34 zenith volumio[1470]: Forking 3 albumart workers Feb 22 12:24:34 zenith volumio[1364]: info: Applying required configuration parameters for plugin backup_restore Feb 22 12:24:34 zenith volumio[1364]: info: Loading plugin "Systeminfo"... Feb 22 12:24:34 zenith volumio[1364]: info: Loading i18n strings for locale en Feb 22 12:24:34 zenith volumio[1364]: Updating browse sources language Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::initPlayerControls Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: Express server listening on port 3000 Feb 22 12:24:34 zenith volumio[1364]: [Metrics] WebUI: 2s 67.32ms Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::resetVolumioState Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::getcurrentVolume Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 12:24:34 zenith volumio[1364]: info: Volumio Network Manager: Network status updated: 2 Feb 22 12:24:34 zenith volumio[1480]: Starting albumart workers Feb 22 12:24:34 zenith volumio[1364]: verbose: New Socket.io Connection to 192.168.133.234 from 192.168.133.66 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 1 Feb 22 12:24:34 zenith volumio[1482]: Starting albumart workers Feb 22 12:24:34 zenith volumio[1481]: Starting albumart workers Feb 22 12:24:34 zenith volumio[1364]: verbose: New Socket.io Connection to 192.168.133.234 from 192.168.133.66 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 2 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: Reloading queue from file Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::setRepeat null single undefined Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::pushState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::setRandom null Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::pushState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 22 12:24:34 zenith volumio[1364]: info: VolumeController:: Volume=32 Mute =false Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::pushState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::updateTrackBlock Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrackBlock Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 12:24:34 zenith volumio[1364]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 22 12:24:34 zenith volumio[1364]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Feb 22 12:24:34 zenith volumio[1364]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 22 12:24:34 zenith volumio[1364]: info: Setting Device type: Raspberry PI Feb 22 12:24:34 zenith volumio[1364]: info: Completed loading Core Plugins Feb 22 12:24:34 zenith volumio[1364]: info: Preparing to generate the ALSA configuration file Feb 22 12:24:34 zenith ntpd[1120]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:34 zenith volumio[1364]: info: Asound.conf file unchanged, so no further update is needed Feb 22 12:24:34 zenith volumio[1364]: info: Output device has changed, restarting MPD Feb 22 12:24:34 zenith volumio[1364]: info: Output device has changed, restarting Shairport Sync Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith sudo[1529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 12:24:34 zenith sudo[1531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 12:24:34 zenith sudo[1531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith sudo[1529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith sudo[1529]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith volumio[1364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 12:24:34 zenith volumio[1364]: info: ___________ START PLUGINS ___________ Feb 22 12:24:34 zenith volumio[1364]: info: ControllerMpd::onStart: Initializing MPD Feb 22 12:24:34 zenith volumio[1364]: info: Creating MPD Configuration file Feb 22 12:24:34 zenith sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 12:24:34 zenith sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 12:24:34 zenith volumio[1364]: info: [1771781074537] CoreMusicLibrary::Adding element Media Servers Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:34 zenith sudo[1539]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith volumio[1364]: info: UPNP Browser: Client initialized successfully Feb 22 12:24:34 zenith sudo[1542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 12:24:34 zenith sudo[1542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 12:24:34 zenith systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 12:24:34 zenith systemd[1]: mpd.service: Deactivated successfully. Feb 22 12:24:34 zenith systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 12:24:34 zenith systemd[1]: mpd.socket: Deactivated successfully. Feb 22 12:24:34 zenith systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 12:24:34 zenith systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 12:24:34 zenith volumio[1364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 12:24:34 zenith volumio[1364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 12:24:34 zenith volumio[1364]: info: [1771781074611] CoreMusicLibrary::Adding element Last_100 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 12:24:34 zenith volumio[1364]: info: [1771781074612] CoreMusicLibrary::Adding element Webradio Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 12:24:34 zenith volumio[1364]: info: Initializing BBC Radios Feb 22 12:24:34 zenith sudo[1557]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 12:24:34 zenith sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:24:34 zenith sudo[1572]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 12:24:34 zenith sudo[1557]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: Creating Spotify config file Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: Volumio Calling Home Feb 22 12:24:34 zenith volumio[1364]: info: MPD Permissions set Feb 22 12:24:34 zenith volumio[1364]: info: MPD Permissions set Feb 22 12:24:34 zenith volumio[1364]: info: VolumeController:: Volume=32 Mute =false Feb 22 12:24:34 zenith volumio[1364]: info: CoreStateMachine::pushState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 22 12:24:34 zenith volumio[1364]: info: Spotify config file written Feb 22 12:24:34 zenith volumio[1364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 22 12:24:34 zenith volumio[1364]: info: No need to fix Spotify hosts Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 12:24:34 zenith sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:24:34 zenith systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 22 12:24:34 zenith systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 22 12:24:34 zenith volumio[1364]: info: Starting Shairport Sync Feb 22 12:24:34 zenith volumio[1364]: info: Starting Shairport Sync Feb 22 12:24:34 zenith volumio[1364]: info: Starting Shairport Sync Feb 22 12:24:34 zenith sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 12:24:34 zenith sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 12:24:34 zenith go-librespot[1609]: go-librespot daemon starting... Feb 22 12:24:34 zenith sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 12:24:34 zenith sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith sudo[1597]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith sudo[1615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 12:24:34 zenith sudo[1615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:24:34 zenith systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 12:24:34 zenith sudo[1613]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith sudo[1611]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith volumio[1364]: info: Shairport-Sync Started Feb 22 12:24:34 zenith volumio[1364]: Error adding Membership: Error: addMembership EINVAL Feb 22 12:24:34 zenith volumio[1364]: info: Shairport-Sync Started Feb 22 12:24:34 zenith sudo[1615]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:34 zenith volumio[1364]: info: Shairport-Sync Started Feb 22 12:24:34 zenith ntpd[1120]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 22 12:24:34 zenith ntpd[1120]: DNS: Pool taking: 15.204.246.57 Feb 22 12:24:34 zenith ntpd[1120]: DNS: Pool taking: 173.255.255.133 Feb 22 12:24:34 zenith ntpd[1120]: DNS: Pool taking: 170.187.147.56 Feb 22 12:24:34 zenith ntpd[1120]: DNS: Pool taking: 73.185.182.209 Feb 22 12:24:34 zenith ntpd[1120]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 22 12:24:34 zenith go-librespot[1617]: time="2026-02-22T12:24:34-05:00" level=info msg="running go-librespot 0.4.0" Feb 22 12:24:34 zenith go-librespot[1617]: time="2026-02-22T12:24:34-05:00" level=debug msg="app state loaded" Feb 22 12:24:34 zenith go-librespot[1617]: time="2026-02-22T12:24:34-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 12:24:34 zenith volumio[1364]: info: Discovery: adding c4f84076-c4bc-4d19-9ff3-91769836aab1 Feb 22 12:24:34 zenith volumio[1364]: info: Discovery: Found device Zenith Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:34 zenith volumio[1364]: info: Discovery: this is already registered, c4f84076-c4bc-4d19-9ff3-91769836aab1 Feb 22 12:24:34 zenith volumio[1364]: info: Discovery: Found device Zenith Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:34 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:34 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:35 zenith volumio[1364]: info: Volumio called home Feb 22 12:24:35 zenith volumio[1364]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 12:24:35 zenith volumio[1364]: SPOTIFY: BQBrOaBzeO9uecGBT2eOBdWZnH6xp3RkEpvsqV5mwJeV7YrRIMNfTsXoS4keDt8yKZIoraCwf82t53KzwJYFbgT_Yf_R8XgIFnv70mstVTufRIThpH6M6bRN-Wg80dpXJpkHO4OITGOsOJVBCg97HzE0Pb8YO2UUfsskUaQMRq9ojxqMr0d6FQ4DHwsLqwjlI2Hkv8m6t27aTLozMmtquOqSKWW6qBzvMkQ8ru9-eAsh9CshLcJCkg8hxmiXgmT1-bJ47VQ_ckVVep2uv33ADs9ftJ02A1PzbVw Feb 22 12:24:35 zenith volumio[1364]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 12:24:35 zenith volumio[1364]: info: New Spotify access token = BQBrOaBzeO9uecGBT2eOBdWZnH6xp3RkEpvsqV5mwJeV7YrRIMNfTsXoS4keDt8yKZIoraCwf82t53KzwJYFbgT_Yf_R8XgIFnv70mstVTufRIThpH6M6bRN-Wg80dpXJpkHO4OITGOsOJVBCg97HzE0Pb8YO2UUfsskUaQMRq9ojxqMr0d6FQ4DHwsLqwjlI2Hkv8m6t27aTLozMmtquOqSKWW6qBzvMkQ8ru9-eAsh9CshLcJCkg8hxmiXgmT1-bJ47VQ_ckVVep2uv33ADs9ftJ02A1PzbVw Feb 22 12:24:35 zenith volumio[1364]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 22 12:24:35 zenith go-librespot[1617]: time="2026-02-22T12:24:35-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 12:24:35 zenith go-librespot[1617]: time="2026-02-22T12:24:35-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 12:24:35 zenith go-librespot[1617]: time="2026-02-22T12:24:35-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 12:24:35 zenith go-librespot[1617]: time="2026-02-22T12:24:35-05:00" level=info msg="zeroconf server listening on port 34025" Feb 22 12:24:35 zenith ntpd[1120]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 12:24:35 zenith volumio[1364]: SPOTIFY: User informations: {"country":"US","display_name":"Michel Alexander","email":"michel.alexander@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/126269301"},"followers":{"href":null,"total":5},"href":"https://api.spotify.com/v1/users/126269301","id":"126269301","images":[{"height":300,"url":"https://scontent-mrs2-1.xx.fbcdn.net/v/t39.30808-1/468535849_10160940685209385_6134084513883591725_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=109&ccb=1-7&_nc_sid=08baa4&_nc_ohc=xdn7m-rrdlwQ7kNvwE5n1RC&_nc_oc=Adl8euI4jQ8gE9qzm9X75YfAoCzCgpTSFpuyvUyMibqSEYu9jHC4sXYTSf86rp1414ZELpD6TXH-eE1ky_BLbAEp&_nc_zt=24&_nc_ht=scontent-mrs2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=QKVhAYMiG2Eho0vsxQidhQ&_nc_tpa=Q5bMBQEsRfyaJiPK038cQC7HeZMoJHzY_ExL07cgf7mSWSAsiM_ihtMiWgzZ6MSFWqsZyDFW24Ws&oh=00_AftUnTxkpFd9nnDOUeIAmfBKcFbxDbUt8RryAvPsd9SNPA&oe=69A0D23F","width":300},{"height":64,"url":"https://scontent-mrs2-1.xx.fbcdn.net/v/t39.30808-1/468535849_10160940685209385_6134084513883591725_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=109&ccb=1-7&_nc_sid=28885b&_nc_ohc=xdn7m-rrdlwQ7kNvwE5n1RC&_nc_oc=Adl8euI4jQ8gE9qzm9X75YfAoCzCgpTSFpuyvUyMibqSEYu9jHC4sXYTSf86rp1414ZELpD6TXH-eE1ky_BLbAEp&_nc_zt=24&_nc_ht=scontent-mrs2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=QKVhAYMiG2Eho0vsxQidhQ&_nc_tpa=Q5bMBQGaUQLGLeo418oWD5-YQAky79ob_dKmExrS23dhg5sG5YUHef4CL9dbrG5lTERMlPy8GmVE&oh=00_Afu8jA_iL9xJJAeIgnckgZv5sN6mULJ8DYx0VCzLE5fGoQ&oe=69A0D23F","width":64}],"product":"premium","type":"user","uri":"spotify:user:126269301"} Feb 22 12:24:35 zenith volumio[1364]: info: Spotify Successfully logged in Feb 22 12:24:35 zenith volumio[1364]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 12:24:35 zenith volumio[1364]: info: [1771781075562] CoreMusicLibrary::Adding element Spotify Feb 22 12:24:35 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:35 zenith volumio[1364]: Cannot find translation for source Spotify Feb 22 12:24:35 zenith ntpd[1120]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 22 12:24:35 zenith ntpd[1120]: DNS: Pool taking: 50.205.57.38 Feb 22 12:24:35 zenith ntpd[1120]: DNS: Pool skipping: 172.233.155.39 Feb 22 12:24:35 zenith ntpd[1120]: DNS: Pool taking: 144.202.66.214 Feb 22 12:24:35 zenith ntpd[1120]: DNS: Pool taking: 23.131.160.7 Feb 22 12:24:35 zenith ntpd[1120]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 22 12:24:35 zenith go-librespot[1617]: time="2026-02-22T12:24:35-05:00" level=debug msg="obtained new client token: AAAtDBf10rzYYvZasKZMSsc8QSh5xqJPLpYBpjvEIvZeJ7Kc9l6svjW0iuNcSLBU9zTp0+yfm0hQxkuLzzpg7HWUFNGUGxEkB1hez9/mWZh+7Ia3PD6i2ZLHVvI3rVQYT5/L//NvimlPg1oIMKNbegrLml7PCoTdvWJclAUzwoDLoBOpj3e4MMtbHZMEjoD1AwF3nWTwzk1r7PQ13QW9s5kQnnbEUYvYda6obSWMkCxoKGALL2ynyftCofg=" Feb 22 12:24:35 zenith go-librespot[1617]: time="2026-02-22T12:24:35-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 22 12:24:36 zenith volumio[1364]: verbose: New Socket.io Connection to 192.168.133.234 from 192.168.133.66 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 4 Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:36 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:36 zenith mpd[1575]: 2026-02-22T12:24:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 12:24:36 zenith systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 12:24:36 zenith sudo[1542]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:36 zenith sudo[1531]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:36 zenith volumio[1364]: info: Completed starting Core Plugins Feb 22 12:24:36 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:36 zenith volumio[1364]: info: ----- MyVolumio plugins startup ---- Feb 22 12:24:36 zenith volumio[1364]: info: ------------------------------------------- Feb 22 12:24:36 zenith volumio[1364]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 22 12:24:36 zenith volumio[1364]: error: MPD error: The expression evaluated to a falsy value: Feb 22 12:24:36 zenith volumio[1364]: assert.ok(self.idling) Feb 22 12:24:36 zenith volumio[1364]: error: The expression evaluated to a falsy value: Feb 22 12:24:36 zenith volumio[1364]: assert.ok(self.idling) Feb 22 12:24:36 zenith volumio[1364]: info: MPD running with PID1575 Feb 22 12:24:36 zenith volumio[1364]: ,establishing connection Feb 22 12:24:36 zenith volumio[1364]: error: updateQueue error: null Feb 22 12:24:36 zenith volumio[1364]: error: updateQueue error: null Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:36 zenith volumio[1364]: info: Listing playlists Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:36 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:36 zenith volumio[1364]: info: Listing playlists Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:36 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:36 zenith volumio[1364]: info: Listing playlists Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:24:36 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:24:36 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:24:36 zenith volumio[1364]: info: Listing playlists Feb 22 12:24:36 zenith sudo[1414]: pam_unix(sudo:session): session closed for user root Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="completed keyexchange" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="completed challenge" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=info msg="authenticated AP" username="12*****01" Feb 22 12:24:37 zenith sshd[1401]: Accepted password for volumio from 192.168.133.66 port 56291 ssh2 Feb 22 12:24:37 zenith sshd[1401]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Feb 22 12:24:37 zenith systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Feb 22 12:24:37 zenith systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 22 12:24:37 zenith systemd-logind[986]: New session 1 of user volumio. Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=info msg="authenticated Login5" username="12*****01" Feb 22 12:24:37 zenith systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 22 12:24:37 zenith systemd[1]: Starting user@1000.service - User Manager for UID 1000... Feb 22 12:24:37 zenith (systemd)[1648]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="initializing zeroconf session" username="12*****01" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="dealer connection opened" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=trace msg="starting accesspoint recv loop" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=trace msg="starting dealer recv loop" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=trace msg="received accesspoint ping" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="received connection id: OTc1N2YyYTgtOTU3...RTBCRThFMEFENA==" Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=trace msg="received accesspoint pong ack" Feb 22 12:24:37 zenith systemd[1648]: Queued start job for default target default.target. Feb 22 12:24:37 zenith systemd[1648]: Created slice app.slice - User Application Slice. Feb 22 12:24:37 zenith systemd[1648]: Reached target paths.target - Paths. Feb 22 12:24:37 zenith systemd[1648]: Reached target timers.target - Timers. Feb 22 12:24:37 zenith systemd[1648]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Feb 22 12:24:37 zenith systemd[1648]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 22 12:24:37 zenith systemd[1648]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 22 12:24:37 zenith systemd[1648]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 22 12:24:37 zenith systemd[1648]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 22 12:24:37 zenith systemd[1648]: Reached target sockets.target - Sockets. Feb 22 12:24:37 zenith systemd[1648]: Reached target basic.target - Basic System. Feb 22 12:24:37 zenith systemd[1]: Started user@1000.service - User Manager for UID 1000. Feb 22 12:24:37 zenith systemd[1648]: Started mpris-proxy.service - Bluetooth mpris proxy. Feb 22 12:24:37 zenith systemd[1648]: Reached target default.target - Main User Target. Feb 22 12:24:37 zenith systemd[1648]: Startup finished in 200ms. Feb 22 12:24:37 zenith systemd[1]: Started session-1.scope - Session 1 of User volumio. Feb 22 12:24:37 zenith mpris-proxy[1663]: Can't get on session bus Feb 22 12:24:37 zenith systemd[1648]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Feb 22 12:24:37 zenith systemd[1648]: mpris-proxy.service: Failed with result 'exit-code'. Feb 22 12:24:37 zenith sshd[1401]: pam_env(sshd:session): deprecated reading of user environment enabled Feb 22 12:24:37 zenith go-librespot[1617]: time="2026-02-22T12:24:37-05:00" level=debug msg="put connect state because NEW_DEVICE" Feb 22 12:24:37 zenith volumio[1364]: info: go-librespot daemon successfully initialized Feb 22 12:25:02 zenith ntpd[1120]: CLOCK: time stepped by 23.777479 Feb 22 12:25:02 zenith ntpd[1120]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 22 12:25:02 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:25:02 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:25:02 zenith volumio[1364]: info: Listing playlists Feb 22 12:25:02 zenith volumio[1364]: info: Listing playlists Feb 22 12:25:02 zenith sudo[1678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 12:25:02 zenith sudo[1678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:02 zenith sudo[1680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 12:25:02 zenith sudo[1680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:02 zenith systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 22 12:25:02 zenith sudo[1678]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:02 zenith volumio[1364]: info: Successfully started MPD Monitor Feb 22 12:25:02 zenith mpd_monitor.sh[1683]: MPD Monitor Service: Starting MPD Monitor Service Feb 22 12:25:02 zenith sudo[1680]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:02 zenith volumio[1364]: info: Successfully started MPD Monitor Feb 22 12:25:03 zenith volumio-remote-updater[988]: [2026-02-22 12:25:03] [connect] Successful connection Feb 22 12:25:03 zenith volumio-remote-updater[988]: [2026-02-22 12:25:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771781103 101 Feb 22 12:25:03 zenith volumio[1364]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Feb 22 12:25:04 zenith volumio[1364]: info: Initializing connection to go-librespot Websocket Feb 22 12:25:04 zenith go-librespot[1617]: time="2026-02-22T12:25:04-05:00" level=debug msg="new websocket client" Feb 22 12:25:04 zenith volumio[1364]: info: Connection to go-librespot Websocket established Feb 22 12:25:04 zenith volumio[1364]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 22 12:25:06 zenith sudo[1702]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 12:25:06 zenith sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:06 zenith sudo[1704]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 12:25:06 zenith sudo[1704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:06 zenith sudo[1702]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:06 zenith sudo[1704]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:06 zenith sudo[1708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 12:25:06 zenith sudo[1708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:07 zenith systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 12:25:07 zenith sudo[1708]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:07 zenith volumio[1364]: info: Upmpdcli Daemon Started Feb 22 12:25:07 zenith volumio[1364]: info: Getting Spotify volume Feb 22 12:25:07 zenith volumio[1364]: info: Spotify volume: 100 Feb 22 12:25:07 zenith volumio[1364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 22 12:25:07 zenith volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 22 12:25:07 zenith volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 22 12:25:07 zenith volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Feb 22 12:25:07 zenith volumio[1364]: SPOTIFY: SPOTIFY VOLUME 100 Feb 22 12:25:07 zenith volumio[1364]: SPOTIFY: VOLUMIO VOLUME 32 Feb 22 12:25:07 zenith volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 22 12:25:07 zenith volumio[1364]: info: Setting Spotify Volume from Volumio: 32 Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin bluetooth to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin multiroom to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin metavolumio to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin cd_controller to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 22 12:25:08 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:08 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:08 zenith volumio[1364]: info: Starting MyVolumio Remote Streaming Endpoints Feb 22 12:25:08 zenith volumio[1364]: info: MyVolumio login type: Token Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 22 12:25:08 zenith volumio[1364]: info: Streaming services startup Feb 22 12:25:08 zenith volumio[1364]: info: Starting Streaming Daemon Feb 22 12:25:08 zenith sudo[1728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 12:25:08 zenith sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:08 zenith volumio[1364]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 22 12:25:08 zenith sudo[1728]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:08 zenith volumio[1364]: error: Cannot start Volumio Streaming Daemon Feb 22 12:25:08 zenith volumio[1364]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 12:25:08 zenith volumio[1364]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 12:25:08 zenith volumio[1364]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 22 12:25:09 zenith volumio[1364]: SPOTIFY: SETTING SPOTIFY VOLUME 32 Feb 22 12:25:09 zenith volumio[1364]: info: Sending Spotify command with payload to local API: /player/volume Feb 22 12:25:09 zenith go-librespot[1617]: time="2026-02-22T12:25:09-05:00" level=debug msg="update volume requested to 20971/65535" Feb 22 12:25:09 zenith volumio[1364]: info: MyVolumio token set successfully Feb 22 12:25:09 zenith volumio[1364]: info: MYVOLUMIO: Adding device Feb 22 12:25:09 zenith volumio[1364]: info: MYVOLUMIO: Evaluating Server Feb 22 12:25:09 zenith go-librespot[1617]: time="2026-02-22T12:25:09-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 22 12:25:09 zenith go-librespot[1617]: time="2026-02-22T12:25:09-05:00" level=trace msg="emitting websocket event: volume" Feb 22 12:25:09 zenith volumio[1364]: info: MyVolumio Plan changed: premium Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 22 12:25:09 zenith volumio[1364]: info: Removing browser output: myVolumio user plan is not superstar Feb 22 12:25:09 zenith volumio[1364]: info: Removing audio output: Feb 22 12:25:09 zenith volumio[1364]: info: MYVOLUMIO: Adding device Feb 22 12:25:09 zenith volumio[1364]: info: MYVOLUMIO: Evaluating Server Feb 22 12:25:09 zenith volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":32,"max":100}} Feb 22 12:25:09 zenith volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 32 Feb 22 12:25:09 zenith systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 22 12:25:09 zenith volumio[1364]: info: Remote config written successfully Feb 22 12:25:09 zenith volumio[1364]: info: Starting Tunnel 1 Feb 22 12:25:09 zenith volumio[1364]: info: Starting Tunnel Connection Checker Feb 22 12:25:09 zenith volumio[1364]: info: Completed starting MyVolumio Plugin Feb 22 12:25:09 zenith volumio[1364]: info: MYVolumio Device enabled Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 22 12:25:09 zenith volumio[1364]: info: MyVolumio status changed Feb 22 12:25:09 zenith volumio[1364]: info: Streaming services startup Feb 22 12:25:09 zenith volumio[1364]: info: Starting Streaming Daemon Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 22 12:25:09 zenith sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 12:25:09 zenith sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 22 12:25:09 zenith sudo[1774]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:09 zenith volumio[1364]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 22 12:25:09 zenith sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 22 12:25:09 zenith sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:09 zenith sudo[1778]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:09 zenith volumio[1364]: info: MRS: MultiRoom plugin initialized Feb 22 12:25:09 zenith volumio[1364]: info: MRS: STOPPING SNAPCLIENT Feb 22 12:25:09 zenith volumio[1364]: info: MRS: Snap server stop Feb 22 12:25:09 zenith sudo[1796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 22 12:25:09 zenith volumio[1364]: info: MRS: STOPPING volumioStreaming Feb 22 12:25:09 zenith sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:09 zenith sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 22 12:25:09 zenith sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 22 12:25:09 zenith sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 22 12:25:09 zenith sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 22 12:25:09 zenith sudo[1803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 22 12:25:09 zenith sudo[1803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 22 12:25:09 zenith sudo[1805]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:09 zenith dhcpcd[895]: timed out Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 22 12:25:09 zenith sh[883]: timed out Feb 22 12:25:09 zenith sudo[1796]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 22 12:25:09 zenith dhcpcd[895]: dhcpcd exited Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 22 12:25:09 zenith sh[832]: ifup: failed to bring up eth0 Feb 22 12:25:09 zenith systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 22 12:25:09 zenith systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 22 12:25:09 zenith sudo[1800]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:09 zenith sudo[1803]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 22 12:25:09 zenith volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 22 12:25:09 zenith volumio[1364]: info: Preparing to generate the ALSA configuration file Feb 22 12:25:09 zenith volumio[1364]: info: Setting Geolocation for MyVolumio to us1 Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:09 zenith volumio[1364]: info: Setting Geolocation for MyVolumio to us1 Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:09 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:09 zenith volumio[1364]: info: MRS: Removed streaming files Feb 22 12:25:09 zenith volumio[1364]: info: MRS: volumioStreaming STOPPED Feb 22 12:25:09 zenith volumio[1364]: info: MRS: SNAPSERVER STOPPED Feb 22 12:25:10 zenith volumio[1364]: info: MRS: SNAPCLIENT STOPPED Feb 22 12:25:10 zenith volumio[1364]: error: Cannot start Volumio Streaming Daemon Feb 22 12:25:10 zenith volumio[1364]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 12:25:10 zenith volumio[1364]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 12:25:10 zenith volumio[1364]: info: Asound.conf file unchanged, so no further update is needed Feb 22 12:25:10 zenith volumio[1364]: info: Output device has changed, restarting MPD Feb 22 12:25:10 zenith volumio[1364]: info: Output device has changed, restarting Shairport Sync Feb 22 12:25:10 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:25:10 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:10 zenith sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 12:25:10 zenith sudo[1815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 12:25:10 zenith sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:10 zenith sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:10 zenith volumio[1364]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 12:25:10 zenith volumio[1364]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 12:25:10 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:25:10 zenith systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 12:25:10 zenith sudo[1811]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:10 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 22 12:25:10 zenith volumio[1364]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 22 12:25:10 zenith volumio[1364]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 22 12:25:10 zenith dbus-daemon[977]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=1000 pid=1364 comm="/usr/bin/node /volumio/index.js") Feb 22 12:25:10 zenith dbus-daemon[977]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found. Feb 22 12:25:10 zenith volumio[1364]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 22 12:25:10 zenith volumio[1364]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Feb 22 12:25:10 zenith volumio[1364]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 22 12:25:10 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 22 12:25:10 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 22 12:25:10 zenith volumio[1364]: info: MRS: Pushing multiroomSync output for this device Feb 22 12:25:10 zenith volumio[1364]: info: MRS: Pushing multiroomSync output Feb 22 12:25:10 zenith volumio[1364]: info: Adding audio output: Feb 22 12:25:10 zenith volumio[1364]: info: Adding audio output: Feb 22 12:25:10 zenith volumio[1364]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 12:25:10 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 12:25:10 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 22 12:25:10 zenith volumio[1364]: info: Adding METAVOLUMIO REST API Endpoints Feb 22 12:25:10 zenith volumio[1364]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 22 12:25:10 zenith volumio[1364]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 22 12:25:10 zenith volumio[1364]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 22 12:25:10 zenith volumio[1364]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 22 12:25:10 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 22 12:25:10 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 22 12:25:10 zenith volumio[1364]: info: Preparing CD Folders Feb 22 12:25:10 zenith volumio[1364]: info: Adding CD REST API Endpoints Feb 22 12:25:10 zenith volumio[1364]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 22 12:25:10 zenith volumio[1364]: info: Starting UDEV Watcher for CD Feb 22 12:25:10 zenith volumio[1364]: info: Detecting CD presence with UDEV Feb 22 12:25:10 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 22 12:25:10 zenith systemd[1]: mpd.service: Deactivated successfully. Feb 22 12:25:10 zenith systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 12:25:10 zenith systemd[1]: mpd.service: Consumed 1.335s CPU time. Feb 22 12:25:10 zenith systemd[1]: mpd.socket: Deactivated successfully. Feb 22 12:25:10 zenith systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 12:25:10 zenith systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 12:25:10 zenith systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 12:25:10 zenith systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 12:25:10 zenith sudo[1831]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 12:25:10 zenith sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 12:25:10 zenith sudo[1831]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:10 zenith mpd[1833]: 2026-02-22T12:25:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 12:25:10 zenith systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 12:25:10 zenith sudo[1815]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:15 zenith volumio[1364]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 22 12:25:15 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 22 12:25:15 zenith volumio[1364]: info: Adding inputs REST Endpoints Feb 22 12:25:15 zenith volumio[1364]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 22 12:25:15 zenith volumio[1364]: info: Scanning Audio Inputs Feb 22 12:25:15 zenith volumio[1364]: info: Checking against Known Cards name Feb 22 12:25:15 zenith volumio[1364]: info: Adding Server instance for streaming Feb 22 12:25:15 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 22 12:25:15 zenith volumio[1364]: error: Hi Res Audio Failed Login: Missing Login Data Feb 22 12:25:15 zenith volumio[1364]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 22 12:25:15 zenith volumio[1364]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 22 12:25:15 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 22 12:25:15 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 22 12:25:15 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 22 12:25:15 zenith volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 22 12:25:15 zenith volumio[1364]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 22 12:25:15 zenith sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 12:25:15 zenith sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:15 zenith volumio[1364]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 22 12:25:15 zenith volumio[1364]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 22 12:25:15 zenith volumio[1364]: info: Adding TIDAL REST API Endpoints Feb 22 12:25:15 zenith volumio[1364]: info: Stopping AccessToken refresher cron for QOBUZ Feb 22 12:25:15 zenith sudo[1847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 12:25:15 zenith sudo[1847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:15 zenith volumio[1364]: info: AccessToken refresher cron started for QOBUZ Feb 22 12:25:15 zenith volumio[1364]: info: Adding QOBUZ REST API Endpoints Feb 22 12:25:15 zenith volumio[1364]: info: Updating MyVolumio device info Feb 22 12:25:15 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:15 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:15 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:15 zenith volumio[1364]: info: Updating MyVolumio device info Feb 22 12:25:15 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:15 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:15 zenith volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 12:25:15 zenith sudo[1847]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:15 zenith volumio[1364]: info: MRS: Getting audio outputs on start Feb 22 12:25:15 zenith volumio[1364]: info: MRS: Requesting all other devices output Feb 22 12:25:15 zenith sudo[1840]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:15 zenith sudo[1849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 12:25:15 zenith sudo[1849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:25:15 zenith volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 12:25:15 zenith volumio[1364]: DBusError: Unit dbus-org.bluez.service not found. Feb 22 12:25:15 zenith volumio[1364]: at _methodReturnHandlers. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:343:27) Feb 22 12:25:15 zenith volumio[1364]: at handleMessage (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:101:11) Feb 22 12:25:15 zenith volumio[1364]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:151:9) Feb 22 12:25:15 zenith volumio[1364]: at EventEmitter.emit (node:events:514:28) Feb 22 12:25:15 zenith volumio[1364]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:132:14 Feb 22 12:25:15 zenith volumio[1364]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/message.js:65:9) Feb 22 12:25:15 zenith volumio[1364]: at Socket.emit (node:events:514:28) Feb 22 12:25:15 zenith volumio[1364]: at emitReadable_ (node:internal/streams/readable:609:12) Feb 22 12:25:15 zenith volumio[1364]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { Feb 22 12:25:15 zenith volumio[1364]: type: 'org.freedesktop.systemd1.NoSuchUnit', Feb 22 12:25:15 zenith volumio[1364]: text: 'Unit dbus-org.bluez.service not found.', Feb 22 12:25:15 zenith volumio[1364]: reply: Message { Feb 22 12:25:15 zenith volumio[1364]: type: 3, Feb 22 12:25:15 zenith volumio[1364]: _sent: false, Feb 22 12:25:15 zenith volumio[1364]: _serial: 3, Feb 22 12:25:15 zenith volumio[1364]: path: undefined, Feb 22 12:25:15 zenith volumio[1364]: interface: undefined, Feb 22 12:25:15 zenith volumio[1364]: member: undefined, Feb 22 12:25:15 zenith volumio[1364]: errorName: 'org.freedesktop.systemd1.NoSuchUnit', Feb 22 12:25:15 zenith volumio[1364]: replySerial: 2, Feb 22 12:25:15 zenith volumio[1364]: destination: ':1.15', Feb 22 12:25:15 zenith volumio[1364]: sender: 'org.freedesktop.DBus', Feb 22 12:25:15 zenith volumio[1364]: signature: 's', Feb 22 12:25:15 zenith volumio[1364]: body: [ 'Unit dbus-org.bluez.service not found.' ], Feb 22 12:25:15 zenith volumio[1364]: flags: 1 Feb 22 12:25:15 zenith volumio[1364]: } Feb 22 12:25:15 zenith volumio[1364]: } Feb 22 12:25:15 zenith volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 12:25:15 zenith systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 12:25:15 zenith sudo[1849]: pam_unix(sudo:session): session closed for user root Feb 22 12:25:15 zenith sudo[1867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 12:24' Feb 22 12:25:15 zenith sudo[1867]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"