Feb 08 11:32:39 zenith kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Feb 08 11:32:39 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 08 11:32:39 zenith kernel: KASLR enabled Feb 08 11:32:39 zenith kernel: random: crng init done Feb 08 11:32:39 zenith kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Feb 08 11:32:39 zenith kernel: efi: UEFI not found. Feb 08 11:32:39 zenith kernel: Reserved memory: created CMA memory pool at 0x000000003bc00000, size 64 MiB Feb 08 11:32:39 zenith kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 08 11:32:39 zenith kernel: OF: reserved mem: 0x000000003bc00000..0x000000003fbfffff (65536 KiB) map reusable linux,cma Feb 08 11:32:39 zenith kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Feb 08 11:32:39 zenith kernel: OF: reserved mem: 0x000000003fd23240..0x000000003fd23277 (0 KiB) nomap non-reusable nvram@0 Feb 08 11:32:39 zenith kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff] Feb 08 11:32:39 zenith kernel: Faking node 0 at [mem 0x0000000000000000-0x000000003fffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 1 at [mem 0x0000000040000000-0x000000007fffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 2 at [mem 0x0000000080000000-0x00000000bfffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 3 at [mem 0x00000000c0000000-0x00000000ffffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 4 at [mem 0x0000000100000000-0x000000013fffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 5 at [mem 0x0000000140000000-0x000000017fffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 6 at [mem 0x0000000180000000-0x00000001bfffffff] (1024MB) Feb 08 11:32:39 zenith kernel: Faking node 7 at [mem 0x00000001c0000000-0x00000001ffffffff] (1024MB) Feb 08 11:32:39 zenith kernel: NUMA: Initialized distance table, cnt=8 Feb 08 11:32:39 zenith kernel: NODE_DATA(0) allocated [mem 0x3bbfd300-0x3bbfffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(1) allocated [mem 0x7fffd300-0x7fffffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(2) allocated [mem 0xbfffd300-0xbfffffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(3) allocated [mem 0xffffd300-0xffffffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(4) allocated [mem 0x13fffd300-0x13fffffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(5) allocated [mem 0x17fffd300-0x17fffffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(6) allocated [mem 0x1bfffd300-0x1bfffffff] Feb 08 11:32:39 zenith kernel: NODE_DATA(7) allocated [mem 0x1fefa1300-0x1fefa3fff] Feb 08 11:32:39 zenith kernel: Zone ranges: Feb 08 11:32:39 zenith kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Feb 08 11:32:39 zenith kernel: DMA32 empty Feb 08 11:32:39 zenith kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Feb 08 11:32:39 zenith kernel: Movable zone start for each node Feb 08 11:32:39 zenith kernel: Early memory node ranges Feb 08 11:32:39 zenith kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Feb 08 11:32:39 zenith kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Feb 08 11:32:39 zenith kernel: node 1: [mem 0x0000000040000000-0x000000007fffffff] Feb 08 11:32:39 zenith kernel: node 2: [mem 0x0000000080000000-0x00000000bfffffff] Feb 08 11:32:39 zenith kernel: node 3: [mem 0x00000000c0000000-0x00000000ffffffff] Feb 08 11:32:39 zenith kernel: node 4: [mem 0x0000000100000000-0x000000013fffffff] Feb 08 11:32:39 zenith kernel: node 5: [mem 0x0000000140000000-0x000000017fffffff] Feb 08 11:32:39 zenith kernel: node 6: [mem 0x0000000180000000-0x00000001bfffffff] Feb 08 11:32:39 zenith kernel: node 7: [mem 0x00000001c0000000-0x00000001ffffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003fbfffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 1 [mem 0x0000000040000000-0x000000007fffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 2 [mem 0x0000000080000000-0x00000000bfffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 3 [mem 0x00000000c0000000-0x00000000ffffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 4 [mem 0x0000000100000000-0x000000013fffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 5 [mem 0x0000000140000000-0x000000017fffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 6 [mem 0x0000000180000000-0x00000001bfffffff] Feb 08 11:32:39 zenith kernel: Initmem setup node 7 [mem 0x00000001c0000000-0x00000001ffffffff] Feb 08 11:32:39 zenith kernel: On node 1, zone DMA: 1024 pages in unavailable ranges Feb 08 11:32:39 zenith kernel: psci: probing for conduit method from DT. Feb 08 11:32:39 zenith kernel: psci: PSCIv1.1 detected in firmware. Feb 08 11:32:39 zenith kernel: psci: Using standard PSCI v0.2 function IDs Feb 08 11:32:39 zenith kernel: psci: MIGRATE_INFO_TYPE not supported. Feb 08 11:32:39 zenith kernel: psci: SMC Calling Convention v1.2 Feb 08 11:32:39 zenith kernel: percpu: Embedded 33 pages/cpu s95192 r8192 d31784 u135168 Feb 08 11:32:39 zenith kernel: pcpu-alloc: s95192 r8192 d31784 u135168 alloc=33*4096 Feb 08 11:32:39 zenith kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 08 11:32:39 zenith kernel: Detected PIPT I-cache on CPU0 Feb 08 11:32:39 zenith kernel: CPU features: detected: Virtualization Host Extensions Feb 08 11:32:39 zenith kernel: CPU features: detected: Spectre-v4 Feb 08 11:32:39 zenith kernel: CPU features: detected: Spectre-BHB Feb 08 11:32:39 zenith kernel: CPU features: kernel page table isolation forced ON by KASLR Feb 08 11:32:39 zenith kernel: CPU features: detected: Kernel page table isolation (KPTI) Feb 08 11:32:39 zenith kernel: CPU features: detected: SSBS not fully self-synchronizing Feb 08 11:32:39 zenith kernel: alternatives: applying boot alternatives Feb 08 11:32:39 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 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=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 08 11:32:39 zenith kernel: cgroup: Disabling memory control group subsystem Feb 08 11:32:39 zenith kernel: mempolicy: NUMA default policy overridden to 'interleave:0-7' Feb 08 11:32:39 zenith kernel: DMA IOMMU NUMA default policy overridden to 'interleave:0-7' Feb 08 11:32:39 zenith kernel: PCIe ASPM is disabled Feb 08 11:32:39 zenith kernel: Unknown kernel command line parameters "splash nodebug 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 08 11:32:39 zenith kernel: Fallback order for Node 0: 0 1 2 3 4 5 6 7 Feb 08 11:32:39 zenith kernel: Fallback order for Node 1: 1 2 3 4 5 6 7 0 Feb 08 11:32:39 zenith kernel: Fallback order for Node 2: 2 3 4 5 6 7 0 1 Feb 08 11:32:39 zenith kernel: Fallback order for Node 3: 3 4 5 6 7 0 1 2 Feb 08 11:32:39 zenith kernel: Fallback order for Node 4: 4 5 6 7 0 1 2 3 Feb 08 11:32:39 zenith kernel: Fallback order for Node 5: 5 6 7 0 1 2 3 4 Feb 08 11:32:39 zenith kernel: Fallback order for Node 6: 6 7 0 1 2 3 4 5 Feb 08 11:32:39 zenith kernel: Fallback order for Node 7: 7 0 1 2 3 4 5 6 Feb 08 11:32:39 zenith kernel: Built 8 zonelists, mobility grouping on. Total pages: 2096128 Feb 08 11:32:39 zenith kernel: Policy zone: Normal Feb 08 11:32:39 zenith kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 08 11:32:39 zenith kernel: software IO TLB: area num 4. Feb 08 11:32:39 zenith kernel: software IO TLB: mapped [mem 0x00000000fae00000-0x00000000fee00000] (64MB) Feb 08 11:32:39 zenith kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8 Feb 08 11:32:39 zenith kernel: ftrace: allocating 45342 entries in 178 pages Feb 08 11:32:39 zenith kernel: ftrace: allocated 178 pages with 4 groups Feb 08 11:32:39 zenith kernel: rcu: Preemptible hierarchical RCU implementation. Feb 08 11:32:39 zenith kernel: rcu: RCU event tracing is enabled. Feb 08 11:32:39 zenith kernel: Trampoline variant of Tasks RCU enabled. Feb 08 11:32:39 zenith kernel: Rude variant of Tasks RCU enabled. Feb 08 11:32:39 zenith kernel: Tracing variant of Tasks RCU enabled. Feb 08 11:32:39 zenith kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Feb 08 11:32:39 zenith kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 08 11:32:39 zenith kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 08 11:32:39 zenith kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 08 11:32:39 zenith kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Feb 08 11:32:39 zenith kernel: Root IRQ handler: gic_handle_irq Feb 08 11:32:39 zenith kernel: GIC: Using split EOI/Deactivate mode Feb 08 11:32:39 zenith kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 08 11:32:39 zenith kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 08 11:32:39 zenith kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 08 11:32:39 zenith kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 08 11:32:39 zenith kernel: Console: colour dummy device 80x25 Feb 08 11:32:39 zenith kernel: printk: legacy console [tty1] enabled Feb 08 11:32:39 zenith kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Feb 08 11:32:39 zenith kernel: pid_max: default: 32768 minimum: 301 Feb 08 11:32:39 zenith kernel: LSM: initializing lsm=capability Feb 08 11:32:39 zenith kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: rcu: Hierarchical SRCU implementation. Feb 08 11:32:39 zenith kernel: rcu: Max phase no-delay instances is 1000. Feb 08 11:32:39 zenith kernel: Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level Feb 08 11:32:39 zenith kernel: EFI services will not be available. Feb 08 11:32:39 zenith kernel: smp: Bringing up secondary CPUs ... Feb 08 11:32:39 zenith kernel: Detected PIPT I-cache on CPU1 Feb 08 11:32:39 zenith kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Feb 08 11:32:39 zenith kernel: Detected PIPT I-cache on CPU2 Feb 08 11:32:39 zenith kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Feb 08 11:32:39 zenith kernel: Detected PIPT I-cache on CPU3 Feb 08 11:32:39 zenith kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Feb 08 11:32:39 zenith kernel: smp: Brought up 8 nodes, 4 CPUs Feb 08 11:32:39 zenith kernel: SMP: Total of 4 processors activated. Feb 08 11:32:39 zenith kernel: CPU: All CPU(s) started at EL2 Feb 08 11:32:39 zenith kernel: CPU features: detected: 32-bit EL0 Support Feb 08 11:32:39 zenith kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Feb 08 11:32:39 zenith kernel: CPU features: detected: Common not Private translations Feb 08 11:32:39 zenith kernel: CPU features: detected: CRC32 instructions Feb 08 11:32:39 zenith kernel: CPU features: detected: RCpc load-acquire (LDAPR) Feb 08 11:32:39 zenith kernel: CPU features: detected: LSE atomic instructions Feb 08 11:32:39 zenith kernel: CPU features: detected: Privileged Access Never Feb 08 11:32:39 zenith kernel: CPU features: detected: RAS Extension Support Feb 08 11:32:39 zenith kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Feb 08 11:32:39 zenith kernel: alternatives: applying system-wide alternatives Feb 08 11:32:39 zenith kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Feb 08 11:32:39 zenith kernel: Memory: 8014680K/8384512K available (14080K kernel code, 2406K rwdata, 4820K rodata, 5440K init, 577K bss, 278696K reserved, 65536K cma-reserved) Feb 08 11:32:39 zenith kernel: devtmpfs: initialized Feb 08 11:32:39 zenith kernel: Enabled cp15_barrier support Feb 08 11:32:39 zenith kernel: Enabled setend support Feb 08 11:32:39 zenith kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Feb 08 11:32:39 zenith kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Feb 08 11:32:39 zenith kernel: 0 pages in range for non-PLT usage Feb 08 11:32:39 zenith kernel: 517392 pages in range for PLT usage Feb 08 11:32:39 zenith kernel: pinctrl core: initialized pinctrl subsystem Feb 08 11:32:39 zenith kernel: DMI not present or invalid. Feb 08 11:32:39 zenith kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 08 11:32:39 zenith kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Feb 08 11:32:39 zenith kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Feb 08 11:32:39 zenith kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Feb 08 11:32:39 zenith kernel: audit: initializing netlink subsys (disabled) Feb 08 11:32:39 zenith kernel: audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 Feb 08 11:32:39 zenith kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 08 11:32:39 zenith kernel: cpuidle: using governor menu Feb 08 11:32:39 zenith kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Feb 08 11:32:39 zenith kernel: ASID allocator initialised with 32768 entries Feb 08 11:32:39 zenith kernel: Serial: AMBA PL011 UART driver Feb 08 11:32:39 zenith kernel: /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000 Feb 08 11:32:39 zenith kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Feb 08 11:32:39 zenith kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3 Feb 08 11:32:39 zenith kernel: printk: legacy console [ttyAMA10] enabled Feb 08 11:32:39 zenith kernel: raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-11-05T17:37:18, variant start_cd Feb 08 11:32:39 zenith kernel: raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 57db150d00000000000000000000000000000000 Feb 08 11:32:39 zenith kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Feb 08 11:32:39 zenith kernel: iommu: Default domain type: Translated Feb 08 11:32:39 zenith kernel: iommu: DMA domain TLB invalidation policy: strict mode Feb 08 11:32:39 zenith kernel: SCSI subsystem initialized Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver usbfs Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver hub Feb 08 11:32:39 zenith kernel: usbcore: registered new device driver usb Feb 08 11:32:39 zenith kernel: pps_core: LinuxPPS API ver. 1 registered Feb 08 11:32:39 zenith kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 08 11:32:39 zenith kernel: PTP clock support registered Feb 08 11:32:39 zenith kernel: vgaarb: loaded Feb 08 11:32:39 zenith kernel: clocksource: Switched to clocksource arch_sys_counter Feb 08 11:32:39 zenith kernel: VFS: Disk quotas dquot_6.6.0 Feb 08 11:32:39 zenith kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 08 11:32:39 zenith kernel: NET: Registered PF_INET protocol family Feb 08 11:32:39 zenith kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: TCP: Hash tables configured (established 65536 bind 65536) Feb 08 11:32:39 zenith kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, vmalloc) Feb 08 11:32:39 zenith kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 08 11:32:39 zenith kernel: RPC: Registered named UNIX socket transport module. Feb 08 11:32:39 zenith kernel: RPC: Registered udp transport module. Feb 08 11:32:39 zenith kernel: RPC: Registered tcp transport module. Feb 08 11:32:39 zenith kernel: RPC: Registered tcp-with-tls transport module. Feb 08 11:32:39 zenith kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 08 11:32:39 zenith kernel: PCI: CLS 0 bytes, default 64 Feb 08 11:32:39 zenith kernel: Trying to unpack rootfs image as initramfs... Feb 08 11:32:39 zenith kernel: kvm [1]: nv: 554 coarse grained trap handlers Feb 08 11:32:39 zenith kernel: kvm [1]: IPA Size Limit: 40 bits Feb 08 11:32:39 zenith kernel: kvm [1]: vgic interrupt IRQ9 Feb 08 11:32:39 zenith kernel: kvm [1]: VHE mode initialized successfully Feb 08 11:32:39 zenith kernel: Initialise system trusted keyrings Feb 08 11:32:39 zenith kernel: workingset: timestamp_bits=42 max_order=21 bucket_order=0 Feb 08 11:32:39 zenith kernel: NFS: Registering the id_resolver key type Feb 08 11:32:39 zenith kernel: Key type id_resolver registered Feb 08 11:32:39 zenith kernel: Key type id_legacy registered Feb 08 11:32:39 zenith kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 08 11:32:39 zenith kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 08 11:32:39 zenith kernel: Key type asymmetric registered Feb 08 11:32:39 zenith kernel: Asymmetric key parser 'x509' registered Feb 08 11:32:39 zenith kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 08 11:32:39 zenith kernel: io scheduler mq-deadline registered Feb 08 11:32:39 zenith kernel: io scheduler kyber registered Feb 08 11:32:39 zenith kernel: io scheduler bfq registered Feb 08 11:32:39 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27) Feb 08 11:32:39 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28) Feb 08 11:32:39 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29) Feb 08 11:32:39 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30) Feb 08 11:32:39 zenith kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31) Feb 08 11:32:39 zenith kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 08 11:32:39 zenith kernel: Freeing initrd memory: 35752K Feb 08 11:32:39 zenith kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Feb 08 11:32:39 zenith kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Feb 08 11:32:39 zenith kernel: serial serial0: tty port ttyS0 registered Feb 08 11:32:39 zenith kernel: iproc-rng200 107d208000.rng: hwrng registered Feb 08 11:32:39 zenith kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Feb 08 11:32:39 zenith kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Feb 08 11:32:39 zenith kernel: brd: module loaded Feb 08 11:32:39 zenith kernel: loop: module loaded Feb 08 11:32:39 zenith kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 08 11:32:39 zenith kernel: Loading iSCSI transport class v2.0-870. Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver lan78xx Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver smsc95xx Feb 08 11:32:39 zenith kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 08 11:32:39 zenith kernel: dwc_otg: FIQ enabled Feb 08 11:32:39 zenith kernel: dwc_otg: NAK holdoff enabled Feb 08 11:32:39 zenith kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 08 11:32:39 zenith kernel: Module dwc_common_port init Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver uas Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver usb-storage Feb 08 11:32:39 zenith kernel: mousedev: PS/2 mouse device common for all mice Feb 08 11:32:39 zenith kernel: rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0 Feb 08 11:32:39 zenith kernel: rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 2026-02-08T16:32:28 UTC (1770568348) Feb 08 11:32:39 zenith kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Feb 08 11:32:39 zenith kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 08 11:32:39 zenith kernel: sdhci: Secure Digital Host Controller Interface driver Feb 08 11:32:39 zenith kernel: sdhci: Copyright(c) Pierre Ossman Feb 08 11:32:39 zenith kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 08 11:32:39 zenith kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Feb 08 11:32:39 zenith kernel: hid: raw HID events driver (C) Jiri Kosina Feb 08 11:32:39 zenith kernel: usbcore: registered new interface driver usbhid Feb 08 11:32:39 zenith kernel: usbhid: USB HID core driver Feb 08 11:32:39 zenith kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available Feb 08 11:32:39 zenith kernel: NET: Registered PF_PACKET protocol family Feb 08 11:32:39 zenith kernel: Key type dns_resolver registered Feb 08 11:32:39 zenith kernel: registered taskstats version 1 Feb 08 11:32:39 zenith kernel: Loading compiled-in X.509 certificates Feb 08 11:32:39 zenith kernel: Demotion targets for Node 0: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 1: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 2: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 3: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 4: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 5: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 6: null Feb 08 11:32:39 zenith kernel: Demotion targets for Node 7: null Feb 08 11:32:39 zenith kernel: Key type .fscrypt registered Feb 08 11:32:39 zenith kernel: Key type fscrypt-provisioning registered Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges: Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@1000110000, using [bus 00-ff] Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00 Feb 08 11:32:39 zenith kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Feb 08 11:32:39 zenith kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Feb 08 11:32:39 zenith kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: PCI bridge to [bus 00] Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: bridge window [mem 0x00000000-0x000fffff] Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: bridge window [mem 0x00000000-0x000fffff 64bit pref] Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 08 11:32:39 zenith kernel: brcm-pcie 1000110000.pcie: link down Feb 08 11:32:39 zenith kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Feb 08 11:32:39 zenith kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff] Feb 08 11:32:39 zenith kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref] Feb 08 11:32:39 zenith kernel: pci 0001:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512 Feb 08 11:32:39 zenith kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 38 Feb 08 11:32:39 zenith kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 38 Feb 08 11:32:39 zenith kernel: pci_bus 0001:01: busn_res: [bus 01] is released Feb 08 11:32:39 zenith kernel: pci_bus 0001:00: busn_res: [bus 00-ff] is released Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges: Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff] Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000 Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00 Feb 08 11:32:39 zenith kernel: pci_bus 0002:00: root bus resource [bus 00-ff] Feb 08 11:32:39 zenith kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Feb 08 11:32:39 zenith kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: PCI bridge to [bus 00] Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff] Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: PME# supported from D0 D3hot Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default Feb 08 11:32:39 zenith kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff] Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff] Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff] Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: supports D1 Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold Feb 08 11:32:39 zenith kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01 Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: PCI bridge to [bus 01] Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Feb 08 11:32:39 zenith kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb] Feb 08 11:32:39 zenith kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref] Feb 08 11:32:39 zenith kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff] Feb 08 11:32:39 zenith kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Feb 08 11:32:39 zenith kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Feb 08 11:32:39 zenith kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002) Feb 08 11:32:39 zenith kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 39 Feb 08 11:32:39 zenith kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 39 Feb 08 11:32:39 zenith kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Feb 08 11:32:39 zenith kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Feb 08 11:32:39 zenith kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002) Feb 08 11:32:39 zenith kernel: rp1 0002:01:00.0: chip_id 0x20001927 Feb 08 11:32:39 zenith kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 08 11:32:39 zenith kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Feb 08 11:32:39 zenith kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 08 11:32:39 zenith kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Feb 08 11:32:39 zenith kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 08 11:32:39 zenith kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Feb 08 11:32:39 zenith kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101 Feb 08 11:32:39 zenith kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Feb 08 11:32:39 zenith kernel: mmc1: CQHCI version 5.10 Feb 08 11:32:39 zenith kernel: mmc0: CQHCI version 5.10 Feb 08 11:32:39 zenith kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (2c:cf:67:95:e5:67) Feb 08 11:32:39 zenith kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Feb 08 11:32:39 zenith kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Feb 08 11:32:39 zenith kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 08 11:32:39 zenith kernel: usb usb1: Product: xHCI Host Controller Feb 08 11:32:39 zenith kernel: usb usb1: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 08 11:32:39 zenith kernel: usb usb1: SerialNumber: xhci-hcd.0 Feb 08 11:32:39 zenith kernel: hub 1-0:1.0: USB hub found Feb 08 11:32:39 zenith kernel: hub 1-0:1.0: 2 ports detected Feb 08 11:32:39 zenith kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Feb 08 11:32:39 zenith kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 08 11:32:39 zenith kernel: usb usb2: Product: xHCI Host Controller Feb 08 11:32:39 zenith kernel: usb usb2: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 08 11:32:39 zenith kernel: usb usb2: SerialNumber: xhci-hcd.0 Feb 08 11:32:39 zenith kernel: hub 2-0:1.0: USB hub found Feb 08 11:32:39 zenith kernel: hub 2-0:1.0: 1 port detected Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Feb 08 11:32:39 zenith kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Feb 08 11:32:39 zenith kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Feb 08 11:32:39 zenith kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 08 11:32:39 zenith kernel: usb usb3: Product: xHCI Host Controller Feb 08 11:32:39 zenith kernel: usb usb3: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 08 11:32:39 zenith kernel: usb usb3: SerialNumber: xhci-hcd.1 Feb 08 11:32:39 zenith kernel: hub 3-0:1.0: USB hub found Feb 08 11:32:39 zenith kernel: hub 3-0:1.0: 2 ports detected Feb 08 11:32:39 zenith kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Feb 08 11:32:39 zenith kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 08 11:32:39 zenith kernel: usb usb4: Product: xHCI Host Controller Feb 08 11:32:39 zenith kernel: usb usb4: Manufacturer: Linux 6.12.47-v8+ xhci-hcd Feb 08 11:32:39 zenith kernel: usb usb4: SerialNumber: xhci-hcd.1 Feb 08 11:32:39 zenith kernel: hub 4-0:1.0: USB hub found Feb 08 11:32:39 zenith kernel: hub 4-0:1.0: 1 port detected Feb 08 11:32:39 zenith kernel: of_cfs_init Feb 08 11:32:39 zenith kernel: of_cfs_init: OK Feb 08 11:32:39 zenith kernel: clk: Disabling unused clocks Feb 08 11:32:39 zenith kernel: PM: genpd: Disabling unused power domains Feb 08 11:32:39 zenith kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Feb 08 11:32:39 zenith kernel: mmc0: new ultra high speed SDR104 SDXC card at address 5048 Feb 08 11:32:39 zenith kernel: mmcblk0: mmc0:5048 SD64G 58.0 GiB Feb 08 11:32:39 zenith kernel: mmcblk0: p1 p2 p3 Feb 08 11:32:39 zenith kernel: mmcblk0: mmc0:5048 SD64G 58.0 GiB (quirks 0x80000000) Feb 08 11:32:39 zenith kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Feb 08 11:32:39 zenith kernel: Freeing unused kernel memory: 5440K Feb 08 11:32:39 zenith kernel: Run /init as init process Feb 08 11:32:39 zenith kernel: with arguments: Feb 08 11:32:39 zenith kernel: /init Feb 08 11:32:39 zenith kernel: splash Feb 08 11:32:39 zenith kernel: nodebug Feb 08 11:32:39 zenith kernel: with environment: Feb 08 11:32:39 zenith kernel: HOME=/ Feb 08 11:32:39 zenith kernel: TERM=linux Feb 08 11:32:39 zenith kernel: imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 Feb 08 11:32:39 zenith kernel: imgfile=/volumio_current.sqsh Feb 08 11:32:39 zenith kernel: bootpart=UUID=3B89-0B23 Feb 08 11:32:39 zenith kernel: datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 Feb 08 11:32:39 zenith kernel: uuidconfig=cmdline.txt Feb 08 11:32:39 zenith kernel: bootdelay=7 Feb 08 11:32:39 zenith kernel: use_kmsg=no Feb 08 11:32:39 zenith kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Feb 08 11:32:39 zenith kernel: rp1-firmware rp1_firmware: RP1 Firmware version 23fca1cd776798b50bd3e640ecb242215778e7f8 Feb 08 11:32:39 zenith kernel: rp1-pio 1f00178000.pio: Created instance as pio0 Feb 08 11:32:39 zenith kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Feb 08 11:32:39 zenith kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Feb 08 11:32:39 zenith kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Feb 08 11:32:39 zenith kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform! Feb 08 11:32:39 zenith kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0 Feb 08 11:32:39 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1 Feb 08 11:32:39 zenith kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: Adding to iommu group 1 Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Feb 08 11:32:39 zenith kernel: Registered IR keymap rc-cec Feb 08 11:32:39 zenith kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0 Feb 08 11:32:39 zenith kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input1 Feb 08 11:32:39 zenith kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input2 Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Feb 08 11:32:39 zenith kernel: Registered IR keymap rc-cec Feb 08 11:32:39 zenith kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1 Feb 08 11:32:39 zenith kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input3 Feb 08 11:32:39 zenith kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input4 Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Feb 08 11:32:39 zenith kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1 Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 08 11:32:39 zenith kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 08 11:32:39 zenith kernel: fuse: init (API version 7.41) Feb 08 11:32:39 zenith kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 08 11:32:39 zenith kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Feb 08 11:32:39 zenith kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340 Feb 08 11:32:39 zenith kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r Feb 08 11:32:39 zenith kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18 Feb 08 11:32:39 zenith kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18 Feb 08 11:32:39 zenith kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green Feb 08 11:32:39 zenith kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v Feb 08 11:32:39 zenith kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13 Feb 08 11:32:39 zenith kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned. Feb 08 11:32:39 zenith kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d Feb 08 11:32:39 zenith kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 08 11:32:39 zenith kernel: EXT4-fs (mmcblk0p2): mounted filesystem dafa3844-b779-48cd-9b4c-01ecfd09e0f4 r/w with ordered data mode. Quota mode: none. Feb 08 11:32:39 zenith kernel: loop0: detected capacity change from 0 to 1422424 Feb 08 11:32:39 zenith kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 08 11:32:39 zenith kernel: EXT4-fs (mmcblk0p3): mounted filesystem 752d19ad-b702-471d-847a-f79ae83515d0 r/w with ordered data mode. Quota mode: none. Feb 08 11:32:39 zenith kernel: NET: Registered PF_INET6 protocol family Feb 08 11:32:39 zenith kernel: Segment Routing with IPv6 Feb 08 11:32:39 zenith kernel: In-situ OAM (IOAM) with IPv6 Feb 08 11:32:39 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 08 11:32:39 zenith systemd[1]: Detected architecture arm64. Feb 08 11:32:39 zenith systemd[1]: Hostname set to . Feb 08 11:32:39 zenith systemd[1]: Queued start job for default target graphical.target. Feb 08 11:32:39 zenith systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 08 11:32:39 zenith systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Feb 08 11:32:39 zenith systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Feb 08 11:32:39 zenith systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Feb 08 11:32:39 zenith systemd[1]: Created slice user.slice - User and Session Slice. Feb 08 11:32:39 zenith systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Feb 08 11:32:39 zenith systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Feb 08 11:32:39 zenith systemd[1]: Expecting device dev-disk-by\x2duuid-3B89\x2d0B23.device - /dev/disk/by-uuid/3B89-0B23... Feb 08 11:32:39 zenith systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10... Feb 08 11:32:39 zenith systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Feb 08 11:32:39 zenith systemd[1]: Reached target slices.target - Slice Units. Feb 08 11:32:39 zenith systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Feb 08 11:32:39 zenith systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-journald.socket - Journal Socket. Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 08 11:32:39 zenith systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 08 11:32:39 zenith systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Feb 08 11:32:39 zenith systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Feb 08 11:32:39 zenith systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Feb 08 11:32:39 zenith systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Feb 08 11:32:39 zenith systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Feb 08 11:32:39 zenith systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 11:32:39 zenith systemd[1]: Reached target swap.target - Swaps. Feb 08 11:32:39 zenith systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Feb 08 11:32:39 zenith systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 08 11:32:39 zenith systemd[1]: Starting systemd-journald.service - Journal Service... Feb 08 11:32:39 zenith kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Feb 08 11:32:39 zenith systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 08 11:32:39 zenith systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Feb 08 11:32:39 zenith systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 08 11:32:39 zenith systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Feb 08 11:32:39 zenith systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Feb 08 11:32:39 zenith systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Feb 08 11:32:39 zenith systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Feb 08 11:32:39 zenith systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 08 11:32:39 zenith systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Feb 08 11:32:39 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 08 11:32:39 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 08 11:32:39 zenith systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Feb 08 11:32:39 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 08 11:32:39 zenith systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Feb 08 11:32:39 zenith systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Feb 08 11:32:39 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 08 11:32:39 zenith systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 08 11:32:39 zenith systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Feb 08 11:32:39 zenith systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Feb 08 11:32:39 zenith systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 11:32:39 zenith systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Feb 08 11:32:39 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:39 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 08 11:32:39 zenith kernel: i2c_dev: i2c /dev entries driver Feb 08 11:32:39 zenith systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Feb 08 11:32:39 zenith systemd[1]: Starting systemd-sysusers.service - Create System Users... Feb 08 11:32:39 zenith systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 08 11:32:39 zenith systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 08 11:32:39 zenith systemd-journald[521]: Journal started Feb 08 11:32:39 zenith systemd-journald[521]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.7M, max 30.0M, 26.2M free. Feb 08 11:32:39 zenith systemd-modules-load[527]: Inserted module 'i2c_dev' Feb 08 11:32:39 zenith fake-hwclock[513]: Current system time: 2026-02-08 16:32:39 Feb 08 11:32:39 zenith fake-hwclock[513]: fake-hwclock saved clock information is in the past: 2026-02-08 16:32:10 Feb 08 11:32:39 zenith fake-hwclock[513]: To set system time to this saved clock anyway, use "force" Feb 08 11:32:39 zenith systemd[1]: Started systemd-journald.service - Journal Service. Feb 08 11:32:39 zenith systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Feb 08 11:32:39 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:39 zenith systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 08 11:32:39 zenith systemd[1]: Finished systemd-sysusers.service - Create System Users. Feb 08 11:32:39 zenith systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 08 11:32:39 zenith systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 08 11:32:39 zenith systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Feb 08 11:32:39 zenith systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 08 11:32:39 zenith systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Feb 08 11:32:39 zenith systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 08 11:32:39 zenith systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Feb 08 11:32:39 zenith systemd-udevd[549]: Using default interface naming scheme 'v252'. Feb 08 11:32:39 zenith systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 08 11:32:39 zenith (udev-worker)[563]: Network interface NamePolicy= disabled on kernel command line. Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Feb 08 11:32:39 zenith kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Feb 08 11:32:39 zenith systemd[1]: Found device dev-disk-by\x2duuid-3B89\x2d0B23.device - /dev/disk/by-uuid/3B89-0B23. Feb 08 11:32:39 zenith systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-3B89\x2d0B23.service - File System Check on /dev/disk/by-uuid/3B89-0B23... Feb 08 11:32:39 zenith kernel: mc: Linux media interface: v0.10 Feb 08 11:32:39 zenith systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Feb 08 11:32:39 zenith kernel: videodev: Linux video capture interface: v2.00 Feb 08 11:32:39 zenith systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Feb 08 11:32:39 zenith systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Feb 08 11:32:39 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0 Feb 08 11:32:39 zenith kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Feb 08 11:32:39 zenith kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0 Feb 08 11:32:39 zenith kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Feb 08 11:32:39 zenith systemd-fsck[753]: fsck.fat 4.2 (2021-01-31) Feb 08 11:32:39 zenith systemd-fsck[753]: /dev/mmcblk0p1: 514 files, 23705/93500 clusters Feb 08 11:32:39 zenith systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10. Feb 08 11:32:39 zenith systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-3B89\x2d0B23.service - File System Check on /dev/disk/by-uuid/3B89-0B23. Feb 08 11:32:39 zenith kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19 Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 08 11:32:39 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 08 11:32:39 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:39 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 08 11:32:40 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 08 11:32:40 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 08 11:32:40 zenith systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 08 11:32:40 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 08 11:32:40 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 08 11:32:40 zenith kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 08 11:32:40 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 08 11:32:40 zenith kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 08 11:32:40 zenith kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 08 11:32:40 zenith kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 08 11:32:40 zenith kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Feb 08 11:32:40 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 08 11:32:40 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 08 11:32:40 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith kernel: Bluetooth: Core ver 2.22 Feb 08 11:32:40 zenith kernel: NET: Registered PF_BLUETOOTH protocol family Feb 08 11:32:40 zenith kernel: Bluetooth: HCI device and connection manager initialized Feb 08 11:32:40 zenith kernel: Bluetooth: HCI socket layer initialized Feb 08 11:32:40 zenith kernel: Bluetooth: L2CAP socket layer initialized Feb 08 11:32:40 zenith kernel: Bluetooth: SCO socket layer initialized Feb 08 11:32:40 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 08 11:32:40 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 08 11:32:40 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 08 11:32:40 zenith kernel: Bluetooth: HCI UART driver ver 2.3 Feb 08 11:32:40 zenith kernel: Bluetooth: HCI UART protocol H4 registered Feb 08 11:32:40 zenith kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 08 11:32:40 zenith kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 08 11:32:40 zenith kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 08 11:32:40 zenith kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 08 11:32:40 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 08 11:32:40 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 08 11:32:40 zenith kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 08 11:32:40 zenith kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 08 11:32:40 zenith kernel: usbcore: registered new interface driver brcmfmac Feb 08 11:32:40 zenith (udev-worker)[577]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Feb 08 11:32:40 zenith kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Feb 08 11:32:40 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 08 11:32:40 zenith systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Feb 08 11:32:40 zenith systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Feb 08 11:32:40 zenith systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 08 11:32:40 zenith systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 08 11:32:40 zenith systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 08 11:32:40 zenith systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Feb 08 11:32:40 zenith systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 08 11:32:40 zenith systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 08 11:32:40 zenith systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 08 11:32:40 zenith systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 08 11:32:40 zenith systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 08 11:32:40 zenith systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 08 11:32:40 zenith (udev-worker)[577]: Network interface NamePolicy= disabled on kernel command line. Feb 08 11:32:40 zenith systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Feb 08 11:32:40 zenith systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Feb 08 11:32:40 zenith kernel: Bluetooth: hci0: BCM: chip id 107 Feb 08 11:32:40 zenith kernel: Bluetooth: hci0: BCM: features 0x2f Feb 08 11:32:40 zenith kernel: Bluetooth: hci0: BCM4345C0 Feb 08 11:32:40 zenith kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Feb 08 11:32:40 zenith kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,5-model-b.hcd' Patch Feb 08 11:32:40 zenith systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Feb 08 11:32:40 zenith systemd[1]: Mounting boot.mount - /boot... Feb 08 11:32:40 zenith systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Feb 08 11:32:40 zenith systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 08 11:32:40 zenith systemd[1]: Mounting tmp.mount - /tmp... Feb 08 11:32:40 zenith systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 08 11:32:40 zenith systemd[1]: Mounting var-log.mount - /var/log... Feb 08 11:32:40 zenith systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Feb 08 11:32:40 zenith systemd[1]: Mounted tmp.mount - /tmp. Feb 08 11:32:40 zenith systemd[1]: Mounted boot.mount - /boot. Feb 08 11:32:40 zenith systemd[1]: Mounted var-log.mount - /var/log. Feb 08 11:32:40 zenith systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Feb 08 11:32:40 zenith systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Feb 08 11:32:40 zenith systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Feb 08 11:32:40 zenith systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Feb 08 11:32:40 zenith systemd[1]: Reached target local-fs.target - Local File Systems. Feb 08 11:32:40 zenith systemd[1]: Started ifup@eth0.service - ifup for eth0. Feb 08 11:32:40 zenith systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Feb 08 11:32:40 zenith systemd[1]: Starting networking.service - Raise network interfaces... Feb 08 11:32:40 zenith systemd-journald[521]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.7M, max 30.0M, 26.2M free. Feb 08 11:32:40 zenith systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Feb 08 11:32:40 zenith systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Feb 08 11:32:40 zenith systemd-journald[521]: Received client request to flush runtime journal. Feb 08 11:32:40 zenith systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Feb 08 11:32:40 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 08 11:32:40 zenith systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Feb 08 11:32:40 zenith systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 08 11:32:40 zenith systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Feb 08 11:32:40 zenith systemd[1]: Received SIGRTMIN+20 from PID 402 (plymouthd). Feb 08 11:32:40 zenith systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 856 (systemd-binfmt) Feb 08 11:32:40 zenith systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 08 11:32:40 zenith kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Feb 08 11:32:40 zenith systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Feb 08 11:32:40 zenith systemd[1]: Starting rpcbind.service - RPC bind portmap service... Feb 08 11:32:40 zenith systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Feb 08 11:32:40 zenith systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Feb 08 11:32:40 zenith systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Feb 08 11:32:40 zenith sh[898]: dhcpcd-9.4.1 starting Feb 08 11:32:40 zenith dhcpcd[898]: dhcpcd-9.4.1 starting Feb 08 11:32:40 zenith systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Feb 08 11:32:40 zenith sh[898]: DUID 00:01:00:01:30:c6:00:2d:2c:cf:67:95:e5:69 Feb 08 11:32:40 zenith sh[898]: dhcp_vendor: No such process Feb 08 11:32:40 zenith dhcpcd[909]: DUID 00:01:00:01:30:c6:00:2d:2c:cf:67:95:e5:69 Feb 08 11:32:40 zenith dhcpcd[909]: dhcp_vendor: No such process Feb 08 11:32:40 zenith haveged[886]: haveged: command socket is listening at fd 3 Feb 08 11:32:40 zenith kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 08 11:32:40 zenith systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Feb 08 11:32:40 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 08 11:32:40 zenith systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Feb 08 11:32:40 zenith systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 08 11:32:40 zenith systemd[1]: Reached target paths.target - Path Units. Feb 08 11:32:40 zenith systemd[1]: Started rpcbind.service - RPC bind portmap service. Feb 08 11:32:40 zenith systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Feb 08 11:32:40 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 08 11:32:40 zenith kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Feb 08 11:32:40 zenith kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Feb 08 11:32:40 zenith dhcpcd[909]: eth0: waiting for carrier Feb 08 11:32:40 zenith sh[898]: eth0: waiting for carrier Feb 08 11:32:40 zenith kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Feb 08 11:32:40 zenith sh[971]: wlan0=wlan0 Feb 08 11:32:40 zenith systemd[1]: Finished networking.service - Raise network interfaces. Feb 08 11:32:41 zenith haveged[886]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Feb 08 11:32:41 zenith haveged[886]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Feb 08 11:32:41 zenith haveged[886]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0007 Feb 08 11:32:41 zenith haveged[886]: haveged: fills: 0, generated: 0 Feb 08 11:32:41 zenith kernel: Bluetooth: hci0: BCM: features 0x2f Feb 08 11:32:41 zenith kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Feb 08 11:32:41 zenith kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Feb 08 11:32:41 zenith kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Feb 08 11:32:41 zenith systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Feb 08 11:32:41 zenith systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Feb 08 11:32:41 zenith systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Feb 08 11:32:41 zenith systemd[1]: Reached target rpc_pipefs.target. Feb 08 11:32:41 zenith systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Feb 08 11:32:41 zenith systemd[1]: Reached target nfs-client.target - NFS client services. Feb 08 11:32:41 zenith systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Feb 08 11:32:41 zenith systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 08 11:32:41 zenith systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Feb 08 11:32:41 zenith systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Feb 08 11:32:41 zenith systemd[1]: Reached target sysinit.target - System Initialization. Feb 08 11:32:41 zenith systemd[1]: Started apt-daily.timer - Daily apt download activities. Feb 08 11:32:41 zenith systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Feb 08 11:32:41 zenith systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Feb 08 11:32:41 zenith systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Feb 08 11:32:41 zenith systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Feb 08 11:32:41 zenith systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Feb 08 11:32:41 zenith systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Feb 08 11:32:41 zenith systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Feb 08 11:32:41 zenith systemd[1]: Reached target timers.target - Timer Units. Feb 08 11:32:41 zenith systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Feb 08 11:32:41 zenith systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Feb 08 11:32:41 zenith systemd[1]: Reached target sockets.target - Socket Units. Feb 08 11:32:41 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 08 11:32:41 zenith systemd[1]: Reached target basic.target - Basic System. Feb 08 11:32:41 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 08 11:32:41 zenith systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Feb 08 11:32:41 zenith systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 08 11:32:41 zenith systemd[1]: Starting bluetooth.service - Bluetooth service... Feb 08 11:32:41 zenith systemd[1]: Starting dbus.service - D-Bus System Message Bus... Feb 08 11:32:41 zenith systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Feb 08 11:32:41 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 08 11:32:41 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 08 11:32:41 zenith systemd[1]: Started iptables.service - Volumio Iptables Module. Feb 08 11:32:41 zenith systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Feb 08 11:32:41 zenith systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Feb 08 11:32:41 zenith systemd[1]: Starting systemd-logind.service - User Login Management... Feb 08 11:32:41 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 08 11:32:41 zenith systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Feb 08 11:32:41 zenith systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 08 11:32:41 zenith systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Feb 08 11:32:41 zenith systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Feb 08 11:32:41 zenith systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Feb 08 11:32:41 zenith systemd[1]: Starting wireless.service - Wireless Services... Feb 08 11:32:41 zenith volumio_rfkill_unblock.sh[1011]: Volumio WiFi Soft Blocked check script Feb 08 11:32:41 zenith volumio_rfkill_unblock.sh[1011]: Check if rfkill is available Feb 08 11:32:41 zenith thd[1005]: Unable to parse trigger line: Feb 08 11:32:41 zenith thd[1005]: Unable to parse trigger line: Feb 08 11:32:41 zenith volumio_rfkill_unblock.sh[1011]: Check if rfkill listed devices are already unblocked Feb 08 11:32:41 zenith systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Feb 08 11:32:41 zenith systemd[1]: e2scrub_reap.service: Deactivated successfully. Feb 08 11:32:41 zenith systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Feb 08 11:32:41 zenith avahi-daemon[996]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Feb 08 11:32:41 zenith avahi-daemon[996]: Successfully dropped root privileges. Feb 08 11:32:41 zenith avahi-daemon[996]: avahi-daemon 0.8 starting up. Feb 08 11:32:41 zenith systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Feb 08 11:32:41 zenith alsactl[1020]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 08 11:32:41 zenith alsactl[1020]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 08 11:32:41 zenith alsactl[1020]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 08 11:32:41 zenith volumio_rfkill_unblock.sh[1011]: Wi-Fi is already unblocked. Feb 08 11:32:41 zenith systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Feb 08 11:32:41 zenith systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Feb 08 11:32:41 zenith systemd[1]: Reached target sound.target - Sound Card. Feb 08 11:32:41 zenith systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Feb 08 11:32:41 zenith th-udev-rebind.sh[1029]: Rebinding /dev/input/event0 to thd using --add... Feb 08 11:32:41 zenith systemd-logind[1003]: New seat seat0. Feb 08 11:32:41 zenith systemd-logind[1003]: Watching system buttons on /dev/input/event0 (pwr_button) Feb 08 11:32:41 zenith systemd-logind[1003]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Feb 08 11:32:41 zenith systemd-logind[1003]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Feb 08 11:32:41 zenith th-udev-rebind.sh[1029]: Rebinding /dev/input/event1 to thd using --add... Feb 08 11:32:41 zenith th-udev-rebind.sh[1029]: Rebinding /dev/input/event2 to thd using --add... Feb 08 11:32:41 zenith th-udev-rebind.sh[1029]: Rebinding /dev/input/event3 to thd using --add... Feb 08 11:32:41 zenith th-udev-rebind.sh[1029]: Rebinding /dev/input/event4 to thd using --add... Feb 08 11:32:41 zenith systemd[1]: th-udev-rebind.service: Deactivated successfully. Feb 08 11:32:41 zenith systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Feb 08 11:32:41 zenith rpi-eeprom-update[1002]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0 Feb 08 11:32:41 zenith dhcpcd[1030]: dhcpcd-9.4.1 starting Feb 08 11:32:41 zenith loadcpufreq[1001]: Loading cpufreq kernel modules...done (none). Feb 08 11:32:41 zenith systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Feb 08 11:32:41 zenith dhcpcd[1058]: dev: loaded udev Feb 08 11:32:41 zenith dhcpcd[1058]: DUID 00:01:00:01:30:c6:00:2d:2c:cf:67:95:e5:69 Feb 08 11:32:41 zenith systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Feb 08 11:32:41 zenith dhcpcd[1058]: dhcp_vendor: No such process Feb 08 11:32:41 zenith dhcpcd[1030]: dhcp_vendor: No such process Feb 08 11:32:41 zenith systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Feb 08 11:32:41 zenith dhcpcd[1058]: eth0: waiting for carrier Feb 08 11:32:41 zenith dhcpcd[1058]: wlan0: waiting for carrier Feb 08 11:32:41 zenith bluetoothd[997]: Bluetooth daemon 5.83 Feb 08 11:32:41 zenith cpufrequtils[1069]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 08 11:32:41 zenith systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Feb 08 11:32:41 zenith systemd[1]: Started dbus.service - D-Bus System Message Bus. Feb 08 11:32:41 zenith avahi-daemon[996]: Successfully called chroot(). Feb 08 11:32:41 zenith avahi-daemon[996]: Successfully dropped remaining capabilities. Feb 08 11:32:41 zenith dbus-daemon[998]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 08 11:32:41 zenith bluetoothd[997]: Starting SDP server Feb 08 11:32:41 zenith bluetoothd[997]: Excluding (cli) sap Feb 08 11:32:41 zenith bluetoothd[997]: Excluding (cli) midi Feb 08 11:32:41 zenith bluetoothd[997]: Excluding (cli) battery Feb 08 11:32:41 zenith avahi-daemon[996]: Loading service file /services/volumio.service. Feb 08 11:32:41 zenith avahi-daemon[996]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 08 11:32:41 zenith avahi-daemon[996]: New relevant interface lo.IPv4 for mDNS. Feb 08 11:32:41 zenith avahi-daemon[996]: Network interface enumeration completed. Feb 08 11:32:41 zenith avahi-daemon[996]: Registering new address record for 127.0.0.1 on lo.IPv4. Feb 08 11:32:41 zenith dbus-daemon[998]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=997 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 08 11:32:41 zenith kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 08 11:32:41 zenith kernel: Bluetooth: BNEP filters: protocol multicast Feb 08 11:32:41 zenith kernel: Bluetooth: BNEP socket layer initialized Feb 08 11:32:41 zenith bluetoothd[997]: Bluetooth management interface 1.23 initialized Feb 08 11:32:41 zenith systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Feb 08 11:32:41 zenith bluetoothd[997]: Battery Provider Manager created Feb 08 11:32:41 zenith systemd[1]: Started bluetooth.service - Bluetooth service. Feb 08 11:32:41 zenith systemd[1]: Started systemd-logind.service - User Login Management. Feb 08 11:32:41 zenith systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 08 11:32:41 zenith kernel: Bluetooth: MGMT ver 1.23 Feb 08 11:32:41 zenith systemd[1]: iptables.service: Deactivated successfully. Feb 08 11:32:41 zenith systemd[1]: Starting bluealsa.service - BlueALSA service... Feb 08 11:32:41 zenith systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Feb 08 11:32:41 zenith kernel: NET: Registered PF_ALG protocol family Feb 08 11:32:41 zenith dbus-daemon[998]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 08 11:32:41 zenith systemd[1]: Started systemd-hostnamed.service - Hostname Service. Feb 08 11:32:41 zenith wpa_supplicant[1122]: Successfully initialized wpa_supplicant Feb 08 11:32:41 zenith systemd[1]: Started wpa_supplicant.service - WPA supplicant. Feb 08 11:32:41 zenith systemd[1]: Reached target network.target - Network. Feb 08 11:32:41 zenith systemd[1]: Reached target network-online.target - Network is Online. Feb 08 11:32:42 zenith bluetoothd[997]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Feb 08 11:32:42 zenith bluetoothd[997]: bap: Operation not supported (95) Feb 08 11:32:42 zenith bluetoothd[997]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Feb 08 11:32:42 zenith systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Feb 08 11:32:42 zenith systemd[1]: Starting nmbd.service - Samba NMB Daemon... Feb 08 11:32:42 zenith systemd[1]: Starting ntpsec.service - Network Time Service... Feb 08 11:32:42 zenith systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Feb 08 11:32:42 zenith systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Feb 08 11:32:42 zenith systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Feb 08 11:32:42 zenith volumio-remote-updater[1010]: Volumio OTA Client: 1.7.0 Feb 08 11:32:42 zenith volumio-remote-updater[1010]: Error: No active session Feb 08 11:32:42 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:42] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 08 11:32:42 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:42] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 08 11:32:42 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:42] [error] handle_connect error: Connection refused Feb 08 11:32:42 zenith systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 08 11:32:42 zenith systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:32:42 zenith sm-notify[1148]: Version 2.6.2 starting Feb 08 11:32:42 zenith systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Feb 08 11:32:42 zenith systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Feb 08 11:32:42 zenith systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Feb 08 11:32:42 zenith systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 08 11:32:42 zenith systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Feb 08 11:32:42 zenith systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Feb 08 11:32:42 zenith systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Feb 08 11:32:42 zenith systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Feb 08 11:32:42 zenith welcome[1166]: Resolved ip:[0] Feb 08 11:32:42 zenith upmpdcli[1008]: Could not open config: /tmp/upmpdcli.conf Feb 08 11:32:42 zenith ifplugd(eth0)[1201]: ifplugd 0.28 initializing. Feb 08 11:32:42 zenith ifplugd(eth0)[1201]: Using interface eth0/2C:CF:67:95:E5:67 with driver (version: 6.12.47-v8+) Feb 08 11:32:42 zenith ifplugd(eth0)[1201]: Using detection mode: SIOCETHTOOL Feb 08 11:32:42 zenith ifplugd(eth0)[1201]: Initialization complete, link beat not detected. Feb 08 11:32:42 zenith systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 08 11:32:42 zenith systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 08 11:32:42 zenith rpi-eeprom-update[1002]: BOOTLOADER: up to date Feb 08 11:32:42 zenith rpi-eeprom-update[1002]: CURRENT: Wed Nov 5 17:37:18 UTC 2025 (1762364238) Feb 08 11:32:42 zenith rpi-eeprom-update[1002]: LATEST: Wed Nov 5 17:37:18 UTC 2025 (1762364238) Feb 08 11:32:42 zenith rpi-eeprom-update[1002]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default) Feb 08 11:32:42 zenith rpi-eeprom-update[1002]: Use raspi-config to change the release. Feb 08 11:32:42 zenith systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Feb 08 11:32:42 zenith ntpd[1158]: INIT: ntpd ntpsec-1.2.2: Starting Feb 08 11:32:42 zenith ntp-systemd-wrapper[1158]: 2026-02-08T11:32:42 ntpd[1158]: INIT: ntpd ntpsec-1.2.2: Starting Feb 08 11:32:42 zenith ntp-systemd-wrapper[1158]: 2026-02-08T11:32:42 ntpd[1158]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Feb 08 11:32:42 zenith ntpd[1158]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Feb 08 11:32:42 zenith systemd[1]: Started ntpsec.service - Network Time Service. Feb 08 11:32:42 zenith ntpd[1204]: INIT: precision = 0.370 usec (-21) Feb 08 11:32:42 zenith ntpd[1204]: INIT: successfully locked into RAM Feb 08 11:32:42 zenith ntpd[1204]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Feb 08 11:32:42 zenith ntpd[1204]: CONFIG: restrict nopeer ignored Feb 08 11:32:42 zenith systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 08 11:32:42 zenith ntpd[1204]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 08 11:32:42 zenith ntpd[1204]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Feb 08 11:32:42 zenith ntpd[1204]: INIT: Using SO_TIMESTAMPNS(ns) Feb 08 11:32:42 zenith ntpd[1204]: IO: Listen and drop on 0 v6wildcard [::]:123 Feb 08 11:32:42 zenith ntpd[1204]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 08 11:32:42 zenith ntpd[1204]: IO: Listen normally on 2 lo 127.0.0.1:123 Feb 08 11:32:42 zenith ntpd[1204]: IO: Listening on routing socket on fd #19 for interface updates Feb 08 11:32:42 zenith ifplugd[1138]: Network Interface Plugging Daemon...start eth0...done. Feb 08 11:32:42 zenith ntpd[1204]: INIT: This system has a 32-bit time_t. Feb 08 11:32:42 zenith ntpd[1204]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Feb 08 11:32:42 zenith ntpd[1204]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 08 11:32:42 zenith ntpd[1204]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Feb 08 11:32:42 zenith ntpd[1204]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Feb 08 11:32:42 zenith ntpd[1204]: NTSc: Using system default root certificates. Feb 08 11:32:42 zenith systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Feb 08 11:32:42 zenith sshd[1210]: Server listening on 0.0.0.0 port 22. Feb 08 11:32:42 zenith sshd[1210]: Server listening on :: port 22. Feb 08 11:32:42 zenith systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Feb 08 11:32:42 zenith ntpd[1204]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Feb 08 11:32:42 zenith bluealsa[1129]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Feb 08 11:32:42 zenith bluealsa[1129]: main.c:663: Starting main dispatching loop Feb 08 11:32:42 zenith systemd[1]: Started bluealsa.service - BlueALSA service. Feb 08 11:32:42 zenith bluealsa[1129]: main.c:118: Acquired D-Bus service name: org.bluealsa Feb 08 11:32:42 zenith systemd[1]: Reached target bluetooth.target - Bluetooth Support. Feb 08 11:32:42 zenith bluealsa[1129]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Feb 08 11:32:42 zenith bluealsa[1129]: bluez.c:153: Registering media application: /org/bluez/hci0 Feb 08 11:32:42 zenith bluealsa[1129]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Feb 08 11:32:42 zenith bluealsa[1129]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Feb 08 11:32:42 zenith bluealsa[1129]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Feb 08 11:32:42 zenith bluealsa[1129]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Feb 08 11:32:42 zenith bluetoothd[997]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Feb 08 11:32:42 zenith bluetoothd[997]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Feb 08 11:32:42 zenith bluetoothd[997]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Feb 08 11:32:42 zenith bluetoothd[997]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Feb 08 11:32:42 zenith avahi-daemon[996]: Server startup complete. Host name is zenith.local. Local service cookie is 1438330016. Feb 08 11:32:42 zenith systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Feb 08 11:32:42 zenith systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Feb 08 11:32:42 zenith nmbd[1241]: [2026/02/08 11:32:42.853877, 0] ../../source3/nmbd/nmbd.c:901(main) Feb 08 11:32:42 zenith nmbd[1241]: nmbd version 4.17.8-Raspbian started. Feb 08 11:32:42 zenith nmbd[1241]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 08 11:32:42 zenith nmbd[1241]: [2026/02/08 11:32:42.859102, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 08 11:32:42 zenith nmbd[1241]: started asyncdns process 1242 Feb 08 11:32:42 zenith nmbd[1241]: [2026/02/08 11:32:42.859558, 0] ../../lib/util/become_daemon.c:150(daemon_status) Feb 08 11:32:42 zenith nmbd[1241]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 08 11:32:42 zenith nmbd[1241]: [2026/02/08 11:32:42.859582, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Feb 08 11:32:42 zenith nmbd[1241]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 08 11:32:42 zenith wireless.js[1012]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Feb 08 11:32:42 zenith wireless.js[1012]: WIRELESS.JS: Wireless.js initializing wireless flow Feb 08 11:32:42 zenith wireless.js[1012]: WIRELESS.JS: Cleaning previous... Feb 08 11:32:42 zenith sudo[1251]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 08 11:32:42 zenith sudo[1251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:32:42 zenith sudo[1251]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:42 zenith sudo[1253]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 08 11:32:42 zenith sudo[1253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:32:43 zenith ntpd[1204]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:32:43 zenith avahi-daemon[996]: Service "Zenith" (/services/volumio.service) successfully established. Feb 08 11:32:43 zenith ntpd[1204]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 08 11:32:43 zenith ntpd[1204]: DNS: dns_check: DNS error: -11, System error Feb 08 11:32:43 zenith ntpd[1204]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Feb 08 11:32:43 zenith sudo[1253]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:43 zenith wireless.js[1012]: WIRELESS.JS: Stopped aP Feb 08 11:32:43 zenith sudo[1268]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 08 11:32:43 zenith sudo[1268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:43 zenith sudo[1268]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:43 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 08 11:32:43 zenith sudo[1270]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 08 11:32:43 zenith sudo[1270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:43 zenith sudo[1270]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:43 zenith sudo[1278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 08 11:32:43 zenith sudo[1278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:43 zenith sudo[1278]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:43 zenith sudo[1280]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 08 11:32:43 zenith sudo[1280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:44 zenith ntpd[1204]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:32:44 zenith ntpd[1204]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 08 11:32:44 zenith ntpd[1204]: DNS: dns_check: DNS error: -11, System error Feb 08 11:32:44 zenith ntpd[1204]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Feb 08 11:32:45 zenith systemd[1]: systemd-rfkill.service: Deactivated successfully. Feb 08 11:32:45 zenith ntpd[1204]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:32:45 zenith ntpd[1204]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 08 11:32:45 zenith ntpd[1204]: DNS: dns_check: DNS error: -11, System error Feb 08 11:32:45 zenith ntpd[1204]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Feb 08 11:32:46 zenith sudo[1280]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:46 zenith wireless.js[1012]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Feb 08 11:32:46 zenith ntpd[1204]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:32:46 zenith ntpd[1204]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 08 11:32:46 zenith ntpd[1204]: DNS: dns_check: DNS error: -11, System error Feb 08 11:32:46 zenith ntpd[1204]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Feb 08 11:32:46 zenith sudo[1306]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 08 11:32:46 zenith sudo[1306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:46 zenith sudo[1306]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:46 zenith sudo[1309]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Feb 08 11:32:46 zenith sudo[1309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:46 zenith sudo[1309]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:46 zenith wireless.js[1012]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Feb 08 11:32:46 zenith wireless.js[1012]: WIRELESS.JS: Start wireless flow Feb 08 11:32:46 zenith wireless.js[1012]: WIRELESS.JS: Stopped hotspot (if there).. Feb 08 11:32:46 zenith sudo[1319]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 08 11:32:46 zenith sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:32:46 zenith sudo[1319]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:46 zenith sudo[1321]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 08 11:32:46 zenith sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:32:46 zenith sudo[1321]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:46 zenith wireless.js[1012]: WIRELESS.JS: DHCP IP fallback Feb 08 11:32:46 zenith wireless.js[1012]: WIRELESS.JS: Start ap Feb 08 11:32:46 zenith wpa_supplicant[1326]: Successfully initialized wpa_supplicant Feb 08 11:32:46 zenith sudo[1327]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 08 11:32:46 zenith sudo[1327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:32:46 zenith dhcpcd[1058]: control command: /sbin/dhcpcd Feb 08 11:32:46 zenith sudo[1327]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:46 zenith dhcpcd[1058]: control_free: No such file or directory Feb 08 11:32:46 zenith dhcpcd[1058]: lo: IAID 00:00:00:00 Feb 08 11:32:46 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 08 11:32:46 zenith dhcpcd[1058]: lo: adding address fe80::6430:6751:43e0:3f69 Feb 08 11:32:46 zenith dhcpcd[1058]: ipv6_addaddr1: Permission denied Feb 08 11:32:46 zenith dhcpcd[1058]: lo: using static address 127.0.0.1/8 Feb 08 11:32:46 zenith dhcpcd[1058]: lo: adding route to 127.0.0.0/8 Feb 08 11:32:46 zenith systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Feb 08 11:32:46 zenith wpa_supplicant[1326]: nl80211: kernel reports: Registration to specific type not supported Feb 08 11:32:47 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:47] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 08 11:32:47 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:47] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 08 11:32:47 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:47] [error] handle_connect error: Connection refused Feb 08 11:32:47 zenith bash[1156]: setdatetime-helper: all HTTPS Date fallbacks failed Feb 08 11:32:47 zenith systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 08 11:32:47 zenith systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 08 11:32:47 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:47 zenith sudo[1354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:47 zenith sudo[1354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:47 zenith sudo[1354]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:48 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:48 zenith sudo[1358]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:48 zenith sudo[1358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:48 zenith sudo[1358]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:49 zenith wpa_supplicant[1350]: wlan0: Trying to associate with d8:8e:d4:11:15:e7 (SSID='18070Farm' freq=5500 MHz) Feb 08 11:32:49 zenith wpa_supplicant[1350]: wlan0: Associated with d8:8e:d4:11:15:e7 Feb 08 11:32:49 zenith wpa_supplicant[1350]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 08 11:32:49 zenith wpa_supplicant[1350]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Feb 08 11:32:49 zenith wpa_supplicant[1350]: wlan0: WPA: Key negotiation completed with d8:8e:d4:11:15:e7 [PTK=CCMP GTK=CCMP] Feb 08 11:32:49 zenith wpa_supplicant[1350]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:8e:d4:11:15:e7 completed [id=0 id_str=] Feb 08 11:32:49 zenith dhcpcd[1058]: wlan0: carrier acquired Feb 08 11:32:49 zenith dhcpcd[1058]: wlan0: connected to Access Point: 18070Farm Feb 08 11:32:49 zenith dhcpcd[1058]: wlan0: IAID 67:95:e5:69 Feb 08 11:32:49 zenith dhcpcd[1058]: wlan0: adding address fe80::422e:83e0:3e32:602d Feb 08 11:32:49 zenith dhcpcd[1058]: ipv6_addaddr1: Permission denied Feb 08 11:32:49 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:49 zenith sudo[1363]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:49 zenith sudo[1363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:49 zenith sudo[1363]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:50 zenith dhcpcd[1058]: wlan0: soliciting an IPv6 router Feb 08 11:32:50 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:50 zenith sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:50 zenith sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:50 zenith sudo[1367]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:51 zenith dhcpcd[1058]: wlan0: rebinding lease of 192.168.133.234 Feb 08 11:32:51 zenith dhcpcd[1058]: wlan0: probing address 192.168.133.234/24 Feb 08 11:32:51 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:51 zenith sudo[1373]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:51 zenith sudo[1373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:51 zenith sudo[1373]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:52 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:52 zenith sudo[1377]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:52 zenith sudo[1377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:52 zenith sudo[1377]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:53 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:53 zenith sudo[1381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:53 zenith sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:53 zenith sudo[1381]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:54 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:54] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 08 11:32:54 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:54] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 08 11:32:54 zenith volumio-remote-updater[1010]: [2026-02-08 11:32:54] [error] handle_connect error: Connection refused Feb 08 11:32:54 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:54 zenith sudo[1385]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:54 zenith sudo[1385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:54 zenith sudo[1385]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:55 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:55 zenith sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:55 zenith sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:55 zenith sudo[1389]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:56 zenith dhcpcd[1058]: wlan0: leased 192.168.133.234 for 86400 seconds Feb 08 11:32:56 zenith avahi-daemon[996]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.133.234. Feb 08 11:32:56 zenith avahi-daemon[996]: New relevant interface wlan0.IPv4 for mDNS. Feb 08 11:32:56 zenith avahi-daemon[996]: Registering new address record for 192.168.133.234 on wlan0.IPv4. Feb 08 11:32:56 zenith dhcpcd[1058]: wlan0: adding route to 192.168.133.0/24 Feb 08 11:32:56 zenith dhcpcd[1058]: wlan0: adding default route via 192.168.133.1 Feb 08 11:32:56 zenith systemd[1]: welcome.service: Deactivated successfully. Feb 08 11:32:56 zenith systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 08 11:32:56 zenith systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 08 11:32:56 zenith systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 08 11:32:56 zenith welcome[1400]: Resolved ip:[1] 192.168.133.234 Feb 08 11:32:56 zenith systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 08 11:32:56 zenith systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 08 11:32:56 zenith systemd[1]: Started nmbd.service - Samba NMB Daemon. Feb 08 11:32:56 zenith wireless.js[1012]: WIRELESS.JS: trying... Feb 08 11:32:56 zenith sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 08 11:32:56 zenith sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:56 zenith sudo[1421]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:56 zenith wireless.js[1012]: WIRELESS.JS: Connected to: ----18070Farm---- Feb 08 11:32:56 zenith sudo[1424]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 11:32:56 zenith sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:32:56 zenith sudo[1424]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:56 zenith wireless.js[1012]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.133.234, ipV6 is undefined Feb 08 11:32:56 zenith wireless.js[1012]: WIRELESS.JS: It's done! AP Feb 08 11:32:56 zenith wireless.js[1012]: WIRELESS.JS: Restarting avahi-daemon... Feb 08 11:32:56 zenith systemd[1]: Starting winbind.service - Samba Winbind Daemon... Feb 08 11:32:56 zenith systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 11:32:56 zenith systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 11:32:56 zenith systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:32:56 zenith systemd[1]: shairport-sync.service: Consumed 1.580s CPU time. Feb 08 11:32:56 zenith systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 08 11:32:56 zenith avahi-daemon[996]: Got SIGTERM, quitting. Feb 08 11:32:56 zenith avahi-daemon[996]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.133.234. Feb 08 11:32:56 zenith avahi-daemon[996]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 08 11:32:56 zenith avahi-daemon[996]: avahi-daemon 0.8 exiting. Feb 08 11:32:56 zenith systemd[1]: avahi-daemon.service: Deactivated successfully. Feb 08 11:32:56 zenith systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 08 11:32:56 zenith wireless.js[1012]: WIRELESS.JS: Notified systemd about wireless ready Feb 08 11:32:56 zenith systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 08 11:32:56 zenith avahi-daemon[1432]: Process 996 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Feb 08 11:32:56 zenith avahi-daemon[1432]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Feb 08 11:32:56 zenith avahi-daemon[1432]: Successfully dropped root privileges. Feb 08 11:32:56 zenith avahi-daemon[1432]: avahi-daemon 0.8 starting up. Feb 08 11:32:56 zenith kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 08 11:32:56 zenith systemd[1]: Started wireless.service - Wireless Services. Feb 08 11:32:56 zenith avahi-daemon[1432]: Successfully called chroot(). Feb 08 11:32:56 zenith avahi-daemon[1432]: Successfully dropped remaining capabilities. Feb 08 11:32:56 zenith systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 11:32:56 zenith avahi-daemon[1432]: Loading service file /services/volumio.service. Feb 08 11:32:56 zenith avahi-daemon[1432]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.133.234. Feb 08 11:32:56 zenith avahi-daemon[1432]: New relevant interface wlan0.IPv4 for mDNS. Feb 08 11:32:56 zenith avahi-daemon[1432]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 08 11:32:56 zenith avahi-daemon[1432]: New relevant interface lo.IPv4 for mDNS. Feb 08 11:32:56 zenith avahi-daemon[1432]: Network interface enumeration completed. Feb 08 11:32:56 zenith avahi-daemon[1432]: Registering new address record for 192.168.133.234 on wlan0.IPv4. Feb 08 11:32:56 zenith avahi-daemon[1432]: Registering new address record for 127.0.0.1 on lo.IPv4. Feb 08 11:32:56 zenith systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 08 11:32:56 zenith systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 08 11:32:56 zenith systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 08 11:32:56 zenith systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:32:56 zenith systemd[1]: Received SIGRTMIN+21 from PID 402 (plymouthd). Feb 08 11:32:56 zenith systemd[1]: Received SIGRTMIN+21 from PID 402 (plymouthd). Feb 08 11:32:56 zenith systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 08 11:32:56 zenith systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 08 11:32:56 zenith systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 08 11:32:56 zenith systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Feb 08 11:32:56 zenith systemd[1]: Reached target getty.target - Login Prompts. Feb 08 11:32:56 zenith volumio-cpu-tweak[1437]: Setting RT Priority for mpd Feb 08 11:32:56 zenith volumio-cpu-tweak[1469]: pid 35's current scheduling policy: SCHED_OTHER Feb 08 11:32:56 zenith volumio-cpu-tweak[1469]: pid 35's current scheduling priority: 0 Feb 08 11:32:56 zenith volumio-cpu-tweak[1437]: Setting MPD Affinity Feb 08 11:32:56 zenith volumio-cpu-tweak[1471]: pid 3's current affinity mask: f Feb 08 11:32:56 zenith volumio-cpu-tweak[1437]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 08 11:32:56 zenith volumio-cpu-tweak[1437]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Feb 08 11:32:57 zenith systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 08 11:32:57 zenith winbindd[1451]: [2026/02/08 11:32:57.048390, 0] ../../source3/winbindd/winbindd.c:1440(main) Feb 08 11:32:57 zenith winbindd[1451]: winbindd version 4.17.8-Raspbian started. Feb 08 11:32:57 zenith winbindd[1451]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 08 11:32:57 zenith winbindd[1451]: [2026/02/08 11:32:57.053031, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Feb 08 11:32:57 zenith winbindd[1451]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 08 11:32:57 zenith systemd[1]: Started winbind.service - Samba Winbind Daemon. Feb 08 11:32:57 zenith systemd[1]: Starting smbd.service - Samba SMB Daemon... Feb 08 11:32:57 zenith smbd[1480]: [2026/02/08 11:32:57.213997, 0] ../../source3/smbd/server.c:1741(main) Feb 08 11:32:57 zenith smbd[1480]: smbd version 4.17.8-Raspbian started. Feb 08 11:32:57 zenith smbd[1480]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 08 11:32:57 zenith systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 08 11:32:57 zenith systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 08 11:32:57 zenith systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 08 11:32:57 zenith upmpdcli[1492]: Could not open config: /tmp/upmpdcli.conf Feb 08 11:32:57 zenith systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 08 11:32:57 zenith systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 08 11:32:57 zenith systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 08 11:32:57 zenith systemd[1]: Reached target multi-user.target - Multi-User System. Feb 08 11:32:57 zenith systemd[1]: Reached target graphical.target - Graphical Interface. Feb 08 11:32:57 zenith systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 08 11:32:57 zenith systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 08 11:32:57 zenith systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 08 11:32:57 zenith systemd[1]: Startup finished in 11.128s (kernel) + 18.491s (userspace) = 29.619s. Feb 08 11:32:57 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:32:57 zenith volumio[1435]: info: ----- Volumio3 ---- Feb 08 11:32:57 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:32:57 zenith volumio[1435]: info: ----- System startup ---- Feb 08 11:32:57 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:32:57 zenith avahi-daemon[1432]: Server startup complete. Host name is zenith.local. Local service cookie is 3069025280. Feb 08 11:32:58 zenith volumio[1435]: info: MYVOLUMIO Environment detected Feb 08 11:32:58 zenith volumio[1435]: info: Plugin folders cleanup Feb 08 11:32:58 zenith volumio[1435]: info: Scanning into folder /volumio/app/plugins/ Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category audio_interface Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category miscellanea Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category music_service Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category plugins.json Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category system_controller Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category user_interface Feb 08 11:32:58 zenith volumio[1435]: info: Scanning into folder /data/plugins/ Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category audio_interface Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category music_service Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category system_controller Feb 08 11:32:58 zenith volumio[1435]: info: Scanning category user_interface Feb 08 11:32:58 zenith volumio[1435]: info: Plugin folders cleanup completed Feb 08 11:32:58 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:32:58 zenith volumio[1435]: info: ----- Core plugins startup ---- Feb 08 11:32:58 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 11:32:58 zenith volumio[1435]: info: Adding plugin upnp to MyMusic Plugins Feb 08 11:32:58 zenith volumio[1435]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 11:32:58 zenith volumio[1435]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugins from folder /data/plugins/ Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "system"... Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "appearance"... Feb 08 11:32:58 zenith ntpd[1204]: IO: Listen normally on 3 wlan0 192.168.133.234:123 Feb 08 11:32:58 zenith ntpd[1204]: IO: new interface(s) found: waking up resolver Feb 08 11:32:58 zenith ntpd[1204]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "network"... Feb 08 11:32:58 zenith volumio[1435]: info: Refreshing Cached IP Addresses Feb 08 11:32:58 zenith sudo[1515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 11:32:58 zenith sudo[1515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:58 zenith sudo[1517]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 11:32:58 zenith sudo[1517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:58 zenith sudo[1517]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:58 zenith sudo[1515]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "services"... Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "alsa_controller"... Feb 08 11:32:58 zenith sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 11:32:58 zenith sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:58 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "wizard"... Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "networkfs"... Feb 08 11:32:58 zenith volumio[1435]: info: Starting Udev Watcher for removable devices Feb 08 11:32:58 zenith sudo[1549]: 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 08 11:32:58 zenith sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:32:58 zenith volumio[1435]: info: Ignoring mount for partition: boot Feb 08 11:32:58 zenith volumio[1435]: info: Ignoring mount for partition: volumio Feb 08 11:32:58 zenith volumio[1435]: info: Ignoring mount for partition: volumio_data Feb 08 11:32:58 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "volumio_command_line_client"... Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "upnp"... Feb 08 11:32:58 zenith volumio[1435]: info: [1770568378641] Starting Upmpd Daemon Feb 08 11:32:58 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "my_music"... Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "mpd"... Feb 08 11:32:58 zenith avahi-daemon[1432]: Service "Zenith" (/services/volumio.service) successfully established. Feb 08 11:32:58 zenith ntpd[1204]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 08 11:32:58 zenith ntpd[1204]: DNS: Pool taking: 23.143.196.200 Feb 08 11:32:58 zenith ntpd[1204]: DNS: Pool taking: 104.167.215.195 Feb 08 11:32:58 zenith ntpd[1204]: DNS: Pool taking: 23.159.16.194 Feb 08 11:32:58 zenith ntpd[1204]: DNS: Pool taking: 23.143.196.199 Feb 08 11:32:58 zenith ntpd[1204]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 08 11:32:58 zenith kernel: netfs: FS-Cache loaded Feb 08 11:32:58 zenith volumio[1435]: info: Loading plugin "upnp_browser"... Feb 08 11:32:58 zenith kernel: Key type cifs.spnego registered Feb 08 11:32:58 zenith kernel: Key type cifs.idmap registered Feb 08 11:32:58 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 08 11:32:58 zenith kernel: CIFS: Attempting to mount //library/data/media/music Feb 08 11:32:59 zenith volumio[1435]: info: Starting UPNP Browser Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "alarm-clock"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "airplay_emulation"... Feb 08 11:32:59 zenith volumio[1435]: info: Starting Shairport Sync Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "last_100"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "webradio"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "i2s_dacs"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "volumiodiscovery"... Feb 08 11:32:59 zenith volumio[1435]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 11:32:59 zenith volumio[1435]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 11:32:59 zenith volumio[1435]: *** WARNING *** For more information see Feb 08 11:32:59 zenith volumio[1435]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 11:32:59 zenith volumio[1435]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 11:32:59 zenith volumio[1435]: *** WARNING *** For more information see Feb 08 11:32:59 zenith node[1435]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 11:32:59 zenith node[1435]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 11:32:59 zenith node[1435]: *** WARNING *** For more information see Feb 08 11:32:59 zenith node[1435]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 11:32:59 zenith node[1435]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 11:32:59 zenith node[1435]: *** WARNING *** For more information see Feb 08 11:32:59 zenith volumio[1435]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 11:32:59 zenith volumio[1435]: info: Discovery: Started advertising with name: Zenith Feb 08 11:32:59 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "spop"... Feb 08 11:32:59 zenith ntpd[1204]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:32:59 zenith ntpd[1204]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 198.23.133.146 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 198.71.50.75 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 172.233.157.223 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 96.19.94.82 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 2604:e880:3::4 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 2600:3c06::2000:a7ff:fe46:9a62 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 2602:f9f3:1:2f::3:123 Feb 08 11:32:59 zenith ntpd[1204]: DNS: Pool taking: 2602:f9f3:1:2f::123:123 Feb 08 11:32:59 zenith ntpd[1204]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "outputs"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "albumart"... Feb 08 11:32:59 zenith volumio[1435]: info: Plugin example_plugin is not enabled Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "inputs"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "updater_comm"... Feb 08 11:32:59 zenith volumio[1435]: info: Plugin mpdemulation is not enabled Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "rest_api"... Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "websocket"... Feb 08 11:32:59 zenith volumio[1435]: info: Starting Socket.io Server version 1.7.4 Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "fusiondsp"... Feb 08 11:32:59 zenith volumio[1578]: Forking 3 albumart workers Feb 08 11:32:59 zenith sudo[1549]: pam_unix(sudo:session): session closed for user root Feb 08 11:32:59 zenith volumio[1435]: info: Applying required configuration parameters for plugin fusiondsp Feb 08 11:32:59 zenith volumio[1435]: info: Loading plugin "backup_restore"... Feb 08 11:33:00 zenith volumio[1589]: Starting albumart workers Feb 08 11:33:00 zenith volumio[1435]: info: Applying required configuration parameters for plugin backup_restore Feb 08 11:33:00 zenith volumio[1435]: info: Loading plugin "Systeminfo"... Feb 08 11:33:00 zenith volumio[1590]: Starting albumart workers Feb 08 11:33:00 zenith volumio[1591]: Starting albumart workers Feb 08 11:33:00 zenith volumio[1435]: info: Loading i18n strings for locale en Feb 08 11:33:00 zenith volumio[1435]: Updating browse sources language Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::initPlayerControls Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: Express server listening on port 3000 Feb 08 11:33:00 zenith volumio[1435]: [Metrics] WebUI: 2s 686.12ms Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::resetVolumioState Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::getcurrentVolume Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 11:33:00 zenith volumio[1435]: info: Volumio Network Manager: Network status updated: 2 Feb 08 11:33:00 zenith ntpd[1204]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: Reloading queue from file Feb 08 11:33:00 zenith volumio[1435]: info: Setting Device type: Raspberry PI Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::setRepeat null single undefined Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::pushState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioPushState Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::setRandom null Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::pushState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioPushState Feb 08 11:33:00 zenith volumio[1435]: info: Completed loading Core Plugins Feb 08 11:33:00 zenith volumio[1435]: info: Preparing to generate the ALSA configuration file Feb 08 11:33:00 zenith volumio[1435]: info: VolumeController:: Volume=32 Mute =false Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::pushState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioPushState Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::updateTrackBlock Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrackBlock Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 11:33:00 zenith volumio[1435]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 08 11:33:00 zenith volumio[1435]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Feb 08 11:33:00 zenith volumio[1435]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 08 11:33:00 zenith volumio[1435]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 08 11:33:00 zenith volumio[1435]: info: Reading ALSA contributions from plugins. Feb 08 11:33:00 zenith volumio[1435]: info: VolumeController:: Volume=32 Mute =false Feb 08 11:33:00 zenith volumio[1435]: info: CoreStateMachine::pushState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioPushState Feb 08 11:33:00 zenith volumio[1435]: info: Asound.conf file written Feb 08 11:33:00 zenith sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 08 11:33:00 zenith sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1660]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith volumio[1435]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 08 11:33:00 zenith volumio[1435]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 08 11:33:00 zenith volumio[1435]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 08 11:33:00 zenith volumio[1435]: info: Output device has changed, restarting MPD Feb 08 11:33:00 zenith volumio[1435]: info: Output device has changed, restarting Shairport Sync Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith sudo[1668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 11:33:00 zenith sudo[1668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 11:33:00 zenith sudo[1666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1666]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith volumio[1435]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 11:33:00 zenith volumio[1435]: info: ___________ START PLUGINS ___________ Feb 08 11:33:00 zenith volumio[1435]: info: ControllerMpd::onStart: Initializing MPD Feb 08 11:33:00 zenith volumio[1435]: info: Creating MPD Configuration file Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 11:33:00 zenith volumio[1435]: info: [1770568380404] CoreMusicLibrary::Adding element Media Servers Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 11:33:00 zenith sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 11:33:00 zenith volumio[1435]: info: UPNP Browser: Client initialized successfully Feb 08 11:33:00 zenith sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1677]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith sudo[1679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 11:33:00 zenith sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 11:33:00 zenith volumio[1435]: info: [1770568380422] CoreMusicLibrary::Adding element Last_100 Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 11:33:00 zenith volumio[1435]: info: [1770568380423] CoreMusicLibrary::Adding element Webradio Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 11:33:00 zenith volumio[1435]: info: Initializing BBC Radios Feb 08 11:33:00 zenith systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 11:33:00 zenith systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 11:33:00 zenith systemd[1]: mpd.service: Deactivated successfully. Feb 08 11:33:00 zenith systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 11:33:00 zenith systemd[1]: mpd.socket: Deactivated successfully. Feb 08 11:33:00 zenith systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 11:33:00 zenith systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 11:33:00 zenith systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 11:33:00 zenith volumio[1435]: info: Creating Spotify config file Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith ntpd[1204]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 08 11:33:00 zenith ntpd[1204]: DNS: Pool taking: 172.235.154.118 Feb 08 11:33:00 zenith ntpd[1204]: DNS: Pool taking: 23.186.168.124 Feb 08 11:33:00 zenith ntpd[1204]: DNS: Pool taking: 50.251.160.20 Feb 08 11:33:00 zenith ntpd[1204]: DNS: Pool taking: 170.187.147.56 Feb 08 11:33:00 zenith ntpd[1204]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 08 11:33:00 zenith sudo[1706]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 11:33:00 zenith sudo[1706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:33:00 zenith sudo[1707]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 08 11:33:00 zenith sudo[1706]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith volumio[1435]: info: Loading i18n strings for locale en Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: FusionDsp - mixtype--------------------- Hardware Feb 08 11:33:00 zenith volumio[1435]: info: Volumio Calling Home Feb 08 11:33:00 zenith volumio[1435]: info: Preparing to generate the ALSA configuration file Feb 08 11:33:00 zenith volumio[1435]: info: Discovery: adding c4f84076-c4bc-4d19-9ff3-91769836aab1 Feb 08 11:33:00 zenith volumio[1435]: info: Discovery: Found device Zenith Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioGetState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith volumio[1435]: info: Discovery: this is already registered, c4f84076-c4bc-4d19-9ff3-91769836aab1 Feb 08 11:33:00 zenith volumio[1435]: info: Discovery: Found device Zenith Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioGetState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith volumio[1435]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 08 11:33:00 zenith volumio[1435]: info: Reading ALSA contributions from plugins. Feb 08 11:33:00 zenith volumio[1435]: info: MPD Permissions set Feb 08 11:33:00 zenith volumio[1435]: info: MPD Permissions set Feb 08 11:33:00 zenith volumio[1435]: info: Spotify config file written Feb 08 11:33:00 zenith sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 11:33:00 zenith sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith volumio[1435]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 08 11:33:00 zenith volumio[1435]: info: No need to fix Spotify hosts Feb 08 11:33:00 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 08 11:33:00 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 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: Starting Shairport Sync Feb 08 11:33:00 zenith volumio[1435]: info: Starting Shairport Sync Feb 08 11:33:00 zenith volumio[1435]: info: Starting Shairport Sync Feb 08 11:33:00 zenith volumio[1435]: info: Asound.conf file unchanged, so no further update is needed Feb 08 11:33:00 zenith volumio[1435]: info: Output device has changed, restarting MPD Feb 08 11:33:00 zenith systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 11:33:00 zenith sudo[1734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 11:33:00 zenith go-librespot[1719]: go-librespot daemon starting... Feb 08 11:33:00 zenith sudo[1734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1717]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 11:33:00 zenith sudo[1732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 11:33:00 zenith sudo[1732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith volumio[1435]: info: Output device has changed, restarting Shairport Sync Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 11:33:00 zenith sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith sudo[1740]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith volumio[1435]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 11:33:00 zenith sudo[1744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 11:33:00 zenith sudo[1744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith volumio[1435]: info: MPD Permissions set Feb 08 11:33:00 zenith systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 11:33:00 zenith systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 11:33:00 zenith systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:33:00 zenith systemd[1]: shairport-sync.service: Consumed 1.512s CPU time. Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:33:00 zenith sudo[1734]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith sudo[1732]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith sudo[1736]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith systemd[1]: mpd.service: Deactivated successfully. Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 11:33:00 zenith systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:00 zenith systemd[1]: mpd.socket: Deactivated successfully. Feb 08 11:33:00 zenith systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 11:33:00 zenith systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 11:33:00 zenith volumio[1435]: info: Shairport-Sync Started Feb 08 11:33:00 zenith volumio[1435]: Error adding Membership: Error: addMembership EINVAL Feb 08 11:33:00 zenith volumio[1435]: info: Shairport-Sync Started Feb 08 11:33:00 zenith volumio[1435]: info: Shairport-Sync Started Feb 08 11:33:00 zenith systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 11:33:00 zenith systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 11:33:00 zenith volumio[1435]: info: Starting Shairport Sync Feb 08 11:33:00 zenith volumio[1435]: info: CoreCommandRouter::volumioGetState Feb 08 11:33:00 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:00 zenith sudo[1779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 11:33:00 zenith sudo[1779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:00 zenith systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 11:33:00 zenith systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 11:33:00 zenith sudo[1777]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 11:33:00 zenith systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:33:00 zenith sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 11:33:00 zenith systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 11:33:00 zenith sudo[1779]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith sudo[1782]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 08 11:33:00 zenith sudo[1777]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:00 zenith volumio[1435]: info: Shairport-Sync Started Feb 08 11:33:00 zenith go-librespot[1737]: time="2026-02-08T11:33:00-05:00" level=info msg="running go-librespot 0.4.0" Feb 08 11:33:00 zenith go-librespot[1737]: time="2026-02-08T11:33:00-05:00" level=debug msg="app state loaded" Feb 08 11:33:00 zenith go-librespot[1737]: time="2026-02-08T11:33:00-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 11:33:01 zenith volumio[1435]: info: Volumio called home Feb 08 11:33:01 zenith volumio[1435]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 11:33:01 zenith volumio[1435]: SPOTIFY: BQC3_JcdecvZ7tnJ3fwFjpiO2G5BG2nFv38YtI_1UJe6QZPXJg2RqRzyrlQ3MTwTM_33DQGnZj4bMn451NUUqPh0p3o_LZd66yUBh8e6ku_JWiyVtp-dZLXLEGj1RyEMKoZ0XluBrgZKoX6XqRFOMfd8cwj2_pG44ea_FRMJZio4t4dIDxPIm5t6aY9F8ai5hoND84eYqg00tjpJBLa3Cem2JOM_mSsHYUQyYAeMgdVwrlQnmGtESYljyVQ2an_kw7HXOK7838XmowGxGXtewyGN_AyLlfy3DbM Feb 08 11:33:01 zenith volumio[1435]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 11:33:01 zenith volumio[1435]: info: New Spotify access token = BQC3_JcdecvZ7tnJ3fwFjpiO2G5BG2nFv38YtI_1UJe6QZPXJg2RqRzyrlQ3MTwTM_33DQGnZj4bMn451NUUqPh0p3o_LZd66yUBh8e6ku_JWiyVtp-dZLXLEGj1RyEMKoZ0XluBrgZKoX6XqRFOMfd8cwj2_pG44ea_FRMJZio4t4dIDxPIm5t6aY9F8ai5hoND84eYqg00tjpJBLa3Cem2JOM_mSsHYUQyYAeMgdVwrlQnmGtESYljyVQ2an_kw7HXOK7838XmowGxGXtewyGN_AyLlfy3DbM Feb 08 11:33:01 zenith volumio[1435]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 11:33:01 zenith ntpd[1204]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 08 11:33:01 zenith volumio[1435]: 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-cdg4-2.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=93EO_UAbqZUQ7kNvwFI_D-u&_nc_oc=Adn5TJkCOSUSw8HjMC9bpfyzwfKJd58CYE38oQ-XZMxRxF3AdAnVIPfiXGVPCV2ZDitux6Zigjq5MKiGiPiXOBDC&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=81hIFaWMoWw3lCiepeWwCA&_nc_tpa=Q5bMBQGKnP-HNh4inxUmco2sukrQb-lq3vnL4TD7t-iwZCDwTFYuB4FJBo2EqeebipxfVpaYBzoG&oh=00_AfsNYrRyZwuQJOB2_HQ6hj1OEEXXIV06eCPsBKP7Q5eNNw&oe=698E5D3F","width":300},{"height":64,"url":"https://scontent-cdg4-2.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=93EO_UAbqZUQ7kNvwFI_D-u&_nc_oc=Adn5TJkCOSUSw8HjMC9bpfyzwfKJd58CYE38oQ-XZMxRxF3AdAnVIPfiXGVPCV2ZDitux6Zigjq5MKiGiPiXOBDC&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=81hIFaWMoWw3lCiepeWwCA&_nc_tpa=Q5bMBQEZjXsOeK-3d9ax6-qMh--enbwS4JJs2fZBm7h2-IW0pviBy_jgFITa3QeB3eMAxrntlpUF&oh=00_AfvwzIZM-y9GhOmQJc8BpCUQgc_ZwJ0MkslkDrAxNje7VA&oe=698E5D3F","width":64}],"product":"premium","type":"user","uri":"spotify:user:126269301"} Feb 08 11:33:01 zenith volumio[1435]: info: Spotify Successfully logged in Feb 08 11:33:01 zenith volumio[1435]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 11:33:01 zenith volumio[1435]: info: [1770568381347] CoreMusicLibrary::Adding element Spotify Feb 08 11:33:01 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 11:33:01 zenith volumio[1435]: Cannot find translation for source Spotify Feb 08 11:33:01 zenith ntpd[1204]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 08 11:33:01 zenith ntpd[1204]: DNS: Pool taking: 45.79.111.167 Feb 08 11:33:01 zenith ntpd[1204]: DNS: Pool taking: 66.118.229.14 Feb 08 11:33:01 zenith ntpd[1204]: DNS: Pool taking: 23.186.168.130 Feb 08 11:33:01 zenith ntpd[1204]: DNS: Pool taking: 67.217.246.204 Feb 08 11:33:01 zenith ntpd[1204]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 08 11:33:01 zenith go-librespot[1737]: time="2026-02-08T11:33:01-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 08 11:33:01 zenith go-librespot[1737]: time="2026-02-08T11:33:01-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 08 11:33:01 zenith go-librespot[1737]: time="2026-02-08T11:33:01-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 08 11:33:01 zenith go-librespot[1737]: time="2026-02-08T11:33:01-05:00" level=info msg="zeroconf server listening on port 34569" Feb 08 11:33:01 zenith go-librespot[1737]: time="2026-02-08T11:33:01-05:00" level=debug msg="obtained new client token: AAAwztK/uGuTia3LLaem/qAblzWc2PlND9cHTplE/YWADA+O7/wPs51CAkHts5j0s0xld1tN2IniSF880vuMh22DuArEPgFtIDdqC+w535iD75b8XUhJIikUERoN8CibEP5QxGk6gb8ME6uQc6yBDmp3LR7/NoJPWwn6b3l0MN8fCUy9DzMcd++g/fsSFW1+RN5m0Ckkdaupw5QzjCW3eYuJV7xbKOt8lmuoyHQT1GUTGDtmIwQjaOxpAC8=" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="completed keyexchange" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="completed challenge" Feb 08 11:33:02 zenith sudo[1526]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=info msg="authenticated AP" username="12*****01" Feb 08 11:33:02 zenith mpd[1783]: 2026-02-08T11:33:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 11:33:02 zenith systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 11:33:02 zenith sudo[1668]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:02 zenith sudo[1744]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:02 zenith sudo[1679]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:02 zenith volumio[1435]: info: Completed starting Core Plugins Feb 08 11:33:02 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:33:02 zenith volumio[1435]: info: ----- MyVolumio plugins startup ---- Feb 08 11:33:02 zenith volumio[1435]: info: ------------------------------------------- Feb 08 11:33:02 zenith volumio[1435]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 11:33:02 zenith volumio[1435]: error: MPD error: The expression evaluated to a falsy value: Feb 08 11:33:02 zenith volumio[1435]: assert.ok(self.idling) Feb 08 11:33:02 zenith volumio[1435]: error: The expression evaluated to a falsy value: Feb 08 11:33:02 zenith volumio[1435]: assert.ok(self.idling) Feb 08 11:33:02 zenith volumio[1435]: error: MPD error: The expression evaluated to a falsy value: Feb 08 11:33:02 zenith volumio[1435]: assert.ok(self.idling) Feb 08 11:33:02 zenith volumio[1435]: error: The expression evaluated to a falsy value: Feb 08 11:33:02 zenith volumio[1435]: assert.ok(self.idling) Feb 08 11:33:02 zenith volumio[1435]: info: MPD running with PID1783 Feb 08 11:33:02 zenith volumio[1435]: ,establishing connection Feb 08 11:33:02 zenith volumio[1435]: error: updateQueue error: null Feb 08 11:33:02 zenith volumio[1435]: error: updateQueue error: null Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=info msg="authenticated Login5" username="12*****01" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="initializing zeroconf session" username="12*****01" Feb 08 11:33:02 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 11:33:02 zenith volumio[1435]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Feb 08 11:33:02 zenith volumio[1435]: info: FusionDsp loaded Feb 08 11:33:02 zenith volumio[1435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 11:33:02 zenith sudo[1816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 08 11:33:02 zenith sudo[1816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:02 zenith volumio[1435]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 08 11:33:02 zenith volumio[1435]: info: Adding Signal Path Element [object Object] Feb 08 11:33:02 zenith volumio[1435]: info: Adding fusiondspeq DSP Signal Path Element Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="dealer connection opened" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=trace msg="starting accesspoint recv loop" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=trace msg="starting dealer recv loop" Feb 08 11:33:02 zenith volumio[1435]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=trace msg="received accesspoint ping" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 08 11:33:02 zenith volumio[1435]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="received connection id: YWJkMmJhODctY2I5...Qzk0Mjc0NDJDRA==" Feb 08 11:33:02 zenith systemd[1]: /lib/systemd/system/fusiondsp.service:11: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 11:33:02 zenith systemd[1]: /lib/systemd/system/fusiondsp.service:12: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 11:33:02 zenith systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Feb 08 11:33:02 zenith sudo[1816]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=trace msg="received accesspoint pong ack" Feb 08 11:33:02 zenith go-librespot[1737]: time="2026-02-08T11:33:02-05:00" level=debug msg="put connect state because NEW_DEVICE" Feb 08 11:33:03 zenith volumio[1818]: Traceback (most recent call last): Feb 08 11:33:03 zenith volumio[1818]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Feb 08 11:33:03 zenith volumio[1818]: from camilladsp import CamillaConnection Feb 08 11:33:03 zenith volumio[1818]: ModuleNotFoundError: No module named 'camilladsp' Feb 08 11:33:03 zenith systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 08 11:33:03 zenith systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 08 11:33:03 zenith volumio[1435]: info: FusionDsp - eq1: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 25 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 2.5 Feb 08 11:33:03 zenith volumio[1435]: eq2: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 40 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 3.5 Feb 08 11:33:03 zenith volumio[1435]: eq3: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 63 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 3.5 Feb 08 11:33:03 zenith volumio[1435]: eq4: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 100 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 2.5 Feb 08 11:33:03 zenith volumio[1435]: eq5: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 160 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 1 Feb 08 11:33:03 zenith volumio[1435]: eq6: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 250 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 0 Feb 08 11:33:03 zenith volumio[1435]: eq7: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 400 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 1 Feb 08 11:33:03 zenith volumio[1435]: eq8: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 630 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 3.5 Feb 08 11:33:03 zenith volumio[1435]: eq9: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 1000 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 4.5 Feb 08 11:33:03 zenith volumio[1435]: eq10: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 1600 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 4.5 Feb 08 11:33:03 zenith volumio[1435]: eq11: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 2500 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 4 Feb 08 11:33:03 zenith volumio[1435]: eq12: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 4000 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 2.5 Feb 08 11:33:03 zenith volumio[1435]: eq13: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 6300 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 0 Feb 08 11:33:03 zenith volumio[1435]: eq14: Feb 08 11:33:03 zenith volumio[1435]: type: Biquad Feb 08 11:33:03 zenith volumio[1435]: parameters: Feb 08 11:33:03 zenith volumio[1435]: type: Peaking Feb 08 11:33:03 zenith volumio[1435]: freq: 10000 Feb 08 11:33:03 zenith volumio[1435]: q: 1.85 Feb 08 11:33:03 zenith volumio[1435]: gain: 0.5 Feb 08 11:33:03 zenith volumio[1435]: error: FusionDsp - WebSocket error: [object Object] Feb 08 11:33:03 zenith volumio[1435]: info: go-librespot daemon successfully initialized Feb 08 11:33:04 zenith volumio[1435]: info: CoreCommandRouter::volumioGetState Feb 08 11:33:04 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:04 zenith volumio[1435]: info: Listing playlists Feb 08 11:33:04 zenith volumio[1435]: info: Listing playlists Feb 08 11:33:04 zenith ntpd[1204]: CLOCK: time stepped by -0.141068 Feb 08 11:33:04 zenith ntpd[1204]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 08 11:33:04 zenith volumio-remote-updater[1010]: [2026-02-08 11:33:04] [connect] Successful connection Feb 08 11:33:04 zenith volumio-remote-updater[1010]: [2026-02-08 11:33:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770568384 101 Feb 08 11:33:04 zenith volumio[1435]: 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: 3 Feb 08 11:33:05 zenith systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Feb 08 11:33:05 zenith systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Feb 08 11:33:05 zenith systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Feb 08 11:33:05 zenith sudo[1821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 11:33:05 zenith sudo[1821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:05 zenith sudo[1823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 11:33:05 zenith sudo[1823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:05 zenith sudo[1827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 11:33:05 zenith sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 11:33:05 zenith volumio[1819]: Traceback (most recent call last): Feb 08 11:33:05 zenith volumio[1819]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Feb 08 11:33:05 zenith volumio[1819]: from camilladsp import CamillaConnection Feb 08 11:33:05 zenith volumio[1819]: ModuleNotFoundError: No module named 'camilladsp' Feb 08 11:33:05 zenith systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 11:33:05 zenith sudo[1823]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:05 zenith sudo[1821]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:05 zenith mpd_monitor.sh[1829]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 11:33:05 zenith volumio[1435]: info: Successfully started MPD Monitor Feb 08 11:33:05 zenith volumio[1435]: info: Successfully started MPD Monitor Feb 08 11:33:05 zenith sudo[1827]: pam_unix(sudo:session): session closed for user root Feb 08 11:33:05 zenith volumio[1435]: info: Successfully started MPD Monitor Feb 08 11:33:05 zenith systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 08 11:33:05 zenith systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="handling transfer player command from eecca9e381069be0d3620265fc6ae6fadbcbe23a" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EIgM5iO4YYPuD" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EIgM5iO4YYPuD" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="loading track (paused: false, position: 101160ms)" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=trace msg="emitting websocket event: will_play" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2208" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="selected format OGG_VORBIS_320 (b7e7ba0a3324ec1c3c2dedd09fc47cebcf61262e)" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=debug msg="requested aes key for file b7e7ba0a3324ec1c3c2dedd09fc47cebcf61262e, gid: 4at0npP4QkdIUulFZIsYft" Feb 08 11:33:05 zenith go-librespot[1737]: time="2026-02-08T11:33:05-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched first chunk of 24, total size is 12398768 bytes" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith volumio[1435]: info: Initializing connection to go-librespot Websocket Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 10/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="new websocket client" Feb 08 11:33:06 zenith volumio[1435]: info: Connection to go-librespot Websocket established Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 8/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 9/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 7/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=trace msg="seek to 101160ms (diff: 100ms, samples: 4461156, bytes: 4151918)" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="created new output device" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=info msg="loaded track \"Tunnel\" (paused: false, position: 101160ms, duration: 310573ms, prefetched: false)" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=trace msg="scheduling prefetch in 179s" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=trace msg="emitting websocket event: metadata" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=trace msg="emitting websocket event: active" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="sending successful reply for dealer request" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 08 11:33:06 zenith volumio[1435]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4at0npP4QkdIUulFZIsYft","name":"Tunnel","artist_names":["Polo \u0026 Pan","Channel Tres"],"album_name":"Cyclorama","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02aaf57ed3bbc2e6df325c1cd2","position":101160,"duration":310573,"release_date":"year:2021 month:6 day:25","track_number":5,"disc_number":1}} Feb 08 11:33:06 zenith volumio[1435]: SPOTIFY: received: {"type":"active","data":null} Feb 08 11:33:06 zenith volumio[1435]: info: Aligning Spotify Volume to Volumio Volume Feb 08 11:33:06 zenith volumio[1435]: info: CoreCommandRouter::volumioGetState Feb 08 11:33:06 zenith volumio[1435]: info: CorePlayQueue::getTrack 0 Feb 08 11:33:06 zenith volumio[1435]: info: Setting Spotify Volume from Volumio: 32 Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=trace msg="emitting websocket event: playing" Feb 08 11:33:06 zenith volumio[1435]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1EIgM5iO4YYPuD","uri":"spotify:track:4at0npP4QkdIUulFZIsYft","resume":false,"play_origin":"playlist/ondemand"}} Feb 08 11:33:06 zenith volumio[1435]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 11:33:06 zenith volumio[1435]: TypeError: Cannot read properties of undefined (reading 'service') Feb 08 11:33:06 zenith volumio[1435]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Feb 08 11:33:06 zenith volumio[1435]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Feb 08 11:33:06 zenith volumio[1435]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 08 11:33:06 zenith volumio[1435]: at WebSocket.emit (node:events:514:28) Feb 08 11:33:06 zenith volumio[1435]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 08 11:33:06 zenith volumio[1435]: at Receiver.emit (node:events:514:28) Feb 08 11:33:06 zenith volumio[1435]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 08 11:33:06 zenith volumio[1435]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 08 11:33:06 zenith volumio[1435]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 08 11:33:06 zenith volumio[1435]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 08 11:33:06 zenith volumio[1435]: at writeOrBuffer (node:internal/streams/writable:399:12) Feb 08 11:33:06 zenith volumio[1435]: at _write (node:internal/streams/writable:340:10) Feb 08 11:33:06 zenith volumio[1435]: at Writable.write (node:internal/streams/writable:344:10) Feb 08 11:33:06 zenith volumio[1435]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 08 11:33:06 zenith volumio[1435]: at Socket.emit (node:events:514:28) Feb 08 11:33:06 zenith volumio[1435]: at addChunk (node:internal/streams/readable:343:12) Feb 08 11:33:06 zenith volumio[1435]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 11:33:06 zenith go-librespot[1737]: time="2026-02-08T11:33:06-05:00" level=debug msg="fetched chunk 11/23, size: 524288" uri="spotify:track:4at0npP4QkdIUulFZIsYft" Feb 08 11:33:06 zenith sudo[1851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 11:32' Feb 08 11:33:06 zenith sudo[1851]: 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"