Dec 01 21:17:12 studio kernel: Booting Linux on physical CPU 0x0 Dec 01 21:17:12 studio kernel: Linux version 6.12.47-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1904 SMP Mon Sep 15 13:08:10 BST 2025 Dec 01 21:17:12 studio kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Dec 01 21:17:12 studio kernel: CPU: div instructions available: patching division code Dec 01 21:17:12 studio kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Dec 01 21:17:12 studio kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2 Dec 01 21:17:12 studio kernel: random: crng init done Dec 01 21:17:12 studio kernel: Memory policy: Data cache writealloc Dec 01 21:17:12 studio kernel: Reserved memory: created CMA memory pool at 0x0000000028c00000, size 64 MiB Dec 01 21:17:12 studio kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 01 21:17:12 studio kernel: OF: reserved mem: 0x0000000028c00000..0x000000002cbfffff (65536 KiB) map reusable linux,cma Dec 01 21:17:12 studio kernel: Zone ranges: Dec 01 21:17:12 studio kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Dec 01 21:17:12 studio kernel: Normal empty Dec 01 21:17:12 studio kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Dec 01 21:17:12 studio kernel: Movable zone start for each node Dec 01 21:17:12 studio kernel: Early memory node ranges Dec 01 21:17:12 studio kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Dec 01 21:17:12 studio kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Dec 01 21:17:12 studio kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Dec 01 21:17:12 studio kernel: OF: reserved mem: 0x000000003ef73360..0x000000003ef7374f (0 KiB) nomap non-reusable nvram@0 Dec 01 21:17:12 studio kernel: OF: reserved mem: 0x000000003ef73120..0x000000003ef7331f (0 KiB) nomap non-reusable nvram@1 Dec 01 21:17:12 studio kernel: percpu: Embedded 19 pages/cpu s45772 r8192 d23860 u77824 Dec 01 21:17:12 studio kernel: pcpu-alloc: s45772 r8192 d23860 u77824 alloc=19*4096 Dec 01 21:17:12 studio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 01 21:17:12 studio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:87:25:8D vc_mem.mem_base=0x3ec00000 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=ttyS0,115200 console=tty1 imgpart=UUID=2fcd4aa9-19da-4188-a556-3009b8a5e64a imgfile=/volumio_current.sqsh bootpart=UUID=DFF5-D140 datapart=UUID=08d6ed05-4edb-44af-8ed0-2ea18b54c960 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 01 21:17:12 studio kernel: cgroup: Disabling memory control group subsystem Dec 01 21:17:12 studio kernel: Unknown kernel command line parameters "splash nodebug numa_policy=interleave numa=fake=2 imgpart=UUID=2fcd4aa9-19da-4188-a556-3009b8a5e64a imgfile=/volumio_current.sqsh bootpart=UUID=DFF5-D140 datapart=UUID=08d6ed05-4edb-44af-8ed0-2ea18b54c960 uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Dec 01 21:17:12 studio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Dec 01 21:17:12 studio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 01 21:17:12 studio kernel: Built 1 zonelists, mobility grouping on. Total pages: 999424 Dec 01 21:17:12 studio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 01 21:17:12 studio kernel: software IO TLB: area num 4. Dec 01 21:17:12 studio kernel: software IO TLB: mapped [mem 0x0000000022890000-0x0000000026890000] (64MB) Dec 01 21:17:12 studio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 01 21:17:12 studio kernel: ftrace: allocating 40021 entries in 118 pages Dec 01 21:17:12 studio kernel: ftrace: allocated 118 pages with 5 groups Dec 01 21:17:12 studio kernel: rcu: Hierarchical RCU implementation. Dec 01 21:17:12 studio kernel: Rude variant of Tasks RCU enabled. Dec 01 21:17:12 studio kernel: Tracing variant of Tasks RCU enabled. Dec 01 21:17:12 studio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Dec 01 21:17:12 studio kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 01 21:17:12 studio kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 01 21:17:12 studio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Dec 01 21:17:12 studio kernel: GIC: Using split EOI/Deactivate mode Dec 01 21:17:12 studio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 01 21:17:12 studio kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 01 21:17:12 studio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 01 21:17:12 studio kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 01 21:17:12 studio kernel: Switching to timer-based delay loop, resolution 18ns Dec 01 21:17:12 studio kernel: Console: colour dummy device 80x30 Dec 01 21:17:12 studio kernel: printk: legacy console [tty1] enabled Dec 01 21:17:12 studio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Dec 01 21:17:12 studio kernel: CPU: Testing write buffer coherency: ok Dec 01 21:17:12 studio kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Dec 01 21:17:12 studio kernel: pid_max: default: 32768 minimum: 301 Dec 01 21:17:12 studio kernel: LSM: initializing lsm=capability Dec 01 21:17:12 studio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 01 21:17:12 studio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 01 21:17:12 studio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Dec 01 21:17:12 studio kernel: Setting up static identity map for 0x200000 - 0x20003c Dec 01 21:17:12 studio kernel: rcu: Hierarchical SRCU implementation. Dec 01 21:17:12 studio kernel: rcu: Max phase no-delay instances is 1000. Dec 01 21:17:12 studio kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Dec 01 21:17:12 studio kernel: smp: Bringing up secondary CPUs ... Dec 01 21:17:12 studio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Dec 01 21:17:12 studio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Dec 01 21:17:12 studio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Dec 01 21:17:12 studio kernel: smp: Brought up 1 node, 4 CPUs Dec 01 21:17:12 studio kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Dec 01 21:17:12 studio kernel: CPU: All CPU(s) started in HYP mode. Dec 01 21:17:12 studio kernel: CPU: Virtualization extensions available. Dec 01 21:17:12 studio kernel: Memory: 3767784K/3997696K available (12288K kernel code, 1648K rwdata, 3848K rodata, 2048K init, 402K bss, 160952K reserved, 65536K cma-reserved, 3211264K highmem) Dec 01 21:17:12 studio kernel: devtmpfs: initialized Dec 01 21:17:12 studio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Dec 01 21:17:12 studio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Dec 01 21:17:12 studio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 01 21:17:12 studio kernel: pinctrl core: initialized pinctrl subsystem Dec 01 21:17:12 studio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 01 21:17:12 studio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Dec 01 21:17:12 studio kernel: audit: initializing netlink subsys (disabled) Dec 01 21:17:12 studio kernel: audit: type=2000 audit(0.000:1): state=initialized audit_enabled=0 res=1 Dec 01 21:17:12 studio kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 01 21:17:12 studio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Dec 01 21:17:12 studio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Dec 01 21:17:12 studio kernel: Serial: AMBA PL011 UART driver Dec 01 21:17:12 studio kernel: /soc/interrupt-controller@40041000: Fixed dependency cycle(s) with /soc/interrupt-controller@40041000 Dec 01 21:17:12 studio kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Dec 01 21:17:12 studio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2025-08-20T17:02:31, variant start Dec 01 21:17:12 studio kernel: raspberrypi-firmware soc:firmware: Firmware hash is cd866525580337c0aee4b25880e1f5f9f674fb24 Dec 01 21:17:12 studio kernel: /scb/pcie@7d500000: Fixed dependency cycle(s) with /scb/pcie@7d500000 Dec 01 21:17:12 studio kernel: /scb/pcie@7d500000: Fixed dependency cycle(s) with /scb/pcie@7d500000 Dec 01 21:17:12 studio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 01 21:17:12 studio kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 Dec 01 21:17:12 studio kernel: SCSI subsystem initialized Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver usbfs Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver hub Dec 01 21:17:12 studio kernel: usbcore: registered new device driver usb Dec 01 21:17:12 studio kernel: pps_core: LinuxPPS API ver. 1 registered Dec 01 21:17:12 studio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 01 21:17:12 studio kernel: PTP clock support registered Dec 01 21:17:12 studio kernel: vgaarb: loaded Dec 01 21:17:12 studio kernel: clocksource: Switched to clocksource arch_sys_counter Dec 01 21:17:12 studio kernel: VFS: Disk quotas dquot_6.6.0 Dec 01 21:17:12 studio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Dec 01 21:17:12 studio kernel: NET: Registered PF_INET protocol family Dec 01 21:17:12 studio kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Dec 01 21:17:12 studio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Dec 01 21:17:12 studio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 01 21:17:12 studio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Dec 01 21:17:12 studio kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Dec 01 21:17:12 studio kernel: TCP: Hash tables configured (established 8192 bind 8192) Dec 01 21:17:12 studio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 01 21:17:12 studio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 01 21:17:12 studio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 01 21:17:12 studio kernel: RPC: Registered named UNIX socket transport module. Dec 01 21:17:12 studio kernel: RPC: Registered udp transport module. Dec 01 21:17:12 studio kernel: RPC: Registered tcp transport module. Dec 01 21:17:12 studio kernel: RPC: Registered tcp-with-tls transport module. Dec 01 21:17:12 studio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 01 21:17:12 studio kernel: PCI: CLS 0 bytes, default 64 Dec 01 21:17:12 studio kernel: Initialise system trusted keyrings Dec 01 21:17:12 studio kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Dec 01 21:17:12 studio kernel: Trying to unpack rootfs image as initramfs... Dec 01 21:17:12 studio kernel: NFS: Registering the id_resolver key type Dec 01 21:17:12 studio kernel: Key type id_resolver registered Dec 01 21:17:12 studio kernel: Key type id_legacy registered Dec 01 21:17:12 studio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 01 21:17:12 studio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 01 21:17:12 studio kernel: Key type asymmetric registered Dec 01 21:17:12 studio kernel: Asymmetric key parser 'x509' registered Dec 01 21:17:12 studio kernel: bounce: pool size: 64 pages Dec 01 21:17:12 studio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 01 21:17:12 studio kernel: io scheduler mq-deadline registered Dec 01 21:17:12 studio kernel: io scheduler kyber registered Dec 01 21:17:12 studio kernel: io scheduler bfq registered Dec 01 21:17:12 studio kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Dec 01 21:17:12 studio kernel: Freeing initrd memory: 35756K Dec 01 21:17:12 studio kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Dec 01 21:17:12 studio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 01 21:17:12 studio kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 PCIe Root Port Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: PCI bridge to [bus 00] Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: bridge window [mem 0x80000000-0xbfffffff] Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 01 21:17:12 studio kernel: PCI: bus0: Fast back to back transfers disabled Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 01 21:17:12 studio kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Dec 01 21:17:12 studio kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Dec 01 21:17:12 studio kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: clkreq-mode set to default Dec 01 21:17:12 studio kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Dec 01 21:17:12 studio kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 PCIe Endpoint Dec 01 21:17:12 studio kernel: pci 0000:01:00.0: BAR 0 [mem 0x00000000-0x00000fff 64bit] Dec 01 21:17:12 studio kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Dec 01 21:17:12 studio kernel: PCI: bus1: Fast back to back transfers disabled Dec 01 21:17:12 studio kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]: assigned Dec 01 21:17:12 studio kernel: pci 0000:01:00.0: BAR 0 [mem 0x600000000-0x600000fff 64bit]: assigned Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Dec 01 21:17:12 studio kernel: pci_bus 0000:00: resource 4 [mem 0x600000000-0x63fffffff] Dec 01 21:17:12 studio kernel: pci_bus 0000:01: resource 1 [mem 0x600000000-0x6000fffff] Dec 01 21:17:12 studio kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 01 21:17:12 studio kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 01 21:17:12 studio kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Dec 01 21:17:12 studio kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 41 Dec 01 21:17:12 studio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Dec 01 21:17:12 studio kernel: Console: switching to colour frame buffer device 80x30 Dec 01 21:17:12 studio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Dec 01 21:17:12 studio kernel: iproc-rng200 fe104000.rng: hwrng registered Dec 01 21:17:12 studio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Dec 01 21:17:12 studio kernel: brd: module loaded Dec 01 21:17:12 studio kernel: loop: module loaded Dec 01 21:17:12 studio kernel: Loading iSCSI transport class v2.0-870. Dec 01 21:17:12 studio kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Dec 01 21:17:12 studio kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver lan78xx Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver smsc95xx Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890 Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Dec 01 21:17:12 studio kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Dec 01 21:17:12 studio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Dec 01 21:17:12 studio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 01 21:17:12 studio kernel: usb usb1: Product: xHCI Host Controller Dec 01 21:17:12 studio kernel: usb usb1: Manufacturer: Linux 6.12.47-v7l+ xhci-hcd Dec 01 21:17:12 studio kernel: usb usb1: SerialNumber: 0000:01:00.0 Dec 01 21:17:12 studio kernel: hub 1-0:1.0: USB hub found Dec 01 21:17:12 studio kernel: hub 1-0:1.0: 1 port detected Dec 01 21:17:12 studio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Dec 01 21:17:12 studio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 01 21:17:12 studio kernel: usb usb2: Product: xHCI Host Controller Dec 01 21:17:12 studio kernel: usb usb2: Manufacturer: Linux 6.12.47-v7l+ xhci-hcd Dec 01 21:17:12 studio kernel: usb usb2: SerialNumber: 0000:01:00.0 Dec 01 21:17:12 studio kernel: hub 2-0:1.0: USB hub found Dec 01 21:17:12 studio kernel: hub 2-0:1.0: 4 ports detected Dec 01 21:17:12 studio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 01 21:17:12 studio kernel: dwc_otg: FIQ enabled Dec 01 21:17:12 studio kernel: dwc_otg: NAK holdoff enabled Dec 01 21:17:12 studio kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 01 21:17:12 studio kernel: Module dwc_common_port init Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver uas Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver usb-storage Dec 01 21:17:12 studio kernel: mousedev: PS/2 mouse device common for all mice Dec 01 21:17:12 studio kernel: sdhci: Secure Digital Host Controller Interface driver Dec 01 21:17:12 studio kernel: sdhci: Copyright(c) Pierre Ossman Dec 01 21:17:12 studio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 01 21:17:12 studio kernel: hid: raw HID events driver (C) Jiri Kosina Dec 01 21:17:12 studio kernel: usbcore: registered new interface driver usbhid Dec 01 21:17:12 studio kernel: usbhid: USB HID core driver Dec 01 21:17:12 studio kernel: Initializing XFRM netlink socket Dec 01 21:17:12 studio kernel: NET: Registered PF_PACKET protocol family Dec 01 21:17:12 studio kernel: Key type dns_resolver registered Dec 01 21:17:12 studio kernel: Registering SWP/SWPB emulation handler Dec 01 21:17:12 studio kernel: registered taskstats version 1 Dec 01 21:17:12 studio kernel: Loading compiled-in X.509 certificates Dec 01 21:17:12 studio kernel: Key type .fscrypt registered Dec 01 21:17:12 studio kernel: Key type fscrypt-provisioning registered Dec 01 21:17:12 studio kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Dec 01 21:17:12 studio kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Dec 01 21:17:12 studio kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 50, base_baud = 0) is a PL011 rev3 Dec 01 21:17:12 studio kernel: serial serial0: tty port ttyAMA1 registered Dec 01 21:17:12 studio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 01 21:17:12 studio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 01 21:17:12 studio kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Dec 01 21:17:12 studio kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Dec 01 21:17:12 studio kernel: of_cfs_init Dec 01 21:17:12 studio kernel: of_cfs_init: OK Dec 01 21:17:12 studio kernel: clk: Disabling unused clocks Dec 01 21:17:12 studio kernel: PM: genpd: Disabling unused power domains Dec 01 21:17:12 studio kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Dec 01 21:17:12 studio kernel: Freeing unused kernel image (initmem) memory: 2048K Dec 01 21:17:12 studio kernel: Run /init as init process Dec 01 21:17:12 studio kernel: with arguments: Dec 01 21:17:12 studio kernel: /init Dec 01 21:17:12 studio kernel: splash Dec 01 21:17:12 studio kernel: nodebug Dec 01 21:17:12 studio kernel: with environment: Dec 01 21:17:12 studio kernel: HOME=/ Dec 01 21:17:12 studio kernel: TERM=linux Dec 01 21:17:12 studio kernel: numa_policy=interleave Dec 01 21:17:12 studio kernel: numa=fake=2 Dec 01 21:17:12 studio kernel: imgpart=UUID=2fcd4aa9-19da-4188-a556-3009b8a5e64a Dec 01 21:17:12 studio kernel: imgfile=/volumio_current.sqsh Dec 01 21:17:12 studio kernel: bootpart=UUID=DFF5-D140 Dec 01 21:17:12 studio kernel: datapart=UUID=08d6ed05-4edb-44af-8ed0-2ea18b54c960 Dec 01 21:17:12 studio kernel: uuidconfig=cmdline.txt Dec 01 21:17:12 studio kernel: pcie_aspm=off Dec 01 21:17:12 studio kernel: bootdelay=7 Dec 01 21:17:12 studio kernel: use_kmsg=no Dec 01 21:17:12 studio kernel: mmc1: new high speed SDIO card at address 0001 Dec 01 21:17:12 studio kernel: mmc0: new ultra high speed DDR50 SDXC card at address aaaa Dec 01 21:17:12 studio kernel: mmcblk0: mmc0:aaaa SC64G 59.5 GiB Dec 01 21:17:12 studio kernel: mmcblk0: p1 p2 p3 Dec 01 21:17:12 studio kernel: mmcblk0: mmc0:aaaa SC64G 59.5 GiB (quirks 0x0000c000) Dec 01 21:17:12 studio kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 01 21:17:12 studio kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Dec 01 21:17:12 studio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 01 21:17:12 studio kernel: usb 1-1: Product: USB2.0 Hub Dec 01 21:17:12 studio kernel: hub 1-1:1.0: USB hub found Dec 01 21:17:12 studio kernel: hub 1-1:1.0: 4 ports detected Dec 01 21:17:12 studio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 01 21:17:12 studio kernel: bcm2835-audio bcm2835-audio: card created with 4 channels Dec 01 21:17:12 studio kernel: bcm2835-audio bcm2835-audio: card created with 4 channels Dec 01 21:17:12 studio kernel: fuse: init (API version 7.41) Dec 01 21:17:12 studio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 01 21:17:12 studio kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340 Dec 01 21:17:12 studio kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r Dec 01 21:17:12 studio kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18 Dec 01 21:17:12 studio kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18 Dec 01 21:17:12 studio kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green Dec 01 21:17:12 studio kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v Dec 01 21:17:12 studio kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13 Dec 01 21:17:12 studio kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:12 studio kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d Dec 01 21:17:12 studio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 01 21:17:12 studio kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 01 21:17:12 studio kernel: EXT4-fs (mmcblk0p2): mounted filesystem 2fcd4aa9-19da-4188-a556-3009b8a5e64a r/w with ordered data mode. Quota mode: none. Dec 01 21:17:12 studio kernel: loop0: detected capacity change from 0 to 1420368 Dec 01 21:17:12 studio kernel: EXT4-fs (mmcblk0p3): 2 orphan inodes deleted Dec 01 21:17:12 studio kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 01 21:17:12 studio kernel: EXT4-fs (mmcblk0p3): mounted filesystem 08d6ed05-4edb-44af-8ed0-2ea18b54c960 r/w with ordered data mode. Quota mode: none. Dec 01 21:17:12 studio systemd[1]: System time before build time, advancing clock. Dec 01 21:17:12 studio kernel: NET: Registered PF_INET6 protocol family Dec 01 21:17:12 studio kernel: Segment Routing with IPv6 Dec 01 21:17:12 studio kernel: In-situ OAM (IOAM) with IPv6 Dec 01 21:17:12 studio systemd[1]: systemd 252.39-1~deb12u1+rpi1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Dec 01 21:17:12 studio systemd[1]: Detected architecture arm. Dec 01 21:17:12 studio systemd[1]: Hostname set to . Dec 01 21:17:12 studio systemd[1]: Queued start job for default target graphical.target. Dec 01 21:17:12 studio systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 01 21:17:12 studio systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 01 21:17:12 studio systemd[1]: Created slice user.slice - User and Session Slice. Dec 01 21:17:12 studio systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 01 21:17:12 studio systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 01 21:17:12 studio systemd[1]: Expecting device dev-disk-by\x2duuid-DFF5\x2dD140.device - /dev/disk/by-uuid/DFF5-D140... Dec 01 21:17:12 studio systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 01 21:17:12 studio systemd[1]: Reached target slices.target - Slice Units. Dec 01 21:17:12 studio systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 01 21:17:12 studio systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Dec 01 21:17:12 studio systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Dec 01 21:17:12 studio systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Dec 01 21:17:12 studio systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Dec 01 21:17:12 studio systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 01 21:17:12 studio systemd[1]: Listening on systemd-journald.socket - Journal Socket. Dec 01 21:17:12 studio systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 01 21:17:12 studio systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 01 21:17:12 studio systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 01 21:17:12 studio systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 01 21:17:12 studio systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 01 21:17:12 studio systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 01 21:17:12 studio systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Dec 01 21:17:12 studio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 01 21:17:12 studio systemd[1]: Reached target swap.target - Swaps. Dec 01 21:17:12 studio systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Dec 01 21:17:12 studio systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 01 21:17:12 studio systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 01 21:17:12 studio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 01 21:17:12 studio systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 01 21:17:12 studio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 01 21:17:12 studio systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 01 21:17:12 studio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 01 21:17:12 studio systemd[1]: Starting systemd-journald.service - Journal Service... Dec 01 21:17:12 studio systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 01 21:17:12 studio systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 01 21:17:12 studio systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 01 21:17:12 studio systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 01 21:17:12 studio systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 01 21:17:12 studio systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 01 21:17:12 studio kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 01 21:17:12 studio systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Dec 01 21:17:12 studio systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 01 21:17:12 studio systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 01 21:17:12 studio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 01 21:17:12 studio systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 01 21:17:12 studio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 01 21:17:12 studio systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 01 21:17:12 studio systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 01 21:17:12 studio systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 01 21:17:12 studio systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 01 21:17:12 studio kernel: i2c_dev: i2c /dev entries driver Dec 01 21:17:12 studio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 01 21:17:12 studio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 01 21:17:12 studio systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 01 21:17:12 studio systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 01 21:17:12 studio systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 01 21:17:12 studio systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 01 21:17:12 studio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 01 21:17:12 studio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 01 21:17:12 studio systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Dec 01 21:17:12 studio systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 01 21:17:12 studio systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 01 21:17:12 studio systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 01 21:17:12 studio systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Dec 01 21:17:12 studio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 01 21:17:12 studio systemd-journald[431]: Journal started Dec 01 21:17:12 studio systemd-journald[431]: Runtime Journal (/run/log/journal/3713d82dde2ff507d70b8369691b4bf9) is 3.7M, max 30.0M, 26.2M free. Dec 01 21:17:12 studio systemd-modules-load[435]: Inserted module 'i2c_dev' Dec 01 21:17:12 studio systemd[1]: Started systemd-journald.service - Journal Service. Dec 01 21:17:12 studio fake-hwclock[438]: Mon Dec 1 17:17:12 UTC 2025 Dec 01 21:17:12 studio systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 01 21:17:12 studio systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 01 21:17:12 studio systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 01 21:17:12 studio systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 01 21:17:12 studio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 01 21:17:12 studio systemd[1]: Mounting tmp.mount - /tmp... Dec 01 21:17:12 studio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 01 21:17:12 studio systemd[1]: Mounting var-log.mount - /var/log... Dec 01 21:17:12 studio systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Dec 01 21:17:12 studio systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 01 21:17:12 studio systemd[1]: Mounted tmp.mount - /tmp. Dec 01 21:17:12 studio systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 01 21:17:12 studio systemd[1]: Mounted var-log.mount - /var/log. Dec 01 21:17:12 studio systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Dec 01 21:17:12 studio systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Dec 01 21:17:12 studio systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 01 21:17:12 studio systemd-journald[431]: Runtime Journal (/run/log/journal/3713d82dde2ff507d70b8369691b4bf9) is 3.7M, max 30.0M, 26.2M free. Dec 01 21:17:12 studio systemd-journald[431]: Received client request to flush runtime journal. Dec 01 21:17:12 studio systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 01 21:17:12 studio systemd-udevd[457]: Using default interface naming scheme 'v252'. Dec 01 21:17:12 studio systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 01 21:17:13 studio systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Dec 01 21:17:13 studio systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Dec 01 21:17:13 studio (udev-worker)[466]: Network interface NamePolicy= disabled on kernel command line. Dec 01 21:17:13 studio systemd[1]: Found device dev-disk-by\x2duuid-DFF5\x2dD140.device - /dev/disk/by-uuid/DFF5-D140. Dec 01 21:17:13 studio systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-DFF5\x2dD140.service - File System Check on /dev/disk/by-uuid/DFF5-D140... Dec 01 21:17:13 studio kernel: mc: Linux media interface: v0.10 Dec 01 21:17:13 studio systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Dec 01 21:17:13 studio systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Dec 01 21:17:13 studio systemd-fsck[516]: fsck.fat 4.2 (2021-01-31) Dec 01 21:17:13 studio systemd-fsck[516]: There are differences between boot sector and its backup. Dec 01 21:17:13 studio systemd-fsck[516]: This is mostly harmless. Differences: (offset:original/backup) Dec 01 21:17:13 studio systemd-fsck[516]: 65:01/00 Dec 01 21:17:13 studio systemd-fsck[516]: Not automatically fixing this. Dec 01 21:17:13 studio systemd-fsck[516]: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Dec 01 21:17:13 studio systemd-fsck[516]: Automatically removing dirty bit. Dec 01 21:17:13 studio systemd-fsck[516]: *** Filesystem was changed *** Dec 01 21:17:13 studio systemd-fsck[516]: Writing changes. Dec 01 21:17:13 studio systemd-fsck[516]: /dev/mmcblk0p1: 427 files, 23457/93500 clusters Dec 01 21:17:13 studio systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-DFF5\x2dD140.service - File System Check on /dev/disk/by-uuid/DFF5-D140. Dec 01 21:17:13 studio systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Dec 01 21:17:14 studio kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 01 21:17:14 studio systemd[1]: Mounting boot.mount - /boot... Dec 01 21:17:14 studio kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 01 21:17:14 studio systemd[1]: Mounted boot.mount - /boot. Dec 01 21:17:14 studio systemd[1]: Reached target local-fs.target - Local File Systems. Dec 01 21:17:14 studio systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Dec 01 21:17:14 studio systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Dec 01 21:17:14 studio systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Dec 01 21:17:14 studio systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Dec 01 21:17:14 studio systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 01 21:17:14 studio systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Dec 01 21:17:14 studio systemd[1]: Started ifup@eth0.service - ifup for eth0. Dec 01 21:17:14 studio systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 535 (systemd-binfmt) Dec 01 21:17:14 studio systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Dec 01 21:17:14 studio systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Dec 01 21:17:14 studio systemd[1]: Received SIGRTMIN+20 from PID 307 (plymouthd). Dec 01 21:17:14 studio kernel: videodev: Linux video capture interface: v2.00 Dec 01 21:17:14 studio systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Dec 01 21:17:14 studio systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Dec 01 21:17:14 studio systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Dec 01 21:17:14 studio systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Dec 01 21:17:14 studio systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Dec 01 21:17:14 studio systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Dec 01 21:17:14 studio systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Dec 01 21:17:14 studio systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 01 21:17:14 studio systemd[1]: Reached target paths.target - Path Units. Dec 01 21:17:14 studio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:14 studio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 01 21:17:14 studio kernel: [vc_sm_connected_init]: start Dec 01 21:17:14 studio systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 01 21:17:14 studio systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Dec 01 21:17:14 studio kernel: [vc_sm_connected_init]: installed successfully Dec 01 21:17:14 studio systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Dec 01 21:17:14 studio systemd[1]: Starting rpcbind.service - RPC bind portmap service... Dec 01 21:17:14 studio systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 01 21:17:14 studio systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Dec 01 21:17:14 studio systemd[1]: Reached target rpc_pipefs.target. Dec 01 21:17:14 studio systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Dec 01 21:17:14 studio systemd[1]: Reached target nfs-client.target - NFS client services. Dec 01 21:17:14 studio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 01 21:17:14 studio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:14 studio kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 01 21:17:14 studio kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 01 21:17:14 studio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 01 21:17:14 studio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 01 21:17:14 studio systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Dec 01 21:17:14 studio systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 01 21:17:14 studio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:14 studio sh[568]: command failed: No such device (-19) Dec 01 21:17:14 studio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 01 21:17:15 studio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 01 21:17:15 studio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 01 21:17:15 studio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 01 21:17:15 studio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 01 21:17:15 studio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 01 21:17:15 studio systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Dec 01 21:17:15 studio systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 01 21:17:15 studio kernel: rpi-hevc-dec feb00000.codec: Device registered as /dev/video19 Dec 01 21:17:15 studio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 01 21:17:15 studio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 01 21:17:15 studio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 01 21:17:15 studio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 01 21:17:15 studio systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 01 21:17:15 studio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 01 21:17:15 studio systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Dec 01 21:17:15 studio systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Dec 01 21:17:15 studio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 01 21:17:15 studio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Dec 01 21:17:15 studio systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Dec 01 21:17:15 studio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 01 21:17:15 studio systemd[1]: Reached target sysinit.target - System Initialization. Dec 01 21:17:15 studio systemd[1]: apt-daily.timer: Not using persistent file timestamp Thu 2025-12-18 14:36:34 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started apt-daily.timer - Daily apt download activities. Dec 01 21:17:15 studio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2025-12-19 01:51:15 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Dec 01 21:17:15 studio systemd[1]: dpkg-db-backup.timer: Not using persistent file timestamp Fri 2025-12-19 01:50:00 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Dec 01 21:17:15 studio systemd[1]: e2scrub_all.timer: Not using persistent file timestamp Fri 2025-12-19 01:50:00 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Dec 01 21:17:15 studio systemd[1]: fstrim.timer: Not using persistent file timestamp Fri 2025-12-19 00:54:01 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Dec 01 21:17:15 studio systemd[1]: man-db.timer: Not using persistent file timestamp Thu 2025-12-18 03:20:28 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started man-db.timer - Daily man-db regeneration. Dec 01 21:17:15 studio systemd[1]: ntpsec-rotate-stats.timer: Not using persistent file timestamp Fri 2025-12-19 01:50:00 +04 as it is in the future. Dec 01 21:17:15 studio systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Dec 01 21:17:15 studio systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Dec 01 21:17:15 studio systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 01 21:17:15 studio systemd[1]: Reached target timers.target - Timer Units. Dec 01 21:17:15 studio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:15 studio systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Dec 01 21:17:15 studio systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 01 21:17:15 studio systemd[1]: Reached target sockets.target - Socket Units. Dec 01 21:17:15 studio systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 01 21:17:15 studio systemd[1]: Reached target basic.target - Basic System. Dec 01 21:17:15 studio systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Dec 01 21:17:15 studio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 21:17:15 studio systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 01 21:17:15 studio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 01 21:17:15 studio systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 01 21:17:15 studio systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Dec 01 21:17:15 studio systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Dec 01 21:17:15 studio systemd[1]: Reached target getty.target - Login Prompts. Dec 01 21:17:15 studio systemd[1]: Starting glamor-test.service - Check for glamor... Dec 01 21:17:15 studio systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 01 21:17:15 studio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 01 21:17:15 studio dhcpcd[635]: dhcpcd-9.4.1 starting Dec 01 21:17:15 studio sh[635]: dhcpcd-9.4.1 starting Dec 01 21:17:15 studio systemd[1]: Started iptables.service - Volumio Iptables Module. Dec 01 21:17:15 studio systemd[1]: Starting rp1-test.service - Check for RP1 displays for Xorg... Dec 01 21:17:15 studio systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Dec 01 21:17:15 studio sh[635]: DUID 00:01:00:01:30:ae:0a:75:dc:a6:32:87:25:8d Dec 01 21:17:15 studio dhcpcd[648]: DUID 00:01:00:01:30:ae:0a:75:dc:a6:32:87:25:8d Dec 01 21:17:15 studio systemd[1]: Starting systemd-logind.service - User Login Management... Dec 01 21:17:15 studio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 01 21:17:15 studio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Dec 01 21:17:15 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 01 21:17:15 studio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 01 21:17:15 studio systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Dec 01 21:17:15 studio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Dec 01 21:17:15 studio systemd[1]: Starting wireless.service - Wireless Services... Dec 01 21:17:15 studio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 01 21:17:15 studio systemd[1]: Started rpcbind.service - RPC bind portmap service. Dec 01 21:17:15 studio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 01 21:17:15 studio thd[654]: Unable to parse trigger line: Dec 01 21:17:15 studio thd[654]: Unable to parse trigger line: Dec 01 21:17:15 studio systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 01 21:17:15 studio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Dec 01 21:17:15 studio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Dec 01 21:17:15 studio systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 01 21:17:15 studio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Dec 01 21:17:15 studio volumio_rfkill_unblock.sh[664]: Volumio WiFi Soft Blocked check script Dec 01 21:17:15 studio volumio_rfkill_unblock.sh[664]: Check if rfkill is available Dec 01 21:17:15 studio systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Dec 01 21:17:15 studio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 01 21:17:15 studio avahi-daemon[636]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 01 21:17:15 studio avahi-daemon[636]: Successfully dropped root privileges. Dec 01 21:17:15 studio kernel: usbcore: registered new interface driver brcmfmac Dec 01 21:17:15 studio avahi-daemon[636]: avahi-daemon 0.8 starting up. Dec 01 21:17:15 studio volumio_rfkill_unblock.sh[664]: Check if rfkill listed devices are already unblocked Dec 01 21:17:15 studio systemd[1]: th-udev-rebind.service: Deactivated successfully. Dec 01 21:17:15 studio systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Dec 01 21:17:15 studio kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 01 21:17:15 studio volumio_rfkill_unblock.sh[664]: Wi-Fi is already unblocked. Dec 01 21:17:15 studio systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Dec 01 21:17:15 studio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Dec 01 21:17:15 studio alsactl[702]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 01 21:17:15 studio alsactl[702]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 01 21:17:15 studio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Dec 01 21:17:15 studio systemd[1]: Reached target sound.target - Sound Card. Dec 01 21:17:15 studio kernel: Bluetooth: Core ver 2.22 Dec 01 21:17:15 studio kernel: NET: Registered PF_BLUETOOTH protocol family Dec 01 21:17:15 studio kernel: Bluetooth: HCI device and connection manager initialized Dec 01 21:17:15 studio kernel: Bluetooth: HCI socket layer initialized Dec 01 21:17:15 studio kernel: Bluetooth: L2CAP socket layer initialized Dec 01 21:17:15 studio kernel: Bluetooth: SCO socket layer initialized Dec 01 21:17:16 studio kernel: Bluetooth: HCI UART driver ver 2.3 Dec 01 21:17:16 studio kernel: Bluetooth: HCI UART protocol H4 registered Dec 01 21:17:16 studio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 01 21:17:16 studio kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 01 21:17:16 studio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 01 21:17:16 studio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 01 21:17:16 studio kernel: Bluetooth: hci0: BCM: chip id 107 Dec 01 21:17:16 studio kernel: Bluetooth: hci0: BCM: features 0x2f Dec 01 21:17:16 studio kernel: Bluetooth: hci0: BCM4345C0 Dec 01 21:17:16 studio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 01 21:17:16 studio kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 01 21:17:16 studio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b Dec 01 21:17:16 studio kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,4-model-b.hcd' Patch Dec 01 21:17:16 studio systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 01 21:17:16 studio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Dec 01 21:17:16 studio systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 01 21:17:16 studio kernel: hwmon hwmon1: Undervoltage detected! Dec 01 21:17:16 studio avahi-daemon[636]: Successfully called chroot(). Dec 01 21:17:16 studio avahi-daemon[636]: Successfully dropped remaining capabilities. Dec 01 21:17:16 studio avahi-daemon[636]: Loading service file /services/volumio.service. Dec 01 21:17:16 studio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 01 21:17:16 studio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 01 21:17:16 studio dhcpcd[648]: eth0: waiting for carrier Dec 01 21:17:16 studio sh[635]: eth0: waiting for carrier Dec 01 21:17:16 studio avahi-daemon[636]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 01 21:17:16 studio avahi-daemon[636]: New relevant interface lo.IPv4 for mDNS. Dec 01 21:17:16 studio avahi-daemon[636]: Network interface enumeration completed. Dec 01 21:17:16 studio avahi-daemon[636]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 01 21:17:16 studio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Dec 01 21:17:16 studio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 01 21:17:16 studio systemd[1]: Finished glamor-test.service - Check for glamor. Dec 01 21:17:17 studio loadcpufreq[672]: Loading cpufreq kernel modules...done (none). Dec 01 21:17:17 studio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Dec 01 21:17:17 studio (udev-worker)[479]: Network interface NamePolicy= disabled on kernel command line. Dec 01 21:17:17 studio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Dec 01 21:17:17 studio systemd[1]: Finished rp1-test.service - Check for RP1 displays for Xorg. Dec 01 21:17:17 studio (udev-worker)[473]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Dec 01 21:17:17 studio kernel: Bluetooth: hci0: BCM: features 0x2f Dec 01 21:17:17 studio kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Dec 01 21:17:17 studio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Dec 01 21:17:17 studio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Dec 01 21:17:17 studio systemd[1]: iptables.service: Deactivated successfully. Dec 01 21:17:17 studio volumio-remote-updater[661]: Volumio OTA Client: 1.7.0 Dec 01 21:17:17 studio volumio-remote-updater[661]: Error: No active session Dec 01 21:17:17 studio volumio-remote-updater[661]: [2025-12-01 21:17:17] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 01 21:17:17 studio volumio-remote-updater[661]: [2025-12-01 21:17:17] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 01 21:17:17 studio volumio-remote-updater[661]: [2025-12-01 21:17:17] [error] handle_connect error: Connection refused Dec 01 21:17:17 studio avahi-daemon[636]: Server startup complete. Host name is studio.local. Local service cookie is 3992244202. Dec 01 21:17:17 studio systemd[1]: e2scrub_reap.service: Deactivated successfully. Dec 01 21:17:17 studio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Dec 01 21:17:17 studio wpa_supplicant[777]: Successfully initialized wpa_supplicant Dec 01 21:17:17 studio systemd[1]: Started wpa_supplicant.service - WPA supplicant. Dec 01 21:17:17 studio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Dec 01 21:17:17 studio dhcpcd[709]: dhcpcd-9.4.1 starting Dec 01 21:17:17 studio systemd[1]: Starting bluetooth.service - Bluetooth service... Dec 01 21:17:17 studio haveged[579]: haveged: command socket is listening at fd 3 Dec 01 21:17:17 studio dhcpcd[851]: dev: loaded udev Dec 01 21:17:17 studio dhcpcd[851]: DUID 00:01:00:01:30:ae:0a:75:dc:a6:32:87:25:8d Dec 01 21:17:17 studio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Dec 01 21:17:18 studio cpufrequtils[818]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 01 21:17:18 studio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Dec 01 21:17:18 studio systemd-logind[652]: New seat seat0. Dec 01 21:17:18 studio upmpdcli[658]: Could not open config: /tmp/upmpdcli.conf Dec 01 21:17:18 studio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Dec 01 21:17:18 studio systemd[1]: Started systemd-logind.service - User Login Management. Dec 01 21:17:18 studio dhcpcd[851]: eth0: waiting for carrier Dec 01 21:17:18 studio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Dec 01 21:17:18 studio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 01 21:17:18 studio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 01 21:17:18 studio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled Dec 01 21:17:18 studio dhcpcd[851]: wlan0: waiting for carrier Dec 01 21:17:18 studio dhcpcd[851]: wlan0: waiting for carrier Dec 01 21:17:18 studio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 01 21:17:18 studio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 01 21:17:18 studio avahi-daemon[636]: Service "Studio" (/services/volumio.service) successfully established. Dec 01 21:17:18 studio rpi-eeprom-update[647]: BOOTLOADER: up to date Dec 01 21:17:18 studio rpi-eeprom-update[647]: CURRENT: Wed Nov 5 17:28:26 UTC 2025 (1762363706) Dec 01 21:17:18 studio bluetoothd[848]: Bluetooth daemon 5.83 Dec 01 21:17:19 studio rpi-eeprom-update[647]: LATEST: Wed Nov 5 17:28:26 UTC 2025 (1762363706) Dec 01 21:17:19 studio rpi-eeprom-update[647]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2711/default) Dec 01 21:17:19 studio rpi-eeprom-update[647]: Use raspi-config to change the release. Dec 01 21:17:19 studio rpi-eeprom-update[647]: VL805_FW: Dedicated VL805 EEPROM Dec 01 21:17:19 studio rpi-eeprom-update[647]: VL805: up to date Dec 01 21:17:19 studio rpi-eeprom-update[647]: CURRENT: 000138c0 Dec 01 21:17:19 studio rpi-eeprom-update[647]: LATEST: 000138c0 Dec 01 21:17:19 studio systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Dec 01 21:17:19 studio systemd[1]: Started bluetooth.service - Bluetooth service. Dec 01 21:17:19 studio bluetoothd[848]: Starting SDP server Dec 01 21:17:19 studio bluetoothd[848]: Excluding (cli) sap Dec 01 21:17:19 studio bluetoothd[848]: Excluding (cli) midi Dec 01 21:17:19 studio bluetoothd[848]: Excluding (cli) battery Dec 01 21:17:19 studio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 01 21:17:19 studio kernel: Bluetooth: BNEP filters: protocol multicast Dec 01 21:17:19 studio kernel: Bluetooth: BNEP socket layer initialized Dec 01 21:17:19 studio bluetoothd[848]: Bluetooth management interface 1.23 initialized Dec 01 21:17:19 studio dbus-daemon[638]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=848 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Dec 01 21:17:19 studio bluetoothd[848]: Battery Provider Manager created Dec 01 21:17:19 studio kernel: Bluetooth: MGMT ver 1.23 Dec 01 21:17:19 studio systemd[1]: Starting bluealsa.service - BlueALSA service... Dec 01 21:17:19 studio systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Dec 01 21:17:19 studio kernel: NET: Registered PF_ALG protocol family Dec 01 21:17:19 studio kernel: cryptd: max_cpu_qlen set to 1000 Dec 01 21:17:19 studio systemd[1]: Starting networking.service - Raise network interfaces... Dec 01 21:17:19 studio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Dec 01 21:17:19 studio sh[987]: wlan0=wlan0 Dec 01 21:17:19 studio bluetoothd[848]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Dec 01 21:17:19 studio bluetoothd[848]: bap: Operation not supported (95) Dec 01 21:17:19 studio bluetoothd[848]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Dec 01 21:17:20 studio dbus-daemon[638]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 01 21:17:20 studio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Dec 01 21:17:20 studio systemd[1]: Finished networking.service - Raise network interfaces. Dec 01 21:17:20 studio systemd[1]: Reached target network.target - Network. Dec 01 21:17:20 studio systemd[1]: Reached target network-online.target - Network is Online. Dec 01 21:17:20 studio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Dec 01 21:17:20 studio systemd[1]: Starting nmbd.service - Samba NMB Daemon... Dec 01 21:17:20 studio systemd[1]: Starting ntpsec.service - Network Time Service... Dec 01 21:17:20 studio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Dec 01 21:17:20 studio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Dec 01 21:17:20 studio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Dec 01 21:17:20 studio haveged[579]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Dec 01 21:17:20 studio haveged[579]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 Dec 01 21:17:20 studio haveged[579]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99894 Dec 01 21:17:20 studio haveged[579]: haveged: fills: 0, generated: 0 Dec 01 21:17:20 studio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 01 21:17:20 studio sm-notify[1038]: Version 2.6.2 starting Dec 01 21:17:20 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 01 21:17:20 studio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 01 21:17:20 studio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Dec 01 21:17:20 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:17:20 studio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Dec 01 21:17:20 studio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Dec 01 21:17:20 studio ntpd[1043]: INIT: ntpd ntpsec-1.2.2: Starting Dec 01 21:17:20 studio ntp-systemd-wrapper[1043]: 2025-12-01T21:17:20 ntpd[1043]: INIT: ntpd ntpsec-1.2.2: Starting Dec 01 21:17:20 studio ntp-systemd-wrapper[1043]: 2025-12-01T21:17:20 ntpd[1043]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 01 21:17:20 studio ntpd[1043]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 01 21:17:20 studio systemd[1]: Started ntpsec.service - Network Time Service. Dec 01 21:17:20 studio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 01 21:17:20 studio welcome[1049]: Resolved ip:[0] Dec 01 21:17:20 studio ntpd[1056]: INIT: precision = 1.130 usec (-20) Dec 01 21:17:20 studio ntpd[1056]: INIT: successfully locked into RAM Dec 01 21:17:20 studio ntpd[1056]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Dec 01 21:17:20 studio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Dec 01 21:17:20 studio ntpd[1056]: CONFIG: restrict nopeer ignored Dec 01 21:17:20 studio ifplugd(eth0)[1076]: ifplugd 0.28 initializing. Dec 01 21:17:20 studio ifplugd(eth0)[1076]: Using interface eth0/DC:A6:32:87:25:8D with driver (version: 6.12.47-v7l+) Dec 01 21:17:20 studio ifplugd(eth0)[1076]: Using detection mode: SIOCETHTOOL Dec 01 21:17:20 studio ifplugd(eth0)[1076]: Initialization complete, link beat not detected. Dec 01 21:17:20 studio ntpd[1056]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 01 21:17:20 studio ntpd[1056]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Dec 01 21:17:20 studio ntpd[1056]: INIT: Using SO_TIMESTAMPNS(ns) Dec 01 21:17:20 studio ntpd[1056]: IO: Listen and drop on 0 v6wildcard [::]:123 Dec 01 21:17:20 studio ntpd[1056]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 01 21:17:20 studio ntpd[1056]: IO: Listen normally on 2 lo 127.0.0.1:123 Dec 01 21:17:20 studio ntpd[1056]: IO: Listening on routing socket on fd #19 for interface updates Dec 01 21:17:20 studio dhcpcd[648]: eth0: carrier acquired Dec 01 21:17:20 studio sh[635]: eth0: carrier acquired Dec 01 21:17:20 studio dhcpcd[851]: eth0: carrier acquired Dec 01 21:17:20 studio ifplugd[1030]: Network Interface Plugging Daemon...start eth0...done. Dec 01 21:17:20 studio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Dec 01 21:17:20 studio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Dec 01 21:17:20 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:17:20 studio dhcpcd[851]: eth0: IAID 32:87:25:8d Dec 01 21:17:20 studio dhcpcd[851]: eth0: adding address fe80::dea6:32ff:fe87:258d Dec 01 21:17:20 studio dhcpcd[851]: ipv6_addaddr1: Permission denied Dec 01 21:17:20 studio dhcpcd[648]: eth0: IAID 32:87:25:8d Dec 01 21:17:20 studio sh[635]: eth0: IAID 32:87:25:8d Dec 01 21:17:20 studio dhcpcd[648]: eth0: adding address fe80::dea6:32ff:fe87:258d Dec 01 21:17:20 studio dhcpcd[648]: ipv6_addaddr1: Permission denied Dec 01 21:17:20 studio sh[635]: eth0: adding address fe80::dea6:32ff:fe87:258d Dec 01 21:17:20 studio sh[635]: ipv6_addaddr1: Permission denied Dec 01 21:17:21 studio ntpd[1056]: INIT: This system has a 32-bit time_t. Dec 01 21:17:21 studio ntpd[1056]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Dec 01 21:17:21 studio ntpd[1056]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 01 21:17:21 studio ntpd[1056]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Dec 01 21:17:21 studio ntpd[1056]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Dec 01 21:17:21 studio ntpd[1056]: NTSc: Using system default root certificates. Dec 01 21:17:21 studio bluealsa[946]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Dec 01 21:17:21 studio bluealsa[946]: main.c:663: Starting main dispatching loop Dec 01 21:17:21 studio bluealsa[946]: main.c:118: Acquired D-Bus service name: org.bluealsa Dec 01 21:17:21 studio bluealsa[946]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Dec 01 21:17:21 studio systemd[1]: Started bluealsa.service - BlueALSA service. Dec 01 21:17:21 studio systemd[1]: Reached target bluetooth.target - Bluetooth Support. Dec 01 21:17:21 studio bluealsa[946]: bluez.c:153: Registering media application: /org/bluez/hci0 Dec 01 21:17:21 studio bluealsa[946]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Dec 01 21:17:21 studio bluealsa[946]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Dec 01 21:17:21 studio bluealsa[946]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Dec 01 21:17:21 studio bluealsa[946]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Dec 01 21:17:21 studio dbus-daemon[638]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.6' (uid=0 pid=946 comm="/usr/bin/bluealsa -S -p a2dp-source -p a2dp-sink") Dec 01 21:17:21 studio bluetoothd[848]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Dec 01 21:17:21 studio bluetoothd[848]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Dec 01 21:17:21 studio systemd[1]: Starting upower.service - Daemon for power management... Dec 01 21:17:21 studio ntpd[1056]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Dec 01 21:17:21 studio dbus-daemon[638]: [system] Successfully activated service 'org.freedesktop.UPower' Dec 01 21:17:21 studio systemd[1]: Started upower.service - Daemon for power management. Dec 01 21:17:21 studio dhcpcd[851]: eth0: soliciting an IPv6 router Dec 01 21:17:21 studio bluetoothd[848]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Dec 01 21:17:21 studio bluetoothd[848]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Dec 01 21:17:21 studio ifplugd(eth0)[1076]: Link beat detected. Dec 01 21:17:21 studio dhcpcd[648]: eth0: soliciting an IPv6 router Dec 01 21:17:21 studio sh[635]: eth0: soliciting an IPv6 router Dec 01 21:17:22 studio dhcpcd[648]: eth0: rebinding lease of 192.168.115.249 Dec 01 21:17:22 studio sh[635]: eth0: rebinding lease of 192.168.115.249 Dec 01 21:17:22 studio ifplugd(eth0)[1076]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 01 21:17:22 studio ifplugd(eth0)[1076]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 01 21:17:22 studio dhcpcd[851]: eth0: rebinding lease of 192.168.115.249 Dec 01 21:17:22 studio systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 01 21:17:22 studio ntpd[1056]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:17:22 studio ntpd[1056]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 01 21:17:22 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:17:22 studio ntpd[1056]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 01 21:17:22 studio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Dec 01 21:17:22 studio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Dec 01 21:17:22 studio volumio-remote-updater[661]: [2025-12-01 21:17:22] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 01 21:17:22 studio volumio-remote-updater[661]: [2025-12-01 21:17:22] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 01 21:17:22 studio volumio-remote-updater[661]: [2025-12-01 21:17:22] [error] handle_connect error: Connection refused Dec 01 21:17:23 studio ntpd[1056]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:17:23 studio ntpd[1056]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 01 21:17:23 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:17:23 studio ntpd[1056]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 01 21:17:23 studio nmbd[1123]: [2025/12/01 21:17:23.550615, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 01 21:17:23 studio nmbd[1123]: nmbd version 4.17.8-Raspbian started. Dec 01 21:17:23 studio nmbd[1123]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 01 21:17:23 studio nmbd[1123]: [2025/12/01 21:17:23.581643, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 01 21:17:23 studio nmbd[1123]: started asyncdns process 1127 Dec 01 21:17:23 studio nmbd[1123]: [2025/12/01 21:17:23.583999, 0] ../../lib/util/become_daemon.c:150(daemon_status) Dec 01 21:17:23 studio nmbd[1123]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 01 21:17:23 studio nmbd[1123]: [2025/12/01 21:17:23.584224, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Dec 01 21:17:23 studio nmbd[1123]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 01 21:17:24 studio wireless.js[666]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 01 21:17:24 studio wireless.js[666]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 01 21:17:24 studio wireless.js[666]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 01 21:17:24 studio wireless.js[666]: WIRELESS.JS: Cleaning previous... Dec 01 21:17:24 studio ntpd[1056]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:17:24 studio ntpd[1056]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 01 21:17:24 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:17:24 studio ntpd[1056]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 01 21:17:24 studio sudo[1142]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 01 21:17:24 studio sudo[1142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 01 21:17:24 studio sudo[1142]: pam_unix(sudo:session): session closed for user root Dec 01 21:17:24 studio sudo[1145]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 01 21:17:24 studio sudo[1145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 01 21:17:25 studio sudo[1145]: pam_unix(sudo:session): session closed for user root Dec 01 21:17:25 studio ntpd[1056]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:17:25 studio ntpd[1056]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 01 21:17:25 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:17:25 studio ntpd[1056]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 01 21:17:25 studio wireless.js[666]: WIRELESS.JS: Stopped aP Dec 01 21:17:25 studio wireless.js[666]: WIRELESS.JS: Wireless Networking DISABLED, not starting wireless flow Dec 01 21:17:25 studio systemd[1]: Started wireless.service - Wireless Services. Dec 01 21:17:25 studio wireless.js[666]: WIRELESS.JS: Notified systemd about wireless ready Dec 01 21:17:25 studio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 01 21:17:25 studio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 01 21:17:25 studio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 01 21:17:25 studio systemd[1]: Received SIGRTMIN+21 from PID 307 (plymouthd). Dec 01 21:17:25 studio systemd[1]: Received SIGRTMIN+21 from PID 307 (plymouthd). Dec 01 21:17:25 studio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 01 21:17:25 studio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 01 21:17:26 studio volumio-cpu-tweak[1163]: Setting RT Priority for mpd Dec 01 21:17:26 studio volumio-cpu-tweak[1177]: pid 35's current scheduling policy: SCHED_OTHER Dec 01 21:17:26 studio volumio-cpu-tweak[1177]: pid 35's current scheduling priority: 0 Dec 01 21:17:26 studio volumio-cpu-tweak[1163]: Setting MPD Affinity Dec 01 21:17:26 studio volumio-cpu-tweak[1178]: pid 3's current affinity mask: f Dec 01 21:17:26 studio volumio-cpu-tweak[1163]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 01 21:17:26 studio volumio-cpu-tweak[1163]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 01 21:17:26 studio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 01 21:17:27 studio dhcpcd[648]: eth0: probing for an IPv4LL address Dec 01 21:17:27 studio sh[635]: eth0: probing for an IPv4LL address Dec 01 21:17:27 studio sh[635]: eth0: DHCP lease expired Dec 01 21:17:27 studio dhcpcd[648]: eth0: DHCP lease expired Dec 01 21:17:27 studio dhcpcd[648]: eth0: soliciting a DHCP lease Dec 01 21:17:27 studio sh[635]: eth0: soliciting a DHCP lease Dec 01 21:17:27 studio dhcpcd[851]: eth0: probing for an IPv4LL address Dec 01 21:17:27 studio dhcpcd[851]: eth0: DHCP lease expired Dec 01 21:17:27 studio dhcpcd[851]: eth0: soliciting a DHCP lease Dec 01 21:17:29 studio bash[1045]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 01 21:17:29 studio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 01 21:17:29 studio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 01 21:17:29 studio systemd[1]: setdatetime-helper.service: Consumed 1.973s CPU time. Dec 01 21:17:30 studio volumio-remote-updater[661]: [2025-12-01 21:17:30] [connect] Successful connection Dec 01 21:17:31 studio volumio[1161]: info: ------------------------------------------- Dec 01 21:17:31 studio volumio[1161]: info: ----- Volumio3 ---- Dec 01 21:17:31 studio volumio[1161]: info: ------------------------------------------- Dec 01 21:17:31 studio volumio[1161]: info: ----- System startup ---- Dec 01 21:17:31 studio volumio[1161]: info: ------------------------------------------- Dec 01 21:17:32 studio dhcpcd[648]: eth0: probing for an IPv4LL address Dec 01 21:17:32 studio sh[635]: eth0: probing for an IPv4LL address Dec 01 21:17:32 studio dhcpcd[851]: eth0: probing for an IPv4LL address Dec 01 21:17:32 studio volumio[1161]: info: MYVOLUMIO Environment detected Dec 01 21:17:33 studio volumio[1161]: info: Plugin folders cleanup Dec 01 21:17:33 studio volumio[1161]: info: Scanning into folder /volumio/app/plugins/ Dec 01 21:17:33 studio volumio[1161]: info: Scanning category audio_interface Dec 01 21:17:33 studio volumio[1161]: info: Scanning category miscellanea Dec 01 21:17:33 studio volumio[1161]: info: Scanning category music_service Dec 01 21:17:33 studio volumio[1161]: info: Scanning category plugins.json Dec 01 21:17:33 studio volumio[1161]: info: Scanning category system_controller Dec 01 21:17:33 studio volumio[1161]: info: Scanning category user_interface Dec 01 21:17:33 studio volumio[1161]: info: Scanning into folder /data/plugins/ Dec 01 21:17:33 studio volumio[1161]: info: Scanning category audio_interface Dec 01 21:17:33 studio volumio[1161]: info: Scanning category music_service Dec 01 21:17:33 studio volumio[1161]: info: Scanning category system_controller Dec 01 21:17:33 studio volumio[1161]: info: Scanning category user_interface Dec 01 21:17:33 studio volumio[1161]: info: Plugin folders cleanup completed Dec 01 21:17:33 studio volumio[1161]: info: ------------------------------------------- Dec 01 21:17:33 studio volumio[1161]: info: ----- Core plugins startup ---- Dec 01 21:17:33 studio volumio[1161]: info: ------------------------------------------- Dec 01 21:17:33 studio volumio[1161]: info: Loading plugins from folder /volumio/app/plugins/ Dec 01 21:17:33 studio volumio[1161]: info: Adding plugin upnp to MyMusic Plugins Dec 01 21:17:33 studio volumio[1161]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 01 21:17:33 studio volumio[1161]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 01 21:17:33 studio volumio[1161]: info: Loading plugins from folder /data/plugins/ Dec 01 21:17:33 studio volumio[1161]: info: Loading plugin "system"... Dec 01 21:17:33 studio volumio[1161]: info: Loading plugin "appearance"... Dec 01 21:17:36 studio volumio[1161]: info: Loading plugin "network"... Dec 01 21:17:36 studio volumio[1161]: info: Refreshing Cached IP Addresses Dec 01 21:17:36 studio volumio[1161]: info: Loading plugin "services"... Dec 01 21:17:36 studio volumio[1161]: info: Loading plugin "alsa_controller"... Dec 01 21:17:36 studio sudo[1234]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 21:17:36 studio sudo[1234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:17:36 studio sudo[1234]: pam_unix(sudo:session): session closed for user root Dec 01 21:17:36 studio sudo[1236]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 21:17:36 studio sudo[1236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:17:36 studio sudo[1236]: pam_unix(sudo:session): session closed for user root Dec 01 21:17:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:17:37 studio volumio[1161]: info: Loading plugin "wizard"... Dec 01 21:17:37 studio volumio[1161]: info: Loading plugin "networkfs"... Dec 01 21:17:37 studio volumio[1161]: info: Starting Udev Watcher for removable devices Dec 01 21:17:37 studio volumio[1161]: info: Ignoring mount for partition: boot Dec 01 21:17:37 studio volumio[1161]: info: Ignoring mount for partition: volumio Dec 01 21:17:37 studio volumio[1161]: info: Ignoring mount for partition: volumio_data Dec 01 21:17:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:17:37 studio volumio[1161]: info: Loading plugin "volumio_command_line_client"... Dec 01 21:17:37 studio sudo[1265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=attas,password=RahafUae@#$0555%,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.115.230/music /mnt/NAS/attasdsm Dec 01 21:17:37 studio volumio[1161]: info: Loading plugin "upnp"... Dec 01 21:17:37 studio sudo[1265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:17:37 studio volumio[1161]: info: [1764609457413] Starting Upmpd Daemon Dec 01 21:17:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:17:37 studio volumio[1161]: info: Loading plugin "my_music"... Dec 01 21:17:37 studio volumio[1161]: info: Loading plugin "mpd"... Dec 01 21:17:37 studio kernel: netfs: FS-Cache loaded Dec 01 21:17:37 studio kernel: Key type cifs.spnego registered Dec 01 21:17:37 studio kernel: Key type cifs.idmap registered Dec 01 21:17:37 studio 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 01 21:17:37 studio kernel: CIFS: Attempting to mount //192.168.115.230/music Dec 01 21:17:37 studio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 01 21:17:37 studio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 01 21:17:37 studio sudo[1265]: pam_unix(sudo:session): session closed for user root Dec 01 21:17:37 studio dhcpcd[648]: eth0: using IPv4LL address 169.254.228.194 Dec 01 21:17:37 studio sh[635]: eth0: using IPv4LL address 169.254.228.194 Dec 01 21:17:37 studio sh[635]: eth0: adding route to 169.254.0.0/16 Dec 01 21:17:37 studio sh[635]: eth0: adding default route Dec 01 21:17:37 studio dhcpcd[648]: eth0: adding route to 169.254.0.0/16 Dec 01 21:17:37 studio dhcpcd[648]: eth0: adding default route Dec 01 21:17:37 studio avahi-daemon[636]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.228.194. Dec 01 21:17:37 studio avahi-daemon[636]: New relevant interface eth0.IPv4 for mDNS. Dec 01 21:17:37 studio avahi-daemon[636]: Registering new address record for 169.254.228.194 on eth0.IPv4. Dec 01 21:17:37 studio systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 01 21:17:37 studio sh[635]: forked to background, child pid 646 Dec 01 21:17:38 studio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 01 21:17:38 studio systemd[1]: welcome.service: Deactivated successfully. Dec 01 21:17:38 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 01 21:17:38 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 01 21:17:38 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:17:38 studio welcome[1304]: Resolved ip:[1] 169.254.228.194 Dec 01 21:17:38 studio dhcpcd[851]: eth0: using IPv4LL address 169.254.228.194 Dec 01 21:17:38 studio dhcpcd[851]: eth0: adding route to 169.254.0.0/16 Dec 01 21:17:38 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:17:38 studio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:17:38 studio dhcpcd[851]: eth0: adding default route Dec 01 21:17:38 studio sh[1332]: eth0=eth0 Dec 01 21:17:38 studio ifplugd(eth0)[1076]: client: ifup: interface eth0 already configured Dec 01 21:17:38 studio ifplugd(eth0)[1076]: Program executed successfully. Dec 01 21:17:38 studio winbindd[1323]: [2025/12/01 21:17:38.949594, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 01 21:17:38 studio winbindd[1323]: winbindd version 4.17.8-Raspbian started. Dec 01 21:17:38 studio winbindd[1323]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 01 21:17:38 studio winbindd[1323]: [2025/12/01 21:17:38.976828, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Dec 01 21:17:38 studio winbindd[1323]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 01 21:17:39 studio systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 01 21:17:39 studio systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 01 21:17:39 studio volumio[1161]: info: Loading plugin "upnp_browser"... Dec 01 21:17:39 studio ntpd[1056]: IO: Listen normally on 3 eth0 169.254.228.194:123 Dec 01 21:17:39 studio ntpd[1056]: IO: new interface(s) found: waking up resolver Dec 01 21:17:39 studio ntpd[1056]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:17:40 studio smbd[1344]: [2025/12/01 21:17:40.199053, 0] ../../source3/smbd/server.c:1741(main) Dec 01 21:17:40 studio smbd[1344]: smbd version 4.17.8-Raspbian started. Dec 01 21:17:40 studio smbd[1344]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 01 21:17:42 studio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 01 21:17:42 studio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 01 21:17:42 studio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 01 21:17:42 studio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 01 21:17:42 studio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 01 21:17:42 studio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 01 21:17:42 studio systemd[1]: Startup finished in 17.940s (kernel) + 33.061s (userspace) = 51.001s. Dec 01 21:17:43 studio volumio[1161]: info: Loading plugin "alarm-clock"... Dec 01 21:17:43 studio volumio[1161]: info: Loading plugin "airplay_emulation"... Dec 01 21:17:43 studio volumio[1161]: info: Starting Shairport Sync Dec 01 21:17:43 studio volumio[1161]: info: Loading plugin "last_100"... Dec 01 21:17:43 studio volumio[1161]: info: Loading plugin "webradio"... Dec 01 21:17:43 studio volumio[1161]: info: Loading plugin "i2s_dacs"... Dec 01 21:17:43 studio volumio[1161]: info: Loading plugin "volumiodiscovery"... Dec 01 21:17:43 studio volumio[1161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 21:17:43 studio volumio[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:17:43 studio volumio[1161]: *** WARNING *** For more information see Dec 01 21:17:43 studio volumio[1161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 21:17:43 studio volumio[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:17:43 studio node[1161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 21:17:43 studio volumio[1161]: *** WARNING *** For more information see Dec 01 21:17:43 studio node[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:17:43 studio node[1161]: *** WARNING *** For more information see Dec 01 21:17:43 studio node[1161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 21:17:43 studio node[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:17:43 studio node[1161]: *** WARNING *** For more information see Dec 01 21:17:43 studio volumio[1161]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 01 21:17:43 studio volumio[1161]: info: Discovery: Started advertising with name: Studio Dec 01 21:17:43 studio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 01 21:17:44 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:17:44 studio volumio[1161]: info: Loading plugin "bandcamp"... Dec 01 21:17:45 studio volumio-remote-updater[661]: [2025-12-01 21:17:45] [connect] Successful connection Dec 01 21:17:48 studio volumio[1161]: info: Loading plugin "calmradio"... Dec 01 21:17:50 studio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 01 21:17:50 studio volumio[1161]: info: Loading plugin "mixcloud"... Dec 01 21:17:51 studio ntpd[1056]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 01 21:17:51 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:17:51 studio ntpd[1056]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 01 21:17:52 studio ntpd[1056]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:17:52 studio volumio[1161]: info: Loading plugin "soundcloud"... Dec 01 21:17:53 studio volumio[1161]: info: Loading plugin "spop"... Dec 01 21:17:56 studio volumio[1161]: info: Plugin squeezelite_mc is not enabled Dec 01 21:17:56 studio volumio[1161]: info: Loading plugin "youtube2"... Dec 01 21:17:58 studio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 01 21:17:59 studio volumio[1161]: info: Loading plugin "ytcr"... Dec 01 21:18:00 studio volumio-remote-updater[661]: [2025-12-01 21:18:00] [connect] Successful connection Dec 01 21:18:02 studio volumio[1161]: info: Loading plugin "ytmusic"... Dec 01 21:18:04 studio ntpd[1056]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 01 21:18:04 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:18:04 studio ntpd[1056]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 01 21:18:05 studio volumio[1161]: info: Loading plugin "autostart"... Dec 01 21:18:05 studio ntpd[1056]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:18:05 studio volumio[1161]: info: Applying required configuration parameters for plugin autostart Dec 01 21:18:05 studio volumio[1161]: info: AutoStart - onVolumioStart - read config.json Dec 01 21:18:05 studio volumio[1161]: info: Loading plugin "outputs"... Dec 01 21:18:05 studio volumio[1161]: info: Loading plugin "albumart"... Dec 01 21:18:05 studio volumio[1161]: info: Plugin example_plugin is not enabled Dec 01 21:18:05 studio volumio[1161]: info: Loading plugin "inputs"... Dec 01 21:18:05 studio volumio[1161]: info: Loading plugin "updater_comm"... Dec 01 21:18:06 studio volumio[1161]: info: Plugin mpdemulation is not enabled Dec 01 21:18:06 studio volumio[1161]: info: Loading plugin "rest_api"... Dec 01 21:18:06 studio volumio[1161]: info: Loading plugin "websocket"... Dec 01 21:18:06 studio volumio[1161]: info: Starting Socket.io Server version 1.7.4 Dec 01 21:18:06 studio volumio[1161]: info: Loading plugin "fusiondsp"... Dec 01 21:18:08 studio volumio[1161]: info: Applying required configuration parameters for plugin fusiondsp Dec 01 21:18:08 studio volumio[1417]: Forking 3 albumart workers Dec 01 21:18:08 studio volumio[1161]: info: Loading plugin "80s80s"... Dec 01 21:18:12 studio volumio[1161]: info: Applying required configuration parameters for plugin 80s80s Dec 01 21:18:12 studio volumio[1161]: info: [1764609492814] [80s80s] API delay: 30 Dec 01 21:18:12 studio volumio[1161]: info: Loading plugin "moosbox"... Dec 01 21:18:15 studio volumio-remote-updater[661]: [2025-12-01 21:18:15] [connect] Successful connection Dec 01 21:18:15 studio ntpd[1056]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 01 21:18:15 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:18:15 studio ntpd[1056]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 01 21:18:16 studio ntpd[1056]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:18:16 studio volumio[1161]: info: Applying required configuration parameters for plugin moosbox Dec 01 21:18:16 studio volumio[1161]: info: Loading plugin "motherearthradio"... Dec 01 21:18:19 studio volumio[1448]: Starting albumart workers Dec 01 21:18:19 studio volumio[1447]: Starting albumart workers Dec 01 21:18:20 studio volumio[1161]: info: Applying required configuration parameters for plugin motherearthradio Dec 01 21:18:20 studio volumio[1161]: info: [1764609500131] [MotherEarth] API delay: 5 Dec 01 21:18:20 studio volumio[1161]: info: Loading plugin "Systeminfo"... Dec 01 21:18:21 studio volumio[1446]: Starting albumart workers Dec 01 21:18:25 studio volumio[1161]: info: Loading plugin "touch_display"... Dec 01 21:18:26 studio dhcpcd[648]: eth0: offered 192.168.115.17 from 192.168.115.194 Dec 01 21:18:26 studio dhcpcd[648]: eth0: probing address 192.168.115.17/24 Dec 01 21:18:26 studio ntpd[1056]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 01 21:18:26 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:18:26 studio ntpd[1056]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 01 21:18:27 studio volumio[1161]: info: Applying required configuration parameters for plugin touch_display Dec 01 21:18:28 studio volumio[1161]: info: Loading i18n strings for locale en Dec 01 21:18:28 studio volumio[1161]: Updating browse sources language Dec 01 21:18:28 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:30 studio volumio-remote-updater[661]: [2025-12-01 21:18:30] [connect] Successful connection Dec 01 21:18:30 studio dhcpcd[648]: eth0: leased 192.168.115.17 for 60 seconds Dec 01 21:18:30 studio dhcpcd[648]: eth0: adding route to 192.168.115.0/24 Dec 01 21:18:30 studio avahi-daemon[636]: Registering new address record for 192.168.115.17 on eth0.IPv4. Dec 01 21:18:30 studio dhcpcd[648]: eth0: changing default route via 192.168.115.194 Dec 01 21:18:30 studio dhcpcd[851]: eth0: pid 648 deleted default route Dec 01 21:18:30 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:18:30 studio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:30 studio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 01 21:18:30 studio systemd[1]: welcome.service: Deactivated successfully. Dec 01 21:18:30 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 01 21:18:30 studio volumio[1161]: info: CoreCommandRouter::initPlayerControls Dec 01 21:18:30 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:31 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:18:31 studio welcome[1562]: Resolved ip:[2] 169.254.228.194 192.168.115.17 Dec 01 21:18:31 studio avahi-daemon[636]: Withdrawing address record for 169.254.228.194 on eth0. Dec 01 21:18:31 studio avahi-daemon[636]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.228.194. Dec 01 21:18:31 studio avahi-daemon[636]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.115.17. Dec 01 21:18:31 studio dhcpcd[851]: eth0: pid 648 deleted IP address 169.254.228.194/16 Dec 01 21:18:31 studio dhcpcd[648]: eth0: deleting route to 169.254.0.0/16 Dec 01 21:18:31 studio dhcpcd[851]: eth0: deleting route to 169.254.0.0/16 Dec 01 21:18:31 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:18:31 studio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:18:31 studio dhcpcd[851]: eth0: probing for an IPv4LL address Dec 01 21:18:31 studio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:31 studio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 01 21:18:31 studio systemd[1]: welcome.service: Deactivated successfully. Dec 01 21:18:31 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 01 21:18:31 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 01 21:18:31 studio volumio[1161]: Express server listening on port 3000 Dec 01 21:18:31 studio volumio[1161]: [Metrics] WebUI: 61s 397.81ms Dec 01 21:18:31 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:18:31 studio welcome[1604]: Resolved ip:[1] 192.168.115.17 Dec 01 21:18:31 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:18:31 studio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:31 studio volumio[1161]: info: CoreStateMachine::resetVolumioState Dec 01 21:18:31 studio volumio[1161]: info: CoreStateMachine::getcurrentVolume Dec 01 21:18:31 studio volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:18:31 studio sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 21:18:31 studio sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:31 studio sudo[1617]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:31 studio sudo[1614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 21:18:31 studio sudo[1614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:32 studio sudo[1614]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:32 studio volumio[1161]: info: Volumio Network Manager: Network status updated: 0 Dec 01 21:18:32 studio volumio[1161]: info: Cannot mount NAS attasdsm at system boot, trial number 1 ,retrying in 5 seconds Dec 01 21:18:32 studio ntpd[1056]: IO: Listen normally on 4 eth0 192.168.115.17:123 Dec 01 21:18:32 studio ntpd[1056]: IO: Deleting interface #3 eth0, 169.254.228.194#123, interface stats: received=0, sent=0, dropped=0, active_time=53 secs Dec 01 21:18:32 studio ntpd[1056]: IO: new interface(s) found: waking up resolver Dec 01 21:18:32 studio ntpd[1056]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 01 21:18:32 studio dhcpcd[851]: eth0: offered 192.168.115.249 from 192.168.115.1 Dec 01 21:18:32 studio dhcpcd[851]: eth0: probing address 192.168.115.249/24 Dec 01 21:18:33 studio volumio[1161]: info: VolumeController:: Volume=51 Mute =false Dec 01 21:18:33 studio volumio[1161]: info: CoreStateMachine::pushState Dec 01 21:18:33 studio volumio[1161]: info: CorePlayQueue::getTrack 0 Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::volumioPushState Dec 01 21:18:33 studio volumio[1161]: info: CoreStateMachine::updateTrackBlock Dec 01 21:18:33 studio volumio[1161]: info: CorePlayQueue::getTrackBlock Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:18:33 studio volumio[1161]: info: Reloading queue from file Dec 01 21:18:33 studio volumio[1161]: info: CoreStateMachine::setRepeat null single undefined Dec 01 21:18:33 studio volumio[1161]: info: CoreStateMachine::pushState Dec 01 21:18:33 studio volumio[1161]: info: CorePlayQueue::getTrack 0 Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::volumioPushState Dec 01 21:18:33 studio volumio[1161]: info: CoreStateMachine::setRandom null Dec 01 21:18:33 studio volumio[1161]: info: CoreStateMachine::pushState Dec 01 21:18:33 studio volumio[1161]: info: CorePlayQueue::getTrack 0 Dec 01 21:18:33 studio volumio[1161]: info: CoreCommandRouter::volumioPushState Dec 01 21:18:33 studio volumio[1161]: info: Setting Device type: Raspberry PI Dec 01 21:18:33 studio volumio[1161]: info: Completed loading Core Plugins Dec 01 21:18:33 studio volumio[1161]: info: Preparing to generate the ALSA configuration file Dec 01 21:18:34 studio volumio[1161]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 01 21:18:34 studio volumio[1161]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Dec 01 21:18:34 studio volumio[1161]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 01 21:18:34 studio volumio[1161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 01 21:18:34 studio volumio[1161]: info: Reading ALSA contributions from plugins. Dec 01 21:18:34 studio volumio[1161]: verbose: New Socket.io Connection to 169.254.228.194:3000 from 169.254.69.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 01 21:18:34 studio volumio[1161]: verbose: New Socket.io Connection to 169.254.228.194:3000 from 169.254.69.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 01 21:18:34 studio volumio[1161]: info: VolumeController:: Volume=51 Mute =false Dec 01 21:18:34 studio volumio[1161]: info: CoreStateMachine::pushState Dec 01 21:18:34 studio volumio[1161]: info: CorePlayQueue::getTrack 0 Dec 01 21:18:34 studio volumio[1161]: info: CoreCommandRouter::volumioPushState Dec 01 21:18:34 studio sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 01 21:18:34 studio bash[1395]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 01 21:18:34 studio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 01 21:18:34 studio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 01 21:18:34 studio systemd[1]: setdatetime-helper.service: Consumed 1.186s CPU time. Dec 01 21:18:34 studio sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:34 studio volumio[1161]: verbose: New Socket.io Connection to 169.254.228.194:3000 from 169.254.69.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 01 21:18:34 studio volumio-remote-updater[661]: [2025-12-01 21:18:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1764609510 101 Dec 01 21:18:34 studio volumio[1161]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 01 21:18:35 studio volumio[1161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 01 21:18:35 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 01 21:18:35 studio sudo[1637]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:35 studio volumio[1161]: info: Upmpdcli Daemon Started Dec 01 21:18:35 studio volumio[1161]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Dec 01 21:18:35 studio volumio[1161]: info: Asound.conf file written Dec 01 21:18:35 studio sudo[1653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 01 21:18:35 studio sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:35 studio sudo[1653]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:35 studio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 01 21:18:35 studio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 01 21:18:35 studio volumio[1161]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 01 21:18:35 studio volumio[1161]: info: Output device has changed, restarting MPD Dec 01 21:18:35 studio sudo[1659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 21:18:35 studio sudo[1659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:35 studio volumio[1161]: info: Output device has changed, restarting Shairport Sync Dec 01 21:18:35 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:35 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:18:36 studio sudo[1659]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:36 studio volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:18:36 studio sudo[1661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 21:18:36 studio sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:36 studio volumio[1161]: info: ___________ START PLUGINS ___________ Dec 01 21:18:36 studio volumio[1161]: info: ControllerMpd::onStart: Initializing MPD Dec 01 21:18:36 studio volumio[1161]: info: Creating MPD Configuration file Dec 01 21:18:36 studio dhcpcd[851]: eth0: using IPv4LL address 169.254.109.84 Dec 01 21:18:36 studio avahi-daemon[636]: Registering new address record for 169.254.109.84 on eth0.IPv4. Dec 01 21:18:36 studio dhcpcd[851]: eth0: adding route to 169.254.0.0/16 Dec 01 21:18:36 studio dhcpcd[851]: eth0: adding default route Dec 01 21:18:36 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 01 21:18:36 studio dhcpcd[648]: eth0: pid 851 deleted default route via 192.168.115.194 Dec 01 21:18:36 studio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 01 21:18:36 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:18:36 studio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:36 studio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 01 21:18:36 studio systemd[1]: welcome.service: Deactivated successfully. Dec 01 21:18:36 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 01 21:18:36 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 01 21:18:36 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:36 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:18:36 studio volumio[1161]: info: [1764609516576] CoreMusicLibrary::Adding element Media Servers Dec 01 21:18:36 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:36 studio welcome[1687]: Resolved ip:[2] 192.168.115.17 169.254.109.84 Dec 01 21:18:36 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:18:36 studio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:36 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:36 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:18:36 studio sudo[1675]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 21:18:36 studio sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 01 21:18:36 studio sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 21:18:36 studio sudo[1680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 21:18:36 studio sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:36 studio sudo[1680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:36 studio sudo[1699]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 01 21:18:36 studio sudo[1675]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:36 studio sudo[1670]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:37 studio volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:18:37 studio volumio[1161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:18:37 studio systemd[1]: mpd.service: Deactivated successfully. Dec 01 21:18:37 studio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 01 21:18:37 studio systemd[1]: mpd.socket: Deactivated successfully. Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:37 studio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 01 21:18:37 studio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 01 21:18:37 studio volumio[1161]: info: [1764609517256] CoreMusicLibrary::Adding element Last_100 Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:37 studio volumio[1161]: info: [1764609517277] CoreMusicLibrary::Adding element Webradio Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:37 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 01 21:18:37 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:18:37 studio volumio[1161]: info: Initializing BBC Radios Dec 01 21:18:37 studio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 01 21:18:37 studio sudo[1710]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 21:18:37 studio sudo[1710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 01 21:18:37 studio sudo[1714]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 01 21:18:37 studio sudo[1710]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:37 studio dhcpcd[851]: eth0: leased 192.168.115.249 for infinity Dec 01 21:18:37 studio avahi-daemon[636]: Registering new address record for 192.168.115.249 on eth0.IPv4. Dec 01 21:18:37 studio dhcpcd[851]: eth0: adding route to 192.168.115.0/24 Dec 01 21:18:37 studio dhcpcd[851]: eth0: changing default route via 192.168.115.1 Dec 01 21:18:37 studio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:37 studio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 01 21:18:37 studio systemd[1]: welcome.service: Deactivated successfully. Dec 01 21:18:37 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 01 21:18:37 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 01 21:18:37 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:18:37 studio welcome[1739]: Resolved ip:[3] 192.168.115.17 169.254.109.84 192.168.115.249 Dec 01 21:18:38 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:18:38 studio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:38 studio avahi-daemon[636]: Withdrawing address record for 169.254.109.84 on eth0. Dec 01 21:18:38 studio dhcpcd[851]: eth0: deleting route to 169.254.0.0/16 Dec 01 21:18:38 studio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:38 studio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 01 21:18:38 studio systemd[1]: welcome.service: Deactivated successfully. Dec 01 21:18:38 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 01 21:18:38 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:18:38 studio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 01 21:18:38 studio welcome[1769]: Resolved ip:[2] 192.168.115.17 192.168.115.249 Dec 01 21:18:38 studio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 01 21:18:38 studio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:38 studio volumio[1161]: info: [1764609518335] CoreMusicLibrary::Adding element Bandcamp Discover Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:38 studio volumio[1161]: info: Adding Calm Radio to Browse Sources Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:38 studio volumio[1161]: info: [1764609518457] CoreMusicLibrary::Adding element Calm Radio Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:38 studio volumio[1161]: info: [1764609518592] CoreMusicLibrary::Adding element Mixcloud Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Mixcloud Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:38 studio volumio[1161]: info: [1764609518790] CoreMusicLibrary::Adding element SoundCloud Dec 01 21:18:38 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source Mixcloud Dec 01 21:18:38 studio volumio[1161]: Cannot find translation for source SoundCloud Dec 01 21:18:38 studio volumio[1161]: info: Creating Spotify config file Dec 01 21:18:39 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:39 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:39 studio volumio[1161]: info: [1764609519166] CoreMusicLibrary::Adding element YouTube2 Dec 01 21:18:39 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source Mixcloud Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source SoundCloud Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source YouTube2 Dec 01 21:18:39 studio ntpd[1056]: IO: Listen normally on 5 eth0 192.168.115.249:123 Dec 01 21:18:39 studio ntpd[1056]: IO: new interface(s) found: waking up resolver Dec 01 21:18:39 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:39 studio volumio[1161]: info: [1764609519454] CoreMusicLibrary::Adding element YouTube Music Dec 01 21:18:39 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source Mixcloud Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source SoundCloud Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source YouTube2 Dec 01 21:18:39 studio volumio[1161]: Cannot find translation for source YouTube Music Dec 01 21:18:39 studio volumio[1161]: info: AutoStart - onStart Dec 01 21:18:39 studio volumio[1161]: info: FusionDsp - CamillaDSP binary found. Dec 01 21:18:39 studio volumio[1161]: info: Loading i18n strings for locale en Dec 01 21:18:39 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 01 21:18:40 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:40 studio volumio[1161]: info: FusionDsp - mixtype--------------------- Hardware Dec 01 21:18:40 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:40 studio volumio[1161]: info: [1764609520162] CoreMusicLibrary::Adding element 80s80s Radio Dec 01 21:18:40 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Mixcloud Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source SoundCloud Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source YouTube2 Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source YouTube Music Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source 80s80s Radio Dec 01 21:18:40 studio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:18:40 studio volumio[1161]: info: [1764609520284] CoreMusicLibrary::Adding element Mother Earth Radio Dec 01 21:18:40 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Bandcamp Discover Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Calm Radio Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Mixcloud Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source SoundCloud Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source YouTube2 Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source YouTube Music Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source 80s80s Radio Dec 01 21:18:40 studio volumio[1161]: Cannot find translation for source Mother Earth Radio Dec 01 21:18:40 studio volumio[1161]: info: Loading i18n strings for locale en Dec 01 21:18:40 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:18:40 studio volumio[1161]: info: Volumio Calling Home Dec 01 21:18:40 studio sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Dec 01 21:18:41 studio sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:41 studio sudo[1792]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:41 studio volumio[1161]: info: Preparing to generate the ALSA configuration file Dec 01 21:18:42 studio sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 01 21:18:42 studio sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:42 studio sudo[1798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 01 21:18:42 studio sudo[1798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:42 studio sudo[1802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 01 21:18:42 studio sudo[1802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:42 studio systemd[1]: Reloading. Dec 01 21:18:46 studio sudo[1800]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:46 studio systemd[1]: Reloading. Dec 01 21:18:46 studio mpd[1715]: 2025-12-01T21:18:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 21:18:46 studio volumio[1161]: info: camilladsp service started and running in background, instance 1 Dec 01 21:18:46 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:18:46 studio sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=attas,password=RahafUae@#$0555%,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.115.230/music /mnt/NAS/attasdsm Dec 01 21:18:46 studio sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:46 studio volumio[1161]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Dec 01 21:18:46 studio kernel: CIFS: Attempting to mount //192.168.115.230/music Dec 01 21:18:47 studio volumio[1161]: info: FusionDsp loaded Dec 01 21:18:47 studio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 21:18:47 studio volumio[1161]: error: FusionDsp - Socket connection not established Dec 01 21:18:47 studio volumio[1161]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 01 21:18:47 studio volumio[1161]: info: Adding Signal Path Element [object Object] Dec 01 21:18:47 studio volumio[1161]: info: Adding fusiondspeq DSP Signal Path Element Dec 01 21:18:47 studio volumio[1161]: info: FusionDsp - ---- installed callbackRead Dec 01 21:18:47 studio volumio[1161]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 01 21:18:47 studio kernel: CIFS: VFS: Autodisabling the use of server inode numbers on new server Dec 01 21:18:47 studio kernel: CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS) Dec 01 21:18:47 studio kernel: CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message. Dec 01 21:18:47 studio sudo[1824]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:47 studio sudo[1869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 01 21:18:47 studio sudo[1869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:47 studio volumio[1161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 01 21:18:47 studio volumio[1161]: info: Reading ALSA contributions from plugins. Dec 01 21:18:47 studio volumio[1161]: info: touch_display: No backlight interface detected. Dec 01 21:18:47 studio volumio[1161]: info: Volumio Network Manager: Network status updated: 1 Dec 01 21:18:47 studio nmbd[1123]: [2025/12/01 21:18:47.985201, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 01 21:18:47 studio nmbd[1123]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Dec 01 21:18:48 studio volumio[1161]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 01 21:18:48 studio volumio[1161]: info: MPD Permissions set Dec 01 21:18:48 studio volumio[1161]: info: MPD Permissions set Dec 01 21:18:48 studio volumio[1161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:18:48 studio volumio[1161]: Error: addMembership ENODEV Dec 01 21:18:48 studio volumio[1161]: at Socket.addMembership (node:dgram:860:11) Dec 01 21:18:48 studio volumio[1161]: at addMembership (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Dec 01 21:18:48 studio volumio[1161]: at runNextTicks (node:internal/process/task_queues:60:5) Dec 01 21:18:48 studio volumio[1161]: at process.processTimers (node:internal/timers:511:9) { Dec 01 21:18:48 studio volumio[1161]: errno: -19, Dec 01 21:18:48 studio volumio[1161]: code: 'ENODEV', Dec 01 21:18:48 studio volumio[1161]: syscall: 'addMembership' Dec 01 21:18:48 studio volumio[1161]: } Dec 01 21:18:48 studio volumio[1161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:18:48 studio systemd[1]: Started mpd.service - Music Player Daemon. Dec 01 21:18:48 studio sudo[1680]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:48 studio sudo[1802]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:48 studio sudo[1661]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:48 studio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Dec 01 21:18:48 studio sudo[1869]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:48 studio sudo[1889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Dec 01 21:18:48 studio sudo[1889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:49 studio sudo[1798]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:49 studio sudo[1889]: pam_unix(sudo:session): session closed for user root Dec 01 21:18:55 studio sudo[1921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-01 21:17' Dec 01 21:18:55 studio sudo[1921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 01 21:18:57 studio ntpd[1056]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 01 21:18:57 studio ntpd[1056]: DNS: dns_check: DNS error: -11, System error Dec 01 21:18:57 studio ntpd[1056]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"