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