-- Logs begin at Sat 2025-12-27 13:20:41 CET, end at Sat 2025-12-27 13:21:13 CET. -- Dec 27 13:20:41 music kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Dec 27 13:20:41 music 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 Dec 27 13:20:41 music kernel: KASLR enabled Dec 27 13:20:41 music kernel: random: crng init done Dec 27 13:20:41 music kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Dec 27 13:20:41 music kernel: efi: UEFI not found. Dec 27 13:20:41 music kernel: Reserved memory: created CMA memory pool at 0x0000000001c00000, size 64 MiB Dec 27 13:20:41 music kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 27 13:20:41 music kernel: OF: reserved mem: 0x0000000001c00000..0x0000000005bfffff (65536 KiB) map reusable linux,cma Dec 27 13:20:41 music kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Dec 27 13:20:41 music kernel: OF: reserved mem: 0x000000003fd16200..0x000000003fd16237 (0 KiB) nomap non-reusable nvram@0 Dec 27 13:20:41 music kernel: NUMA: No NUMA configuration found Dec 27 13:20:41 music kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000000ffffffff] Dec 27 13:20:41 music kernel: NUMA: NODE_DATA [mem 0xff7993c0-0xff79bfff] Dec 27 13:20:41 music kernel: Zone ranges: Dec 27 13:20:41 music kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Dec 27 13:20:41 music kernel: DMA32 empty Dec 27 13:20:41 music kernel: Normal empty Dec 27 13:20:41 music kernel: Movable zone start for each node Dec 27 13:20:41 music kernel: Early memory node ranges Dec 27 13:20:41 music kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Dec 27 13:20:41 music kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Dec 27 13:20:41 music kernel: node 0: [mem 0x0000000040000000-0x00000000ffffffff] Dec 27 13:20:41 music kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000ffffffff] Dec 27 13:20:41 music kernel: On node 0, zone DMA: 1024 pages in unavailable ranges Dec 27 13:20:41 music kernel: psci: probing for conduit method from DT. Dec 27 13:20:41 music kernel: psci: PSCIv1.1 detected in firmware. Dec 27 13:20:41 music kernel: psci: Using standard PSCI v0.2 function IDs Dec 27 13:20:41 music kernel: psci: MIGRATE_INFO_TYPE not supported. Dec 27 13:20:41 music kernel: psci: SMC Calling Convention v1.2 Dec 27 13:20:41 music kernel: percpu: Embedded 30 pages/cpu s85928 r8192 d28760 u122880 Dec 27 13:20:41 music kernel: pcpu-alloc: s85928 r8192 d28760 u122880 alloc=30*4096 Dec 27 13:20:41 music kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 27 13:20:41 music kernel: Detected PIPT I-cache on CPU0 Dec 27 13:20:41 music kernel: CPU features: detected: Virtualization Host Extensions Dec 27 13:20:41 music kernel: CPU features: detected: Hardware dirty bit management Dec 27 13:20:41 music kernel: CPU features: detected: Spectre-v4 Dec 27 13:20:41 music kernel: CPU features: detected: Spectre-BHB Dec 27 13:20:41 music kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 27 13:20:41 music kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 27 13:20:41 music kernel: CPU features: detected: SSBS not fully self-synchronizing Dec 27 13:20:41 music kernel: alternatives: applying boot alternatives Dec 27 13:20:41 music 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:61:1B:8F 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=bc357b65-966a-45c3-975f-98ac1d7c9730 imgfile=/volumio_current.sqsh bootpart=UUID=25F2-3254 datapart=UUID=629ac953-0ee4-4cce-ab49-7a828cbf7f04 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 Dec 27 13:20:41 music kernel: cgroup: Disabling memory control group subsystem Dec 27 13:20:41 music kernel: mempolicy: NUMA default policy overridden to 'interleave:0' Dec 27 13:20:41 music kernel: PCIe ASPM is disabled Dec 27 13:20:41 music kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=bc357b65-966a-45c3-975f-98ac1d7c9730 imgfile=/volumio_current.sqsh bootpart=UUID=25F2-3254 datapart=UUID=629ac953-0ee4-4cce-ab49-7a828cbf7f04 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Dec 27 13:20:41 music kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 27 13:20:41 music kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 27 13:20:41 music kernel: Fallback order for Node 0: 0 Dec 27 13:20:41 music kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031168 Dec 27 13:20:41 music kernel: Policy zone: DMA Dec 27 13:20:41 music kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 27 13:20:41 music kernel: Memory: 3989132K/4190208K available (13440K kernel code, 2208K rwdata, 4284K rodata, 4864K init, 1086K bss, 135540K reserved, 65536K cma-reserved) Dec 27 13:20:41 music kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 27 13:20:41 music kernel: ftrace: allocating 43555 entries in 171 pages Dec 27 13:20:41 music kernel: ftrace: allocated 171 pages with 5 groups Dec 27 13:20:41 music kernel: trace event string verifier disabled Dec 27 13:20:41 music kernel: rcu: Preemptible hierarchical RCU implementation. Dec 27 13:20:41 music kernel: rcu: RCU event tracing is enabled. Dec 27 13:20:41 music kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Dec 27 13:20:41 music kernel: Trampoline variant of Tasks RCU enabled. Dec 27 13:20:41 music kernel: Rude variant of Tasks RCU enabled. Dec 27 13:20:41 music kernel: Tracing variant of Tasks RCU enabled. Dec 27 13:20:41 music kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Dec 27 13:20:41 music kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Dec 27 13:20:41 music kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 27 13:20:41 music kernel: Root IRQ handler: gic_handle_irq Dec 27 13:20:41 music kernel: GIC: Using split EOI/Deactivate mode Dec 27 13:20:41 music kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 27 13:20:41 music kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 27 13:20:41 music kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 27 13:20:41 music kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 27 13:20:41 music kernel: Console: colour dummy device 80x25 Dec 27 13:20:41 music kernel: printk: console [tty1] enabled Dec 27 13:20:41 music kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Dec 27 13:20:41 music kernel: pid_max: default: 32768 minimum: 301 Dec 27 13:20:41 music kernel: LSM: initializing lsm=capability,integrity Dec 27 13:20:41 music kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 27 13:20:41 music kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 27 13:20:41 music kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 27 13:20:41 music kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 27 13:20:41 music kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 27 13:20:41 music kernel: rcu: Hierarchical SRCU implementation. Dec 27 13:20:41 music kernel: rcu: Max phase no-delay instances is 1000. Dec 27 13:20:41 music kernel: EFI services will not be available. Dec 27 13:20:41 music kernel: smp: Bringing up secondary CPUs ... Dec 27 13:20:41 music kernel: Detected PIPT I-cache on CPU1 Dec 27 13:20:41 music kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Dec 27 13:20:41 music kernel: Detected PIPT I-cache on CPU2 Dec 27 13:20:41 music kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Dec 27 13:20:41 music kernel: Detected PIPT I-cache on CPU3 Dec 27 13:20:41 music kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Dec 27 13:20:41 music kernel: smp: Brought up 1 node, 4 CPUs Dec 27 13:20:41 music kernel: SMP: Total of 4 processors activated. Dec 27 13:20:41 music kernel: CPU features: detected: 32-bit EL0 Support Dec 27 13:20:41 music kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 27 13:20:41 music kernel: CPU features: detected: Common not Private translations Dec 27 13:20:41 music kernel: CPU features: detected: CRC32 instructions Dec 27 13:20:41 music kernel: CPU features: detected: RCpc load-acquire (LDAPR) Dec 27 13:20:41 music kernel: CPU features: detected: LSE atomic instructions Dec 27 13:20:41 music kernel: CPU features: detected: Privileged Access Never Dec 27 13:20:41 music kernel: CPU features: detected: RAS Extension Support Dec 27 13:20:41 music kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 27 13:20:41 music kernel: CPU: All CPU(s) started at EL2 Dec 27 13:20:41 music kernel: alternatives: applying system-wide alternatives Dec 27 13:20:41 music kernel: devtmpfs: initialized Dec 27 13:20:41 music kernel: Enabled cp15_barrier support Dec 27 13:20:41 music kernel: Enabled setend support Dec 27 13:20:41 music kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Dec 27 13:20:41 music kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 27 13:20:41 music kernel: pinctrl core: initialized pinctrl subsystem Dec 27 13:20:41 music kernel: DMI not present or invalid. Dec 27 13:20:41 music kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 27 13:20:41 music kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Dec 27 13:20:41 music kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 27 13:20:41 music kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 27 13:20:41 music kernel: audit: initializing netlink subsys (disabled) Dec 27 13:20:41 music kernel: audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 Dec 27 13:20:41 music kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 27 13:20:41 music kernel: cpuidle: using governor menu Dec 27 13:20:41 music kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 27 13:20:41 music kernel: ASID allocator initialised with 32768 entries Dec 27 13:20:41 music kernel: Serial: AMBA PL011 UART driver Dec 27 13:20:41 music kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Dec 27 13:20:41 music kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 Dec 27 13:20:41 music kernel: printk: console [ttyAMA10] enabled Dec 27 13:20:41 music kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-09-23T13:02:56, variant start_cd Dec 27 13:20:41 music kernel: raspberrypi-firmware soc:firmware: Firmware hash is 2682625900000000000000000000000000000000 Dec 27 13:20:41 music kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Dec 27 13:20:41 music kernel: Modules: 0 pages in range for non-PLT usage Dec 27 13:20:41 music kernel: Modules: 517776 pages in range for PLT usage Dec 27 13:20:41 music kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Dec 27 13:20:41 music kernel: iommu: Default domain type: Translated Dec 27 13:20:41 music kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 27 13:20:41 music kernel: SCSI subsystem initialized Dec 27 13:20:41 music kernel: usbcore: registered new interface driver usbfs Dec 27 13:20:41 music kernel: usbcore: registered new interface driver hub Dec 27 13:20:41 music kernel: usbcore: registered new device driver usb Dec 27 13:20:41 music kernel: pps_core: LinuxPPS API ver. 1 registered Dec 27 13:20:41 music kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 27 13:20:41 music kernel: PTP clock support registered Dec 27 13:20:41 music kernel: vgaarb: loaded Dec 27 13:20:41 music kernel: clocksource: Switched to clocksource arch_sys_counter Dec 27 13:20:41 music kernel: VFS: Disk quotas dquot_6.6.0 Dec 27 13:20:41 music kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 27 13:20:41 music kernel: FS-Cache: Loaded Dec 27 13:20:41 music kernel: CacheFiles: Loaded Dec 27 13:20:41 music kernel: NET: Registered PF_INET protocol family Dec 27 13:20:41 music kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 27 13:20:41 music kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 27 13:20:41 music kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 27 13:20:41 music kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 27 13:20:41 music kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Dec 27 13:20:41 music kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 27 13:20:41 music kernel: MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) Dec 27 13:20:41 music kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 27 13:20:41 music kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 27 13:20:41 music kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 27 13:20:41 music kernel: RPC: Registered named UNIX socket transport module. Dec 27 13:20:41 music kernel: RPC: Registered udp transport module. Dec 27 13:20:41 music kernel: RPC: Registered tcp transport module. Dec 27 13:20:41 music kernel: RPC: Registered tcp-with-tls transport module. Dec 27 13:20:41 music kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 27 13:20:41 music kernel: PCI: CLS 0 bytes, default 64 Dec 27 13:20:41 music kernel: Trying to unpack rootfs image as initramfs... Dec 27 13:20:41 music kernel: kvm [1]: IPA Size Limit: 40 bits Dec 27 13:20:41 music kernel: kvm [1]: vgic interrupt IRQ9 Dec 27 13:20:41 music kernel: kvm [1]: VHE mode initialized successfully Dec 27 13:20:41 music kernel: Initialise system trusted keyrings Dec 27 13:20:41 music kernel: workingset: timestamp_bits=42 max_order=20 bucket_order=0 Dec 27 13:20:41 music kernel: zbud: loaded Dec 27 13:20:41 music kernel: NFS: Registering the id_resolver key type Dec 27 13:20:41 music kernel: Key type id_resolver registered Dec 27 13:20:41 music kernel: Key type id_legacy registered Dec 27 13:20:41 music kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 27 13:20:41 music kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 27 13:20:41 music kernel: Key type asymmetric registered Dec 27 13:20:41 music kernel: Asymmetric key parser 'x509' registered Dec 27 13:20:41 music kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 27 13:20:41 music kernel: io scheduler mq-deadline registered Dec 27 13:20:41 music kernel: io scheduler kyber registered Dec 27 13:20:41 music kernel: io scheduler bfq registered Dec 27 13:20:41 music kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 26) Dec 27 13:20:41 music kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27) Dec 27 13:20:41 music kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28) Dec 27 13:20:41 music kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29) Dec 27 13:20:41 music kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 30) Dec 27 13:20:41 music kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 31) Dec 27 13:20:41 music kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Dec 27 13:20:41 music kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Dec 27 13:20:41 music kernel: serial serial0: tty port ttyS0 registered Dec 27 13:20:41 music kernel: iproc-rng200 107d208000.rng: hwrng registered Dec 27 13:20:41 music kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Dec 27 13:20:41 music kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Dec 27 13:20:41 music kernel: brd: module loaded Dec 27 13:20:41 music kernel: Freeing initrd memory: 28128K Dec 27 13:20:41 music kernel: loop: module loaded Dec 27 13:20:41 music kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 27 13:20:41 music kernel: Loading iSCSI transport class v2.0-870. Dec 27 13:20:41 music kernel: usbcore: registered new device driver r8152-cfgselector Dec 27 13:20:41 music kernel: usbcore: registered new interface driver r8152 Dec 27 13:20:41 music kernel: usbcore: registered new interface driver lan78xx Dec 27 13:20:41 music kernel: usbcore: registered new interface driver smsc95xx Dec 27 13:20:41 music kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 27 13:20:41 music kernel: dwc_otg: FIQ enabled Dec 27 13:20:41 music kernel: dwc_otg: NAK holdoff enabled Dec 27 13:20:41 music kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 27 13:20:41 music kernel: Module dwc_common_port init Dec 27 13:20:41 music kernel: usbcore: registered new interface driver uas Dec 27 13:20:41 music kernel: usbcore: registered new interface driver usb-storage Dec 27 13:20:41 music kernel: mousedev: PS/2 mouse device common for all mice Dec 27 13:20:41 music kernel: rpi-rtc soc:rpi_rtc: registered as rtc0 Dec 27 13:20:41 music kernel: rpi-rtc soc:rpi_rtc: setting system clock to 2025-12-27T12:20:30 UTC (1766838030) Dec 27 13:20:41 music kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Dec 27 13:20:41 music kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 27 13:20:41 music kernel: sdhci: Secure Digital Host Controller Interface driver Dec 27 13:20:41 music kernel: sdhci: Copyright(c) Pierre Ossman Dec 27 13:20:41 music kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 27 13:20:41 music kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 27 13:20:41 music kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Dec 27 13:20:41 music kernel: hid: raw HID events driver (C) Jiri Kosina Dec 27 13:20:41 music kernel: usbcore: registered new interface driver usbhid Dec 27 13:20:41 music kernel: usbhid: USB HID core driver Dec 27 13:20:41 music kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available Dec 27 13:20:41 music kernel: NET: Registered PF_PACKET protocol family Dec 27 13:20:41 music kernel: Key type dns_resolver registered Dec 27 13:20:41 music kernel: registered taskstats version 1 Dec 27 13:20:41 music kernel: Loading compiled-in X.509 certificates Dec 27 13:20:41 music kernel: Key type .fscrypt registered Dec 27 13:20:41 music kernel: Key type fscrypt-provisioning registered Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@110000 ranges: Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@110000, using [bus 00-ff] Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: Forcing gen 2 Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0000:00 Dec 27 13:20:41 music kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 27 13:20:41 music kernel: pci_bus 0000:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Dec 27 13:20:41 music kernel: pci_bus 0000:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Dec 27 13:20:41 music kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Dec 27 13:20:41 music kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 27 13:20:41 music kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 27 13:20:41 music kernel: brcm-pcie 1000110000.pcie: link down Dec 27 13:20:41 music kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 27 13:20:41 music kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 27 13:20:41 music kernel: pci 0000:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512 Dec 27 13:20:41 music kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 38 Dec 27 13:20:41 music kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 38 Dec 27 13:20:41 music kernel: pci_bus 0000:01: busn_res: [bus 01] is released Dec 27 13:20:41 music kernel: pci_bus 0000:00: busn_res: [bus 00-ff] is released Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: Forcing gen 2 Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00 Dec 27 13:20:41 music kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 27 13:20:41 music kernel: pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Dec 27 13:20:41 music kernel: pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Dec 27 13:20:41 music kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Dec 27 13:20:41 music kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 27 13:20:41 music kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 27 13:20:41 music kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Dec 27 13:20:41 music kernel: pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000 Dec 27 13:20:41 music kernel: pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] Dec 27 13:20:41 music kernel: pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] Dec 27 13:20:41 music kernel: pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] Dec 27 13:20:41 music kernel: pci 0000:01:00.0: supports D1 Dec 27 13:20:41 music kernel: pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold Dec 27 13:20:41 music kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 27 13:20:41 music kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] Dec 27 13:20:41 music kernel: pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] Dec 27 13:20:41 music kernel: pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] Dec 27 13:20:41 music kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] Dec 27 13:20:41 music kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 27 13:20:41 music kernel: pci 0000:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Dec 27 13:20:41 music kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 27 13:20:41 music kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 27 13:20:41 music kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Dec 27 13:20:41 music kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 39 Dec 27 13:20:41 music kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 39 Dec 27 13:20:41 music kernel: rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Dec 27 13:20:41 music kernel: rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Dec 27 13:20:41 music kernel: rp1 0000:01:00.0: enabling device (0000 -> 0002) Dec 27 13:20:41 music kernel: rp1 0000:01:00.0: chip_id 0x20001927 Dec 27 13:20:41 music kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101 Dec 27 13:20:41 music kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (2c:cf:67:61:1b:8f) Dec 27 13:20:41 music kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Dec 27 13:20:41 music kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Dec 27 13:20:41 music kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 27 13:20:41 music kernel: usb usb1: Product: xHCI Host Controller Dec 27 13:20:41 music kernel: usb usb1: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Dec 27 13:20:41 music kernel: usb usb1: SerialNumber: xhci-hcd.0 Dec 27 13:20:41 music kernel: hub 1-0:1.0: USB hub found Dec 27 13:20:41 music kernel: hub 1-0:1.0: 2 ports detected Dec 27 13:20:41 music kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Dec 27 13:20:41 music kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 27 13:20:41 music kernel: usb usb2: Product: xHCI Host Controller Dec 27 13:20:41 music kernel: usb usb2: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Dec 27 13:20:41 music kernel: usb usb2: SerialNumber: xhci-hcd.0 Dec 27 13:20:41 music kernel: hub 2-0:1.0: USB hub found Dec 27 13:20:41 music kernel: hub 2-0:1.0: 1 port detected Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Dec 27 13:20:41 music kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Dec 27 13:20:41 music kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Dec 27 13:20:41 music kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 27 13:20:41 music kernel: usb usb3: Product: xHCI Host Controller Dec 27 13:20:41 music kernel: usb usb3: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Dec 27 13:20:41 music kernel: usb usb3: SerialNumber: xhci-hcd.1 Dec 27 13:20:41 music kernel: hub 3-0:1.0: USB hub found Dec 27 13:20:41 music kernel: hub 3-0:1.0: 2 ports detected Dec 27 13:20:41 music kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Dec 27 13:20:41 music kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 27 13:20:41 music kernel: usb usb4: Product: xHCI Host Controller Dec 27 13:20:41 music kernel: usb usb4: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Dec 27 13:20:41 music kernel: usb usb4: SerialNumber: xhci-hcd.1 Dec 27 13:20:41 music kernel: hub 4-0:1.0: USB hub found Dec 27 13:20:41 music kernel: hub 4-0:1.0: 1 port detected Dec 27 13:20:41 music kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 27 13:20:41 music kernel: platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: platform 1000800000.codec: Adding to iommu group 0 Dec 27 13:20:41 music kernel: platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: platform 1000880000.pisp_be: Adding to iommu group 0 Dec 27 13:20:41 music kernel: platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Dec 27 13:20:41 music kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 27 13:20:41 music kernel: platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Dec 27 13:20:41 music kernel: platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Dec 27 13:20:41 music kernel: platform axi:gpu: Adding to iommu group 1 Dec 27 13:20:41 music kernel: platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Dec 27 13:20:41 music kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Dec 27 13:20:41 music kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 27 13:20:41 music kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Dec 27 13:20:41 music kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Dec 27 13:20:41 music kernel: mmc0: CQHCI version 5.10 Dec 27 13:20:41 music kernel: mmc1: CQHCI version 5.10 Dec 27 13:20:41 music kernel: of_cfs_init Dec 27 13:20:41 music kernel: of_cfs_init: OK Dec 27 13:20:41 music kernel: clk: Disabling unused clocks Dec 27 13:20:41 music kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Dec 27 13:20:41 music kernel: mmc0: Command Queue Engine enabled, 31 tags Dec 27 13:20:41 music kernel: mmc0: new ultra high speed SDR104 SDXC card at address aaaa Dec 27 13:20:41 music kernel: mmcblk0: mmc0:aaaa SN64G 59.5 GiB Dec 27 13:20:41 music kernel: mmcblk0: p1 p2 p3 Dec 27 13:20:41 music kernel: mmcblk0: mmc0:aaaa SN64G 59.5 GiB (quirks 0x00004000) Dec 27 13:20:41 music kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Dec 27 13:20:41 music kernel: Freeing unused kernel memory: 4864K Dec 27 13:20:41 music kernel: Run /init as init process Dec 27 13:20:41 music kernel: with arguments: Dec 27 13:20:41 music kernel: /init Dec 27 13:20:41 music kernel: splash Dec 27 13:20:41 music kernel: nodebug Dec 27 13:20:41 music kernel: with environment: Dec 27 13:20:41 music kernel: HOME=/ Dec 27 13:20:41 music kernel: TERM=linux Dec 27 13:20:41 music kernel: imgpart=UUID=bc357b65-966a-45c3-975f-98ac1d7c9730 Dec 27 13:20:41 music kernel: imgfile=/volumio_current.sqsh Dec 27 13:20:41 music kernel: bootpart=UUID=25F2-3254 Dec 27 13:20:41 music kernel: datapart=UUID=629ac953-0ee4-4cce-ab49-7a828cbf7f04 Dec 27 13:20:41 music kernel: uuidconfig=cmdline.txt Dec 27 13:20:41 music kernel: bootdelay=7 Dec 27 13:20:41 music kernel: use_kmsg=no Dec 27 13:20:41 music kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_info_register (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_device_new (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Dec 27 13:20:41 music kernel: snd_timer: Unknown symbol snd_register_device (err -2) Dec 27 13:20:41 music kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Dec 27 13:20:41 music kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Dec 27 13:20:41 music kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Dec 27 13:20:41 music kernel: [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 0 Dec 27 13:20:41 music kernel: fuse: init (API version 7.39) Dec 27 13:20:41 music kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 27 13:20:41 music kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 27 13:20:41 music kernel: EXT4-fs (mmcblk0p2): mounted filesystem bc357b65-966a-45c3-975f-98ac1d7c9730 r/w with ordered data mode. Quota mode: none. Dec 27 13:20:41 music kernel: loop0: detected capacity change from 0 to 1005568 Dec 27 13:20:41 music kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 27 13:20:41 music kernel: EXT4-fs (mmcblk0p3): mounted filesystem 629ac953-0ee4-4cce-ab49-7a828cbf7f04 r/w with ordered data mode. Quota mode: none. Dec 27 13:20:41 music kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Dec 27 13:20:41 music kernel: NET: Registered PF_INET6 protocol family Dec 27 13:20:41 music kernel: Segment Routing with IPv6 Dec 27 13:20:41 music kernel: In-situ OAM (IOAM) with IPv6 Dec 27 13:20:41 music 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) Dec 27 13:20:41 music systemd[1]: Detected architecture arm64. Dec 27 13:20:41 music systemd[1]: Set hostname to . Dec 27 13:20:41 music kernel: uart-pl011 107d001000.serial: no DMA platform data Dec 27 13:20:41 music 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. Dec 27 13:20:41 music systemd[1]: Listening on Journal Audit Socket. Dec 27 13:20:41 music systemd[1]: Listening on fsck to fsckd communication Socket. Dec 27 13:20:41 music systemd[1]: Created slice system-getty.slice. Dec 27 13:20:41 music systemd[1]: Listening on Journal Socket (/dev/log). Dec 27 13:20:41 music systemd[1]: Created slice system-systemd\x2dfsck.slice. Dec 27 13:20:41 music systemd[1]: Listening on udev Control Socket. Dec 27 13:20:41 music kernel: i2c_dev: i2c /dev entries driver Dec 27 13:20:41 music systemd-journald[378]: Journal started Dec 27 13:20:41 music systemd-journald[378]: Runtime journal (/run/log/journal/d59f8fba44ffb4c0d2e89736682361ca) is 3.7M, max 30.0M, 26.2M free. Dec 27 13:20:41 music systemd-modules-load[372]: Inserted module 'i2c_dev' Dec 27 13:20:41 music fake-hwclock[368]: Current system time: 2025-12-27 12:20:41 Dec 27 13:20:41 music fake-hwclock[368]: fake-hwclock saved clock information is in the past: 2025-12-27 12:20:18 Dec 27 13:20:41 music fake-hwclock[368]: To set system time to this saved clock anyway, use "force" Dec 27 13:20:41 music systemd[1]: Mounted Kernel Configuration File System. Dec 27 13:20:41 music systemd[1]: Mounted FUSE Control File System. Dec 27 13:20:41 music systemd[1]: Started Load/Save Random Seed. Dec 27 13:20:41 music systemd[1]: Started udev Coldplug all Devices. Dec 27 13:20:41 music systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 27 13:20:41 music systemd[1]: dynamicswap.service: Succeeded. Dec 27 13:20:41 music systemd[1]: Started Create System Users. Dec 27 13:20:41 music systemd[1]: Started Apply Kernel Variables. Dec 27 13:20:41 music systemd[1]: Starting Create Static Device Nodes in /dev... Dec 27 13:20:41 music systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 27 13:20:41 music systemd[1]: Started Create Static Device Nodes in /dev. Dec 27 13:20:41 music systemd[1]: Starting udev Kernel Device Manager... Dec 27 13:20:41 music systemd[1]: Reached target Local File Systems (Pre). Dec 27 13:20:41 music systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 27 13:20:41 music systemd[1]: Mounting /tmp... Dec 27 13:20:41 music systemd[1]: Mounting /var/spool/cups... Dec 27 13:20:41 music systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 27 13:20:41 music systemd[1]: Mounting /var/log... Dec 27 13:20:41 music systemd[1]: Mounted /tmp. Dec 27 13:20:41 music systemd[1]: Mounted /var/spool/cups. Dec 27 13:20:41 music systemd[1]: Mounted /var/log. Dec 27 13:20:41 music systemd[1]: Starting Flush Journal to Persistent Storage... Dec 27 13:20:41 music systemd[1]: Mounting /var/spool/cups/tmp... Dec 27 13:20:41 music systemd[1]: Mounted /var/spool/cups/tmp. Dec 27 13:20:41 music systemd-udevd[403]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 27 13:20:41 music systemd-journald[378]: Runtime journal (/run/log/journal/d59f8fba44ffb4c0d2e89736682361ca) is 3.7M, max 30.0M, 26.2M free. Dec 27 13:20:41 music systemd[1]: Started udev Kernel Device Manager. Dec 27 13:20:41 music systemd[1]: Starting Show Plymouth Boot Screen... Dec 27 13:20:41 music systemd[1]: Started Flush Journal to Persistent Storage. Dec 27 13:20:41 music systemd[1]: plymouth-start.service: Succeeded. Dec 27 13:20:41 music systemd[1]: Started Show Plymouth Boot Screen. Dec 27 13:20:41 music systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 27 13:20:41 music systemd[1]: Reached target Paths. Dec 27 13:20:41 music systemd[1]: Reached target Local Encrypted Volumes. Dec 27 13:20:41 music systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 27 13:20:41 music kernel: mc: Linux media interface: v0.10 Dec 27 13:20:41 music systemd-udevd[442]: Using default interface naming scheme 'v240'. Dec 27 13:20:41 music systemd-udevd[442]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 27 13:20:41 music kernel: videodev: Linux video capture interface: v2.00 Dec 27 13:20:41 music kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Dec 27 13:20:41 music kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Dec 27 13:20:41 music kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Dec 27 13:20:41 music kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Dec 27 13:20:41 music kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Dec 27 13:20:41 music kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Dec 27 13:20:41 music kernel: rp1_pio: Unknown symbol rp1_firmware_message (err -2) Dec 27 13:20:41 music kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Dec 27 13:20:41 music kernel: rp1_pio: Unknown symbol devm_rp1_firmware_get (err -2) Dec 27 13:20:41 music kernel: rp1_pio: Unknown symbol rp1_firmware_get_feature (err -2) Dec 27 13:20:41 music kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Dec 27 13:20:41 music kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Dec 27 13:20:41 music kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Dec 27 13:20:41 music systemd[1]: Found device /dev/ttyAMA10. Dec 27 13:20:41 music kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 27 13:20:41 music kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Dec 27 13:20:41 music kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Dec 27 13:20:41 music kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Dec 27 13:20:41 music systemd[1]: Found device /dev/disk/by-uuid/25F2-3254. Dec 27 13:20:41 music systemd-udevd[440]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 27 13:20:41 music systemd[1]: Starting File System Check on /dev/disk/by-uuid/25F2-3254... Dec 27 13:20:41 music kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 27 13:20:41 music kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 27 13:20:41 music kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 27 13:20:41 music kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 27 13:20:41 music kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 27 13:20:41 music systemd-udevd[410]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 27 13:20:41 music kernel: Bluetooth: Core ver 2.22 Dec 27 13:20:41 music kernel: NET: Registered PF_BLUETOOTH protocol family Dec 27 13:20:41 music kernel: Bluetooth: HCI device and connection manager initialized Dec 27 13:20:41 music kernel: Bluetooth: HCI socket layer initialized Dec 27 13:20:41 music kernel: Bluetooth: L2CAP socket layer initialized Dec 27 13:20:41 music kernel: Bluetooth: SCO socket layer initialized Dec 27 13:20:41 music kernel: Bluetooth: HCI UART driver ver 2.3 Dec 27 13:20:41 music kernel: Bluetooth: HCI UART protocol H4 registered Dec 27 13:20:41 music kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 27 13:20:41 music kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 27 13:20:42 music kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 27 13:20:42 music kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 27 13:20:42 music systemd-fsck[585]: fsck.fat 4.1 (2017-01-24) Dec 27 13:20:42 music systemd-fsck[585]: /dev/mmcblk0p1: 500 files, 168119/184582 clusters Dec 27 13:20:42 music systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Dec 27 13:20:42 music systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 27 13:20:42 music systemd[1]: Started File System Check Daemon to report status. Dec 27 13:20:42 music systemd[1]: Started File System Check on /dev/disk/by-uuid/25F2-3254. Dec 27 13:20:42 music sh[621]: command failed: No such device (-19) Dec 27 13:20:42 music systemd[1]: Mounting /boot... Dec 27 13:20:42 music ifup[622]: command failed: No such device (-19) Dec 27 13:20:42 music systemd[1]: Mounted /boot. Dec 27 13:20:42 music systemd[1]: Reached target Local File Systems. Dec 27 13:20:42 music systemd[1]: Started ifup for eth0. Dec 27 13:20:42 music systemd[1]: Starting Raise network interfaces... Dec 27 13:20:42 music systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 27 13:20:42 music systemd[1]: Starting Create Volatile Files and Directories... Dec 27 13:20:42 music systemd[1]: Starting Preprocess NFS configuration... Dec 27 13:20:42 music systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 27 13:20:42 music systemd[1]: Received SIGRTMIN+20 from PID 265 (plymouthd). Dec 27 13:20:42 music systemd[1]: plymouth-read-write.service: Succeeded. Dec 27 13:20:42 music systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 27 13:20:42 music systemd[1]: nfs-config.service: Succeeded. Dec 27 13:20:42 music systemd[1]: Started Preprocess NFS configuration. Dec 27 13:20:42 music systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 27 13:20:42 music systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 27 13:20:42 music systemd[1]: Reached target NFS client services. Dec 27 13:20:42 music systemd[1]: Started Create Volatile Files and Directories. Dec 27 13:20:42 music systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 27 13:20:42 music systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 27 13:20:42 music systemd[1]: Starting RPC bind portmap service... Dec 27 13:20:42 music systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 27 13:20:42 music systemd[1]: Reached target System Initialization. Dec 27 13:20:42 music systemd[1]: Started Daily apt download activities. Dec 27 13:20:42 music systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 27 13:20:42 music systemd[1]: Started Daily apt upgrade and clean activities. Dec 27 13:20:42 music systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 27 13:20:42 music systemd[1]: Reached target Timers. Dec 27 13:20:42 music systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 27 13:20:42 music systemd[1]: Listening on triggerhappy.socket. Dec 27 13:20:42 music systemd[1]: Reached target Sockets. Dec 27 13:20:42 music systemd[1]: Reached target Basic System. Dec 27 13:20:42 music systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 27 13:20:42 music systemd[1]: Started volumio-remote-updater.service. Dec 27 13:20:42 music systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 27 13:20:42 music systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 27 13:20:42 music systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Dec 27 13:20:42 music systemd[1]: Starting Volumio Time Update Utility... Dec 27 13:20:42 music systemd[1]: Starting Login Service... Dec 27 13:20:42 music systemd[1]: Starting dhcpcd on all interfaces... Dec 27 13:20:42 music systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 27 13:20:42 music systemd[1]: Started D-Bus System Message Bus. Dec 27 13:20:42 music volumio-time-update[659]: volumio-time-update-util: Fetching time from Volumio... Dec 27 13:20:42 music systemd[1]: Starting WPA supplicant... Dec 27 13:20:42 music systemd[1]: Starting Wireless Services... Dec 27 13:20:42 music systemd[1]: Starting triggerhappy global hotkey daemon... Dec 27 13:20:42 music systemd[1]: Started Volumio Log Rotation Service. Dec 27 13:20:42 music dhcpcd[664]: Not running dhcpcd because /etc/network/interfaces Dec 27 13:20:42 music dhcpcd[664]: defines some interfaces that will use a Dec 27 13:20:42 music dhcpcd[664]: DHCP client or static address Dec 27 13:20:42 music systemd[1]: Started UPnP Renderer front-end to MPD. Dec 27 13:20:42 music systemd[1]: Started Volumio Iptables Module. Dec 27 13:20:42 music systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 27 13:20:42 music systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 27 13:20:42 music systemd[1]: Failed to start dhcpcd on all interfaces. Dec 27 13:20:42 music kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 27 13:20:42 music kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 27 13:20:42 music kernel: usbcore: registered new interface driver brcmfmac Dec 27 13:20:42 music kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Dec 27 13:20:42 music thd[678]: Unable to parse trigger line: Dec 27 13:20:42 music thd[678]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 27 13:20:42 music thd[678]: Unable to parse trigger line: Dec 27 13:20:42 music thd[678]: Found socket passed from systemd Dec 27 13:20:42 music systemd-logind[662]: New seat seat0. Dec 27 13:20:42 music systemd-logind[662]: Watching system buttons on /dev/input/event0 (pwr_button) Dec 27 13:20:42 music kernel: Bluetooth: hci0: BCM: chip id 107 Dec 27 13:20:42 music kernel: Bluetooth: hci0: BCM: features 0x2f Dec 27 13:20:42 music kernel: Bluetooth: hci0: BCM4345C0 Dec 27 13:20:42 music kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 27 13:20:42 music kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 27 13:20:42 music ifup[622]: command failed: No such device (-19) Dec 27 13:20:42 music avahi-daemon[657]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 27 13:20:42 music avahi-daemon[657]: Successfully dropped root privileges. Dec 27 13:20:42 music avahi-daemon[657]: avahi-daemon 0.7 starting up. Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Dec 27 13:20:42 music kernel: Registered IR keymap rc-cec Dec 27 13:20:42 music kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Dec 27 13:20:42 music kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input1 Dec 27 13:20:42 music kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card0/input2 Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Dec 27 13:20:42 music kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 27 13:20:42 music kernel: Registered IR keymap rc-cec Dec 27 13:20:42 music kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Dec 27 13:20:42 music kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input3 Dec 27 13:20:42 music kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card1/input4 Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Dec 27 13:20:42 music kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 1 Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Dec 27 13:20:42 music kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Dec 27 13:20:42 music systemd-udevd[440]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 27 13:20:42 music systemd-logind[662]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Dec 27 13:20:42 music systemd-udevd[417]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 27 13:20:42 music systemd-udevd[420]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 27 13:20:42 music systemd-udevd[436]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 27 13:20:42 music systemd-logind[662]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Dec 27 13:20:42 music kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 27 13:20:42 music 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 Dec 27 13:20:42 music kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Dec 27 13:20:42 music kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Dec 27 13:20:42 music sh[621]: eth0: waiting for carrier Dec 27 13:20:42 music dhcpcd[679]: eth0: waiting for carrier Dec 27 13:20:42 music systemd-udevd[417]: Using default interface naming scheme 'v240'. Dec 27 13:20:42 music systemd-udevd[417]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 27 13:20:42 music kernel: pps pps0: new PPS source ptp0 Dec 27 13:20:42 music kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Dec 27 13:20:42 music avahi-daemon[657]: Successfully called chroot(). Dec 27 13:20:42 music volumio-remote-updater[656]: Error: No active session Dec 27 13:20:42 music avahi-daemon[657]: Successfully dropped remaining capabilities. Dec 27 13:20:42 music wpa_supplicant[672]: Successfully initialized wpa_supplicant Dec 27 13:20:42 music avahi-daemon[657]: Loading service file /services/volumio.service. Dec 27 13:20:42 music volumio[682]: Could not open config: /tmp/upmpdcli.conf Dec 27 13:20:42 music systemd[1]: Started RPC bind portmap service. Dec 27 13:20:42 music systemd[1]: Started triggerhappy global hotkey daemon. Dec 27 13:20:42 music systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 27 13:20:42 music systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 27 13:20:42 music haveged[642]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 27 13:20:42 music haveged[642]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63488/63488 Dec 27 13:20:42 music haveged[642]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00337 Dec 27 13:20:42 music haveged[642]: haveged: fills: 0, generated: 0 Dec 27 13:20:42 music avahi-daemon[657]: Network interface enumeration completed. Dec 27 13:20:42 music volumio-remote-updater[656]: [2025-12-27 13:20:42] [info] asio async_connect error: system:111 (Connection refused) Dec 27 13:20:42 music volumio-remote-updater[656]: [2025-12-27 13:20:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 27 13:20:42 music volumio-remote-updater[656]: [2025-12-27 13:20:42] [error] handle_connect error: Underlying Transport Error Dec 27 13:20:42 music avahi-daemon[657]: Server startup complete. Host name is music.local. Local service cookie is 2358996638. Dec 27 13:20:42 music avahi-daemon[657]: Service "music" (/services/volumio.service) successfully established. Dec 27 13:20:42 music volumio-time-update[659]: volumio-time-update-util: Date not found in response Dec 27 13:20:42 music volumio-time-update[659]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 13:20:42 music dbus-daemon[666]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 27 13:20:42 music systemd[1]: iptables.service: Succeeded. Dec 27 13:20:42 music systemd[1]: Started Manage Sound Card State (restore and store). Dec 27 13:20:42 music systemd[1]: Starting Save/Restore Sound Card State... Dec 27 13:20:42 music alsactl[810]: alsactl 1.1.8 daemon started Dec 27 13:20:42 music systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 27 13:20:42 music systemd[1]: Reached target Remote File Systems (Pre). Dec 27 13:20:42 music systemd[1]: Reached target Remote File Systems. Dec 27 13:20:42 music systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 27 13:20:42 music systemd[1]: Reached target RPC Port Mapper. Dec 27 13:20:42 music systemd[1]: Created slice system-bthelper.slice. Dec 27 13:20:42 music systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Dec 27 13:20:42 music systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 27 13:20:42 music systemd[1]: Started Raise network interfaces. Dec 27 13:20:42 music kernel: alsactl[811]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 27 13:20:42 music systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 27 13:20:42 music systemd[1]: Started Load/Save RF Kill Switch Status. Dec 27 13:20:42 music systemd[1]: Started Save/Restore Sound Card State. Dec 27 13:20:42 music systemd[1]: Reached target Sound Card. Dec 27 13:20:43 music systemd[1]: Started ifup for wlan0. Dec 27 13:20:43 music systemd[1]: Started WPA supplicant. Dec 27 13:20:43 music systemd[1]: Started Login Service. Dec 27 13:20:43 music systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 27 13:20:43 music systemd[1]: Reached target Network. Dec 27 13:20:43 music systemd[1]: Starting Network Time Service... Dec 27 13:20:43 music systemd[1]: Reached target Network is Online. Dec 27 13:20:43 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:43 music systemd[1]: Starting LSB: Brings up/down network automatically... Dec 27 13:20:43 music systemd[1]: Starting /etc/rc.local Compatibility... Dec 27 13:20:43 music kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 27 13:20:43 music systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 27 13:20:43 music systemd[1]: Starting Permit User Sessions... Dec 27 13:20:43 music systemd[1]: Starting Samba NMB Daemon... Dec 27 13:20:43 music systemd[1]: Started /etc/rc.local Compatibility. Dec 27 13:20:43 music kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 13:20:43 music kernel: Bluetooth: hci0: BCM: features 0x2f Dec 27 13:20:43 music kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 27 13:20:43 music kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 27 13:20:43 music kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Dec 27 13:20:43 music systemd[1]: Started Permit User Sessions. Dec 27 13:20:43 music loadcpufreq[813]: Loading cpufreq kernel modules...done (none). Dec 27 13:20:43 music systemd[1]: Starting Hold until boot process finishes up... Dec 27 13:20:43 music systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 27 13:20:43 music systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 27 13:20:43 music bthelper[814]: Raspberry Pi BDADDR already set Dec 27 13:20:43 music systemd[1]: Started Raspberry Pi bluetooth helper. Dec 27 13:20:43 music ntpd[830]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 27 13:20:43 music ntpd[830]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 27 13:20:43 music systemd[1]: Started Network Time Service. Dec 27 13:20:43 music ntpd[908]: proto: precision = 0.352 usec (-21) Dec 27 13:20:43 music systemd[1]: Starting Bluetooth service... Dec 27 13:20:43 music systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 27 13:20:43 music systemd[1]: Received SIGRTMIN+21 from PID 265 (plymouthd). Dec 27 13:20:43 music systemd[1]: Received SIGRTMIN+21 from PID 265 (plymouthd). Dec 27 13:20:43 music systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 27 13:20:43 music ifplugd[834]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Dec 27 13:20:43 music systemd[1]: Started Hold until boot process finishes up. Dec 27 13:20:43 music systemd[1]: plymouth-quit.service: Succeeded. Dec 27 13:20:43 music systemd[1]: Started Terminate Plymouth Boot Screen. Dec 27 13:20:43 music systemd[1]: Started Getty on tty1. Dec 27 13:20:43 music systemd[1]: Started Serial Getty on ttyAMA10. Dec 27 13:20:43 music systemd[1]: Reached target Login Prompts. Dec 27 13:20:43 music ifplugd(eth0)[927]: ifplugd 0.28 initializing. Dec 27 13:20:43 music ifplugd(eth0)[927]: Using interface eth0/2C:CF:67:61:1B:8F with driver (version: 6.6.62-v8+) Dec 27 13:20:43 music ifplugd(eth0)[927]: Using detection mode: SIOCETHTOOL Dec 27 13:20:43 music ifplugd(eth0)[927]: Initialization complete, link beat not detected. Dec 27 13:20:43 music ntpd[908]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 27 13:20:43 music ntpd[908]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 27 13:20:43 music ntpd[908]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 365 days ago Dec 27 13:20:43 music ntpd[908]: Listen and drop on 0 v6wildcard [::]:123 Dec 27 13:20:43 music ntpd[908]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 27 13:20:43 music ntpd[908]: Listen normally on 2 lo 127.0.0.1:123 Dec 27 13:20:43 music ntpd[908]: Listening on routing socket on fd #19 for interface updates Dec 27 13:20:43 music ntpd[908]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 27 13:20:43 music ntpd[908]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 27 13:20:43 music sh[818]: wlan0=wlan0 Dec 27 13:20:43 music cpufrequtils[910]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 27 13:20:43 music systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 27 13:20:43 music bluetoothd[909]: Bluetooth daemon 5.83 Dec 27 13:20:43 music bluetoothd[909]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 27 13:20:43 music systemd[1]: Started Bluetooth service. Dec 27 13:20:43 music systemd[1]: Reached target Bluetooth. Dec 27 13:20:43 music bluetoothd[909]: Starting SDP server Dec 27 13:20:43 music bluetoothd[909]: Excluding (cli) hostname Dec 27 13:20:43 music bluetoothd[909]: Excluding (cli) scanparam Dec 27 13:20:43 music bluetoothd[909]: Excluding (cli) battery Dec 27 13:20:43 music bluetoothd[909]: Bluetooth management interface 1.22 initialized Dec 27 13:20:43 music bluetoothd[909]: Battery Provider Manager created Dec 27 13:20:43 music kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 27 13:20:43 music kernel: Bluetooth: BNEP filters: protocol multicast Dec 27 13:20:43 music kernel: Bluetooth: BNEP socket layer initialized Dec 27 13:20:43 music kernel: Bluetooth: MGMT ver 1.22 Dec 27 13:20:43 music ifplugd[834]: start eth0...done. Dec 27 13:20:43 music systemd[1]: Started LSB: Brings up/down network automatically. Dec 27 13:20:43 music kernel: NET: Registered PF_ALG protocol family Dec 27 13:20:43 music bluetoothd[909]: src/adapter.c:convert_config() Unable set contents for /usr/var/lib/bluetooth/2C:CF:67:61:1B:91/settings: (Failed to create file “/usr/var/lib/bluetooth/2C:CF:67:61:1B:91/settings.AIL1H3”: No such file or directory) Dec 27 13:20:43 music bluetoothd[909]: src/adapter.c:load_config() Unable to load key file from /usr/var/lib/bluetooth/2C:CF:67:61:1B:91/settings: (No such file or directory) Dec 27 13:20:43 music bluetoothd[909]: Unable to open adapter storage directory: /usr/var/lib/bluetooth/2C:CF:67:61:1B:91 Dec 27 13:20:43 music bluetoothd[909]: Failed to set privacy: Rejected (0x0b) Dec 27 13:20:43 music wireless.js[675]: Cleaning previous... Dec 27 13:20:43 music nmbd[855]: [2025/12/27 13:20:43.443647, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 27 13:20:43 music nmbd[855]: started asyncdns process 954 Dec 27 13:20:43 music nmbd[855]: [2025/12/27 13:20:43.444344, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 27 13:20:43 music nmbd[855]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 27 13:20:43 music nmbd[855]: [2025/12/27 13:20:43.444384, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 27 13:20:43 music nmbd[855]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 27 13:20:43 music sudo[955]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 27 13:20:43 music sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:43 music sudo[955]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:43 music sudo[957]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 27 13:20:43 music sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:44 music sudo[957]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:44 music wireless.js[675]: Stopped aP Dec 27 13:20:44 music sudo[964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 27 13:20:44 music sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:44 music sudo[964]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:44 music kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 13:20:44 music sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 27 13:20:44 music sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:44 music sudo[966]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:44 music sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 27 13:20:44 music sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:44 music sudo[973]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:44 music sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 27 13:20:44 music sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:44 music ntpd[908]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 27 13:20:45 music ntpd[908]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 27 13:20:46 music ntpd[908]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 27 13:20:46 music sudo[975]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:46 music wireless.js[675]: SETTING APPROPRIATE REG DOMAIN: FR Dec 27 13:20:46 music sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 27 13:20:46 music sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:46 music sudo[980]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:46 music sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Dec 27 13:20:46 music sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:46 music sudo[982]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:46 music wireless.js[675]: SUCCESSFULLY SET NEW REGDOMAIN: FR Dec 27 13:20:46 music sudo[989]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 27 13:20:46 music sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:46 music sudo[989]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:46 music sudo[991]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 27 13:20:46 music sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:46 music kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Dec 27 13:20:47 music ntpd[908]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 27 13:20:47 music sh[621]: eth0: carrier acquired Dec 27 13:20:47 music dhcpcd[679]: eth0: carrier acquired Dec 27 13:20:47 music sudo[991]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:47 music sh[621]: DUID 00:01:00:01:2f:b6:20:2e:2c:cf:67:61:1b:8f Dec 27 13:20:47 music sh[621]: eth0: IAID 67:61:1b:8f Dec 27 13:20:47 music dhcpcd[679]: DUID 00:01:00:01:2f:b6:20:2e:2c:cf:67:61:1b:8f Dec 27 13:20:47 music dhcpcd[679]: eth0: IAID 67:61:1b:8f Dec 27 13:20:47 music sh[621]: eth0: adding address fe80::d1b2:7761:872c:d685 Dec 27 13:20:47 music dhcpcd[679]: eth0: adding address fe80::d1b2:7761:872c:d685 Dec 27 13:20:47 music sh[621]: ipv6_addaddr1: Permission denied Dec 27 13:20:47 music dhcpcd[679]: ipv6_addaddr1: Permission denied Dec 27 13:20:47 music avahi-daemon[657]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 27 13:20:47 music avahi-daemon[657]: New relevant interface wlan0.IPv4 for mDNS. Dec 27 13:20:47 music avahi-daemon[657]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 27 13:20:47 music kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 27 13:20:47 music systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Dec 27 13:20:47 music systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 27 13:20:47 music dnsmasq[998]: dnsmasq: syntax check OK. Dec 27 13:20:47 music hostapd[999]: Configuration file: /etc/hostapd/hostapd.conf Dec 27 13:20:47 music hostapd[999]: wlan0: Could not connect to kernel driver Dec 27 13:20:47 music hostapd[999]: Using interface wlan0 with hwaddr 2c:cf:67:61:1b:90 and ssid "Volumio-AC629" Dec 27 13:20:47 music dnsmasq[1005]: started, version 2.80 cachesize 150 Dec 27 13:20:47 music dnsmasq[1005]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Dec 27 13:20:47 music dnsmasq-dhcp[1005]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Dec 27 13:20:47 music dnsmasq[1005]: reading /etc/resolv.conf Dec 27 13:20:47 music dnsmasq[1005]: using nameserver 208.67.222.222#53 Dec 27 13:20:47 music dnsmasq[1005]: using nameserver 208.67.220.220#53 Dec 27 13:20:47 music dnsmasq[1005]: read /etc/hosts - 1 addresses Dec 27 13:20:47 music systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Dec 27 13:20:47 music systemd[1]: Reached target Host and Network Name Lookups. Dec 27 13:20:47 music hostapd[999]: wlan0: interface state UNINITIALIZED->ENABLED Dec 27 13:20:47 music hostapd[999]: wlan0: AP-ENABLED Dec 27 13:20:47 music systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 27 13:20:47 music ifplugd(eth0)[927]: Link beat detected. Dec 27 13:20:47 music iwconfig[1013]: Error for wireless request "Set Power Management" (8B2C) : Dec 27 13:20:47 music iwconfig[1013]: SET failed on device wlan0 ; Invalid argument. Dec 27 13:20:47 music systemd[1]: wireless.service: Succeeded. Dec 27 13:20:47 music systemd[1]: Started Wireless Services. Dec 27 13:20:47 music systemd[1]: Started Volumio Backend Module. Dec 27 13:20:47 music systemd[1]: Started Volumio Cpu Tweaker. Dec 27 13:20:47 music ifplugd(eth0)[927]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 27 13:20:47 music volumio-cpu-tweak[1015]: Setting RT Priority for mpd Dec 27 13:20:47 music volumio-cpu-tweak[1015]: pid 35's current scheduling policy: SCHED_OTHER Dec 27 13:20:47 music volumio-cpu-tweak[1015]: pid 35's current scheduling priority: 0 Dec 27 13:20:47 music volumio-cpu-tweak[1015]: Setting MPD Affinity Dec 27 13:20:47 music volumio-cpu-tweak[1015]: pid 3's current affinity mask: f Dec 27 13:20:47 music volumio-cpu-tweak[1015]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 27 13:20:47 music volumio-cpu-tweak[1015]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Dec 27 13:20:47 music ifplugd(eth0)[927]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 27 13:20:47 music systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 27 13:20:47 music volumio-remote-updater[656]: [2025-12-27 13:20:47] [connect] Successful connection Dec 27 13:20:47 music sh[621]: eth0: soliciting an IPv6 router Dec 27 13:20:47 music dhcpcd[679]: eth0: soliciting an IPv6 router Dec 27 13:20:47 music volumio-time-update[659]: volumio-time-update-util: Fetching time from Volumio... Dec 27 13:20:47 music volumio-time-update[659]: volumio-time-update-util: Date not found in response Dec 27 13:20:47 music volumio-time-update[659]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 13:20:47 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:47 music volumio[1014]: info: ----- Volumio3 ---- Dec 27 13:20:47 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:47 music volumio[1014]: info: ----- System startup ---- Dec 27 13:20:47 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:47 music systemd[1]: systemd-rfkill.service: Succeeded. Dec 27 13:20:48 music bluetoothd[909]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Dec 27 13:20:48 music bthelper[814]: [NEW] Media /org/bluez/hci0 Dec 27 13:20:48 music bthelper[814]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 27 13:20:48 music bthelper[814]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 27 13:20:48 music bthelper[814]: hci0 class of device changed: 0x000000 Dec 27 13:20:48 music bthelper[814]: hci0 new_settings: ssp br/edr le secure-conn Dec 27 13:20:48 music bthelper[814]: [CHG] Controller 2C:CF:67:61:1B:91 PowerState: on-disabling Dec 27 13:20:48 music bthelper[814]: Changing power off succeeded Dec 27 13:20:48 music bthelper[814]: [NEW] Media /org/bluez/hci0 Dec 27 13:20:48 music bthelper[814]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 27 13:20:48 music bthelper[814]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 27 13:20:48 music bthelper[814]: [CHG] Controller 2C:CF:67:61:1B:91 PowerState: off-enabling Dec 27 13:20:48 music sh[621]: eth0: soliciting a DHCP lease Dec 27 13:20:48 music dhcpcd[679]: eth0: soliciting a DHCP lease Dec 27 13:20:48 music bthelper[814]: hci0 class of device changed: 0x00041c Dec 27 13:20:48 music bthelper[814]: [CHG] Controller 2C:CF:67:61:1B:91 Class: 0x0000041c (1052) Dec 27 13:20:48 music bthelper[814]: hci0 new_settings: powered ssp br/edr le secure-conn Dec 27 13:20:48 music bthelper[814]: Changing power on succeeded Dec 27 13:20:48 music bthelper[814]: [CHG] Controller 2C:CF:67:61:1B:91 PowerState: on Dec 27 13:20:48 music bthelper[814]: [CHG] Controller 2C:CF:67:61:1B:91 Powered: yes Dec 27 13:20:48 music volumio[1014]: info: MYVOLUMIO Environment detected Dec 27 13:20:48 music volumio[1014]: info: Plugin folders cleanup Dec 27 13:20:48 music volumio[1014]: info: Scanning into folder /volumio/app/plugins/ Dec 27 13:20:48 music volumio[1014]: info: Scanning category audio_interface Dec 27 13:20:48 music volumio[1014]: info: Scanning category miscellanea Dec 27 13:20:48 music volumio[1014]: info: Scanning category music_service Dec 27 13:20:48 music volumio[1014]: info: Scanning category plugins.json Dec 27 13:20:48 music volumio[1014]: info: Scanning category system_controller Dec 27 13:20:48 music volumio[1014]: info: Scanning category user_interface Dec 27 13:20:48 music volumio[1014]: info: Scanning into folder /data/plugins/ Dec 27 13:20:48 music volumio[1014]: info: Scanning category audio_interface Dec 27 13:20:48 music volumio[1014]: info: Scanning category music_service Dec 27 13:20:48 music volumio[1014]: info: Plugin folders cleanup completed Dec 27 13:20:48 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:48 music volumio[1014]: info: ----- Core plugins startup ---- Dec 27 13:20:48 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:48 music volumio[1014]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 13:20:48 music volumio[1014]: info: Adding plugin upnp to MyMusic Plugins Dec 27 13:20:48 music volumio[1014]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 13:20:48 music volumio[1014]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 13:20:48 music volumio[1014]: info: Loading plugins from folder /data/plugins/ Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "system"... Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "appearance"... Dec 27 13:20:48 music nmbd[855]: [2025/12/27 13:20:48.446253, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 27 13:20:48 music systemd[1]: Started Samba NMB Daemon. Dec 27 13:20:48 music nmbd[855]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 27 13:20:48 music systemd[1]: Starting Samba Winbind Daemon... Dec 27 13:20:48 music winbindd[1055]: [2025/12/27 13:20:48.570673, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 27 13:20:48 music winbindd[1055]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 27 13:20:48 music systemd[1]: Started Samba Winbind Daemon. Dec 27 13:20:48 music winbindd[1055]: [2025/12/27 13:20:48.585421, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 27 13:20:48 music winbindd[1055]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 27 13:20:48 music systemd[1]: Starting Samba SMB Daemon... Dec 27 13:20:48 music smbd[1060]: [2025/12/27 13:20:48.752009, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 27 13:20:48 music systemd[1]: Started Samba SMB Daemon. Dec 27 13:20:48 music smbd[1060]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "network"... Dec 27 13:20:48 music volumio[1014]: info: Refreshing Cached IP Addresses Dec 27 13:20:48 music sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 13:20:48 music sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:48 music sudo[1069]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:48 music sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 13:20:48 music sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:48 music sudo[1071]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "services"... Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "alsa_controller"... Dec 27 13:20:48 music sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 13:20:48 music sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:48 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "wizard"... Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "networkfs"... Dec 27 13:20:48 music volumio[1014]: info: Starting Udev Watcher for removable devices Dec 27 13:20:48 music sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Les2Cascades,password=Windsurfing301?,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.16/Music/Music library /mnt/NAS/VolumioMusic Dec 27 13:20:48 music sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:48 music volumio[1014]: info: Ignoring mount for partition: boot Dec 27 13:20:48 music volumio[1014]: info: Ignoring mount for partition: volumio Dec 27 13:20:48 music volumio[1014]: info: Ignoring mount for partition: volumio_data Dec 27 13:20:48 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "volumio_command_line_client"... Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "upnp"... Dec 27 13:20:48 music volumio[1014]: info: [1766838048883] Starting Upmpd Daemon Dec 27 13:20:48 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "my_music"... Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "mpd"... Dec 27 13:20:48 music sudo[1095]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:48 music kernel: Key type cifs.spnego registered Dec 27 13:20:48 music kernel: Key type cifs.idmap registered Dec 27 13:20:48 music 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. Dec 27 13:20:48 music kernel: CIFS: Attempting to mount //192.168.1.16/Music/Music library Dec 27 13:20:48 music kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 27 13:20:48 music kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 27 13:20:48 music volumio[1014]: info: Loading plugin "upnp_browser"... Dec 27 13:20:49 music volumio[1014]: info: Starting UPNP Browser Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "alarm-clock"... Dec 27 13:20:49 music ntpd[908]: Listen normally on 3 wlan0 192.168.211.1:123 Dec 27 13:20:49 music ntpd[908]: new interface(s) found: waking up resolver Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "airplay_emulation"... Dec 27 13:20:49 music volumio[1014]: info: Starting Shairport Sync Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "last_100"... Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "webradio"... Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "i2s_dacs"... Dec 27 13:20:49 music volumio[1014]: info: I2S DAC not set, start Auto-detection Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "volumiodiscovery"... Dec 27 13:20:49 music volumio[1014]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 13:20:49 music node[1014]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 13:20:49 music node[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 13:20:49 music volumio[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 13:20:49 music volumio[1014]: *** WARNING *** For more information see Dec 27 13:20:49 music volumio[1014]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 13:20:49 music volumio[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 13:20:49 music volumio[1014]: *** WARNING *** For more information see Dec 27 13:20:49 music node[1014]: *** WARNING *** For more information see Dec 27 13:20:49 music node[1014]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 13:20:49 music node[1014]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 13:20:49 music node[1014]: *** WARNING *** For more information see Dec 27 13:20:49 music volumio[1014]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 13:20:49 music volumio[1014]: info: Discovery: Started advertising with name: music Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "soundcloud"... Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "outputs"... Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "albumart"... Dec 27 13:20:49 music volumio[1014]: info: Plugin example_plugin is not enabled Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "inputs"... Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "updater_comm"... Dec 27 13:20:49 music volumio[1014]: info: Plugin mpdemulation is not enabled Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "rest_api"... Dec 27 13:20:49 music volumio[1014]: Forking 3 albumart workers Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "websocket"... Dec 27 13:20:49 music volumio[1014]: info: Starting Socket.io Server version 2.3.0 Dec 27 13:20:49 music volumio[1014]: info: Loading plugin "fusiondsp"... Dec 27 13:20:49 music volumio[1014]: info: Applying required configuration parameters for plugin fusiondsp Dec 27 13:20:49 music volumio[1014]: Starting albumart workers Dec 27 13:20:49 music volumio[1014]: Starting albumart workers Dec 27 13:20:49 music volumio[1014]: Starting albumart workers Dec 27 13:20:49 music volumio[1014]: info: Loading i18n strings for locale en Dec 27 13:20:49 music volumio[1014]: Updating browse sources language Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::initPlayerControls Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 13:20:49 music volumio[1014]: Express server listening on port 3000 Dec 27 13:20:49 music volumio[1014]: [Metrics] WebUI: 2s 100.68ms Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::resetVolumioState Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::getcurrentVolume Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:49 music volumio[1014]: info: Volumio Network Manager: Network status updated: 0 Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::pushState Dec 27 13:20:49 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::volumioPushState Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::updateTrackBlock Dec 27 13:20:49 music volumio[1014]: info: CorePlayQueue::getTrackBlock Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 13:20:49 music volumio[1014]: info: Cannot mount NAS VolumioMusic at system boot, trial number 1 ,retrying in 5 seconds Dec 27 13:20:49 music volumio-remote-updater[656]: [2025-12-27 13:20:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766838047 101 Dec 27 13:20:49 music volumio[1014]: 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: 1 Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::pushState Dec 27 13:20:49 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::volumioPushState Dec 27 13:20:49 music volumio[1014]: info: Reloading queue from file Dec 27 13:20:49 music volumio[1014]: info: Setting Device type: Raspberry PI Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::setRepeat true single undefined Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::pushState Dec 27 13:20:49 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::volumioPushState Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::setRandom null Dec 27 13:20:49 music volumio[1014]: info: CoreStateMachine::pushState Dec 27 13:20:49 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:20:49 music volumio[1014]: info: CoreCommandRouter::volumioPushState Dec 27 13:20:49 music volumio[1014]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 27 13:20:49 music volumio[1014]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Dec 27 13:20:49 music volumio[1014]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 27 13:20:49 music volumio[1014]: info: Completed loading Core Plugins Dec 27 13:20:49 music volumio[1014]: info: Preparing to generate the ALSA configuration file Dec 27 13:20:49 music volumio[1014]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 13:20:49 music volumio[1014]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 27 13:20:49 music volumio[1014]: info: Reading ALSA contributions from plugins. Dec 27 13:20:49 music volumio[1014]: info: Asound.conf file written Dec 27 13:20:49 music sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 27 13:20:49 music sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:49 music sudo[1187]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:49 music volumio[1014]: info: Output device has changed, restarting MPD Dec 27 13:20:49 music sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 13:20:49 music sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music volumio[1014]: info: Output device has changed, restarting Shairport Sync Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music sudo[1192]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 13:20:50 music sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:20:50 music volumio[1014]: info: ___________ START PLUGINS ___________ Dec 27 13:20:50 music volumio[1014]: info: ControllerMpd::onStart: Initializing MPD Dec 27 13:20:50 music volumio[1014]: info: Creating MPD Configuration file Dec 27 13:20:50 music sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 13:20:50 music volumio[1014]: info: [1766838050025] CoreMusicLibrary::Adding element Media Servers Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:20:50 music volumio[1014]: info: UPNP Browser: Client initialized successfully Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music sudo[1202]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 13:20:50 music sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music systemd[1]: Listening on mpd.socket. Dec 27 13:20:50 music systemd[1]: Starting Music Player Daemon... Dec 27 13:20:50 music systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 27 13:20:50 music systemd[1]: mpd.service: Succeeded. Dec 27 13:20:50 music systemd[1]: Stopped Music Player Daemon. Dec 27 13:20:50 music volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:20:50 music systemd[1]: Starting Music Player Daemon... Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 13:20:50 music volumio[1014]: info: [1766838050060] CoreMusicLibrary::Adding element Last_100 Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 13:20:50 music volumio[1014]: info: [1766838050061] CoreMusicLibrary::Adding element Webradio Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 13:20:50 music volumio[1014]: info: Initializing BBC Radios Dec 27 13:20:50 music sudo[1211]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 13:20:50 music sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music sudo[1211]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 27 13:20:50 music sudo[1211]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 13:20:50 music volumio[1014]: info: [1766838050085] CoreMusicLibrary::Adding element SoundCloud Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:20:50 music volumio[1014]: Cannot find translation for source SoundCloud Dec 27 13:20:50 music volumio[1014]: info: Loading i18n strings for locale en Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: FusionDsp - mixtype--------------------- Software Dec 27 13:20:50 music volumio[1014]: info: Volumio Calling Home Dec 27 13:20:50 music volumio[1014]: info: Preparing to generate the ALSA configuration file Dec 27 13:20:50 music volumio[1014]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 13:20:50 music volumio[1014]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 27 13:20:50 music volumio[1014]: info: Reading ALSA contributions from plugins. Dec 27 13:20:50 music volumio[1014]: info: MPD Permissions set Dec 27 13:20:50 music volumio[1014]: info: MPD Permissions set Dec 27 13:20:50 music volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:20:50 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:20:50 music volumio[1014]: info: Starting Shairport Sync Dec 27 13:20:50 music volumio[1014]: info: Starting Shairport Sync Dec 27 13:20:50 music sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:20:50 music sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music volumio[1014]: info: Starting Shairport Sync Dec 27 13:20:50 music sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:20:50 music sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 13:20:50 music sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:20:50 music sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music systemd[1]: shairport-sync.service: Succeeded. Dec 27 13:20:50 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:50 music volumio[1014]: info: Asound.conf file unchanged, so no further update is needed Dec 27 13:20:50 music volumio[1014]: info: Output device has changed, restarting MPD Dec 27 13:20:50 music volumio[1014]: info: Output device has changed, restarting Shairport Sync Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 13:20:50 music sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music sudo[1249]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 13:20:50 music sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:50 music sudo[1239]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music sudo[1241]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music volumio[1014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:20:50 music volumio[1014]: info: MPD Permissions set Dec 27 13:20:50 music volumio[1014]: info: Shairport-Sync Started Dec 27 13:20:50 music volumio[1014]: Error adding Membership: Error: addMembership EINVAL Dec 27 13:20:50 music volumio[1014]: info: Shairport-Sync Started Dec 27 13:20:50 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 13:20:50 music systemd[1]: shairport-sync.service: Succeeded. Dec 27 13:20:50 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:50 music volumio[1014]: info: Discovery: adding e8ca7669-2945-4dac-8ff1-eb17fe3d73c5 Dec 27 13:20:50 music volumio[1014]: info: Discovery: Found device music Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:20:50 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:20:50 music systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 27 13:20:50 music systemd[1]: mpd.service: Succeeded. Dec 27 13:20:50 music systemd[1]: Stopped Music Player Daemon. Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music systemd[1]: Starting Music Player Daemon... Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:50 music sudo[1245]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:50 music volumio[1014]: info: Shairport-Sync Started Dec 27 13:20:50 music volumio[1014]: info: Starting Shairport Sync Dec 27 13:20:50 music sudo[1259]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 13:20:50 music sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music sudo[1259]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 27 13:20:50 music sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:20:50 music sudo[1259]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:50 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 13:20:50 music systemd[1]: shairport-sync.service: Succeeded. Dec 27 13:20:50 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:50 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 13:20:50 music sudo[1267]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:50 music volumio[1014]: info: Shairport-Sync Started Dec 27 13:20:50 music mpd[1271]: Dec 27 13:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 13:20:51 music systemd[1]: Started Music Player Daemon. Dec 27 13:20:51 music sudo[1194]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:51 music sudo[1204]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:51 music sudo[1251]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:51 music volumio[1014]: info: Completed starting Core Plugins Dec 27 13:20:51 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:51 music volumio[1014]: info: ----- MyVolumio plugins startup ---- Dec 27 13:20:51 music volumio[1014]: info: ------------------------------------------- Dec 27 13:20:51 music volumio[1014]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 13:20:51 music volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Dec 27 13:20:51 music volumio[1014]: assert.ok(self.idling) Dec 27 13:20:51 music volumio[1014]: error: The expression evaluated to a falsy value: Dec 27 13:20:51 music volumio[1014]: assert.ok(self.idling) Dec 27 13:20:51 music volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Dec 27 13:20:51 music volumio[1014]: assert.ok(self.idling) Dec 27 13:20:51 music volumio[1014]: error: The expression evaluated to a falsy value: Dec 27 13:20:51 music volumio[1014]: assert.ok(self.idling) Dec 27 13:20:51 music volumio[1014]: info: MPD running with PID1271 Dec 27 13:20:51 music volumio[1014]: ,establishing connection Dec 27 13:20:51 music volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Dec 27 13:20:51 music volumio[1014]: assert.ok(self.idling) Dec 27 13:20:51 music volumio[1014]: error: The expression evaluated to a falsy value: Dec 27 13:20:51 music volumio[1014]: assert.ok(self.idling) Dec 27 13:20:51 music volumio[1014]: error: updateQueue error: null Dec 27 13:20:52 music volumio[1014]: info: camilladsp service started and running in background, instance 1 Dec 27 13:20:52 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:20:52 music volumio[1014]: cannot open device 'volumioHw': Unknown error 524 Dec 27 13:20:52 music volumio[1014]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 27 13:20:52 music volumio[1014]: cannot open device 'volumioHw': Unknown error 524 Dec 27 13:20:52 music volumio[1014]: info: FusionDsp loaded Dec 27 13:20:52 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:20:52 music sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 27 13:20:52 music sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:52 music volumio[1014]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 27 13:20:52 music volumio[1014]: info: Adding Signal Path Element [object Object] Dec 27 13:20:52 music volumio[1014]: info: Adding fusiondspeq DSP Signal Path Element Dec 27 13:20:52 music volumio[1014]: info: FusionDsp - ---- installed callbackRead Dec 27 13:20:52 music volumio[1014]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 13:20:52 music volumio[1014]: info: FusionDsp - Nulleq applied Dec 27 13:20:52 music volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 27 13:20:52 music volumio[1014]: error: FusionDsp - WebSocket error: [object Object] Dec 27 13:20:52 music systemd[1]: Started FusionDsp Daemon. Dec 27 13:20:52 music sudo[1289]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:52 music sudo[1081]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:52 music volumio-time-update[659]: volumio-time-update-util: Fetching time from Volumio... Dec 27 13:20:52 music volumio-time-update[659]: volumio-time-update-util: Date not found in response Dec 27 13:20:52 music volumio-time-update[659]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 13:20:53 music sh[621]: eth0: probing for an IPv4LL address Dec 27 13:20:53 music dhcpcd[679]: eth0: probing for an IPv4LL address Dec 27 13:20:54 music sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 13:20:54 music sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:54 music sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 13:20:54 music sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:54 music sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 13:20:54 music sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:54 music systemd[1]: Started MPD Monitor Service. Dec 27 13:20:54 music sudo[2228]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:54 music volumio[1014]: info: Successfully started MPD Monitor Dec 27 13:20:54 music mpd_monitor.sh[2236]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 13:20:54 music systemd[1]: Stopping MPD Monitor Service... Dec 27 13:20:54 music systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 27 13:20:54 music systemd[1]: mpd_monitor.service: Succeeded. Dec 27 13:20:54 music systemd[1]: Stopped MPD Monitor Service. Dec 27 13:20:54 music systemd[1]: Started MPD Monitor Service. Dec 27 13:20:54 music sudo[2234]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:54 music sudo[2230]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:54 music volumio[1014]: info: Successfully started MPD Monitor Dec 27 13:20:54 music mpd_monitor.sh[2260]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 13:20:54 music volumio[1014]: info: Successfully started MPD Monitor Dec 27 13:20:54 music sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Les2Cascades,password=Windsurfing301?,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.16/Music/Music library /mnt/NAS/VolumioMusic Dec 27 13:20:54 music sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:54 music sudo[2559]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:54 music kernel: CIFS: Attempting to mount //192.168.1.16/Music/Music library Dec 27 13:20:54 music kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 27 13:20:54 music kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 27 13:20:54 music volumio[1014]: info: Cannot mount NAS VolumioMusic at system boot, trial number 2 ,retrying in 5 seconds Dec 27 13:20:56 music volumio[1014]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 13:20:57 music sh[621]: eth0: using IPv4LL address 169.254.15.236 Dec 27 13:20:57 music sh[621]: eth0: adding route to 169.254.0.0/16 Dec 27 13:20:57 music sh[621]: eth0: adding default route Dec 27 13:20:57 music dhcpcd[679]: eth0: using IPv4LL address 169.254.15.236 Dec 27 13:20:57 music dhcpcd[679]: eth0: adding route to 169.254.0.0/16 Dec 27 13:20:57 music dhcpcd[679]: eth0: adding default route Dec 27 13:20:57 music avahi-daemon[657]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.15.236. Dec 27 13:20:57 music avahi-daemon[657]: New relevant interface eth0.IPv4 for mDNS. Dec 27 13:20:57 music avahi-daemon[657]: Registering new address record for 169.254.15.236 on eth0.IPv4. Dec 27 13:20:57 music sh[621]: forked to background, child pid 3379 Dec 27 13:20:57 music dhcpcd[679]: forked to background, child pid 3379 Dec 27 13:20:57 music ifplugd(eth0)[927]: client: ifup: interface eth0 already configured Dec 27 13:20:57 music sh[621]: eth0=eth0 Dec 27 13:20:57 music ifplugd(eth0)[927]: Program executed successfully. Dec 27 13:20:57 music volumio-time-update[659]: volumio-time-update-util: Fetching time from Volumio... Dec 27 13:20:59 music ntpd[908]: Listen normally on 4 eth0 169.254.15.236:123 Dec 27 13:20:59 music ntpd[908]: new interface(s) found: waking up resolver Dec 27 13:20:59 music sudo[3441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 13:20:59 music sudo[3441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:59 music sudo[3441]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:59 music sudo[3444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 13:20:59 music sudo[3444]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:20:59 music sudo[3444]: pam_unix(sudo:session): session closed for user root Dec 27 13:20:59 music volumio[1014]: info: Volumio Network Manager: Network status updated: 1 Dec 27 13:21:00 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:21:00 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:21:00 music volumio[1014]: info: Discovery: Getting this device information Dec 27 13:21:00 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:21:00 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:21:00 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:21:00 music volumio[1014]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.886&uuid=ac6295e76d074dc5c3f66faf4aa368dd" http://updates.volumio.org/downloader-v1/track-device Dec 27 13:21:00 music volumio[1014]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 27 13:21:00 music volumio[1014]: Dload Upload Total Spent Left Speed Dec 27 13:21:00 music volumio[1014]: [132B blob data] Dec 27 13:21:00 music volumio[1014]: retrying in 5 seconds, trial 0 Dec 27 13:21:00 music volumio[1014]: info: Volumio Calling Home Dec 27 13:21:00 music sudo[3454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Les2Cascades,password=Windsurfing301?,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.16/Music/Music library /mnt/NAS/VolumioMusic Dec 27 13:21:00 music sudo[3454]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:21:00 music kernel: CIFS: Attempting to mount //192.168.1.16/Music/Music library Dec 27 13:21:02 music dhcpcd[3379]: eth0: offered 192.168.1.160 from 192.168.1.254 Dec 27 13:21:02 music dhcpcd[3379]: eth0: probing address 192.168.1.160/24 Dec 27 13:21:02 music volumio[1014]: verbose: New Socket.io Connection to 169.254.15.236:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 27 13:21:03 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 13:21:03 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 13:21:03 music volumio[1014]: Cannot compose Albumart path Dec 27 13:21:04 music sudo[3476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 27 13:21:04 music sudo[3476]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:21:04 music volumio[1014]: info: Discovery: Getting this device information Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:21:04 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:21:04 music volumio[1014]: verbose: New Socket.io Connection to 169.254.15.236:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 27 13:21:04 music systemd[1]: Started UPnP Renderer front-end to MPD. Dec 27 13:21:04 music sudo[3476]: pam_unix(sudo:session): session closed for user root Dec 27 13:21:04 music volumio[1014]: info: Upmpdcli Daemon Started Dec 27 13:21:04 music volumio[1014]: verbose: New Socket.io Connection to 169.254.15.236:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:21:04 music volumio[1014]: info: Discovery: Getting this device information Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:21:04 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 13:21:04 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 13:21:04 music volumio[3478]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 27 13:21:07 music dhcpcd[3379]: eth0: leased 192.168.1.160 for 86400 seconds Dec 27 13:21:07 music dhcpcd[3379]: eth0: adding route to 192.168.1.0/24 Dec 27 13:21:07 music dhcpcd[3379]: eth0: changing default route via 192.168.1.254 Dec 27 13:21:07 music avahi-daemon[657]: Registering new address record for 192.168.1.160 on eth0.IPv4. Dec 27 13:21:07 music dnsmasq[1005]: reading /etc/resolv.conf Dec 27 13:21:07 music dnsmasq[1005]: using nameserver 192.168.1.254#53 Dec 27 13:21:07 music dnsmasq[1005]: using nameserver 208.67.222.222#53 Dec 27 13:21:07 music dnsmasq[1005]: using nameserver 208.67.220.220#53 Dec 27 13:21:07 music dhcpcd[3379]: eth0: deleting route to 169.254.0.0/16 Dec 27 13:21:07 music avahi-daemon[657]: Withdrawing address record for 169.254.15.236 on eth0. Dec 27 13:21:07 music avahi-daemon[657]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.15.236. Dec 27 13:21:07 music avahi-daemon[657]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.160. Dec 27 13:21:07 music dhcpcd[3379]: eth0: pid 3379 deleted default route via 192.168.1.254 Dec 27 13:21:07 music volumio-time-update[659]: volumio-time-update-util: Date not found in response Dec 27 13:21:07 music volumio-time-update[659]: volumio-time-update-util: Retrying in 5 seconds... Dec 27 13:21:08 music volumio[1014]: verbose: New Socket.io Connection to 192.168.1.160:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 27 13:21:08 music volumio[1014]: verbose: New Socket.io Connection to 192.168.1.160:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:21:08 music volumio[1014]: info: Discovery: Getting this device information Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:21:08 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:21:08 music volumio[1014]: verbose: New Socket.io Connection to 192.168.1.160:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:21:08 music volumio[1014]: info: Discovery: Getting this device information Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:21:08 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:21:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:21:09 music volumio[3478]: ..........................................................................................++++ Dec 27 13:21:09 music ntpd[908]: Listen normally on 5 eth0 192.168.1.160:123 Dec 27 13:21:09 music ntpd[908]: Deleting interface #4 eth0, 169.254.15.236#123, interface stats: received=0, sent=0, dropped=0, active_time=10 secs Dec 27 13:21:09 music ntpd[908]: new interface(s) found: waking up resolver Dec 27 13:21:09 music volumio[3478]: ....++++ Dec 27 13:21:09 music volumio[3478]: e is 65537 (0x010001) Dec 27 13:21:09 music volumio[3478]: writing RSA key Dec 27 13:21:09 music volumio[1014]: info: Discovery: this is already registered, e8ca7669-2945-4dac-8ff1-eb17fe3d73c5 Dec 27 13:21:09 music volumio[1014]: info: Discovery: Found device music Dec 27 13:21:09 music volumio[1014]: info: CoreCommandRouter::volumioGetState Dec 27 13:21:09 music volumio[1014]: info: CorePlayQueue::getTrack 0 Dec 27 13:21:09 music volumio[1014]: info: Discovery: adding 8956131a-6fed-4e25-823c-8b17b10dde8b Dec 27 13:21:09 music volumio[1014]: info: Discovery: Found device Cuisine Dec 27 13:21:09 music volumio[1014]: info: Discovery: Connecting to remote: 192.168.1.101 Dec 27 13:21:09 music volumio[1014]: info: Discovery: adding 5355d33e-6b8a-405d-975c-1b10e6cb1545 Dec 27 13:21:09 music volumio[1014]: info: Discovery: Found device Salon Dec 27 13:21:09 music volumio[1014]: info: Discovery: Connecting to remote: 192.168.1.171 Dec 27 13:21:09 music volumio[1014]: info: Discovery: Connected to remote: 192.168.1.171 Dec 27 13:21:09 music volumio[1014]: info: Discovery: Connected to remote: 192.168.1.101 Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 27 13:21:10 music volumio[1014]: info: Adding plugin bluetooth to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: Adding plugin multiroom to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: Adding plugin metavolumio to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: Adding plugin cd_controller to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 27 13:21:10 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:21:10 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:21:10 music volumio[1014]: info: Starting MyVolumio Remote Streaming Endpoints Dec 27 13:21:10 music volumio[1014]: info: MyVolumio login type: Token Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 27 13:21:10 music volumio[1014]: info: Starting Streaming Service Transparent Proxy Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 27 13:21:10 music volumio[1014]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 27 13:21:10 music volumio[1014]: info: Streaming services startup Dec 27 13:21:10 music volumio[1014]: info: Starting Streaming Daemon Dec 27 13:21:11 music sudo[5239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 13:21:11 music sudo[5239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:21:11 music sudo[5239]: pam_unix(sudo:session): session closed for user root Dec 27 13:21:11 music volumio[1014]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 27 13:21:11 music kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 27 13:21:11 music kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Dec 27 13:21:11 music sudo[3454]: pam_unix(sudo:session): session closed for user root Dec 27 13:21:11 music volumio[1014]: error: Cannot start Volumio Streaming Daemon Dec 27 13:21:11 music volumio[1014]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 27 13:21:11 music volumio[1014]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 27 13:21:11 music volumio[1014]: info: Cannot mount NAS VolumioMusic at system boot, trial number 3 ,retrying in 5 seconds Dec 27 13:21:11 music volumio[1014]: error: MyVolumio Custom Token format not valid, refreshing it Dec 27 13:21:11 music volumio[1014]: STREAMING PROXY: Starting server on port 3245 Dec 27 13:21:11 music volumio[1014]: Node JS runtime: 14 Dec 27 13:21:11 music volumio[1014]: info: MyVolumio login type: Token Dec 27 13:21:12 music systemd[1]: systemd-fsckd.service: Succeeded. Dec 27 13:21:12 music volumio[1014]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 27 13:21:12 music volumio[1014]: verbose: New Socket.io Connection to 192.168.1.160:3000 from 192.168.1.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 27 13:21:12 music volumio[1014]: info: MyVolumio token set successfully Dec 27 13:21:12 music volumio[1014]: info: MYVOLUMIO: Adding device Dec 27 13:21:12 music volumio[1014]: info: MYVOLUMIO: Evaluating Server Dec 27 13:21:12 music volumio-time-update[659]: volumio-time-update-util: Fetching time from Volumio... Dec 27 13:21:12 music volumio-time-update[659]: volumio-time-update-util: Setting system time to: 2025-12-27 13:21:12 Dec 27 13:21:12 music sudo[5737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-27 13:21:12 Dec 27 13:21:12 music sudo[5737]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 13:21:12 music dbus-daemon[666]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=5739 comm="timedatectl set-time 2025-12-27 13:21:12 ") Dec 27 13:21:12 music systemd[1]: Starting Time & Date Service... Dec 27 13:21:13 music dbus-daemon[666]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 27 13:21:13 music systemd[1]: Started Time & Date Service. Dec 27 13:21:12 music systemd-timedated[5741]: Changed local time to Sat Dec 27 13:21:12 2025 Dec 27 13:21:12 music sudo[5737]: pam_unix(sudo:session): session closed for user root Dec 27 13:21:12 music volumio-time-update[659]: volumio-time-update-util: System time updated successfully. Dec 27 13:21:12 music systemd[1]: Started Volumio Time Update Utility. Dec 27 13:21:12 music systemd[1]: Reached target Multi-User System. Dec 27 13:21:12 music systemd[1]: Reached target Graphical Interface. Dec 27 13:21:12 music systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 27 13:21:12 music systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 27 13:21:12 music systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 27 13:21:12 music systemd[1]: Startup finished in 11.045s (kernel) + 31.870s (userspace) = 42.915s. Dec 27 13:21:12 music volumio[1014]: info: MyVolumio Plan changed: premium Dec 27 13:21:12 music volumio[1014]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 27 13:21:12 music volumio[1014]: info: Removing browser output: myVolumio user plan is not superstar Dec 27 13:21:12 music volumio[1014]: info: Removing audio output: Dec 27 13:21:12 music volumio[1014]: info: MYVOLUMIO: Adding device Dec 27 13:21:12 music volumio[1014]: info: MYVOLUMIO: Evaluating Server Dec 27 13:21:12 music volumio[1014]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Dec 27 13:21:12 music volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 13:21:12 music volumio[1014]: Error: Unable to resolve or reject the same promise twice Dec 27 13:21:12 music volumio[1014]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 27 13:21:12 music volumio[1014]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Dec 27 13:21:12 music volumio[1014]: at Socket.emit (events.js:315:20) Dec 27 13:21:12 music volumio[1014]: at addChunk (internal/streams/readable.js:309:12) Dec 27 13:21:12 music volumio[1014]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 27 13:21:12 music volumio[1014]: at Socket.Readable.push (internal/streams/readable.js:223:10) Dec 27 13:21:12 music volumio[1014]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 27 13:21:12 music volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 13:21:12 music sudo[5760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 13:20 Dec 27 13:21:12 music sudo[5760]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"