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