Jun 21 21:21:05 volumio-alpha kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Jun 21 21:21:05 volumio-alpha 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 Jun 21 21:21:05 volumio-alpha kernel: KASLR enabled Jun 21 21:21:05 volumio-alpha kernel: random: crng init done Jun 21 21:21:05 volumio-alpha kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Jun 21 21:21:05 volumio-alpha kernel: efi: UEFI not found. Jun 21 21:21:05 volumio-alpha kernel: Reserved memory: created CMA memory pool at 0x000000003bc00000, size 64 MiB Jun 21 21:21:05 volumio-alpha kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jun 21 21:21:05 volumio-alpha kernel: OF: reserved mem: 0x000000003bc00000..0x000000003fbfffff (65536 KiB) map reusable linux,cma Jun 21 21:21:05 volumio-alpha kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Jun 21 21:21:05 volumio-alpha kernel: OF: reserved mem: 0x000000003fd16200..0x000000003fd16237 (0 KiB) nomap non-reusable nvram@0 Jun 21 21:21:05 volumio-alpha kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000000ffffffff] Jun 21 21:21:05 volumio-alpha kernel: Faking node 0 at [mem 0x0000000000000000-0x000000001fffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 1 at [mem 0x0000000020000000-0x000000003fffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 2 at [mem 0x0000000040000000-0x000000005fffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 3 at [mem 0x0000000060000000-0x000000007fffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 4 at [mem 0x0000000080000000-0x000000009fffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 5 at [mem 0x00000000a0000000-0x00000000bfffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 6 at [mem 0x00000000c0000000-0x00000000dfffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: Faking node 7 at [mem 0x00000000e0000000-0x00000000ffffffff] (512MB) Jun 21 21:21:05 volumio-alpha kernel: NUMA: Initialized distance table, cnt=8 Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(0) allocated [mem 0x1fffd300-0x1fffffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(1) allocated [mem 0x3bbfd300-0x3bbfffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(2) allocated [mem 0x5fffd300-0x5fffffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(3) allocated [mem 0x7fffd300-0x7fffffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(4) allocated [mem 0x9fffd300-0x9fffffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(5) allocated [mem 0xbfffd300-0xbfffffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(6) allocated [mem 0xdfffd300-0xdfffffff] Jun 21 21:21:05 volumio-alpha kernel: NODE_DATA(7) allocated [mem 0xff7a6300-0xff7a8fff] Jun 21 21:21:05 volumio-alpha kernel: Zone ranges: Jun 21 21:21:05 volumio-alpha kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Jun 21 21:21:05 volumio-alpha kernel: DMA32 empty Jun 21 21:21:05 volumio-alpha kernel: Normal empty Jun 21 21:21:05 volumio-alpha kernel: Movable zone start for each node Jun 21 21:21:05 volumio-alpha kernel: Early memory node ranges Jun 21 21:21:05 volumio-alpha kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Jun 21 21:21:05 volumio-alpha kernel: node 0: [mem 0x0000000000080000-0x000000001fffffff] Jun 21 21:21:05 volumio-alpha kernel: node 1: [mem 0x0000000020000000-0x000000003fbfffff] Jun 21 21:21:05 volumio-alpha kernel: node 2: [mem 0x0000000040000000-0x000000005fffffff] Jun 21 21:21:05 volumio-alpha kernel: node 3: [mem 0x0000000060000000-0x000000007fffffff] Jun 21 21:21:05 volumio-alpha kernel: node 4: [mem 0x0000000080000000-0x000000009fffffff] Jun 21 21:21:05 volumio-alpha kernel: node 5: [mem 0x00000000a0000000-0x00000000bfffffff] Jun 21 21:21:05 volumio-alpha kernel: node 6: [mem 0x00000000c0000000-0x00000000dfffffff] Jun 21 21:21:05 volumio-alpha kernel: node 7: [mem 0x00000000e0000000-0x00000000ffffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000001fffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 1 [mem 0x0000000020000000-0x000000003fbfffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 2 [mem 0x0000000040000000-0x000000005fffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 3 [mem 0x0000000060000000-0x000000007fffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 4 [mem 0x0000000080000000-0x000000009fffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 5 [mem 0x00000000a0000000-0x00000000bfffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 6 [mem 0x00000000c0000000-0x00000000dfffffff] Jun 21 21:21:05 volumio-alpha kernel: Initmem setup node 7 [mem 0x00000000e0000000-0x00000000ffffffff] Jun 21 21:21:05 volumio-alpha kernel: On node 2, zone DMA: 1024 pages in unavailable ranges Jun 21 21:21:05 volumio-alpha kernel: psci: probing for conduit method from DT. Jun 21 21:21:05 volumio-alpha kernel: psci: PSCIv1.1 detected in firmware. Jun 21 21:21:05 volumio-alpha kernel: psci: Using standard PSCI v0.2 function IDs Jun 21 21:21:05 volumio-alpha kernel: psci: MIGRATE_INFO_TYPE not supported. Jun 21 21:21:05 volumio-alpha kernel: psci: SMC Calling Convention v1.2 Jun 21 21:21:05 volumio-alpha kernel: percpu: Embedded 33 pages/cpu s95064 r8192 d31912 u135168 Jun 21 21:21:05 volumio-alpha kernel: pcpu-alloc: s95064 r8192 d31912 u135168 alloc=33*4096 Jun 21 21:21:05 volumio-alpha kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jun 21 21:21:05 volumio-alpha kernel: Detected PIPT I-cache on CPU0 Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Virtualization Host Extensions Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Spectre-v4 Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Spectre-BHB Jun 21 21:21:05 volumio-alpha kernel: CPU features: kernel page table isolation forced ON by KASLR Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Kernel page table isolation (KPTI) Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: SSBS not fully self-synchronizing Jun 21 21:21:05 volumio-alpha kernel: alternatives: applying boot alternatives Jun 21 21:21:05 volumio-alpha 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=D8:3A:DD:A6:E1:27 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=d25ca7f0-22de-4cf5-b88b-5d613378cd81 imgfile=/volumio_current.sqsh bootpart=UUID=4EB6-F5D5 datapart=UUID=dd4f07b7-1c67-40a7-9fb8-946fbf816bf3 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 Jun 21 21:21:05 volumio-alpha kernel: cgroup: Disabling memory control group subsystem Jun 21 21:21:05 volumio-alpha kernel: mempolicy: NUMA default policy overridden to 'interleave:0-7' Jun 21 21:21:05 volumio-alpha kernel: PCIe ASPM is disabled Jun 21 21:21:05 volumio-alpha kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=d25ca7f0-22de-4cf5-b88b-5d613378cd81 imgfile=/volumio_current.sqsh bootpart=UUID=4EB6-F5D5 datapart=UUID=dd4f07b7-1c67-40a7-9fb8-946fbf816bf3 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 0: 0 1 2 3 4 5 6 7 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 1: 1 2 3 4 5 6 7 0 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 2: 2 3 4 5 6 7 0 1 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 3: 3 4 5 6 7 0 1 2 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 4: 4 5 6 7 0 1 2 3 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 5: 5 6 7 0 1 2 3 4 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 6: 6 7 0 1 2 3 4 5 Jun 21 21:21:05 volumio-alpha kernel: Fallback order for Node 7: 7 0 1 2 3 4 5 6 Jun 21 21:21:05 volumio-alpha kernel: Built 8 zonelists, mobility grouping on. Total pages: 1047552 Jun 21 21:21:05 volumio-alpha kernel: Policy zone: DMA Jun 21 21:21:05 volumio-alpha kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 21 21:21:05 volumio-alpha kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=8 Jun 21 21:21:05 volumio-alpha kernel: ftrace: allocating 44960 entries in 176 pages Jun 21 21:21:05 volumio-alpha kernel: ftrace: allocated 176 pages with 3 groups Jun 21 21:21:05 volumio-alpha kernel: rcu: Preemptible hierarchical RCU implementation. Jun 21 21:21:05 volumio-alpha kernel: rcu: RCU event tracing is enabled. Jun 21 21:21:05 volumio-alpha kernel: Trampoline variant of Tasks RCU enabled. Jun 21 21:21:05 volumio-alpha kernel: Rude variant of Tasks RCU enabled. Jun 21 21:21:05 volumio-alpha kernel: Tracing variant of Tasks RCU enabled. Jun 21 21:21:05 volumio-alpha kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jun 21 21:21:05 volumio-alpha kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 21 21:21:05 volumio-alpha kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 21 21:21:05 volumio-alpha kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 21 21:21:05 volumio-alpha kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jun 21 21:21:05 volumio-alpha kernel: Root IRQ handler: gic_handle_irq Jun 21 21:21:05 volumio-alpha kernel: GIC: Using split EOI/Deactivate mode Jun 21 21:21:05 volumio-alpha kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 21 21:21:05 volumio-alpha kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Jun 21 21:21:05 volumio-alpha kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Jun 21 21:21:05 volumio-alpha kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Jun 21 21:21:05 volumio-alpha kernel: Console: colour dummy device 80x25 Jun 21 21:21:05 volumio-alpha kernel: printk: legacy console [tty1] enabled Jun 21 21:21:05 volumio-alpha kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Jun 21 21:21:05 volumio-alpha kernel: pid_max: default: 32768 minimum: 301 Jun 21 21:21:05 volumio-alpha kernel: LSM: initializing lsm=capability Jun 21 21:21:05 volumio-alpha kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: rcu: Hierarchical SRCU implementation. Jun 21 21:21:05 volumio-alpha kernel: rcu: Max phase no-delay instances is 1000. Jun 21 21:21:05 volumio-alpha kernel: Timer migration: 1 hierarchy levels; 8 children per group; 0 crossnode level Jun 21 21:21:05 volumio-alpha kernel: EFI services will not be available. Jun 21 21:21:05 volumio-alpha kernel: smp: Bringing up secondary CPUs ... Jun 21 21:21:05 volumio-alpha kernel: Detected PIPT I-cache on CPU1 Jun 21 21:21:05 volumio-alpha kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Jun 21 21:21:05 volumio-alpha kernel: Detected PIPT I-cache on CPU2 Jun 21 21:21:05 volumio-alpha kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Jun 21 21:21:05 volumio-alpha kernel: Detected PIPT I-cache on CPU3 Jun 21 21:21:05 volumio-alpha kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Jun 21 21:21:05 volumio-alpha kernel: smp: Brought up 8 nodes, 4 CPUs Jun 21 21:21:05 volumio-alpha kernel: SMP: Total of 4 processors activated. Jun 21 21:21:05 volumio-alpha kernel: CPU: All CPU(s) started at EL2 Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: 32-bit EL0 Support Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Common not Private translations Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: CRC32 instructions Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: LSE atomic instructions Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Privileged Access Never Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: RAS Extension Support Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jun 21 21:21:05 volumio-alpha kernel: alternatives: applying system-wide alternatives Jun 21 21:21:05 volumio-alpha kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jun 21 21:21:05 volumio-alpha kernel: Memory: 3975176K/4190208K available (13888K kernel code, 2404K rwdata, 4772K rodata, 5376K init, 577K bss, 135892K reserved, 65536K cma-reserved) Jun 21 21:21:05 volumio-alpha kernel: devtmpfs: initialized Jun 21 21:21:05 volumio-alpha kernel: Enabled cp15_barrier support Jun 21 21:21:05 volumio-alpha kernel: Enabled setend support Jun 21 21:21:05 volumio-alpha kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jun 21 21:21:05 volumio-alpha kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jun 21 21:21:05 volumio-alpha kernel: 0 pages in range for non-PLT usage Jun 21 21:21:05 volumio-alpha kernel: 517472 pages in range for PLT usage Jun 21 21:21:05 volumio-alpha kernel: pinctrl core: initialized pinctrl subsystem Jun 21 21:21:05 volumio-alpha kernel: DMI not present or invalid. Jun 21 21:21:05 volumio-alpha kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 21 21:21:05 volumio-alpha kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Jun 21 21:21:05 volumio-alpha kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jun 21 21:21:05 volumio-alpha kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jun 21 21:21:05 volumio-alpha kernel: audit: initializing netlink subsys (disabled) Jun 21 21:21:05 volumio-alpha kernel: audit: type=2000 audit(0.048:1): state=initialized audit_enabled=0 res=1 Jun 21 21:21:05 volumio-alpha kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 21 21:21:05 volumio-alpha kernel: cpuidle: using governor menu Jun 21 21:21:05 volumio-alpha kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jun 21 21:21:05 volumio-alpha kernel: ASID allocator initialised with 32768 entries Jun 21 21:21:05 volumio-alpha kernel: Serial: AMBA PL011 UART driver Jun 21 21:21:05 volumio-alpha kernel: /soc@107c000000/interrupt-controller@7fff9000: Fixed dependency cycle(s) with /soc@107c000000/interrupt-controller@7fff9000 Jun 21 21:21:05 volumio-alpha kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Jun 21 21:21:05 volumio-alpha kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 16, base_baud = 0) is a PL011 rev3 Jun 21 21:21:05 volumio-alpha kernel: printk: legacy console [ttyAMA10] enabled Jun 21 21:21:05 volumio-alpha kernel: raspberrypi-firmware soc@107c000000:firmware: Attached to firmware from 2025-06-09T09:30:52, variant start_cd Jun 21 21:21:05 volumio-alpha kernel: raspberrypi-firmware soc@107c000000:firmware: Firmware hash is 3a4915a400000000000000000000000000000000 Jun 21 21:21:05 volumio-alpha kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Jun 21 21:21:05 volumio-alpha kernel: iommu: Default domain type: Translated Jun 21 21:21:05 volumio-alpha kernel: iommu: DMA domain TLB invalidation policy: strict mode Jun 21 21:21:05 volumio-alpha kernel: SCSI subsystem initialized Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver usbfs Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver hub Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new device driver usb Jun 21 21:21:05 volumio-alpha kernel: pps_core: LinuxPPS API ver. 1 registered Jun 21 21:21:05 volumio-alpha kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 21 21:21:05 volumio-alpha kernel: PTP clock support registered Jun 21 21:21:05 volumio-alpha kernel: vgaarb: loaded Jun 21 21:21:05 volumio-alpha kernel: clocksource: Switched to clocksource arch_sys_counter Jun 21 21:21:05 volumio-alpha kernel: VFS: Disk quotas dquot_6.6.0 Jun 21 21:21:05 volumio-alpha kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 21 21:21:05 volumio-alpha kernel: NET: Registered PF_INET protocol family Jun 21 21:21:05 volumio-alpha kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: TCP: Hash tables configured (established 32768 bind 32768) Jun 21 21:21:05 volumio-alpha kernel: MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, vmalloc) Jun 21 21:21:05 volumio-alpha kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 21 21:21:05 volumio-alpha kernel: RPC: Registered named UNIX socket transport module. Jun 21 21:21:05 volumio-alpha kernel: RPC: Registered udp transport module. Jun 21 21:21:05 volumio-alpha kernel: RPC: Registered tcp transport module. Jun 21 21:21:05 volumio-alpha kernel: RPC: Registered tcp-with-tls transport module. Jun 21 21:21:05 volumio-alpha kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jun 21 21:21:05 volumio-alpha kernel: PCI: CLS 0 bytes, default 64 Jun 21 21:21:05 volumio-alpha kernel: Trying to unpack rootfs image as initramfs... Jun 21 21:21:05 volumio-alpha kernel: kvm [1]: nv: 554 coarse grained trap handlers Jun 21 21:21:05 volumio-alpha kernel: kvm [1]: IPA Size Limit: 40 bits Jun 21 21:21:05 volumio-alpha kernel: kvm [1]: vgic interrupt IRQ9 Jun 21 21:21:05 volumio-alpha kernel: kvm [1]: VHE mode initialized successfully Jun 21 21:21:05 volumio-alpha kernel: Initialise system trusted keyrings Jun 21 21:21:05 volumio-alpha kernel: workingset: timestamp_bits=42 max_order=20 bucket_order=0 Jun 21 21:21:05 volumio-alpha kernel: NFS: Registering the id_resolver key type Jun 21 21:21:05 volumio-alpha kernel: Key type id_resolver registered Jun 21 21:21:05 volumio-alpha kernel: Key type id_legacy registered Jun 21 21:21:05 volumio-alpha kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jun 21 21:21:05 volumio-alpha kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jun 21 21:21:05 volumio-alpha kernel: Key type asymmetric registered Jun 21 21:21:05 volumio-alpha kernel: Asymmetric key parser 'x509' registered Jun 21 21:21:05 volumio-alpha kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jun 21 21:21:05 volumio-alpha kernel: io scheduler mq-deadline registered Jun 21 21:21:05 volumio-alpha kernel: io scheduler kyber registered Jun 21 21:21:05 volumio-alpha kernel: io scheduler bfq registered Jun 21 21:21:05 volumio-alpha kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7d510600, parent irq: 27) Jun 21 21:21:05 volumio-alpha kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/interrupt-controller@7c502000, parent irq: 28) Jun 21 21:21:05 volumio-alpha kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508380, parent irq: 29) Jun 21 21:21:05 volumio-alpha kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d508400, parent irq: 30) Jun 21 21:21:05 volumio-alpha kernel: irq_brcmstb_l2: registered L2 intc (/soc@107c000000/intc@7d503000, parent irq: 31) Jun 21 21:21:05 volumio-alpha kernel: ledtrig-cpu: registered to indicate activity on CPUs Jun 21 21:21:05 volumio-alpha kernel: Freeing initrd memory: 33316K Jun 21 21:21:05 volumio-alpha kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Jun 21 21:21:05 volumio-alpha kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Jun 21 21:21:05 volumio-alpha kernel: serial serial0: tty port ttyS0 registered Jun 21 21:21:05 volumio-alpha kernel: iproc-rng200 107d208000.rng: hwrng registered Jun 21 21:21:05 volumio-alpha kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Jun 21 21:21:05 volumio-alpha kernel: brd: module loaded Jun 21 21:21:05 volumio-alpha kernel: loop: module loaded Jun 21 21:21:05 volumio-alpha kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jun 21 21:21:05 volumio-alpha kernel: Loading iSCSI transport class v2.0-870. Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver lan78xx Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver smsc95xx Jun 21 21:21:05 volumio-alpha kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jun 21 21:21:05 volumio-alpha kernel: dwc_otg: FIQ enabled Jun 21 21:21:05 volumio-alpha kernel: dwc_otg: NAK holdoff enabled Jun 21 21:21:05 volumio-alpha kernel: dwc_otg: FIQ split-transaction FSM enabled Jun 21 21:21:05 volumio-alpha kernel: Module dwc_common_port init Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver uas Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver usb-storage Jun 21 21:21:05 volumio-alpha kernel: mousedev: PS/2 mouse device common for all mice Jun 21 21:21:05 volumio-alpha kernel: rpi-rtc soc@107c000000:rpi_rtc: registered as rtc0 Jun 21 21:21:05 volumio-alpha kernel: rpi-rtc soc@107c000000:rpi_rtc: setting system clock to 2025-06-21T20:20:54 UTC (1750537254) Jun 21 21:21:05 volumio-alpha kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Jun 21 21:21:05 volumio-alpha kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jun 21 21:21:05 volumio-alpha kernel: sdhci: Secure Digital Host Controller Interface driver Jun 21 21:21:05 volumio-alpha kernel: sdhci: Copyright(c) Pierre Ossman Jun 21 21:21:05 volumio-alpha kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jun 21 21:21:05 volumio-alpha kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Jun 21 21:21:05 volumio-alpha kernel: hid: raw HID events driver (C) Jiri Kosina Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver usbhid Jun 21 21:21:05 volumio-alpha kernel: usbhid: USB HID core driver Jun 21 21:21:05 volumio-alpha kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 (0,8000003f) counters available Jun 21 21:21:05 volumio-alpha kernel: NET: Registered PF_PACKET protocol family Jun 21 21:21:05 volumio-alpha kernel: Key type dns_resolver registered Jun 21 21:21:05 volumio-alpha kernel: registered taskstats version 1 Jun 21 21:21:05 volumio-alpha kernel: Loading compiled-in X.509 certificates Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 0: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 1: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 2: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 3: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 4: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 5: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 6: null Jun 21 21:21:05 volumio-alpha kernel: Demotion targets for Node 7: null Jun 21 21:21:05 volumio-alpha kernel: Key type .fscrypt registered Jun 21 21:21:05 volumio-alpha kernel: Key type fscrypt-provisioning registered Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@1000110000 ranges: Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@1000110000, using [bus 00-ff] Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00 Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: PCI bridge to [bus 00] Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1bbfffffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: clkreq-mode set to safe Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000110000.pcie: link up, 5.0 GT/s PCIe x1 (!SSC) Jun 21 21:21:05 volumio-alpha kernel: pci 0001:01:00.0: [144d:a809] type 00 class 0x010802 PCIe Endpoint Jun 21 21:21:05 volumio-alpha kernel: pci 0001:01:00.0: BAR 0 [mem 0x00000000-0x00003fff 64bit] Jun 21 21:21:05 volumio-alpha 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) Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff]: assigned Jun 21 21:21:05 volumio-alpha kernel: pci 0001:01:00.0: BAR 0 [mem 0x1b80000000-0x1b80003fff 64bit]: assigned Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0001:01: resource 1 [mem 0x1b80000000-0x1b800fffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0001:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jun 21 21:21:05 volumio-alpha kernel: pci 0001:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Jun 21 21:21:05 volumio-alpha kernel: pcieport 0001:00:00.0: enabling device (0000 -> 0002) Jun 21 21:21:05 volumio-alpha kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 38 Jun 21 21:21:05 volumio-alpha kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 38 Jun 21 21:21:05 volumio-alpha kernel: nvme nvme0: pci function 0001:01:00.0 Jun 21 21:21:05 volumio-alpha kernel: nvme 0001:01:00.0: enabling device (0000 -> 0002) Jun 21 21:21:05 volumio-alpha kernel: nvme nvme0: D3 entry latency set to 8 seconds Jun 21 21:21:05 volumio-alpha kernel: hwmon hwmon1: temp1_input not attached to any thermal zone Jun 21 21:21:05 volumio-alpha kernel: hwmon hwmon1: temp2_input not attached to any thermal zone Jun 21 21:21:05 volumio-alpha kernel: nvme nvme0: failed to allocate host memory buffer. Jun 21 21:21:05 volumio-alpha kernel: nvme nvme0: 4/0/0 default/read/poll queues Jun 21 21:21:05 volumio-alpha kernel: nvme0n1: p1 p2 p3 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges: Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff] Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000 Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00 Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:00: root bus resource [bus 00-ff] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: PCI bridge to [bus 00] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: PME# supported from D0 D3hot Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default Jun 21 21:21:05 volumio-alpha kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: supports D1 Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01 Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: PCI bridge to [bus 01] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref] Jun 21 21:21:05 volumio-alpha kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff] Jun 21 21:21:05 volumio-alpha kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jun 21 21:21:05 volumio-alpha kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Jun 21 21:21:05 volumio-alpha kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002) Jun 21 21:21:05 volumio-alpha kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 44 Jun 21 21:21:05 volumio-alpha kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 44 Jun 21 21:21:05 volumio-alpha kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Jun 21 21:21:05 volumio-alpha kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Jun 21 21:21:05 volumio-alpha kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002) Jun 21 21:21:05 volumio-alpha kernel: rp1 0002:01:00.0: chip_id 0x20001927 Jun 21 21:21:05 volumio-alpha kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 106 Jun 21 21:21:05 volumio-alpha kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 112 (d8:3a:dd:a6:e1:27) Jun 21 21:21:05 volumio-alpha kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: irq 137, io mem 0x1f00200000 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Jun 21 21:21:05 volumio-alpha kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 21 21:21:05 volumio-alpha kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 21 21:21:05 volumio-alpha kernel: usb usb1: Product: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: usb usb1: Manufacturer: Linux 6.12.27-v8+ xhci-hcd Jun 21 21:21:05 volumio-alpha kernel: usb usb1: SerialNumber: xhci-hcd.0 Jun 21 21:21:05 volumio-alpha kernel: hub 1-0:1.0: USB hub found Jun 21 21:21:05 volumio-alpha kernel: hub 1-0:1.0: 2 ports detected Jun 21 21:21:05 volumio-alpha kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jun 21 21:21:05 volumio-alpha kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 21 21:21:05 volumio-alpha kernel: usb usb2: Product: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: usb usb2: Manufacturer: Linux 6.12.27-v8+ xhci-hcd Jun 21 21:21:05 volumio-alpha kernel: usb usb2: SerialNumber: xhci-hcd.0 Jun 21 21:21:05 volumio-alpha kernel: hub 2-0:1.0: USB hub found Jun 21 21:21:05 volumio-alpha kernel: hub 2-0:1.0: 1 port detected Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: irq 142, io mem 0x1f00300000 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Jun 21 21:21:05 volumio-alpha kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Jun 21 21:21:05 volumio-alpha kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jun 21 21:21:05 volumio-alpha kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 21 21:21:05 volumio-alpha kernel: usb usb3: Product: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: usb usb3: Manufacturer: Linux 6.12.27-v8+ xhci-hcd Jun 21 21:21:05 volumio-alpha kernel: usb usb3: SerialNumber: xhci-hcd.1 Jun 21 21:21:05 volumio-alpha kernel: hub 3-0:1.0: USB hub found Jun 21 21:21:05 volumio-alpha kernel: hub 3-0:1.0: 2 ports detected Jun 21 21:21:05 volumio-alpha kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jun 21 21:21:05 volumio-alpha kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 21 21:21:05 volumio-alpha kernel: usb usb4: Product: xHCI Host Controller Jun 21 21:21:05 volumio-alpha kernel: usb usb4: Manufacturer: Linux 6.12.27-v8+ xhci-hcd Jun 21 21:21:05 volumio-alpha kernel: usb usb4: SerialNumber: xhci-hcd.1 Jun 21 21:21:05 volumio-alpha kernel: hub 4-0:1.0: USB hub found Jun 21 21:21:05 volumio-alpha kernel: hub 4-0:1.0: 1 port detected Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jun 21 21:21:05 volumio-alpha kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Jun 21 21:21:05 volumio-alpha kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Jun 21 21:21:05 volumio-alpha kernel: mmc0: CQHCI version 5.10 Jun 21 21:21:05 volumio-alpha kernel: mmc1: CQHCI version 5.10 Jun 21 21:21:05 volumio-alpha kernel: of_cfs_init Jun 21 21:21:05 volumio-alpha kernel: of_cfs_init: OK Jun 21 21:21:05 volumio-alpha kernel: clk: Disabling unused clocks Jun 21 21:21:05 volumio-alpha kernel: PM: genpd: Disabling unused power domains Jun 21 21:21:05 volumio-alpha kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Jun 21 21:21:05 volumio-alpha kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Jun 21 21:21:05 volumio-alpha kernel: Freeing unused kernel memory: 5376K Jun 21 21:21:05 volumio-alpha kernel: Run /init as init process Jun 21 21:21:05 volumio-alpha kernel: with arguments: Jun 21 21:21:05 volumio-alpha kernel: /init Jun 21 21:21:05 volumio-alpha kernel: splash Jun 21 21:21:05 volumio-alpha kernel: nodebug Jun 21 21:21:05 volumio-alpha kernel: with environment: Jun 21 21:21:05 volumio-alpha kernel: HOME=/ Jun 21 21:21:05 volumio-alpha kernel: TERM=linux Jun 21 21:21:05 volumio-alpha kernel: imgpart=UUID=d25ca7f0-22de-4cf5-b88b-5d613378cd81 Jun 21 21:21:05 volumio-alpha kernel: imgfile=/volumio_current.sqsh Jun 21 21:21:05 volumio-alpha kernel: bootpart=UUID=4EB6-F5D5 Jun 21 21:21:05 volumio-alpha kernel: datapart=UUID=dd4f07b7-1c67-40a7-9fb8-946fbf816bf3 Jun 21 21:21:05 volumio-alpha kernel: uuidconfig=cmdline.txt Jun 21 21:21:05 volumio-alpha kernel: bootdelay=7 Jun 21 21:21:05 volumio-alpha kernel: use_kmsg=no Jun 21 21:21:05 volumio-alpha kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_info_register (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_device_new (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Jun 21 21:21:05 volumio-alpha kernel: snd_timer: Unknown symbol snd_register_device (err -2) Jun 21 21:21:05 volumio-alpha kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Jun 21 21:21:05 volumio-alpha kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Jun 21 21:21:05 volumio-alpha kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform! Jun 21 21:21:05 volumio-alpha kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Jun 21 21:21:05 volumio-alpha kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0 Jun 21 21:21:05 volumio-alpha kernel: fuse: init (API version 7.41) Jun 21 21:21:05 volumio-alpha kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 21 21:21:05 volumio-alpha kernel: FAT-fs (nvme0n1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jun 21 21:21:05 volumio-alpha kernel: EXT4-fs (nvme0n1p2): mounted filesystem d25ca7f0-22de-4cf5-b88b-5d613378cd81 r/w with ordered data mode. Quota mode: none. Jun 21 21:21:05 volumio-alpha kernel: loop0: detected capacity change from 0 to 1353392 Jun 21 21:21:05 volumio-alpha kernel: EXT4-fs (nvme0n1p3): recovery complete Jun 21 21:21:05 volumio-alpha kernel: EXT4-fs (nvme0n1p3): mounted filesystem dd4f07b7-1c67-40a7-9fb8-946fbf816bf3 r/w with ordered data mode. Quota mode: none. Jun 21 21:21:05 volumio-alpha kernel: NET: Registered PF_INET6 protocol family Jun 21 21:21:05 volumio-alpha kernel: Segment Routing with IPv6 Jun 21 21:21:05 volumio-alpha kernel: In-situ OAM (IOAM) with IPv6 Jun 21 21:21:05 volumio-alpha 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) Jun 21 21:21:05 volumio-alpha systemd[1]: Detected architecture arm64. Jun 21 21:21:05 volumio-alpha systemd[1]: Hostname set to . Jun 21 21:21:05 volumio-alpha systemd[1]: Queued start job for default target graphical.target. Jun 21 21:21:05 volumio-alpha systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 21 21:21:05 volumio-alpha systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 21 21:21:05 volumio-alpha systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 21 21:21:05 volumio-alpha systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 21 21:21:05 volumio-alpha systemd[1]: Created slice user.slice - User and Session Slice. Jun 21 21:21:05 volumio-alpha systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 21 21:21:05 volumio-alpha systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 21 21:21:05 volumio-alpha systemd[1]: Expecting device dev-disk-by\x2duuid-4EB6\x2dF5D5.device - /dev/disk/by-uuid/4EB6-F5D5... Jun 21 21:21:05 volumio-alpha systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10... Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target slices.target - Slice Units. Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 21:21:05 volumio-alpha systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 21 21:21:05 volumio-alpha systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jun 21 21:21:05 volumio-alpha systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target swap.target - Swaps. Jun 21 21:21:05 volumio-alpha systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-journald.service - Journal Service... Jun 21 21:21:05 volumio-alpha kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 21:21:05 volumio-alpha systemd[1]: dynamicswap.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 21 21:21:05 volumio-alpha kernel: i2c_dev: i2c /dev entries driver Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 21 21:21:05 volumio-alpha systemd-journald[417]: Journal started Jun 21 21:21:05 volumio-alpha systemd-journald[417]: Runtime Journal (/run/log/journal/c873d1e6a71fefc55049a325683a7f00) is 3.7M, max 30.0M, 26.2M free. Jun 21 21:21:05 volumio-alpha systemd-modules-load[420]: Inserted module 'i2c_dev' Jun 21 21:21:05 volumio-alpha fake-hwclock[407]: Current system time: 2025-06-21 20:21:05 Jun 21 21:21:05 volumio-alpha fake-hwclock[407]: fake-hwclock saved clock information is in the past: 2025-06-21 20:20:16 Jun 21 21:21:05 volumio-alpha fake-hwclock[407]: To set system time to this saved clock anyway, use "force" Jun 21 21:21:05 volumio-alpha systemd[1]: Started systemd-journald.service - Journal Service. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Jun 21 21:21:05 volumio-alpha systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 21 21:21:05 volumio-alpha systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting tmp.mount - /tmp... Jun 21 21:21:05 volumio-alpha systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting var-log.mount - /var/log... Jun 21 21:21:05 volumio-alpha systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted tmp.mount - /tmp. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted var-log.mount - /var/log. Jun 21 21:21:05 volumio-alpha systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 21:21:05 volumio-alpha systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Jun 21 21:21:05 volumio-alpha systemd-journald[417]: Runtime Journal (/run/log/journal/c873d1e6a71fefc55049a325683a7f00) is 3.7M, max 30.0M, 26.2M free. Jun 21 21:21:05 volumio-alpha systemd-journald[417]: Received client request to flush runtime journal. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 21 21:21:05 volumio-alpha systemd-udevd[443]: Using default interface naming scheme 'v252'. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Jun 21 21:21:05 volumio-alpha systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 21:21:05 volumio-alpha systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Jun 21 21:21:05 volumio-alpha systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Jun 21 21:21:05 volumio-alpha kernel: platform rp1_firmware: deferred probe pending: (reason unknown) Jun 21 21:21:05 volumio-alpha kernel: platform 1f00178000.pio: deferred probe pending: rp1-pio: failed to find RP1 firmware driver Jun 21 21:21:05 volumio-alpha 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). Jun 21 21:21:05 volumio-alpha systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 21 21:21:05 volumio-alpha systemd[1]: Reached target paths.target - Path Units. Jun 21 21:21:05 volumio-alpha (udev-worker)[476]: Network interface NamePolicy= disabled on kernel command line. Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Jun 21 21:21:05 volumio-alpha kernel: mc: Linux media interface: v0.10 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Jun 21 21:21:05 volumio-alpha kernel: rp1-firmware rp1_firmware: RP1 Firmware version eb39cfd516f8c90628aa9d91f52370aade5d0a55 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Jun 21 21:21:05 volumio-alpha kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Jun 21 21:21:05 volumio-alpha kernel: rp1-pio 1f00178000.pio: Created instance as pio0 Jun 21 21:21:05 volumio-alpha systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10. Jun 21 21:21:05 volumio-alpha systemd[1]: Found device dev-disk-by\x2duuid-4EB6\x2dF5D5.device - SAMSUNG MZ9LQ256HBJD-00BVL boot. Jun 21 21:21:05 volumio-alpha kernel: videodev: Linux video capture interface: v2.00 Jun 21 21:21:05 volumio-alpha systemd[1]: Condition check resulted in sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0 being skipped. Jun 21 21:21:05 volumio-alpha (udev-worker)[464]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-4EB6\x2dF5D5.service - File System Check on /dev/disk/by-uuid/4EB6-F5D5... Jun 21 21:21:05 volumio-alpha systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jun 21 21:21:05 volumio-alpha kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 21 21:21:05 volumio-alpha kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0 Jun 21 21:21:05 volumio-alpha kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19 Jun 21 21:21:05 volumio-alpha kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 21 21:21:05 volumio-alpha kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 21 21:21:05 volumio-alpha kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 21 21:21:05 volumio-alpha kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 21 21:21:05 volumio-alpha kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: fsck.fat 4.2 (2021-01-31) Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: There are differences between boot sector and its backup. Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: This is mostly harmless. Differences: (offset:original/backup) Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: 65:01/00 Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: Not automatically fixing this. Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: Automatically removing dirty bit. Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: *** Filesystem was changed *** Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: Writing changes. Jun 21 21:21:05 volumio-alpha systemd-fsck[637]: /dev/nvme0n1p1: 424 files, 22735/93508 clusters Jun 21 21:21:05 volumio-alpha kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0 Jun 21 21:21:05 volumio-alpha kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Jun 21 21:21:05 volumio-alpha kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: Core ver 2.22 Jun 21 21:21:05 volumio-alpha kernel: NET: Registered PF_BLUETOOTH protocol family Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: HCI device and connection manager initialized Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: HCI socket layer initialized Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: L2CAP socket layer initialized Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: SCO socket layer initialized Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: HCI UART driver ver 2.3 Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: HCI UART protocol H4 registered Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 21 21:21:05 volumio-alpha kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 21 21:21:05 volumio-alpha kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 21 21:21:05 volumio-alpha kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jun 21 21:21:05 volumio-alpha kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 21 21:21:05 volumio-alpha systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-4EB6\x2dF5D5.service - File System Check on /dev/disk/by-uuid/4EB6-F5D5. Jun 21 21:21:05 volumio-alpha systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 21 21:21:05 volumio-alpha kernel: usbcore: registered new interface driver brcmfmac Jun 21 21:21:05 volumio-alpha (udev-worker)[463]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Jun 21 21:21:05 volumio-alpha kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jun 21 21:21:05 volumio-alpha kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b Jun 21 21:21:05 volumio-alpha systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Jun 21 21:21:05 volumio-alpha systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Jun 21 21:21:05 volumio-alpha 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). Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 21 21:21:05 volumio-alpha systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 21 21:21:05 volumio-alpha kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1 Jun 21 21:21:05 volumio-alpha kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: Adding to iommu group 1 Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Jun 21 21:21:05 volumio-alpha (udev-worker)[449]: Network interface NamePolicy= disabled on kernel command line. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 21:21:05 volumio-alpha kernel: Registered IR keymap rc-cec Jun 21 21:21:05 volumio-alpha kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0 Jun 21 21:21:05 volumio-alpha kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input1 Jun 21 21:21:05 volumio-alpha kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input2 Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Jun 21 21:21:05 volumio-alpha kernel: Registered IR keymap rc-cec Jun 21 21:21:05 volumio-alpha kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1 Jun 21 21:21:05 volumio-alpha kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input3 Jun 21 21:21:05 volumio-alpha kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input4 Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Jun 21 21:21:05 volumio-alpha systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Jun 21 21:21:05 volumio-alpha kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1 Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Jun 21 21:21:05 volumio-alpha kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Jun 21 21:21:05 volumio-alpha systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Jun 21 21:21:05 volumio-alpha 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). Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: hci0: BCM: chip id 107 Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: hci0: BCM: features 0x2f Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: hci0: BCM4345C0 Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jun 21 21:21:05 volumio-alpha kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,5-model-b.hcd' Patch Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 21:21:05 volumio-alpha systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 21 21:21:05 volumio-alpha systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 21:21:05 volumio-alpha systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 21:21:05 volumio-alpha systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 21:21:05 volumio-alpha systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 21:21:05 volumio-alpha (udev-worker)[476]: event4: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 21 21:21:05 volumio-alpha (udev-worker)[459]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 21 21:21:05 volumio-alpha (udev-worker)[463]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 21 21:21:05 volumio-alpha (udev-worker)[453]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 21 21:21:06 volumio-alpha systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Jun 21 21:21:06 volumio-alpha systemd[1]: Mounting boot.mount - /boot... Jun 21 21:21:06 volumio-alpha systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Jun 21 21:21:06 volumio-alpha systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Jun 21 21:21:06 volumio-alpha systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Jun 21 21:21:06 volumio-alpha systemd[1]: Mounted boot.mount - /boot. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target local-fs.target - Local File Systems. Jun 21 21:21:06 volumio-alpha systemd[1]: Started ifup@eth0.service - ifup for eth0. Jun 21 21:21:06 volumio-alpha systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Jun 21 21:21:06 volumio-alpha systemd[1]: Starting networking.service - Raise network interfaces... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jun 21 21:21:06 volumio-alpha 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). Jun 21 21:21:06 volumio-alpha systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 21 21:21:06 volumio-alpha systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 762 (systemd-binfmt) Jun 21 21:21:06 volumio-alpha systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jun 21 21:21:06 volumio-alpha systemd[1]: Received SIGRTMIN+20 from PID 288 (plymouthd). Jun 21 21:21:06 volumio-alpha systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jun 21 21:21:06 volumio-alpha kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 21 21:21:06 volumio-alpha systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jun 21 21:21:06 volumio-alpha systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jun 21 21:21:06 volumio-alpha systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 21:21:06 volumio-alpha systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jun 21 21:21:06 volumio-alpha sh[797]: dhcpcd-9.4.1 starting Jun 21 21:21:06 volumio-alpha dhcpcd[797]: dhcpcd-9.4.1 starting Jun 21 21:21:06 volumio-alpha systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 21 21:21:06 volumio-alpha dhcpcd[807]: DUID 00:01:00:01:2f:cd:3f:2f:d8:3a:dd:a6:e1:27 Jun 21 21:21:06 volumio-alpha dhcpcd[807]: dhcp_vendor: No such process Jun 21 21:21:06 volumio-alpha sh[797]: DUID 00:01:00:01:2f:cd:3f:2f:d8:3a:dd:a6:e1:27 Jun 21 21:21:06 volumio-alpha sh[797]: dhcp_vendor: No such process Jun 21 21:21:06 volumio-alpha systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target sysinit.target - System Initialization. Jun 21 21:21:06 volumio-alpha systemd[1]: Started apt-daily.timer - Daily apt download activities. Jun 21 21:21:06 volumio-alpha systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jun 21 21:21:06 volumio-alpha systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jun 21 21:21:06 volumio-alpha systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jun 21 21:21:06 volumio-alpha systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jun 21 21:21:06 volumio-alpha systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jun 21 21:21:06 volumio-alpha systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jun 21 21:21:06 volumio-alpha systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target timers.target - Timer Units. Jun 21 21:21:06 volumio-alpha systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jun 21 21:21:06 volumio-alpha systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 21 21:21:06 volumio-alpha systemd[1]: Listening on triggerhappy.socket. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target sockets.target - Socket Units. Jun 21 21:21:06 volumio-alpha kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 21 21:21:06 volumio-alpha 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). Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target basic.target - Basic System. Jun 21 21:21:06 volumio-alpha 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). Jun 21 21:21:06 volumio-alpha systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting bluetooth.service - Bluetooth service... Jun 21 21:21:06 volumio-alpha haveged[799]: haveged: command socket is listening at fd 3 Jun 21 21:21:06 volumio-alpha systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jun 21 21:21:06 volumio-alpha 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). Jun 21 21:21:06 volumio-alpha systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jun 21 21:21:06 volumio-alpha systemd[1]: Started iptables.service - Volumio Iptables Module. Jun 21 21:21:06 volumio-alpha systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Jun 21 21:21:06 volumio-alpha kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 21 21:21:06 volumio-alpha systemd[1]: Starting systemd-logind.service - User Login Management... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jun 21 21:21:06 volumio-alpha systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 21 21:21:06 volumio-alpha systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jun 21 21:21:06 volumio-alpha systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jun 21 21:21:06 volumio-alpha systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jun 21 21:21:06 volumio-alpha systemd[1]: Starting wireless.service - Wireless Services... Jun 21 21:21:06 volumio-alpha systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target sound.target - Sound Card. Jun 21 21:21:06 volumio-alpha volumio_rfkill_unblock.sh[843]: Volumio WiFi Soft Blocked check script Jun 21 21:21:06 volumio-alpha volumio_rfkill_unblock.sh[843]: Check if rfkill is available Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Successfully dropped root privileges. Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: avahi-daemon 0.8 starting up. Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Unable to parse trigger line: Jun 21 21:21:06 volumio-alpha thd[838]: Found socket passed from systemd Jun 21 21:21:06 volumio-alpha volumio_rfkill_unblock.sh[843]: Check if rfkill listed devices are already unblocked Jun 21 21:21:06 volumio-alpha systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jun 21 21:21:06 volumio-alpha systemd[1]: Started rpcbind.service - RPC bind portmap service. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jun 21 21:21:06 volumio-alpha systemd[1]: e2scrub_reap.service: Deactivated successfully. Jun 21 21:21:06 volumio-alpha systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jun 21 21:21:06 volumio-alpha volumio_rfkill_unblock.sh[843]: Wi-Fi is already unblocked. Jun 21 21:21:06 volumio-alpha systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jun 21 21:21:06 volumio-alpha systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jun 21 21:21:06 volumio-alpha rpi-eeprom-update[830]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0 Jun 21 21:21:06 volumio-alpha systemd-logind[834]: New seat seat0. Jun 21 21:21:06 volumio-alpha systemd-logind[834]: Watching system buttons on /dev/input/event0 (pwr_button) Jun 21 21:21:06 volumio-alpha systemd-logind[834]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Jun 21 21:21:06 volumio-alpha systemd-logind[834]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Jun 21 21:21:06 volumio-alpha dhcpcd[872]: dhcpcd-9.4.1 starting Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Bluetooth daemon 5.72 Jun 21 21:21:06 volumio-alpha sh[910]: wlan0=wlan0 Jun 21 21:21:06 volumio-alpha dhcpcd[890]: dev: loaded udev Jun 21 21:21:06 volumio-alpha dhcpcd[890]: DUID 00:01:00:01:2f:cd:3f:2f:d8:3a:dd:a6:e1:27 Jun 21 21:21:06 volumio-alpha dhcpcd[872]: dhcp_vendor: No such process Jun 21 21:21:06 volumio-alpha dhcpcd[890]: dhcp_vendor: No such process Jun 21 21:21:06 volumio-alpha systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Starting SDP server Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Excluding (cli) wiimote Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Excluding (cli) sap Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Excluding (cli) midi Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Excluding (cli) battery Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Successfully called chroot(). Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Successfully dropped remaining capabilities. Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Loading service file /services/volumio.service. Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: New relevant interface lo.IPv4 for mDNS. Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Network interface enumeration completed. Jun 21 21:21:06 volumio-alpha avahi-daemon[825]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 21 21:21:06 volumio-alpha kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Jun 21 21:21:06 volumio-alpha kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: BNEP filters: protocol multicast Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: BNEP socket layer initialized Jun 21 21:21:06 volumio-alpha systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jun 21 21:21:06 volumio-alpha sh[797]: eth0: waiting for carrier Jun 21 21:21:06 volumio-alpha dhcpcd[807]: eth0: waiting for carrier Jun 21 21:21:06 volumio-alpha systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jun 21 21:21:06 volumio-alpha kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Jun 21 21:21:06 volumio-alpha dbus-daemon[827]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Bluetooth management interface 1.23 initialized Jun 21 21:21:06 volumio-alpha dbus-daemon[827]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=826 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Jun 21 21:21:06 volumio-alpha systemd[1]: Finished networking.service - Raise network interfaces. Jun 21 21:21:06 volumio-alpha systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 21 21:21:06 volumio-alpha systemd[1]: Started systemd-logind.service - User Login Management. Jun 21 21:21:06 volumio-alpha systemd[1]: Started bluetooth.service - Bluetooth service. Jun 21 21:21:06 volumio-alpha systemd[1]: Starting bluealsa.service - BlueALSA service... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jun 21 21:21:06 volumio-alpha dhcpcd[890]: eth0: waiting for carrier Jun 21 21:21:06 volumio-alpha dhcpcd[890]: wlan0: waiting for carrier Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: hci0: BCM: features 0x2f Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Battery Provider Manager created Jun 21 21:21:06 volumio-alpha kernel: Bluetooth: MGMT ver 1.23 Jun 21 21:21:06 volumio-alpha systemd[1]: iptables.service: Deactivated successfully. Jun 21 21:21:06 volumio-alpha kernel: NET: Registered PF_ALG protocol family Jun 21 21:21:06 volumio-alpha wpa_supplicant[962]: Successfully initialized wpa_supplicant Jun 21 21:21:06 volumio-alpha systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target network.target - Network. Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target network-online.target - Network is Online. Jun 21 21:21:06 volumio-alpha volumio-remote-updater[842]: Volumio OTA Client: 1.7.0 Jun 21 21:21:06 volumio-alpha dbus-daemon[827]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 21 21:21:06 volumio-alpha volumio-remote-updater[842]: Error: No active session Jun 21 21:21:06 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:06] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 21 21:21:06 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 21 21:21:06 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:06] [error] handle_connect error: Connection refused Jun 21 21:21:06 volumio-alpha systemd[1]: Starting nmbd.service - Samba NMB Daemon... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting ntpsec.service - Network Time Service... Jun 21 21:21:06 volumio-alpha systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Jun 21 21:21:06 volumio-alpha bluetoothd[826]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Jun 21 21:21:06 volumio-alpha systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Jun 21 21:21:06 volumio-alpha bluetoothd[826]: bap: Operation not supported (95) Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Jun 21 21:21:06 volumio-alpha systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Jun 21 21:21:06 volumio-alpha upmpdcli[841]: Could not open config: /tmp/upmpdcli.conf Jun 21 21:21:06 volumio-alpha systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jun 21 21:21:06 volumio-alpha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 21 21:21:06 volumio-alpha sm-notify[986]: Version 2.6.2 starting Jun 21 21:21:06 volumio-alpha systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Jun 21 21:21:06 volumio-alpha systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 21 21:21:06 volumio-alpha systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 21 21:21:06 volumio-alpha systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 21 21:21:06 volumio-alpha systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Jun 21 21:21:06 volumio-alpha systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jun 21 21:21:06 volumio-alpha systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Jun 21 21:21:06 volumio-alpha dbus-daemon[827]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1000 comm="timedatectl show --property=NTPSynchronized --valu") Jun 21 21:21:06 volumio-alpha welcome[996]: Resolved ip:[0] Jun 21 21:21:06 volumio-alpha ntpd[1003]: INIT: ntpd ntpsec-1.2.2: Starting Jun 21 21:21:06 volumio-alpha ntp-systemd-wrapper[1003]: 2025-06-21T21:21:06 ntpd[1003]: INIT: ntpd ntpsec-1.2.2: Starting Jun 21 21:21:06 volumio-alpha ntp-systemd-wrapper[1003]: 2025-06-21T21:21:06 ntpd[1003]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 21 21:21:06 volumio-alpha ntpd[1003]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jun 21 21:21:06 volumio-alpha systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 21 21:21:06 volumio-alpha systemd[1]: Started ntpsec.service - Network Time Service. Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: precision = 0.389 usec (-21) Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: successfully locked into RAM Jun 21 21:21:06 volumio-alpha ntpd[1011]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Jun 21 21:21:06 volumio-alpha ntpd[1011]: CONFIG: restrict nopeer ignored Jun 21 21:21:06 volumio-alpha rpi-eeprom-update[830]: BOOTLOADER: up to date Jun 21 21:21:06 volumio-alpha rpi-eeprom-update[830]: CURRENT: Mon Jun 9 09:30:52 UTC 2025 (1749461452) Jun 21 21:21:06 volumio-alpha rpi-eeprom-update[830]: LATEST: Thu May 8 14:13:17 UTC 2025 (1746713597) Jun 21 21:21:06 volumio-alpha rpi-eeprom-update[830]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default) Jun 21 21:21:06 volumio-alpha rpi-eeprom-update[830]: Use raspi-config to change the release. Jun 21 21:21:06 volumio-alpha ntpd[1011]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 21 21:21:06 volumio-alpha ntpd[1011]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2025-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: Using SO_TIMESTAMPNS(ns) Jun 21 21:21:06 volumio-alpha ntpd[1011]: IO: Listen and drop on 0 v6wildcard [::]:123 Jun 21 21:21:06 volumio-alpha ntpd[1011]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 21 21:21:06 volumio-alpha ntpd[1011]: IO: Listen normally on 2 lo 127.0.0.1:123 Jun 21 21:21:06 volumio-alpha ntpd[1011]: IO: Listening on routing socket on fd #19 for interface updates Jun 21 21:21:06 volumio-alpha systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Jun 21 21:21:06 volumio-alpha systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: This system has a 32-bit time_t. Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Jun 21 21:21:06 volumio-alpha ntpd[1011]: INIT: Running with OpenSSL 3.0.16 11 Feb 2025, 30000100 Jun 21 21:21:06 volumio-alpha ntpd[1011]: NTSc: Using system default root certificates. Jun 21 21:21:06 volumio-alpha dbus-daemon[827]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 21 21:21:06 volumio-alpha systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 21 21:21:06 volumio-alpha bash[993]: Time is already synchronized. Jun 21 21:21:06 volumio-alpha systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 21 21:21:06 volumio-alpha systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 21 21:21:06 volumio-alpha haveged[799]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Jun 21 21:21:06 volumio-alpha haveged[799]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356 Jun 21 21:21:06 volumio-alpha haveged[799]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00006 Jun 21 21:21:06 volumio-alpha haveged[799]: haveged: fills: 0, generated: 0 Jun 21 21:21:06 volumio-alpha ntpd[1011]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Jun 21 21:21:06 volumio-alpha bluealsa[967]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Jun 21 21:21:06 volumio-alpha bluealsa[967]: main.c:663: Starting main dispatching loop Jun 21 21:21:06 volumio-alpha systemd[1]: Started bluealsa.service - BlueALSA service. Jun 21 21:21:06 volumio-alpha bluealsa[967]: main.c:118: Acquired D-Bus service name: org.bluealsa Jun 21 21:21:06 volumio-alpha systemd[1]: Reached target bluetooth.target - Bluetooth Support. Jun 21 21:21:06 volumio-alpha bluealsa[967]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Jun 21 21:21:06 volumio-alpha bluealsa[967]: bluez.c:153: Registering media application: /org/bluez/hci0 Jun 21 21:21:06 volumio-alpha bluealsa[967]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Jun 21 21:21:06 volumio-alpha bluealsa[967]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Jun 21 21:21:06 volumio-alpha bluealsa[967]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Jun 21 21:21:06 volumio-alpha bluealsa[967]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 Jun 21 21:21:06 volumio-alpha bluetoothd[826]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 Jun 21 21:21:07 volumio-alpha systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Jun 21 21:21:07 volumio-alpha systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Jun 21 21:21:07 volumio-alpha systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jun 21 21:21:07 volumio-alpha systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jun 21 21:21:07 volumio-alpha systemd[1]: Reached target rpc_pipefs.target. Jun 21 21:21:07 volumio-alpha 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). Jun 21 21:21:07 volumio-alpha systemd[1]: Reached target nfs-client.target - NFS client services. Jun 21 21:21:07 volumio-alpha systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 21 21:21:07 volumio-alpha systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 21 21:21:07 volumio-alpha systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Jun 21 21:21:07 volumio-alpha systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jun 21 21:21:07 volumio-alpha 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). Jun 21 21:21:07 volumio-alpha systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 21 21:21:07 volumio-alpha systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 21 21:21:07 volumio-alpha systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Jun 21 21:21:07 volumio-alpha ifplugd(eth0)[1075]: ifplugd 0.28 initializing. Jun 21 21:21:07 volumio-alpha ifplugd(eth0)[1075]: Using interface eth0/D8:3A:DD:A6:E1:27 with driver (version: 6.12.27-v8+) Jun 21 21:21:07 volumio-alpha ifplugd(eth0)[1075]: Using detection mode: SIOCETHTOOL Jun 21 21:21:07 volumio-alpha ifplugd(eth0)[1075]: Initialization complete, link beat not detected. Jun 21 21:21:07 volumio-alpha nmbd[1060]: [2025/06/21 21:21:07.241370, 0] ../../source3/nmbd/nmbd.c:901(main) Jun 21 21:21:07 volumio-alpha nmbd[1060]: nmbd version 4.17.8-Raspbian started. Jun 21 21:21:07 volumio-alpha nmbd[1060]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 21 21:21:07 volumio-alpha nmbd[1060]: [2025/06/21 21:21:07.243467, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 21 21:21:07 volumio-alpha nmbd[1060]: started asyncdns process 1080 Jun 21 21:21:07 volumio-alpha nmbd[1060]: [2025/06/21 21:21:07.244504, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jun 21 21:21:07 volumio-alpha nmbd[1060]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 21 21:21:07 volumio-alpha nmbd[1060]: [2025/06/21 21:21:07.244697, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jun 21 21:21:07 volumio-alpha nmbd[1060]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 21 21:21:07 volumio-alpha loadcpufreq[1056]: Loading cpufreq kernel modules...done (none). Jun 21 21:21:07 volumio-alpha systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jun 21 21:21:07 volumio-alpha systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jun 21 21:21:07 volumio-alpha sudo[1110]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 21 21:21:07 volumio-alpha sudo[1110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:07 volumio-alpha sudo[1110]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:07 volumio-alpha sudo[1115]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 21 21:21:07 volumio-alpha sudo[1115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:07 volumio-alpha ifplugd[1054]: Network Interface Plugging Daemon...start eth0...done. Jun 21 21:21:07 volumio-alpha systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Jun 21 21:21:07 volumio-alpha cpufrequtils[1108]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 21 21:21:07 volumio-alpha systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jun 21 21:21:07 volumio-alpha avahi-daemon[825]: Server startup complete. Host name is volumio-alpha.local. Local service cookie is 355902464. Jun 21 21:21:07 volumio-alpha sudo[1115]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:07 volumio-alpha sudo[1132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 21 21:21:07 volumio-alpha sudo[1132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:07 volumio-alpha ntpd[1011]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:07 volumio-alpha kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 21 21:21:07 volumio-alpha ntpd[1011]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 21 21:21:07 volumio-alpha ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jun 21 21:21:07 volumio-alpha ntpd[1011]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jun 21 21:21:07 volumio-alpha sudo[1132]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:07 volumio-alpha sudo[1135]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 21 21:21:07 volumio-alpha sudo[1135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:07 volumio-alpha sudo[1135]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:07 volumio-alpha sudo[1143]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 21 21:21:07 volumio-alpha sudo[1143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:07 volumio-alpha sudo[1143]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:07 volumio-alpha sudo[1145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 21 21:21:07 volumio-alpha sudo[1145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:08 volumio-alpha avahi-daemon[825]: Service "Volumio Alpha" (/services/volumio.service) successfully established. Jun 21 21:21:08 volumio-alpha ntpd[1011]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:08 volumio-alpha ntpd[1011]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 21 21:21:08 volumio-alpha ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jun 21 21:21:08 volumio-alpha ntpd[1011]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jun 21 21:21:09 volumio-alpha ntpd[1011]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:09 volumio-alpha ntpd[1011]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 21 21:21:09 volumio-alpha ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jun 21 21:21:09 volumio-alpha ntpd[1011]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jun 21 21:21:10 volumio-alpha dhcpcd[890]: eth0: carrier acquired Jun 21 21:21:10 volumio-alpha sh[797]: eth0: carrier acquired Jun 21 21:21:10 volumio-alpha dhcpcd[807]: eth0: carrier acquired Jun 21 21:21:10 volumio-alpha kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control tx Jun 21 21:21:10 volumio-alpha dhcpcd[890]: eth0: IAID dd:a6:e1:27 Jun 21 21:21:10 volumio-alpha sh[797]: eth0: IAID dd:a6:e1:27 Jun 21 21:21:10 volumio-alpha dhcpcd[807]: eth0: IAID dd:a6:e1:27 Jun 21 21:21:10 volumio-alpha dhcpcd[807]: eth0: adding address fe80::a384:2960:ca1a:90f5 Jun 21 21:21:10 volumio-alpha sh[797]: eth0: adding address fe80::a384:2960:ca1a:90f5 Jun 21 21:21:10 volumio-alpha dhcpcd[890]: eth0: adding address fe80::a384:2960:ca1a:90f5 Jun 21 21:21:10 volumio-alpha sh[797]: ipv6_addaddr1: Permission denied Jun 21 21:21:10 volumio-alpha dhcpcd[807]: ipv6_addaddr1: Permission denied Jun 21 21:21:10 volumio-alpha dhcpcd[890]: ipv6_addaddr1: Permission denied Jun 21 21:21:10 volumio-alpha sudo[1145]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:10 volumio-alpha sudo[1155]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 21 21:21:10 volumio-alpha sudo[1155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:10 volumio-alpha sudo[1155]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:10 volumio-alpha sudo[1157]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 21 21:21:10 volumio-alpha sudo[1157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:10 volumio-alpha sudo[1157]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:10 volumio-alpha sudo[1167]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 21 21:21:10 volumio-alpha sudo[1167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:10 volumio-alpha sudo[1167]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:10 volumio-alpha sudo[1169]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 21 21:21:10 volumio-alpha sudo[1169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:10 volumio-alpha dhcpcd[807]: eth0: soliciting a DHCP lease Jun 21 21:21:10 volumio-alpha sh[797]: eth0: soliciting a DHCP lease Jun 21 21:21:10 volumio-alpha sh[797]: eth0: offered 192.168.0.229 from 192.168.0.1 Jun 21 21:21:10 volumio-alpha dhcpcd[807]: eth0: offered 192.168.0.229 from 192.168.0.1 Jun 21 21:21:10 volumio-alpha sh[797]: eth0: probing address 192.168.0.229/24 Jun 21 21:21:10 volumio-alpha dhcpcd[807]: eth0: probing address 192.168.0.229/24 Jun 21 21:21:10 volumio-alpha systemd[1]: systemd-rfkill.service: Deactivated successfully. Jun 21 21:21:10 volumio-alpha ntpd[1011]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:10 volumio-alpha ntpd[1011]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 21 21:21:10 volumio-alpha ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jun 21 21:21:10 volumio-alpha ntpd[1011]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jun 21 21:21:11 volumio-alpha sudo[1169]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:11 volumio-alpha wpa_supplicant[1177]: Successfully initialized wpa_supplicant Jun 21 21:21:11 volumio-alpha sudo[1178]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 21 21:21:11 volumio-alpha sudo[1178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:11 volumio-alpha dhcpcd[890]: control command: /sbin/dhcpcd Jun 21 21:21:11 volumio-alpha dhcpcd[890]: control_free: No such file or directory Jun 21 21:21:11 volumio-alpha sudo[1178]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:11 volumio-alpha dhcpcd[890]: lo: IAID 00:00:00:00 Jun 21 21:21:11 volumio-alpha dhcpcd[890]: lo: adding address fe80::110b:e7a0:105b:1274 Jun 21 21:21:11 volumio-alpha dhcpcd[890]: ipv6_addaddr1: Permission denied Jun 21 21:21:11 volumio-alpha dhcpcd[890]: lo: using static address 127.0.0.1/8 Jun 21 21:21:11 volumio-alpha dhcpcd[890]: lo: adding route to 127.0.0.0/8 Jun 21 21:21:11 volumio-alpha kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 21 21:21:11 volumio-alpha systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Jun 21 21:21:11 volumio-alpha ifplugd(eth0)[1075]: Link beat detected. Jun 21 21:21:11 volumio-alpha wpa_supplicant[1177]: nl80211: kernel reports: Registration to specific type not supported Jun 21 21:21:11 volumio-alpha dhcpcd[807]: eth0: soliciting an IPv6 router Jun 21 21:21:11 volumio-alpha sh[797]: eth0: soliciting an IPv6 router Jun 21 21:21:11 volumio-alpha ifplugd(eth0)[1075]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 21 21:21:11 volumio-alpha ifplugd(eth0)[1075]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jun 21 21:21:11 volumio-alpha dhcpcd[890]: eth0: soliciting an IPv6 router Jun 21 21:21:11 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:11] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 21 21:21:11 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:11] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 21 21:21:11 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:11] [error] handle_connect error: Connection refused Jun 21 21:21:11 volumio-alpha dhcpcd[890]: eth0: soliciting a DHCP lease Jun 21 21:21:11 volumio-alpha dhcpcd[890]: eth0: offered 192.168.0.229 from 192.168.0.1 Jun 21 21:21:11 volumio-alpha dhcpcd[890]: eth0: probing address 192.168.0.229/24 Jun 21 21:21:12 volumio-alpha sudo[1213]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:12 volumio-alpha sudo[1213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:12 volumio-alpha sudo[1213]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:13 volumio-alpha sudo[1217]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:13 volumio-alpha sudo[1217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:13 volumio-alpha sudo[1217]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:13 volumio-alpha wpa_supplicant[1201]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jun 21 21:21:13 volumio-alpha wpa_supplicant[1201]: wlan0: Trying to associate with 40:3f:8c:ec:ba:8c (SSID='OramNet' freq=5500 MHz) Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: wlan0: Associated with 40:3f:8c:ec:ba:8c Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: wlan0: WPA: Key negotiation completed with 40:3f:8c:ec:ba:8c [PTK=CCMP GTK=TKIP] Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: wlan0: CTRL-EVENT-CONNECTED - Connection to 40:3f:8c:ec:ba:8c completed [id=0 id_str=] Jun 21 21:21:14 volumio-alpha dhcpcd[890]: wlan0: carrier acquired Jun 21 21:21:14 volumio-alpha dhcpcd[890]: wlan0: connected to Access Point: OramNet Jun 21 21:21:14 volumio-alpha dhcpcd[890]: wlan0: IAID dd:a6:e1:29 Jun 21 21:21:14 volumio-alpha dhcpcd[890]: wlan0: adding address fe80::aa45:6ce0:82c0:b79a Jun 21 21:21:14 volumio-alpha dhcpcd[890]: ipv6_addaddr1: Permission denied Jun 21 21:21:14 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:21:14 volumio-alpha sudo[1222]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:14 volumio-alpha sudo[1222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:14 volumio-alpha sudo[1222]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:14 volumio-alpha dhcpcd[890]: wlan0: soliciting an IPv6 router Jun 21 21:21:15 volumio-alpha sudo[1226]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:15 volumio-alpha sudo[1226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:15 volumio-alpha sudo[1226]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:15 volumio-alpha dhcpcd[890]: wlan0: rebinding lease of 192.168.0.231 Jun 21 21:21:15 volumio-alpha dhcpcd[890]: wlan0: probing address 192.168.0.231/24 Jun 21 21:21:15 volumio-alpha dhcpcd[807]: eth0: leased 192.168.0.229 for infinity Jun 21 21:21:15 volumio-alpha sh[797]: eth0: leased 192.168.0.229 for infinity Jun 21 21:21:15 volumio-alpha sh[797]: eth0: adding route to 192.168.0.0/24 Jun 21 21:21:15 volumio-alpha avahi-daemon[825]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.229. Jun 21 21:21:15 volumio-alpha dhcpcd[807]: eth0: adding route to 192.168.0.0/24 Jun 21 21:21:15 volumio-alpha avahi-daemon[825]: New relevant interface eth0.IPv4 for mDNS. Jun 21 21:21:15 volumio-alpha avahi-daemon[825]: Registering new address record for 192.168.0.229 on eth0.IPv4. Jun 21 21:21:15 volumio-alpha dhcpcd[807]: eth0: adding default route via 192.168.0.1 Jun 21 21:21:15 volumio-alpha sh[797]: eth0: adding default route via 192.168.0.1 Jun 21 21:21:15 volumio-alpha sh[797]: forked to background, child pid 806 Jun 21 21:21:15 volumio-alpha systemd[1]: welcome.service: Deactivated successfully. Jun 21 21:21:15 volumio-alpha systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 21 21:21:15 volumio-alpha systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 21 21:21:15 volumio-alpha systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 21 21:21:15 volumio-alpha systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 21 21:21:15 volumio-alpha welcome[1253]: Resolved ip:[1] 192.168.0.229 Jun 21 21:21:15 volumio-alpha systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jun 21 21:21:15 volumio-alpha systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 21 21:21:15 volumio-alpha systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 21 21:21:16 volumio-alpha ifplugd(eth0)[1075]: client: ifup: interface eth0 already configured Jun 21 21:21:16 volumio-alpha sh[1285]: eth0=eth0 Jun 21 21:21:16 volumio-alpha winbindd[1284]: [2025/06/21 21:21:16.120977, 0] ../../source3/winbindd/winbindd.c:1440(main) Jun 21 21:21:16 volumio-alpha winbindd[1284]: winbindd version 4.17.8-Raspbian started. Jun 21 21:21:16 volumio-alpha winbindd[1284]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 21 21:21:16 volumio-alpha winbindd[1284]: [2025/06/21 21:21:16.125085, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Jun 21 21:21:16 volumio-alpha winbindd[1284]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 21 21:21:16 volumio-alpha ifplugd(eth0)[1075]: Program executed successfully. Jun 21 21:21:16 volumio-alpha systemd[1]: Started winbind.service - Samba Winbind Daemon. Jun 21 21:21:16 volumio-alpha systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 21 21:21:16 volumio-alpha sudo[1305]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:16 volumio-alpha sudo[1305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:16 volumio-alpha smbd[1302]: [2025/06/21 21:21:16.256234, 0] ../../source3/smbd/server.c:1741(main) Jun 21 21:21:16 volumio-alpha smbd[1302]: smbd version 4.17.8-Raspbian started. Jun 21 21:21:16 volumio-alpha smbd[1302]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 21 21:21:16 volumio-alpha sudo[1305]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:16 volumio-alpha systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 21 21:21:16 volumio-alpha dhcpcd[890]: eth0: leased 192.168.0.229 for infinity Jun 21 21:21:16 volumio-alpha dhcpcd[890]: eth0: adding route to 192.168.0.0/24 Jun 21 21:21:16 volumio-alpha dhcpcd[890]: eth0: adding default route via 192.168.0.1 Jun 21 21:21:16 volumio-alpha systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 21 21:21:16 volumio-alpha systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 21 21:21:16 volumio-alpha systemd[1]: welcome.service: Deactivated successfully. Jun 21 21:21:16 volumio-alpha systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 21 21:21:16 volumio-alpha systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 21 21:21:16 volumio-alpha systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 21 21:21:16 volumio-alpha welcome[1333]: Resolved ip:[1] 192.168.0.229 Jun 21 21:21:16 volumio-alpha systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 21 21:21:16 volumio-alpha systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 21 21:21:17 volumio-alpha sudo[1340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:17 volumio-alpha sudo[1340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:17 volumio-alpha sudo[1340]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:17 volumio-alpha ntpd[1011]: IO: Listen normally on 3 eth0 192.168.0.229:123 Jun 21 21:21:17 volumio-alpha ntpd[1011]: IO: new interface(s) found: waking up resolver Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: Pool taking: 194.164.127.129 Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: Pool taking: 131.111.8.60 Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: Pool taking: 131.111.8.61 Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: Pool taking: 85.199.214.102 Jun 21 21:21:17 volumio-alpha ntpd[1011]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 21 21:21:18 volumio-alpha sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:18 volumio-alpha sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:18 volumio-alpha sudo[1345]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:18 volumio-alpha ntpd[1011]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 217.154.60.177 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 139.162.219.252 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 149.22.188.7 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 85.199.214.98 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 2a00:2381:19c6::100 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 2a0c:b840:2:6a::a Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 2a02:6b67:d551:8f04:: Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: Pool taking: 2001:678:8::123 Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 21 21:21:19 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:19] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 21 21:21:19 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:19] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 21 21:21:19 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:19] [error] handle_connect error: Connection refused Jun 21 21:21:19 volumio-alpha sudo[1350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:19 volumio-alpha sudo[1350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:19 volumio-alpha sudo[1350]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:19 volumio-alpha ntpd[1011]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:20 volumio-alpha dhcpcd[890]: wlan0: leased 192.168.0.231 for 7200 seconds Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.231. Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: New relevant interface wlan0.IPv4 for mDNS. Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: Registering new address record for 192.168.0.231 on wlan0.IPv4. Jun 21 21:21:20 volumio-alpha dhcpcd[890]: wlan0: adding route to 192.168.0.0/24 Jun 21 21:21:20 volumio-alpha systemd[1]: welcome.service: Deactivated successfully. Jun 21 21:21:20 volumio-alpha systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 21 21:21:20 volumio-alpha systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 21 21:21:20 volumio-alpha dhcpcd[890]: wlan0: adding default route via 192.168.0.1 Jun 21 21:21:20 volumio-alpha systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 21 21:21:20 volumio-alpha welcome[1366]: Resolved ip:[2] 192.168.0.229 192.168.0.231 Jun 21 21:21:20 volumio-alpha systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 21 21:21:20 volumio-alpha systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 217.114.59.3 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 109.74.206.120 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 131.111.8.63 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 85.199.214.101 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 21 21:21:20 volumio-alpha sudo[1379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 21:21:20 volumio-alpha sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:20 volumio-alpha sudo[1379]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:20 volumio-alpha sudo[1382]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 21:21:20 volumio-alpha sudo[1382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:20 volumio-alpha sudo[1382]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:20 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:21:20 volumio-alpha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 21 21:21:20 volumio-alpha systemd[1]: shairport-sync.service: Deactivated successfully. Jun 21 21:21:20 volumio-alpha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 21 21:21:20 volumio-alpha systemd[1]: shairport-sync.service: Consumed 1.589s CPU time. Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: Got SIGTERM, quitting. Jun 21 21:21:20 volumio-alpha systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.231. Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.229. Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 21 21:21:20 volumio-alpha dbus-daemon[827]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.12' (uid=0 pid=1302 comm="/usr/sbin/smbd --foreground --no-process-group") Jun 21 21:21:20 volumio-alpha avahi-daemon[825]: avahi-daemon 0.8 exiting. Jun 21 21:21:20 volumio-alpha systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 21 21:21:20 volumio-alpha systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 21 21:21:20 volumio-alpha systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Process 825 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Successfully dropped root privileges. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: avahi-daemon 0.8 starting up. Jun 21 21:21:20 volumio-alpha dbus-daemon[827]: [system] Successfully activated service 'org.freedesktop.Avahi' Jun 21 21:21:20 volumio-alpha systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Successfully called chroot(). Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Successfully dropped remaining capabilities. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Loading service file /services/volumio.service. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.231. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: New relevant interface wlan0.IPv4 for mDNS. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.229. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: New relevant interface eth0.IPv4 for mDNS. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: New relevant interface lo.IPv4 for mDNS. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Network interface enumeration completed. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Registering new address record for 192.168.0.231 on wlan0.IPv4. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Registering new address record for 192.168.0.229 on eth0.IPv4. Jun 21 21:21:20 volumio-alpha avahi-daemon[1387]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 21 21:21:20 volumio-alpha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 21 21:21:20 volumio-alpha kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 21 21:21:20 volumio-alpha systemd[1]: Started wireless.service - Wireless Services. Jun 21 21:21:20 volumio-alpha systemd[1]: Started volumio.service - Volumio Backend Module. Jun 21 21:21:20 volumio-alpha systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jun 21 21:21:20 volumio-alpha systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jun 21 21:21:20 volumio-alpha systemd[1]: Received SIGRTMIN+21 from PID 288 (plymouthd). Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1407]: Setting RT Priority for mpd Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1423]: pid 35's current scheduling policy: SCHED_OTHER Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1423]: pid 35's current scheduling priority: 0 Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1407]: Setting MPD Affinity Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1424]: pid 3's current affinity mask: f Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1407]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 21 21:21:20 volumio-alpha volumio-cpu-tweak[1407]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Jun 21 21:21:20 volumio-alpha systemd[1]: Received SIGRTMIN+21 from PID 288 (plymouthd). Jun 21 21:21:20 volumio-alpha systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jun 21 21:21:20 volumio-alpha systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jun 21 21:21:20 volumio-alpha systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 21 21:21:20 volumio-alpha systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Jun 21 21:21:20 volumio-alpha systemd[1]: Reached target getty.target - Login Prompts. Jun 21 21:21:20 volumio-alpha systemd[1]: Reached target multi-user.target - Multi-User System. Jun 21 21:21:20 volumio-alpha systemd[1]: Reached target graphical.target - Graphical Interface. Jun 21 21:21:20 volumio-alpha systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jun 21 21:21:20 volumio-alpha systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jun 21 21:21:20 volumio-alpha systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jun 21 21:21:20 volumio-alpha systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jun 21 21:21:20 volumio-alpha systemd[1]: Startup finished in 10.742s (kernel) + 15.641s (userspace) = 26.384s. Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 162.159.200.123 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 162.159.200.1 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 178.62.68.79 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: Pool taking: 176.58.109.199 Jun 21 21:21:20 volumio-alpha ntpd[1011]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 21 21:21:20 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:20 volumio-alpha volumio[1393]: info: ----- Volumio3 ---- Jun 21 21:21:20 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:20 volumio-alpha volumio[1393]: info: ----- System startup ---- Jun 21 21:21:20 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:21 volumio-alpha volumio[1393]: info: MYVOLUMIO Environment detected Jun 21 21:21:21 volumio-alpha avahi-daemon[1387]: Server startup complete. Host name is volumio-alpha.local. Local service cookie is 3891076280. Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Plugin folders cleanup Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning into folder /volumio/app/plugins/ Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category audio_interface Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category miscellanea Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category music_service Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category plugins.json Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category system_controller Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category user_interface Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning into folder /data/plugins/ Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category music_service Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Scanning category system_controller Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Plugin folders cleanup completed Jun 21 21:21:21 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:21 volumio-alpha volumio[1393]: info: ----- Core plugins startup ---- Jun 21 21:21:21 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugins from folder /volumio/app/plugins/ Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Adding plugin upnp to MyMusic Plugins Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugins from folder /data/plugins/ Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "system"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "appearance"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "network"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Refreshing Cached IP Addresses Jun 21 21:21:21 volumio-alpha sudo[1445]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 21:21:21 volumio-alpha sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:21 volumio-alpha sudo[1445]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:21 volumio-alpha sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 21:21:21 volumio-alpha sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "services"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "alsa_controller"... Jun 21 21:21:21 volumio-alpha sudo[1447]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:21 volumio-alpha sudo[1455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 21 21:21:21 volumio-alpha sudo[1455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:21 volumio-alpha volumio[1393]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Jun 21 21:21:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "wizard"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "networkfs"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Starting Udev Watcher for removable devices Jun 21 21:21:21 volumio-alpha sudo[1476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.166/Music /mnt/NAS/Music Jun 21 21:21:21 volumio-alpha sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Ignoring mount for partition: boot Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Ignoring mount for partition: volumio Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Ignoring mount for partition: volumio_data Jun 21 21:21:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "volumio_command_line_client"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "upnp"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: [1750537281666] Starting Upmpd Daemon Jun 21 21:21:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "my_music"... Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "mpd"... Jun 21 21:21:21 volumio-alpha kernel: netfs: FS-Cache loaded Jun 21 21:21:21 volumio-alpha kernel: Key type cifs.spnego registered Jun 21 21:21:21 volumio-alpha kernel: Key type cifs.idmap registered Jun 21 21:21:21 volumio-alpha 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. Jun 21 21:21:21 volumio-alpha kernel: CIFS: Attempting to mount //192.168.0.166/Music Jun 21 21:21:21 volumio-alpha volumio[1393]: info: Loading plugin "upnp_browser"... Jun 21 21:21:21 volumio-alpha sudo[1476]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:21 volumio-alpha ntpd[1011]: IO: Listen normally on 4 wlan0 192.168.0.231:123 Jun 21 21:21:21 volumio-alpha ntpd[1011]: IO: new interface(s) found: waking up resolver Jun 21 21:21:22 volumio-alpha avahi-daemon[1387]: Service "Volumio Alpha" (/services/volumio.service) successfully established. Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "alarm-clock"... Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "airplay_emulation"... Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Starting Shairport Sync Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "last_100"... Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "webradio"... Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "i2s_dacs"... Jun 21 21:21:22 volumio-alpha volumio[1393]: info: I2S DAC not set, start Auto-detection Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "volumiodiscovery"... Jun 21 21:21:22 volumio-alpha volumio[1393]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 21 21:21:22 volumio-alpha node[1393]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 21 21:21:22 volumio-alpha volumio[1393]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 21:21:22 volumio-alpha volumio[1393]: *** WARNING *** For more information see Jun 21 21:21:22 volumio-alpha volumio[1393]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 21 21:21:22 volumio-alpha volumio[1393]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 21:21:22 volumio-alpha volumio[1393]: *** WARNING *** For more information see Jun 21 21:21:22 volumio-alpha node[1393]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 21:21:22 volumio-alpha node[1393]: *** WARNING *** For more information see Jun 21 21:21:22 volumio-alpha node[1393]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 21 21:21:22 volumio-alpha node[1393]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 21:21:22 volumio-alpha node[1393]: *** WARNING *** For more information see Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Discovery: Started advertising with name: Volumio Jun 21 21:21:22 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "spop"... Jun 21 21:21:22 volumio-alpha volumio[1393]: info: Loading plugin "ytmusic"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "outputs"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "albumart"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Plugin example_plugin is not enabled Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "inputs"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "updater_comm"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Plugin mpdemulation is not enabled Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "rest_api"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "websocket"... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Starting Socket.io Server version 1.7.4 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading plugin "backup_restore"... Jun 21 21:21:23 volumio-alpha volumio[1508]: Forking 3 albumart workers Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Applying required configuration parameters for plugin backup_restore Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Loading i18n strings for locale en Jun 21 21:21:23 volumio-alpha volumio[1393]: Updating browse sources language Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::initPlayerControls Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: Express server listening on port 3000 Jun 21 21:21:23 volumio-alpha volumio[1393]: [Metrics] WebUI: 2s 655.45ms Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::resetVolumioState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::getcurrentVolume Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::pushState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioPushState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Volumio Network Manager: Network status updated: 3 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Reloading queue from file Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::setRepeat null single undefined Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::pushState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioPushState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::setRandom false Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreStateMachine::pushState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioPushState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Setting Device type: Raspberry PI Jun 21 21:21:23 volumio-alpha volumio[1393]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 21 21:21:23 volumio-alpha volumio[1393]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 13 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Discovery: adding 42726e1c-0f86-42b7-a80c-f768d91e4d78 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Discovery: Found device Volumio Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 14 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 15 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 16 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 17 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 18 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Discovery: this is already registered, 42726e1c-0f86-42b7-a80c-f768d91e4d78 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Discovery: Found device Volumio Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Discovery: this is already registered, 42726e1c-0f86-42b7-a80c-f768d91e4d78 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Discovery: Found device Volumio Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 19 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 20 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Completed loading Core Plugins Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Preparing to generate the ALSA configuration file Jun 21 21:21:23 volumio-alpha volumio[1518]: Starting albumart workers Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 21 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 22 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 23 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 24 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 25 Jun 21 21:21:23 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 26 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Asound.conf file unchanged, so no further update is needed Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Output device has changed, restarting MPD Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Output device has changed, restarting Shairport Sync Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1520]: Starting albumart workers Jun 21 21:21:23 volumio-alpha sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 21 21:21:23 volumio-alpha sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:23 volumio-alpha sudo[1564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 21 21:21:23 volumio-alpha sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:23 volumio-alpha sudo[1562]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: ___________ START PLUGINS ___________ Jun 21 21:21:23 volumio-alpha volumio[1393]: info: ControllerMpd::onStart: Initializing MPD Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Creating MPD Configuration file Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 21:21:23 volumio-alpha volumio[1393]: info: [1750537283655] CoreMusicLibrary::Adding element Media Servers Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:23 volumio-alpha sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 21 21:21:23 volumio-alpha sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:23 volumio-alpha sudo[1575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 21 21:21:23 volumio-alpha sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:23 volumio-alpha sudo[1573]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:23 volumio-alpha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 21 21:21:23 volumio-alpha systemd[1]: Starting mpd.service - Music Player Daemon... Jun 21 21:21:23 volumio-alpha volumio[1519]: Starting albumart workers Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 21:21:23 volumio-alpha systemd[1]: mpd.service: Deactivated successfully. Jun 21 21:21:23 volumio-alpha systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 21 21:21:23 volumio-alpha systemd[1]: mpd.socket: Deactivated successfully. Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:23 volumio-alpha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 21 21:21:23 volumio-alpha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 21 21:21:23 volumio-alpha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 21 21:21:23 volumio-alpha systemd[1]: Starting mpd.service - Music Player Daemon... Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 21:21:23 volumio-alpha volumio[1393]: info: [1750537283713] CoreMusicLibrary::Adding element Last_100 Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 21:21:23 volumio-alpha volumio[1393]: info: [1750537283713] CoreMusicLibrary::Adding element Webradio Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Initializing BBC Radios Jun 21 21:21:23 volumio-alpha sudo[1588]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 21 21:21:23 volumio-alpha sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:23 volumio-alpha sudo[1597]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 21 21:21:23 volumio-alpha sudo[1588]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Creating Spotify config file Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 21:21:23 volumio-alpha volumio[1393]: info: [1750537283875] CoreMusicLibrary::Adding element YouTube Music Jun 21 21:21:23 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:23 volumio-alpha volumio[1393]: Cannot find translation for source YouTube Music Jun 21 21:21:23 volumio-alpha volumio[1393]: info: Volumio Calling Home Jun 21 21:21:24 volumio-alpha ntpd[1011]: CLOCK: time stepped by 0.152778 Jun 21 21:21:24 volumio-alpha ntpd[1011]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 27 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 28 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 29 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 30 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 31 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 32 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 33 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 34 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 35 Jun 21 21:21:24 volumio-alpha volumio[1393]: info: MPD Permissions set Jun 21 21:21:24 volumio-alpha volumio[1393]: info: MPD Permissions set Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Spotify config file written Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Jun 21 21:21:24 volumio-alpha volumio[1393]: info: No need to fix Spotify hosts Jun 21 21:21:24 volumio-alpha sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 21 21:21:24 volumio-alpha sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:24 volumio-alpha systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:24 volumio-alpha systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Volumio called home Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha go-librespot[1635]: go-librespot daemon starting... Jun 21 21:21:24 volumio-alpha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 37 Jun 21 21:21:24 volumio-alpha sudo[1633]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 38 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 39 Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Starting Shairport Sync Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Starting Shairport Sync Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 40 Jun 21 21:21:24 volumio-alpha sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 21:21:24 volumio-alpha sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:24 volumio-alpha sudo[1656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 21:21:24 volumio-alpha sudo[1656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 41 Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Starting Shairport Sync Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=info msg="running go-librespot 0.2.0" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="app state loaded" Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 42 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 43 Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 21 21:21:24 volumio-alpha sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 21:21:24 volumio-alpha sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:24 volumio-alpha systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 21 21:21:24 volumio-alpha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 21 21:21:24 volumio-alpha systemd[1]: shairport-sync.service: Deactivated successfully. Jun 21 21:21:24 volumio-alpha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 21 21:21:24 volumio-alpha systemd[1]: shairport-sync.service: Consumed 1.523s CPU time. Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:24 volumio-alpha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 21 21:21:24 volumio-alpha sudo[1654]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:24 volumio-alpha sudo[1656]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:24 volumio-alpha setdatetime-helper.sh[1661]: Time is not synchronized. Attempting to sync... Jun 21 21:21:24 volumio-alpha sudo[1660]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Shairport-Sync Started Jun 21 21:21:24 volumio-alpha volumio[1393]: Error adding Membership: Error: addMembership EINVAL Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Shairport-Sync Started Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Shairport-Sync Started Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 44 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 45 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 46 Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=info msg="zeroconf server listening on port 39509" Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 47 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 48 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 49 Jun 21 21:21:24 volumio-alpha volumio[1393]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 21 21:21:24 volumio-alpha volumio[1393]: SPOTIFY: BQC2ozqPZV8JOeljOW69CZFqNkCyamK3xpkwgwWFKzRPXjU8hyn4zS9QGEWgF-SL0tn8UczDmgylBoDeIE-JcVRYHzL-ok3En0-nBrCMSK5n26x8uf3ky0wcmS3rIYCqvjNJhX54NRrrvY0NY3EbMFQDxlV2HEpe1_GTkF_dectYGWhlq1mLIg7UfbBEHOmHZByY0dKHsFdbe5GjvmgMuQywX0mrQDJ_T2PVvvXDji7ub89P1yrMz_ilLx6NARigmyfeIB4RXpKQIX-RsxtSUQ Jun 21 21:21:24 volumio-alpha volumio[1393]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 21 21:21:24 volumio-alpha volumio[1393]: info: New Spotify access token = BQC2ozqPZV8JOeljOW69CZFqNkCyamK3xpkwgwWFKzRPXjU8hyn4zS9QGEWgF-SL0tn8UczDmgylBoDeIE-JcVRYHzL-ok3En0-nBrCMSK5n26x8uf3ky0wcmS3rIYCqvjNJhX54NRrrvY0NY3EbMFQDxlV2HEpe1_GTkF_dectYGWhlq1mLIg7UfbBEHOmHZByY0dKHsFdbe5GjvmgMuQywX0mrQDJ_T2PVvvXDji7ub89P1yrMz_ilLx6NARigmyfeIB4RXpKQIX-RsxtSUQ Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="obtained new client token: AAAT2keN+XvoTYwSCHbOyHewvmptcjNjzN024zx4YO3Nj/OaOXMyE3ScijJmxL3kL08w30WBhg7X3+MInzqLeYPbxhYmyjEZaWPU1xQwVfkcm9bu9jdkXe+ML1N7gKkLgtFaZJDojOMakrblXzBVJiPF2Ge2oVGi4WDa/uUQDi19EyuaTSdwl39wGovMnuhyzGEqjwuQ2sOPtV8mTTZ7F0JNUYdxWiHvsqR/eHbn0jSgn6ztMaUB2pPUe4k=" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jun 21 21:21:24 volumio-alpha sudo[1686]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 21 Jun 2025 20:21:24 GMT#015' Jun 21 21:21:24 volumio-alpha sudo[1686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 21:21:24 volumio-alpha setdatetime-helper.sh[1687]: Sat Jun 21 21:21:24 BST 2025 Jun 21 21:21:24 volumio-alpha sudo[1686]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:24 volumio-alpha setdatetime-helper.sh[1661]: Time synchronized successfully. Jun 21 21:21:24 volumio-alpha systemd-journald[417]: Time jumped backwards, rotating. Jun 21 21:21:24 volumio-alpha systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 21 21:21:24 volumio-alpha systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="completed keyexchange" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="completed challenge" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=info msg="authenticated AP as oram01" Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 50 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 51 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 52 Jun 21 21:21:24 volumio-alpha volumio[1393]: SPOTIFY: User informations: {"country":"GB","display_name":"oram01","email":"stephen.oram@gmx.co.uk","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/oram01"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/oram01","id":"oram01","images":[],"product":"premium","type":"user","uri":"spotify:user:oram01"} Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Spotify Successfully logged in Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 21:21:24 volumio-alpha volumio[1393]: info: [1750537284063] CoreMusicLibrary::Adding element Spotify Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:24 volumio-alpha volumio[1393]: Cannot find translation for source YouTube Music Jun 21 21:21:24 volumio-alpha volumio[1393]: Cannot find translation for source Spotify Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 53 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 54 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 55 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 56 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 57 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 58 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 59 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 60 Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=info msg="authenticated Login5 as oram01" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="initializing zeroconf session, username: oram01" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="dealer connection opened" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=trace msg="starting accesspoint recv loop" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=trace msg="starting dealer recv loop" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=trace msg="received accesspoint ping" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="received connection id: ZmZjNThlNzgtZGRhZS00ZTE1LTkwMDEtZTUxNmE0NmRiMjIxK2RlYWxlcit0Y3A6Ly8wYWNhNDBlZi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArNzdCNUY3OTVEQ0QwM0Y5NDU5N0IzRkJEMTRCMEU4REJFMURDODY2RjNEMEY1QUI3NTJEM0I3QTYzOTgyQTI1MA==" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=trace msg="received accesspoint pong ack" Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 61 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 62 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 63 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 64 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 65 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 66 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 67 Jun 21 21:21:24 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 68 Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 21:21:24 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:24 volumio-alpha volumio[1393]: info: Listing playlists Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="put connect state because NEW_DEVICE" Jun 21 21:21:24 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:24+01:00" level=debug msg="update volume to 65535/65535" Jun 21 21:21:24 volumio-alpha sudo[1455]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:24 volumio-alpha mpd[1600]: 2025-06-21T21:21:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 21 21:21:25 volumio-alpha systemd[1]: Started mpd.service - Music Player Daemon. Jun 21 21:21:25 volumio-alpha sudo[1575]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:25 volumio-alpha sudo[1564]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:25 volumio-alpha volumio[1393]: info: Completed starting Core Plugins Jun 21 21:21:25 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:25 volumio-alpha volumio[1393]: info: ----- MyVolumio plugins startup ---- Jun 21 21:21:25 volumio-alpha volumio[1393]: info: ------------------------------------------- Jun 21 21:21:25 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 21 21:21:25 volumio-alpha volumio[1393]: error: MPD error: The expression evaluated to a falsy value: Jun 21 21:21:25 volumio-alpha volumio[1393]: assert.ok(self.idling) Jun 21 21:21:25 volumio-alpha volumio[1393]: error: The expression evaluated to a falsy value: Jun 21 21:21:25 volumio-alpha volumio[1393]: assert.ok(self.idling) Jun 21 21:21:25 volumio-alpha volumio[1393]: info: MPD running with PID1600 Jun 21 21:21:25 volumio-alpha volumio[1393]: ,establishing connection Jun 21 21:21:25 volumio-alpha volumio[1393]: error: updateQueue error: null Jun 21 21:21:25 volumio-alpha volumio[1393]: error: updateQueue error: null Jun 21 21:21:25 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:25+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 21 21:21:25 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:25+01:00" level=trace msg="emitting websocket event: volume" Jun 21 21:21:26 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:21:26 volumio-alpha volumio[1393]: info: go-librespot daemon successfully initialized Jun 21 21:21:28 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:28] [connect] Successful connection Jun 21 21:21:28 volumio-alpha volumio-remote-updater[842]: [2025-06-21 21:21:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750537288 101 Jun 21 21:21:28 volumio-alpha volumio[1393]: 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: 69 Jun 21 21:21:29 volumio-alpha volumio[1393]: info: Initializing connection to go-librespot Websocket Jun 21 21:21:29 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:29+01:00" level=debug msg="new websocket client" Jun 21 21:21:29 volumio-alpha volumio[1393]: info: Connection to go-librespot Websocket established Jun 21 21:21:30 volumio-alpha volumio[1393]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 21 21:21:31 volumio-alpha sudo[1711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 21:21:31 volumio-alpha sudo[1711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:31 volumio-alpha sudo[1711]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:31 volumio-alpha sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 21:21:31 volumio-alpha sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:31 volumio-alpha sudo[1714]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:32 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:21:32 volumio-alpha volumio[1393]: info: Getting Spotify volume Jun 21 21:21:33 volumio-alpha volumio[1393]: info: Spotify volume: 100 Jun 21 21:21:33 volumio-alpha sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 21 21:21:33 volumio-alpha sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:33 volumio-alpha systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 21 21:21:33 volumio-alpha sudo[1717]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:33 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 70 Jun 21 21:21:33 volumio-alpha volumio[1393]: info: Upmpdcli Daemon Started Jun 21 21:21:35 volumio-alpha systemd[1]: systemd-fsckd.service: Deactivated successfully. Jun 21 21:21:36 volumio-alpha systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 21 21:21:36 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:36 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:37 volumio-alpha sudo[1741]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 21:21:37 volumio-alpha sudo[1741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:37 volumio-alpha sudo[1741]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:37 volumio-alpha sudo[1743]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 21:21:37 volumio-alpha sudo[1743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:37 volumio-alpha sudo[1743]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:37 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 70 Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 21 21:21:37 volumio-alpha volumio[1393]: info: Received Get System Version Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:37 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jun 21 21:21:38 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin bluetooth to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin multiroom to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin metavolumio to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin cd_controller to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 21 21:21:38 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:39 volumio-alpha volumio[1393]: info: Starting MyVolumio Remote Streaming Endpoints Jun 21 21:21:39 volumio-alpha volumio[1393]: info: MyVolumio login type: Token Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 21 21:21:39 volumio-alpha volumio[1393]: info: Streaming services startup Jun 21 21:21:39 volumio-alpha volumio[1393]: info: Starting Streaming Daemon Jun 21 21:21:39 volumio-alpha sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 21 21:21:39 volumio-alpha sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:39 volumio-alpha volumio[1393]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 21 21:21:39 volumio-alpha sudo[1767]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:39 volumio-alpha volumio[1393]: error: Cannot start Volumio Streaming Daemon Jun 21 21:21:39 volumio-alpha volumio[1393]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 21 21:21:39 volumio-alpha volumio[1393]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 21 21:21:39 volumio-alpha volumio[1393]: error: MyVolumio Custom Token format not valid, refreshing it Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 21:21:39 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:21:39 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:39 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 21:21:40 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:21:40 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:21:40 volumio-alpha volumio[1393]: info: MyVolumio login type: Token Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 21 21:21:40 volumio-alpha volumio[1393]: info: MyVolumio login type: Token Jun 21 21:21:40 volumio-alpha volumio[1393]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 21 21:21:40 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 21 21:21:40 volumio-alpha volumio[1393]: info: MyVolumio login type: Token Jun 21 21:21:41 volumio-alpha volumio[1393]: info: MyVolumio token set successfully Jun 21 21:21:41 volumio-alpha volumio[1393]: info: MYVOLUMIO: Adding device Jun 21 21:21:41 volumio-alpha volumio[1393]: info: MYVOLUMIO: Evaluating Server Jun 21 21:21:41 volumio-alpha upmpdcli[1775]: writing RSA key Jun 21 21:21:41 volumio-alpha volumio[1393]: info: MyVolumio status changed Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Streaming services startup Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Starting Streaming Daemon Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Removing browser output: myVolumio user plan is not superstar Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Removing audio output: Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Stoppping Tunnel 1 Jun 21 21:21:41 volumio-alpha sudo[1795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 21 21:21:41 volumio-alpha sudo[1795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:41 volumio-alpha sudo[1797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 21 21:21:41 volumio-alpha sudo[1797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:21:41 volumio-alpha sudo[1795]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:41 volumio-alpha volumio[1393]: error: Cannot start Volumio Streaming Daemon Jun 21 21:21:41 volumio-alpha volumio[1393]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 21 21:21:41 volumio-alpha volumio[1393]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 21 21:21:41 volumio-alpha sudo[1797]: pam_unix(sudo:session): session closed for user root Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Remote SSH Stopped Jun 21 21:21:41 volumio-alpha volumio[1393]: info: Setting Geolocation for MyVolumio to eu3 Jun 21 21:21:41 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:41 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:41 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:41 volumio-alpha volumio[1393]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 21:21:42 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:21:42 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:21:42 volumio-alpha volumio[1393]: info: Updating MyVolumio device info Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 21:21:42 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:21:42 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:42 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:21:43 volumio-alpha volumio[1393]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 21 21:21:44 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:21:45 volumio-alpha volumio[1393]: info: MYVOLUMIO: Adding device Jun 21 21:21:45 volumio-alpha volumio[1393]: info: MYVOLUMIO: Evaluating Server Jun 21 21:21:46 volumio-alpha volumio[1393]: info: Setting Geolocation for MyVolumio to eu3 Jun 21 21:21:46 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:46 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:46 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:46 volumio-alpha volumio[1393]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 21 21:21:47 volumio-alpha volumio[1393]: info: Updating MyVolumio device info Jun 21 21:21:47 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:47 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:47 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:47 volumio-alpha volumio[1393]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 21 21:21:51 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 21 21:21:51 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetQueue Jun 21 21:21:51 volumio-alpha volumio[1393]: info: CoreStateMachine::getQueue Jun 21 21:21:51 volumio-alpha volumio[1393]: info: CorePlayQueue::getQueue Jun 21 21:21:51 volumio-alpha volumio[1393]: info: Listing playlists Jun 21 21:21:51 volumio-alpha volumio[1393]: info: Preload queue cleared Jun 21 21:21:53 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:53 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:53 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 21 21:21:53 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 21 21:21:53 volumio-alpha volumio-remote-updater[842]: Test mode enabled Jun 21 21:21:53 volumio-alpha volumio-remote-updater[842]: Alpha mode disabled Jun 21 21:21:53 volumio-alpha volumio-remote-updater[842]: Alpha legacy test mode disabled Jun 21 21:21:53 volumio-alpha volumio[1393]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 21 21:21:53 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 21 21:21:53 volumio-alpha systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 21 21:21:54 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 21:21:54 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 71 Jun 21 21:21:54 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:54 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:54 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:54+01:00" level=trace msg="sent dealer ping" Jun 21 21:21:54 volumio-alpha go-librespot[1646]: time="2025-06-21T21:21:54+01:00" level=trace msg="received dealer pong" Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 21 21:21:55 volumio-alpha volumio[1393]: info: Received Get System Version Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:55 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:21:55 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:21:55 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetBrowseSources Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:21:59 volumio-alpha volumio[1393]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 21 21:21:59 volumio-alpha volumio[1393]: info: Completed starting MyVolumio Plugin Jun 21 21:21:59 volumio-alpha volumio[1393]: [Metrics] CommandRouter: 38s 686.05ms Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumiosetStartupVolume Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::Close All Modals sent Jun 21 21:21:59 volumio-alpha volumio[1393]: info: CoreCommandRouter::Close All Modals sent Jun 21 21:21:59 volumio-alpha volumio[1393]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 21 21:21:59 volumio-alpha volumio[1393]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for C20 Jun 21 21:21:59 volumio-alpha volumio[1393]: aplay: main:831: audio open error: No such device Jun 21 21:22:00 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 21 21:22:00 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 21 21:22:00 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 21 21:22:00 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 21 21:22:00 volumio-alpha volumio[1393]: info: Version has changed, forcing UI Reload Jun 21 21:22:00 volumio-alpha volumio[1393]: info: CoreCommandRouter::Reload Ui Jun 21 21:22:00 volumio-alpha sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 21:22:00 volumio-alpha sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:22:00 volumio-alpha sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 21:22:00 volumio-alpha sudo[1856]: pam_unix(sudo:session): session closed for user root Jun 21 21:22:00 volumio-alpha sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 21:22:00 volumio-alpha sudo[1858]: pam_unix(sudo:session): session closed for user root Jun 21 21:22:00 volumio-alpha volumio[1393]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 71 Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetQueue Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreStateMachine::getQueue Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CorePlayQueue::getQueue Jun 21 21:22:01 volumio-alpha volumio[1393]: info: Listing playlists Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 21:22:01 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 21:22:02 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 21:22:03 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:22:03 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 21:22:03 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:22:03 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:22:03 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:22:06 volumio-alpha volumio[1393]: info: BOOT COMPLETED Jun 21 21:22:11 volumio-alpha wpa_supplicant[1201]: RRM: Ignoring radio measurement request: Not RRM network Jun 21 21:22:17 volumio-alpha volumio[1393]: Searching all installed plugins Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 21 21:22:17 volumio-alpha volumio[1393]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jun 21 21:22:17 volumio-alpha volumio[1393]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Jun 21 21:22:17 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 21 21:22:19 volumio-alpha volumio[1393]: info: An error occurred while querying SHOUTCAST Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 21 21:22:19 volumio-alpha volumio[1393]: info: Received Get System Version Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 21:22:19 volumio-alpha volumio[1393]: info: Received Get System Info Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 21:22:19 volumio-alpha volumio[1393]: info: Discovery: Getting this device information Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::volumioGetState Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CorePlayQueue::getTrack 0 Jun 21 21:22:19 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 21:22:19 volumio-alpha volumio[1393]: info: [ytmusic] InnertubeLoader: obtaining po_token by datasyncIdToken... Jun 21 21:22:20 volumio-alpha volumio[1393]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Jun 21 21:22:20 volumio-alpha volumio[1393]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Jun 21 21:22:20 volumio-alpha volumio[1393]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Jun 21 21:22:21 volumio-alpha volumio[1393]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Jun 21 21:22:21 volumio-alpha volumio[1393]: Searching all installed plugins Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 21 21:22:21 volumio-alpha volumio[1393]: Searching all installed plugins Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jun 21 21:22:21 volumio-alpha volumio[1393]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 21 21:22:21 volumio-alpha volumio[1393]: Use XMLElement.setAttribute instead Jun 21 21:22:21 volumio-alpha volumio[1393]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 21:22:21 volumio-alpha volumio[1393]: TypeError: children[i].attr(...).value is not a function Jun 21 21:22:21 volumio-alpha volumio[1393]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 21 21:22:21 volumio-alpha volumio[1393]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 21 21:22:21 volumio-alpha volumio[1393]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 21:22:21 volumio-alpha sudo[1918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-21 21:21' Jun 21 21:22:21 volumio-alpha sudo[1918]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"