Dec 29 18:05:20 volumio4 kernel: Booting Linux on physical CPU 0x0 Dec 29 18:05:20 volumio4 kernel: Linux version 6.12.47-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1904 SMP Mon Sep 15 13:08:10 BST 2025 Dec 29 18:05:20 volumio4 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Dec 29 18:05:20 volumio4 kernel: CPU: div instructions available: patching division code Dec 29 18:05:20 volumio4 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Dec 29 18:05:20 volumio4 kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 Dec 29 18:05:20 volumio4 kernel: random: crng init done Dec 29 18:05:20 volumio4 kernel: Memory policy: Data cache writealloc Dec 29 18:05:20 volumio4 kernel: Reserved memory: created CMA memory pool at 0x0000000028c00000, size 64 MiB Dec 29 18:05:20 volumio4 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 29 18:05:20 volumio4 kernel: OF: reserved mem: 0x0000000028c00000..0x000000002cbfffff (65536 KiB) map reusable linux,cma Dec 29 18:05:20 volumio4 kernel: Zone ranges: Dec 29 18:05:20 volumio4 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Dec 29 18:05:20 volumio4 kernel: Normal empty Dec 29 18:05:20 volumio4 kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Dec 29 18:05:20 volumio4 kernel: Movable zone start for each node Dec 29 18:05:20 volumio4 kernel: Early memory node ranges Dec 29 18:05:20 volumio4 kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Dec 29 18:05:20 volumio4 kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Dec 29 18:05:20 volumio4 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Dec 29 18:05:20 volumio4 kernel: OF: reserved mem: 0x000000003ef73360..0x000000003ef73395 (0 KiB) nomap non-reusable nvram@0 Dec 29 18:05:20 volumio4 kernel: OF: reserved mem: 0x000000003ef73120..0x000000003ef7331f (0 KiB) nomap non-reusable nvram@1 Dec 29 18:05:20 volumio4 kernel: percpu: Embedded 19 pages/cpu s45772 r8192 d23860 u77824 Dec 29 18:05:20 volumio4 kernel: pcpu-alloc: s45772 r8192 d23860 u77824 alloc=19*4096 Dec 29 18:05:20 volumio4 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 29 18:05:20 volumio4 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:43:FA:FE vc_mem.mem_base=0x3ec00000 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=ttyAMA0,115200 console=tty1 imgpart=UUID=e35b38c1-e778-4d8e-862d-9aabf7e72075 imgfile=/volumio_current.sqsh bootpart=UUID=2CA4-156B datapart=UUID=56b97db1-b6be-4e20-94da-f9e169f83497 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 Dec 29 18:05:20 volumio4 kernel: cgroup: Disabling memory control group subsystem Dec 29 18:05:20 volumio4 kernel: Unknown kernel command line parameters "splash nodebug numa_policy=interleave numa=fake=2 imgpart=UUID=e35b38c1-e778-4d8e-862d-9aabf7e72075 imgfile=/volumio_current.sqsh bootpart=UUID=2CA4-156B datapart=UUID=56b97db1-b6be-4e20-94da-f9e169f83497 uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Dec 29 18:05:20 volumio4 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Dec 29 18:05:20 volumio4 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 29 18:05:20 volumio4 kernel: Built 1 zonelists, mobility grouping on. Total pages: 999424 Dec 29 18:05:20 volumio4 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 29 18:05:20 volumio4 kernel: software IO TLB: area num 4. Dec 29 18:05:20 volumio4 kernel: software IO TLB: mapped [mem 0x0000000022890000-0x0000000026890000] (64MB) Dec 29 18:05:20 volumio4 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 29 18:05:20 volumio4 kernel: ftrace: allocating 40021 entries in 118 pages Dec 29 18:05:20 volumio4 kernel: ftrace: allocated 118 pages with 5 groups Dec 29 18:05:20 volumio4 kernel: rcu: Hierarchical RCU implementation. Dec 29 18:05:20 volumio4 kernel: Rude variant of Tasks RCU enabled. Dec 29 18:05:20 volumio4 kernel: Tracing variant of Tasks RCU enabled. Dec 29 18:05:20 volumio4 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Dec 29 18:05:20 volumio4 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 29 18:05:20 volumio4 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 29 18:05:20 volumio4 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Dec 29 18:05:20 volumio4 kernel: GIC: Using split EOI/Deactivate mode Dec 29 18:05:20 volumio4 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 29 18:05:20 volumio4 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 29 18:05:20 volumio4 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 29 18:05:20 volumio4 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 29 18:05:20 volumio4 kernel: Switching to timer-based delay loop, resolution 18ns Dec 29 18:05:20 volumio4 kernel: Console: colour dummy device 80x30 Dec 29 18:05:20 volumio4 kernel: printk: legacy console [tty1] enabled Dec 29 18:05:20 volumio4 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Dec 29 18:05:20 volumio4 kernel: CPU: Testing write buffer coherency: ok Dec 29 18:05:20 volumio4 kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Dec 29 18:05:20 volumio4 kernel: pid_max: default: 32768 minimum: 301 Dec 29 18:05:20 volumio4 kernel: LSM: initializing lsm=capability Dec 29 18:05:20 volumio4 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 29 18:05:20 volumio4 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 29 18:05:20 volumio4 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Dec 29 18:05:20 volumio4 kernel: Setting up static identity map for 0x200000 - 0x20003c Dec 29 18:05:20 volumio4 kernel: rcu: Hierarchical SRCU implementation. Dec 29 18:05:20 volumio4 kernel: rcu: Max phase no-delay instances is 1000. Dec 29 18:05:20 volumio4 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Dec 29 18:05:20 volumio4 kernel: smp: Bringing up secondary CPUs ... Dec 29 18:05:20 volumio4 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Dec 29 18:05:20 volumio4 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Dec 29 18:05:20 volumio4 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Dec 29 18:05:20 volumio4 kernel: smp: Brought up 1 node, 4 CPUs Dec 29 18:05:20 volumio4 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Dec 29 18:05:20 volumio4 kernel: CPU: All CPU(s) started in HYP mode. Dec 29 18:05:20 volumio4 kernel: CPU: Virtualization extensions available. Dec 29 18:05:20 volumio4 kernel: Memory: 3767784K/3997696K available (12288K kernel code, 1648K rwdata, 3848K rodata, 2048K init, 402K bss, 160952K reserved, 65536K cma-reserved, 3211264K highmem) Dec 29 18:05:20 volumio4 kernel: devtmpfs: initialized Dec 29 18:05:20 volumio4 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Dec 29 18:05:20 volumio4 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Dec 29 18:05:20 volumio4 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 29 18:05:20 volumio4 kernel: pinctrl core: initialized pinctrl subsystem Dec 29 18:05:20 volumio4 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 29 18:05:20 volumio4 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Dec 29 18:05:20 volumio4 kernel: audit: initializing netlink subsys (disabled) Dec 29 18:05:20 volumio4 kernel: audit: type=2000 audit(0.010:1): state=initialized audit_enabled=0 res=1 Dec 29 18:05:20 volumio4 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 29 18:05:20 volumio4 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Dec 29 18:05:20 volumio4 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Dec 29 18:05:20 volumio4 kernel: Serial: AMBA PL011 UART driver Dec 29 18:05:20 volumio4 kernel: /soc/interrupt-controller@40041000: Fixed dependency cycle(s) with /soc/interrupt-controller@40041000 Dec 29 18:05:20 volumio4 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Dec 29 18:05:20 volumio4 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2025-08-20T17:02:31, variant start Dec 29 18:05:20 volumio4 kernel: raspberrypi-firmware soc:firmware: Firmware hash is cd866525580337c0aee4b25880e1f5f9f674fb24 Dec 29 18:05:20 volumio4 kernel: /scb/pcie@7d500000: Fixed dependency cycle(s) with /scb/pcie@7d500000 Dec 29 18:05:20 volumio4 kernel: /scb/pcie@7d500000: Fixed dependency cycle(s) with /scb/pcie@7d500000 Dec 29 18:05:20 volumio4 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 29 18:05:20 volumio4 kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 Dec 29 18:05:20 volumio4 kernel: SCSI subsystem initialized Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver usbfs Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver hub Dec 29 18:05:20 volumio4 kernel: usbcore: registered new device driver usb Dec 29 18:05:20 volumio4 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 29 18:05:20 volumio4 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 29 18:05:20 volumio4 kernel: PTP clock support registered Dec 29 18:05:20 volumio4 kernel: vgaarb: loaded Dec 29 18:05:20 volumio4 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 29 18:05:20 volumio4 kernel: VFS: Disk quotas dquot_6.6.0 Dec 29 18:05:20 volumio4 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Dec 29 18:05:20 volumio4 kernel: NET: Registered PF_INET protocol family Dec 29 18:05:20 volumio4 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Dec 29 18:05:20 volumio4 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Dec 29 18:05:20 volumio4 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 29 18:05:20 volumio4 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Dec 29 18:05:20 volumio4 kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Dec 29 18:05:20 volumio4 kernel: TCP: Hash tables configured (established 8192 bind 8192) Dec 29 18:05:20 volumio4 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 29 18:05:20 volumio4 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 29 18:05:20 volumio4 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 29 18:05:20 volumio4 kernel: RPC: Registered named UNIX socket transport module. Dec 29 18:05:20 volumio4 kernel: RPC: Registered udp transport module. Dec 29 18:05:20 volumio4 kernel: RPC: Registered tcp transport module. Dec 29 18:05:20 volumio4 kernel: RPC: Registered tcp-with-tls transport module. Dec 29 18:05:20 volumio4 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 29 18:05:20 volumio4 kernel: PCI: CLS 0 bytes, default 64 Dec 29 18:05:20 volumio4 kernel: Trying to unpack rootfs image as initramfs... Dec 29 18:05:20 volumio4 kernel: Initialise system trusted keyrings Dec 29 18:05:20 volumio4 kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Dec 29 18:05:20 volumio4 kernel: NFS: Registering the id_resolver key type Dec 29 18:05:20 volumio4 kernel: Key type id_resolver registered Dec 29 18:05:20 volumio4 kernel: Key type id_legacy registered Dec 29 18:05:20 volumio4 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 29 18:05:20 volumio4 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 29 18:05:20 volumio4 kernel: Key type asymmetric registered Dec 29 18:05:20 volumio4 kernel: Asymmetric key parser 'x509' registered Dec 29 18:05:20 volumio4 kernel: bounce: pool size: 64 pages Dec 29 18:05:20 volumio4 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 29 18:05:20 volumio4 kernel: io scheduler mq-deadline registered Dec 29 18:05:20 volumio4 kernel: io scheduler kyber registered Dec 29 18:05:20 volumio4 kernel: io scheduler bfq registered Dec 29 18:05:20 volumio4 kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Dec 29 18:05:20 volumio4 kernel: Freeing initrd memory: 35756K Dec 29 18:05:20 volumio4 kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 PCIe Root Port Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: PCI bridge to [bus 00] Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: bridge window [mem 0x80000000-0xbfffffff] Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 29 18:05:20 volumio4 kernel: PCI: bus0: Fast back to back transfers disabled Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: clkreq-mode set to default Dec 29 18:05:20 volumio4 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Dec 29 18:05:20 volumio4 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 PCIe Endpoint Dec 29 18:05:20 volumio4 kernel: pci 0000:01:00.0: BAR 0 [mem 0x00000000-0x00000fff 64bit] Dec 29 18:05:20 volumio4 kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Dec 29 18:05:20 volumio4 kernel: PCI: bus1: Fast back to back transfers disabled Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]: assigned Dec 29 18:05:20 volumio4 kernel: pci 0000:01:00.0: BAR 0 [mem 0x600000000-0x600000fff 64bit]: assigned Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:00: resource 4 [mem 0x600000000-0x63fffffff] Dec 29 18:05:20 volumio4 kernel: pci_bus 0000:01: resource 1 [mem 0x600000000-0x6000fffff] Dec 29 18:05:20 volumio4 kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 29 18:05:20 volumio4 kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 29 18:05:20 volumio4 kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Dec 29 18:05:20 volumio4 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 41 Dec 29 18:05:20 volumio4 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Dec 29 18:05:20 volumio4 kernel: Console: switching to colour frame buffer device 80x30 Dec 29 18:05:20 volumio4 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Dec 29 18:05:20 volumio4 kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Dec 29 18:05:20 volumio4 kernel: iproc-rng200 fe104000.rng: hwrng registered Dec 29 18:05:20 volumio4 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Dec 29 18:05:20 volumio4 kernel: brd: module loaded Dec 29 18:05:20 volumio4 kernel: loop: module loaded Dec 29 18:05:20 volumio4 kernel: Loading iSCSI transport class v2.0-870. Dec 29 18:05:20 volumio4 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Dec 29 18:05:20 volumio4 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver lan78xx Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver smsc95xx Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890 Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Dec 29 18:05:20 volumio4 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Dec 29 18:05:20 volumio4 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Dec 29 18:05:20 volumio4 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 29 18:05:20 volumio4 kernel: usb usb1: Product: xHCI Host Controller Dec 29 18:05:20 volumio4 kernel: usb usb1: Manufacturer: Linux 6.12.47-v7l+ xhci-hcd Dec 29 18:05:20 volumio4 kernel: usb usb1: SerialNumber: 0000:01:00.0 Dec 29 18:05:20 volumio4 kernel: hub 1-0:1.0: USB hub found Dec 29 18:05:20 volumio4 kernel: hub 1-0:1.0: 1 port detected Dec 29 18:05:20 volumio4 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Dec 29 18:05:20 volumio4 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 29 18:05:20 volumio4 kernel: usb usb2: Product: xHCI Host Controller Dec 29 18:05:20 volumio4 kernel: usb usb2: Manufacturer: Linux 6.12.47-v7l+ xhci-hcd Dec 29 18:05:20 volumio4 kernel: usb usb2: SerialNumber: 0000:01:00.0 Dec 29 18:05:20 volumio4 kernel: hub 2-0:1.0: USB hub found Dec 29 18:05:20 volumio4 kernel: hub 2-0:1.0: 4 ports detected Dec 29 18:05:20 volumio4 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 29 18:05:20 volumio4 kernel: dwc_otg: FIQ enabled Dec 29 18:05:20 volumio4 kernel: dwc_otg: NAK holdoff enabled Dec 29 18:05:20 volumio4 kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 29 18:05:20 volumio4 kernel: Module dwc_common_port init Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver uas Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver usb-storage Dec 29 18:05:20 volumio4 kernel: mousedev: PS/2 mouse device common for all mice Dec 29 18:05:20 volumio4 kernel: sdhci: Secure Digital Host Controller Interface driver Dec 29 18:05:20 volumio4 kernel: sdhci: Copyright(c) Pierre Ossman Dec 29 18:05:20 volumio4 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 29 18:05:20 volumio4 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 29 18:05:20 volumio4 kernel: usbcore: registered new interface driver usbhid Dec 29 18:05:20 volumio4 kernel: usbhid: USB HID core driver Dec 29 18:05:20 volumio4 kernel: Initializing XFRM netlink socket Dec 29 18:05:20 volumio4 kernel: NET: Registered PF_PACKET protocol family Dec 29 18:05:20 volumio4 kernel: Key type dns_resolver registered Dec 29 18:05:20 volumio4 kernel: Registering SWP/SWPB emulation handler Dec 29 18:05:20 volumio4 kernel: registered taskstats version 1 Dec 29 18:05:20 volumio4 kernel: Loading compiled-in X.509 certificates Dec 29 18:05:20 volumio4 kernel: Key type .fscrypt registered Dec 29 18:05:20 volumio4 kernel: Key type fscrypt-provisioning registered Dec 29 18:05:20 volumio4 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 29 18:05:20 volumio4 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 29 18:05:20 volumio4 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Dec 29 18:05:20 volumio4 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Dec 29 18:05:20 volumio4 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Dec 29 18:05:20 volumio4 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Dec 29 18:05:20 volumio4 kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev3 Dec 29 18:05:20 volumio4 kernel: printk: legacy console [ttyAMA0] enabled Dec 29 18:05:20 volumio4 kernel: of_cfs_init Dec 29 18:05:20 volumio4 kernel: of_cfs_init: OK Dec 29 18:05:20 volumio4 kernel: clk: Disabling unused clocks Dec 29 18:05:20 volumio4 kernel: PM: genpd: Disabling unused power domains Dec 29 18:05:20 volumio4 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Dec 29 18:05:20 volumio4 kernel: Freeing unused kernel image (initmem) memory: 2048K Dec 29 18:05:20 volumio4 kernel: Run /init as init process Dec 29 18:05:20 volumio4 kernel: with arguments: Dec 29 18:05:20 volumio4 kernel: /init Dec 29 18:05:20 volumio4 kernel: splash Dec 29 18:05:20 volumio4 kernel: nodebug Dec 29 18:05:20 volumio4 kernel: with environment: Dec 29 18:05:20 volumio4 kernel: HOME=/ Dec 29 18:05:20 volumio4 kernel: TERM=linux Dec 29 18:05:20 volumio4 kernel: numa_policy=interleave Dec 29 18:05:20 volumio4 kernel: numa=fake=2 Dec 29 18:05:20 volumio4 kernel: imgpart=UUID=e35b38c1-e778-4d8e-862d-9aabf7e72075 Dec 29 18:05:20 volumio4 kernel: imgfile=/volumio_current.sqsh Dec 29 18:05:20 volumio4 kernel: bootpart=UUID=2CA4-156B Dec 29 18:05:20 volumio4 kernel: datapart=UUID=56b97db1-b6be-4e20-94da-f9e169f83497 Dec 29 18:05:20 volumio4 kernel: uuidconfig=cmdline.txt Dec 29 18:05:20 volumio4 kernel: pcie_aspm=off Dec 29 18:05:20 volumio4 kernel: bootdelay=7 Dec 29 18:05:20 volumio4 kernel: use_kmsg=no Dec 29 18:05:20 volumio4 kernel: mmc1: new high speed SDIO card at address 0001 Dec 29 18:05:20 volumio4 kernel: mmc0: new ultra high speed DDR50 SDHC card at address 1234 Dec 29 18:05:20 volumio4 kernel: mmcblk0: mmc0:1234 SA32G 29.0 GiB Dec 29 18:05:20 volumio4 kernel: mmcblk0: p1 p2 p3 Dec 29 18:05:20 volumio4 kernel: mmcblk0: mmc0:1234 SA32G 29.0 GiB Dec 29 18:05:20 volumio4 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 29 18:05:20 volumio4 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Dec 29 18:05:20 volumio4 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 29 18:05:20 volumio4 kernel: usb 1-1: Product: USB2.0 Hub Dec 29 18:05:20 volumio4 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 29 18:05:20 volumio4 kernel: hub 1-1:1.0: USB hub found Dec 29 18:05:20 volumio4 kernel: hub 1-1:1.0: 4 ports detected Dec 29 18:05:20 volumio4 kernel: bcm2835-audio bcm2835-audio: card created with 4 channels Dec 29 18:05:20 volumio4 kernel: bcm2835-audio bcm2835-audio: card created with 4 channels Dec 29 18:05:20 volumio4 kernel: fuse: init (API version 7.41) Dec 29 18:05:20 volumio4 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 29 18:05:20 volumio4 kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340 Dec 29 18:05:20 volumio4 kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r Dec 29 18:05:20 volumio4 kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18 Dec 29 18:05:20 volumio4 kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18 Dec 29 18:05:20 volumio4 kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green Dec 29 18:05:20 volumio4 kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v Dec 29 18:05:20 volumio4 kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13 Dec 29 18:05:20 volumio4 kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:20 volumio4 kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d Dec 29 18:05:20 volumio4 kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 29 18:05:20 volumio4 kernel: EXT4-fs (mmcblk0p2): mounted filesystem e35b38c1-e778-4d8e-862d-9aabf7e72075 r/w with ordered data mode. Quota mode: none. Dec 29 18:05:20 volumio4 kernel: loop0: detected capacity change from 0 to 1422424 Dec 29 18:05:20 volumio4 kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 29 18:05:20 volumio4 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 56b97db1-b6be-4e20-94da-f9e169f83497 r/w with ordered data mode. Quota mode: none. Dec 29 18:05:20 volumio4 systemd[1]: System time before build time, advancing clock. Dec 29 18:05:20 volumio4 kernel: NET: Registered PF_INET6 protocol family Dec 29 18:05:20 volumio4 kernel: Segment Routing with IPv6 Dec 29 18:05:20 volumio4 kernel: In-situ OAM (IOAM) with IPv6 Dec 29 18:05:20 volumio4 systemd[1]: systemd 252.39-1~deb12u1+rpi1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Dec 29 18:05:20 volumio4 systemd[1]: Detected architecture arm. Dec 29 18:05:20 volumio4 systemd[1]: Hostname set to . Dec 29 18:05:20 volumio4 systemd[1]: Queued start job for default target graphical.target. Dec 29 18:05:20 volumio4 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 29 18:05:20 volumio4 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 29 18:05:20 volumio4 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 29 18:05:20 volumio4 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 29 18:05:20 volumio4 systemd[1]: Created slice user.slice - User and Session Slice. Dec 29 18:05:20 volumio4 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 29 18:05:20 volumio4 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 29 18:05:20 volumio4 systemd[1]: Expecting device dev-disk-by\x2duuid-2CA4\x2d156B.device - /dev/disk/by-uuid/2CA4-156B... Dec 29 18:05:20 volumio4 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Dec 29 18:05:20 volumio4 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 29 18:05:20 volumio4 systemd[1]: Reached target slices.target - Slice Units. Dec 29 18:05:20 volumio4 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 29 18:05:20 volumio4 systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 29 18:05:20 volumio4 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 29 18:05:20 volumio4 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 29 18:05:20 volumio4 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 29 18:05:20 volumio4 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 29 18:05:20 volumio4 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 29 18:05:20 volumio4 systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Dec 29 18:05:20 volumio4 systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 29 18:05:20 volumio4 systemd[1]: Reached target swap.target - Swaps. Dec 29 18:05:20 volumio4 systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Dec 29 18:05:20 volumio4 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 29 18:05:20 volumio4 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 29 18:05:20 volumio4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 29 18:05:20 volumio4 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 29 18:05:20 volumio4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 29 18:05:20 volumio4 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 29 18:05:20 volumio4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 29 18:05:20 volumio4 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 29 18:05:20 volumio4 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 29 18:05:20 volumio4 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 29 18:05:20 volumio4 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 29 18:05:20 volumio4 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 29 18:05:20 volumio4 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 29 18:05:20 volumio4 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 29 18:05:20 volumio4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 29 18:05:20 volumio4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 29 18:05:20 volumio4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 29 18:05:20 volumio4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 29 18:05:20 volumio4 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 29 18:05:20 volumio4 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 29 18:05:20 volumio4 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 29 18:05:20 volumio4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 29 18:05:20 volumio4 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 29 18:05:20 volumio4 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 29 18:05:20 volumio4 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 29 18:05:20 volumio4 systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Dec 29 18:05:20 volumio4 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 29 18:05:20 volumio4 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 29 18:05:20 volumio4 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 29 18:05:20 volumio4 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 29 18:05:20 volumio4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 29 18:05:20 volumio4 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 29 18:05:20 volumio4 systemd[1]: dynamicswap.service: Deactivated successfully. Dec 29 18:05:20 volumio4 kernel: i2c_dev: i2c /dev entries driver Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 29 18:05:20 volumio4 systemd-journald[445]: Journal started Dec 29 18:05:20 volumio4 systemd-journald[445]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.7M, max 30.0M, 26.2M free. Dec 29 18:05:20 volumio4 systemd-modules-load[447]: Inserted module 'i2c_dev' Dec 29 18:05:20 volumio4 fake-hwclock[454]: Mon Dec 29 09:05:20 UTC 2025 Dec 29 18:05:20 volumio4 systemd[1]: Started systemd-journald.service - Journal Service. Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 29 18:05:20 volumio4 systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 29 18:05:20 volumio4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 29 18:05:20 volumio4 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 29 18:05:20 volumio4 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 29 18:05:20 volumio4 systemd-udevd[473]: Using default interface naming scheme 'v252'. Dec 29 18:05:20 volumio4 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 29 18:05:20 volumio4 (udev-worker)[494]: Network interface NamePolicy= disabled on kernel command line. Dec 29 18:05:20 volumio4 systemd[1]: Found device dev-ttyAMA0.device - /dev/ttyAMA0. Dec 29 18:05:20 volumio4 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 29 18:05:20 volumio4 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 29 18:05:20 volumio4 systemd[1]: Found device dev-disk-by\x2duuid-2CA4\x2d156B.device - /dev/disk/by-uuid/2CA4-156B. Dec 29 18:05:20 volumio4 systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-2CA4\x2d156B.service - File System Check on /dev/disk/by-uuid/2CA4-156B... Dec 29 18:05:20 volumio4 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 29 18:05:20 volumio4 systemd[1]: Mounting tmp.mount - /tmp... Dec 29 18:05:20 volumio4 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 29 18:05:21 volumio4 systemd[1]: Mounting var-log.mount - /var/log... Dec 29 18:05:21 volumio4 systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Dec 29 18:05:21 volumio4 systemd[1]: Mounted tmp.mount - /tmp. Dec 29 18:05:21 volumio4 systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Dec 29 18:05:21 volumio4 systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Dec 29 18:05:21 volumio4 systemd[1]: Mounted var-log.mount - /var/log. Dec 29 18:05:21 volumio4 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 29 18:05:21 volumio4 systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Dec 29 18:05:21 volumio4 systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Dec 29 18:05:21 volumio4 kernel: mc: Linux media interface: v0.10 Dec 29 18:05:21 volumio4 systemd-journald[445]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.7M, max 30.0M, 26.2M free. Dec 29 18:05:21 volumio4 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 29 18:05:21 volumio4 systemd-journald[445]: Received client request to flush runtime journal. Dec 29 18:05:21 volumio4 systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Dec 29 18:05:21 volumio4 kernel: videodev: Linux video capture interface: v2.00 Dec 29 18:05:21 volumio4 systemd-fsck[602]: fsck.fat 4.2 (2021-01-31) Dec 29 18:05:21 volumio4 systemd-fsck[602]: /dev/mmcblk0p1: 427 files, 23457/93500 clusters Dec 29 18:05:21 volumio4 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:21 volumio4 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 29 18:05:21 volumio4 kernel: [vc_sm_connected_init]: start Dec 29 18:05:21 volumio4 kernel: [vc_sm_connected_init]: installed successfully Dec 29 18:05:21 volumio4 systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-2CA4\x2d156B.service - File System Check on /dev/disk/by-uuid/2CA4-156B. Dec 29 18:05:21 volumio4 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:21 volumio4 systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Dec 29 18:05:21 volumio4 systemd[1]: Mounting boot.mount - /boot... Dec 29 18:05:21 volumio4 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 29 18:05:21 volumio4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 29 18:05:21 volumio4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 29 18:05:21 volumio4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 29 18:05:21 volumio4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 29 18:05:21 volumio4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 29 18:05:21 volumio4 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:21 volumio4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 29 18:05:21 volumio4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 29 18:05:21 volumio4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 29 18:05:21 volumio4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 29 18:05:21 volumio4 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 29 18:05:21 volumio4 systemd[1]: Mounted boot.mount - /boot. Dec 29 18:05:21 volumio4 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 29 18:05:21 volumio4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 29 18:05:21 volumio4 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 29 18:05:21 volumio4 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:21 volumio4 systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Dec 29 18:05:21 volumio4 systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Dec 29 18:05:21 volumio4 systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Dec 29 18:05:21 volumio4 systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Dec 29 18:05:21 volumio4 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 29 18:05:21 volumio4 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 29 18:05:21 volumio4 systemd[1]: Received SIGRTMIN+20 from PID 319 (plymouthd). Dec 29 18:05:21 volumio4 systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Dec 29 18:05:21 volumio4 systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Dec 29 18:05:21 volumio4 systemd[1]: Started ifup@eth0.service - ifup for eth0. Dec 29 18:05:21 volumio4 systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 635 (systemd-binfmt) Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 29 18:05:21 volumio4 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 29 18:05:21 volumio4 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 29 18:05:21 volumio4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 29 18:05:21 volumio4 systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Dec 29 18:05:21 volumio4 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 29 18:05:21 volumio4 systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Dec 29 18:05:21 volumio4 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 29 18:05:21 volumio4 systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Dec 29 18:05:21 volumio4 systemd[1]: Starting rpcbind.service - RPC bind portmap service... Dec 29 18:05:21 volumio4 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 29 18:05:21 volumio4 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 29 18:05:21 volumio4 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 29 18:05:21 volumio4 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 29 18:05:21 volumio4 kernel: rpi-hevc-dec feb00000.codec: Device registered as /dev/video19 Dec 29 18:05:21 volumio4 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 29 18:05:21 volumio4 systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 29 18:05:21 volumio4 sh[659]: command failed: No such device (-19) Dec 29 18:05:21 volumio4 systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Dec 29 18:05:21 volumio4 systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Dec 29 18:05:21 volumio4 systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Dec 29 18:05:21 volumio4 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 29 18:05:21 volumio4 systemd[1]: Reached target paths.target - Path Units. Dec 29 18:05:21 volumio4 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 29 18:05:21 volumio4 systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Dec 29 18:05:21 volumio4 sh[705]: dhcpcd-9.4.1 starting Dec 29 18:05:21 volumio4 dhcpcd[705]: dhcpcd-9.4.1 starting Dec 29 18:05:21 volumio4 sh[705]: DUID 00:01:00:01:30:c6:00:30:dc:a6:32:43:fa:fe Dec 29 18:05:21 volumio4 dhcpcd[710]: DUID 00:01:00:01:30:c6:00:30:dc:a6:32:43:fa:fe Dec 29 18:05:21 volumio4 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 29 18:05:21 volumio4 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 29 18:05:21 volumio4 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 29 18:05:21 volumio4 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 29 18:05:21 volumio4 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 29 18:05:21 volumio4 systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Dec 29 18:05:21 volumio4 systemd[1]: Started rpcbind.service - RPC bind portmap service. Dec 29 18:05:21 volumio4 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 29 18:05:21 volumio4 kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 29 18:05:21 volumio4 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 29 18:05:21 volumio4 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 29 18:05:21 volumio4 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 29 18:05:21 volumio4 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 29 18:05:21 volumio4 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 29 18:05:21 volumio4 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 29 18:05:21 volumio4 haveged[675]: haveged: command socket is listening at fd 3 Dec 29 18:05:21 volumio4 systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Dec 29 18:05:21 volumio4 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 29 18:05:21 volumio4 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 29 18:05:21 volumio4 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 29 18:05:21 volumio4 kernel: usbcore: registered new interface driver brcmfmac Dec 29 18:05:21 volumio4 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 29 18:05:21 volumio4 systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Dec 29 18:05:21 volumio4 systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Dec 29 18:05:21 volumio4 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 29 18:05:21 volumio4 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 29 18:05:22 volumio4 sh[705]: eth0: waiting for carrier Dec 29 18:05:21 volumio4 dhcpcd[710]: eth0: waiting for carrier Dec 29 18:05:22 volumio4 systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Dec 29 18:05:22 volumio4 systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Dec 29 18:05:22 volumio4 systemd[1]: Reached target rpc_pipefs.target. Dec 29 18:05:22 volumio4 systemd[1]: Starting networking.service - Raise network interfaces... Dec 29 18:05:22 volumio4 systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Dec 29 18:05:22 volumio4 systemd[1]: Reached target nfs-client.target - NFS client services. Dec 29 18:05:22 volumio4 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 29 18:05:22 volumio4 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 29 18:05:22 volumio4 systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Dec 29 18:05:22 volumio4 systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Dec 29 18:05:22 volumio4 systemd[1]: Reached target sysinit.target - System Initialization. Dec 29 18:05:22 volumio4 systemd[1]: Started apt-daily.timer - Daily apt download activities. Dec 29 18:05:22 volumio4 systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Dec 29 18:05:22 volumio4 systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Dec 29 18:05:22 volumio4 systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Dec 29 18:05:22 volumio4 systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Dec 29 18:05:22 volumio4 systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Dec 29 18:05:22 volumio4 systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Dec 29 18:05:22 volumio4 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 29 18:05:22 volumio4 systemd[1]: Reached target timers.target - Timer Units. Dec 29 18:05:22 volumio4 systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Dec 29 18:05:22 volumio4 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 29 18:05:22 volumio4 systemd[1]: Reached target sockets.target - Socket Units. Dec 29 18:05:22 volumio4 systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 29 18:05:22 volumio4 systemd[1]: Reached target basic.target - Basic System. Dec 29 18:05:22 volumio4 systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Dec 29 18:05:22 volumio4 systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Dec 29 18:05:22 volumio4 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 29 18:05:22 volumio4 ifup[737]: command failed: No such device (-19) Dec 29 18:05:22 volumio4 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 29 18:05:22 volumio4 systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Dec 29 18:05:22 volumio4 systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Dec 29 18:05:22 volumio4 systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Dec 29 18:05:22 volumio4 systemd[1]: Started iptables.service - Volumio Iptables Module. Dec 29 18:05:22 volumio4 systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Dec 29 18:05:22 volumio4 systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Dec 29 18:05:22 volumio4 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 29 18:05:22 volumio4 systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 29 18:05:22 volumio4 systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Dec 29 18:05:22 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 18:05:22 volumio4 systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 29 18:05:22 volumio4 avahi-daemon[741]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 29 18:05:22 volumio4 avahi-daemon[741]: Successfully dropped root privileges. Dec 29 18:05:22 volumio4 avahi-daemon[741]: avahi-daemon 0.8 starting up. Dec 29 18:05:22 volumio4 systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Dec 29 18:05:22 volumio4 systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Dec 29 18:05:22 volumio4 systemd[1]: Starting wireless.service - Wireless Services... Dec 29 18:05:22 volumio4 thd[757]: Unable to parse trigger line: Dec 29 18:05:22 volumio4 thd[757]: Unable to parse trigger line: Dec 29 18:05:22 volumio4 systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Dec 29 18:05:22 volumio4 systemd[1]: e2scrub_reap.service: Deactivated successfully. Dec 29 18:05:22 volumio4 systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Dec 29 18:05:22 volumio4 systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[763]: Volumio WiFi Soft Blocked check script Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[763]: Check if rfkill is available Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[763]: Check if rfkill listed devices are already unblocked Dec 29 18:05:22 volumio4 systemd[1]: th-udev-rebind.service: Deactivated successfully. Dec 29 18:05:22 volumio4 systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Dec 29 18:05:22 volumio4 ifup[780]: command failed: No such device (-19) Dec 29 18:05:22 volumio4 alsactl[787]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 29 18:05:22 volumio4 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 29 18:05:22 volumio4 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 Dec 29 18:05:22 volumio4 alsactl[787]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 29 18:05:22 volumio4 alsactl[787]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 29 18:05:22 volumio4 systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Dec 29 18:05:22 volumio4 systemd[1]: Reached target sound.target - Sound Card. Dec 29 18:05:22 volumio4 systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[763]: nl80211 modules are in the blocked state - unblocking Dec 29 18:05:22 volumio4 rfkill[799]: unblock set for all Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[801]: 0: phy0: Wireless LAN Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[801]: Soft blocked: no Dec 29 18:05:22 volumio4 volumio_rfkill_unblock.sh[801]: Hard blocked: no Dec 29 18:05:22 volumio4 systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Dec 29 18:05:22 volumio4 systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Dec 29 18:05:22 volumio4 (udev-worker)[496]: Network interface NamePolicy= disabled on kernel command line. Dec 29 18:05:22 volumio4 systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Dec 29 18:05:22 volumio4 systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Dec 29 18:05:22 volumio4 systemd-logind[755]: New seat seat0. Dec 29 18:05:22 volumio4 systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Dec 29 18:05:22 volumio4 systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Dec 29 18:05:22 volumio4 dhcpcd[802]: dhcpcd-9.4.1 starting Dec 29 18:05:22 volumio4 haveged[675]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Dec 29 18:05:22 volumio4 haveged[675]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 Dec 29 18:05:22 volumio4 haveged[675]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00248 Dec 29 18:05:22 volumio4 haveged[675]: haveged: fills: 0, generated: 0 Dec 29 18:05:22 volumio4 loadcpufreq[749]: Loading cpufreq kernel modules...done (none). Dec 29 18:05:22 volumio4 dhcpcd[847]: dev: loaded udev Dec 29 18:05:22 volumio4 systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Dec 29 18:05:22 volumio4 dhcpcd[847]: DUID 00:01:00:01:30:c6:00:30:dc:a6:32:43:fa:fe Dec 29 18:05:22 volumio4 systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Dec 29 18:05:22 volumio4 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 29 18:05:22 volumio4 systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Dec 29 18:05:22 volumio4 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 29 18:05:22 volumio4 avahi-daemon[741]: Successfully called chroot(). Dec 29 18:05:22 volumio4 avahi-daemon[741]: Successfully dropped remaining capabilities. Dec 29 18:05:22 volumio4 dbus-daemon[743]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 29 18:05:22 volumio4 dhcpcd[847]: eth0: waiting for carrier Dec 29 18:05:22 volumio4 dhcpcd[847]: dhcpcd_prestartinterface: Operation not possible due to RF-kill Dec 29 18:05:22 volumio4 dhcpcd[847]: wlan0: waiting for carrier Dec 29 18:05:22 volumio4 avahi-daemon[741]: Loading service file /services/volumio.service. Dec 29 18:05:22 volumio4 avahi-daemon[741]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 29 18:05:22 volumio4 avahi-daemon[741]: New relevant interface lo.IPv4 for mDNS. Dec 29 18:05:22 volumio4 avahi-daemon[741]: Network interface enumeration completed. Dec 29 18:05:22 volumio4 avahi-daemon[741]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 29 18:05:22 volumio4 cpufrequtils[867]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 29 18:05:22 volumio4 systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Dec 29 18:05:22 volumio4 systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Dec 29 18:05:22 volumio4 systemd[1]: iptables.service: Deactivated successfully. Dec 29 18:05:22 volumio4 systemd[1]: Started systemd-logind.service - User Login Management. Dec 29 18:05:22 volumio4 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 29 18:05:22 volumio4 ifup[731]: ifup: waiting for lock on /run/network/ifstate.wlan0 Dec 29 18:05:22 volumio4 sh[953]: wlan0=wlan0 Dec 29 18:05:23 volumio4 systemd[1]: Finished networking.service - Raise network interfaces. Dec 29 18:05:23 volumio4 volumio-remote-updater[759]: Volumio OTA Client: 1.7.0 Dec 29 18:05:23 volumio4 volumio-remote-updater[759]: Error: No active session Dec 29 18:05:23 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:23] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 29 18:05:23 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:23] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 29 18:05:23 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:23] [error] handle_connect error: Connection refused Dec 29 18:05:23 volumio4 wpa_supplicant[930]: Successfully initialized wpa_supplicant Dec 29 18:05:23 volumio4 systemd[1]: Started wpa_supplicant.service - WPA supplicant. Dec 29 18:05:23 volumio4 systemd[1]: Reached target network.target - Network. Dec 29 18:05:23 volumio4 systemd[1]: Reached target network-online.target - Network is Online. Dec 29 18:05:23 volumio4 upmpdcli[758]: Could not open config: /tmp/upmpdcli.conf Dec 29 18:05:23 volumio4 systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Dec 29 18:05:23 volumio4 systemd[1]: Starting nmbd.service - Samba NMB Daemon... Dec 29 18:05:23 volumio4 systemd[1]: Starting ntpsec.service - Network Time Service... Dec 29 18:05:23 volumio4 systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Dec 29 18:05:23 volumio4 systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Dec 29 18:05:23 volumio4 systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Dec 29 18:05:23 volumio4 sm-notify[1001]: Version 2.6.2 starting Dec 29 18:05:23 volumio4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 29 18:05:23 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 29 18:05:23 volumio4 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 29 18:05:23 volumio4 systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Dec 29 18:05:23 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 29 18:05:23 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 29 18:05:23 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 29 18:05:23 volumio4 systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Dec 29 18:05:23 volumio4 systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: BOOTLOADER: up to date Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: CURRENT: Thu Nov 27 12:57:35 UTC 2025 (1764248255) Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: LATEST: Wed Nov 5 17:28:26 UTC 2025 (1762363706) Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2711/default) Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: Use raspi-config to change the release. Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: VL805_FW: Dedicated VL805 EEPROM Dec 29 18:05:23 volumio4 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: VL805: up to date Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: CURRENT: 000138c0 Dec 29 18:05:23 volumio4 rpi-eeprom-update[751]: LATEST: 000138c0 Dec 29 18:05:23 volumio4 systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Dec 29 18:05:23 volumio4 systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Dec 29 18:05:23 volumio4 ifplugd(eth0)[1040]: ifplugd 0.28 initializing. Dec 29 18:05:23 volumio4 ifplugd(eth0)[1040]: Using interface eth0/DC:A6:32:43:FA:FE with driver (version: 6.12.47-v7l+) Dec 29 18:05:23 volumio4 ifplugd(eth0)[1040]: Using detection mode: SIOCETHTOOL Dec 29 18:05:23 volumio4 ifplugd(eth0)[1040]: Initialization complete, link beat not detected. Dec 29 18:05:23 volumio4 ntpd[1012]: INIT: ntpd ntpsec-1.2.2: Starting Dec 29 18:05:23 volumio4 ntpd[1012]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 29 18:05:23 volumio4 ntp-systemd-wrapper[1012]: 2025-12-29T18:05:23 ntpd[1012]: INIT: ntpd ntpsec-1.2.2: Starting Dec 29 18:05:23 volumio4 ntp-systemd-wrapper[1012]: 2025-12-29T18:05:23 ntpd[1012]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 29 18:05:23 volumio4 systemd[1]: Started ntpsec.service - Network Time Service. Dec 29 18:05:23 volumio4 welcome[1014]: Resolved ip:[0] Dec 29 18:05:23 volumio4 avahi-daemon[741]: Server startup complete. Host name is volumio4.local. Local service cookie is 2151053712. Dec 29 18:05:23 volumio4 ifplugd[988]: Network Interface Plugging Daemon...start eth0...done. Dec 29 18:05:23 volumio4 systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Dec 29 18:05:23 volumio4 ntpd[1041]: INIT: precision = 0.445 usec (-21) Dec 29 18:05:23 volumio4 ntpd[1041]: INIT: successfully locked into RAM Dec 29 18:05:24 volumio4 ntpd[1041]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Dec 29 18:05:24 volumio4 ntpd[1041]: CONFIG: restrict nopeer ignored Dec 29 18:05:24 volumio4 ntpd[1041]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 29 18:05:24 volumio4 ntpd[1041]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Dec 29 18:05:24 volumio4 ntpd[1041]: INIT: Using SO_TIMESTAMPNS(ns) Dec 29 18:05:24 volumio4 ntpd[1041]: IO: Listen and drop on 0 v6wildcard [::]:123 Dec 29 18:05:24 volumio4 ntpd[1041]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 29 18:05:24 volumio4 ntpd[1041]: IO: Listen normally on 2 lo 127.0.0.1:123 Dec 29 18:05:24 volumio4 ntpd[1041]: IO: Listening on routing socket on fd #19 for interface updates Dec 29 18:05:24 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 29 18:05:24 volumio4 ntpd[1041]: INIT: This system has a 32-bit time_t. Dec 29 18:05:24 volumio4 ntpd[1041]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Dec 29 18:05:24 volumio4 ntpd[1041]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 29 18:05:24 volumio4 ntpd[1041]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Dec 29 18:05:24 volumio4 ntpd[1041]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Dec 29 18:05:24 volumio4 ntpd[1041]: NTSc: Using system default root certificates. Dec 29 18:05:24 volumio4 ntpd[1041]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Dec 29 18:05:24 volumio4 avahi-daemon[741]: Service "Volumio4" (/services/volumio.service) successfully established. Dec 29 18:05:25 volumio4 ntpd[1041]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:05:25 volumio4 ntpd[1041]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 29 18:05:25 volumio4 ntpd[1041]: DNS: dns_check: DNS error: -11, System error Dec 29 18:05:25 volumio4 ntpd[1041]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 29 18:05:25 volumio4 systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Dec 29 18:05:25 volumio4 systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Dec 29 18:05:25 volumio4 nmbd[1086]: [2025/12/29 18:05:25.964260, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 29 18:05:25 volumio4 nmbd[1086]: nmbd version 4.17.8-Raspbian started. Dec 29 18:05:25 volumio4 nmbd[1086]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 29 18:05:25 volumio4 nmbd[1086]: [2025/12/29 18:05:25.979850, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 29 18:05:25 volumio4 nmbd[1086]: started asyncdns process 1087 Dec 29 18:05:25 volumio4 nmbd[1086]: [2025/12/29 18:05:25.980949, 0] ../../lib/util/become_daemon.c:150(daemon_status) Dec 29 18:05:25 volumio4 nmbd[1086]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 29 18:05:25 volumio4 nmbd[1086]: [2025/12/29 18:05:25.981058, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Dec 29 18:05:25 volumio4 nmbd[1086]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 29 18:05:26 volumio4 dhcpcd[710]: eth0: carrier acquired Dec 29 18:05:26 volumio4 sh[705]: eth0: carrier acquired Dec 29 18:05:26 volumio4 dhcpcd[847]: eth0: carrier acquired Dec 29 18:05:26 volumio4 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 29 18:05:26 volumio4 dhcpcd[847]: eth0: IAID 32:43:fa:fe Dec 29 18:05:26 volumio4 dhcpcd[710]: eth0: IAID 32:43:fa:fe Dec 29 18:05:26 volumio4 sh[705]: eth0: IAID 32:43:fa:fe Dec 29 18:05:26 volumio4 sh[705]: eth0: adding address fe80::dea6:32ff:fe43:fafe Dec 29 18:05:26 volumio4 dhcpcd[710]: eth0: adding address fe80::dea6:32ff:fe43:fafe Dec 29 18:05:26 volumio4 dhcpcd[847]: eth0: adding address fe80::dea6:32ff:fe43:fafe Dec 29 18:05:26 volumio4 dhcpcd[847]: ipv6_addaddr1: Permission denied Dec 29 18:05:26 volumio4 sh[705]: ipv6_addaddr1: Permission denied Dec 29 18:05:26 volumio4 dhcpcd[710]: ipv6_addaddr1: Permission denied Dec 29 18:05:26 volumio4 ntpd[1041]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:05:26 volumio4 ntpd[1041]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 29 18:05:26 volumio4 ntpd[1041]: DNS: dns_check: DNS error: -11, System error Dec 29 18:05:26 volumio4 ntpd[1041]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Cleaning previous... Dec 29 18:05:26 volumio4 ifplugd(eth0)[1040]: Link beat detected. Dec 29 18:05:26 volumio4 sudo[1104]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 29 18:05:26 volumio4 sudo[1104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 18:05:26 volumio4 sudo[1104]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:26 volumio4 sudo[1106]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 29 18:05:26 volumio4 sudo[1106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 18:05:26 volumio4 sudo[1106]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:26 volumio4 ifplugd(eth0)[1040]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 29 18:05:26 volumio4 ifplugd(eth0)[1040]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 29 18:05:26 volumio4 sh[705]: eth0: soliciting an IPv6 router Dec 29 18:05:26 volumio4 dhcpcd[710]: eth0: soliciting an IPv6 router Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Stopped aP Dec 29 18:05:26 volumio4 sudo[1121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 18:05:26 volumio4 sudo[1121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:05:26 volumio4 sudo[1121]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:26 volumio4 wireless.js[766]: SIOCSIFFLAGS: Operation not possible due to RF-kill Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Failed to determine most appropriate reg domain: Error: Command failed: /usr/bin/sudo /sbin/ifconfig wlan0 up && /usr/bin/sudo /sbin/iw reg get | grep country | cut -f1 -d':' Dec 29 18:05:26 volumio4 wireless.js[766]: SIOCSIFFLAGS: Operation not possible due to RF-kill Dec 29 18:05:26 volumio4 wireless.js[766]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 29 18:05:26 volumio4 sh[705]: eth0: soliciting a DHCP lease Dec 29 18:05:26 volumio4 dhcpcd[710]: eth0: soliciting a DHCP lease Dec 29 18:05:27 volumio4 dhcpcd[847]: eth0: soliciting an IPv6 router Dec 29 18:05:27 volumio4 wireless.js[766]: WIRELESS.JS: Notified systemd about wireless ready Dec 29 18:05:27 volumio4 sh[705]: eth0: offered 192.168.24.134 from 192.168.24.1 Dec 29 18:05:27 volumio4 dhcpcd[710]: eth0: offered 192.168.24.134 from 192.168.24.1 Dec 29 18:05:27 volumio4 systemd[1]: Started wireless.service - Wireless Services. Dec 29 18:05:27 volumio4 systemd[1]: Started volumio.service - Volumio Backend Module. Dec 29 18:05:27 volumio4 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 29 18:05:27 volumio4 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 29 18:05:27 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 319 (plymouthd). Dec 29 18:05:27 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 319 (plymouthd). Dec 29 18:05:27 volumio4 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 29 18:05:27 volumio4 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 29 18:05:27 volumio4 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 29 18:05:27 volumio4 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Dec 29 18:05:27 volumio4 systemd[1]: Reached target getty.target - Login Prompts. Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1135]: Setting RT Priority for mpd Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1151]: pid 35's current scheduling policy: SCHED_OTHER Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1151]: pid 35's current scheduling priority: 0 Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1135]: Setting MPD Affinity Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1152]: pid 3's current affinity mask: f Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1135]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 29 18:05:27 volumio4 volumio-cpu-tweak[1135]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 29 18:05:27 volumio4 sh[705]: eth0: probing address 192.168.24.134/24 Dec 29 18:05:27 volumio4 dhcpcd[710]: eth0: probing address 192.168.24.134/24 Dec 29 18:05:27 volumio4 ntpd[1041]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:05:27 volumio4 ntpd[1041]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 29 18:05:27 volumio4 ntpd[1041]: DNS: dns_check: DNS error: -11, System error Dec 29 18:05:27 volumio4 ntpd[1041]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 29 18:05:27 volumio4 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 29 18:05:27 volumio4 systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 29 18:05:27 volumio4 dhcpcd[847]: eth0: soliciting a DHCP lease Dec 29 18:05:27 volumio4 dhcpcd[847]: eth0: offered 192.168.24.134 from 192.168.24.1 Dec 29 18:05:27 volumio4 dhcpcd[847]: eth0: probing address 192.168.24.134/24 Dec 29 18:05:28 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:28] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 29 18:05:28 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:28] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 29 18:05:28 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:28] [error] handle_connect error: Connection refused Dec 29 18:05:28 volumio4 ntpd[1041]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:05:28 volumio4 ntpd[1041]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 29 18:05:28 volumio4 ntpd[1041]: DNS: dns_check: DNS error: -11, System error Dec 29 18:05:28 volumio4 ntpd[1041]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 29 18:05:30 volumio4 volumio[1133]: info: ------------------------------------------- Dec 29 18:05:30 volumio4 volumio[1133]: info: ----- Volumio3 ---- Dec 29 18:05:30 volumio4 volumio[1133]: info: ------------------------------------------- Dec 29 18:05:30 volumio4 volumio[1133]: info: ----- System startup ---- Dec 29 18:05:30 volumio4 volumio[1133]: info: ------------------------------------------- Dec 29 18:05:31 volumio4 bash[1010]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 29 18:05:31 volumio4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 29 18:05:31 volumio4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 29 18:05:31 volumio4 volumio[1133]: info: MYVOLUMIO Environment detected Dec 29 18:05:31 volumio4 volumio[1133]: info: Plugin folders cleanup Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning into folder /volumio/app/plugins/ Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category audio_interface Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category miscellanea Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category music_service Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category plugins.json Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category system_controller Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category user_interface Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning into folder /data/plugins/ Dec 29 18:05:31 volumio4 volumio[1133]: info: Scanning category system_hardware Dec 29 18:05:31 volumio4 volumio[1133]: info: Plugin folders cleanup completed Dec 29 18:05:31 volumio4 volumio[1133]: info: ------------------------------------------- Dec 29 18:05:31 volumio4 volumio[1133]: info: ----- Core plugins startup ---- Dec 29 18:05:31 volumio4 volumio[1133]: info: ------------------------------------------- Dec 29 18:05:31 volumio4 volumio[1133]: info: Loading plugins from folder /volumio/app/plugins/ Dec 29 18:05:31 volumio4 volumio[1133]: info: Adding plugin upnp to MyMusic Plugins Dec 29 18:05:31 volumio4 volumio[1133]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 29 18:05:31 volumio4 volumio[1133]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 29 18:05:31 volumio4 volumio[1133]: info: Loading plugins from folder /data/plugins/ Dec 29 18:05:31 volumio4 volumio[1133]: info: Loading plugin "system"... Dec 29 18:05:31 volumio4 volumio[1133]: info: Loading plugin "appearance"... Dec 29 18:05:32 volumio4 dhcpcd[710]: eth0: leased 192.168.24.134 for 172800 seconds Dec 29 18:05:32 volumio4 sh[705]: eth0: leased 192.168.24.134 for 172800 seconds Dec 29 18:05:32 volumio4 avahi-daemon[741]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.24.134. Dec 29 18:05:32 volumio4 avahi-daemon[741]: New relevant interface eth0.IPv4 for mDNS. Dec 29 18:05:32 volumio4 avahi-daemon[741]: Registering new address record for 192.168.24.134 on eth0.IPv4. Dec 29 18:05:32 volumio4 sh[705]: eth0: adding route to 192.168.24.0/24 Dec 29 18:05:32 volumio4 dhcpcd[710]: eth0: adding route to 192.168.24.0/24 Dec 29 18:05:32 volumio4 dhcpcd[710]: eth0: adding default route via 192.168.24.1 Dec 29 18:05:32 volumio4 sh[705]: eth0: adding default route via 192.168.24.1 Dec 29 18:05:32 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 29 18:05:32 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 29 18:05:32 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 29 18:05:32 volumio4 sh[705]: forked to background, child pid 709 Dec 29 18:05:32 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 29 18:05:32 volumio4 welcome[1212]: Resolved ip:[1] 192.168.24.134 Dec 29 18:05:32 volumio4 systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 29 18:05:32 volumio4 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 29 18:05:32 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 29 18:05:32 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 29 18:05:32 volumio4 ifplugd(eth0)[1040]: client: ifup: interface eth0 already configured Dec 29 18:05:32 volumio4 sh[1249]: eth0=eth0 Dec 29 18:05:32 volumio4 ifplugd(eth0)[1040]: Program executed successfully. Dec 29 18:05:33 volumio4 winbindd[1248]: [2025/12/29 18:05:33.209144, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 29 18:05:33 volumio4 winbindd[1248]: winbindd version 4.17.8-Raspbian started. Dec 29 18:05:33 volumio4 winbindd[1248]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 29 18:05:33 volumio4 winbindd[1248]: [2025/12/29 18:05:33.239498, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Dec 29 18:05:33 volumio4 winbindd[1248]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 29 18:05:33 volumio4 ntpd[1041]: IO: Listen normally on 3 eth0 192.168.24.134:123 Dec 29 18:05:33 volumio4 ntpd[1041]: IO: new interface(s) found: waking up resolver Dec 29 18:05:33 volumio4 ntpd[1041]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:05:33 volumio4 systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 29 18:05:33 volumio4 dhcpcd[847]: eth0: leased 192.168.24.134 for 172800 seconds Dec 29 18:05:33 volumio4 dhcpcd[847]: eth0: adding route to 192.168.24.0/24 Dec 29 18:05:33 volumio4 dhcpcd[847]: eth0: adding default route via 192.168.24.1 Dec 29 18:05:33 volumio4 systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 29 18:05:33 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 29 18:05:33 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 29 18:05:33 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 29 18:05:33 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 29 18:05:33 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 29 18:05:33 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 29 18:05:33 volumio4 welcome[1274]: Resolved ip:[1] 192.168.24.134 Dec 29 18:05:33 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 29 18:05:33 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 29 18:05:34 volumio4 smbd[1292]: [2025/12/29 18:05:34.009333, 0] ../../source3/smbd/server.c:1741(main) Dec 29 18:05:34 volumio4 smbd[1292]: smbd version 4.17.8-Raspbian started. Dec 29 18:05:34 volumio4 smbd[1292]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "network"... Dec 29 18:05:34 volumio4 volumio[1133]: info: Refreshing Cached IP Addresses Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "services"... Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "alsa_controller"... Dec 29 18:05:34 volumio4 sudo[1299]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 18:05:34 volumio4 sudo[1299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:05:34 volumio4 sudo[1298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 18:05:34 volumio4 sudo[1306]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 29 18:05:34 volumio4 sudo[1306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:05:34 volumio4 sudo[1298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:05:34 volumio4 sudo[1298]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:34 volumio4 sudo[1299]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:34 volumio4 sudo[1306]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:34 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "wizard"... Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "networkfs"... Dec 29 18:05:34 volumio4 volumio[1133]: info: Starting Udev Watcher for removable devices Dec 29 18:05:34 volumio4 sudo[1328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=gguest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.24.180/music /mnt/NAS/RasPi5 Dec 29 18:05:34 volumio4 sudo[1328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:05:34 volumio4 volumio[1133]: info: Ignoring mount for partition: boot Dec 29 18:05:34 volumio4 volumio[1133]: info: Ignoring mount for partition: volumio Dec 29 18:05:34 volumio4 volumio[1133]: info: Ignoring mount for partition: volumio_data Dec 29 18:05:34 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "volumio_command_line_client"... Dec 29 18:05:34 volumio4 volumio[1133]: info: Plugin upnp is not enabled Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "my_music"... Dec 29 18:05:34 volumio4 volumio[1133]: info: Loading plugin "mpd"... Dec 29 18:05:34 volumio4 kernel: netfs: FS-Cache loaded Dec 29 18:05:34 volumio4 ntpd[1041]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 29 18:05:34 volumio4 ntpd[1041]: DNS: Pool taking: 129.250.35.251 Dec 29 18:05:34 volumio4 ntpd[1041]: DNS: Pool taking: 172.105.192.74 Dec 29 18:05:34 volumio4 ntpd[1041]: DNS: Pool taking: 85.121.50.82 Dec 29 18:05:34 volumio4 ntpd[1041]: DNS: Pool taking: 47.79.37.210 Dec 29 18:05:34 volumio4 ntpd[1041]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 29 18:05:34 volumio4 kernel: Key type cifs.spnego registered Dec 29 18:05:34 volumio4 kernel: Key type cifs.idmap registered Dec 29 18:05:34 volumio4 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Dec 29 18:05:34 volumio4 kernel: CIFS: Attempting to mount //192.168.24.180/music Dec 29 18:05:35 volumio4 kernel: cryptd: max_cpu_qlen set to 1000 Dec 29 18:05:35 volumio4 systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 29 18:05:35 volumio4 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 29 18:05:35 volumio4 systemd[1]: Reached target graphical.target - Graphical Interface. Dec 29 18:05:35 volumio4 sudo[1328]: pam_unix(sudo:session): session closed for user root Dec 29 18:05:35 volumio4 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 29 18:05:35 volumio4 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 29 18:05:35 volumio4 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 29 18:05:35 volumio4 systemd[1]: Startup finished in 19.963s (kernel) + 16.436s (userspace) = 36.400s. Dec 29 18:05:35 volumio4 ntpd[1041]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:05:35 volumio4 volumio[1133]: info: Plugin upnp_browser is not enabled Dec 29 18:05:35 volumio4 volumio[1133]: info: Loading plugin "alarm-clock"... Dec 29 18:05:35 volumio4 volumio-remote-updater[759]: [2025-12-29 18:05:35] [connect] Successful connection Dec 29 18:05:35 volumio4 volumio[1133]: info: Plugin airplay_emulation is not enabled Dec 29 18:05:35 volumio4 volumio[1133]: info: Loading plugin "last_100"... Dec 29 18:05:35 volumio4 volumio[1133]: info: Loading plugin "webradio"... Dec 29 18:05:36 volumio4 volumio[1133]: info: Loading plugin "i2s_dacs"... Dec 29 18:05:36 volumio4 volumio[1133]: info: Loading plugin "volumiodiscovery"... Dec 29 18:05:36 volumio4 volumio[1133]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 29 18:05:36 volumio4 volumio[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 18:05:36 volumio4 volumio[1133]: *** WARNING *** For more information see Dec 29 18:05:36 volumio4 volumio[1133]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 29 18:05:36 volumio4 volumio[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 18:05:36 volumio4 node[1133]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 29 18:05:36 volumio4 volumio[1133]: *** WARNING *** For more information see Dec 29 18:05:36 volumio4 node[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 18:05:36 volumio4 node[1133]: *** WARNING *** For more information see Dec 29 18:05:36 volumio4 node[1133]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 29 18:05:36 volumio4 node[1133]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 18:05:36 volumio4 node[1133]: *** WARNING *** For more information see Dec 29 18:05:36 volumio4 volumio[1133]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 29 18:05:36 volumio4 volumio[1133]: info: Discovery: Started advertising with name: Volumio4 Dec 29 18:05:36 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 29 18:05:36 volumio4 volumio[1133]: info: Loading plugin "outputs"... Dec 29 18:05:36 volumio4 volumio[1133]: info: Loading plugin "albumart"... Dec 29 18:05:36 volumio4 volumio[1133]: info: Plugin example_plugin is not enabled Dec 29 18:05:36 volumio4 volumio[1133]: info: Loading plugin "inputs"... Dec 29 18:05:36 volumio4 volumio[1133]: info: Loading plugin "updater_comm"... Dec 29 18:05:37 volumio4 volumio[1133]: info: Plugin mpdemulation is not enabled Dec 29 18:05:37 volumio4 volumio[1133]: info: Loading plugin "rest_api"... Dec 29 18:05:37 volumio4 volumio[1133]: info: Loading plugin "websocket"... Dec 29 18:05:37 volumio4 volumio[1133]: info: Starting Socket.io Server version 1.7.4 Dec 29 18:05:37 volumio4 volumio[1133]: info: Loading plugin "gpio_control"... Dec 29 18:05:37 volumio4 volumio[1384]: Forking 3 albumart workers Dec 29 18:05:37 volumio4 volumio[1133]: gpiox_arm32 V2.2.1 Dec 29 18:05:38 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 29 18:05:38 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 18:05:38 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 18:05:38 volumio4 upmpdcli[1424]: Could not open config: /tmp/upmpdcli.conf Dec 29 18:05:38 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 29 18:05:38 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 29 18:05:39 volumio4 volumio[1133]: info: Applying required configuration parameters for plugin gpio_control Dec 29 18:05:39 volumio4 volumio[1133]: info: Loading plugin "mpd_oled"... Dec 29 18:05:39 volumio4 volumio[1133]: info: Applying required configuration parameters for plugin mpd_oled Dec 29 18:05:40 volumio4 volumio[1133]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 29 18:05:40 volumio4 volumio[1133]: info: Loading i18n strings for locale ja Dec 29 18:05:40 volumio4 volumio[1133]: Updating browse sources language Dec 29 18:05:40 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 199.189.156.6 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 172.233.91.137 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 162.159.200.123 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 129.250.35.250 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 2400:8902::2000:52ff:fee4:69d9 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 2001:418:3ff::1:53 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 2400:8902::2000:12ff:fecd:605 Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: Pool taking: 2406:da14:10c4:9aa0:123:: Dec 29 18:05:40 volumio4 ntpd[1041]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 29 18:06:19 volumio4 ntpd[1041]: CLOCK: time stepped by 39.536709 Dec 29 18:06:19 volumio4 ntpd[1041]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 29 18:06:20 volumio4 ntpd[1041]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: Pool taking: 153.127.38.46 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: Pool taking: 103.131.151.20 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: Pool taking: 162.159.200.1 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: Pool taking: 5.104.87.198 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::initPlayerControls Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:21 volumio4 volumio[1133]: Express server listening on port 3000 Dec 29 18:06:21 volumio4 volumio[1133]: [Metrics] WebUI: 11s 889.60ms Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreStateMachine::resetVolumioState Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreStateMachine::getcurrentVolume Dec 29 18:06:21 volumio4 volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume Dec 29 18:06:21 volumio4 volumio[1133]: info: Volumio Network Manager: Network status updated: 1 Dec 29 18:06:21 volumio4 ntpd[1041]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 29 18:06:21 volumio4 volumio[1133]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 29 18:06:21 volumio4 volumio[1133]: wlan0 Interface doesn't support scanning : Network is down Dec 29 18:06:21 volumio4 volumio[1133]: info: Cannot use regular scanning, forcing with ap-force Dec 29 18:06:22 volumio4 ntpd[1041]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 29 18:06:22 volumio4 ntpd[1041]: DNS: Pool taking: 43.229.16.149 Dec 29 18:06:22 volumio4 ntpd[1041]: DNS: Pool taking: 160.25.135.55 Dec 29 18:06:22 volumio4 ntpd[1041]: DNS: Pool taking: 142.91.108.61 Dec 29 18:06:22 volumio4 ntpd[1041]: DNS: Pool taking: 142.91.105.55 Dec 29 18:06:22 volumio4 ntpd[1041]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 29 18:06:22 volumio4 volumio[1394]: Starting albumart workers Dec 29 18:06:22 volumio4 volumio[1395]: Starting albumart workers Dec 29 18:06:22 volumio4 sudo[1435]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 29 18:06:22 volumio4 sudo[1435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:22 volumio4 volumio[1396]: Starting albumart workers Dec 29 18:06:22 volumio4 sudo[1435]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:22 volumio4 volumio[1133]: command failed: Network is down (-100) Dec 29 18:06:22 volumio4 volumio[1133]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 29 18:06:22 volumio4 volumio[1133]: command failed: Network is down (-100) Dec 29 18:06:23 volumio4 volumio[1133]: verbose: New Socket.io Connection to 192.168.24.134 from 192.168.24.124 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::pushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::volumioPushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::updateTrackBlock Dec 29 18:06:23 volumio4 volumio[1133]: info: CorePlayQueue::getTrackBlock Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:06:23 volumio4 volumio[1133]: info: Reloading queue from file Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::pushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::volumioPushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::setRepeat null single undefined Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::pushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::volumioPushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::setRandom null Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreStateMachine::pushState Dec 29 18:06:23 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:23 volumio4 volumio[1133]: info: CoreCommandRouter::volumioPushState Dec 29 18:06:23 volumio4 volumio[1133]: info: Setting Device type: Raspberry PI Dec 29 18:06:23 volumio4 volumio[1133]: verbose: New Socket.io Connection to 192.168.24.134 from 192.168.24.124 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 29 18:06:23 volumio4 volumio[1133]: info: Completed loading Core Plugins Dec 29 18:06:23 volumio4 volumio[1133]: info: Preparing to generate the ALSA configuration file Dec 29 18:06:24 volumio4 volumio[1133]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 29 18:06:24 volumio4 volumio[1133]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Dec 29 18:06:24 volumio4 volumio[1133]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 29 18:06:24 volumio4 volumio[1133]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 29 18:06:24 volumio4 volumio[1133]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 29 18:06:24 volumio4 volumio[1133]: info: Reading ALSA contributions from plugins. Dec 29 18:06:24 volumio4 volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 29 18:06:24 volumio4 volumio[1133]: info: Discovery: adding d6e21869-fe60-4e16-b84a-99f564ffea1a Dec 29 18:06:24 volumio4 volumio[1133]: info: Discovery: Found device Volumio4 Dec 29 18:06:24 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetState Dec 29 18:06:24 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:24 volumio4 volumio[1133]: verbose: New Socket.io Connection to 192.168.24.134 from 192.168.24.124 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 29 18:06:24 volumio4 volumio[1133]: info: Discovery: this is already registered, d6e21869-fe60-4e16-b84a-99f564ffea1a Dec 29 18:06:24 volumio4 volumio[1133]: info: Discovery: Found device Volumio4 Dec 29 18:06:24 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetState Dec 29 18:06:24 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetState Dec 29 18:06:25 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 18:06:25 volumio4 volumio[1133]: info: Received Get System Info Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 18:06:25 volumio4 volumio[1133]: info: Discovery: Getting this device information Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetState Dec 29 18:06:25 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetState Dec 29 18:06:25 volumio4 volumio[1133]: info: CorePlayQueue::getTrack 0 Dec 29 18:06:25 volumio4 volumio[1133]: info: Listing playlists Dec 29 18:06:25 volumio4 volumio[1133]: info: Asound.conf file unchanged, so no further update is needed Dec 29 18:06:25 volumio4 volumio[1133]: info: Output device has changed, restarting MPD Dec 29 18:06:25 volumio4 volumio[1133]: info: ___________ START PLUGINS ___________ Dec 29 18:06:25 volumio4 volumio[1133]: info: ControllerMpd::onStart: Initializing MPD Dec 29 18:06:25 volumio4 volumio[1133]: info: Creating MPD Configuration file Dec 29 18:06:25 volumio4 sudo[1489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 29 18:06:25 volumio4 sudo[1489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 18:06:25 volumio4 volumio[1133]: info: [1766999185648] CoreMusicLibrary::Adding element Last_100 Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 18:06:25 volumio4 sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 18:06:25 volumio4 volumio[1133]: info: [1766999185650] CoreMusicLibrary::Adding element Webradio Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 18:06:25 volumio4 sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:25 volumio4 sudo[1491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 29 18:06:25 volumio4 sudo[1491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:25 volumio4 sudo[1493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 29 18:06:25 volumio4 sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:25 volumio4 sudo[1487]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:25 volumio4 sudo[1491]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 18:06:25 volumio4 volumio[1133]: info: Initializing BBC Radios Dec 29 18:06:25 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 29 18:06:25 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 29 18:06:25 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 29 18:06:25 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 29 18:06:25 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 29 18:06:25 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 29 18:06:25 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 29 18:06:25 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 29 18:06:25 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 18:06:25 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:06:26 volumio4 sudo[1509]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 29 18:06:26 volumio4 sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 18:06:26 volumio4 sudo[1515]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 29 18:06:26 volumio4 sudo[1509]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:26 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 29 18:06:26 volumio4 volumio[1133]: info: Volumio Calling Home Dec 29 18:06:26 volumio4 sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 29 18:06:26 volumio4 sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:26 volumio4 sudo[1523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 29 18:06:26 volumio4 sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 18:06:26 volumio4 sudo[1521]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:26 volumio4 volumio[1133]: info: Preparing to generate the ALSA configuration file Dec 29 18:06:27 volumio4 volumio[1133]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 29 18:06:27 volumio4 volumio[1133]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 29 18:06:27 volumio4 volumio[1133]: info: Reading ALSA contributions from plugins. Dec 29 18:06:27 volumio4 volumio[1133]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 29 18:06:27 volumio4 volumio[1133]: info: MPD Permissions set Dec 29 18:06:27 volumio4 volumio[1133]: info: MPD Permissions set Dec 29 18:06:28 volumio4 volumio[1133]: info: Volumio called home Dec 29 18:06:28 volumio4 volumio[1133]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 29 18:06:29 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 29 18:06:29 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:06:29 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 29 18:06:29 volumio4 volumio[1133]: info: Getting Alsa Cards List without I2S DAC Dec 29 18:06:29 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 29 18:06:29 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 29 18:06:30 volumio4 volumio-remote-updater[759]: [2025-12-29 18:06:30] [connect] Successful connection Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::volumioPlay Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::play index undefined Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::volumioGetQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::getQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: CorePlayQueue::getQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: Preload queue cleared Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::ClearQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::stop Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 18:06:30 volumio4 volumio[1133]: info: CorePlayQueue::clearPlayQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: CorePlayQueue::saveQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreCommandRouter::volumioPushQueue Dec 29 18:06:30 volumio4 volumio[1133]: info: CoreStateMachine::addQueueItems Dec 29 18:06:30 volumio4 volumio[1133]: info: CorePlayQueue::addQueueItems Dec 29 18:06:30 volumio4 volumio[1133]: info: Preload queue cleared Dec 29 18:06:30 volumio4 volumio[1133]: info: Adding Item to queue: music-library/NAS/RasPi5/2Cellos/In2ition (disc 1)/01 - 2Cellos feat. Elton John - Oh, Well.flac Dec 29 18:06:30 volumio4 volumio[1133]: info: Exploding uri music-library/NAS/RasPi5/2Cellos/In2ition (disc 1)/01 - 2Cellos feat. Elton John - Oh, Well.flac in service mpd Dec 29 18:06:30 volumio4 volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 18:06:30 volumio4 volumio[1133]: TypeError: Cannot read properties of undefined (reading 'then') Dec 29 18:06:30 volumio4 volumio[1133]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2546:19) Dec 29 18:06:30 volumio4 volumio[1133]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2344:21) Dec 29 18:06:30 volumio4 volumio[1133]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Dec 29 18:06:30 volumio4 volumio[1133]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 29 18:06:30 volumio4 volumio[1133]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 29 18:06:30 volumio4 volumio[1133]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Dec 29 18:06:30 volumio4 volumio[1133]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Dec 29 18:06:30 volumio4 volumio[1133]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:526:23) Dec 29 18:06:30 volumio4 volumio[1133]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Dec 29 18:06:30 volumio4 volumio[1133]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Dec 29 18:06:30 volumio4 volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 18:06:30 volumio4 systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 29 18:06:31 volumio4 mpd[1518]: 2025-12-29T18:06:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 29 18:06:32 volumio4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 29 18:06:32 volumio4 sudo[1493]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:32 volumio4 sudo[1489]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:32 volumio4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 29 18:06:32 volumio4 sudo[1523]: pam_unix(sudo:session): session closed for user root Dec 29 18:06:33 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 29 18:06:33 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 18:06:33 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 29 18:06:33 volumio4 upmpdcli[1560]: Could not open config: /tmp/upmpdcli.conf Dec 29 18:06:33 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 29 18:06:33 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 29 18:06:34 volumio4 sudo[1563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 18:05' Dec 29 18:06:34 volumio4 sudo[1563]: 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"