-- Logs begin at Fri 2025-12-26 23:50:51 +07, end at Fri 2025-12-26 23:51:59 +07. -- Dec 26 23:50:51 volumio-livingroom kernel: Booting Linux on physical CPU 0x0 Dec 26 23:50:51 volumio-livingroom kernel: Linux version 6.6.62-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) #1816 SMP Mon Nov 18 15:33:33 GMT 2024 Dec 26 23:50:51 volumio-livingroom kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Dec 26 23:50:51 volumio-livingroom kernel: CPU: div instructions available: patching division code Dec 26 23:50:51 volumio-livingroom kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Dec 26 23:50:51 volumio-livingroom kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 Dec 26 23:50:51 volumio-livingroom kernel: random: crng init done Dec 26 23:50:51 volumio-livingroom kernel: Memory policy: Data cache writealloc Dec 26 23:50:51 volumio-livingroom kernel: Reserved memory: created CMA memory pool at 0x0000000029400000, size 64 MiB Dec 26 23:50:51 volumio-livingroom kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 26 23:50:51 volumio-livingroom kernel: OF: reserved mem: 0x0000000029400000..0x000000002d3fffff (65536 KiB) map reusable linux,cma Dec 26 23:50:51 volumio-livingroom kernel: OF: reserved mem: 0x000000003ef651c0..0x000000003ef655ab (0 KiB) nomap non-reusable nvram@0 Dec 26 23:50:51 volumio-livingroom kernel: Zone ranges: Dec 26 23:50:51 volumio-livingroom kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Dec 26 23:50:51 volumio-livingroom kernel: Normal empty Dec 26 23:50:51 volumio-livingroom kernel: HighMem [mem 0x0000000030000000-0x000000007fffffff] Dec 26 23:50:51 volumio-livingroom kernel: Movable zone start for each node Dec 26 23:50:51 volumio-livingroom kernel: Early memory node ranges Dec 26 23:50:51 volumio-livingroom kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Dec 26 23:50:51 volumio-livingroom kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] Dec 26 23:50:51 volumio-livingroom kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff] Dec 26 23:50:51 volumio-livingroom kernel: percpu: Embedded 18 pages/cpu s42004 r8192 d23532 u73728 Dec 26 23:50:51 volumio-livingroom kernel: pcpu-alloc: s42004 r8192 d23532 u73728 alloc=18*4096 Dec 26 23:50:51 volumio-livingroom kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 26 23:50:51 volumio-livingroom kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:0D:B7:A8 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=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 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 26 23:50:51 volumio-livingroom kernel: cgroup: Disabling memory control group subsystem Dec 26 23:50:51 volumio-livingroom kernel: Unknown kernel command line parameters "splash nodebug numa_policy=interleave imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Dec 26 23:50:51 volumio-livingroom kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: Built 1 zonelists, mobility grouping on. Total pages: 489792 Dec 26 23:50:51 volumio-livingroom kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 26 23:50:51 volumio-livingroom kernel: software IO TLB: area num 4. Dec 26 23:50:51 volumio-livingroom kernel: software IO TLB: mapped [mem 0x0000000024200000-0x0000000028200000] (64MB) Dec 26 23:50:51 volumio-livingroom kernel: Memory: 1767036K/1966080K available (12288K kernel code, 1527K rwdata, 3492K rodata, 2048K init, 628K bss, 133508K reserved, 65536K cma-reserved, 1179648K highmem) Dec 26 23:50:51 volumio-livingroom kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 26 23:50:51 volumio-livingroom kernel: ftrace: allocating 38825 entries in 114 pages Dec 26 23:50:51 volumio-livingroom kernel: ftrace: allocated 114 pages with 4 groups Dec 26 23:50:51 volumio-livingroom kernel: trace event string verifier disabled Dec 26 23:50:51 volumio-livingroom kernel: rcu: Hierarchical RCU implementation. Dec 26 23:50:51 volumio-livingroom kernel: Rude variant of Tasks RCU enabled. Dec 26 23:50:51 volumio-livingroom kernel: Tracing variant of Tasks RCU enabled. Dec 26 23:50:51 volumio-livingroom kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Dec 26 23:50:51 volumio-livingroom kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Dec 26 23:50:51 volumio-livingroom kernel: GIC: Using split EOI/Deactivate mode Dec 26 23:50:51 volumio-livingroom kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 26 23:50:51 volumio-livingroom kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 26 23:50:51 volumio-livingroom kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 26 23:50:51 volumio-livingroom kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 26 23:50:51 volumio-livingroom kernel: Switching to timer-based delay loop, resolution 18ns Dec 26 23:50:51 volumio-livingroom kernel: Console: colour dummy device 80x30 Dec 26 23:50:51 volumio-livingroom kernel: printk: console [tty1] enabled Dec 26 23:50:51 volumio-livingroom kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Dec 26 23:50:51 volumio-livingroom kernel: CPU: Testing write buffer coherency: ok Dec 26 23:50:51 volumio-livingroom kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Dec 26 23:50:51 volumio-livingroom kernel: pid_max: default: 32768 minimum: 301 Dec 26 23:50:51 volumio-livingroom kernel: LSM: initializing lsm=capability,integrity Dec 26 23:50:51 volumio-livingroom kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Dec 26 23:50:51 volumio-livingroom kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 26 23:50:51 volumio-livingroom kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 26 23:50:51 volumio-livingroom kernel: Setting up static identity map for 0x200000 - 0x20003c Dec 26 23:50:51 volumio-livingroom kernel: rcu: Hierarchical SRCU implementation. Dec 26 23:50:51 volumio-livingroom kernel: rcu: Max phase no-delay instances is 1000. Dec 26 23:50:51 volumio-livingroom kernel: smp: Bringing up secondary CPUs ... Dec 26 23:50:51 volumio-livingroom kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Dec 26 23:50:51 volumio-livingroom kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Dec 26 23:50:51 volumio-livingroom kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Dec 26 23:50:51 volumio-livingroom kernel: smp: Brought up 1 node, 4 CPUs Dec 26 23:50:51 volumio-livingroom kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Dec 26 23:50:51 volumio-livingroom kernel: CPU: All CPU(s) started in HYP mode. Dec 26 23:50:51 volumio-livingroom kernel: CPU: Virtualization extensions available. Dec 26 23:50:51 volumio-livingroom kernel: devtmpfs: initialized Dec 26 23:50:51 volumio-livingroom kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Dec 26 23:50:51 volumio-livingroom kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Dec 26 23:50:51 volumio-livingroom kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: pinctrl core: initialized pinctrl subsystem Dec 26 23:50:51 volumio-livingroom kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 26 23:50:51 volumio-livingroom kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Dec 26 23:50:51 volumio-livingroom kernel: audit: initializing netlink subsys (disabled) Dec 26 23:50:51 volumio-livingroom kernel: audit: type=2000 audit(0.010:1): state=initialized audit_enabled=0 res=1 Dec 26 23:50:51 volumio-livingroom kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 26 23:50:51 volumio-livingroom kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Dec 26 23:50:51 volumio-livingroom kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Dec 26 23:50:51 volumio-livingroom kernel: Serial: AMBA PL011 UART driver Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Dec 26 23:50:51 volumio-livingroom kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-11T15:48:13, variant start Dec 26 23:50:51 volumio-livingroom kernel: raspberrypi-firmware soc:firmware: Firmware hash is 903570ba72a9e117f92e5499de439f59dd96e417 Dec 26 23:50:51 volumio-livingroom kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 Dec 26 23:50:51 volumio-livingroom kernel: SCSI subsystem initialized Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver usbfs Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver hub Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new device driver usb Dec 26 23:50:51 volumio-livingroom kernel: pps_core: LinuxPPS API ver. 1 registered Dec 26 23:50:51 volumio-livingroom kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 26 23:50:51 volumio-livingroom kernel: PTP clock support registered Dec 26 23:50:51 volumio-livingroom kernel: vgaarb: loaded Dec 26 23:50:51 volumio-livingroom kernel: clocksource: Switched to clocksource arch_sys_counter Dec 26 23:50:51 volumio-livingroom kernel: VFS: Disk quotas dquot_6.6.0 Dec 26 23:50:51 volumio-livingroom kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Dec 26 23:50:51 volumio-livingroom kernel: FS-Cache: Loaded Dec 26 23:50:51 volumio-livingroom kernel: CacheFiles: Loaded Dec 26 23:50:51 volumio-livingroom kernel: NET: Registered PF_INET protocol family Dec 26 23:50:51 volumio-livingroom kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: TCP: Hash tables configured (established 8192 bind 8192) Dec 26 23:50:51 volumio-livingroom kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 26 23:50:51 volumio-livingroom kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 26 23:50:51 volumio-livingroom kernel: RPC: Registered named UNIX socket transport module. Dec 26 23:50:51 volumio-livingroom kernel: RPC: Registered udp transport module. Dec 26 23:50:51 volumio-livingroom kernel: RPC: Registered tcp transport module. Dec 26 23:50:51 volumio-livingroom kernel: RPC: Registered tcp-with-tls transport module. Dec 26 23:50:51 volumio-livingroom kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 26 23:50:51 volumio-livingroom kernel: PCI: CLS 0 bytes, default 64 Dec 26 23:50:51 volumio-livingroom kernel: Trying to unpack rootfs image as initramfs... Dec 26 23:50:51 volumio-livingroom kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Dec 26 23:50:51 volumio-livingroom kernel: Freeing initrd memory: 28132K Dec 26 23:50:51 volumio-livingroom kernel: Initialise system trusted keyrings Dec 26 23:50:51 volumio-livingroom kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Dec 26 23:50:51 volumio-livingroom kernel: zbud: loaded Dec 26 23:50:51 volumio-livingroom kernel: NFS: Registering the id_resolver key type Dec 26 23:50:51 volumio-livingroom kernel: Key type id_resolver registered Dec 26 23:50:51 volumio-livingroom kernel: Key type id_legacy registered Dec 26 23:50:51 volumio-livingroom kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 26 23:50:51 volumio-livingroom kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 26 23:50:51 volumio-livingroom kernel: Key type asymmetric registered Dec 26 23:50:51 volumio-livingroom kernel: Asymmetric key parser 'x509' registered Dec 26 23:50:51 volumio-livingroom kernel: bounce: pool size: 64 pages Dec 26 23:50:51 volumio-livingroom kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 26 23:50:51 volumio-livingroom kernel: io scheduler mq-deadline registered Dec 26 23:50:51 volumio-livingroom kernel: io scheduler kyber registered Dec 26 23:50:51 volumio-livingroom kernel: io scheduler bfq registered Dec 26 23:50:51 volumio-livingroom kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Dec 26 23:50:51 volumio-livingroom kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Dec 26 23:50:51 volumio-livingroom kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Dec 26 23:50:51 volumio-livingroom kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x067fffffff -> 0x0080000000 Dec 26 23:50:51 volumio-livingroom kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 Dec 26 23:50:51 volumio-livingroom kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Dec 26 23:50:51 volumio-livingroom kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 26 23:50:51 volumio-livingroom kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x67fffffff] (bus address [0x80000000-0xffffffff]) Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 26 23:50:51 volumio-livingroom kernel: PCI: bus0: Fast back to back transfers disabled Dec 26 23:50:51 volumio-livingroom kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Dec 26 23:50:51 volumio-livingroom kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Dec 26 23:50:51 volumio-livingroom kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Dec 26 23:50:51 volumio-livingroom kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Dec 26 23:50:51 volumio-livingroom kernel: PCI: bus1: Fast back to back transfers disabled Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 26 23:50:51 volumio-livingroom kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 26 23:50:51 volumio-livingroom kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Dec 26 23:50:51 volumio-livingroom kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Dec 26 23:50:51 volumio-livingroom kernel: bcm2708_fb soc:fb: FB found 1 display(s) Dec 26 23:50:51 volumio-livingroom kernel: Console: switching to colour frame buffer device 80x30 Dec 26 23:50:51 volumio-livingroom kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Dec 26 23:50:51 volumio-livingroom kernel: iproc-rng200 fe104000.rng: hwrng registered Dec 26 23:50:51 volumio-livingroom kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Dec 26 23:50:51 volumio-livingroom kernel: brd: module loaded Dec 26 23:50:51 volumio-livingroom kernel: loop: module loaded Dec 26 23:50:51 volumio-livingroom kernel: Loading iSCSI transport class v2.0-870. Dec 26 23:50:51 volumio-livingroom kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Dec 26 23:50:51 volumio-livingroom kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new device driver r8152-cfgselector Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver r8152 Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver lan78xx Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver smsc95xx Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0700240000000890 Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Dec 26 23:50:51 volumio-livingroom kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Dec 26 23:50:51 volumio-livingroom kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Dec 26 23:50:51 volumio-livingroom kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 26 23:50:51 volumio-livingroom kernel: usb usb1: Product: xHCI Host Controller Dec 26 23:50:51 volumio-livingroom kernel: usb usb1: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Dec 26 23:50:51 volumio-livingroom kernel: usb usb1: SerialNumber: 0000:01:00.0 Dec 26 23:50:51 volumio-livingroom kernel: hub 1-0:1.0: USB hub found Dec 26 23:50:51 volumio-livingroom kernel: hub 1-0:1.0: 1 port detected Dec 26 23:50:51 volumio-livingroom kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Dec 26 23:50:51 volumio-livingroom kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 26 23:50:51 volumio-livingroom kernel: usb usb2: Product: xHCI Host Controller Dec 26 23:50:51 volumio-livingroom kernel: usb usb2: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Dec 26 23:50:51 volumio-livingroom kernel: usb usb2: SerialNumber: 0000:01:00.0 Dec 26 23:50:51 volumio-livingroom kernel: hub 2-0:1.0: USB hub found Dec 26 23:50:51 volumio-livingroom kernel: hub 2-0:1.0: 4 ports detected Dec 26 23:50:51 volumio-livingroom kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 26 23:50:51 volumio-livingroom kernel: dwc_otg: FIQ enabled Dec 26 23:50:51 volumio-livingroom kernel: dwc_otg: NAK holdoff enabled Dec 26 23:50:51 volumio-livingroom kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 26 23:50:51 volumio-livingroom kernel: Module dwc_common_port init Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver uas Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver usb-storage Dec 26 23:50:51 volumio-livingroom kernel: mousedev: PS/2 mouse device common for all mice Dec 26 23:50:51 volumio-livingroom kernel: sdhci: Secure Digital Host Controller Interface driver Dec 26 23:50:51 volumio-livingroom kernel: sdhci: Copyright(c) Pierre Ossman Dec 26 23:50:51 volumio-livingroom kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 26 23:50:51 volumio-livingroom kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 26 23:50:51 volumio-livingroom kernel: hid: raw HID events driver (C) Jiri Kosina Dec 26 23:50:51 volumio-livingroom kernel: usbcore: registered new interface driver usbhid Dec 26 23:50:51 volumio-livingroom kernel: usbhid: USB HID core driver Dec 26 23:50:51 volumio-livingroom kernel: Initializing XFRM netlink socket Dec 26 23:50:51 volumio-livingroom kernel: NET: Registered PF_PACKET protocol family Dec 26 23:50:51 volumio-livingroom kernel: Key type dns_resolver registered Dec 26 23:50:51 volumio-livingroom kernel: Registering SWP/SWPB emulation handler Dec 26 23:50:51 volumio-livingroom kernel: registered taskstats version 1 Dec 26 23:50:51 volumio-livingroom kernel: Loading compiled-in X.509 certificates Dec 26 23:50:51 volumio-livingroom kernel: Key type .fscrypt registered Dec 26 23:50:51 volumio-livingroom kernel: Key type fscrypt-provisioning registered Dec 26 23:50:51 volumio-livingroom kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Dec 26 23:50:51 volumio-livingroom kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Dec 26 23:50:51 volumio-livingroom kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Dec 26 23:50:51 volumio-livingroom kernel: serial serial0: tty port ttyAMA1 registered Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 26 23:50:51 volumio-livingroom kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Dec 26 23:50:51 volumio-livingroom kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Dec 26 23:50:51 volumio-livingroom kernel: of_cfs_init Dec 26 23:50:51 volumio-livingroom kernel: of_cfs_init: OK Dec 26 23:50:51 volumio-livingroom kernel: clk: Disabling unused clocks Dec 26 23:50:51 volumio-livingroom kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Dec 26 23:50:51 volumio-livingroom kernel: Freeing unused kernel image (initmem) memory: 2048K Dec 26 23:50:51 volumio-livingroom kernel: Run /init as init process Dec 26 23:50:51 volumio-livingroom kernel: with arguments: Dec 26 23:50:51 volumio-livingroom kernel: /init Dec 26 23:50:51 volumio-livingroom kernel: splash Dec 26 23:50:51 volumio-livingroom kernel: nodebug Dec 26 23:50:51 volumio-livingroom kernel: with environment: Dec 26 23:50:51 volumio-livingroom kernel: HOME=/ Dec 26 23:50:51 volumio-livingroom kernel: TERM=linux Dec 26 23:50:51 volumio-livingroom kernel: numa_policy=interleave Dec 26 23:50:51 volumio-livingroom kernel: imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad Dec 26 23:50:51 volumio-livingroom kernel: imgfile=/volumio_current.sqsh Dec 26 23:50:51 volumio-livingroom kernel: bootpart=UUID=2E0A-AE55 Dec 26 23:50:51 volumio-livingroom kernel: datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 Dec 26 23:50:51 volumio-livingroom kernel: uuidconfig=cmdline.txt Dec 26 23:50:51 volumio-livingroom kernel: pcie_aspm=off Dec 26 23:50:51 volumio-livingroom kernel: bootdelay=7 Dec 26 23:50:51 volumio-livingroom kernel: use_kmsg=no Dec 26 23:50:51 volumio-livingroom kernel: mmc1: new high speed SDIO card at address 0001 Dec 26 23:50:51 volumio-livingroom kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Dec 26 23:50:51 volumio-livingroom kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB Dec 26 23:50:51 volumio-livingroom kernel: mmcblk0: p1 p2 p3 Dec 26 23:50:51 volumio-livingroom kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB (quirks 0x0000c000) Dec 26 23:50:51 volumio-livingroom kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 26 23:50:51 volumio-livingroom kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Dec 26 23:50:51 volumio-livingroom kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 26 23:50:51 volumio-livingroom kernel: usb 1-1: Product: USB2.0 Hub Dec 26 23:50:51 volumio-livingroom kernel: hub 1-1:1.0: USB hub found Dec 26 23:50:51 volumio-livingroom kernel: hub 1-1:1.0: 4 ports detected Dec 26 23:50:51 volumio-livingroom kernel: fuse: init (API version 7.39) Dec 26 23:50:51 volumio-livingroom kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 26 23:50:51 volumio-livingroom kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 26 23:50:51 volumio-livingroom kernel: EXT4-fs (mmcblk0p2): mounted filesystem af3faec2-9654-4fec-8791-d5638a5837ad r/w with ordered data mode. Quota mode: none. Dec 26 23:50:51 volumio-livingroom kernel: loop0: detected capacity change from 0 to 1005568 Dec 26 23:50:51 volumio-livingroom kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 26 23:50:51 volumio-livingroom kernel: EXT4-fs (mmcblk0p3): mounted filesystem 660717eb-e471-4727-aac1-2c263938c365 r/w with ordered data mode. Quota mode: none. Dec 26 23:50:51 volumio-livingroom systemd[1]: System time before build time, advancing clock. Dec 26 23:50:51 volumio-livingroom kernel: NET: Registered PF_INET6 protocol family Dec 26 23:50:51 volumio-livingroom kernel: Segment Routing with IPv6 Dec 26 23:50:51 volumio-livingroom kernel: In-situ OAM (IOAM) with IPv6 Dec 26 23:50:51 volumio-livingroom systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Dec 26 23:50:51 volumio-livingroom systemd[1]: Detected architecture arm. Dec 26 23:50:51 volumio-livingroom systemd[1]: Set hostname to . Dec 26 23:50:51 volumio-livingroom systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Forward Password Requests to Wall Directory Watch. Dec 26 23:50:51 volumio-livingroom systemd[1]: Listening on Journal Socket. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounting POSIX Message Queue File System... Dec 26 23:50:51 volumio-livingroom systemd[1]: Starting Load Kernel Modules... Dec 26 23:50:51 volumio-livingroom systemd[1]: Started dynamicswap service. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounting RPC Pipe File System... Dec 26 23:50:51 volumio-livingroom kernel: i2c_dev: i2c /dev entries driver Dec 26 23:50:51 volumio-livingroom systemd-journald[310]: Journal started Dec 26 23:50:51 volumio-livingroom systemd-journald[310]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 3.7M, max 30.0M, 26.2M free. Dec 26 23:50:51 volumio-livingroom systemd-modules-load[294]: Inserted module 'i2c_dev' Dec 26 23:50:51 volumio-livingroom fake-hwclock[297]: Fri Dec 26 16:50:51 UTC 2025 Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Create System Users. Dec 26 23:50:51 volumio-livingroom systemd[1]: Starting Create Static Device Nodes in /dev... Dec 26 23:50:51 volumio-livingroom systemd[1]: Started udev Coldplug all Devices. Dec 26 23:50:51 volumio-livingroom systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Create Static Device Nodes in /dev. Dec 26 23:50:51 volumio-livingroom systemd[1]: Reached target Local File Systems (Pre). Dec 26 23:50:51 volumio-livingroom systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounting /tmp... Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounting /var/spool/cups... Dec 26 23:50:51 volumio-livingroom systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounting /var/log... Dec 26 23:50:51 volumio-livingroom systemd[1]: Starting udev Kernel Device Manager... Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounted /tmp. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounted /var/spool/cups. Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounted /var/log. Dec 26 23:50:51 volumio-livingroom systemd[1]: Starting Flush Journal to Persistent Storage... Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounting /var/spool/cups/tmp... Dec 26 23:50:51 volumio-livingroom systemd[1]: Mounted /var/spool/cups/tmp. Dec 26 23:50:51 volumio-livingroom systemd-udevd[332]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 26 23:50:51 volumio-livingroom systemd-journald[310]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 3.7M, max 30.0M, 26.2M free. Dec 26 23:50:51 volumio-livingroom systemd[1]: Started udev Kernel Device Manager. Dec 26 23:50:51 volumio-livingroom systemd[1]: Starting Show Plymouth Boot Screen... Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Flush Journal to Persistent Storage. Dec 26 23:50:51 volumio-livingroom systemd[1]: plymouth-start.service: Succeeded. Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Show Plymouth Boot Screen. Dec 26 23:50:51 volumio-livingroom systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 26 23:50:51 volumio-livingroom systemd[1]: Reached target Local Encrypted Volumes. Dec 26 23:50:51 volumio-livingroom systemd[1]: Reached target Paths. Dec 26 23:50:51 volumio-livingroom systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 26 23:50:51 volumio-livingroom systemd-udevd[336]: Using default interface naming scheme 'v240'. Dec 26 23:50:51 volumio-livingroom kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 26 23:50:51 volumio-livingroom kernel: [vc_sm_connected_init]: start Dec 26 23:50:51 volumio-livingroom kernel: [vc_sm_connected_init]: installed successfully Dec 26 23:50:51 volumio-livingroom systemd-udevd[336]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 26 23:50:51 volumio-livingroom kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 26 23:50:51 volumio-livingroom kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 26 23:50:51 volumio-livingroom kernel: mc: Linux media interface: v0.10 Dec 26 23:50:51 volumio-livingroom kernel: videodev: Linux video capture interface: v2.00 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: rpivid feb10000.codec: Device registered as /dev/video19 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: Core ver 2.22 Dec 26 23:50:51 volumio-livingroom kernel: NET: Registered PF_BLUETOOTH protocol family Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: HCI device and connection manager initialized Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: HCI socket layer initialized Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: L2CAP socket layer initialized Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: SCO socket layer initialized Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 26 23:50:51 volumio-livingroom kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 26 23:50:51 volumio-livingroom kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 26 23:50:51 volumio-livingroom kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 26 23:50:51 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 26 23:50:51 volumio-livingroom kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 26 23:50:51 volumio-livingroom kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: HCI UART driver ver 2.3 Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: HCI UART protocol H4 registered Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 26 23:50:51 volumio-livingroom kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 26 23:50:51 volumio-livingroom kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 26 23:50:51 volumio-livingroom kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 26 23:50:51 volumio-livingroom kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 26 23:50:52 volumio-livingroom kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 26 23:50:52 volumio-livingroom kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 26 23:50:52 volumio-livingroom systemd[1]: Found device /dev/disk/by-uuid/2E0A-AE55. Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting File System Check on /dev/disk/by-uuid/2E0A-AE55... Dec 26 23:50:52 volumio-livingroom systemd-udevd[351]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 26 23:50:52 volumio-livingroom kernel: wm8804 1-003b: revision E Dec 26 23:50:52 volumio-livingroom systemd-fsck[437]: fsck.fat 4.1 (2017-01-24) Dec 26 23:50:52 volumio-livingroom systemd-fsck[437]: /dev/mmcblk0p1: 407 files, 41769/46774 clusters Dec 26 23:50:52 volumio-livingroom systemd[1]: Started File System Check on /dev/disk/by-uuid/2E0A-AE55. Dec 26 23:50:52 volumio-livingroom kernel: Bluetooth: hci0: BCM: chip id 107 Dec 26 23:50:52 volumio-livingroom kernel: Bluetooth: hci0: BCM: features 0x2f Dec 26 23:50:52 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 Dec 26 23:50:52 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 26 23:50:52 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 26 23:50:52 volumio-livingroom kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 26 23:50:52 volumio-livingroom kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 26 23:50:52 volumio-livingroom kernel: usbcore: registered new interface driver brcmfmac Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Dec 26 23:50:52 volumio-livingroom systemd-udevd[336]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Dec 26 23:50:52 volumio-livingroom kernel: alsactl[466]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 26 23:50:52 volumio-livingroom kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 26 23:50:52 volumio-livingroom kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Dec 26 23:50:52 volumio-livingroom systemd-udevd[337]: Using default interface naming scheme 'v240'. Dec 26 23:50:52 volumio-livingroom systemd-udevd[337]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 26 23:50:52 volumio-livingroom systemd[1]: Created slice system-bthelper.slice. Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Show Plymouth Boot Screen... Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 26 23:50:52 volumio-livingroom systemd[1]: Mounting /boot... Dec 26 23:50:52 volumio-livingroom systemd[1]: Started File System Check Daemon to report status. Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 26 23:50:52 volumio-livingroom systemd[1]: Mounted /boot. Dec 26 23:50:52 volumio-livingroom systemd[1]: plymouth-start.service: Succeeded. Dec 26 23:50:52 volumio-livingroom systemd[1]: Started Show Plymouth Boot Screen. Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Reached target Local File Systems. Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Raise network interfaces... Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Create Volatile Files and Directories... Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Preprocess NFS configuration... Dec 26 23:50:52 volumio-livingroom systemd[1]: Started ifup for eth0. Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Started Load/Save RF Kill Switch Status. Dec 26 23:50:52 volumio-livingroom systemd[1]: Received SIGRTMIN+20 from PID 189 (plymouthd). Dec 26 23:50:52 volumio-livingroom systemd[1]: plymouth-read-write.service: Succeeded. Dec 26 23:50:52 volumio-livingroom systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 26 23:50:52 volumio-livingroom systemd[1]: nfs-config.service: Succeeded. Dec 26 23:50:52 volumio-livingroom systemd[1]: Started Preprocess NFS configuration. Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Reached target NFS client services. Dec 26 23:50:52 volumio-livingroom systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 26 23:50:52 volumio-livingroom systemd[1]: Started Create Volatile Files and Directories. Dec 26 23:50:52 volumio-livingroom kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting RPC bind portmap service... Dec 26 23:50:52 volumio-livingroom systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 26 23:50:52 volumio-livingroom systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 26 23:50:52 volumio-livingroom systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 26 23:50:52 volumio-livingroom systemd[1]: Started ifup for wlan0. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target System Initialization. Dec 26 23:50:53 volumio-livingroom kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 26 23:50:53 volumio-livingroom systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 26 23:50:53 volumio-livingroom sh[500]: eth0: waiting for carrier Dec 26 23:50:53 volumio-livingroom dhcpcd[527]: eth0: waiting for carrier Dec 26 23:50:53 volumio-livingroom kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 26 23:50:53 volumio-livingroom kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Daily apt download activities. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 26 23:50:53 volumio-livingroom systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 26 23:50:53 volumio-livingroom systemd[1]: Listening on triggerhappy.socket. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Sockets. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Basic System. Dec 26 23:50:53 volumio-livingroom kernel: Bluetooth: hci0: BCM: features 0x2f Dec 26 23:50:53 volumio-livingroom kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 26 23:50:53 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 26 23:50:53 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 26 23:50:53 volumio-livingroom systemd[1]: Started volumio-remote-updater.service. Dec 26 23:50:53 volumio-livingroom systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting dhcpcd on all interfaces... Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Manage Sound Card State (restore and store). Dec 26 23:50:53 volumio-livingroom systemd[1]: Started D-Bus System Message Bus. Dec 26 23:50:53 volumio-livingroom alsactl[620]: alsactl 1.1.8 daemon started Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting WPA supplicant... Dec 26 23:50:53 volumio-livingroom dhcpcd[616]: Not running dhcpcd because /etc/network/interfaces Dec 26 23:50:53 volumio-livingroom dhcpcd[616]: defines some interfaces that will use a Dec 26 23:50:53 volumio-livingroom dhcpcd[616]: DHCP client or static address Dec 26 23:50:53 volumio-livingroom systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Volumio Iptables Module. Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting triggerhappy global hotkey daemon... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Login Service... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Volumio Time Update Utility... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Wireless Services... Dec 26 23:50:53 volumio-livingroom systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Save/Restore Sound Card State... Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Volumio Log Rotation Service. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started UPnP Renderer front-end to MPD. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Daily apt upgrade and clean activities. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Timers. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started RPC bind portmap service. Dec 26 23:50:53 volumio-livingroom systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 26 23:50:53 volumio-livingroom thd[631]: Unable to parse trigger line: Dec 26 23:50:53 volumio-livingroom thd[631]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 26 23:50:53 volumio-livingroom thd[631]: Unable to parse trigger line: Dec 26 23:50:53 volumio-livingroom thd[631]: Found socket passed from systemd Dec 26 23:50:53 volumio-livingroom systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 26 23:50:53 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Fetching time from Volumio... Dec 26 23:50:53 volumio-livingroom systemd[1]: Failed to start dhcpcd on all interfaces. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started triggerhappy global hotkey daemon. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target RPC Port Mapper. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Remote File Systems (Pre). Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Remote File Systems. Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 26 23:50:53 volumio-livingroom sh[562]: wlan0=wlan0 Dec 26 23:50:53 volumio-livingroom systemd-logind[632]: New seat seat0. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Successfully dropped root privileges. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: avahi-daemon 0.7 starting up. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Successfully called chroot(). Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Successfully dropped remaining capabilities. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 26 23:50:53 volumio-livingroom wpa_supplicant[625]: Successfully initialized wpa_supplicant Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Raise network interfaces. Dec 26 23:50:53 volumio-livingroom systemd[1]: hciuart.service: Succeeded. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Save/Restore Sound Card State. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Loading service file /services/volumio.service. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Network interface enumeration completed. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Server startup complete. Host name is volumio-livingroom.local. Local service cookie is 2288255160. Dec 26 23:50:53 volumio-livingroom avahi-daemon[633]: Service "Volumio-Livingroom" (/services/volumio.service) successfully established. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Login Service. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started WPA supplicant. Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Sound Card. Dec 26 23:50:53 volumio-livingroom volumio-remote-updater[603]: Error: No active session Dec 26 23:50:53 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:50:53] [info] asio async_connect error: system:111 (Connection refused) Dec 26 23:50:53 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:50:53] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 26 23:50:53 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:50:53] [error] handle_connect error: Underlying Transport Error Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Network. Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Permit User Sessions... Dec 26 23:50:53 volumio-livingroom systemd[1]: Reached target Network is Online. Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting LSB: Brings up/down network automatically... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting /etc/rc.local Compatibility... Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Network Time Service... Dec 26 23:50:53 volumio-livingroom volumio[639]: Could not open config: /tmp/upmpdcli.conf Dec 26 23:50:53 volumio-livingroom systemd[1]: Starting Samba NMB Daemon... Dec 26 23:50:53 volumio-livingroom systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 26 23:50:53 volumio-livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 23:50:54 volumio-livingroom systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 23:50:54 volumio-livingroom systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Permit User Sessions. Dec 26 23:50:54 volumio-livingroom systemd[1]: Started /etc/rc.local Compatibility. Dec 26 23:50:54 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Date not found in response Dec 26 23:50:54 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Retrying in 5 seconds... Dec 26 23:50:54 volumio-livingroom systemd[1]: iptables.service: Succeeded. Dec 26 23:50:54 volumio-livingroom systemd[1]: Starting Hold until boot process finishes up... Dec 26 23:50:54 volumio-livingroom bthelper[678]: Raspberry Pi BDADDR already set Dec 26 23:50:54 volumio-livingroom systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Raspberry Pi bluetooth helper. Dec 26 23:50:54 volumio-livingroom systemd[1]: Received SIGRTMIN+21 from PID 189 (plymouthd). Dec 26 23:50:54 volumio-livingroom systemd[1]: Starting Bluetooth service... Dec 26 23:50:54 volumio-livingroom systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 26 23:50:54 volumio-livingroom loadcpufreq[648]: Loading cpufreq kernel modules...done (none). Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Hold until boot process finishes up. Dec 26 23:50:54 volumio-livingroom systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 26 23:50:54 volumio-livingroom systemd[1]: plymouth-quit.service: Succeeded. Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Terminate Plymouth Boot Screen. Dec 26 23:50:54 volumio-livingroom systemd[1]: Received SIGRTMIN+21 from PID 189 (n/a). Dec 26 23:50:54 volumio-livingroom systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Getty on tty1. Dec 26 23:50:54 volumio-livingroom systemd[1]: Reached target Login Prompts. Dec 26 23:50:54 volumio-livingroom ifplugd(eth0)[749]: ifplugd 0.28 initializing. Dec 26 23:50:54 volumio-livingroom ifplugd(eth0)[749]: Using interface eth0/DC:A6:32:0D:B7:A8 with driver (version: 6.6.62-v7l+) Dec 26 23:50:54 volumio-livingroom ifplugd(eth0)[749]: Using detection mode: SIOCETHTOOL Dec 26 23:50:54 volumio-livingroom ifplugd(eth0)[749]: Initialization complete, link beat not detected. Dec 26 23:50:54 volumio-livingroom ntpd[691]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 26 23:50:54 volumio-livingroom ntpd[691]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Network Time Service. Dec 26 23:50:54 volumio-livingroom ntpd[751]: proto: precision = 0.833 usec (-20) Dec 26 23:50:54 volumio-livingroom ntpd[751]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 26 23:50:54 volumio-livingroom ntpd[751]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 26 23:50:54 volumio-livingroom ntpd[751]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 364 days ago Dec 26 23:50:54 volumio-livingroom ntpd[751]: Listen and drop on 0 v6wildcard [::]:123 Dec 26 23:50:54 volumio-livingroom ntpd[751]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 26 23:50:54 volumio-livingroom ntpd[751]: Listen normally on 2 lo 127.0.0.1:123 Dec 26 23:50:54 volumio-livingroom ntpd[751]: Listening on routing socket on fd #19 for interface updates Dec 26 23:50:54 volumio-livingroom ntpd[751]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 26 23:50:54 volumio-livingroom ntpd[751]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 26 23:50:54 volumio-livingroom ifplugd[685]: Network Interface Plugging Daemon...start eth0...done. Dec 26 23:50:54 volumio-livingroom systemd[1]: Started LSB: Brings up/down network automatically. Dec 26 23:50:54 volumio-livingroom cpufrequtils[736]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 26 23:50:54 volumio-livingroom systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Bluetooth daemon 5.83 Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 26 23:50:54 volumio-livingroom systemd[1]: Started Bluetooth service. Dec 26 23:50:54 volumio-livingroom systemd[1]: Reached target Bluetooth. Dec 26 23:50:54 volumio-livingroom haveged[558]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 26 23:50:54 volumio-livingroom haveged[558]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 26 23:50:54 volumio-livingroom haveged[558]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99608 Dec 26 23:50:54 volumio-livingroom haveged[558]: haveged: fills: 0, generated: 0 Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Starting SDP server Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Excluding (cli) hostname Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Excluding (cli) scanparam Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Excluding (cli) battery Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Bluetooth management interface 1.22 initialized Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Battery Provider Manager created Dec 26 23:50:54 volumio-livingroom kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 26 23:50:54 volumio-livingroom kernel: Bluetooth: BNEP filters: protocol multicast Dec 26 23:50:54 volumio-livingroom kernel: Bluetooth: BNEP socket layer initialized Dec 26 23:50:54 volumio-livingroom kernel: Bluetooth: MGMT ver 1.22 Dec 26 23:50:54 volumio-livingroom kernel: NET: Registered PF_ALG protocol family Dec 26 23:50:54 volumio-livingroom kernel: cryptd: max_cpu_qlen set to 1000 Dec 26 23:50:54 volumio-livingroom bluetoothd[732]: Failed to set privacy: Rejected (0x0b) Dec 26 23:50:54 volumio-livingroom nmbd[716]: [2025/12/26 23:50:54.939677, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 26 23:50:54 volumio-livingroom nmbd[716]: started asyncdns process 787 Dec 26 23:50:54 volumio-livingroom nmbd[716]: [2025/12/26 23:50:54.941195, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 26 23:50:54 volumio-livingroom nmbd[716]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 26 23:50:54 volumio-livingroom nmbd[716]: [2025/12/26 23:50:54.941312, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 26 23:50:54 volumio-livingroom nmbd[716]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 26 23:50:55 volumio-livingroom wireless.js[636]: Cleaning previous... Dec 26 23:50:55 volumio-livingroom ntpd[751]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 26 23:50:55 volumio-livingroom sudo[795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 26 23:50:55 volumio-livingroom sudo[795]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:55 volumio-livingroom sudo[795]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:55 volumio-livingroom sudo[797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 26 23:50:55 volumio-livingroom sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:55 volumio-livingroom sudo[797]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:55 volumio-livingroom wireless.js[636]: Stopped aP Dec 26 23:50:55 volumio-livingroom sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 26 23:50:55 volumio-livingroom sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:55 volumio-livingroom sudo[804]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:55 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 26 23:50:55 volumio-livingroom sudo[806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 26 23:50:55 volumio-livingroom sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:55 volumio-livingroom sudo[806]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:55 volumio-livingroom sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 26 23:50:55 volumio-livingroom sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:55 volumio-livingroom sudo[813]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:55 volumio-livingroom sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 26 23:50:55 volumio-livingroom sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:56 volumio-livingroom ntpd[751]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 26 23:50:57 volumio-livingroom ntpd[751]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 26 23:50:57 volumio-livingroom systemd[1]: systemd-rfkill.service: Succeeded. Dec 26 23:50:58 volumio-livingroom ntpd[751]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 26 23:50:58 volumio-livingroom sudo[815]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:58 volumio-livingroom wireless.js[636]: SETTING APPROPRIATE REG DOMAIN: US Dec 26 23:50:58 volumio-livingroom sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 26 23:50:58 volumio-livingroom sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:58 volumio-livingroom sudo[820]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:58 volumio-livingroom sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 26 23:50:58 volumio-livingroom sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:58 volumio-livingroom sudo[822]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:58 volumio-livingroom wireless.js[636]: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 26 23:50:58 volumio-livingroom wireless.js[636]: Start wireless flow Dec 26 23:50:58 volumio-livingroom wireless.js[636]: Stopped hotspot (if there).. Dec 26 23:50:58 volumio-livingroom sudo[827]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 26 23:50:58 volumio-livingroom sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:58 volumio-livingroom sudo[827]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:58 volumio-livingroom sudo[829]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 26 23:50:58 volumio-livingroom sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:59 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Fetching time from Volumio... Dec 26 23:50:59 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Date not found in response Dec 26 23:50:59 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Retrying in 5 seconds... Dec 26 23:50:59 volumio-livingroom bluetoothd[732]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Dec 26 23:50:59 volumio-livingroom bthelper[678]: [NEW] Media /org/bluez/hci0 Dec 26 23:50:59 volumio-livingroom bthelper[678]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 26 23:50:59 volumio-livingroom bthelper[678]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 26 23:50:59 volumio-livingroom bthelper[678]: hci0 class of device changed: 0x000000 Dec 26 23:50:59 volumio-livingroom bthelper[678]: hci0 new_settings: ssp br/edr le secure-conn Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA PowerState: on-disabling Dec 26 23:50:59 volumio-livingroom bthelper[678]: Changing power off succeeded Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA PowerState: off Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA Powered: no Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA Discovering: no Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA Class: 0x00000000 (0) Dec 26 23:50:59 volumio-livingroom bthelper[678]: [NEW] Media /org/bluez/hci0 Dec 26 23:50:59 volumio-livingroom bthelper[678]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 26 23:50:59 volumio-livingroom bthelper[678]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA PowerState: off-enabling Dec 26 23:50:59 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:50:59] [info] asio async_connect error: system:111 (Connection refused) Dec 26 23:50:59 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:50:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 26 23:50:59 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:50:59] [error] handle_connect error: Underlying Transport Error Dec 26 23:50:59 volumio-livingroom sudo[829]: pam_unix(sudo:session): session closed for user root Dec 26 23:50:59 volumio-livingroom wireless.js[636]: DHCP IP Dec 26 23:50:59 volumio-livingroom wireless.js[636]: Start ap Dec 26 23:50:59 volumio-livingroom wpa_supplicant[837]: Successfully initialized wpa_supplicant Dec 26 23:50:59 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 26 23:50:59 volumio-livingroom sudo[838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 26 23:50:59 volumio-livingroom sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:50:59 volumio-livingroom dhcpcd[839]: dev: loaded udev Dec 26 23:50:59 volumio-livingroom bthelper[678]: hci0 class of device changed: 0x00041c Dec 26 23:50:59 volumio-livingroom bthelper[678]: [CHG] Controller DC:A6:32:0D:B7:AA Class: 0x0000041c (1052) Dec 26 23:50:59 volumio-livingroom dhcpcd-run-hooks[857]: wlan0: starting wpa_supplicant Dec 26 23:50:59 volumio-livingroom bthelper[678]: hci0 new_settings: powered ssp br/edr le secure-conn Dec 26 23:50:59 volumio-livingroom bthelper[678]: Changing power on succeeded Dec 26 23:50:59 volumio-livingroom dhcpcd-run-hooks[862]: wlan0: failed to start wpa_supplicant Dec 26 23:50:59 volumio-livingroom dhcpcd-run-hooks[863]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Dec 26 23:50:59 volumio-livingroom dhcpcd[839]: wlan0: connected to Access Point `' Dec 26 23:50:59 volumio-livingroom dhcpcd[839]: no interfaces have a carrier Dec 26 23:50:59 volumio-livingroom dhcpcd[839]: forked to background, child pid 873 Dec 26 23:50:59 volumio-livingroom dhcpcd[873]: eth0: waiting for carrier Dec 26 23:50:59 volumio-livingroom dhcpcd[873]: wlan0: waiting for carrier Dec 26 23:50:59 volumio-livingroom sudo[838]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:00 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:00 volumio-livingroom sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:00 volumio-livingroom sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:00 volumio-livingroom sudo[875]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:01 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:01 volumio-livingroom sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:01 volumio-livingroom sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:01 volumio-livingroom sudo[878]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:02 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:02 volumio-livingroom sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:02 volumio-livingroom sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:02 volumio-livingroom sudo[881]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:03 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:03 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 26 23:51:03 volumio-livingroom sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:03 volumio-livingroom sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:03 volumio-livingroom sudo[884]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:03 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 26 23:51:03 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 26 23:51:03 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 26 23:51:04 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Fetching time from Volumio... Dec 26 23:51:04 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Date not found in response Dec 26 23:51:04 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Retrying in 5 seconds... Dec 26 23:51:04 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:04 volumio-livingroom sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:04 volumio-livingroom sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:04 volumio-livingroom sudo[892]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:05 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:05 volumio-livingroom sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:05 volumio-livingroom sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:05 volumio-livingroom sudo[895]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:05 volumio-livingroom wpa_supplicant[861]: wlan0: Trying to associate with SSID 'ASUS.5G' Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: carrier acquired Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: connected to Access Point `ASUS.5G' Dec 26 23:51:06 volumio-livingroom wpa_supplicant[861]: wlan0: Associated with 04:d4:c4:3f:a2:34 Dec 26 23:51:06 volumio-livingroom wpa_supplicant[861]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:d4:c4:3f:a2:34 completed [id=0 id_str=] Dec 26 23:51:06 volumio-livingroom wpa_supplicant[861]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:0d:b7:a8 Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: IAID 32:0d:b7:a9 Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: adding address fe80::f7b1:aea8:47:5d66 Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: ipv6_addaddr1: Permission denied Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: carrier lost Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: carrier acquired Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: IAID 32:0d:b7:a9 Dec 26 23:51:06 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:06 volumio-livingroom sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:06 volumio-livingroom sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:06 volumio-livingroom sudo[911]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:06 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:51:06] [info] asio async_connect error: system:111 (Connection refused) Dec 26 23:51:06 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:51:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 26 23:51:06 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:51:06] [error] handle_connect error: Underlying Transport Error Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: rebinding lease of 192.168.2.12 Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: probing address 192.168.2.12/24 Dec 26 23:51:06 volumio-livingroom dhcpcd[873]: wlan0: soliciting an IPv6 router Dec 26 23:51:07 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:07 volumio-livingroom sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:07 volumio-livingroom sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:07 volumio-livingroom sudo[914]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:08 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:08 volumio-livingroom sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:08 volumio-livingroom sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:08 volumio-livingroom sudo[917]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:09 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Fetching time from Volumio... Dec 26 23:51:09 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Date not found in response Dec 26 23:51:09 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Retrying in 5 seconds... Dec 26 23:51:09 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:09 volumio-livingroom sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:09 volumio-livingroom sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:09 volumio-livingroom sudo[925]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:10 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:10 volumio-livingroom sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:10 volumio-livingroom sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:10 volumio-livingroom sudo[928]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:11 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:11 volumio-livingroom sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:11 volumio-livingroom sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:11 volumio-livingroom sudo[931]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:12 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:12 volumio-livingroom sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:12 volumio-livingroom sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:12 volumio-livingroom sudo[934]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:12 volumio-livingroom dhcpcd[873]: wlan0: leased 192.168.2.12 for 86400 seconds Dec 26 23:51:12 volumio-livingroom avahi-daemon[633]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.12. Dec 26 23:51:12 volumio-livingroom dhcpcd[873]: wlan0: adding route to 192.168.2.0/24 Dec 26 23:51:12 volumio-livingroom avahi-daemon[633]: New relevant interface wlan0.IPv4 for mDNS. Dec 26 23:51:12 volumio-livingroom dhcpcd[873]: wlan0: adding default route via 192.168.2.1 Dec 26 23:51:12 volumio-livingroom avahi-daemon[633]: Registering new address record for 192.168.2.12 on wlan0.IPv4. Dec 26 23:51:13 volumio-livingroom wireless.js[636]: trying... Dec 26 23:51:13 volumio-livingroom sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 23:51:13 volumio-livingroom sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:13 volumio-livingroom sudo[957]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:13 volumio-livingroom wireless.js[636]: Connected to: ----ASUS.5G Dec 26 23:51:13 volumio-livingroom wireless.js[636]: ---- Dec 26 23:51:13 volumio-livingroom sudo[960]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 23:51:13 volumio-livingroom sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:13 volumio-livingroom sudo[960]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:13 volumio-livingroom wireless.js[636]: ... joined AP, wlan0 IPv4 is 192.168.2.12, ipV6 is undefined Dec 26 23:51:13 volumio-livingroom wireless.js[636]: It's done! AP Dec 26 23:51:13 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 26 23:51:13 volumio-livingroom systemd[1]: Started Wireless Services. Dec 26 23:51:13 volumio-livingroom systemd[1]: Started Volumio Backend Module. Dec 26 23:51:13 volumio-livingroom systemd[1]: Started Volumio Cpu Tweaker. Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: Setting RT Priority for mpd Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: pid 35's current scheduling policy: SCHED_OTHER Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: pid 35's current scheduling priority: 0 Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: Setting MPD Affinity Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: pid 3's current affinity mask: f Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 26 23:51:13 volumio-livingroom volumio-cpu-tweak[966]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 26 23:51:13 volumio-livingroom systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 26 23:51:14 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Fetching time from Volumio... Dec 26 23:51:14 volumio-livingroom ntpd[751]: Listen normally on 3 wlan0 192.168.2.12:123 Dec 26 23:51:14 volumio-livingroom ntpd[751]: new interface(s) found: waking up resolver Dec 26 23:51:14 volumio-livingroom nmbd[716]: [2025/12/26 23:51:14.945062, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 26 23:51:14 volumio-livingroom nmbd[716]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 26 23:51:14 volumio-livingroom systemd[1]: Started Samba NMB Daemon. Dec 26 23:51:14 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:14 volumio-livingroom volumio[965]: info: ----- Volumio3 ---- Dec 26 23:51:14 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:14 volumio-livingroom volumio[965]: info: ----- System startup ---- Dec 26 23:51:14 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:14 volumio-livingroom systemd[1]: Starting Samba Winbind Daemon... Dec 26 23:51:15 volumio-livingroom winbindd[994]: [2025/12/26 23:51:15.220493, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 26 23:51:15 volumio-livingroom winbindd[994]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 26 23:51:15 volumio-livingroom winbindd[994]: [2025/12/26 23:51:15.248700, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 26 23:51:15 volumio-livingroom winbindd[994]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 26 23:51:15 volumio-livingroom systemd[1]: Started Samba Winbind Daemon. Dec 26 23:51:15 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: Setting system time to: 2025-12-26 23:51:45 Dec 26 23:51:15 volumio-livingroom sudo[1000]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-26 23:51:45 Dec 26 23:51:15 volumio-livingroom systemd[1]: Starting Samba SMB Daemon... Dec 26 23:51:15 volumio-livingroom sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:15 volumio-livingroom dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1001 comm="timedatectl set-time 2025-12-26 23:51:45 ") Dec 26 23:51:15 volumio-livingroom systemd[1]: Starting Time & Date Service... Dec 26 23:51:15 volumio-livingroom dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 26 23:51:15 volumio-livingroom systemd[1]: Started Time & Date Service. Dec 26 23:51:45 volumio-livingroom systemd-timedated[1003]: Changed local time to Fri Dec 26 23:51:45 2025 Dec 26 23:51:45 volumio-livingroom sudo[1000]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:45 volumio-livingroom volumio-time-update[635]: volumio-time-update-util: System time updated successfully. Dec 26 23:51:45 volumio-livingroom systemd[1]: Started Volumio Time Update Utility. Dec 26 23:51:45 volumio-livingroom smbd[1002]: [2025/12/26 23:51:45.273029, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 26 23:51:45 volumio-livingroom smbd[1002]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 26 23:51:45 volumio-livingroom systemd[1]: Started Samba SMB Daemon. Dec 26 23:51:45 volumio-livingroom systemd[1]: Reached target Multi-User System. Dec 26 23:51:45 volumio-livingroom systemd[1]: Reached target Graphical Interface. Dec 26 23:51:45 volumio-livingroom systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 26 23:51:45 volumio-livingroom systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 26 23:51:45 volumio-livingroom systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 26 23:51:45 volumio-livingroom systemd[1]: Startup finished in 11.847s (kernel) + 25.720s (userspace) = 37.567s. Dec 26 23:51:45 volumio-livingroom volumio[965]: info: MYVOLUMIO Environment detected Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Plugin folders cleanup Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning into folder /volumio/app/plugins/ Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category audio_interface Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category miscellanea Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category music_service Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category plugins.json Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category system_controller Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category user_interface Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning into folder /data/plugins/ Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Scanning category music_service Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Plugin folders cleanup completed Dec 26 23:51:45 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:45 volumio-livingroom volumio[965]: info: ----- Core plugins startup ---- Dec 26 23:51:45 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Loading plugins from folder /volumio/app/plugins/ Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Adding plugin upnp to MyMusic Plugins Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Loading plugins from folder /data/plugins/ Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Loading plugin "system"... Dec 26 23:51:45 volumio-livingroom volumio[965]: info: Loading plugin "appearance"... Dec 26 23:51:46 volumio-livingroom volumio-remote-updater[603]: [2025-12-26 23:51:46] [connect] Successful connection Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "network"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Refreshing Cached IP Addresses Dec 26 23:51:46 volumio-livingroom sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 23:51:46 volumio-livingroom sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:46 volumio-livingroom sudo[1018]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:46 volumio-livingroom sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 23:51:46 volumio-livingroom sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:46 volumio-livingroom sudo[1020]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "services"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "alsa_controller"... Dec 26 23:51:46 volumio-livingroom sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 23:51:46 volumio-livingroom sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:46 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "wizard"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "networkfs"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Starting Udev Watcher for removable devices Dec 26 23:51:46 volumio-livingroom sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=csabvarg,password=Kokszos84!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.200/music /mnt/NAS/Synology_DS918+ Dec 26 23:51:46 volumio-livingroom sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Ignoring mount for partition: boot Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Ignoring mount for partition: volumio Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Ignoring mount for partition: volumio_data Dec 26 23:51:46 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "volumio_command_line_client"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "upnp"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: [1766767906813] Starting Upmpd Daemon Dec 26 23:51:46 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "my_music"... Dec 26 23:51:46 volumio-livingroom volumio[965]: info: Loading plugin "mpd"... Dec 26 23:51:46 volumio-livingroom kernel: Key type cifs.spnego registered Dec 26 23:51:46 volumio-livingroom kernel: Key type cifs.idmap registered Dec 26 23:51:46 volumio-livingroom 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 26 23:51:46 volumio-livingroom kernel: CIFS: Attempting to mount //192.168.2.200/music Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "upnp_browser"... Dec 26 23:51:47 volumio-livingroom sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Starting UPNP Browser Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "alarm-clock"... Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "airplay_emulation"... Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Starting Shairport Sync Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "last_100"... Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "webradio"... Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "i2s_dacs"... Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "volumiodiscovery"... Dec 26 23:51:47 volumio-livingroom volumio[965]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 23:51:47 volumio-livingroom volumio[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 23:51:47 volumio-livingroom volumio[965]: *** WARNING *** For more information see Dec 26 23:51:47 volumio-livingroom node[965]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 23:51:47 volumio-livingroom volumio[965]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 23:51:47 volumio-livingroom volumio[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 23:51:47 volumio-livingroom volumio[965]: *** WARNING *** For more information see Dec 26 23:51:47 volumio-livingroom node[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 23:51:47 volumio-livingroom node[965]: *** WARNING *** For more information see Dec 26 23:51:47 volumio-livingroom node[965]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 23:51:47 volumio-livingroom node[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 23:51:47 volumio-livingroom node[965]: *** WARNING *** For more information see Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Discovery: Started advertising with name: Volumio-Livingroom Dec 26 23:51:47 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 23:51:47 volumio-livingroom volumio[965]: info: Loading plugin "spop"... Dec 26 23:51:48 volumio-livingroom volumio[965]: info: Loading plugin "ytcr"... Dec 26 23:51:50 volumio-livingroom sudo[1029]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:50 volumio-livingroom volumio[965]: info: Loading plugin "outputs"... Dec 26 23:51:50 volumio-livingroom volumio[965]: info: Loading plugin "albumart"... Dec 26 23:51:50 volumio-livingroom volumio[965]: info: Plugin example_plugin is not enabled Dec 26 23:51:50 volumio-livingroom volumio[965]: info: Loading plugin "inputs"... Dec 26 23:51:50 volumio-livingroom volumio[965]: info: Loading plugin "updater_comm"... Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Plugin mpdemulation is not enabled Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Loading plugin "rest_api"... Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Loading plugin "websocket"... Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Starting Socket.io Server version 2.3.0 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Loading i18n strings for locale en Dec 26 23:51:51 volumio-livingroom volumio[965]: Forking 3 albumart workers Dec 26 23:51:51 volumio-livingroom volumio[965]: Updating browse sources language Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::initPlayerControls Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 23:51:51 volumio-livingroom volumio[965]: Express server listening on port 3000 Dec 26 23:51:51 volumio-livingroom volumio[965]: [Metrics] WebUI: 7s 462.65ms Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::resetVolumioState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::getcurrentVolume Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::pushState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioPushState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Volumio Network Manager: Network status updated: 2 Dec 26 23:51:51 volumio-livingroom volumio[965]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Reloading queue from file Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Setting Device type: Raspberry PI Dec 26 23:51:51 volumio-livingroom volumio[965]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 26 23:51:51 volumio-livingroom volumio[965]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::setRepeat null single undefined Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::pushState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioPushState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::setRandom null Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreStateMachine::pushState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioPushState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Completed loading Core Plugins Dec 26 23:51:51 volumio-livingroom volumio[965]: Starting albumart workers Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Preparing to generate the ALSA configuration file Dec 26 23:51:51 volumio-livingroom volumio[965]: Starting albumart workers Dec 26 23:51:51 volumio-livingroom volumio[965]: Starting albumart workers Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioGetState Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Asound.conf file unchanged, so no further update is needed Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Output device has changed, restarting MPD Dec 26 23:51:51 volumio-livingroom sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 23:51:51 volumio-livingroom sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:51 volumio-livingroom sudo[1119]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Output device has changed, restarting Shairport Sync Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:51 volumio-livingroom sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 23:51:51 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:51 volumio-livingroom sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:51 volumio-livingroom systemd[1]: Listening on mpd.socket. Dec 26 23:51:51 volumio-livingroom volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 23:51:51 volumio-livingroom volumio[965]: info: ___________ START PLUGINS ___________ Dec 26 23:51:51 volumio-livingroom systemd[1]: Starting Music Player Daemon... Dec 26 23:51:52 volumio-livingroom volumio[965]: info: ControllerMpd::onStart: Initializing MPD Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Creating MPD Configuration file Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 23:51:52 volumio-livingroom sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 23:51:52 volumio-livingroom sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom volumio[965]: info: [1766767912045] CoreMusicLibrary::Adding element Media Servers Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 23:51:52 volumio-livingroom sudo[1128]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 23:51:52 volumio-livingroom sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom sudo[1130]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom sudo[1128]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 23:51:52 volumio-livingroom sudo[1128]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom volumio[965]: info: UPNP Browser: Client initialized successfully Dec 26 23:51:52 volumio-livingroom sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 23:51:52 volumio-livingroom sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:52 volumio-livingroom systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 23:51:52 volumio-livingroom systemd[1]: mpd.service: Succeeded. Dec 26 23:51:52 volumio-livingroom systemd[1]: Stopped Music Player Daemon. Dec 26 23:51:52 volumio-livingroom systemd[1]: Starting Music Player Daemon... Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:52 volumio-livingroom sudo[1138]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 23:51:52 volumio-livingroom sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom sudo[1138]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 23:51:52 volumio-livingroom sudo[1138]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 23:51:52 volumio-livingroom volumio[965]: info: [1766767912239] CoreMusicLibrary::Adding element Last_100 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 23:51:52 volumio-livingroom volumio[965]: info: [1766767912243] CoreMusicLibrary::Adding element Webradio Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Initializing BBC Radios Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Creating Spotify config file Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom systemd[1]: systemd-fsckd.service: Succeeded. Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Volumio Calling Home Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Discovery: adding a7286d2f-7184-4191-b9c3-f20bcdee8e3a Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Discovery: Found device Volumio-Livingroom Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioGetState Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Discovery: adding f07f740a-0747-49c5-84c9-2bf98041f393 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Discovery: Found device Volumio-Bedroom Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Discovery: Connecting to remote: 192.168.2.11 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: MPD Permissions set Dec 26 23:51:52 volumio-livingroom volumio[965]: info: MPD Permissions set Dec 26 23:51:52 volumio-livingroom volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Spotify config file written Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom sh[500]: timed out Dec 26 23:51:52 volumio-livingroom dhcpcd[527]: timed out Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom sh[500]: dhcpcd exited Dec 26 23:51:52 volumio-livingroom dhcpcd[527]: dhcpcd exited Dec 26 23:51:52 volumio-livingroom sh[500]: ifup: failed to bring up eth0 Dec 26 23:51:52 volumio-livingroom systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 26 23:51:52 volumio-livingroom systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 26 23:51:52 volumio-livingroom sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 23:51:52 volumio-livingroom sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 23:51:52 volumio-livingroom volumio[965]: info: No need to fix Spotify hosts Dec 26 23:51:52 volumio-livingroom systemd[1]: Started go-librespot Daemon. Dec 26 23:51:52 volumio-livingroom go-librespot[1192]: go-librespot daemon starting... Dec 26 23:51:52 volumio-livingroom sudo[1184]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Discovery: Connected to remote: 192.168.2.11 Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Starting Shairport Sync Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Starting Shairport Sync Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Starting Shairport Sync Dec 26 23:51:52 volumio-livingroom sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 23:51:52 volumio-livingroom sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 23:51:52 volumio-livingroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioGetState Dec 26 23:51:52 volumio-livingroom systemd[1]: shairport-sync.service: Succeeded. Dec 26 23:51:52 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:52 volumio-livingroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 23:51:52 volumio-livingroom sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 23:51:52 volumio-livingroom sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:52 volumio-livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 23:51:52 volumio-livingroom sudo[1198]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 23:51:52 volumio-livingroom systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 26 23:51:52 volumio-livingroom systemd[1]: shairport-sync.service: Succeeded. Dec 26 23:51:52 volumio-livingroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 23:51:52 volumio-livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 23:51:52 volumio-livingroom sudo[1200]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom sudo[1203]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Shairport-Sync Started Dec 26 23:51:52 volumio-livingroom volumio[965]: Error adding Membership: Error: addMembership EINVAL Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Shairport-Sync Started Dec 26 23:51:52 volumio-livingroom volumio[965]: info: Shairport-Sync Started Dec 26 23:51:53 volumio-livingroom volumio[965]: info: Volumio called home Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=info msg="running go-librespot 0.4.0" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="app state loaded" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 23:51:53 volumio-livingroom volumio[965]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 23:51:53 volumio-livingroom volumio[965]: SPOTIFY: BQBpRP94FL3bcs_IgajpP8vOBHqbqwU_jXrap0Yz897zrcFSCEnkAblHdSQMKzw1-rKiaHlYvYRxxDNF3cdv4wAYcPInT2Vb88S0pE40sJ4WqkHXpXu-vUwBSZWJm2fYm1CykQg0CGrWC5uQjTMvPUK6HMN5NkY5ETtg0jTa7oU0XiFX0jP8ID0ck9w9hzCmlQt4YexhrT0MKCL5ErWwc4W3YBImpLrJAIEeTCCRhueSOyDKm73Dn7IgN4Qs29In1tCVgT0PvY_0IfBobQuM2C5kM3ob8rHCdzvb8r3J1eZmZ_0pcwwLnOGz Dec 26 23:51:53 volumio-livingroom volumio[965]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 23:51:53 volumio-livingroom volumio[965]: info: New Spotify access token = BQBpRP94FL3bcs_IgajpP8vOBHqbqwU_jXrap0Yz897zrcFSCEnkAblHdSQMKzw1-rKiaHlYvYRxxDNF3cdv4wAYcPInT2Vb88S0pE40sJ4WqkHXpXu-vUwBSZWJm2fYm1CykQg0CGrWC5uQjTMvPUK6HMN5NkY5ETtg0jTa7oU0XiFX0jP8ID0ck9w9hzCmlQt4YexhrT0MKCL5ErWwc4W3YBImpLrJAIEeTCCRhueSOyDKm73Dn7IgN4Qs29In1tCVgT0PvY_0IfBobQuM2C5kM3ob8rHCdzvb8r3J1eZmZ_0pcwwLnOGz Dec 26 23:51:53 volumio-livingroom volumio[965]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=info msg="zeroconf server listening on port 42299" Dec 26 23:51:53 volumio-livingroom volumio[965]: SPOTIFY: User informations: {"country":"VN","display_name":"Csaba","email":"csabvarg@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ml41vxnjulnyo8p6fb26cer9v"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/ml41vxnjulnyo8p6fb26cer9v","id":"ml41vxnjulnyo8p6fb26cer9v","images":[],"product":"premium","type":"user","uri":"spotify:user:ml41vxnjulnyo8p6fb26cer9v"} Dec 26 23:51:53 volumio-livingroom volumio[965]: info: Spotify Successfully logged in Dec 26 23:51:53 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 23:51:53 volumio-livingroom volumio[965]: info: [1766767913589] CoreMusicLibrary::Adding element Spotify Dec 26 23:51:53 volumio-livingroom volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 23:51:53 volumio-livingroom volumio[965]: Cannot find translation for source Spotify Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="obtained new client token: AABvxf8cxuhWhqmwI+QO1oexP/A+Ia3jXdH6K1bm0Z3ml3fWhnGxcPfd7rfkVFfE2aHoU1HFT39OLHGhZbUZ7mTzLwFi2vlTkvWzlbiKirRZw9sliB6P93XXE2kyDE/ARJN2mf4arbjsQBoez+OjaUEGPM2uQun8WP4ounCIWQIFRbVCSbmXBOvqH43aZzEH9whJIdl4YBTiOf746dKhLCWoXHc9votfYOTOP5vsGW6zXp3jxpQUCfzY8g==" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="completed keyexchange" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=debug msg="completed challenge" Dec 26 23:51:53 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:53+07:00" level=info msg="authenticated AP" username="ml*********************9v" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=info msg="authenticated Login5" username="ml*********************9v" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="initializing zeroconf session" username="ml*********************9v" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="dealer connection opened" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=trace msg="starting accesspoint recv loop" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=trace msg="starting dealer recv loop" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=trace msg="received accesspoint ping" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="received connection id: MTBkY2Y1YzYtMGJl...Njg0ODU0QTVCMQ==" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=trace msg="received accesspoint pong ack" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="put connect state because NEW_DEVICE" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="update volume requested to 65535/65535" Dec 26 23:51:54 volumio-livingroom mpd[1145]: Dec 26 23:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 26 23:51:54 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:54+07:00" level=trace msg="emitting websocket event: volume" Dec 26 23:51:54 volumio-livingroom volumio[965]: info: [yt-cast-receiver] DIAL server listening on port 8098 Dec 26 23:51:54 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 23:51:54 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioGetState Dec 26 23:51:54 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:54 volumio-livingroom volumio[965]: info: CoreStateMachine::pushState Dec 26 23:51:54 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:54 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioPushState Dec 26 23:51:54 volumio-livingroom volumio[965]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Dec 26 23:51:54 volumio-livingroom volumio[965]: (TypeError) Cannot read property 'vol' of undefined Dec 26 23:51:54 volumio-livingroom volumio[965]: TypeError: Cannot read property 'vol' of undefined Dec 26 23:51:54 volumio-livingroom volumio[965]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Dec 26 23:51:54 volumio-livingroom volumio[965]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 26 23:51:54 volumio-livingroom volumio[965]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Dec 26 23:51:54 volumio-livingroom volumio[965]: at async /data/plugins/music_service/ytcr/dist/index.js:310:13 Dec 26 23:51:54 volumio-livingroom systemd[1]: Started Music Player Daemon. Dec 26 23:51:54 volumio-livingroom sudo[1122]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:54 volumio-livingroom sudo[1133]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:54 volumio-livingroom volumio[965]: info: Completed starting Core Plugins Dec 26 23:51:54 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:54 volumio-livingroom volumio[965]: info: ----- MyVolumio plugins startup ---- Dec 26 23:51:54 volumio-livingroom volumio[965]: info: ------------------------------------------- Dec 26 23:51:54 volumio-livingroom volumio[965]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 26 23:51:54 volumio-livingroom volumio[965]: error: MPD error: The expression evaluated to a falsy value: Dec 26 23:51:54 volumio-livingroom volumio[965]: assert.ok(self.idling) Dec 26 23:51:54 volumio-livingroom volumio[965]: error: The expression evaluated to a falsy value: Dec 26 23:51:54 volumio-livingroom volumio[965]: assert.ok(self.idling) Dec 26 23:51:54 volumio-livingroom volumio[965]: error: updateQueue error: null Dec 26 23:51:54 volumio-livingroom volumio[965]: info: MPD running with PID1145 Dec 26 23:51:54 volumio-livingroom volumio[965]: ,establishing connection Dec 26 23:51:54 volumio-livingroom volumio[965]: error: updateQueue error: null Dec 26 23:51:55 volumio-livingroom volumio[965]: info: go-librespot daemon successfully initialized Dec 26 23:51:56 volumio-livingroom sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 23:51:56 volumio-livingroom sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:56 volumio-livingroom sudo[1237]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:56 volumio-livingroom sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 23:51:56 volumio-livingroom sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:56 volumio-livingroom sudo[1239]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:56 volumio-livingroom sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 23:51:56 volumio-livingroom sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:56 volumio-livingroom systemd[1]: Started UPnP Renderer front-end to MPD. Dec 26 23:51:56 volumio-livingroom sudo[1243]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:56 volumio-livingroom volumio[965]: info: Upmpdcli Daemon Started Dec 26 23:51:57 volumio-livingroom volumio[1245]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 26 23:51:57 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:57+07:00" level=debug msg="handling transfer player command from 9eddd26d539edf4b9b2fef41d2a4b7ddf419bed7" Dec 26 23:51:57 volumio-livingroom sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 23:51:57 volumio-livingroom sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:57 volumio-livingroom sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 23:51:57 volumio-livingroom sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 23:51:57 volumio-livingroom systemd[1]: Started MPD Monitor Service. Dec 26 23:51:57 volumio-livingroom systemd[1]: Stopping MPD Monitor Service... Dec 26 23:51:57 volumio-livingroom systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 26 23:51:57 volumio-livingroom systemd[1]: mpd_monitor.service: Succeeded. Dec 26 23:51:57 volumio-livingroom systemd[1]: Stopped MPD Monitor Service. Dec 26 23:51:57 volumio-livingroom sudo[1262]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:57 volumio-livingroom volumio[965]: info: Successfully started MPD Monitor Dec 26 23:51:57 volumio-livingroom systemd[1]: Started MPD Monitor Service. Dec 26 23:51:57 volumio-livingroom mpd_monitor.sh[1268]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 23:51:57 volumio-livingroom sudo[1264]: pam_unix(sudo:session): session closed for user root Dec 26 23:51:57 volumio-livingroom volumio[965]: info: Successfully started MPD Monitor Dec 26 23:51:57 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:57+07:00" level=debug msg="resolved context of track" uri="spotify:search:max+brown+songs" Dec 26 23:51:57 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:57+07:00" level=trace msg="fetched new page 0 with 20 items (list: 20)" uri="spotify:search:max+brown+songs" Dec 26 23:51:57 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:57+07:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:57 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:57+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 26 23:51:57 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:57+07:00" level=trace msg="emitting websocket event: will_play" Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=debug msg="selected format OGG_VORBIS_320 (a55c49f6d43f54dee5759c5dee99048348d2740b)" uri="spotify:track:4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=debug msg="requested aes key for file a55c49f6d43f54dee5759c5dee99048348d2740b, gid: 4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1371" Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1135" Dec 26 23:51:58 volumio-livingroom volumio[965]: info: Initializing connection to go-librespot Websocket Dec 26 23:51:58 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:58+07:00" level=debug msg="new websocket client" Dec 26 23:51:58 volumio-livingroom volumio[965]: info: Connection to go-librespot Websocket established Dec 26 23:51:58 volumio-livingroom volumio[1245]: ....................++++ Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="fetched first chunk of 16, total size is 8274398 bytes" uri="spotify:track:4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="created new output device" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=info msg="loaded track \"Better Me For You (Brown Eyes)\" (paused: true, position: 0ms, duration: 194120ms, prefetched: false)" uri="spotify:track:4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=trace msg="emitting websocket event: metadata" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=trace msg="emitting websocket event: active" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="sending successful reply for dealer request" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 26 23:51:59 volumio-livingroom volumio[965]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4Iu73sdCNxZXZUIyClMoPZ","name":"Better Me For You (Brown Eyes)","artist_names":["Max McNown"],"album_name":"Night Diving","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020eb26fce888d263b2cdf5614","position":0,"duration":194120,"release_date":"year:2025 month:1 day:24","track_number":2,"disc_number":1}} Dec 26 23:51:59 volumio-livingroom volumio[965]: SPOTIFY: received: {"type":"active","data":null} Dec 26 23:51:59 volumio-livingroom volumio[965]: info: Aligning Spotify Volume to Volumio Volume Dec 26 23:51:59 volumio-livingroom volumio[965]: info: CoreCommandRouter::volumioGetState Dec 26 23:51:59 volumio-livingroom volumio[965]: info: CorePlayQueue::getTrack 0 Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=trace msg="emitting websocket event: paused" Dec 26 23:51:59 volumio-livingroom volumio[965]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:search:max+brown+songs","uri":"spotify:track:4Iu73sdCNxZXZUIyClMoPZ","play_origin":"search"}} Dec 26 23:51:59 volumio-livingroom volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 23:51:59 volumio-livingroom volumio[965]: TypeError: Cannot read property 'service' of undefined Dec 26 23:51:59 volumio-livingroom volumio[965]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 26 23:51:59 volumio-livingroom volumio[965]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Dec 26 23:51:59 volumio-livingroom volumio[965]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 26 23:51:59 volumio-livingroom volumio[965]: at WebSocket.emit (events.js:315:20) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver.emit (events.js:315:20) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 26 23:51:59 volumio-livingroom volumio[965]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 26 23:51:59 volumio-livingroom volumio[965]: at Socket.emit (events.js:315:20) Dec 26 23:51:59 volumio-livingroom volumio[965]: at addChunk (internal/streams/readable.js:309:12) Dec 26 23:51:59 volumio-livingroom volumio[965]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 26 23:51:59 volumio-livingroom volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 23:51:59 volumio-livingroom go-librespot[1192]: time="2025-12-26T23:51:59+07:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:4Iu73sdCNxZXZUIyClMoPZ" Dec 26 23:51:59 volumio-livingroom volumio[1245]: ....++++ Dec 26 23:51:59 volumio-livingroom volumio[1245]: e is 65537 (0x010001) Dec 26 23:51:59 volumio-livingroom volumio[1245]: writing RSA key Dec 26 23:51:59 volumio-livingroom sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 23:50 Dec 26 23:51:59 volumio-livingroom sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"