Jul 19 07:05:13 volumio-4.0 kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Jul 19 07:05:13 volumio-4.0 kernel: Linux version 6.12.34-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1889 SMP PREEMPT Mon Jun 23 11:11:06 BST 2025 Jul 19 07:05:13 volumio-4.0 kernel: KASLR enabled Jul 19 07:05:13 volumio-4.0 kernel: random: crng init done Jul 19 07:05:13 volumio-4.0 kernel: Machine model: Raspberry Pi Compute Module 5 Lite Rev 1.0 Jul 19 07:05:13 volumio-4.0 kernel: efi: UEFI not found. Jul 19 07:05:13 volumio-4.0 kernel: Reserved memory: created CMA memory pool at 0x000000003b800000, size 64 MiB Jul 19 07:05:13 volumio-4.0 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jul 19 07:05:13 volumio-4.0 kernel: OF: reserved mem: 0x000000003b800000..0x000000003f7fffff (65536 KiB) map reusable linux,cma Jul 19 07:05:13 volumio-4.0 kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Jul 19 07:05:13 volumio-4.0 kernel: OF: reserved mem: 0x000000003fd16200..0x000000003fd1629f (0 KiB) nomap non-reusable nvram@0 Jul 19 07:05:13 volumio-4.0 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000000ffffffff] Jul 19 07:05:13 volumio-4.0 kernel: Faking node 0 at [mem 0x0000000000000000-0x000000001fffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 1 at [mem 0x0000000020000000-0x000000003fffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 2 at [mem 0x0000000040000000-0x000000005fffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 3 at [mem 0x0000000060000000-0x000000007fffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 4 at [mem 0x0000000080000000-0x000000009fffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 5 at [mem 0x00000000a0000000-0x00000000bfffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 6 at [mem 0x00000000c0000000-0x00000000dfffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: Faking node 7 at [mem 0x00000000e0000000-0x00000000ffffffff] (512MB) Jul 19 07:05:13 volumio-4.0 kernel: NUMA: Initialized distance table, cnt=8 Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(0) allocated [mem 0x1fffd300-0x1fffffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(1) allocated [mem 0x3b7fd300-0x3b7fffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(2) allocated [mem 0x5fffd300-0x5fffffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(3) allocated [mem 0x7fffd300-0x7fffffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(4) allocated [mem 0x9fffd300-0x9fffffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(5) allocated [mem 0xbfffd300-0xbfffffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(6) allocated [mem 0xdfffd300-0xdfffffff] Jul 19 07:05:13 volumio-4.0 kernel: NODE_DATA(7) allocated [mem 0xff7a6300-0xff7a8fff] Jul 19 07:05:13 volumio-4.0 kernel: Zone ranges: Jul 19 07:05:13 volumio-4.0 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Jul 19 07:05:13 volumio-4.0 kernel: DMA32 empty Jul 19 07:05:13 volumio-4.0 kernel: Normal empty Jul 19 07:05:13 volumio-4.0 kernel: Movable zone start for each node Jul 19 07:05:13 volumio-4.0 kernel: Early memory node ranges Jul 19 07:05:13 volumio-4.0 kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Jul 19 07:05:13 volumio-4.0 kernel: node 0: [mem 0x0000000000080000-0x000000001fffffff] Jul 19 07:05:13 volumio-4.0 kernel: node 1: [mem 0x0000000020000000-0x000000003f7fffff] Jul 19 07:05:13 volumio-4.0 kernel: node 2: [mem 0x0000000040000000-0x000000005fffffff] Jul 19 07:05:13 volumio-4.0 kernel: node 3: [mem 0x0000000060000000-0x000000007fffffff] Jul 19 07:05:13 volumio-4.0 kernel: node 4: [mem 0x0000000080000000-0x000000009fffffff] Jul 19 07:05:13 volumio-4.0 kernel: node 5: [mem 0x00000000a0000000-0x00000000bfffffff] Jul 19 07:05:13 volumio-4.0 kernel: node 6: [mem 0x00000000c0000000-0x00000000dfffffff] Jul 19 07:05:13 volumio-4.0 kernel: node 7: [mem 0x00000000e0000000-0x00000000ffffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000001fffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 1 [mem 0x0000000020000000-0x000000003f7fffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 2 [mem 0x0000000040000000-0x000000005fffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 3 [mem 0x0000000060000000-0x000000007fffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 4 [mem 0x0000000080000000-0x000000009fffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 5 [mem 0x00000000a0000000-0x00000000bfffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 6 [mem 0x00000000c0000000-0x00000000dfffffff] Jul 19 07:05:13 volumio-4.0 kernel: Initmem setup node 7 [mem 0x00000000e0000000-0x00000000ffffffff] Jul 19 07:05:13 volumio-4.0 kernel: On node 2, zone DMA: 2048 pages in unavailable ranges Jul 19 07:05:13 volumio-4.0 kernel: psci: probing for conduit method from DT. Jul 19 07:05:13 volumio-4.0 kernel: psci: PSCIv1.1 detected in firmware. Jul 19 07:05:13 volumio-4.0 kernel: psci: Using standard PSCI v0.2 function IDs Jul 19 07:05:13 volumio-4.0 kernel: psci: MIGRATE_INFO_TYPE not supported. Jul 19 07:05:13 volumio-4.0 kernel: psci: SMC Calling Convention v1.2 Jul 19 07:05:13 volumio-4.0 kernel: percpu: Embedded 33 pages/cpu s95064 r8192 d31912 u135168 Jul 19 07:05:13 volumio-4.0 kernel: pcpu-alloc: s95064 r8192 d31912 u135168 alloc=33*4096 Jul 19 07:05:13 volumio-4.0 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 19 07:05:13 volumio-4.0 kernel: Detected PIPT I-cache on CPU0 Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Virtualization Host Extensions Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Spectre-v4 Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Spectre-BHB Jul 19 07:05:13 volumio-4.0 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 19 07:05:13 volumio-4.0 kernel: alternatives: applying boot alternatives Jul 19 07:05:13 volumio-4.0 kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 smsc95xx.macaddr=2C:CF:67:C2:73:FB vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc imgfile=/volumio_current.sqsh bootpart=UUID=F765-E57D datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 uuidconfig=cmdline.txt 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 Jul 19 07:05:13 volumio-4.0 kernel: cgroup: Disabling memory control group subsystem Jul 19 07:05:13 volumio-4.0 kernel: mempolicy: NUMA default policy overridden to 'interleave:0-7' Jul 19 07:05:13 volumio-4.0 kernel: PCIe ASPM is disabled Jul 19 07:05:13 volumio-4.0 kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc imgfile=/volumio_current.sqsh bootpart=UUID=F765-E57D datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 0: 0 1 2 3 4 5 6 7 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 1: 1 2 3 4 5 6 7 0 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 2: 2 3 4 5 6 7 0 1 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 3: 3 4 5 6 7 0 1 2 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 4: 4 5 6 7 0 1 2 3 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 5: 5 6 7 0 1 2 3 4 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 6: 6 7 0 1 2 3 4 5 Jul 19 07:05:13 volumio-4.0 kernel: Fallback order for Node 7: 7 0 1 2 3 4 5 6 Jul 19 07:05:13 volumio-4.0 kernel: Built 8 zonelists, mobility grouping on. Total pages: 1046528 Jul 19 07:05:13 volumio-4.0 kernel: Policy zone: DMA Jul 19 07:05:13 volumio-4.0 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 19 07:05:13 volumio-4.0 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8 Jul 19 07:05:13 volumio-4.0 kernel: ftrace: allocating 45317 entries in 178 pages Jul 19 07:05:13 volumio-4.0 kernel: ftrace: allocated 178 pages with 4 groups Jul 19 07:05:13 volumio-4.0 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 19 07:05:13 volumio-4.0 kernel: rcu: RCU event tracing is enabled. Jul 19 07:05:13 volumio-4.0 kernel: Trampoline variant of Tasks RCU enabled. Jul 19 07:05:13 volumio-4.0 kernel: Rude variant of Tasks RCU enabled. Jul 19 07:05:13 volumio-4.0 kernel: Tracing variant of Tasks RCU enabled. Jul 19 07:05:13 volumio-4.0 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jul 19 07:05:13 volumio-4.0 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 19 07:05:13 volumio-4.0 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 19 07:05:13 volumio-4.0 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 19 07:05:13 volumio-4.0 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 19 07:05:13 volumio-4.0 kernel: Root IRQ handler: gic_handle_irq Jul 19 07:05:13 volumio-4.0 kernel: GIC: Using split EOI/Deactivate mode Jul 19 07:05:13 volumio-4.0 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 19 07:05:13 volumio-4.0 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Jul 19 07:05:13 volumio-4.0 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Jul 19 07:05:13 volumio-4.0 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Jul 19 07:05:13 volumio-4.0 kernel: Console: colour dummy device 80x25 Jul 19 07:05:13 volumio-4.0 kernel: printk: legacy console [tty1] enabled Jul 19 07:05:13 volumio-4.0 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Jul 19 07:05:13 volumio-4.0 kernel: pid_max: default: 32768 minimum: 301 Jul 19 07:05:13 volumio-4.0 kernel: LSM: initializing lsm=capability Jul 19 07:05:13 volumio-4.0 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: rcu: Hierarchical SRCU implementation. Jul 19 07:05:13 volumio-4.0 kernel: rcu: Max phase no-delay instances is 1000. Jul 19 07:05:13 volumio-4.0 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level Jul 19 07:05:13 volumio-4.0 kernel: EFI services will not be available. Jul 19 07:05:13 volumio-4.0 kernel: smp: Bringing up secondary CPUs ... Jul 19 07:05:13 volumio-4.0 kernel: Detected PIPT I-cache on CPU1 Jul 19 07:05:13 volumio-4.0 kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Jul 19 07:05:13 volumio-4.0 kernel: Detected PIPT I-cache on CPU2 Jul 19 07:05:13 volumio-4.0 kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Jul 19 07:05:13 volumio-4.0 kernel: Detected PIPT I-cache on CPU3 Jul 19 07:05:13 volumio-4.0 kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Jul 19 07:05:13 volumio-4.0 kernel: smp: Brought up 8 nodes, 4 CPUs Jul 19 07:05:13 volumio-4.0 kernel: SMP: Total of 4 processors activated. Jul 19 07:05:13 volumio-4.0 kernel: CPU: All CPU(s) started at EL2 Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: 32-bit EL0 Support Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Common not Private translations Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: CRC32 instructions Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: LSE atomic instructions Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Privileged Access Never Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: RAS Extension Support Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 19 07:05:13 volumio-4.0 kernel: alternatives: applying system-wide alternatives Jul 19 07:05:13 volumio-4.0 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jul 19 07:05:13 volumio-4.0 kernel: Memory: 3965348K/4186112K available (14080K kernel code, 2406K rwdata, 4816K rodata, 5440K init, 577K bss, 141644K reserved, 65536K cma-reserved) Jul 19 07:05:13 volumio-4.0 kernel: devtmpfs: initialized Jul 19 07:05:13 volumio-4.0 kernel: Enabled cp15_barrier support Jul 19 07:05:13 volumio-4.0 kernel: Enabled setend support Jul 19 07:05:13 volumio-4.0 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jul 19 07:05:13 volumio-4.0 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jul 19 07:05:13 volumio-4.0 kernel: 0 pages in range for non-PLT usage Jul 19 07:05:13 volumio-4.0 kernel: 517392 pages in range for PLT usage Jul 19 07:05:13 volumio-4.0 kernel: pinctrl core: initialized pinctrl subsystem Jul 19 07:05:13 volumio-4.0 kernel: DMI not present or invalid. Jul 19 07:05:13 volumio-4.0 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 19 07:05:13 volumio-4.0 kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Jul 19 07:05:13 volumio-4.0 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 19 07:05:13 volumio-4.0 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 19 07:05:13 volumio-4.0 kernel: audit: initializing netlink subsys (disabled) Jul 19 07:05:13 volumio-4.0 kernel: audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 Jul 19 07:05:13 volumio-4.0 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 19 07:05:13 volumio-4.0 kernel: cpuidle: using governor menu Jul 19 07:05:13 volumio-4.0 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 19 07:05:13 volumio-4.0 kernel: ASID allocator initialised with 32768 entries Jul 19 07:05:13 volumio-4.0 kernel: Serial: AMBA PL011 UART driver Jul 19 07:05:13 volumio-4.0 kernel: /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000 Jul 19 07:05:13 volumio-4.0 kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Jul 19 07:05:13 volumio-4.0 kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3 Jul 19 07:05:13 volumio-4.0 kernel: printk: legacy console [ttyAMA10] enabled Jul 19 07:05:13 volumio-4.0 kernel: raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-05-08T14:13:17, variant start_cd Jul 19 07:05:13 volumio-4.0 kernel: raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 6947117700000000000000000000000000000000 Jul 19 07:05:13 volumio-4.0 kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Jul 19 07:05:13 volumio-4.0 kernel: iommu: Default domain type: Translated Jul 19 07:05:13 volumio-4.0 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 19 07:05:13 volumio-4.0 kernel: SCSI subsystem initialized Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver usbfs Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver hub Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new device driver usb Jul 19 07:05:13 volumio-4.0 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 19 07:05:13 volumio-4.0 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 19 07:05:13 volumio-4.0 kernel: PTP clock support registered Jul 19 07:05:13 volumio-4.0 kernel: vgaarb: loaded Jul 19 07:05:13 volumio-4.0 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 19 07:05:13 volumio-4.0 kernel: VFS: Disk quotas dquot_6.6.0 Jul 19 07:05:13 volumio-4.0 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 19 07:05:13 volumio-4.0 kernel: NET: Registered PF_INET protocol family Jul 19 07:05:13 volumio-4.0 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 19 07:05:13 volumio-4.0 kernel: MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, vmalloc) Jul 19 07:05:13 volumio-4.0 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 19 07:05:13 volumio-4.0 kernel: RPC: Registered named UNIX socket transport module. Jul 19 07:05:13 volumio-4.0 kernel: RPC: Registered udp transport module. Jul 19 07:05:13 volumio-4.0 kernel: RPC: Registered tcp transport module. Jul 19 07:05:13 volumio-4.0 kernel: RPC: Registered tcp-with-tls transport module. Jul 19 07:05:13 volumio-4.0 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 19 07:05:13 volumio-4.0 kernel: PCI: CLS 0 bytes, default 64 Jul 19 07:05:13 volumio-4.0 kernel: Trying to unpack rootfs image as initramfs... Jul 19 07:05:13 volumio-4.0 kernel: kvm [1]: nv: 554 coarse grained trap handlers Jul 19 07:05:13 volumio-4.0 kernel: kvm [1]: IPA Size Limit: 40 bits Jul 19 07:05:13 volumio-4.0 kernel: kvm [1]: vgic interrupt IRQ9 Jul 19 07:05:13 volumio-4.0 kernel: kvm [1]: VHE mode initialized successfully Jul 19 07:05:13 volumio-4.0 kernel: Initialise system trusted keyrings Jul 19 07:05:13 volumio-4.0 kernel: workingset: timestamp_bits=42 max_order=20 bucket_order=0 Jul 19 07:05:13 volumio-4.0 kernel: NFS: Registering the id_resolver key type Jul 19 07:05:13 volumio-4.0 kernel: Key type id_resolver registered Jul 19 07:05:13 volumio-4.0 kernel: Key type id_legacy registered Jul 19 07:05:13 volumio-4.0 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jul 19 07:05:13 volumio-4.0 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jul 19 07:05:13 volumio-4.0 kernel: Key type asymmetric registered Jul 19 07:05:13 volumio-4.0 kernel: Asymmetric key parser 'x509' registered Jul 19 07:05:13 volumio-4.0 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jul 19 07:05:13 volumio-4.0 kernel: io scheduler mq-deadline registered Jul 19 07:05:13 volumio-4.0 kernel: io scheduler kyber registered Jul 19 07:05:13 volumio-4.0 kernel: io scheduler bfq registered Jul 19 07:05:13 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27) Jul 19 07:05:13 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28) Jul 19 07:05:13 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29) Jul 19 07:05:13 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30) Jul 19 07:05:13 volumio-4.0 kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31) Jul 19 07:05:13 volumio-4.0 kernel: ledtrig-cpu: registered to indicate activity on CPUs Jul 19 07:05:13 volumio-4.0 kernel: Freeing initrd memory: 38752K Jul 19 07:05:13 volumio-4.0 kernel: simple-framebuffer 3f800000.framebuffer: framebuffer at 0x3f800000, 0x3f4800 bytes Jul 19 07:05:13 volumio-4.0 kernel: simple-framebuffer 3f800000.framebuffer: format=r5g6b5, mode=1920x1080x16, linelength=3840 Jul 19 07:05:13 volumio-4.0 kernel: Console: switching to colour frame buffer device 240x67 Jul 19 07:05:13 volumio-4.0 kernel: simple-framebuffer 3f800000.framebuffer: fb0: simplefb registered! Jul 19 07:05:13 volumio-4.0 kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Jul 19 07:05:13 volumio-4.0 kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Jul 19 07:05:13 volumio-4.0 kernel: serial serial0: tty port ttyS0 registered Jul 19 07:05:13 volumio-4.0 kernel: iproc-rng200 107d208000.rng: hwrng registered Jul 19 07:05:13 volumio-4.0 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Jul 19 07:05:13 volumio-4.0 kernel: brd: module loaded Jul 19 07:05:13 volumio-4.0 kernel: loop: module loaded Jul 19 07:05:13 volumio-4.0 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jul 19 07:05:13 volumio-4.0 kernel: Loading iSCSI transport class v2.0-870. Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver lan78xx Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver smsc95xx Jul 19 07:05:13 volumio-4.0 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jul 19 07:05:13 volumio-4.0 kernel: dwc_otg: FIQ enabled Jul 19 07:05:13 volumio-4.0 kernel: dwc_otg: NAK holdoff enabled Jul 19 07:05:13 volumio-4.0 kernel: dwc_otg: FIQ split-transaction FSM enabled Jul 19 07:05:13 volumio-4.0 kernel: Module dwc_common_port init Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver uas Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver usb-storage Jul 19 07:05:13 volumio-4.0 kernel: mousedev: PS/2 mouse device common for all mice Jul 19 07:05:13 volumio-4.0 kernel: rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0 Jul 19 07:05:13 volumio-4.0 kernel: rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 2025-07-19T05:05:02 UTC (1752901502) Jul 19 07:05:13 volumio-4.0 kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Jul 19 07:05:13 volumio-4.0 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jul 19 07:05:13 volumio-4.0 kernel: sdhci: Secure Digital Host Controller Interface driver Jul 19 07:05:13 volumio-4.0 kernel: sdhci: Copyright(c) Pierre Ossman Jul 19 07:05:13 volumio-4.0 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jul 19 07:05:13 volumio-4.0 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Jul 19 07:05:13 volumio-4.0 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver usbhid Jul 19 07:05:13 volumio-4.0 kernel: usbhid: USB HID core driver Jul 19 07:05:13 volumio-4.0 kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available Jul 19 07:05:13 volumio-4.0 kernel: NET: Registered PF_PACKET protocol family Jul 19 07:05:13 volumio-4.0 kernel: Key type dns_resolver registered Jul 19 07:05:13 volumio-4.0 kernel: registered taskstats version 1 Jul 19 07:05:13 volumio-4.0 kernel: Loading compiled-in X.509 certificates Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 0: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 1: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 2: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 3: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 4: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 5: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 6: null Jul 19 07:05:13 volumio-4.0 kernel: Demotion targets for Node 7: null Jul 19 07:05:13 volumio-4.0 kernel: Key type .fscrypt registered Jul 19 07:05:13 volumio-4.0 kernel: Key type fscrypt-provisioning registered Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges: Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@1000110000, using [bus 00-ff] Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00 Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: PCI bridge to [bus 00] Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1bbfffffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: clkreq-mode set to default Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000110000.pcie: link up, 5.0 GT/s PCIe x1 (!SSC) Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:01:00.0: [144d:a809] type 00 class 0x010802 PCIe Endpoint Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:01:00.0: BAR 0 [mem 0x00000000-0x00003fff 64bit] Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0001:00:00.0 (capable of 31.504 Gb/s with 8.0 GT/s PCIe x4 link) Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff]: assigned Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:01:00.0: BAR 0 [mem 0x1b80000000-0x1b80003fff 64bit]: assigned Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0001:01: resource 1 [mem 0x1b80000000-0x1b800fffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jul 19 07:05:13 volumio-4.0 kernel: pci 0001:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Jul 19 07:05:13 volumio-4.0 kernel: pcieport 0001:00:00.0: enabling device (0000 -> 0002) Jul 19 07:05:13 volumio-4.0 kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 38 Jul 19 07:05:13 volumio-4.0 kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 38 Jul 19 07:05:13 volumio-4.0 kernel: nvme nvme0: pci function 0001:01:00.0 Jul 19 07:05:13 volumio-4.0 kernel: nvme 0001:01:00.0: enabling device (0000 -> 0002) Jul 19 07:05:13 volumio-4.0 kernel: nvme nvme0: D3 entry latency set to 8 seconds Jul 19 07:05:13 volumio-4.0 kernel: hwmon hwmon1: temp1_input not attached to any thermal zone Jul 19 07:05:13 volumio-4.0 kernel: hwmon hwmon1: temp2_input not attached to any thermal zone Jul 19 07:05:13 volumio-4.0 kernel: nvme nvme0: min host memory (16 MiB) above limit (0 MiB). Jul 19 07:05:13 volumio-4.0 kernel: nvme nvme0: 4/0/0 default/read/poll queues Jul 19 07:05:13 volumio-4.0 kernel: nvme0n1: p1 p2 p3 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges: Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff] Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000 Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00 Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:00: root bus resource [bus 00-ff] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: PCI bridge to [bus 00] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: PME# supported from D0 D3hot Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default Jul 19 07:05:13 volumio-4.0 kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: supports D1 Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01 Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: PCI bridge to [bus 01] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref] Jul 19 07:05:13 volumio-4.0 kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff] Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jul 19 07:05:13 volumio-4.0 kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Jul 19 07:05:13 volumio-4.0 kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002) Jul 19 07:05:13 volumio-4.0 kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 44 Jul 19 07:05:13 volumio-4.0 kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 44 Jul 19 07:05:13 volumio-4.0 kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Jul 19 07:05:13 volumio-4.0 kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Jul 19 07:05:13 volumio-4.0 kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002) Jul 19 07:05:13 volumio-4.0 kernel: rp1 0002:01:00.0: chip_id 0x20001927 Jul 19 07:05:13 volumio-4.0 kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 106 Jul 19 07:05:13 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 112 (2c:cf:67:c2:73:fb) Jul 19 07:05:13 volumio-4.0 kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: irq 137, io mem 0x1f00200000 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Jul 19 07:05:13 volumio-4.0 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jul 19 07:05:13 volumio-4.0 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 19 07:05:13 volumio-4.0 kernel: usb usb1: Product: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: usb usb1: Manufacturer: Linux 6.12.34-v8+ xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb usb1: SerialNumber: xhci-hcd.0 Jul 19 07:05:13 volumio-4.0 kernel: hub 1-0:1.0: USB hub found Jul 19 07:05:13 volumio-4.0 kernel: hub 1-0:1.0: 2 ports detected Jul 19 07:05:13 volumio-4.0 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jul 19 07:05:13 volumio-4.0 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 19 07:05:13 volumio-4.0 kernel: usb usb2: Product: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: usb usb2: Manufacturer: Linux 6.12.34-v8+ xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb usb2: SerialNumber: xhci-hcd.0 Jul 19 07:05:13 volumio-4.0 kernel: hub 2-0:1.0: USB hub found Jul 19 07:05:13 volumio-4.0 kernel: hub 2-0:1.0: 1 port detected Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: irq 142, io mem 0x1f00300000 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Jul 19 07:05:13 volumio-4.0 kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Jul 19 07:05:13 volumio-4.0 kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jul 19 07:05:13 volumio-4.0 kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 19 07:05:13 volumio-4.0 kernel: usb usb3: Product: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: usb usb3: Manufacturer: Linux 6.12.34-v8+ xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb usb3: SerialNumber: xhci-hcd.1 Jul 19 07:05:13 volumio-4.0 kernel: hub 3-0:1.0: USB hub found Jul 19 07:05:13 volumio-4.0 kernel: hub 3-0:1.0: 2 ports detected Jul 19 07:05:13 volumio-4.0 kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jul 19 07:05:13 volumio-4.0 kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 19 07:05:13 volumio-4.0 kernel: usb usb4: Product: xHCI Host Controller Jul 19 07:05:13 volumio-4.0 kernel: usb usb4: Manufacturer: Linux 6.12.34-v8+ xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb usb4: SerialNumber: xhci-hcd.1 Jul 19 07:05:13 volumio-4.0 kernel: hub 4-0:1.0: USB hub found Jul 19 07:05:13 volumio-4.0 kernel: hub 4-0:1.0: 1 port detected Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jul 19 07:05:13 volumio-4.0 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Jul 19 07:05:13 volumio-4.0 kernel: mmc0: CQHCI version 5.10 Jul 19 07:05:13 volumio-4.0 kernel: mmc1: CQHCI version 5.10 Jul 19 07:05:13 volumio-4.0 kernel: of_cfs_init Jul 19 07:05:13 volumio-4.0 kernel: of_cfs_init: OK Jul 19 07:05:13 volumio-4.0 kernel: clk: Disabling unused clocks Jul 19 07:05:13 volumio-4.0 kernel: PM: genpd: Disabling unused power domains Jul 19 07:05:13 volumio-4.0 kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Jul 19 07:05:13 volumio-4.0 kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Jul 19 07:05:13 volumio-4.0 kernel: Freeing unused kernel memory: 5440K Jul 19 07:05:13 volumio-4.0 kernel: Run /init as init process Jul 19 07:05:13 volumio-4.0 kernel: with arguments: Jul 19 07:05:13 volumio-4.0 kernel: /init Jul 19 07:05:13 volumio-4.0 kernel: splash Jul 19 07:05:13 volumio-4.0 kernel: nodebug Jul 19 07:05:13 volumio-4.0 kernel: with environment: Jul 19 07:05:13 volumio-4.0 kernel: HOME=/ Jul 19 07:05:13 volumio-4.0 kernel: TERM=linux Jul 19 07:05:13 volumio-4.0 kernel: imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc Jul 19 07:05:13 volumio-4.0 kernel: imgfile=/volumio_current.sqsh Jul 19 07:05:13 volumio-4.0 kernel: bootpart=UUID=F765-E57D Jul 19 07:05:13 volumio-4.0 kernel: datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 Jul 19 07:05:13 volumio-4.0 kernel: uuidconfig=cmdline.txt Jul 19 07:05:13 volumio-4.0 kernel: bootdelay=7 Jul 19 07:05:13 volumio-4.0 kernel: use_kmsg=no Jul 19 07:05:13 volumio-4.0 kernel: usb 3-1: new high-speed USB device number 2 using xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_info_register (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_device_new (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Jul 19 07:05:13 volumio-4.0 kernel: snd_timer: Unknown symbol snd_register_device (err -2) Jul 19 07:05:13 volumio-4.0 kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Jul 19 07:05:13 volumio-4.0 kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Jul 19 07:05:13 volumio-4.0 kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Jul 19 07:05:13 volumio-4.0 kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform! Jul 19 07:05:13 volumio-4.0 kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0 Jul 19 07:05:13 volumio-4.0 kernel: usb 3-1: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.07 Jul 19 07:05:13 volumio-4.0 kernel: usb 3-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jul 19 07:05:13 volumio-4.0 kernel: usb 3-1: Product: E70 Velvet Jul 19 07:05:13 volumio-4.0 kernel: usb 3-1: Manufacturer: Topping Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610, bcdDevice=94.05 Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1: Product: USB2.1 Hub Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1: Manufacturer: GenesysLogic Jul 19 07:05:13 volumio-4.0 kernel: hub 1-1:1.0: USB hub found Jul 19 07:05:13 volumio-4.0 kernel: hub 1-1:1.0: 4 ports detected Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1: New USB device found, idVendor=05e3, idProduct=0625, bcdDevice=94.05 Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1: Product: USB3.1 Hub Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1: Manufacturer: GenesysLogic Jul 19 07:05:13 volumio-4.0 kernel: fuse: init (API version 7.41) Jul 19 07:05:13 volumio-4.0 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 19 07:05:13 volumio-4.0 kernel: hub 2-1:1.0: USB hub found Jul 19 07:05:13 volumio-4.0 kernel: hub 2-1:1.0: 4 ports detected Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1.1: new SuperSpeed USB device number 3 using xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00 Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1.1: Product: USB3.1 Storage Device Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1.1: Manufacturer: Space keys Jul 19 07:05:13 volumio-4.0 kernel: usb 2-1.1: SerialNumber: 50026B72470114C4 Jul 19 07:05:13 volumio-4.0 kernel: scsi host0: uas Jul 19 07:05:13 volumio-4.0 kernel: scsi 0:0:0:0: Direct-Access Samsung SSD 870 EVO 1TB 0 PQ: 0 ANSI: 6 Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1.2: new full-speed USB device number 4 using xhci-hcd Jul 19 07:05:13 volumio-4.0 kernel: sda: sda1 Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1.2: New USB device found, idVendor=0712, idProduct=000a, bcdDevice= 0.00 Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1.2: Product: WaveShare Jul 19 07:05:13 volumio-4.0 kernel: usb 1-1.2: Manufacturer: WaveShare Jul 19 07:05:13 volumio-4.0 kernel: input: WaveShare WaveShare Touchscreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:0712:000A.0001/input/input1 Jul 19 07:05:13 volumio-4.0 kernel: hid-generic 0003:0712:000A.0001: input,hiddev96,hidraw0: USB HID v1.11 Device [WaveShare WaveShare ] on usb-xhci-hcd.0-1.2/input0 Jul 19 07:05:13 volumio-4.0 kernel: input: WaveShare WaveShare as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:0712:000A.0001/input/input3 Jul 19 07:05:13 volumio-4.0 kernel: hid-multitouch 0003:0712:000A.0001: input,hiddev96,hidraw0: USB HID v1.11 Device [WaveShare WaveShare ] on usb-xhci-hcd.0-1.2/input0 Jul 19 07:05:13 volumio-4.0 kernel: EXT4-fs (nvme0n1p2): recovery complete Jul 19 07:05:13 volumio-4.0 kernel: EXT4-fs (nvme0n1p2): mounted filesystem 1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc r/w with ordered data mode. Quota mode: none. Jul 19 07:05:13 volumio-4.0 kernel: loop0: detected capacity change from 0 to 1488416 Jul 19 07:05:13 volumio-4.0 kernel: EXT4-fs (nvme0n1p3): recovery complete Jul 19 07:05:13 volumio-4.0 kernel: EXT4-fs (nvme0n1p3): mounted filesystem c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 r/w with ordered data mode. Quota mode: none. Jul 19 07:05:13 volumio-4.0 kernel: NET: Registered PF_INET6 protocol family Jul 19 07:05:13 volumio-4.0 kernel: Segment Routing with IPv6 Jul 19 07:05:13 volumio-4.0 kernel: In-situ OAM (IOAM) with IPv6 Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd 252.38-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) Jul 19 07:05:13 volumio-4.0 systemd[1]: Detected architecture arm64. Jul 19 07:05:13 volumio-4.0 systemd[1]: Hostname set to . Jul 19 07:05:13 volumio-4.0 systemd[1]: winbind.service: Found ordering cycle on smbd.service/start Jul 19 07:05:13 volumio-4.0 systemd[1]: winbind.service: Found dependency on winbind.service/start Jul 19 07:05:13 volumio-4.0 systemd[1]: winbind.service: Job smbd.service/start deleted to break ordering cycle starting with winbind.service/start Jul 19 07:05:13 volumio-4.0 systemd[1]: Queued start job for default target graphical.target. Jul 19 07:05:13 volumio-4.0 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 19 07:05:13 volumio-4.0 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 19 07:05:13 volumio-4.0 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 19 07:05:13 volumio-4.0 systemd[1]: Created slice user.slice - User and Session Slice. Jul 19 07:05:13 volumio-4.0 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 19 07:05:13 volumio-4.0 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 19 07:05:13 volumio-4.0 systemd[1]: Expecting device dev-disk-by\x2duuid-F765\x2dE57D.device - /dev/disk/by-uuid/F765-E57D... Jul 19 07:05:13 volumio-4.0 systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10... Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target slices.target - Slice Units. Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 19 07:05:13 volumio-4.0 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 19 07:05:13 volumio-4.0 systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jul 19 07:05:13 volumio-4.0 systemd[1]: Started dynamicswap.service - dynamicswap service. Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target swap.target - Swaps. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 19 07:05:13 volumio-4.0 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 19 07:05:13 volumio-4.0 systemd[1]: dynamicswap.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 19 07:05:13 volumio-4.0 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 19 07:05:13 volumio-4.0 kernel: i2c_dev: i2c /dev entries driver Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 19 07:05:13 volumio-4.0 systemd-journald[443]: Journal started Jul 19 07:05:13 volumio-4.0 systemd-journald[443]: Runtime Journal (/run/log/journal/843c13b42e6eacb566c0b060683dcd83) is 3.7M, max 30.0M, 26.2M free. Jul 19 07:05:13 volumio-4.0 systemd-modules-load[447]: Inserted module 'i2c_dev' Jul 19 07:05:13 volumio-4.0 fake-hwclock[433]: Current system time: 2025-07-19 05:05:13 Jul 19 07:05:13 volumio-4.0 fake-hwclock[433]: fake-hwclock saved clock information is in the past: 2025-07-18 14:43:04 Jul 19 07:05:13 volumio-4.0 fake-hwclock[433]: To set system time to this saved clock anyway, use "force" Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Jul 19 07:05:13 volumio-4.0 systemd[1]: Started systemd-journald.service - Journal Service. Jul 19 07:05:13 volumio-4.0 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Jul 19 07:05:13 volumio-4.0 systemd-udevd[465]: Using default interface naming scheme 'v252'. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Jul 19 07:05:13 volumio-4.0 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Jul 19 07:05:13 volumio-4.0 systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jul 19 07:05:13 volumio-4.0 systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 19 07:05:13 volumio-4.0 systemd[1]: Reached target paths.target - Path Units. Jul 19 07:05:13 volumio-4.0 (udev-worker)[481]: Network interface NamePolicy= disabled on kernel command line. Jul 19 07:05:13 volumio-4.0 systemd[1]: Found device dev-disk-by\x2duuid-F765\x2dE57D.device - SAMSUNG MZ9LQ256HBJD-00BVL boot. Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Jul 19 07:05:13 volumio-4.0 kernel: rp1-firmware rp1_firmware: RP1 Firmware version eb39cfd516f8c90628aa9d91f52370aade5d0a55 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Jul 19 07:05:13 volumio-4.0 kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Jul 19 07:05:13 volumio-4.0 kernel: rp1-pio 1f00178000.pio: Created instance as pio0 Jul 19 07:05:13 volumio-4.0 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-F765\x2dE57D.service - File System Check on /dev/disk/by-uuid/F765-E57D... Jul 19 07:05:13 volumio-4.0 systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10. Jul 19 07:05:13 volumio-4.0 systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Jul 19 07:05:13 volumio-4.0 systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jul 19 07:05:13 volumio-4.0 systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Jul 19 07:05:13 volumio-4.0 kernel: mc: Linux media interface: v0.10 Jul 19 07:05:13 volumio-4.0 systemd-fsck[554]: fsck.fat 4.2 (2021-01-31) Jul 19 07:05:13 volumio-4.0 systemd-fsck[554]: /dev/nvme0n1p1: 566 files, 24611/93500 clusters Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-F765\x2dE57D.service - File System Check on /dev/disk/by-uuid/F765-E57D. Jul 19 07:05:13 volumio-4.0 kernel: videodev: Linux video capture interface: v2.00 Jul 19 07:05:13 volumio-4.0 (udev-worker)[473]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:13 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0 Jul 19 07:05:13 volumio-4.0 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0 Jul 19 07:05:13 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Jul 19 07:05:13 volumio-4.0 (udev-worker)[489]: mouse0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:13 volumio-4.0 kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19 Jul 19 07:05:13 volumio-4.0 systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: Core ver 2.22 Jul 19 07:05:13 volumio-4.0 kernel: NET: Registered PF_BLUETOOTH protocol family Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: HCI device and connection manager initialized Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: HCI socket layer initialized Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: L2CAP socket layer initialized Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: SCO socket layer initialized Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: HCI UART driver ver 2.3 Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: HCI UART protocol H4 registered Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 19 07:05:13 volumio-4.0 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jul 19 07:05:13 volumio-4.0 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jul 19 07:05:13 volumio-4.0 kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 19 07:05:13 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1 Jul 19 07:05:13 volumio-4.0 kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: Adding to iommu group 1 Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Jul 19 07:05:13 volumio-4.0 kernel: Console: switching to colour dummy device 80x25 Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Jul 19 07:05:13 volumio-4.0 kernel: Registered IR keymap rc-cec Jul 19 07:05:13 volumio-4.0 kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0 Jul 19 07:05:13 volumio-4.0 kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input5 Jul 19 07:05:13 volumio-4.0 kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input6 Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Jul 19 07:05:13 volumio-4.0 kernel: Registered IR keymap rc-cec Jul 19 07:05:13 volumio-4.0 kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1 Jul 19 07:05:13 volumio-4.0 kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input7 Jul 19 07:05:13 volumio-4.0 kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input8 Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jul 19 07:05:13 volumio-4.0 kernel: usbcore: registered new interface driver snd-usb-audio Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 19 07:05:13 volumio-4.0 (udev-worker)[482]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 19 07:05:13 volumio-4.0 kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1 Jul 19 07:05:13 volumio-4.0 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jul 19 07:05:13 volumio-4.0 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 19 07:05:13 volumio-4.0 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 19 07:05:13 volumio-4.0 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 19 07:05:13 volumio-4.0 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 19 07:05:13 volumio-4.0 kernel: Console: switching to colour frame buffer device 160x50 Jul 19 07:05:13 volumio-4.0 kernel: vc4-drm axi:gpu: [drm] fb0: vc4drmfb frame buffer device Jul 19 07:05:13 volumio-4.0 (udev-worker)[491]: event4: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:14 volumio-4.0 (udev-worker)[489]: event5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:14 volumio-4.0 (udev-worker)[474]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:14 volumio-4.0 (udev-worker)[476]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 19 07:05:14 volumio-4.0 (udev-worker)[476]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Jul 19 07:05:14 volumio-4.0 systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Jul 19 07:05:14 volumio-4.0 systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 19 07:05:14 volumio-4.0 systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jul 19 07:05:14 volumio-4.0 systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jul 19 07:05:14 volumio-4.0 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 19 07:05:14 volumio-4.0 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 19 07:05:14 volumio-4.0 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 19 07:05:14 volumio-4.0 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 19 07:05:14 volumio-4.0 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 19 07:05:14 volumio-4.0 (udev-worker)[481]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jul 19 07:05:14 volumio-4.0 systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounting boot.mount - /boot... Jul 19 07:05:14 volumio-4.0 systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Jul 19 07:05:14 volumio-4.0 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounting tmp.mount - /tmp... Jul 19 07:05:14 volumio-4.0 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounting var-log.mount - /var/log... Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounted tmp.mount - /tmp. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounted var-log.mount - /var/log. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Jul 19 07:05:14 volumio-4.0 systemd[1]: Mounted boot.mount - /boot. Jul 19 07:05:14 volumio-4.0 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 19 07:05:14 volumio-4.0 systemd[1]: Started ifup@eth0.service - ifup for eth0. Jul 19 07:05:14 volumio-4.0 systemd-journald[443]: Runtime Journal (/run/log/journal/843c13b42e6eacb566c0b060683dcd83) is 3.7M, max 30.0M, 26.2M free. Jul 19 07:05:14 volumio-4.0 systemd-journald[443]: Received client request to flush runtime journal. Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting networking.service - Raise network interfaces... Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jul 19 07:05:14 volumio-4.0 systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 19 07:05:14 volumio-4.0 systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 788 (systemd-binfmt) Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jul 19 07:05:14 volumio-4.0 systemd[1]: Received SIGRTMIN+20 from PID 293 (plymouthd). Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 19 07:05:14 volumio-4.0 systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jul 19 07:05:14 volumio-4.0 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 19 07:05:14 volumio-4.0 systemd[1]: Started rpcbind.service - RPC bind portmap service. Jul 19 07:05:14 volumio-4.0 systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 19 07:05:14 volumio-4.0 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 19 07:05:14 volumio-4.0 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 19 07:05:14 volumio-4.0 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 19 07:05:14 volumio-4.0 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 19 07:05:14 volumio-4.0 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 19 07:05:14 volumio-4.0 sh[797]: command failed: No such device (-19) Jul 19 07:05:14 volumio-4.0 ifup[798]: command failed: No such device (-19) Jul 19 07:05:14 volumio-4.0 haveged[802]: haveged: command socket is listening at fd 3 Jul 19 07:05:14 volumio-4.0 sh[826]: dhcpcd-9.4.1 starting Jul 19 07:05:14 volumio-4.0 dhcpcd[826]: dhcpcd-9.4.1 starting Jul 19 07:05:14 volumio-4.0 dhcpcd[836]: DUID 00:01:00:01:2f:d0:8b:e9:e4:5f:01:b4:0d:29 Jul 19 07:05:14 volumio-4.0 sh[826]: DUID 00:01:00:01:2f:d0:8b:e9:e4:5f:01:b4:0d:29 Jul 19 07:05:14 volumio-4.0 sh[826]: dhcp_vendor: No such process Jul 19 07:05:14 volumio-4.0 dhcpcd[836]: dhcp_vendor: No such process Jul 19 07:05:14 volumio-4.0 ifup[842]: command failed: No such device (-19) Jul 19 07:05:14 volumio-4.0 kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 19 07:05:14 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:00] driver [Broadcom BCM54213PE] (irq=POLL) Jul 19 07:05:14 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Jul 19 07:05:14 volumio-4.0 kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Jul 19 07:05:14 volumio-4.0 sh[826]: eth0: waiting for carrier Jul 19 07:05:14 volumio-4.0 dhcpcd[836]: eth0: waiting for carrier Jul 19 07:05:14 volumio-4.0 systemd[1]: Finished networking.service - Raise network interfaces. Jul 19 07:05:14 volumio-4.0 haveged[802]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Jul 19 07:05:14 volumio-4.0 haveged[802]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Jul 19 07:05:14 volumio-4.0 haveged[802]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00176 Jul 19 07:05:14 volumio-4.0 haveged[802]: haveged: fills: 0, generated: 0 Jul 19 07:05:15 volumio-4.0 systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jul 19 07:05:15 volumio-4.0 systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jul 19 07:05:15 volumio-4.0 systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target rpc_pipefs.target. Jul 19 07:05:15 volumio-4.0 systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target nfs-client.target - NFS client services. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 19 07:05:15 volumio-4.0 systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target sysinit.target - System Initialization. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started apt-daily.timer - Daily apt download activities. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started man-db.timer - Daily man-db regeneration. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target timers.target - Timer Units. Jul 19 07:05:15 volumio-4.0 systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jul 19 07:05:15 volumio-4.0 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 19 07:05:15 volumio-4.0 systemd[1]: Listening on triggerhappy.socket. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target sockets.target - Socket Units. Jul 19 07:05:15 volumio-4.0 systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target basic.target - Basic System. Jul 19 07:05:15 volumio-4.0 systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting bluetooth.service - Bluetooth service... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jul 19 07:05:15 volumio-4.0 systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting glamor-test.service - Check for glamor... Jul 19 07:05:15 volumio-4.0 systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jul 19 07:05:15 volumio-4.0 systemd[1]: Started iptables.service - Volumio Iptables Module. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jul 19 07:05:15 volumio-4.0 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Successfully dropped root privileges. Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: avahi-daemon 0.8 starting up. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting rp1-test.service - Check for RP1 displays for Xorg... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Jul 19 07:05:15 volumio-4.0 mpd_monitor.sh[902]: MPD Monitor Service: Starting MPD Monitor Service Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 19 07:05:15 volumio-4.0 systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jul 19 07:05:15 volumio-4.0 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jul 19 07:05:15 volumio-4.0 systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting wireless.service - Wireless Services... Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Unable to parse trigger line: Jul 19 07:05:15 volumio-4.0 thd[913]: Found socket passed from systemd Jul 19 07:05:15 volumio-4.0 volumio_rfkill_unblock.sh[916]: Volumio WiFi Soft Blocked check script Jul 19 07:05:15 volumio-4.0 volumio_rfkill_unblock.sh[916]: Check if rfkill is available Jul 19 07:05:15 volumio-4.0 systemd[1]: e2scrub_reap.service: Deactivated successfully. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jul 19 07:05:15 volumio-4.0 volumio_rfkill_unblock.sh[916]: Check if rfkill listed devices are already unblocked Jul 19 07:05:15 volumio-4.0 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jul 19 07:05:15 volumio-4.0 volumio_rfkill_unblock.sh[916]: Wi-Fi is already unblocked. Jul 19 07:05:15 volumio-4.0 alsactl[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jul 19 07:05:15 volumio-4.0 alsactl[949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jul 19 07:05:15 volumio-4.0 mpd_monitor.sh[925]: MPD error: Connection refused Jul 19 07:05:15 volumio-4.0 mpd_monitor.sh[902]: MPD Monitor Service: MPD Appears to be inactive, restarting Jul 19 07:05:15 volumio-4.0 systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jul 19 07:05:15 volumio-4.0 systemd-logind[911]: New seat seat0. Jul 19 07:05:15 volumio-4.0 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jul 19 07:05:15 volumio-4.0 systemd-logind[911]: Watching system buttons on /dev/input/event0 (pwr_button) Jul 19 07:05:15 volumio-4.0 systemd-logind[911]: Watching system buttons on /dev/input/event2 (vc4-hdmi-0) Jul 19 07:05:15 volumio-4.0 systemd-logind[911]: Watching system buttons on /dev/input/event4 (vc4-hdmi-1) Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target sound.target - Sound Card. Jul 19 07:05:15 volumio-4.0 volumio-remote-updater[915]: Volumio OTA Client: 1.7.0 Jul 19 07:05:15 volumio-4.0 volumio-remote-updater[915]: Error: No active session Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jul 19 07:05:15 volumio-4.0 volumio-remote-updater[915]: [2025-07-19 07:05:15] [info] asio async_connect error: asio.system:111 (Connection refused) Jul 19 07:05:15 volumio-4.0 volumio-remote-updater[915]: [2025-07-19 07:05:15] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jul 19 07:05:15 volumio-4.0 volumio-remote-updater[915]: [2025-07-19 07:05:15] [error] handle_connect error: Connection refused Jul 19 07:05:15 volumio-4.0 mpd_monitor.sh[958]: mpd: no process found Jul 19 07:05:15 volumio-4.0 rpi-eeprom-update[906]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0 Jul 19 07:05:15 volumio-4.0 dhcpcd[957]: dhcpcd-9.4.1 starting Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Bluetooth daemon 5.72 Jul 19 07:05:15 volumio-4.0 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 19 07:05:15 volumio-4.0 dhcpcd[980]: dev: loaded udev Jul 19 07:05:15 volumio-4.0 dhcpcd[980]: DUID 00:01:00:01:2f:d0:8b:e9:e4:5f:01:b4:0d:29 Jul 19 07:05:15 volumio-4.0 dhcpcd[980]: dhcp_vendor: No such process Jul 19 07:05:15 volumio-4.0 dhcpcd[957]: dhcp_vendor: No such process Jul 19 07:05:15 volumio-4.0 systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Starting SDP server Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Excluding (cli) wiimote Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Excluding (cli) sap Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Excluding (cli) midi Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Excluding (cli) battery Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Successfully called chroot(). Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Successfully dropped remaining capabilities. Jul 19 07:05:15 volumio-4.0 dhcpcd[980]: eth0: waiting for carrier Jul 19 07:05:15 volumio-4.0 dbus-daemon[897]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Loading service file /services/volumio.service. Jul 19 07:05:15 volumio-4.0 loadcpufreq[901]: Loading cpufreq kernel modules...done (none). Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: New relevant interface lo.IPv4 for mDNS. Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Network interface enumeration completed. Jul 19 07:05:15 volumio-4.0 avahi-daemon[895]: Registering new address record for 127.0.0.1 on lo.IPv4. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished glamor-test.service - Check for glamor. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started systemd-logind.service - User Login Management. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started bluetooth.service - Bluetooth service. Jul 19 07:05:15 volumio-4.0 upmpdcli[914]: Could not open config: /tmp/upmpdcli.conf Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting bluealsa.service - BlueALSA service... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jul 19 07:05:15 volumio-4.0 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 19 07:05:15 volumio-4.0 kernel: Bluetooth: BNEP filters: protocol multicast Jul 19 07:05:15 volumio-4.0 kernel: Bluetooth: BNEP socket layer initialized Jul 19 07:05:15 volumio-4.0 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 19 07:05:15 volumio-4.0 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 19 07:05:15 volumio-4.0 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jul 19 07:05:15 volumio-4.0 bluetoothd[896]: Bluetooth management interface 1.23 initialized Jul 19 07:05:15 volumio-4.0 dbus-daemon[897]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=0 pid=896 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished rp1-test.service - Check for RP1 displays for Xorg. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jul 19 07:05:15 volumio-4.0 systemd[1]: iptables.service: Deactivated successfully. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jul 19 07:05:15 volumio-4.0 wpa_supplicant[1040]: Successfully initialized wpa_supplicant Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target network.target - Network. Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target network-online.target - Network is Online. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting mpd.service - Music Player Daemon... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting ntpsec.service - Network Time Service... Jul 19 07:05:15 volumio-4.0 cpufrequtils[1049]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Jul 19 07:05:15 volumio-4.0 sm-notify[1076]: Version 2.6.2 starting Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jul 19 07:05:15 volumio-4.0 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 19 07:05:15 volumio-4.0 dbus-daemon[897]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 19 07:05:15 volumio-4.0 systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 19 07:05:15 volumio-4.0 ntpd[1079]: INIT: ntpd ntpsec-1.2.2: Starting Jul 19 07:05:15 volumio-4.0 ntp-systemd-wrapper[1079]: 2025-07-19T07:05:15 ntpd[1079]: INIT: ntpd ntpsec-1.2.2: Starting Jul 19 07:05:15 volumio-4.0 ntp-systemd-wrapper[1079]: 2025-07-19T07:05:15 ntpd[1079]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jul 19 07:05:15 volumio-4.0 ntpd[1079]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jul 19 07:05:15 volumio-4.0 dbus-daemon[897]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1083 comm="timedatectl show --property=NTPSynchronized --valu") Jul 19 07:05:15 volumio-4.0 systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started ntpsec.service - Network Time Service. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: precision = 0.370 usec (-21) Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: successfully locked into RAM Jul 19 07:05:15 volumio-4.0 ntpd[1094]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Jul 19 07:05:15 volumio-4.0 ntpd[1094]: CONFIG: restrict nopeer ignored Jul 19 07:05:15 volumio-4.0 ntpd[1094]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 19 07:05:15 volumio-4.0 ntpd[1094]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2025-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: Using SO_TIMESTAMPNS(ns) Jul 19 07:05:15 volumio-4.0 ntpd[1094]: IO: Listen and drop on 0 v6wildcard [::]:123 Jul 19 07:05:15 volumio-4.0 ntpd[1094]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 19 07:05:15 volumio-4.0 ntpd[1094]: IO: Listen normally on 2 lo 127.0.0.1:123 Jul 19 07:05:15 volumio-4.0 ntpd[1094]: IO: Listening on routing socket on fd #19 for interface updates Jul 19 07:05:15 volumio-4.0 welcome[1090]: Resolved ip:[0] Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: This system has a 32-bit time_t. Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Jul 19 07:05:15 volumio-4.0 ntpd[1094]: INIT: Running with OpenSSL 3.0.16 11 Feb 2025, 30000100 Jul 19 07:05:15 volumio-4.0 ntpd[1094]: NTSc: Using system default root certificates. Jul 19 07:05:15 volumio-4.0 rpi-eeprom-update[906]: BOOTLOADER: up to date Jul 19 07:05:15 volumio-4.0 rpi-eeprom-update[906]: CURRENT: Thu May 8 14:13:17 UTC 2025 (1746713597) Jul 19 07:05:15 volumio-4.0 rpi-eeprom-update[906]: LATEST: Thu May 8 14:13:17 UTC 2025 (1746713597) Jul 19 07:05:15 volumio-4.0 rpi-eeprom-update[906]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default) Jul 19 07:05:15 volumio-4.0 rpi-eeprom-update[906]: Use raspi-config to change the release. Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 19 07:05:15 volumio-4.0 ifplugd(eth0)[1126]: ifplugd 0.28 initializing. Jul 19 07:05:15 volumio-4.0 ifplugd(eth0)[1126]: Using interface eth0/2C:CF:67:C2:73:FB with driver (version: 6.12.34-v8+) Jul 19 07:05:15 volumio-4.0 ifplugd(eth0)[1126]: Using detection mode: SIOCETHTOOL Jul 19 07:05:15 volumio-4.0 ifplugd(eth0)[1126]: Initialization complete, link beat not detected. Jul 19 07:05:15 volumio-4.0 ntpd[1094]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Jul 19 07:05:15 volumio-4.0 sshd[1127]: Server listening on 0.0.0.0 port 22. Jul 19 07:05:15 volumio-4.0 sshd[1127]: Server listening on :: port 22. Jul 19 07:05:15 volumio-4.0 systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Jul 19 07:05:15 volumio-4.0 dbus-daemon[897]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 19 07:05:15 volumio-4.0 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 19 07:05:15 volumio-4.0 bash[1082]: Time is already synchronized. Jul 19 07:05:15 volumio-4.0 systemd[1]: setdatetime-helper.service: Deactivated successfully. Jul 19 07:05:15 volumio-4.0 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jul 19 07:05:15 volumio-4.0 bluealsa[1048]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Jul 19 07:05:15 volumio-4.0 bluealsa[1048]: main.c:663: Starting main dispatching loop Jul 19 07:05:15 volumio-4.0 systemd[1]: Started bluealsa.service - BlueALSA service. Jul 19 07:05:15 volumio-4.0 bluealsa[1048]: main.c:118: Acquired D-Bus service name: org.bluealsa Jul 19 07:05:15 volumio-4.0 bluealsa[1048]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Jul 19 07:05:15 volumio-4.0 systemd[1]: Reached target bluetooth.target - Bluetooth Support. Jul 19 07:05:15 volumio-4.0 dbus-daemon[897]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.8' (uid=0 pid=1048 comm="/usr/bin/bluealsa -S -p a2dp-source -p a2dp-sink") Jul 19 07:05:15 volumio-4.0 ifplugd[1064]: Network Interface Plugging Daemon...start eth0...done. Jul 19 07:05:15 volumio-4.0 sudo[1068]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 07:05:15 volumio-4.0 sudo[1068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 19 07:05:15 volumio-4.0 sudo[1139]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 19 07:05:15 volumio-4.0 sudo[1068]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:15 volumio-4.0 systemd[1]: Starting upower.service - Daemon for power management... Jul 19 07:05:15 volumio-4.0 systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Jul 19 07:05:16 volumio-4.0 dbus-daemon[897]: [system] Successfully activated service 'org.freedesktop.UPower' Jul 19 07:05:16 volumio-4.0 systemd[1]: Started upower.service - Daemon for power management. Jul 19 07:05:16 volumio-4.0 kernel: Bluetooth: hci0: command 0xfc18 tx timeout Jul 19 07:05:16 volumio-4.0 kernel: Bluetooth: hci0: BCM: failed to write update baudrate (-110) Jul 19 07:05:16 volumio-4.0 kernel: Bluetooth: hci0: Failed to set baudrate Jul 19 07:05:16 volumio-4.0 systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Jul 19 07:05:16 volumio-4.0 systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Jul 19 07:05:16 volumio-4.0 iw[1168]: command failed: No such device (-19) Jul 19 07:05:16 volumio-4.0 iwconfig[1171]: Error for wireless request "Set Power Management" (8B2C) : Jul 19 07:05:16 volumio-4.0 iwconfig[1171]: SET failed on device wlan0 ; No such device. Jul 19 07:05:16 volumio-4.0 systemd[1]: wireless.service: Deactivated successfully. Jul 19 07:05:16 volumio-4.0 systemd[1]: Started wireless.service - Wireless Services. Jul 19 07:05:16 volumio-4.0 systemd[1]: Started volumio.service - Volumio Backend Module. Jul 19 07:05:16 volumio-4.0 avahi-daemon[895]: Server startup complete. Host name is volumio-4.local. Local service cookie is 3581652264. Jul 19 07:05:16 volumio-4.0 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jul 19 07:05:16 volumio-4.0 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jul 19 07:05:16 volumio-4.0 systemd[1]: Received SIGRTMIN+21 from PID 293 (plymouthd). Jul 19 07:05:16 volumio-4.0 volumio-cpu-tweak[1176]: Setting RT Priority for mpd Jul 19 07:05:16 volumio-4.0 volumio-cpu-tweak[1176]: Setting MPD Affinity Jul 19 07:05:16 volumio-4.0 volumio-cpu-tweak[1192]: pid 1141's current affinity mask: f Jul 19 07:05:16 volumio-4.0 volumio-cpu-tweak[1192]: pid 1141's new affinity mask: 3 Jul 19 07:05:16 volumio-4.0 volumio-cpu-tweak[1176]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 19 07:05:16 volumio-4.0 volumio-cpu-tweak[1176]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Jul 19 07:05:16 volumio-4.0 systemd[1]: Received SIGRTMIN+21 from PID 293 (plymouthd). Jul 19 07:05:16 volumio-4.0 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jul 19 07:05:16 volumio-4.0 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jul 19 07:05:16 volumio-4.0 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jul 19 07:05:16 volumio-4.0 systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Jul 19 07:05:16 volumio-4.0 systemd[1]: Reached target getty.target - Login Prompts. Jul 19 07:05:16 volumio-4.0 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 19 07:05:16 volumio-4.0 systemd[1]: Reached target graphical.target - Graphical Interface. Jul 19 07:05:16 volumio-4.0 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jul 19 07:05:16 volumio-4.0 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 19 07:05:16 volumio-4.0 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jul 19 07:05:16 volumio-4.0 ntpd[1094]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:16 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jul 19 07:05:16 volumio-4.0 ntpd[1094]: DNS: dns_check: DNS error: -11, System error Jul 19 07:05:16 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jul 19 07:05:17 volumio-4.0 avahi-daemon[895]: Service "Volumio 4.0" (/services/volumio.service) successfully established. Jul 19 07:05:17 volumio-4.0 mpd[1141]: 2025-07-19T07:05:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ----- Volumio3 ---- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ----- System startup ---- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:17 volumio-4.0 systemd[1]: Started mpd.service - Music Player Daemon. Jul 19 07:05:17 volumio-4.0 systemd[1]: Startup finished in 10.916s (kernel) + 4.697s (userspace) = 15.613s. Jul 19 07:05:17 volumio-4.0 mpd_monitor.sh[902]: MPD Monitor Service: MPD restarted due to no mpc output. Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: MYVOLUMIO Environment detected Jul 19 07:05:17 volumio-4.0 ntpd[1094]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:17 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jul 19 07:05:17 volumio-4.0 ntpd[1094]: DNS: dns_check: DNS error: -11, System error Jul 19 07:05:17 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Plugin folders cleanup Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning into folder /volumio/app/plugins/ Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category audio_interface Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category miscellanea Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category music_service Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category plugins.json Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category system_controller Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category user_interface Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning into folder /data/plugins/ Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category music_service Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Scanning category user_interface Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Plugin folders cleanup completed Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ----- Core plugins startup ---- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Loading plugins from folder /volumio/app/plugins/ Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Adding plugin upnp to MyMusic Plugins Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Loading plugins from folder /data/plugins/ Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Loading plugin "system"... Jul 19 07:05:17 volumio-4.0 volumio[1174]: info: Loading plugin "appearance"... Jul 19 07:05:18 volumio-4.0 kernel: Bluetooth: hci0: command 0xfc18 tx timeout Jul 19 07:05:18 volumio-4.0 kernel: Bluetooth: hci0: BCM: Reset failed (-110) Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Loading plugin "network"... Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Refreshing Cached IP Addresses Jul 19 07:05:18 volumio-4.0 sudo[1215]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 07:05:18 volumio-4.0 sudo[1215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:18 volumio-4.0 sudo[1217]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 07:05:18 volumio-4.0 sudo[1217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:18 volumio-4.0 sudo[1215]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Loading plugin "services"... Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Loading plugin "alsa_controller"... Jul 19 07:05:18 volumio-4.0 sudo[1227]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 19 07:05:18 volumio-4.0 sudo[1227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:18 volumio-4.0 sudo[1217]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:18 volumio-4.0 sudo[1227]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Loading plugin "wizard"... Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Loading plugin "networkfs"... Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Starting Udev Watcher for removable devices Jul 19 07:05:18 volumio-4.0 sudo[1256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Underwood,password=rts%C0ntax_7c,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.123/music /mnt/NAS/DS216 Jul 19 07:05:18 volumio-4.0 sudo[1256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Ignoring mount for partition: boot Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Ignoring mount for partition: volumio Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Ignoring mount for partition: volumio_data Jul 19 07:05:18 volumio-4.0 volumio[1174]: info: Mounting Device ROSEDISK Jul 19 07:05:18 volumio-4.0 kernel: netfs: FS-Cache loaded Jul 19 07:05:18 volumio-4.0 sudo[1267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ROSEDISK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 19 07:05:18 volumio-4.0 sudo[1267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:18 volumio-4.0 kernel: Key type cifs.spnego registered Jul 19 07:05:18 volumio-4.0 kernel: Key type cifs.idmap registered Jul 19 07:05:18 volumio-4.0 sudo[1256]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:18 volumio-4.0 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 19 07:05:18 volumio-4.0 kernel: CIFS: Attempting to mount //192.168.1.123/music Jul 19 07:05:18 volumio-4.0 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 19 07:05:18 volumio-4.0 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jul 19 07:05:18 volumio-4.0 dhcpcd[836]: eth0: carrier acquired Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: carrier acquired Jul 19 07:05:18 volumio-4.0 sh[826]: eth0: carrier acquired Jul 19 07:05:18 volumio-4.0 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control tx Jul 19 07:05:18 volumio-4.0 dhcpcd[836]: eth0: IAID 67:c2:73:fb Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: IAID 67:c2:73:fb Jul 19 07:05:18 volumio-4.0 sh[826]: eth0: IAID 67:c2:73:fb Jul 19 07:05:18 volumio-4.0 dhcpcd[836]: eth0: adding address fe80::a29e:7d86:eb08:8fef Jul 19 07:05:18 volumio-4.0 sh[826]: eth0: adding address fe80::a29e:7d86:eb08:8fef Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: adding address fe80::a29e:7d86:eb08:8fef Jul 19 07:05:18 volumio-4.0 dhcpcd[836]: ipv6_addaddr1: Permission denied Jul 19 07:05:18 volumio-4.0 sh[826]: ipv6_addaddr1: Permission denied Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: ipv6_addaddr1: Permission denied Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: soliciting an IPv6 router Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: soliciting a DHCP lease Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: offered 192.168.1.134 from 192.168.1.1 Jul 19 07:05:18 volumio-4.0 dhcpcd[980]: eth0: probing address 192.168.1.134/24 Jul 19 07:05:18 volumio-4.0 ntpd[1094]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:18 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jul 19 07:05:18 volumio-4.0 ntpd[1094]: DNS: dns_check: DNS error: -11, System error Jul 19 07:05:18 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jul 19 07:05:18 volumio-4.0 ifplugd(eth0)[1126]: Link beat detected. Jul 19 07:05:19 volumio-4.0 ifplugd(eth0)[1126]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 19 07:05:19 volumio-4.0 ifplugd(eth0)[1126]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 19 07:05:19 volumio-4.0 systemd[1]: systemd-rfkill.service: Deactivated successfully. Jul 19 07:05:19 volumio-4.0 ntfs-3g[1290]: Version 2022.10.3 integrated FUSE 28 Jul 19 07:05:19 volumio-4.0 ntfs-3g[1290]: Mounted /dev/sda1 (Read-Write, label "ROSEDISK", NTFS 3.1) Jul 19 07:05:19 volumio-4.0 ntfs-3g[1290]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 19 07:05:19 volumio-4.0 ntfs-3g[1290]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jul 19 07:05:19 volumio-4.0 ntfs-3g[1290]: Global ownership and permissions enforced, configuration type 7 Jul 19 07:05:19 volumio-4.0 sudo[1267]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "volumio_command_line_client"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "upnp"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: [1752901519310] Starting Upmpd Daemon Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "my_music"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "mpd"... Jul 19 07:05:19 volumio-4.0 sh[826]: eth0: soliciting a DHCP lease Jul 19 07:05:19 volumio-4.0 dhcpcd[836]: eth0: soliciting a DHCP lease Jul 19 07:05:19 volumio-4.0 dhcpcd[836]: eth0: offered 192.168.1.134 from 192.168.1.1 Jul 19 07:05:19 volumio-4.0 sh[826]: eth0: offered 192.168.1.134 from 192.168.1.1 Jul 19 07:05:19 volumio-4.0 sh[826]: eth0: probing address 192.168.1.134/24 Jul 19 07:05:19 volumio-4.0 dhcpcd[836]: eth0: probing address 192.168.1.134/24 Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "upnp_browser"... Jul 19 07:05:19 volumio-4.0 sh[826]: eth0: soliciting an IPv6 router Jul 19 07:05:19 volumio-4.0 dhcpcd[836]: eth0: soliciting an IPv6 router Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "alarm-clock"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "airplay_emulation"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Starting Shairport Sync Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "last_100"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "webradio"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "i2s_dacs"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "volumiodiscovery"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 07:05:19 volumio-4.0 volumio[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 07:05:19 volumio-4.0 volumio[1174]: *** WARNING *** For more information see Jul 19 07:05:19 volumio-4.0 volumio[1174]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 07:05:19 volumio-4.0 volumio[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 07:05:19 volumio-4.0 volumio[1174]: *** WARNING *** For more information see Jul 19 07:05:19 volumio-4.0 node[1174]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 07:05:19 volumio-4.0 node[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 07:05:19 volumio-4.0 node[1174]: *** WARNING *** For more information see Jul 19 07:05:19 volumio-4.0 node[1174]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 07:05:19 volumio-4.0 node[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 07:05:19 volumio-4.0 node[1174]: *** WARNING *** For more information see Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Discovery: Started advertising with name: Volumio 4.0 Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "outputs"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "albumart"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Plugin example_plugin is not enabled Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "inputs"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "updater_comm"... Jul 19 07:05:19 volumio-4.0 ntpd[1094]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:19 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jul 19 07:05:19 volumio-4.0 ntpd[1094]: DNS: dns_check: DNS error: -11, System error Jul 19 07:05:19 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Plugin mpdemulation is not enabled Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "rest_api"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "websocket"... Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Starting Socket.io Server version 1.7.4 Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "motherearthradio"... Jul 19 07:05:19 volumio-4.0 volumio[1296]: Forking 3 albumart workers Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Applying required configuration parameters for plugin motherearthradio Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: [1752901519957] [MotherEarth] API delay: 6 Jul 19 07:05:19 volumio-4.0 volumio[1174]: info: Loading plugin "Systeminfo"... Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Loading plugin "touch_display"... Jul 19 07:05:20 volumio-4.0 volumio[1308]: Starting albumart workers Jul 19 07:05:20 volumio-4.0 volumio[1309]: Starting albumart workers Jul 19 07:05:20 volumio-4.0 volumio[1307]: Starting albumart workers Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Applying required configuration parameters for plugin touch_display Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Loading i18n strings for locale de Jul 19 07:05:20 volumio-4.0 volumio[1174]: Updating browse sources language Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::initPlayerControls Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: Express server listening on port 3000 Jul 19 07:05:20 volumio-4.0 volumio[1174]: [Metrics] WebUI: 3s 95.47ms Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::resetVolumioState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::getcurrentVolume Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Volumio Network Manager: Network status updated: 0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Cannot mount NAS DS216 at system boot, trial number 1 ,retrying in 5 seconds Jul 19 07:05:20 volumio-4.0 volumio[1174]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 19 07:05:20 volumio-4.0 volumio[1174]: wlan0 Interface doesn't support scanning. Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Cannot use regular scanning, forcing with ap-force Jul 19 07:05:20 volumio-4.0 sudo[1363]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 19 07:05:20 volumio-4.0 sudo[1363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1363]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:20 volumio-4.0 volumio[1174]: command failed: No such device (-19) Jul 19 07:05:20 volumio-4.0 volumio[1174]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 19 07:05:20 volumio-4.0 volumio[1174]: command failed: No such device (-19) Jul 19 07:05:20 volumio-4.0 volumio-remote-updater[915]: [2025-07-19 07:05:20] [connect] Successful connection Jul 19 07:05:20 volumio-4.0 volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Reloading queue from file Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: VolumeController:: Volume=96 Mute =false Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::pushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioPushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::updateTrackBlock Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrackBlock Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::setRepeat false single undefined Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::pushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioPushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::setRandom null Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::pushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioPushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Setting Device type: Raspberry PI Jul 19 07:05:20 volumio-4.0 volumio-remote-updater[915]: [2025-07-19 07:05:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1752901520 101 Jul 19 07:05:20 volumio-4.0 volumio[1174]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c041a0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: VolumeController:: Volume=96 Mute =false Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreStateMachine::pushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioPushState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Completed loading Core Plugins Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Preparing to generate the ALSA configuration file Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Asound.conf file unchanged, so no further update is needed Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Output device has changed, restarting MPD Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Output device has changed, restarting Shairport Sync Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 sudo[1379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 07:05:20 volumio-4.0 sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 07:05:20 volumio-4.0 sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1379]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: ___________ START PLUGINS ___________ Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: ControllerMpd::onStart: Initializing MPD Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Creating MPD Configuration file Jul 19 07:05:20 volumio-4.0 sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 07:05:20 volumio-4.0 sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 07:05:20 volumio-4.0 sudo[1389]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: [1752901520538] CoreMusicLibrary::Adding element Medienserver Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 07:05:20 volumio-4.0 systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 sudo[1391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 07:05:20 volumio-4.0 sudo[1391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: [1752901520565] CoreMusicLibrary::Adding element Last_100 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: [1752901520566] CoreMusicLibrary::Adding element Webradio Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Initializing BBC Radios Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: [1752901520596] CoreMusicLibrary::Adding element Mother Earth Radio Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 07:05:20 volumio-4.0 volumio[1174]: Cannot find translation for source Mother Earth Radio Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Loading i18n strings for locale de Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Volumio Calling Home Jul 19 07:05:20 volumio-4.0 sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 19 07:05:20 volumio-4.0 sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 19 07:05:20 volumio-4.0 sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 19 07:05:20 volumio-4.0 sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 systemd[1]: Reloading. Jul 19 07:05:20 volumio-4.0 volumio[1174]: Unhandled rejection Error: No sockets available, cannot start. Jul 19 07:05:20 volumio-4.0 volumio[1174]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 19 07:05:20 volumio-4.0 volumio[1174]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 19 07:05:20 volumio-4.0 volumio[1174]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: touch_display: No backlight interface detected. Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: MPD Permissions set Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: MPD Permissions set Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Discovery: adding 4b1bb0d3-be4f-4462-be1f-474d9ad5fc5a Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Discovery: Found device Volumio 4.0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:20 volumio-4.0 volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Starting Shairport Sync Jul 19 07:05:20 volumio-4.0 sudo[1460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 19 07:05:20 volumio-4.0 sudo[1460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Starting Shairport Sync Jul 19 07:05:20 volumio-4.0 sudo[1460]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:20 volumio-4.0 sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: Starting Shairport Sync Jul 19 07:05:20 volumio-4.0 sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1462]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:20 volumio-4.0 sudo[1465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 07:05:20 volumio-4.0 sudo[1465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 07:05:20 volumio-4.0 sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 sudo[1470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 07:05:20 volumio-4.0 sudo[1470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:20 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:21 volumio-4.0 systemd[1]: mpd.service: Deactivated successfully. Jul 19 07:05:21 volumio-4.0 systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 19 07:05:21 volumio-4.0 systemd[1]: mpd.service: Consumed 1.507s CPU time. Jul 19 07:05:21 volumio-4.0 sudo[1425]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 systemd[1]: Reloading. Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 19 07:05:21 volumio-4.0 sudo[1427]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: systemctl daemon-reload succeeded. Jul 19 07:05:21 volumio-4.0 sudo[1494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 19 07:05:21 volumio-4.0 sudo[1494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:21 volumio-4.0 systemd[1]: mpd.socket: Deactivated successfully. Jul 19 07:05:21 volumio-4.0 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 19 07:05:21 volumio-4.0 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 19 07:05:21 volumio-4.0 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 19 07:05:21 volumio-4.0 systemd[1]: Starting mpd.service - Music Player Daemon... Jul 19 07:05:21 volumio-4.0 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 19 07:05:21 volumio-4.0 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jul 19 07:05:21 volumio-4.0 sudo[1494]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: Volumio Kiosk started. Jul 19 07:05:21 volumio-4.0 systemd[1]: shairport-sync.service: Deactivated successfully. Jul 19 07:05:21 volumio-4.0 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:21 volumio-4.0 systemd[1]: shairport-sync.service: Consumed 1.577s CPU time. Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:21 volumio-4.0 sudo[1423]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: X display number found: Jul 19 07:05:21 volumio-4.0 sudo[1497]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 07:05:21 volumio-4.0 sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 19 07:05:21 volumio-4.0 sudo[1497]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 startx[1524]: X.Org X Server 1.21.1.7 Jul 19 07:05:21 volumio-4.0 startx[1524]: X Protocol Version 11, Revision 0 Jul 19 07:05:21 volumio-4.0 startx[1524]: Current Operating System: Linux volumio-4.0 6.12.34-v8+ #1889 SMP PREEMPT Mon Jun 23 11:11:06 BST 2025 aarch64 Jul 19 07:05:21 volumio-4.0 startx[1524]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 smsc95xx.macaddr=2C:CF:67:C2:73:FB vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=1fc6c06a-b72e-4168-bec9-b4f19a5ca3fc imgfile=/volumio_current.sqsh bootpart=UUID=F765-E57D datapart=UUID=c3dc73c1-8a1c-4c0b-9b92-b131c91bd728 uuidconfig=cmdline.txt 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 Jul 19 07:05:21 volumio-4.0 startx[1524]: xorg-server 2:21.1.7-3+rpt3+deb12u9 (https://www.debian.org/support) Jul 19 07:05:21 volumio-4.0 startx[1524]: Current version of pixman: 0.44.0 Jul 19 07:05:21 volumio-4.0 startx[1524]: Before reporting problems, check http://wiki.x.org Jul 19 07:05:21 volumio-4.0 startx[1524]: to make sure that you have the latest version. Jul 19 07:05:21 volumio-4.0 startx[1524]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 19 07:05:21 volumio-4.0 startx[1524]: (++) from command line, (!!) notice, (II) informational, Jul 19 07:05:21 volumio-4.0 startx[1524]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 19 07:05:21 volumio-4.0 startx[1524]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jul 19 07:05:21 2025 Jul 19 07:05:21 volumio-4.0 startx[1524]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 19 07:05:21 volumio-4.0 startx[1524]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 19 07:05:21 volumio-4.0 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 19 07:05:21 volumio-4.0 sudo[1465]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 sudo[1470]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: Shairport-Sync Started Jul 19 07:05:21 volumio-4.0 volumio[1174]: Error adding Membership: Error: addMembership EINVAL Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: Shairport-Sync Started Jul 19 07:05:21 volumio-4.0 sudo[1468]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: Shairport-Sync Started Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: X display number found: 0 Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 19 07:05:21 volumio-4.0 volumio[1174]: info: touch_display: X display number found: 0 Jul 19 07:05:21 volumio-4.0 mpd[1525]: 2025-07-19T07:05:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 07:05:22 volumio-4.0 systemd[1]: Started mpd.service - Music Player Daemon. Jul 19 07:05:22 volumio-4.0 sudo[1381]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:22 volumio-4.0 sudo[1391]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:22 volumio-4.0 volumio[1174]: error: updateQueue error: null Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: Completed starting Core Plugins Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: ----- MyVolumio plugins startup ---- Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: ------------------------------------------- Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 19 07:05:22 volumio-4.0 volumio[1174]: error: updateQueue error: null Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: MPD running with PID1525 Jul 19 07:05:22 volumio-4.0 volumio[1174]: ,establishing connection Jul 19 07:05:22 volumio-4.0 volumio[1174]: error: updateQueue error: null Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:22 volumio-4.0 volumio[1174]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 19 07:05:24 volumio-4.0 dhcpcd[980]: eth0: leased 192.168.1.134 for 86400 seconds Jul 19 07:05:24 volumio-4.0 avahi-daemon[895]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.134. Jul 19 07:05:24 volumio-4.0 avahi-daemon[895]: New relevant interface eth0.IPv4 for mDNS. Jul 19 07:05:24 volumio-4.0 avahi-daemon[895]: Registering new address record for 192.168.1.134 on eth0.IPv4. Jul 19 07:05:24 volumio-4.0 dhcpcd[980]: eth0: adding route to 192.168.1.0/24 Jul 19 07:05:24 volumio-4.0 systemd[1]: welcome.service: Deactivated successfully. Jul 19 07:05:24 volumio-4.0 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 19 07:05:24 volumio-4.0 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 19 07:05:24 volumio-4.0 dhcpcd[980]: eth0: adding default route via 192.168.1.1 Jul 19 07:05:24 volumio-4.0 systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 19 07:05:24 volumio-4.0 welcome[1777]: Resolved ip:[1] 192.168.1.134 Jul 19 07:05:24 volumio-4.0 systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 19 07:05:24 volumio-4.0 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jul 19 07:05:24 volumio-4.0 volumio[1174]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 19 07:05:24 volumio-4.0 dhcpcd[836]: eth0: leased 192.168.1.134 for 86400 seconds Jul 19 07:05:24 volumio-4.0 sh[826]: eth0: leased 192.168.1.134 for 86400 seconds Jul 19 07:05:24 volumio-4.0 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jul 19 07:05:24 volumio-4.0 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jul 19 07:05:24 volumio-4.0 systemd[1]: welcome.service: Deactivated successfully. Jul 19 07:05:24 volumio-4.0 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 19 07:05:24 volumio-4.0 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 19 07:05:24 volumio-4.0 sh[826]: forked to background, child pid 835 Jul 19 07:05:24 volumio-4.0 dhcpcd[836]: eth0: adding route to 192.168.1.0/24 Jul 19 07:05:24 volumio-4.0 dhcpcd[836]: eth0: adding default route via 192.168.1.1 Jul 19 07:05:24 volumio-4.0 welcome[1812]: Resolved ip:[1] 192.168.1.134 Jul 19 07:05:24 volumio-4.0 systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 19 07:05:24 volumio-4.0 systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 19 07:05:24 volumio-4.0 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jul 19 07:05:24 volumio-4.0 ifplugd(eth0)[1126]: client: ifup: interface eth0 already configured Jul 19 07:05:24 volumio-4.0 sh[1844]: eth0=eth0 Jul 19 07:05:24 volumio-4.0 ifplugd(eth0)[1126]: Program executed successfully. Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: Discovery: this is already registered, 4b1bb0d3-be4f-4462-be1f-474d9ad5fc5a Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: Discovery: Found device Volumio 4.0 Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:25 volumio-4.0 volumio[1174]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: Received Get System Info Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: Discovery: Getting this device information Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: Listing playlists Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 19 07:05:25 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 07:05:25 volumio-4.0 sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Underwood,password=rts%C0ntax_7c,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.123/music /mnt/NAS/DS216 Jul 19 07:05:25 volumio-4.0 sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:25 volumio-4.0 kernel: CIFS: Attempting to mount //192.168.1.123/music Jul 19 07:05:25 volumio-4.0 ntpd[1094]: IO: Listen normally on 3 eth0 192.168.1.134:123 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: IO: new interface(s) found: waking up resolver Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: Pool taking: 193.134.29.12 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: Pool taking: 81.94.123.17 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: Pool taking: 81.94.123.16 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: Pool taking: 217.147.208.1 Jul 19 07:05:25 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: Received Get System Info Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: Discovery: Getting this device information Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 212.51.144.47 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 62.12.173.11 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 82.197.162.18 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 62.220.129.71 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 2a02:168:420b:d::12 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 2001:1600:4:1::f Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 2001:1600:4:1::123 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: Pool taking: 2a0b:ee80:e:dead::42 Jul 19 07:05:26 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jul 19 07:05:26 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 19 07:05:27 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: Pool taking: 109.233.182.116 Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: Pool taking: 83.150.25.25 Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: Pool taking: 130.60.204.10 Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: Pool taking: 79.110.235.11 Jul 19 07:05:27 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: Received Get System Info Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: Discovery: Getting this device information Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CoreCommandRouter::volumioGetState Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CorePlayQueue::getTrack 0 Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: Volumio Network Manager: Network status updated: 1 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: Pool taking: 85.195.227.68 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: Pool taking: 217.147.223.78 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: Pool taking: 91.235.212.22 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: Pool taking: 109.233.182.115 Jul 19 07:05:28 volumio-4.0 ntpd[1094]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jul 19 07:05:28 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 19 07:05:29 volumio-4.0 sudo[1869]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 07:05:29 volumio-4.0 sudo[1869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:29 volumio-4.0 sudo[1869]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:29 volumio-4.0 sudo[1872]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 07:05:29 volumio-4.0 sudo[1872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:29 volumio-4.0 sudo[1872]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:29 volumio-4.0 volumio[1174]: Cannot compose Albumart path Jul 19 07:05:30 volumio-4.0 sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 19 07:05:30 volumio-4.0 sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:30 volumio-4.0 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jul 19 07:05:30 volumio-4.0 sudo[1877]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Upmpdcli Daemon Started Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin bluetooth to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin multiroom to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin metavolumio to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin cd_controller to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: Starting MyVolumio Remote Streaming Endpoints Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: MyVolumio login type: Token Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 19 07:05:30 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: Streaming services startup Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: Starting Streaming Daemon Jul 19 07:05:31 volumio-4.0 sudo[1897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 19 07:05:31 volumio-4.0 sudo[1897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.016&uuid=3ffe497c2641062416e148af26326275" http://updates.volumio.org/downloader-v1/track-device Jul 19 07:05:31 volumio-4.0 volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 19 07:05:31 volumio-4.0 volumio[1174]: Dload Upload Total Spent Left Speed Jul 19 07:05:31 volumio-4.0 volumio[1174]: [132B blob data] Jul 19 07:05:31 volumio-4.0 volumio[1174]: retrying in 5 seconds, trial 0 Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: Volumio Calling Home Jul 19 07:05:31 volumio-4.0 sudo[1897]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:31 volumio-4.0 volumio[1174]: error: Cannot start Volumio Streaming Daemon Jul 19 07:05:31 volumio-4.0 volumio[1174]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 19 07:05:31 volumio-4.0 volumio[1174]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: Volumio called home Jul 19 07:05:31 volumio-4.0 volumio[1174]: error: MyVolumio Custom Token format not valid, refreshing it Jul 19 07:05:31 volumio-4.0 sudo[1846]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:31 volumio-4.0 volumio[1174]: info: Cannot mount NAS DS216 at system boot, trial number 2 ,retrying in 5 seconds Jul 19 07:05:31 volumio-4.0 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 19 07:05:31 volumio-4.0 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 19 07:05:32 volumio-4.0 ntpd[1094]: CLOCK: time stepped by 1.066981 Jul 19 07:05:32 volumio-4.0 ntpd[1094]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MyVolumio login type: Token Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MyVolumio token set successfully Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MYVOLUMIO: Adding device Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MYVOLUMIO: Evaluating Server Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MyVolumio Plan changed: premium Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: Removing browser output: myVolumio user plan is not superstar Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: Removing audio output: Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MYVOLUMIO: Adding device Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: MYVOLUMIO: Evaluating Server Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: Remote config written successfully Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: Starting Tunnel 1 Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: Starting Tunnel Connection Checker Jul 19 07:05:33 volumio-4.0 volumio[1174]: info: Completed starting MyVolumio Plugin Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: MYVolumio Device enabled Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: MyVolumio status changed Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: Streaming services startup Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: Starting Streaming Daemon Jul 19 07:05:34 volumio-4.0 sudo[1939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 19 07:05:34 volumio-4.0 sudo[1939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 19 07:05:34 volumio-4.0 volumio[1174]: error: [MyVolumio PluginManager] Cache data is invalid! Jul 19 07:05:34 volumio-4.0 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 19 07:05:34 volumio-4.0 sudo[1939]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:34 volumio-4.0 volumio[1174]: error: Cannot start Volumio Streaming Daemon Jul 19 07:05:34 volumio-4.0 volumio[1174]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 19 07:05:34 volumio-4.0 volumio[1174]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 19 07:05:34 volumio-4.0 setdatetime-helper.sh[1941]: Time is not synchronized. Attempting to sync... Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: Setting Geolocation for MyVolumio to eu10 Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:34 volumio-4.0 volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 07:05:34 volumio-4.0 sudo[1949]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 19 Jul 2025 05:05:34 GMT#015' Jul 19 07:05:34 volumio-4.0 sudo[1949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 19 07:05:34 volumio-4.0 setdatetime-helper.sh[1950]: Sat Jul 19 07:05:34 CEST 2025 Jul 19 07:05:34 volumio-4.0 sudo[1949]: pam_unix(sudo:session): session closed for user root Jul 19 07:05:34 volumio-4.0 setdatetime-helper.sh[1941]: Time synchronized successfully. Jul 19 07:05:34 volumio-4.0 systemd-journald[443]: Time jumped backwards, rotating. Jul 19 07:05:34 volumio-4.0 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 19 07:05:34 volumio-4.0 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 19 07:05:34 volumio-4.0 volumio[1174]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Jul 19 07:05:34 volumio-4.0 volumio[1174]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 07:05:34 volumio-4.0 volumio[1174]: Error: Unable to resolve or reject the same promise twice Jul 19 07:05:34 volumio-4.0 volumio[1174]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 19 07:05:34 volumio-4.0 volumio[1174]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32090) Jul 19 07:05:34 volumio-4.0 volumio[1174]: at Socket.emit (node:events:526:35) Jul 19 07:05:34 volumio-4.0 volumio[1174]: at endReadableNT (node:internal/streams/readable:1376:12) Jul 19 07:05:34 volumio-4.0 volumio[1174]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Jul 19 07:05:34 volumio-4.0 volumio[1174]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 07:05:34 volumio-4.0 sudo[1966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-19 07:04' Jul 19 07:05:34 volumio-4.0 sudo[1966]: 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="30967a3eaa5e867c9c73ed66f5e07b238b646dd7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c82d2341a3a7e284141656a9703a39c694911dfd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 12 07:39:31 UTC 2025" VOLUMIO_VERSION="4.016" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b26578fd35198fc4430a2f6138ca1e3"