-- Logs begin at Mon 2025-06-30 21:31:48 SAST, end at Mon 2025-06-30 21:32:22 SAST. -- Jun 30 21:31:48 goddank-vir-klank kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Jun 30 21:31:48 goddank-vir-klank kernel: Linux version 6.6.62-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) #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 Jun 30 21:31:48 goddank-vir-klank kernel: KASLR enabled Jun 30 21:31:48 goddank-vir-klank kernel: random: crng init done Jun 30 21:31:48 goddank-vir-klank kernel: Machine model: Raspberry Pi 5 Model B Rev 1.1 Jun 30 21:31:48 goddank-vir-klank kernel: efi: UEFI not found. Jun 30 21:31:48 goddank-vir-klank kernel: Reserved memory: created CMA memory pool at 0x0000000001c00000, size 64 MiB Jun 30 21:31:48 goddank-vir-klank kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jun 30 21:31:48 goddank-vir-klank kernel: OF: reserved mem: 0x0000000001c00000..0x0000000005bfffff (65536 KiB) map reusable linux,cma Jun 30 21:31:48 goddank-vir-klank kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Jun 30 21:31:48 goddank-vir-klank kernel: OF: reserved mem: 0x000000003fd16260..0x000000003fd16297 (0 KiB) nomap non-reusable nvram@0 Jun 30 21:31:48 goddank-vir-klank kernel: NUMA: No NUMA configuration found Jun 30 21:31:48 goddank-vir-klank kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: NUMA: NODE_DATA [mem 0x1fef953c0-0x1fef97fff] Jun 30 21:31:48 goddank-vir-klank kernel: Zone ranges: Jun 30 21:31:48 goddank-vir-klank kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: DMA32 empty Jun 30 21:31:48 goddank-vir-klank kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: Movable zone start for each node Jun 30 21:31:48 goddank-vir-klank kernel: Early memory node ranges Jun 30 21:31:48 goddank-vir-klank kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Jun 30 21:31:48 goddank-vir-klank kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Jun 30 21:31:48 goddank-vir-klank kernel: node 0: [mem 0x0000000040000000-0x00000001ffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: On node 0, zone DMA: 1024 pages in unavailable ranges Jun 30 21:31:48 goddank-vir-klank kernel: psci: probing for conduit method from DT. Jun 30 21:31:48 goddank-vir-klank kernel: psci: PSCIv1.1 detected in firmware. Jun 30 21:31:48 goddank-vir-klank kernel: psci: Using standard PSCI v0.2 function IDs Jun 30 21:31:48 goddank-vir-klank kernel: psci: MIGRATE_INFO_TYPE not supported. Jun 30 21:31:48 goddank-vir-klank kernel: psci: SMC Calling Convention v1.2 Jun 30 21:31:48 goddank-vir-klank kernel: percpu: Embedded 30 pages/cpu s85928 r8192 d28760 u122880 Jun 30 21:31:48 goddank-vir-klank kernel: pcpu-alloc: s85928 r8192 d28760 u122880 alloc=30*4096 Jun 30 21:31:48 goddank-vir-klank kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jun 30 21:31:48 goddank-vir-klank kernel: Detected PIPT I-cache on CPU0 Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Virtualization Host Extensions Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Hardware dirty bit management Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Spectre-v4 Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Spectre-BHB Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: kernel page table isolation forced ON by KASLR Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Kernel page table isolation (KPTI) Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: SSBS not fully self-synchronizing Jun 30 21:31:48 goddank-vir-klank kernel: alternatives: applying boot alternatives Jun 30 21:31:48 goddank-vir-klank kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave smsc95xx.macaddr=2C:CF:67:EE:46:BB 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=ee200d97-771e-4dc2-bc54-5383b3c7262f imgfile=/volumio_current.sqsh bootpart=UUID=6D56-80AF datapart=UUID=9da5fc58-f342-443d-83b5-60353d527d53 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 30 21:31:48 goddank-vir-klank kernel: cgroup: Disabling memory control group subsystem Jun 30 21:31:48 goddank-vir-klank kernel: mempolicy: NUMA default policy overridden to 'interleave:0' Jun 30 21:31:48 goddank-vir-klank kernel: PCIe ASPM is disabled Jun 30 21:31:48 goddank-vir-klank kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=ee200d97-771e-4dc2-bc54-5383b3c7262f imgfile=/volumio_current.sqsh bootpart=UUID=6D56-80AF datapart=UUID=9da5fc58-f342-443d-83b5-60353d527d53 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Jun 30 21:31:48 goddank-vir-klank kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: Fallback order for Node 0: 0 Jun 30 21:31:48 goddank-vir-klank kernel: Built 1 zonelists, mobility grouping on. Total pages: 2063360 Jun 30 21:31:48 goddank-vir-klank kernel: Policy zone: Normal Jun 30 21:31:48 goddank-vir-klank kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 30 21:31:48 goddank-vir-klank kernel: software IO TLB: area num 4. Jun 30 21:31:48 goddank-vir-klank kernel: software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB) Jun 30 21:31:48 goddank-vir-klank kernel: Memory: 8037284K/8384512K available (13440K kernel code, 2208K rwdata, 4284K rodata, 4864K init, 1086K bss, 281692K reserved, 65536K cma-reserved) Jun 30 21:31:48 goddank-vir-klank kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jun 30 21:31:48 goddank-vir-klank kernel: ftrace: allocating 43555 entries in 171 pages Jun 30 21:31:48 goddank-vir-klank kernel: ftrace: allocated 171 pages with 5 groups Jun 30 21:31:48 goddank-vir-klank kernel: trace event string verifier disabled Jun 30 21:31:48 goddank-vir-klank kernel: rcu: Preemptible hierarchical RCU implementation. Jun 30 21:31:48 goddank-vir-klank kernel: rcu: RCU event tracing is enabled. Jun 30 21:31:48 goddank-vir-klank kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Jun 30 21:31:48 goddank-vir-klank kernel: Trampoline variant of Tasks RCU enabled. Jun 30 21:31:48 goddank-vir-klank kernel: Rude variant of Tasks RCU enabled. Jun 30 21:31:48 goddank-vir-klank kernel: Tracing variant of Tasks RCU enabled. Jun 30 21:31:48 goddank-vir-klank kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Jun 30 21:31:48 goddank-vir-klank kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jun 30 21:31:48 goddank-vir-klank kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jun 30 21:31:48 goddank-vir-klank kernel: Root IRQ handler: gic_handle_irq Jun 30 21:31:48 goddank-vir-klank kernel: GIC: Using split EOI/Deactivate mode Jun 30 21:31:48 goddank-vir-klank kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 30 21:31:48 goddank-vir-klank kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Jun 30 21:31:48 goddank-vir-klank kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Jun 30 21:31:48 goddank-vir-klank kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Jun 30 21:31:48 goddank-vir-klank kernel: Console: colour dummy device 80x25 Jun 30 21:31:48 goddank-vir-klank kernel: printk: console [tty1] enabled Jun 30 21:31:48 goddank-vir-klank kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Jun 30 21:31:48 goddank-vir-klank kernel: pid_max: default: 32768 minimum: 301 Jun 30 21:31:48 goddank-vir-klank kernel: LSM: initializing lsm=capability,integrity Jun 30 21:31:48 goddank-vir-klank kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 30 21:31:48 goddank-vir-klank kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 30 21:31:48 goddank-vir-klank kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 30 21:31:48 goddank-vir-klank kernel: rcu: Hierarchical SRCU implementation. Jun 30 21:31:48 goddank-vir-klank kernel: rcu: Max phase no-delay instances is 1000. Jun 30 21:31:48 goddank-vir-klank kernel: EFI services will not be available. Jun 30 21:31:48 goddank-vir-klank kernel: smp: Bringing up secondary CPUs ... Jun 30 21:31:48 goddank-vir-klank kernel: Detected PIPT I-cache on CPU1 Jun 30 21:31:48 goddank-vir-klank kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Jun 30 21:31:48 goddank-vir-klank kernel: Detected PIPT I-cache on CPU2 Jun 30 21:31:48 goddank-vir-klank kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Jun 30 21:31:48 goddank-vir-klank kernel: Detected PIPT I-cache on CPU3 Jun 30 21:31:48 goddank-vir-klank kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Jun 30 21:31:48 goddank-vir-klank kernel: smp: Brought up 1 node, 4 CPUs Jun 30 21:31:48 goddank-vir-klank kernel: SMP: Total of 4 processors activated. Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: 32-bit EL0 Support Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Common not Private translations Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: CRC32 instructions Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: LSE atomic instructions Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Privileged Access Never Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: RAS Extension Support Jun 30 21:31:48 goddank-vir-klank kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jun 30 21:31:48 goddank-vir-klank kernel: CPU: All CPU(s) started at EL2 Jun 30 21:31:48 goddank-vir-klank kernel: alternatives: applying system-wide alternatives Jun 30 21:31:48 goddank-vir-klank kernel: devtmpfs: initialized Jun 30 21:31:48 goddank-vir-klank kernel: Enabled cp15_barrier support Jun 30 21:31:48 goddank-vir-klank kernel: Enabled setend support Jun 30 21:31:48 goddank-vir-klank kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jun 30 21:31:48 goddank-vir-klank kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: pinctrl core: initialized pinctrl subsystem Jun 30 21:31:48 goddank-vir-klank kernel: DMI not present or invalid. Jun 30 21:31:48 goddank-vir-klank kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 30 21:31:48 goddank-vir-klank kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Jun 30 21:31:48 goddank-vir-klank kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jun 30 21:31:48 goddank-vir-klank kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jun 30 21:31:48 goddank-vir-klank kernel: audit: initializing netlink subsys (disabled) Jun 30 21:31:48 goddank-vir-klank kernel: audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 Jun 30 21:31:48 goddank-vir-klank kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 30 21:31:48 goddank-vir-klank kernel: cpuidle: using governor menu Jun 30 21:31:48 goddank-vir-klank kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jun 30 21:31:48 goddank-vir-klank kernel: ASID allocator initialised with 32768 entries Jun 30 21:31:48 goddank-vir-klank kernel: Serial: AMBA PL011 UART driver Jun 30 21:31:48 goddank-vir-klank kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Jun 30 21:31:48 goddank-vir-klank kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 Jun 30 21:31:48 goddank-vir-klank kernel: printk: console [ttyAMA10] enabled Jun 30 21:31:48 goddank-vir-klank kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-09-23T13:02:56, variant start_cd Jun 30 21:31:48 goddank-vir-klank kernel: raspberrypi-firmware soc:firmware: Firmware hash is 2682625900000000000000000000000000000000 Jun 30 21:31:48 goddank-vir-klank kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jun 30 21:31:48 goddank-vir-klank kernel: Modules: 0 pages in range for non-PLT usage Jun 30 21:31:48 goddank-vir-klank kernel: Modules: 517776 pages in range for PLT usage Jun 30 21:31:48 goddank-vir-klank kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Jun 30 21:31:48 goddank-vir-klank kernel: iommu: Default domain type: Translated Jun 30 21:31:48 goddank-vir-klank kernel: iommu: DMA domain TLB invalidation policy: strict mode Jun 30 21:31:48 goddank-vir-klank kernel: SCSI subsystem initialized Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver usbfs Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver hub Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new device driver usb Jun 30 21:31:48 goddank-vir-klank kernel: pps_core: LinuxPPS API ver. 1 registered Jun 30 21:31:48 goddank-vir-klank kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 30 21:31:48 goddank-vir-klank kernel: PTP clock support registered Jun 30 21:31:48 goddank-vir-klank kernel: vgaarb: loaded Jun 30 21:31:48 goddank-vir-klank kernel: clocksource: Switched to clocksource arch_sys_counter Jun 30 21:31:48 goddank-vir-klank kernel: VFS: Disk quotas dquot_6.6.0 Jun 30 21:31:48 goddank-vir-klank kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 30 21:31:48 goddank-vir-klank kernel: FS-Cache: Loaded Jun 30 21:31:48 goddank-vir-klank kernel: CacheFiles: Loaded Jun 30 21:31:48 goddank-vir-klank kernel: NET: Registered PF_INET protocol family Jun 30 21:31:48 goddank-vir-klank kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: TCP: Hash tables configured (established 65536 bind 65536) Jun 30 21:31:48 goddank-vir-klank kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Jun 30 21:31:48 goddank-vir-klank kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 30 21:31:48 goddank-vir-klank kernel: RPC: Registered named UNIX socket transport module. Jun 30 21:31:48 goddank-vir-klank kernel: RPC: Registered udp transport module. Jun 30 21:31:48 goddank-vir-klank kernel: RPC: Registered tcp transport module. Jun 30 21:31:48 goddank-vir-klank kernel: RPC: Registered tcp-with-tls transport module. Jun 30 21:31:48 goddank-vir-klank kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jun 30 21:31:48 goddank-vir-klank kernel: PCI: CLS 0 bytes, default 64 Jun 30 21:31:48 goddank-vir-klank kernel: kvm [1]: IPA Size Limit: 40 bits Jun 30 21:31:48 goddank-vir-klank kernel: kvm [1]: vgic interrupt IRQ9 Jun 30 21:31:48 goddank-vir-klank kernel: kvm [1]: VHE mode initialized successfully Jun 30 21:31:48 goddank-vir-klank kernel: Trying to unpack rootfs image as initramfs... Jun 30 21:31:48 goddank-vir-klank kernel: Initialise system trusted keyrings Jun 30 21:31:48 goddank-vir-klank kernel: workingset: timestamp_bits=42 max_order=21 bucket_order=0 Jun 30 21:31:48 goddank-vir-klank kernel: zbud: loaded Jun 30 21:31:48 goddank-vir-klank kernel: NFS: Registering the id_resolver key type Jun 30 21:31:48 goddank-vir-klank kernel: Key type id_resolver registered Jun 30 21:31:48 goddank-vir-klank kernel: Key type id_legacy registered Jun 30 21:31:48 goddank-vir-klank kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jun 30 21:31:48 goddank-vir-klank kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jun 30 21:31:48 goddank-vir-klank kernel: Key type asymmetric registered Jun 30 21:31:48 goddank-vir-klank kernel: Asymmetric key parser 'x509' registered Jun 30 21:31:48 goddank-vir-klank kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jun 30 21:31:48 goddank-vir-klank kernel: io scheduler mq-deadline registered Jun 30 21:31:48 goddank-vir-klank kernel: io scheduler kyber registered Jun 30 21:31:48 goddank-vir-klank kernel: io scheduler bfq registered Jun 30 21:31:48 goddank-vir-klank kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 26) Jun 30 21:31:48 goddank-vir-klank kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27) Jun 30 21:31:48 goddank-vir-klank kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28) Jun 30 21:31:48 goddank-vir-klank kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29) Jun 30 21:31:48 goddank-vir-klank kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 30) Jun 30 21:31:48 goddank-vir-klank kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 31) Jun 30 21:31:48 goddank-vir-klank kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Jun 30 21:31:48 goddank-vir-klank kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Jun 30 21:31:48 goddank-vir-klank kernel: serial serial0: tty port ttyS0 registered Jun 30 21:31:48 goddank-vir-klank kernel: iproc-rng200 107d208000.rng: hwrng registered Jun 30 21:31:48 goddank-vir-klank kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Jun 30 21:31:48 goddank-vir-klank kernel: brd: module loaded Jun 30 21:31:48 goddank-vir-klank kernel: loop: module loaded Jun 30 21:31:48 goddank-vir-klank kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jun 30 21:31:48 goddank-vir-klank kernel: Loading iSCSI transport class v2.0-870. Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new device driver r8152-cfgselector Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver r8152 Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver lan78xx Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver smsc95xx Jun 30 21:31:48 goddank-vir-klank kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jun 30 21:31:48 goddank-vir-klank kernel: dwc_otg: FIQ enabled Jun 30 21:31:48 goddank-vir-klank kernel: dwc_otg: NAK holdoff enabled Jun 30 21:31:48 goddank-vir-klank kernel: dwc_otg: FIQ split-transaction FSM enabled Jun 30 21:31:48 goddank-vir-klank kernel: Module dwc_common_port init Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver uas Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver usb-storage Jun 30 21:31:48 goddank-vir-klank kernel: mousedev: PS/2 mouse device common for all mice Jun 30 21:31:48 goddank-vir-klank kernel: rpi-rtc soc:rpi_rtc: registered as rtc0 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-rtc soc:rpi_rtc: setting system clock to 2025-06-30T19:31:37 UTC (1751311897) Jun 30 21:31:48 goddank-vir-klank kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Jun 30 21:31:48 goddank-vir-klank kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jun 30 21:31:48 goddank-vir-klank kernel: sdhci: Secure Digital Host Controller Interface driver Jun 30 21:31:48 goddank-vir-klank kernel: sdhci: Copyright(c) Pierre Ossman Jun 30 21:31:48 goddank-vir-klank kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jun 30 21:31:48 goddank-vir-klank kernel: ledtrig-cpu: registered to indicate activity on CPUs Jun 30 21:31:48 goddank-vir-klank kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Jun 30 21:31:48 goddank-vir-klank kernel: hid: raw HID events driver (C) Jiri Kosina Jun 30 21:31:48 goddank-vir-klank kernel: usbcore: registered new interface driver usbhid Jun 30 21:31:48 goddank-vir-klank kernel: usbhid: USB HID core driver Jun 30 21:31:48 goddank-vir-klank kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available Jun 30 21:31:48 goddank-vir-klank kernel: NET: Registered PF_PACKET protocol family Jun 30 21:31:48 goddank-vir-klank kernel: Key type dns_resolver registered Jun 30 21:31:48 goddank-vir-klank kernel: registered taskstats version 1 Jun 30 21:31:48 goddank-vir-klank kernel: Loading compiled-in X.509 certificates Jun 30 21:31:48 goddank-vir-klank kernel: Key type .fscrypt registered Jun 30 21:31:48 goddank-vir-klank kernel: Key type fscrypt-provisioning registered Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@110000 ranges: Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@110000, using [bus 00-ff] Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: Forcing gen 2 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0000:00 Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jun 30 21:31:48 goddank-vir-klank kernel: Freeing initrd memory: 28084K Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000110000.pcie: link down Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512 Jun 30 21:31:48 goddank-vir-klank kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 38 Jun 30 21:31:48 goddank-vir-klank kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 38 Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:01: busn_res: [bus 01] is released Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: busn_res: [bus 00-ff] is released Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: Forcing gen 2 Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00 Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jun 30 21:31:48 goddank-vir-klank kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: supports D1 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold Jun 30 21:31:48 goddank-vir-klank kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jun 30 21:31:48 goddank-vir-klank kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Jun 30 21:31:48 goddank-vir-klank kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Jun 30 21:31:48 goddank-vir-klank kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 39 Jun 30 21:31:48 goddank-vir-klank kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 39 Jun 30 21:31:48 goddank-vir-klank kernel: rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Jun 30 21:31:48 goddank-vir-klank kernel: rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Jun 30 21:31:48 goddank-vir-klank kernel: rp1 0000:01:00.0: enabling device (0000 -> 0002) Jun 30 21:31:48 goddank-vir-klank kernel: rp1 0000:01:00.0: chip_id 0x20001927 Jun 30 21:31:48 goddank-vir-klank kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101 Jun 30 21:31:48 goddank-vir-klank kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (2c:cf:67:ee:46:bb) Jun 30 21:31:48 goddank-vir-klank kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Jun 30 21:31:48 goddank-vir-klank kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb1: Product: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: usb usb1: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Jun 30 21:31:48 goddank-vir-klank kernel: usb usb1: SerialNumber: xhci-hcd.0 Jun 30 21:31:48 goddank-vir-klank kernel: hub 1-0:1.0: USB hub found Jun 30 21:31:48 goddank-vir-klank kernel: hub 1-0:1.0: 2 ports detected Jun 30 21:31:48 goddank-vir-klank kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb2: Product: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: usb usb2: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Jun 30 21:31:48 goddank-vir-klank kernel: usb usb2: SerialNumber: xhci-hcd.0 Jun 30 21:31:48 goddank-vir-klank kernel: hub 2-0:1.0: USB hub found Jun 30 21:31:48 goddank-vir-klank kernel: hub 2-0:1.0: 1 port detected Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Jun 30 21:31:48 goddank-vir-klank kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Jun 30 21:31:48 goddank-vir-klank kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb3: Product: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: usb usb3: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Jun 30 21:31:48 goddank-vir-klank kernel: usb usb3: SerialNumber: xhci-hcd.1 Jun 30 21:31:48 goddank-vir-klank kernel: hub 3-0:1.0: USB hub found Jun 30 21:31:48 goddank-vir-klank kernel: hub 3-0:1.0: 2 ports detected Jun 30 21:31:48 goddank-vir-klank kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 30 21:31:48 goddank-vir-klank kernel: usb usb4: Product: xHCI Host Controller Jun 30 21:31:48 goddank-vir-klank kernel: usb usb4: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Jun 30 21:31:48 goddank-vir-klank kernel: usb usb4: SerialNumber: xhci-hcd.1 Jun 30 21:31:48 goddank-vir-klank kernel: hub 4-0:1.0: USB hub found Jun 30 21:31:48 goddank-vir-klank kernel: hub 4-0:1.0: 1 port detected Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000800000.codec: Adding to iommu group 0 Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000880000.pisp_be: Adding to iommu group 0 Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jun 30 21:31:48 goddank-vir-klank kernel: platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Jun 30 21:31:48 goddank-vir-klank kernel: platform axi:gpu: Adding to iommu group 1 Jun 30 21:31:48 goddank-vir-klank kernel: platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Jun 30 21:31:48 goddank-vir-klank kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Jun 30 21:31:48 goddank-vir-klank kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Jun 30 21:31:48 goddank-vir-klank kernel: mmc0: CQHCI version 5.10 Jun 30 21:31:48 goddank-vir-klank kernel: mmc1: CQHCI version 5.10 Jun 30 21:31:48 goddank-vir-klank kernel: of_cfs_init Jun 30 21:31:48 goddank-vir-klank kernel: of_cfs_init: OK Jun 30 21:31:48 goddank-vir-klank kernel: clk: Disabling unused clocks Jun 30 21:31:48 goddank-vir-klank kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Jun 30 21:31:48 goddank-vir-klank kernel: mmc0: Command Queue Engine enabled, 31 tags Jun 30 21:31:48 goddank-vir-klank kernel: mmc0: new ultra high speed SDR104 SDHC card at address 59b4 Jun 30 21:31:48 goddank-vir-klank kernel: mmcblk0: mmc0:59b4 USD00 29.5 GiB Jun 30 21:31:48 goddank-vir-klank kernel: mmcblk0: p1 p2 p3 Jun 30 21:31:48 goddank-vir-klank kernel: mmcblk0: mmc0:59b4 USD00 29.5 GiB Jun 30 21:31:48 goddank-vir-klank kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Jun 30 21:31:48 goddank-vir-klank kernel: Freeing unused kernel memory: 4864K Jun 30 21:31:48 goddank-vir-klank kernel: Run /init as init process Jun 30 21:31:48 goddank-vir-klank kernel: with arguments: Jun 30 21:31:48 goddank-vir-klank kernel: /init Jun 30 21:31:48 goddank-vir-klank kernel: splash Jun 30 21:31:48 goddank-vir-klank kernel: nodebug Jun 30 21:31:48 goddank-vir-klank kernel: with environment: Jun 30 21:31:48 goddank-vir-klank kernel: HOME=/ Jun 30 21:31:48 goddank-vir-klank kernel: TERM=linux Jun 30 21:31:48 goddank-vir-klank kernel: imgpart=UUID=ee200d97-771e-4dc2-bc54-5383b3c7262f Jun 30 21:31:48 goddank-vir-klank kernel: imgfile=/volumio_current.sqsh Jun 30 21:31:48 goddank-vir-klank kernel: bootpart=UUID=6D56-80AF Jun 30 21:31:48 goddank-vir-klank kernel: datapart=UUID=9da5fc58-f342-443d-83b5-60353d527d53 Jun 30 21:31:48 goddank-vir-klank kernel: uuidconfig=cmdline.txt Jun 30 21:31:48 goddank-vir-klank kernel: bootdelay=7 Jun 30 21:31:48 goddank-vir-klank kernel: use_kmsg=no Jun 30 21:31:48 goddank-vir-klank kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_info_register (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_device_new (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_register_device (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_info_register (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_device_new (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: snd_timer: Unknown symbol snd_register_device (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Jun 30 21:31:48 goddank-vir-klank kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Jun 30 21:31:48 goddank-vir-klank kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Jun 30 21:31:48 goddank-vir-klank kernel: [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 0 Jun 30 21:31:48 goddank-vir-klank kernel: fuse: init (API version 7.39) Jun 30 21:31:48 goddank-vir-klank kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 30 21:31:48 goddank-vir-klank kernel: EXT4-fs (mmcblk0p2): recovery complete Jun 30 21:31:48 goddank-vir-klank kernel: EXT4-fs (mmcblk0p2): mounted filesystem ee200d97-771e-4dc2-bc54-5383b3c7262f r/w with ordered data mode. Quota mode: none. Jun 30 21:31:48 goddank-vir-klank kernel: loop0: detected capacity change from 0 to 1004352 Jun 30 21:31:48 goddank-vir-klank kernel: EXT4-fs (mmcblk0p3): recovery complete Jun 30 21:31:48 goddank-vir-klank kernel: EXT4-fs (mmcblk0p3): mounted filesystem 9da5fc58-f342-443d-83b5-60353d527d53 r/w with ordered data mode. Quota mode: none. Jun 30 21:31:48 goddank-vir-klank kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Jun 30 21:31:48 goddank-vir-klank kernel: NET: Registered PF_INET6 protocol family Jun 30 21:31:48 goddank-vir-klank kernel: Segment Routing with IPv6 Jun 30 21:31:48 goddank-vir-klank kernel: In-situ OAM (IOAM) with IPv6 Jun 30 21:31:48 goddank-vir-klank systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Jun 30 21:31:48 goddank-vir-klank systemd[1]: Detected architecture arm64. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Set hostname to . Jun 30 21:31:48 goddank-vir-klank kernel: uart-pl011 107d001000.serial: no DMA platform data Jun 30 21:31:48 goddank-vir-klank systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Created slice system-serial\x2dgetty.slice. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Listening on udev Kernel Socket. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Listening on Journal Socket. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounting RPC Pipe File System... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting Create list of required static device nodes for the current kernel... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting Remount Root and Kernel File Systems... Jun 30 21:31:48 goddank-vir-klank kernel: i2c_dev: i2c /dev entries driver Jun 30 21:31:48 goddank-vir-klank systemd-journald[392]: Journal started Jun 30 21:31:48 goddank-vir-klank systemd-journald[392]: Runtime journal (/run/log/journal/eea64a7df36cabb6e36ce31068484641) is 3.7M, max 30.0M, 26.2M free. Jun 30 21:31:48 goddank-vir-klank systemd-modules-load[385]: Inserted module 'i2c_dev' Jun 30 21:31:48 goddank-vir-klank fake-hwclock[381]: Current system time: 2025-06-30 19:31:48 Jun 30 21:31:48 goddank-vir-klank fake-hwclock[381]: fake-hwclock saved clock information is in the past: 2025-06-30 19:31:26 Jun 30 21:31:48 goddank-vir-klank fake-hwclock[381]: To set system time to this saved clock anyway, use "force" Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Restore / save the current clock. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounted FUSE Control File System. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Apply Kernel Variables. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Load/Save Random Seed. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started udev Coldplug all Devices. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 30 21:31:48 goddank-vir-klank systemd[1]: dynamicswap.service: Succeeded. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Create System Users. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting Create Static Device Nodes in /dev... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Create Static Device Nodes in /dev. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting udev Kernel Device Manager... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Reached target Local File Systems (Pre). Jun 30 21:31:48 goddank-vir-klank systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounting /tmp... Jun 30 21:31:48 goddank-vir-klank systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounting /var/log... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounting /var/spool/cups... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounted /tmp. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounted /var/log. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting Flush Journal to Persistent Storage... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounted /var/spool/cups. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounting /var/spool/cups/tmp... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Mounted /var/spool/cups/tmp. Jun 30 21:31:48 goddank-vir-klank systemd-udevd[413]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 30 21:31:48 goddank-vir-klank systemd-journald[392]: Runtime journal (/run/log/journal/eea64a7df36cabb6e36ce31068484641) is 3.7M, max 30.0M, 26.2M free. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started udev Kernel Device Manager. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting Show Plymouth Boot Screen... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Flush Journal to Persistent Storage. Jun 30 21:31:48 goddank-vir-klank systemd[1]: plymouth-start.service: Succeeded. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Show Plymouth Boot Screen. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Reached target Paths. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Reached target Local Encrypted Volumes. Jun 30 21:31:48 goddank-vir-klank kernel: mc: Linux media interface: v0.10 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Jun 30 21:31:48 goddank-vir-klank kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Jun 30 21:31:48 goddank-vir-klank systemd-udevd[450]: Using default interface naming scheme 'v240'. Jun 30 21:31:48 goddank-vir-klank kernel: videodev: Linux video capture interface: v2.00 Jun 30 21:31:48 goddank-vir-klank systemd[1]: Found device /dev/ttyAMA10. Jun 30 21:31:48 goddank-vir-klank systemd-udevd[450]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 30 21:31:48 goddank-vir-klank kernel: rp1_pio: Unknown symbol rp1_firmware_message (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: rp1_pio: Unknown symbol devm_rp1_firmware_get (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: rp1_pio: Unknown symbol rp1_firmware_get_feature (err -2) Jun 30 21:31:48 goddank-vir-klank kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jun 30 21:31:48 goddank-vir-klank kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Jun 30 21:31:48 goddank-vir-klank kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Jun 30 21:31:48 goddank-vir-klank kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 30 21:31:48 goddank-vir-klank kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 30 21:31:48 goddank-vir-klank kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 30 21:31:48 goddank-vir-klank kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 30 21:31:48 goddank-vir-klank kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 30 21:31:48 goddank-vir-klank kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Jun 30 21:31:48 goddank-vir-klank systemd-udevd[435]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: Core ver 2.22 Jun 30 21:31:48 goddank-vir-klank kernel: NET: Registered PF_BLUETOOTH protocol family Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: HCI device and connection manager initialized Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: HCI socket layer initialized Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: L2CAP socket layer initialized Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: SCO socket layer initialized Jun 30 21:31:48 goddank-vir-klank systemd[1]: Found device /dev/disk/by-uuid/6D56-80AF. Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: HCI UART driver ver 2.3 Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: HCI UART protocol H4 registered Jun 30 21:31:48 goddank-vir-klank kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 30 21:31:48 goddank-vir-klank kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 30 21:31:49 goddank-vir-klank kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 30 21:31:49 goddank-vir-klank kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 30 21:31:49 goddank-vir-klank kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jun 30 21:31:49 goddank-vir-klank kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 30 21:31:49 goddank-vir-klank kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Jun 30 21:31:49 goddank-vir-klank kernel: usbcore: registered new interface driver brcmfmac Jun 30 21:31:48 goddank-vir-klank systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 30 21:31:49 goddank-vir-klank systemd-fsck[649]: fsck.fat 4.1 (2017-01-24) Jun 30 21:31:49 goddank-vir-klank systemd-fsck[649]: /dev/mmcblk0p1: 401 files, 166316/184582 clusters Jun 30 21:31:48 goddank-vir-klank systemd-udevd[452]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 21:31:48 goddank-vir-klank systemd[1]: Starting File System Check on /dev/disk/by-uuid/6D56-80AF... Jun 30 21:31:48 goddank-vir-klank systemd[1]: Started File System Check Daemon to report status. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started File System Check on /dev/disk/by-uuid/6D56-80AF. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Mounting /boot... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Mounted /boot. Jun 30 21:31:49 goddank-vir-klank sh[659]: command failed: No such device (-19) Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Local File Systems. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Create Volatile Files and Directories... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Preprocess NFS configuration... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started ifup for eth0. Jun 30 21:31:49 goddank-vir-klank systemd[1]: nfs-config.service: Succeeded. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Preprocess NFS configuration. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target NFS client services. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Received SIGRTMIN+20 from PID 275 (plymouthd). Jun 30 21:31:49 goddank-vir-klank systemd[1]: plymouth-read-write.service: Succeeded. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Create Volatile Files and Directories. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting RPC bind portmap service... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target System Initialization. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Daily apt download activities. Jun 30 21:31:49 goddank-vir-klank kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jun 30 21:31:49 goddank-vir-klank kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Jun 30 21:31:49 goddank-vir-klank systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Daily man-db regeneration. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Daily apt upgrade and clean activities. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Listening on triggerhappy.socket. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Sockets. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Basic System. Jun 30 21:31:49 goddank-vir-klank kernel: snd-rpi-iqaudio-dac soc:sound: snd_rpi_iqaudio_gpio_unmute: un-muting amp using GPIO22 Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Volumio Log Rotation Service. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Volumio Iptables Module. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting dhcpcd on all interfaces... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting triggerhappy global hotkey daemon... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started D-Bus System Message Bus. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started UPnP Renderer front-end to MPD. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Wireless Services... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting WPA supplicant... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Timers. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started MPD Monitor Service. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Volumio Time Update Utility... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started volumio-remote-updater.service. Jun 30 21:31:49 goddank-vir-klank dhcpcd[696]: Not running dhcpcd because /etc/network/interfaces Jun 30 21:31:49 goddank-vir-klank dhcpcd[696]: defines some interfaces that will use a Jun 30 21:31:49 goddank-vir-klank dhcpcd[696]: DHCP client or static address Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Login Service... Jun 30 21:31:49 goddank-vir-klank systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 30 21:31:49 goddank-vir-klank systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Failed to start dhcpcd on all interfaces. Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: Registered IR keymap rc-cec Jun 30 21:31:49 goddank-vir-klank kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Jun 30 21:31:49 goddank-vir-klank kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input1 Jun 30 21:31:49 goddank-vir-klank kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card1/input2 Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: Registered IR keymap rc-cec Jun 30 21:31:49 goddank-vir-klank kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Jun 30 21:31:49 goddank-vir-klank kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input3 Jun 30 21:31:49 goddank-vir-klank kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card2/input4 Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Jun 30 21:31:49 goddank-vir-klank kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 1 Jun 30 21:31:49 goddank-vir-klank kernel: Bluetooth: hci0: BCM: chip id 107 Jun 30 21:31:49 goddank-vir-klank kernel: Bluetooth: hci0: BCM: features 0x2f Jun 30 21:31:49 goddank-vir-klank kernel: Bluetooth: hci0: BCM4345C0 Jun 30 21:31:49 goddank-vir-klank kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Jun 30 21:31:49 goddank-vir-klank systemd-udevd[419]: Using default interface naming scheme 'v240'. Jun 30 21:31:49 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Fetching time from Volumio... Jun 30 21:31:49 goddank-vir-klank thd[697]: Unable to parse trigger line: Jun 30 21:31:49 goddank-vir-klank thd[697]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 30 21:31:49 goddank-vir-klank thd[697]: Unable to parse trigger line: Jun 30 21:31:49 goddank-vir-klank thd[697]: Found socket passed from systemd Jun 30 21:31:49 goddank-vir-klank mpd_monitor.sh[704]: MPD Monitor Service: Starting MPD Monitor Service Jun 30 21:31:49 goddank-vir-klank systemd-udevd[419]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 30 21:31:49 goddank-vir-klank kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Jun 30 21:31:49 goddank-vir-klank kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jun 30 21:31:49 goddank-vir-klank systemd-udevd[449]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 21:31:49 goddank-vir-klank systemd-udevd[435]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 21:31:49 goddank-vir-klank systemd-logind[708]: New seat seat0. Jun 30 21:31:49 goddank-vir-klank systemd-logind[708]: Watching system buttons on /dev/input/event0 (pwr_button) Jun 30 21:31:49 goddank-vir-klank systemd-logind[708]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Jun 30 21:31:49 goddank-vir-klank systemd-udevd[453]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 21:31:49 goddank-vir-klank systemd-udevd[442]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 21:31:49 goddank-vir-klank systemd-logind[708]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Jun 30 21:31:49 goddank-vir-klank mpd_monitor.sh[704]: MPD error: Connection refused Jun 30 21:31:49 goddank-vir-klank mpd_monitor.sh[704]: MPD Monitor Service: MPD Appears to be inactive, restarting Jun 30 21:31:49 goddank-vir-klank systemd-logind[708]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Successfully dropped root privileges. Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: avahi-daemon 0.7 starting up. Jun 30 21:31:49 goddank-vir-klank kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 30 21:31:49 goddank-vir-klank mpd_monitor.sh[704]: mpd: no process found Jun 30 21:31:49 goddank-vir-klank volumio-remote-updater[706]: Error: No active session Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Successfully called chroot(). Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Successfully dropped remaining capabilities. Jun 30 21:31:49 goddank-vir-klank wpa_supplicant[703]: Successfully initialized wpa_supplicant Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Loading service file /services/volumio.service. Jun 30 21:31:49 goddank-vir-klank kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Jun 30 21:31:49 goddank-vir-klank kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Jun 30 21:31:49 goddank-vir-klank sh[659]: eth0: waiting for carrier Jun 30 21:31:49 goddank-vir-klank dhcpcd[713]: eth0: waiting for carrier Jun 30 21:31:49 goddank-vir-klank kernel: pps pps0: new PPS source ptp0 Jun 30 21:31:49 goddank-vir-klank kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Jun 30 21:31:49 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:31:49] [info] asio async_connect error: system:111 (Connection refused) Jun 30 21:31:49 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:31:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 30 21:31:49 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:31:49] [error] handle_connect error: Underlying Transport Error Jun 30 21:31:49 goddank-vir-klank volumio[701]: Could not open config: /tmp/upmpdcli.conf Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Network interface enumeration completed. Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Server startup complete. Host name is goddank-vir-klank.local. Local service cookie is 3612461433. Jun 30 21:31:49 goddank-vir-klank avahi-daemon[700]: Service "Goddank Vir Klank" (/services/volumio.service) successfully established. Jun 30 21:31:49 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Date not found in response Jun 30 21:31:49 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started RPC bind portmap service. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started triggerhappy global hotkey daemon. Jun 30 21:31:49 goddank-vir-klank systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 30 21:31:49 goddank-vir-klank systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 30 21:31:49 goddank-vir-klank haveged[673]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 30 21:31:49 goddank-vir-klank haveged[673]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63488/63488 Jun 30 21:31:49 goddank-vir-klank haveged[673]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00119 Jun 30 21:31:49 goddank-vir-klank haveged[673]: haveged: fills: 0, generated: 0 Jun 30 21:31:49 goddank-vir-klank systemd[1]: iptables.service: Succeeded. Jun 30 21:31:49 goddank-vir-klank dbus-daemon[698]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 30 21:31:49 goddank-vir-klank kernel: alsactl[684]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jun 30 21:31:49 goddank-vir-klank systemd[1]: Listening on mpd.socket. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Manage Sound Card State (restore and store). Jun 30 21:31:49 goddank-vir-klank alsactl[799]: alsactl 1.1.8 daemon started Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Save/Restore Sound Card State... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Remote File Systems (Pre). Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Remote File Systems. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target RPC Port Mapper. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Created slice system-bthelper.slice. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 30 21:31:49 goddank-vir-klank systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Load/Save RF Kill Switch Status. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Save/Restore Sound Card State. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Reached target Sound Card. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started ifup for wlan0. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started WPA supplicant. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Login Service. Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 30 21:31:49 goddank-vir-klank kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 30 21:31:49 goddank-vir-klank kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 30 21:31:49 goddank-vir-klank loadcpufreq[802]: Loading cpufreq kernel modules...done (none). Jun 30 21:31:49 goddank-vir-klank systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 30 21:31:49 goddank-vir-klank sh[807]: wlan0=wlan0 Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: hci0: BCM: features 0x2f Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 30 21:31:50 goddank-vir-klank bthelper[803]: Raspberry Pi BDADDR already set Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Raspberry Pi bluetooth helper. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Bluetooth service... Jun 30 21:31:50 goddank-vir-klank cpufrequtils[873]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 30 21:31:50 goddank-vir-klank bluetoothd[890]: Bluetooth daemon 5.50 Jun 30 21:31:50 goddank-vir-klank bluetoothd[890]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Bluetooth service. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Reached target Bluetooth. Jun 30 21:31:50 goddank-vir-klank bluetoothd[890]: Starting SDP server Jun 30 21:31:50 goddank-vir-klank bluetoothd[890]: Excluding (cli) sap Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: BNEP filters: protocol multicast Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: BNEP socket layer initialized Jun 30 21:31:50 goddank-vir-klank dbus-daemon[698]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=890 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 30 21:31:50 goddank-vir-klank bluetoothd[890]: Bluetooth management interface 1.22 initialized Jun 30 21:31:50 goddank-vir-klank kernel: Bluetooth: MGMT ver 1.22 Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Hostname Service... Jun 30 21:31:50 goddank-vir-klank dbus-daemon[698]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Hostname Service. Jun 30 21:31:50 goddank-vir-klank wireless.js[702]: Cleaning previous... Jun 30 21:31:50 goddank-vir-klank bluetoothd[890]: Failed to set privacy: Rejected (0x0b) Jun 30 21:31:50 goddank-vir-klank sudo[905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 30 21:31:50 goddank-vir-klank sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank sudo[905]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:50 goddank-vir-klank sudo[907]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 30 21:31:50 goddank-vir-klank sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Raise network interfaces... Jun 30 21:31:50 goddank-vir-klank sudo[907]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:50 goddank-vir-klank wireless.js[702]: Stopped aP Jun 30 21:31:50 goddank-vir-klank sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 30 21:31:50 goddank-vir-klank sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank sudo[948]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:50 goddank-vir-klank kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 30 21:31:50 goddank-vir-klank sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 30 21:31:50 goddank-vir-klank sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank sudo[953]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:50 goddank-vir-klank sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 30 21:31:50 goddank-vir-klank sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Raise network interfaces. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Reached target Network. Jun 30 21:31:50 goddank-vir-klank sudo[976]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:50 goddank-vir-klank sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 30 21:31:50 goddank-vir-klank sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Music Player Daemon... Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Network Time Service... Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Permit User Sessions... Jun 30 21:31:50 goddank-vir-klank systemd[1]: Reached target Network is Online. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting LSB: Brings up/down network automatically... Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting /etc/rc.local Compatibility... Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Samba NMB Daemon... Jun 30 21:31:50 goddank-vir-klank systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Permit User Sessions. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started /etc/rc.local Compatibility. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 30 21:31:50 goddank-vir-klank sudo[982]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 30 21:31:50 goddank-vir-klank sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:50 goddank-vir-klank systemd[1]: Starting Hold until boot process finishes up... Jun 30 21:31:50 goddank-vir-klank sudo[982]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 30 21:31:50 goddank-vir-klank sudo[982]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:50 goddank-vir-klank systemd[1]: Received SIGRTMIN+21 from PID 275 (plymouthd). Jun 30 21:31:50 goddank-vir-klank systemd[1]: Received SIGRTMIN+21 from PID 275 (plymouthd). Jun 30 21:31:50 goddank-vir-klank systemd[1]: plymouth-quit.service: Succeeded. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Terminate Plymouth Boot Screen. Jun 30 21:31:50 goddank-vir-klank systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Hold until boot process finishes up. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Serial Getty on ttyAMA10. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Reached target Login Prompts. Jun 30 21:31:50 goddank-vir-klank ifplugd(eth0)[1014]: ifplugd 0.28 initializing. Jun 30 21:31:50 goddank-vir-klank ifplugd(eth0)[1014]: Using interface eth0/2C:CF:67:EE:46:BB with driver (version: 6.6.62-v8+) Jun 30 21:31:50 goddank-vir-klank ifplugd(eth0)[1014]: Using detection mode: SIOCETHTOOL Jun 30 21:31:50 goddank-vir-klank ifplugd(eth0)[1014]: Initialization complete, link beat not detected. Jun 30 21:31:50 goddank-vir-klank ntpd[986]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 30 21:31:50 goddank-vir-klank ntpd[986]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started Network Time Service. Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: proto: precision = 0.425 usec (-21) Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 185 days ago Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: Listen and drop on 0 v6wildcard [::]:123 Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: Listen normally on 2 lo 127.0.0.1:123 Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: Listening on routing socket on fd #19 for interface updates Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 30 21:31:50 goddank-vir-klank ntpd[1015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 30 21:31:50 goddank-vir-klank ifplugd[989]: Network Interface Plugging Daemon...start eth0...done. Jun 30 21:31:50 goddank-vir-klank systemd[1]: Started LSB: Brings up/down network automatically. Jun 30 21:31:51 goddank-vir-klank nmbd[997]: [2025/06/30 21:31:51.189718, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 30 21:31:51 goddank-vir-klank nmbd[997]: started asyncdns process 1019 Jun 30 21:31:51 goddank-vir-klank nmbd[997]: [2025/06/30 21:31:51.190849, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 30 21:31:51 goddank-vir-klank nmbd[997]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 30 21:31:51 goddank-vir-klank nmbd[997]: [2025/06/30 21:31:51.191011, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 30 21:31:51 goddank-vir-klank nmbd[997]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 30 21:31:51 goddank-vir-klank mpd[1003]: Jun 30 21:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 30 21:31:51 goddank-vir-klank systemd[1]: Started Music Player Daemon. Jun 30 21:31:51 goddank-vir-klank mpd_monitor.sh[704]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 30 21:31:51 goddank-vir-klank ntpd[1015]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 30 21:31:52 goddank-vir-klank ntpd[1015]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 30 21:31:53 goddank-vir-klank sudo[981]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:53 goddank-vir-klank wireless.js[702]: SETTING APPROPRIATE REG DOMAIN: DE Jun 30 21:31:53 goddank-vir-klank sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 30 21:31:53 goddank-vir-klank sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:53 goddank-vir-klank sudo[1024]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:53 goddank-vir-klank sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 30 21:31:53 goddank-vir-klank sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:53 goddank-vir-klank sudo[1026]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:53 goddank-vir-klank wireless.js[702]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jun 30 21:31:53 goddank-vir-klank wireless.js[702]: Start wireless flow Jun 30 21:31:53 goddank-vir-klank wireless.js[702]: Stopped hotspot (if there).. Jun 30 21:31:53 goddank-vir-klank sudo[1031]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 30 21:31:53 goddank-vir-klank sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:53 goddank-vir-klank sudo[1031]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:53 goddank-vir-klank sudo[1033]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 30 21:31:53 goddank-vir-klank sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:53 goddank-vir-klank sudo[1033]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:53 goddank-vir-klank ntpd[1015]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 30 21:31:53 goddank-vir-klank wireless.js[702]: DHCP IP Jun 30 21:31:53 goddank-vir-klank wireless.js[702]: Start ap Jun 30 21:31:53 goddank-vir-klank wpa_supplicant[1035]: Successfully initialized wpa_supplicant Jun 30 21:31:53 goddank-vir-klank sudo[1036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 30 21:31:53 goddank-vir-klank sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:53 goddank-vir-klank dhcpcd[1037]: dev: loaded udev Jun 30 21:31:53 goddank-vir-klank kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 30 21:31:53 goddank-vir-klank dhcpcd-run-hooks[1054]: wlan0: starting wpa_supplicant Jun 30 21:31:54 goddank-vir-klank dhcpcd-run-hooks[1059]: wlan0: failed to start wpa_supplicant Jun 30 21:31:54 goddank-vir-klank dhcpcd-run-hooks[1060]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jun 30 21:31:54 goddank-vir-klank dhcpcd[1037]: wlan0: connected to Access Point `' Jun 30 21:31:54 goddank-vir-klank dhcpcd[1037]: no interfaces have a carrier Jun 30 21:31:54 goddank-vir-klank dhcpcd[1037]: forked to background, child pid 1069 Jun 30 21:31:54 goddank-vir-klank dhcpcd[1069]: eth0: waiting for carrier Jun 30 21:31:54 goddank-vir-klank dhcpcd[1069]: wlan0: waiting for carrier Jun 30 21:31:54 goddank-vir-klank sudo[1036]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:54 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:31:54] [info] asio async_connect error: system:111 (Connection refused) Jun 30 21:31:54 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:31:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 30 21:31:54 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:31:54] [error] handle_connect error: Underlying Transport Error Jun 30 21:31:54 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Fetching time from Volumio... Jun 30 21:31:54 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Date not found in response Jun 30 21:31:54 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 21:31:54 goddank-vir-klank ntpd[1015]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 30 21:31:54 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:31:54 goddank-vir-klank systemd[1]: systemd-rfkill.service: Succeeded. Jun 30 21:31:54 goddank-vir-klank sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:31:54 goddank-vir-klank sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:54 goddank-vir-klank sudo[1076]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:55 goddank-vir-klank bthelper[803]: Changing power off succeeded Jun 30 21:31:55 goddank-vir-klank bthelper[803]: [CHG] Controller 2C:CF:67:EE:46:BD Class: 0x0000041c Jun 30 21:31:55 goddank-vir-klank bthelper[803]: Changing power on succeeded Jun 30 21:31:55 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:31:55 goddank-vir-klank sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:31:55 goddank-vir-klank sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:55 goddank-vir-klank sudo[1080]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:56 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:31:56 goddank-vir-klank sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:31:56 goddank-vir-klank sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:56 goddank-vir-klank sudo[1083]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:57 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:31:57 goddank-vir-klank sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:31:57 goddank-vir-klank sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:57 goddank-vir-klank sudo[1086]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:58 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:31:58 goddank-vir-klank sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:31:58 goddank-vir-klank sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:58 goddank-vir-klank sudo[1089]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:59 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Fetching time from Volumio... Jun 30 21:31:59 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Date not found in response Jun 30 21:31:59 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 21:31:59 goddank-vir-klank wpa_supplicant[1058]: wlan0: Trying to associate with SSID 'G-Spot' Jun 30 21:31:59 goddank-vir-klank wpa_supplicant[1058]: wlan0: Associated with f8:e7:1e:09:26:b8 Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: carrier acquired Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: connected to Access Point `G-Spot' Jun 30 21:31:59 goddank-vir-klank wpa_supplicant[1058]: wlan0: CTRL-EVENT-CONNECTED - Connection to f8:e7:1e:09:26:b8 completed [id=0 id_str=] Jun 30 21:31:59 goddank-vir-klank wpa_supplicant[1058]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 30 21:31:59 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: DUID 00:01:00:01:2f:db:0f:1d:2c:cf:67:ee:46:bc Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: IAID 67:ee:46:bc Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: adding address fe80::2ecf:67ff:feee:46bc Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: ipv6_addaddr1: Permission denied Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: probing address 192.168.1.20/24 Jun 30 21:31:59 goddank-vir-klank sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:31:59 goddank-vir-klank sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:31:59 goddank-vir-klank sudo[1099]: pam_unix(sudo:session): session closed for user root Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: carrier lost Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: carrier acquired Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: IAID 67:ee:46:bc Jun 30 21:31:59 goddank-vir-klank dhcpcd[1069]: wlan0: probing address 192.168.1.20/24 Jun 30 21:32:00 goddank-vir-klank dhcpcd[1069]: wlan0: soliciting an IPv6 router Jun 30 21:32:00 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:32:00 goddank-vir-klank sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:32:00 goddank-vir-klank sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:00 goddank-vir-klank sudo[1120]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:01 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:32:01 goddank-vir-klank sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:32:01 goddank-vir-klank sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:01 goddank-vir-klank sudo[1123]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:02 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:32:02] [info] asio async_connect error: system:111 (Connection refused) Jun 30 21:32:02 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:32:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 30 21:32:02 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:32:02] [error] handle_connect error: Underlying Transport Error Jun 30 21:32:02 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:32:02 goddank-vir-klank sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:32:02 goddank-vir-klank sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:02 goddank-vir-klank sudo[1126]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:03 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:32:03 goddank-vir-klank sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:32:03 goddank-vir-klank sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:03 goddank-vir-klank sudo[1129]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:04 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Fetching time from Volumio... Jun 30 21:32:04 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Date not found in response Jun 30 21:32:04 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Retrying in 5 seconds... Jun 30 21:32:04 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:32:04 goddank-vir-klank sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:32:04 goddank-vir-klank sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:04 goddank-vir-klank sudo[1137]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:05 goddank-vir-klank dhcpcd[1069]: wlan0: using static address 192.168.1.20/24 Jun 30 21:32:05 goddank-vir-klank avahi-daemon[700]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.20. Jun 30 21:32:05 goddank-vir-klank dhcpcd[1069]: wlan0: adding route to 192.168.1.0/24 Jun 30 21:32:05 goddank-vir-klank dhcpcd[1069]: wlan0: adding default route via 192.168.1.1 Jun 30 21:32:05 goddank-vir-klank avahi-daemon[700]: New relevant interface wlan0.IPv4 for mDNS. Jun 30 21:32:05 goddank-vir-klank avahi-daemon[700]: Registering new address record for 192.168.1.20 on wlan0.IPv4. Jun 30 21:32:05 goddank-vir-klank wireless.js[702]: trying... Jun 30 21:32:05 goddank-vir-klank sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 30 21:32:05 goddank-vir-klank sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:05 goddank-vir-klank sudo[1160]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:05 goddank-vir-klank wireless.js[702]: Connected to: ----G-Spot Jun 30 21:32:05 goddank-vir-klank wireless.js[702]: ---- Jun 30 21:32:05 goddank-vir-klank sudo[1163]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 21:32:05 goddank-vir-klank sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:05 goddank-vir-klank sudo[1163]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:05 goddank-vir-klank wireless.js[702]: ... joined AP, wlan0 IPv4 is 192.168.1.20, ipV6 is undefined Jun 30 21:32:05 goddank-vir-klank wireless.js[702]: It's done! AP Jun 30 21:32:05 goddank-vir-klank systemd[1]: Started Wireless Services. Jun 30 21:32:05 goddank-vir-klank systemd[1]: Started Volumio Backend Module. Jun 30 21:32:05 goddank-vir-klank kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 30 21:32:05 goddank-vir-klank systemd[1]: Started Volumio Cpu Tweaker. Jun 30 21:32:05 goddank-vir-klank volumio-cpu-tweak[1169]: Setting RT Priority for mpd Jun 30 21:32:05 goddank-vir-klank volumio-cpu-tweak[1169]: Setting MPD Affinity Jun 30 21:32:05 goddank-vir-klank volumio-cpu-tweak[1169]: pid 1003's current affinity mask: f Jun 30 21:32:05 goddank-vir-klank volumio-cpu-tweak[1169]: pid 1003's new affinity mask: 3 Jun 30 21:32:05 goddank-vir-klank volumio-cpu-tweak[1169]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 30 21:32:05 goddank-vir-klank volumio-cpu-tweak[1169]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Jun 30 21:32:06 goddank-vir-klank systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 30 21:32:06 goddank-vir-klank nmbd[997]: [2025/06/30 21:32:06.193839, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 30 21:32:06 goddank-vir-klank nmbd[997]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 30 21:32:06 goddank-vir-klank systemd[1]: Started Samba NMB Daemon. Jun 30 21:32:06 goddank-vir-klank systemd[1]: Starting Samba Winbind Daemon... Jun 30 21:32:06 goddank-vir-klank winbindd[1185]: [2025/06/30 21:32:06.310010, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 30 21:32:06 goddank-vir-klank winbindd[1185]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 30 21:32:06 goddank-vir-klank winbindd[1185]: [2025/06/30 21:32:06.319002, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 30 21:32:06 goddank-vir-klank winbindd[1185]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 30 21:32:06 goddank-vir-klank systemd[1]: Started Samba Winbind Daemon. Jun 30 21:32:06 goddank-vir-klank systemd[1]: Starting Samba SMB Daemon... Jun 30 21:32:06 goddank-vir-klank smbd[1190]: [2025/06/30 21:32:06.504178, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 30 21:32:06 goddank-vir-klank systemd[1]: Started Samba SMB Daemon. Jun 30 21:32:06 goddank-vir-klank smbd[1190]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ----- Volumio3 ---- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ----- System startup ---- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:06 goddank-vir-klank ntpd[1015]: Listen normally on 3 wlan0 192.168.1.20:123 Jun 30 21:32:06 goddank-vir-klank ntpd[1015]: new interface(s) found: waking up resolver Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: MYVOLUMIO Environment detected Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Plugin folders cleanup Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning into folder /volumio/app/plugins/ Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category audio_interface Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category miscellanea Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category music_service Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category plugins.json Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category system_controller Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category user_interface Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning into folder /data/plugins/ Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category music_service Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category system_hardware Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Scanning category user_interface Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Plugin folders cleanup completed Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ----- Core plugins startup ---- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Loading plugins from folder /volumio/app/plugins/ Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Adding plugin upnp to MyMusic Plugins Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Loading plugins from folder /data/plugins/ Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Loading plugin "system"... Jun 30 21:32:06 goddank-vir-klank volumio[1168]: info: Loading plugin "appearance"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "network"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Refreshing Cached IP Addresses Jun 30 21:32:07 goddank-vir-klank sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 21:32:07 goddank-vir-klank sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:07 goddank-vir-klank sudo[1212]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:07 goddank-vir-klank sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 21:32:07 goddank-vir-klank sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:07 goddank-vir-klank sudo[1214]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "services"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "alsa_controller"... Jun 30 21:32:07 goddank-vir-klank sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 30 21:32:07 goddank-vir-klank sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "wizard"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "networkfs"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Starting Udev Watcher for removable devices Jun 30 21:32:07 goddank-vir-klank sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Kodi,password=M@ngofire770,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.10/Albums /mnt/NAS/NAS Jun 30 21:32:07 goddank-vir-klank sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Ignoring mount for partition: boot Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Ignoring mount for partition: volumio Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Ignoring mount for partition: volumio_data Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "volumio_command_line_client"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "upnp"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: [1751311927407] Starting Upmpd Daemon Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "my_music"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "mpd"... Jun 30 21:32:07 goddank-vir-klank kernel: Key type cifs.spnego registered Jun 30 21:32:07 goddank-vir-klank kernel: Key type cifs.idmap registered Jun 30 21:32:07 goddank-vir-klank 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 30 21:32:07 goddank-vir-klank kernel: CIFS: Attempting to mount //192.168.1.10/Albums Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "upnp_browser"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "alarm-clock"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "airplay_emulation"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Starting Shairport Sync Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "last_100"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "webradio"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "i2s_dacs"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "volumiodiscovery"... Jun 30 21:32:07 goddank-vir-klank volumio[1168]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 21:32:07 goddank-vir-klank node[1168]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 21:32:07 goddank-vir-klank node[1168]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 21:32:07 goddank-vir-klank volumio[1168]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 21:32:07 goddank-vir-klank volumio[1168]: *** WARNING *** For more information see Jun 30 21:32:07 goddank-vir-klank volumio[1168]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 21:32:07 goddank-vir-klank volumio[1168]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 21:32:07 goddank-vir-klank volumio[1168]: *** WARNING *** For more information see Jun 30 21:32:07 goddank-vir-klank node[1168]: *** WARNING *** For more information see Jun 30 21:32:07 goddank-vir-klank node[1168]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 21:32:07 goddank-vir-klank node[1168]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 21:32:07 goddank-vir-klank node[1168]: *** WARNING *** For more information see Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Discovery: Started advertising with name: Goddank Vir Klank Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 21:32:07 goddank-vir-klank volumio[1168]: info: Loading plugin "spop"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "gpio-buttons"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: GPIO-Buttons initialized Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "outputs"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "albumart"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Plugin example_plugin is not enabled Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "inputs"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "updater_comm"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Plugin mpdemulation is not enabled Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "rest_api"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "websocket"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Starting Socket.io Server version 2.3.0 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "gpio_control"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Forking 3 albumart workers Jun 30 21:32:08 goddank-vir-klank volumio[1168]: gpiox_arm32 V2.1.0 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Starting albumart workers Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Starting albumart workers Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Starting albumart workers Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Applying required configuration parameters for plugin gpio_control Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading plugin "touch_display"... Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Applying required configuration parameters for plugin touch_display Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Loading i18n strings for locale en Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Updating browse sources language Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::initPlayerControls Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Express server listening on port 3000 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: [Metrics] WebUI: 2s 423.10ms Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::resetVolumioState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::getcurrentVolume Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Volumio Network Manager: Network status updated: 2 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.107 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Executing start script for DAC IQaudIO DAC Plus Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Reloading queue from file Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::setRepeat null single undefined Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::pushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioPushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::setRandom null Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::pushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioPushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04171 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Setting Device type: Raspberry PI Jun 30 21:32:08 goddank-vir-klank volumio[1168]: error: Cannot execute DAC script: Error: Command failed: /volumio/app/plugins/system_controller/i2s_dacs/scripts/iqamp-unmute.sh Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Oops: Unable to determine board revision from /proc/cpuinfo Jun 30 21:32:08 goddank-vir-klank volumio[1168]: -> No "Hardware" line Jun 30 21:32:08 goddank-vir-klank volumio[1168]: -> You'd best google the error to find out why. Jun 30 21:32:08 goddank-vir-klank volumio[1168]: Oops: Unable to determine board revision from /proc/cpuinfo Jun 30 21:32:08 goddank-vir-klank volumio[1168]: -> No "Hardware" line Jun 30 21:32:08 goddank-vir-klank volumio[1168]: -> You'd best google the error to find out why. Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: VolumeController:: Volume=61 Mute =false Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::pushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioPushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::updateTrackBlock Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrackBlock Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: VolumeController:: Volume=61 Mute =false Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreStateMachine::pushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioPushState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Discovery: adding 3b709dd4-0d6c-4085-916a-4ab7320a05b9 Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: Discovery: Found device Goddank Vir Klank Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:08 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:09 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Fetching time from Volumio... Jun 30 21:32:10 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: Setting system time to: 2025-06-30 21:32:10 Jun 30 21:32:10 goddank-vir-klank sudo[1339]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-30 21:32:10 Jun 30 21:32:10 goddank-vir-klank sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:10 goddank-vir-klank dbus-daemon[698]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=1340 comm="timedatectl set-time 2025-06-30 21:32:10 ") Jun 30 21:32:10 goddank-vir-klank systemd[1]: Starting Time & Date Service... Jun 30 21:32:10 goddank-vir-klank dbus-daemon[698]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 30 21:32:10 goddank-vir-klank systemd[1]: Started Time & Date Service. Jun 30 21:32:10 goddank-vir-klank systemd-timedated[1341]: Changed local time to Mon Jun 30 21:32:10 2025 Jun 30 21:32:10 goddank-vir-klank sudo[1339]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:10 goddank-vir-klank volumio-time-update[705]: volumio-time-update-util: System time updated successfully. Jun 30 21:32:10 goddank-vir-klank systemd[1]: Started Volumio Time Update Utility. Jun 30 21:32:10 goddank-vir-klank systemd[1]: Reached target Multi-User System. Jun 30 21:32:10 goddank-vir-klank systemd[1]: Reached target Graphical Interface. Jun 30 21:32:10 goddank-vir-klank systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 30 21:32:10 goddank-vir-klank systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 30 21:32:10 goddank-vir-klank systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 30 21:32:10 goddank-vir-klank systemd[1]: Startup finished in 10.894s (kernel) + 22.341s (userspace) = 33.236s. Jun 30 21:32:10 goddank-vir-klank sudo[1223]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:12 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:32:12] [connect] Successful connection Jun 30 21:32:12 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jun 30 21:32:12 goddank-vir-klank volumio-remote-updater[706]: [2025-06-30 21:32:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751311932 101 Jun 30 21:32:12 goddank-vir-klank sudo[1240]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Completed loading Core Plugins Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Preparing to generate the ALSA configuration file Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Asound.conf file unchanged, so no further update is needed Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Output device has changed, restarting MPD Jun 30 21:32:12 goddank-vir-klank sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 30 21:32:12 goddank-vir-klank sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Output device has changed, restarting Shairport Sync Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:12 goddank-vir-klank sudo[1353]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:12 goddank-vir-klank sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 30 21:32:12 goddank-vir-klank sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:12 goddank-vir-klank systemd[1]: Stopping Music Player Daemon... Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: ___________ START PLUGINS ___________ Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: ControllerMpd::onStart: Initializing MPD Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Creating MPD Configuration file Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 21:32:12 goddank-vir-klank sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: [1751311932876] CoreMusicLibrary::Adding element Media Servers Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:12 goddank-vir-klank sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:12 goddank-vir-klank sudo[1362]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:12 goddank-vir-klank sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 30 21:32:12 goddank-vir-klank sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:12 goddank-vir-klank systemd[1]: mpd.service: Succeeded. Jun 30 21:32:12 goddank-vir-klank systemd[1]: Stopped Music Player Daemon. Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: [1751311932915] CoreMusicLibrary::Adding element Last_100 Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: [1751311932915] CoreMusicLibrary::Adding element Webradio Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Initializing BBC Radios Jun 30 21:32:12 goddank-vir-klank systemd[1]: Starting Music Player Daemon... Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Creating Spotify config file Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:12 goddank-vir-klank sudo[1370]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 30 21:32:12 goddank-vir-klank sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:12 goddank-vir-klank sudo[1370]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: GPIO-Buttons: Reading config and creating triggers... Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Reading config and creating GPIOs Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Loading i18n strings for locale en Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: Volumio Calling Home Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: GPIO-Buttons started Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: [GPIO_Control] GPIOs created Jun 30 21:32:12 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Handling event: systemStartup Jun 30 21:32:13 goddank-vir-klank sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 30 21:32:13 goddank-vir-klank sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 30 21:32:13 goddank-vir-klank sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 30 21:32:13 goddank-vir-klank sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank sudo[1395]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: No backlight interface detected. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank systemd[1]: Reloading. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: MPD Permissions set Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: MPD Permissions set Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Status changed: stop Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Handling event: musicStop Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Handling event: repeatAllOff Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Handling event: repeatOff Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: [GPIO_Control] Status changed: stop Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Spotify config file written Jun 30 21:32:13 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 30 21:32:13 goddank-vir-klank sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: No need to fix Spotify hosts Jun 30 21:32:13 goddank-vir-klank systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 30 21:32:13 goddank-vir-klank sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 30 21:32:13 goddank-vir-klank sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Starting Shairport Sync Jun 30 21:32:13 goddank-vir-klank sudo[1428]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 30 21:32:13 goddank-vir-klank sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Starting Shairport Sync Jun 30 21:32:13 goddank-vir-klank sudo[1431]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Starting Shairport Sync Jun 30 21:32:13 goddank-vir-klank sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 21:32:13 goddank-vir-klank sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 21:32:13 goddank-vir-klank sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 30 21:32:13 goddank-vir-klank sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 21:32:13 goddank-vir-klank sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 30 21:32:13 goddank-vir-klank systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 30 21:32:13 goddank-vir-klank mpd[1385]: Jun 30 21:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 30 21:32:13 goddank-vir-klank sudo[1397]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 30 21:32:13 goddank-vir-klank systemd[1]: Reloading. Jun 30 21:32:13 goddank-vir-klank systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Discovery: Getting this device information Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 21:32:13 goddank-vir-klank systemd[1]: Started Music Player Daemon. Jun 30 21:32:13 goddank-vir-klank sudo[1400]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: systemctl daemon-reload succeeded. Jun 30 21:32:13 goddank-vir-klank sudo[1355]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank sudo[1364]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Volumio called home Jun 30 21:32:13 goddank-vir-klank sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 30 21:32:13 goddank-vir-klank sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:13 goddank-vir-klank systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 30 21:32:13 goddank-vir-klank systemd[1]: Started go-librespot Daemon. Jun 30 21:32:13 goddank-vir-klank sudo[1404]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank go-librespot[1462]: go-librespot daemon starting... Jun 30 21:32:13 goddank-vir-klank systemd[1]: shairport-sync.service: Succeeded. Jun 30 21:32:13 goddank-vir-klank systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 30 21:32:13 goddank-vir-klank systemd[1]: Started Volumio Kiosk. Jun 30 21:32:13 goddank-vir-klank systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 21:32:13 goddank-vir-klank sudo[1440]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank sudo[1436]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank sudo[1435]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank sudo[1458]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:13 goddank-vir-klank volumio[1168]: error: MPD error: The expression evaluated to a falsy value: Jun 30 21:32:13 goddank-vir-klank volumio[1168]: assert.ok(self.idling) Jun 30 21:32:13 goddank-vir-klank volumio[1168]: error: The expression evaluated to a falsy value: Jun 30 21:32:13 goddank-vir-klank volumio[1168]: assert.ok(self.idling) Jun 30 21:32:13 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to 192.168.1.20:3000 from 192.168.1.107 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: error: updateQueue error: null Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: Volumio Kiosk started. Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Completed starting Core Plugins Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: ----- MyVolumio plugins startup ---- Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: ------------------------------------------- Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Shairport-Sync Started Jun 30 21:32:13 goddank-vir-klank volumio[1168]: Error adding Membership: Error: addMembership EINVAL Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Shairport-Sync Started Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Shairport-Sync Started Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: MPD running with PID1385 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: ,establishing connection Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 30 21:32:13 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.107 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: X display number found: 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: error: updateQueue error: null Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Received Get System Info Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Discovery: Getting this device information Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetQueue Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreStateMachine::getQueue Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getQueue Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Listing playlists Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Received Get System Info Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Discovery: Getting this device information Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:13 goddank-vir-klank startx[1464]: X.Org X Server 1.20.4 Jun 30 21:32:13 goddank-vir-klank startx[1464]: X Protocol Version 11, Revision 0 Jun 30 21:32:13 goddank-vir-klank startx[1464]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jun 30 21:32:13 goddank-vir-klank startx[1464]: Current Operating System: Linux goddank-vir-klank 6.6.62-v8+ #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 aarch64 Jun 30 21:32:13 goddank-vir-klank startx[1464]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave smsc95xx.macaddr=2C:CF:67:EE:46:BB 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=ee200d97-771e-4dc2-bc54-5383b3c7262f imgfile=/volumio_current.sqsh bootpart=UUID=6D56-80AF datapart=UUID=9da5fc58-f342-443d-83b5-60353d527d53 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 30 21:32:13 goddank-vir-klank startx[1464]: Build Date: 04 April 2023 07:50:56AM Jun 30 21:32:13 goddank-vir-klank startx[1464]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jun 30 21:32:13 goddank-vir-klank startx[1464]: Current version of pixman: 0.36.0 Jun 30 21:32:13 goddank-vir-klank startx[1464]: Before reporting problems, check http://wiki.x.org Jun 30 21:32:13 goddank-vir-klank startx[1464]: to make sure that you have the latest version. Jun 30 21:32:13 goddank-vir-klank startx[1464]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 30 21:32:13 goddank-vir-klank startx[1464]: (++) from command line, (!!) notice, (II) informational, Jun 30 21:32:13 goddank-vir-klank startx[1464]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 30 21:32:13 goddank-vir-klank startx[1464]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jun 30 21:32:13 2025 Jun 30 21:32:13 goddank-vir-klank startx[1464]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 30 21:32:13 goddank-vir-klank startx[1464]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: touch_display: X display number found: 0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 30 21:32:13 goddank-vir-klank volumio[1168]: SPOTIFY: BQBiVO63Xb9gA25ZU1Msmw4HgbYyTvJJJj6wGk4W1MKyLgrJTycBk9cKPtlLcn8_Lvr67d_k_JiF4tYXXgvK4a_SgfiO8uOB4zoIupcRg9dhNKng2s2zC31-CgKMR_iszcLIlEx-K5cICiyVy0FlF3HQRZClvDk3ak234CXy-o-YVaofCWesffbFMYnY-D6MFl6xk0YkPBfwmtnJpqelZ3NGKq8Js1Vq5_EXDyPQIbicQA6QuXeDo2JH-_Pxwyvo2FmMvhcc0pntLn7smZkTV12mfVBirsToNBoy2dmZm0uSZo0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: New Spotify access token = BQBiVO63Xb9gA25ZU1Msmw4HgbYyTvJJJj6wGk4W1MKyLgrJTycBk9cKPtlLcn8_Lvr67d_k_JiF4tYXXgvK4a_SgfiO8uOB4zoIupcRg9dhNKng2s2zC31-CgKMR_iszcLIlEx-K5cICiyVy0FlF3HQRZClvDk3ak234CXy-o-YVaofCWesffbFMYnY-D6MFl6xk0YkPBfwmtnJpqelZ3NGKq8Js1Vq5_EXDyPQIbicQA6QuXeDo2JH-_Pxwyvo2FmMvhcc0pntLn7smZkTV12mfVBirsToNBoy2dmZm0uSZo0 Jun 30 21:32:13 goddank-vir-klank volumio[1168]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 30 21:32:13 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:13+02:00" level=info msg="running go-librespot 0.2.0" Jun 30 21:32:13 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:13+02:00" level=debug msg="app state loaded" Jun 30 21:32:13 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 21:32:14 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jun 30 21:32:14 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:14+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 30 21:32:14 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:14+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 30 21:32:14 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:14+02:00" level=info msg="zeroconf server listening on port 46835" Jun 30 21:32:14 goddank-vir-klank startx[1464]: V3D 7.1 not supported by this version of Mesa. Jun 30 21:32:14 goddank-vir-klank volumio[1168]: SPOTIFY: User informations: {"country":"ZA","display_name":"Eugene","email":"eugene@citc.co.za","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/0j6odmc7k56amiclrz5sbrzfs"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/0j6odmc7k56amiclrz5sbrzfs","id":"0j6odmc7k56amiclrz5sbrzfs","images":[],"product":"premium","type":"user","uri":"spotify:user:0j6odmc7k56amiclrz5sbrzfs"} Jun 30 21:32:14 goddank-vir-klank volumio[1168]: info: Spotify Successfully logged in Jun 30 21:32:14 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 21:32:14 goddank-vir-klank volumio[1168]: info: [1751311934463] CoreMusicLibrary::Adding element Spotify Jun 30 21:32:14 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:14 goddank-vir-klank volumio[1168]: Cannot find translation for source Spotify Jun 30 21:32:14 goddank-vir-klank kernel: broken atomic modeset userspace detected, disabling atomic Jun 30 21:32:14 goddank-vir-klank startx[1464]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jun 30 21:32:14 goddank-vir-klank volumio[1168]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 30 21:32:14 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:14+02:00" level=debug msg="obtained new client token: AADnoM2+yGOi+8x4/8KWrYrqvm9fcmUsJKXGvLfWDscXKr74+q0u4b8VYb+P7wxsuNqfssxcMzyJubT2V1Qom2HW4x39lUBRC3svPdQCjv1kKMGi0vMm56SR4yM4oruBS4ttqwtWsKfNBi6XNBk65Uo3SCzMB8CfxOjbMn0rvXJVg5QEw94N6KkXTWzo2zpe+1MjJ/KMtkuvSWrz2rio9r2gX0G8ZCQ0Z+YepxI42pYchAmZM8Bcwn2Gc+I=" Jun 30 21:32:15 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:15+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jun 30 21:32:15 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:15+02:00" level=debug msg="completed keyexchange" Jun 30 21:32:15 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:15+02:00" level=debug msg="completed challenge" Jun 30 21:32:15 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:15+02:00" level=info msg="authenticated AP as 0j6odmc7k56amiclrz5sbrzfs" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=info msg="authenticated Login5 as 0j6odmc7k56amiclrz5sbrzfs" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="initializing zeroconf session, username: 0j6odmc7k56amiclrz5sbrzfs" Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: go-librespot daemon successfully initialized Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: Discovery: Getting this device information Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:16 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="dealer connection opened" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=trace msg="starting accesspoint recv loop" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=trace msg="starting dealer recv loop" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=trace msg="received accesspoint ping" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 30 21:32:16 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:16+02:00" level=debug msg="received connection id: NzY5NWE4ODYtZmI2OC00Nzc2LWEzNDItZTA1NWZiMjIxOGI0K2RlYWxlcit0Y3A6Ly8wYWNhNDFmNS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQ0Y5QkZBRkVCQTA0OEM5ODY5RkJGRjMxMjc2MTFFQjUzODMxMTExNjk1M0EzN0Y1MTY0NzI4QTk0MkYzRUI4Mg==" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=trace msg="received accesspoint pong ack" Jun 30 21:32:17 goddank-vir-klank sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 21:32:17 goddank-vir-klank sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:17 goddank-vir-klank sudo[1660]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 30 21:32:17 goddank-vir-klank sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 21:32:17 goddank-vir-klank sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:17 goddank-vir-klank sudo[1663]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:17 goddank-vir-klank sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 30 21:32:17 goddank-vir-klank sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 21:32:17 goddank-vir-klank systemd[1]: Started UPnP Renderer front-end to MPD. Jun 30 21:32:17 goddank-vir-klank sudo[1667]: pam_unix(sudo:session): session closed for user root Jun 30 21:32:17 goddank-vir-klank volumio[1168]: info: Upmpdcli Daemon Started Jun 30 21:32:17 goddank-vir-klank volumio[1669]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="handling transfer player command from adb291562392e9a39e619073c5c8ee28c9671fc6" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="resolved context of track" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="loading track (paused: false, position: 59070ms)" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=trace msg="emitting websocket event: will_play" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="selected format OGG_VORBIS_320 (e42e7910f72842b84fb666a0cd0964fa4c15533e)" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="requested aes key for file e42e7910f72842b84fb666a0cd0964fa4c15533e, gid: 6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:17 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:17+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1372" Jun 30 21:32:18 goddank-vir-klank volumio[1669]: .................++++ Jun 30 21:32:18 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1135" Jun 30 21:32:18 goddank-vir-klank volumio[1168]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 30 21:32:18 goddank-vir-klank systemd[1]: systemd-fsckd.service: Succeeded. Jun 30 21:32:19 goddank-vir-klank sh[659]: timed out Jun 30 21:32:19 goddank-vir-klank dhcpcd[713]: timed out Jun 30 21:32:19 goddank-vir-klank sh[659]: dhcpcd exited Jun 30 21:32:19 goddank-vir-klank dhcpcd[713]: dhcpcd exited Jun 30 21:32:19 goddank-vir-klank sh[659]: ifup: failed to bring up eth0 Jun 30 21:32:19 goddank-vir-klank systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 30 21:32:19 goddank-vir-klank systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 30 21:32:19 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: Initializing connection to go-librespot Websocket Jun 30 21:32:19 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:19+02:00" level=debug msg="new websocket client" Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: Connection to go-librespot Websocket established Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 21:32:19 goddank-vir-klank volumio[1168]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 30 21:32:19 goddank-vir-klank systemd[1]: systemd-hostnamed.service: Succeeded. Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: Received Get System Info Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: Discovery: Getting this device information Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: Listing playlists Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 21:32:19 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 21:32:20 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:20 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=debug msg="fetched first chunk of 25, total size is 12820912 bytes" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: Received Get System Info Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: Discovery: Getting this device information Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:21 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=trace msg="seek to 59070ms (diff: 606ms, samples: 2604987, bytes: 1865052)" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=debug msg="created new output device" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jun 30 21:32:21 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:21+02:00" level=info msg="loaded track \"Something I Can Never Have\" (paused: false, position: 59070ms, duration: 354826ms, prefetched: false)" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=trace msg="scheduling prefetch in 266s" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=trace msg="emitting websocket event: metadata" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=trace msg="emitting websocket event: active" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="sending successful reply for dealer request" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 30 21:32:22 goddank-vir-klank volumio[1168]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6azwgyc7oNJvcsYyzqkiA5","name":"Something I Can Never Have","artist_names":["Nine Inch Nails"],"album_name":"Pretty Hate Machine","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029764c0993e45db213f0e359f","position":59070,"duration":354826,"release_date":"year:1989 month:10 day:20","track_number":5,"disc_number":1}} Jun 30 21:32:22 goddank-vir-klank volumio[1168]: SPOTIFY: received: {"type":"active","data":null} Jun 30 21:32:22 goddank-vir-klank volumio[1168]: info: Aligning Spotify Volume to Volumio Volume Jun 30 21:32:22 goddank-vir-klank volumio[1168]: info: CoreCommandRouter::volumioGetState Jun 30 21:32:22 goddank-vir-klank volumio[1168]: info: CorePlayQueue::getTrack 0 Jun 30 21:32:22 goddank-vir-klank volumio[1168]: info: Setting Spotify Volume from Volumio: 61 Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="fetched chunk 4/24, size: 524288" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="fetched chunk 5/24, size: 524288" uri="spotify:track:6azwgyc7oNJvcsYyzqkiA5" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 30 21:32:22 goddank-vir-klank go-librespot[1462]: time="2025-06-30T21:32:22+02:00" level=trace msg="emitting websocket event: playing" Jun 30 21:32:22 goddank-vir-klank volumio[1168]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6azwgyc7oNJvcsYyzqkiA5","play_origin":"search"}} Jun 30 21:32:22 goddank-vir-klank volumio[1168]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 21:32:22 goddank-vir-klank volumio[1168]: TypeError: Cannot read property 'service' of undefined Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:266:18) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at WebSocket.emit (events.js:315:20) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver.emit (events.js:315:20) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at Socket.emit (events.js:315:20) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at addChunk (internal/streams/readable.js:309:12) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: at readableAddChunk (internal/streams/readable.js:284:9) Jun 30 21:32:22 goddank-vir-klank volumio[1168]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 21:32:22 goddank-vir-klank sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 21:31 Jun 30 21:32:22 goddank-vir-klank sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"