-- Logs begin at Fri 2024-11-29 12:10:43 CST, end at Fri 2024-11-29 12:11:23 CST. -- Nov 29 12:10:43 volumio.pi4 kernel: Booting Linux on physical CPU 0x0 Nov 29 12:10:43 volumio.pi4 kernel: Linux version 6.6.56-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) #1803 SMP Thu Oct 10 14:10:41 BST 2024 Nov 29 12:10:43 volumio.pi4 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Nov 29 12:10:43 volumio.pi4 kernel: CPU: div instructions available: patching division code Nov 29 12:10:43 volumio.pi4 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Nov 29 12:10:43 volumio.pi4 kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.5 Nov 29 12:10:43 volumio.pi4 kernel: random: crng init done Nov 29 12:10:43 volumio.pi4 kernel: Memory policy: Data cache writealloc Nov 29 12:10:43 volumio.pi4 kernel: Reserved memory: created CMA memory pool at 0x0000000029400000, size 64 MiB Nov 29 12:10:43 volumio.pi4 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Nov 29 12:10:43 volumio.pi4 kernel: OF: reserved mem: 0x0000000029400000..0x000000002d3fffff (65536 KiB) map reusable linux,cma Nov 29 12:10:43 volumio.pi4 kernel: OF: reserved mem: 0x000000003ef65060..0x000000003ef6525f (0 KiB) nomap non-reusable nvram@1 Nov 29 12:10:43 volumio.pi4 kernel: OF: reserved mem: 0x000000003ef652a0..0x000000003ef652ec (0 KiB) nomap non-reusable nvram@0 Nov 29 12:10:43 volumio.pi4 kernel: Zone ranges: Nov 29 12:10:43 volumio.pi4 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Nov 29 12:10:43 volumio.pi4 kernel: Normal empty Nov 29 12:10:43 volumio.pi4 kernel: HighMem [mem 0x0000000030000000-0x00000001ffffffff] Nov 29 12:10:43 volumio.pi4 kernel: Movable zone start for each node Nov 29 12:10:43 volumio.pi4 kernel: Early memory node ranges Nov 29 12:10:43 volumio.pi4 kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Nov 29 12:10:43 volumio.pi4 kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Nov 29 12:10:43 volumio.pi4 kernel: node 0: [mem 0x0000000100000000-0x00000001ffffffff] Nov 29 12:10:43 volumio.pi4 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Nov 29 12:10:43 volumio.pi4 kernel: percpu: Embedded 18 pages/cpu s41940 r8192 d23596 u73728 Nov 29 12:10:43 volumio.pi4 kernel: pcpu-alloc: s41940 r8192 d23596 u73728 alloc=18*4096 Nov 29 12:10:43 volumio.pi4 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 29 12:10:43 volumio.pi4 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:E1:B4:F7 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=ed280c2b-b4b4-43e6-87d7-b67377eacab2 imgfile=/volumio_current.sqsh bootpart=UUID=FCA2-3F9A datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd 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 Nov 29 12:10:43 volumio.pi4 kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=ed280c2b-b4b4-43e6-87d7-b67377eacab2 imgfile=/volumio_current.sqsh bootpart=UUID=FCA2-3F9A datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Nov 29 12:10:43 volumio.pi4 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2046272 Nov 29 12:10:43 volumio.pi4 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 29 12:10:43 volumio.pi4 kernel: software IO TLB: area num 4. Nov 29 12:10:43 volumio.pi4 kernel: software IO TLB: mapped [mem 0x0000000020c00000-0x0000000024c00000] (64MB) Nov 29 12:10:43 volumio.pi4 kernel: Memory: 7938304K/8192000K available (12288K kernel code, 1527K rwdata, 3488K rodata, 2048K init, 628K bss, 188160K reserved, 65536K cma-reserved, 7405568K highmem) Nov 29 12:10:43 volumio.pi4 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 29 12:10:43 volumio.pi4 kernel: ftrace: allocating 38819 entries in 114 pages Nov 29 12:10:43 volumio.pi4 kernel: ftrace: allocated 114 pages with 4 groups Nov 29 12:10:43 volumio.pi4 kernel: trace event string verifier disabled Nov 29 12:10:43 volumio.pi4 kernel: rcu: Hierarchical RCU implementation. Nov 29 12:10:43 volumio.pi4 kernel: Rude variant of Tasks RCU enabled. Nov 29 12:10:43 volumio.pi4 kernel: Tracing variant of Tasks RCU enabled. Nov 29 12:10:43 volumio.pi4 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Nov 29 12:10:43 volumio.pi4 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Nov 29 12:10:43 volumio.pi4 kernel: GIC: Using split EOI/Deactivate mode Nov 29 12:10:43 volumio.pi4 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 29 12:10:43 volumio.pi4 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Nov 29 12:10:43 volumio.pi4 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Nov 29 12:10:43 volumio.pi4 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Nov 29 12:10:43 volumio.pi4 kernel: Switching to timer-based delay loop, resolution 18ns Nov 29 12:10:43 volumio.pi4 kernel: Console: colour dummy device 80x30 Nov 29 12:10:43 volumio.pi4 kernel: printk: console [tty1] enabled Nov 29 12:10:43 volumio.pi4 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Nov 29 12:10:43 volumio.pi4 kernel: CPU: Testing write buffer coherency: ok Nov 29 12:10:43 volumio.pi4 kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Nov 29 12:10:43 volumio.pi4 kernel: pid_max: default: 32768 minimum: 301 Nov 29 12:10:43 volumio.pi4 kernel: LSM: initializing lsm=capability,integrity Nov 29 12:10:43 volumio.pi4 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: cgroup: Disabling memory control group subsystem Nov 29 12:10:43 volumio.pi4 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Nov 29 12:10:43 volumio.pi4 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 29 12:10:43 volumio.pi4 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 29 12:10:43 volumio.pi4 kernel: Setting up static identity map for 0x200000 - 0x20003c Nov 29 12:10:43 volumio.pi4 kernel: rcu: Hierarchical SRCU implementation. Nov 29 12:10:43 volumio.pi4 kernel: rcu: Max phase no-delay instances is 1000. Nov 29 12:10:43 volumio.pi4 kernel: smp: Bringing up secondary CPUs ... Nov 29 12:10:43 volumio.pi4 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Nov 29 12:10:43 volumio.pi4 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Nov 29 12:10:43 volumio.pi4 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Nov 29 12:10:43 volumio.pi4 kernel: smp: Brought up 1 node, 4 CPUs Nov 29 12:10:43 volumio.pi4 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Nov 29 12:10:43 volumio.pi4 kernel: CPU: All CPU(s) started in HYP mode. Nov 29 12:10:43 volumio.pi4 kernel: CPU: Virtualization extensions available. Nov 29 12:10:43 volumio.pi4 kernel: devtmpfs: initialized Nov 29 12:10:43 volumio.pi4 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Nov 29 12:10:43 volumio.pi4 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Nov 29 12:10:43 volumio.pi4 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: pinctrl core: initialized pinctrl subsystem Nov 29 12:10:43 volumio.pi4 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 29 12:10:43 volumio.pi4 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Nov 29 12:10:43 volumio.pi4 kernel: audit: initializing netlink subsys (disabled) Nov 29 12:10:43 volumio.pi4 kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Nov 29 12:10:43 volumio.pi4 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 29 12:10:43 volumio.pi4 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Nov 29 12:10:43 volumio.pi4 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Nov 29 12:10:43 volumio.pi4 kernel: Serial: AMBA PL011 UART driver Nov 29 12:10:43 volumio.pi4 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Nov 29 12:10:43 volumio.pi4 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-09-13T15:58:42, variant start Nov 29 12:10:43 volumio.pi4 kernel: raspberrypi-firmware soc:firmware: Firmware hash is ddfba3e3c234500025b545512b4b214f28e453e9 Nov 29 12:10:43 volumio.pi4 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 29 12:10:43 volumio.pi4 kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 Nov 29 12:10:43 volumio.pi4 kernel: SCSI subsystem initialized Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver usbfs Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver hub Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new device driver usb Nov 29 12:10:43 volumio.pi4 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 29 12:10:43 volumio.pi4 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 29 12:10:43 volumio.pi4 kernel: PTP clock support registered Nov 29 12:10:43 volumio.pi4 kernel: vgaarb: loaded Nov 29 12:10:43 volumio.pi4 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 29 12:10:43 volumio.pi4 kernel: VFS: Disk quotas dquot_6.6.0 Nov 29 12:10:43 volumio.pi4 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Nov 29 12:10:43 volumio.pi4 kernel: FS-Cache: Loaded Nov 29 12:10:43 volumio.pi4 kernel: CacheFiles: Loaded Nov 29 12:10:43 volumio.pi4 kernel: NET: Registered PF_INET protocol family Nov 29 12:10:43 volumio.pi4 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: TCP: Hash tables configured (established 8192 bind 8192) Nov 29 12:10:43 volumio.pi4 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Nov 29 12:10:43 volumio.pi4 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 29 12:10:43 volumio.pi4 kernel: RPC: Registered named UNIX socket transport module. Nov 29 12:10:43 volumio.pi4 kernel: RPC: Registered udp transport module. Nov 29 12:10:43 volumio.pi4 kernel: RPC: Registered tcp transport module. Nov 29 12:10:43 volumio.pi4 kernel: RPC: Registered tcp-with-tls transport module. Nov 29 12:10:43 volumio.pi4 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 29 12:10:43 volumio.pi4 kernel: PCI: CLS 0 bytes, default 64 Nov 29 12:10:43 volumio.pi4 kernel: Trying to unpack rootfs image as initramfs... Nov 29 12:10:43 volumio.pi4 kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Nov 29 12:10:43 volumio.pi4 kernel: Freeing initrd memory: 28068K Nov 29 12:10:43 volumio.pi4 kernel: Initialise system trusted keyrings Nov 29 12:10:43 volumio.pi4 kernel: workingset: timestamp_bits=14 max_order=21 bucket_order=7 Nov 29 12:10:43 volumio.pi4 kernel: zbud: loaded Nov 29 12:10:43 volumio.pi4 kernel: NFS: Registering the id_resolver key type Nov 29 12:10:43 volumio.pi4 kernel: Key type id_resolver registered Nov 29 12:10:43 volumio.pi4 kernel: Key type id_legacy registered Nov 29 12:10:43 volumio.pi4 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Nov 29 12:10:43 volumio.pi4 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Nov 29 12:10:43 volumio.pi4 kernel: Key type asymmetric registered Nov 29 12:10:43 volumio.pi4 kernel: Asymmetric key parser 'x509' registered Nov 29 12:10:43 volumio.pi4 kernel: bounce: pool size: 64 pages Nov 29 12:10:43 volumio.pi4 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Nov 29 12:10:43 volumio.pi4 kernel: io scheduler mq-deadline registered Nov 29 12:10:43 volumio.pi4 kernel: io scheduler kyber registered Nov 29 12:10:43 volumio.pi4 kernel: io scheduler bfq registered Nov 29 12:10:43 volumio.pi4 kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Nov 29 12:10:43 volumio.pi4 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Nov 29 12:10:43 volumio.pi4 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Nov 29 12:10:43 volumio.pi4 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x067fffffff -> 0x0080000000 Nov 29 12:10:43 volumio.pi4 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x01ffffffff -> 0x0400000000 Nov 29 12:10:43 volumio.pi4 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Nov 29 12:10:43 volumio.pi4 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 29 12:10:43 volumio.pi4 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x67fffffff] (bus address [0x80000000-0xffffffff]) Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Nov 29 12:10:43 volumio.pi4 kernel: PCI: bus0: Fast back to back transfers disabled Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Nov 29 12:10:43 volumio.pi4 kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Nov 29 12:10:43 volumio.pi4 kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Nov 29 12:10:43 volumio.pi4 kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Nov 29 12:10:43 volumio.pi4 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Nov 29 12:10:43 volumio.pi4 kernel: PCI: bus1: Fast back to back transfers disabled Nov 29 12:10:43 volumio.pi4 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Nov 29 12:10:43 volumio.pi4 kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 256), Max Read Rq 512 Nov 29 12:10:43 volumio.pi4 kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Nov 29 12:10:43 volumio.pi4 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Nov 29 12:10:43 volumio.pi4 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Nov 29 12:10:43 volumio.pi4 kernel: Console: switching to colour frame buffer device 228x61 Nov 29 12:10:43 volumio.pi4 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1824x984 Nov 29 12:10:43 volumio.pi4 kernel: iproc-rng200 fe104000.rng: hwrng registered Nov 29 12:10:43 volumio.pi4 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Nov 29 12:10:43 volumio.pi4 kernel: brd: module loaded Nov 29 12:10:43 volumio.pi4 kernel: loop: module loaded Nov 29 12:10:43 volumio.pi4 kernel: Loading iSCSI transport class v2.0-870. Nov 29 12:10:43 volumio.pi4 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Nov 29 12:10:43 volumio.pi4 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new device driver r8152-cfgselector Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver r8152 Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver lan78xx Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver smsc95xx Nov 29 12:10:43 volumio.pi4 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Nov 29 12:10:43 volumio.pi4 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Nov 29 12:10:43 volumio.pi4 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890 Nov 29 12:10:43 volumio.pi4 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Nov 29 12:10:43 volumio.pi4 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Nov 29 12:10:43 volumio.pi4 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Nov 29 12:10:43 volumio.pi4 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Nov 29 12:10:43 volumio.pi4 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 29 12:10:43 volumio.pi4 kernel: usb usb1: Product: xHCI Host Controller Nov 29 12:10:43 volumio.pi4 kernel: usb usb1: Manufacturer: Linux 6.6.56-v7l+ xhci-hcd Nov 29 12:10:43 volumio.pi4 kernel: usb usb1: SerialNumber: 0000:01:00.0 Nov 29 12:10:43 volumio.pi4 kernel: hub 1-0:1.0: USB hub found Nov 29 12:10:43 volumio.pi4 kernel: hub 1-0:1.0: 1 port detected Nov 29 12:10:43 volumio.pi4 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Nov 29 12:10:43 volumio.pi4 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 29 12:10:43 volumio.pi4 kernel: usb usb2: Product: xHCI Host Controller Nov 29 12:10:43 volumio.pi4 kernel: usb usb2: Manufacturer: Linux 6.6.56-v7l+ xhci-hcd Nov 29 12:10:43 volumio.pi4 kernel: usb usb2: SerialNumber: 0000:01:00.0 Nov 29 12:10:43 volumio.pi4 kernel: hub 2-0:1.0: USB hub found Nov 29 12:10:43 volumio.pi4 kernel: hub 2-0:1.0: 4 ports detected Nov 29 12:10:43 volumio.pi4 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Nov 29 12:10:43 volumio.pi4 kernel: dwc_otg: FIQ enabled Nov 29 12:10:43 volumio.pi4 kernel: dwc_otg: NAK holdoff enabled Nov 29 12:10:43 volumio.pi4 kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 29 12:10:43 volumio.pi4 kernel: Module dwc_common_port init Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver uas Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver usb-storage Nov 29 12:10:43 volumio.pi4 kernel: mousedev: PS/2 mouse device common for all mice Nov 29 12:10:43 volumio.pi4 kernel: sdhci: Secure Digital Host Controller Interface driver Nov 29 12:10:43 volumio.pi4 kernel: sdhci: Copyright(c) Pierre Ossman Nov 29 12:10:43 volumio.pi4 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 29 12:10:43 volumio.pi4 kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 29 12:10:43 volumio.pi4 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 29 12:10:43 volumio.pi4 kernel: usbcore: registered new interface driver usbhid Nov 29 12:10:43 volumio.pi4 kernel: usbhid: USB HID core driver Nov 29 12:10:43 volumio.pi4 kernel: Initializing XFRM netlink socket Nov 29 12:10:43 volumio.pi4 kernel: NET: Registered PF_PACKET protocol family Nov 29 12:10:43 volumio.pi4 kernel: Key type dns_resolver registered Nov 29 12:10:43 volumio.pi4 kernel: Registering SWP/SWPB emulation handler Nov 29 12:10:43 volumio.pi4 kernel: registered taskstats version 1 Nov 29 12:10:43 volumio.pi4 kernel: Loading compiled-in X.509 certificates Nov 29 12:10:43 volumio.pi4 kernel: Key type .fscrypt registered Nov 29 12:10:43 volumio.pi4 kernel: Key type fscrypt-provisioning registered Nov 29 12:10:43 volumio.pi4 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Nov 29 12:10:43 volumio.pi4 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Nov 29 12:10:43 volumio.pi4 kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Nov 29 12:10:43 volumio.pi4 kernel: serial serial0: tty port ttyAMA1 registered Nov 29 12:10:43 volumio.pi4 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Nov 29 12:10:43 volumio.pi4 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Nov 29 12:10:43 volumio.pi4 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Nov 29 12:10:43 volumio.pi4 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Nov 29 12:10:43 volumio.pi4 kernel: of_cfs_init Nov 29 12:10:43 volumio.pi4 kernel: of_cfs_init: OK Nov 29 12:10:43 volumio.pi4 kernel: clk: Disabling unused clocks Nov 29 12:10:43 volumio.pi4 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Nov 29 12:10:43 volumio.pi4 kernel: Freeing unused kernel image (initmem) memory: 2048K Nov 29 12:10:43 volumio.pi4 kernel: Run /init as init process Nov 29 12:10:43 volumio.pi4 kernel: with arguments: Nov 29 12:10:43 volumio.pi4 kernel: /init Nov 29 12:10:43 volumio.pi4 kernel: splash Nov 29 12:10:43 volumio.pi4 kernel: nodebug Nov 29 12:10:43 volumio.pi4 kernel: with environment: Nov 29 12:10:43 volumio.pi4 kernel: HOME=/ Nov 29 12:10:43 volumio.pi4 kernel: TERM=linux Nov 29 12:10:43 volumio.pi4 kernel: imgpart=UUID=ed280c2b-b4b4-43e6-87d7-b67377eacab2 Nov 29 12:10:43 volumio.pi4 kernel: imgfile=/volumio_current.sqsh Nov 29 12:10:43 volumio.pi4 kernel: bootpart=UUID=FCA2-3F9A Nov 29 12:10:43 volumio.pi4 kernel: datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd Nov 29 12:10:43 volumio.pi4 kernel: uuidconfig=cmdline.txt Nov 29 12:10:43 volumio.pi4 kernel: pcie_aspm=off Nov 29 12:10:43 volumio.pi4 kernel: bootdelay=7 Nov 29 12:10:43 volumio.pi4 kernel: use_kmsg=no Nov 29 12:10:43 volumio.pi4 kernel: mmc0: new high speed SDXC card at address b368 Nov 29 12:10:43 volumio.pi4 kernel: mmcblk0: mmc0:b368 NCard 116 GiB Nov 29 12:10:43 volumio.pi4 kernel: mmcblk0: p1 p2 p3 Nov 29 12:10:43 volumio.pi4 kernel: mmcblk0: mmc0:b368 NCard 116 GiB Nov 29 12:10:43 volumio.pi4 kernel: mmc1: new high speed SDIO card at address 0001 Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1: Product: USB2.0 Hub Nov 29 12:10:43 volumio.pi4 kernel: hub 1-1:1.0: USB hub found Nov 29 12:10:43 volumio.pi4 kernel: hub 1-1:1.0: 4 ports detected Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1.4: new full-speed USB device number 3 using xhci_hcd Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1.4: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.10 Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1.4: Product: USB Receiver Nov 29 12:10:43 volumio.pi4 kernel: usb 1-1.4: Manufacturer: Logitech Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech USB Receiver as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:046D:C52B.0001/input/input0 Nov 29 12:10:43 volumio.pi4 kernel: hid-generic 0003:046D:C52B.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input0 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech USB Receiver Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C52B.0002/input/input1 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech USB Receiver Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C52B.0002/input/input2 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech USB Receiver System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C52B.0002/input/input3 Nov 29 12:10:43 volumio.pi4 kernel: hid-generic 0003:046D:C52B.0002: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input1 Nov 29 12:10:43 volumio.pi4 kernel: hid-generic 0003:046D:C52B.0003: hiddev97,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input2 Nov 29 12:10:43 volumio.pi4 kernel: logitech-djreceiver 0003:046D:C52B.0003: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input2 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech Wireless Device PID:404d Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input5 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech Wireless Device PID:404d Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input6 Nov 29 12:10:43 volumio.pi4 kernel: hid-generic 0003:046D:404D.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech Wireless Device PID:404d] on usb-0000:01:00.0-1.4/input2:1 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech K370s/K375s as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.0003/0003:046D:4061.0005/input/input10 Nov 29 12:10:43 volumio.pi4 kernel: logitech-hidpp-device 0003:046D:4061.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech K370s/K375s] on usb-0000:01:00.0-1.4/input2:2 Nov 29 12:10:43 volumio.pi4 kernel: input: Logitech K400 Plus as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input11 Nov 29 12:10:43 volumio.pi4 kernel: logitech-hidpp-device 0003:046D:404D.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-0000:01:00.0-1.4/input2:1 Nov 29 12:10:43 volumio.pi4 kernel: fuse: init (API version 7.39) Nov 29 12:10:43 volumio.pi4 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 29 12:10:43 volumio.pi4 kernel: EXT4-fs (mmcblk0p2): recovery complete Nov 29 12:10:43 volumio.pi4 kernel: EXT4-fs (mmcblk0p2): mounted filesystem ed280c2b-b4b4-43e6-87d7-b67377eacab2 r/w with ordered data mode. Quota mode: none. Nov 29 12:10:43 volumio.pi4 kernel: loop0: detected capacity change from 0 to 994880 Nov 29 12:10:43 volumio.pi4 kernel: EXT4-fs (mmcblk0p3): recovery complete Nov 29 12:10:43 volumio.pi4 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 0367e456-8c2f-458a-8465-47f124ad9fdd r/w with ordered data mode. Quota mode: none. Nov 29 12:10:43 volumio.pi4 systemd[1]: System time before build time, advancing clock. Nov 29 12:10:43 volumio.pi4 kernel: NET: Registered PF_INET6 protocol family Nov 29 12:10:43 volumio.pi4 kernel: Segment Routing with IPv6 Nov 29 12:10:43 volumio.pi4 kernel: In-situ OAM (IOAM) with IPv6 Nov 29 12:10:43 volumio.pi4 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) Nov 29 12:10:43 volumio.pi4 systemd[1]: Detected architecture arm. Nov 29 12:10:43 volumio.pi4 systemd[1]: Set hostname to . Nov 29 12:10:43 volumio.pi4 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. Nov 29 12:10:43 volumio.pi4 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 29 12:10:43 volumio.pi4 systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 29 12:10:43 volumio.pi4 systemd[1]: Listening on Journal Socket. Nov 29 12:10:43 volumio.pi4 systemd[1]: Starting Restore / save the current clock... Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounting Kernel Debug File System... Nov 29 12:10:43 volumio.pi4 systemd[1]: Started dynamicswap service. Nov 29 12:10:43 volumio.pi4 kernel: i2c_dev: i2c /dev entries driver Nov 29 12:10:43 volumio.pi4 systemd-journald[314]: Journal started Nov 29 12:10:43 volumio.pi4 systemd-journald[314]: Runtime journal (/run/log/journal/3b9f41731e901aee8cf8cb2a672e4f3b) is 3.7M, max 30.0M, 26.2M free. Nov 29 12:10:43 volumio.pi4 systemd-modules-load[311]: Inserted module 'i2c_dev' Nov 29 12:10:43 volumio.pi4 fake-hwclock[301]: Fri Nov 29 18:10:43 UTC 2024 Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Load/Save Random Seed. Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounted Kernel Configuration File System. Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounted FUSE Control File System. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Apply Kernel Variables. Nov 29 12:10:43 volumio.pi4 systemd[1]: dynamicswap.service: Succeeded. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Create System Users. Nov 29 12:10:43 volumio.pi4 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 29 12:10:43 volumio.pi4 systemd[1]: Started udev Coldplug all Devices. Nov 29 12:10:43 volumio.pi4 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Create Static Device Nodes in /dev. Nov 29 12:10:43 volumio.pi4 systemd[1]: Starting udev Kernel Device Manager... Nov 29 12:10:43 volumio.pi4 systemd[1]: Reached target Local File Systems (Pre). Nov 29 12:10:43 volumio.pi4 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounting /tmp... Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounting /var/spool/cups... Nov 29 12:10:43 volumio.pi4 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounting /var/log... Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounted /tmp. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Helper to synchronize boot up for ifupdown. Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounted /var/spool/cups. Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounted /var/log. Nov 29 12:10:43 volumio.pi4 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounting /var/spool/cups/tmp... Nov 29 12:10:43 volumio.pi4 systemd[1]: Mounted /var/spool/cups/tmp. Nov 29 12:10:43 volumio.pi4 systemd-udevd[344]: Network interface NamePolicy= disabled on kernel command line, ignoring. Nov 29 12:10:43 volumio.pi4 systemd-journald[314]: Runtime journal (/run/log/journal/3b9f41731e901aee8cf8cb2a672e4f3b) is 3.7M, max 30.0M, 26.2M free. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Flush Journal to Persistent Storage. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started udev Kernel Device Manager. Nov 29 12:10:43 volumio.pi4 systemd[1]: Starting Show Plymouth Boot Screen... Nov 29 12:10:43 volumio.pi4 systemd[1]: plymouth-start.service: Succeeded. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Show Plymouth Boot Screen. Nov 29 12:10:43 volumio.pi4 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 29 12:10:43 volumio.pi4 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 29 12:10:43 volumio.pi4 systemd[1]: Reached target Paths. Nov 29 12:10:43 volumio.pi4 systemd[1]: Reached target Local Encrypted Volumes. Nov 29 12:10:43 volumio.pi4 systemd-udevd[366]: Using default interface naming scheme 'v240'. Nov 29 12:10:44 volumio.pi4 kernel: mc: Linux media interface: v0.10 Nov 29 12:10:44 volumio.pi4 systemd-udevd[366]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 29 12:10:44 volumio.pi4 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Nov 29 12:10:44 volumio.pi4 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Nov 29 12:10:44 volumio.pi4 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:44 volumio.pi4 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Nov 29 12:10:44 volumio.pi4 kernel: [vc_sm_connected_init]: start Nov 29 12:10:44 volumio.pi4 kernel: [vc_sm_connected_init]: installed successfully Nov 29 12:10:44 volumio.pi4 kernel: videodev: Linux video capture interface: v2.00 Nov 29 12:10:44 volumio.pi4 systemd-udevd[382]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 29 12:10:44 volumio.pi4 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:45 volumio.pi4 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:45 volumio.pi4 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:45 volumio.pi4 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Nov 29 12:10:45 volumio.pi4 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Nov 29 12:10:45 volumio.pi4 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Nov 29 12:10:45 volumio.pi4 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Nov 29 12:10:45 volumio.pi4 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Nov 29 12:10:45 volumio.pi4 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Nov 29 12:10:45 volumio.pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Nov 29 12:10:45 volumio.pi4 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: Core ver 2.22 Nov 29 12:10:45 volumio.pi4 kernel: NET: Registered PF_BLUETOOTH protocol family Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: HCI device and connection manager initialized Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: HCI socket layer initialized Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: L2CAP socket layer initialized Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: SCO socket layer initialized Nov 29 12:10:45 volumio.pi4 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 29 12:10:45 volumio.pi4 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Nov 29 12:10:45 volumio.pi4 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Nov 29 12:10:45 volumio.pi4 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 29 12:10:45 volumio.pi4 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: HCI UART driver ver 2.3 Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: HCI UART protocol H4 registered Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: HCI UART protocol Broadcom registered Nov 29 12:10:45 volumio.pi4 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Nov 29 12:10:45 volumio.pi4 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Nov 29 12:10:45 volumio.pi4 kernel: uart-pl011 fe201000.serial: no DMA platform data Nov 29 12:10:45 volumio.pi4 systemd-udevd[417]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: hci0: BCM: chip id 107 Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: hci0: BCM: features 0x2f Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: hci0: BCM4345C0 Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Nov 29 12:10:45 volumio.pi4 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Nov 29 12:10:45 volumio.pi4 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Nov 29 12:10:45 volumio.pi4 kernel: usbcore: registered new interface driver brcmfmac Nov 29 12:10:45 volumio.pi4 systemd-udevd[375]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 29 12:10:45 volumio.pi4 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Nov 29 12:10:46 volumio.pi4 systemd-udevd[379]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 29 12:10:46 volumio.pi4 kernel: alsactl[490]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Nov 29 12:10:46 volumio.pi4 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Nov 29 12:10:46 volumio.pi4 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 Nov 29 12:10:46 volumio.pi4 systemd-udevd[393]: Using default interface naming scheme 'v240'. Nov 29 12:10:46 volumio.pi4 systemd-udevd[393]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 29 12:10:46 volumio.pi4 systemd[1]: Found device /dev/disk/by-uuid/FCA2-3F9A. Nov 29 12:10:46 volumio.pi4 kernel: Bluetooth: hci0: BCM: features 0x2f Nov 29 12:10:46 volumio.pi4 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Nov 29 12:10:46 volumio.pi4 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Nov 29 12:10:46 volumio.pi4 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Nov 29 12:10:46 volumio.pi4 systemd[1]: Created slice system-bthelper.slice. Nov 29 12:10:46 volumio.pi4 systemd[1]: Starting Show Plymouth Boot Screen... Nov 29 12:10:46 volumio.pi4 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 29 12:10:46 volumio.pi4 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Nov 29 12:10:46 volumio.pi4 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 29 12:10:46 volumio.pi4 systemd[1]: Starting File System Check on /dev/disk/by-uuid/FCA2-3F9A... Nov 29 12:10:46 volumio.pi4 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 29 12:10:46 volumio.pi4 systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 29 12:10:46 volumio.pi4 systemd[1]: plymouth-start.service: Succeeded. Nov 29 12:10:46 volumio.pi4 systemd[1]: Started Show Plymouth Boot Screen. Nov 29 12:10:46 volumio.pi4 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 29 12:10:47 volumio.pi4 systemd[1]: Started File System Check Daemon to report status. Nov 29 12:10:47 volumio.pi4 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Nov 29 12:10:47 volumio.pi4 systemd[1]: Started Load/Save RF Kill Switch Status. Nov 29 12:10:47 volumio.pi4 systemd-fsck[511]: fsck.fat 4.1 (2017-01-24) Nov 29 12:10:47 volumio.pi4 systemd-fsck[511]: /dev/mmcblk0p1: 395 files, 166075/184582 clusters Nov 29 12:10:47 volumio.pi4 systemd[1]: Started File System Check on /dev/disk/by-uuid/FCA2-3F9A. Nov 29 12:10:47 volumio.pi4 systemd[1]: Mounting /boot... Nov 29 12:10:47 volumio.pi4 systemd[1]: Mounted /boot. Nov 29 12:10:47 volumio.pi4 systemd[1]: Reached target Local File Systems. Nov 29 12:10:47 volumio.pi4 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 29 12:10:47 volumio.pi4 systemd[1]: Starting Create Volatile Files and Directories... Nov 29 12:10:47 volumio.pi4 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 29 12:10:47 volumio.pi4 systemd[1]: Started ifup for wlan0. Nov 29 12:10:47 volumio.pi4 systemd[1]: Starting Raise network interfaces... Nov 29 12:10:47 volumio.pi4 systemd[1]: Starting Preprocess NFS configuration... Nov 29 12:10:47 volumio.pi4 systemd[1]: Started ifup for eth0. Nov 29 12:10:47 volumio.pi4 systemd[1]: plymouth-read-write.service: Succeeded. Nov 29 12:10:47 volumio.pi4 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 29 12:10:47 volumio.pi4 systemd[1]: Received SIGRTMIN+20 from PID 195 (plymouthd). Nov 29 12:10:47 volumio.pi4 systemd[1]: nfs-config.service: Succeeded. Nov 29 12:10:47 volumio.pi4 systemd[1]: Started Preprocess NFS configuration. Nov 29 12:10:47 volumio.pi4 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Nov 29 12:10:47 volumio.pi4 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Nov 29 12:10:47 volumio.pi4 systemd[1]: Reached target NFS client services. Nov 29 12:10:47 volumio.pi4 systemd[1]: Started Create Volatile Files and Directories. Nov 29 12:10:47 volumio.pi4 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Nov 29 12:10:47 volumio.pi4 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 29 12:10:47 volumio.pi4 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Nov 29 12:10:47 volumio.pi4 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 29 12:10:47 volumio.pi4 systemd[1]: Starting RPC bind portmap service... Nov 29 12:10:47 volumio.pi4 systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 29 12:10:47 volumio.pi4 systemd[1]: Reached target System Initialization. Nov 29 12:10:47 volumio.pi4 kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 29 12:10:47 volumio.pi4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 29 12:10:48 volumio.pi4 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Daily apt download activities. Nov 29 12:10:48 volumio.pi4 systemd[1]: Listening on triggerhappy.socket. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Daily apt upgrade and clean activities. Nov 29 12:10:48 volumio.pi4 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target Sockets. Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target Basic System. Nov 29 12:10:48 volumio.pi4 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Nov 29 12:10:48 volumio.pi4 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started volumio-remote-updater.service. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Volumio Iptables Module. Nov 29 12:10:48 volumio.pi4 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started D-Bus System Message Bus. Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting WPA supplicant... Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Wireless Services... Nov 29 12:10:48 volumio.pi4 systemd[1]: Started MPD Monitor Service. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Volumio Log Rotation Service. Nov 29 12:10:48 volumio.pi4 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting dhcpcd on all interfaces... Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Login Service... Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Manage Sound Card State (restore and store). Nov 29 12:10:48 volumio.pi4 alsactl[636]: alsactl 1.1.8 daemon started Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Save/Restore Sound Card State... Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting triggerhappy global hotkey daemon... Nov 29 12:10:48 volumio.pi4 dhcpcd[633]: Not running dhcpcd because /etc/network/interfaces Nov 29 12:10:48 volumio.pi4 dhcpcd[633]: defines some interfaces that will use a Nov 29 12:10:48 volumio.pi4 dhcpcd[633]: DHCP client or static address Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Volumio Time Update Utility... Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target Timers. Nov 29 12:10:48 volumio.pi4 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 29 12:10:48 volumio.pi4 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 29 12:10:48 volumio.pi4 systemd[1]: Failed to start dhcpcd on all interfaces. Nov 29 12:10:48 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Fetching time from Volumio... Nov 29 12:10:48 volumio.pi4 systemd[1]: Started RPC bind portmap service. Nov 29 12:10:48 volumio.pi4 thd[638]: Unable to parse trigger line: Nov 29 12:10:48 volumio.pi4 thd[638]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 29 12:10:48 volumio.pi4 thd[638]: Unable to parse trigger line: Nov 29 12:10:48 volumio.pi4 thd[638]: Found socket passed from systemd Nov 29 12:10:48 volumio.pi4 systemd[1]: Started triggerhappy global hotkey daemon. Nov 29 12:10:48 volumio.pi4 systemd[1]: hciuart.service: Succeeded. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 29 12:10:48 volumio.pi4 systemd[1]: Started Save/Restore Sound Card State. Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target Sound Card. Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting Raspberry Pi bluetooth helper... Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target Remote File Systems (Pre). Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target Remote File Systems. Nov 29 12:10:48 volumio.pi4 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 29 12:10:48 volumio.pi4 systemd[1]: Reached target RPC Port Mapper. Nov 29 12:10:48 volumio.pi4 sh[525]: wlan0=wlan0 Nov 29 12:10:48 volumio.pi4 systemd-logind[634]: Watching system buttons on /dev/input/event1 (Logitech K400 Plus) Nov 29 12:10:48 volumio.pi4 systemd-logind[634]: Watching system buttons on /dev/input/event0 (Logitech K370s/K375s) Nov 29 12:10:48 volumio.pi4 systemd-logind[634]: New seat seat0. Nov 29 12:10:48 volumio.pi4 avahi-daemon[605]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 29 12:10:48 volumio.pi4 avahi-daemon[605]: Successfully dropped root privileges. Nov 29 12:10:48 volumio.pi4 avahi-daemon[605]: avahi-daemon 0.7 starting up. Nov 29 12:10:49 volumio.pi4 mpd_monitor.sh[631]: MPD error: Connection refused Nov 29 12:10:49 volumio.pi4 bthelper[652]: Raspberry Pi BDADDR already set Nov 29 12:10:49 volumio.pi4 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Nov 29 12:10:49 volumio.pi4 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Nov 29 12:10:49 volumio.pi4 sh[530]: eth0: waiting for carrier Nov 29 12:10:49 volumio.pi4 dhcpcd[576]: eth0: waiting for carrier Nov 29 12:10:49 volumio.pi4 mpd_monitor.sh[631]: mpd: no process found Nov 29 12:10:49 volumio.pi4 systemd[1]: Started Login Service. Nov 29 12:10:49 volumio.pi4 systemd[1]: Started Raspberry Pi bluetooth helper. Nov 29 12:10:49 volumio.pi4 avahi-daemon[605]: Successfully called chroot(). Nov 29 12:10:49 volumio.pi4 avahi-daemon[605]: Successfully dropped remaining capabilities. Nov 29 12:10:49 volumio.pi4 wpa_supplicant[628]: Successfully initialized wpa_supplicant Nov 29 12:10:49 volumio.pi4 avahi-daemon[605]: Loading service file /services/volumio.service. Nov 29 12:10:49 volumio.pi4 avahi-daemon[605]: Network interface enumeration completed. Nov 29 12:10:49 volumio.pi4 avahi-daemon[605]: Server startup complete. Host name is volumio.local. Local service cookie is 2014431448. Nov 29 12:10:49 volumio.pi4 avahi-daemon[605]: Service "Volumio.pi4" (/services/volumio.service) successfully established. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started Raise network interfaces. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started WPA supplicant. Nov 29 12:10:50 volumio.pi4 volumio-remote-updater[621]: Error: No active session Nov 29 12:10:50 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:10:50] [info] asio async_connect error: system:111 (Connection refused) Nov 29 12:10:50 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:10:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 29 12:10:50 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:10:50] [error] handle_connect error: Underlying Transport Error Nov 29 12:10:50 volumio.pi4 systemd[1]: Listening on mpd.socket. Nov 29 12:10:50 volumio.pi4 systemd[1]: Reached target Network. Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Network Time Service... Nov 29 12:10:50 volumio.pi4 systemd[1]: Reached target Network is Online. Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting LSB: Brings up/down network automatically... Nov 29 12:10:50 volumio.pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:10:50 volumio.pi4 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Samba NMB Daemon... Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting /etc/rc.local Compatibility... Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Permit User Sessions... Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Music Player Daemon... Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Bluetooth service... Nov 29 12:10:50 volumio.pi4 volumio[620]: Could not open config: /tmp/upmpdcli.conf Nov 29 12:10:50 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Date not found in response Nov 29 12:10:50 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Retrying in 5 seconds... Nov 29 12:10:50 volumio.pi4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 29 12:10:50 volumio.pi4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started /etc/rc.local Compatibility. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started Permit User Sessions. Nov 29 12:10:50 volumio.pi4 systemd[1]: iptables.service: Succeeded. Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Hold until boot process finishes up... Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 29 12:10:50 volumio.pi4 systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started Hold until boot process finishes up. Nov 29 12:10:50 volumio.pi4 loadcpufreq[659]: Loading cpufreq kernel modules...done (none). Nov 29 12:10:50 volumio.pi4 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 29 12:10:50 volumio.pi4 systemd[1]: plymouth-quit.service: Succeeded. Nov 29 12:10:50 volumio.pi4 systemd[1]: Started Terminate Plymouth Boot Screen. Nov 29 12:10:50 volumio.pi4 systemd[1]: Received SIGRTMIN+21 from PID 195 (n/a). Nov 29 12:10:50 volumio.pi4 systemd[1]: Received SIGRTMIN+21 from PID 195 (n/a). Nov 29 12:10:50 volumio.pi4 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 29 12:10:51 volumio.pi4 systemd[1]: Started Getty on tty1. Nov 29 12:10:51 volumio.pi4 systemd[1]: Reached target Login Prompts. Nov 29 12:10:51 volumio.pi4 ifplugd(eth0)[799]: ifplugd 0.28 initializing. Nov 29 12:10:51 volumio.pi4 ifplugd(eth0)[799]: Using interface eth0/E4:5F:01:E1:B4:F7 with driver (version: 6.6.56-v7l+) Nov 29 12:10:51 volumio.pi4 ifplugd(eth0)[799]: Using detection mode: SIOCETHTOOL Nov 29 12:10:51 volumio.pi4 ifplugd(eth0)[799]: Initialization complete, link beat not detected. Nov 29 12:10:51 volumio.pi4 ifplugd[730]: Network Interface Plugging Daemon...start eth0...done. Nov 29 12:10:51 volumio.pi4 systemd[1]: Started LSB: Brings up/down network automatically. Nov 29 12:10:51 volumio.pi4 ntpd[729]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 29 12:10:51 volumio.pi4 ntpd[729]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 29 12:10:51 volumio.pi4 systemd[1]: Started Network Time Service. Nov 29 12:10:51 volumio.pi4 ntpd[805]: proto: precision = 2.093 usec (-19) Nov 29 12:10:51 volumio.pi4 ntpd[805]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 29 12:10:51 volumio.pi4 ntpd[805]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 29 12:10:51 volumio.pi4 ntpd[805]: Listen and drop on 0 v6wildcard [::]:123 Nov 29 12:10:51 volumio.pi4 ntpd[805]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 29 12:10:51 volumio.pi4 ntpd[805]: Listen normally on 2 lo 127.0.0.1:123 Nov 29 12:10:51 volumio.pi4 ntpd[805]: Listening on routing socket on fd #19 for interface updates Nov 29 12:10:51 volumio.pi4 ntpd[805]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 29 12:10:51 volumio.pi4 ntpd[805]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 29 12:10:51 volumio.pi4 bluetoothd[739]: Bluetooth daemon 5.50 Nov 29 12:10:51 volumio.pi4 bluetoothd[739]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 29 12:10:51 volumio.pi4 cpufrequtils[781]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 29 12:10:51 volumio.pi4 systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 29 12:10:51 volumio.pi4 systemd[1]: Started Bluetooth service. Nov 29 12:10:51 volumio.pi4 haveged[591]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 29 12:10:51 volumio.pi4 haveged[591]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 29 12:10:51 volumio.pi4 haveged[591]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00118 Nov 29 12:10:51 volumio.pi4 haveged[591]: haveged: fills: 0, generated: 0 Nov 29 12:10:51 volumio.pi4 systemd[1]: Reached target Bluetooth. Nov 29 12:10:51 volumio.pi4 bluetoothd[739]: Starting SDP server Nov 29 12:10:51 volumio.pi4 bluetoothd[739]: Excluding (cli) sap Nov 29 12:10:51 volumio.pi4 dbus-daemon[626]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=739 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 29 12:10:51 volumio.pi4 bluetoothd[739]: Bluetooth management interface 1.22 initialized Nov 29 12:10:51 volumio.pi4 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 29 12:10:51 volumio.pi4 kernel: Bluetooth: BNEP filters: protocol multicast Nov 29 12:10:51 volumio.pi4 kernel: Bluetooth: BNEP socket layer initialized Nov 29 12:10:51 volumio.pi4 kernel: Bluetooth: MGMT ver 1.22 Nov 29 12:10:51 volumio.pi4 bluetoothd[739]: Failed to set privacy: Rejected (0x0b) Nov 29 12:10:51 volumio.pi4 sudo[738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 12:10:51 volumio.pi4 sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:51 volumio.pi4 sudo[738]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 29 12:10:51 volumio.pi4 sudo[738]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:51 volumio.pi4 systemd[1]: systemd-hostnamed.service: Failed to run 'start' task: No space left on device Nov 29 12:10:51 volumio.pi4 systemd[1]: systemd-hostnamed.service: Failed with result 'resources'. Nov 29 12:10:51 volumio.pi4 systemd[1]: Failed to start Hostname Service. Nov 29 12:10:52 volumio.pi4 systemd[1]: systemd-rfkill.service: Succeeded. Nov 29 12:10:52 volumio.pi4 ntpd[805]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 29 12:10:52 volumio.pi4 nmbd[745]: [2024/11/29 12:10:52.764117, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 29 12:10:52 volumio.pi4 nmbd[745]: started asyncdns process 822 Nov 29 12:10:52 volumio.pi4 nmbd[745]: [2024/11/29 12:10:52.766153, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 29 12:10:52 volumio.pi4 nmbd[745]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 29 12:10:52 volumio.pi4 nmbd[745]: [2024/11/29 12:10:52.766319, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 29 12:10:52 volumio.pi4 nmbd[745]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 29 12:10:53 volumio.pi4 ntpd[805]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 29 12:10:53 volumio.pi4 wireless.js[630]: Cleaning previous... Nov 29 12:10:53 volumio.pi4 sh[530]: eth0: carrier acquired Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: eth0: carrier acquired Nov 29 12:10:53 volumio.pi4 sh[530]: DUID 00:01:00:01:2e:c1:0d:8e:e4:5f:01:e1:b4:f7 Nov 29 12:10:53 volumio.pi4 sh[530]: eth0: IAID 01:e1:b4:f7 Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: DUID 00:01:00:01:2e:c1:0d:8e:e4:5f:01:e1:b4:f7 Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: eth0: IAID 01:e1:b4:f7 Nov 29 12:10:53 volumio.pi4 sh[530]: eth0: adding address fe80::922f:7c67:f03b:cea7 Nov 29 12:10:53 volumio.pi4 sh[530]: ipv6_addaddr1: Permission denied Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: eth0: adding address fe80::922f:7c67:f03b:cea7 Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: ipv6_addaddr1: Permission denied Nov 29 12:10:53 volumio.pi4 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx Nov 29 12:10:53 volumio.pi4 sh[530]: eth0: soliciting a DHCP lease Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: eth0: soliciting a DHCP lease Nov 29 12:10:53 volumio.pi4 sudo[831]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 29 12:10:53 volumio.pi4 sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:53 volumio.pi4 sudo[831]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:53 volumio.pi4 sudo[833]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 29 12:10:53 volumio.pi4 sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:53 volumio.pi4 sh[530]: eth0: soliciting an IPv6 router Nov 29 12:10:53 volumio.pi4 dhcpcd[576]: eth0: soliciting an IPv6 router Nov 29 12:10:54 volumio.pi4 sudo[833]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:54 volumio.pi4 wireless.js[630]: Stopped aP Nov 29 12:10:54 volumio.pi4 ifplugd(eth0)[799]: Link beat detected. Nov 29 12:10:54 volumio.pi4 sudo[840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 29 12:10:54 volumio.pi4 sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:54 volumio.pi4 sudo[840]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:54 volumio.pi4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 29 12:10:54 volumio.pi4 sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 29 12:10:54 volumio.pi4 sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:54 volumio.pi4 sudo[842]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:54 volumio.pi4 ntpd[805]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 29 12:10:54 volumio.pi4 bthelper[652]: Changing power off succeeded Nov 29 12:10:54 volumio.pi4 sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 29 12:10:54 volumio.pi4 sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:54 volumio.pi4 sudo[850]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:54 volumio.pi4 ifplugd(eth0)[799]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 29 12:10:54 volumio.pi4 sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 29 12:10:54 volumio.pi4 ifplugd(eth0)[799]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Nov 29 12:10:54 volumio.pi4 sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:54 volumio.pi4 bthelper[652]: [CHG] Controller E4:5F:01:E1:B4:FA Class: 0x0000041c Nov 29 12:10:54 volumio.pi4 bthelper[652]: Changing power on succeeded Nov 29 12:10:55 volumio.pi4 mpd[820]: Nov 29 12:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 12:10:55 volumio.pi4 systemd[1]: Started Music Player Daemon. Nov 29 12:10:55 volumio.pi4 mpd_monitor.sh[631]: MPD restarted due to no mpc output. Nov 29 12:10:55 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:10:55] [info] asio async_connect error: system:111 (Connection refused) Nov 29 12:10:55 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:10:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 29 12:10:55 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:10:55] [error] handle_connect error: Underlying Transport Error Nov 29 12:10:55 volumio.pi4 ntpd[805]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 29 12:10:55 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Fetching time from Volumio... Nov 29 12:10:55 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Date not found in response Nov 29 12:10:55 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Retrying in 5 seconds... Nov 29 12:10:57 volumio.pi4 sudo[852]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:57 volumio.pi4 wireless.js[630]: SETTING APPROPRIATE REG DOMAIN: US Nov 29 12:10:57 volumio.pi4 sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 29 12:10:57 volumio.pi4 sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:57 volumio.pi4 sudo[877]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:57 volumio.pi4 sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Nov 29 12:10:57 volumio.pi4 sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:57 volumio.pi4 sudo[879]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:57 volumio.pi4 wireless.js[630]: SUCCESSFULLY SET NEW REGDOMAIN: US Nov 29 12:10:57 volumio.pi4 wireless.js[630]: Start wireless flow Nov 29 12:10:57 volumio.pi4 wireless.js[630]: Stopped hotspot (if there).. Nov 29 12:10:57 volumio.pi4 sudo[884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 29 12:10:57 volumio.pi4 sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:57 volumio.pi4 sudo[884]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:57 volumio.pi4 sudo[886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 29 12:10:57 volumio.pi4 sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:57 volumio.pi4 sudo[886]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:57 volumio.pi4 wireless.js[630]: DHCP IP Nov 29 12:10:57 volumio.pi4 wireless.js[630]: Start ap Nov 29 12:10:57 volumio.pi4 wpa_supplicant[888]: Successfully initialized wpa_supplicant Nov 29 12:10:57 volumio.pi4 sudo[889]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 29 12:10:57 volumio.pi4 sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:57 volumio.pi4 dhcpcd[890]: dev: loaded udev Nov 29 12:10:57 volumio.pi4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 29 12:10:57 volumio.pi4 dhcpcd-run-hooks[899]: wlan0: starting wpa_supplicant Nov 29 12:10:58 volumio.pi4 dhcpcd-run-hooks[904]: wlan0: failed to start wpa_supplicant Nov 29 12:10:58 volumio.pi4 dhcpcd-run-hooks[905]: 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 Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: wlan0: connected to Access Point `' Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: DUID 00:01:00:01:2e:c1:0d:8e:e4:5f:01:e1:b4:f7 Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: eth0: IAID 01:e1:b4:f7 Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: eth0: adding address fe80::922f:7c67:f03b:cea7 Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: ipv6_addaddr1: Permission denied Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: wlan0: waiting for carrier Nov 29 12:10:58 volumio.pi4 sh[530]: eth0: probing for an IPv4LL address Nov 29 12:10:58 volumio.pi4 dhcpcd[576]: eth0: probing for an IPv4LL address Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: eth0: soliciting a DHCP lease Nov 29 12:10:58 volumio.pi4 dhcpcd[890]: eth0: soliciting an IPv6 router Nov 29 12:10:58 volumio.pi4 sh[530]: eth0: offered 192.168.102.21 from 192.168.102.1 Nov 29 12:10:58 volumio.pi4 dhcpcd[576]: eth0: offered 192.168.102.21 from 192.168.102.1 Nov 29 12:10:58 volumio.pi4 sh[530]: eth0: probing address 192.168.102.21/24 Nov 29 12:10:58 volumio.pi4 dhcpcd[576]: eth0: probing address 192.168.102.21/24 Nov 29 12:10:58 volumio.pi4 wireless.js[630]: trying... Nov 29 12:10:58 volumio.pi4 sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:10:58 volumio.pi4 sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:58 volumio.pi4 sudo[916]: pam_unix(sudo:session): session closed for user root Nov 29 12:10:59 volumio.pi4 wireless.js[630]: trying... Nov 29 12:10:59 volumio.pi4 sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:10:59 volumio.pi4 sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:10:59 volumio.pi4 sudo[919]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:00 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Fetching time from Volumio... Nov 29 12:11:00 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Date not found in response Nov 29 12:11:00 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Retrying in 5 seconds... Nov 29 12:11:00 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:00 volumio.pi4 sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:00 volumio.pi4 sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:00 volumio.pi4 sudo[927]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:01 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:01 volumio.pi4 sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:01 volumio.pi4 sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:01 volumio.pi4 sudo[930]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:02 volumio.pi4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 29 12:11:02 volumio.pi4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 29 12:11:02 volumio.pi4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 29 12:11:02 volumio.pi4 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 29 12:11:02 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:02] [info] asio async_connect error: system:111 (Connection refused) Nov 29 12:11:02 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 29 12:11:02 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:02] [error] handle_connect error: Underlying Transport Error Nov 29 12:11:02 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:02 volumio.pi4 sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:02 volumio.pi4 sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:02 volumio.pi4 sudo[933]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:03 volumio.pi4 sh[530]: eth0: using IPv4LL address 169.254.75.140 Nov 29 12:11:03 volumio.pi4 sh[530]: eth0: adding route to 169.254.0.0/16 Nov 29 12:11:03 volumio.pi4 sh[530]: eth0: adding default route Nov 29 12:11:03 volumio.pi4 dhcpcd[576]: eth0: using IPv4LL address 169.254.75.140 Nov 29 12:11:03 volumio.pi4 dhcpcd[576]: eth0: adding route to 169.254.0.0/16 Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.75.140. Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: New relevant interface eth0.IPv4 for mDNS. Nov 29 12:11:03 volumio.pi4 dhcpcd[576]: eth0: adding default route Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: Registering new address record for 169.254.75.140 on eth0.IPv4. Nov 29 12:11:03 volumio.pi4 sh[530]: forked to background, child pid 946 Nov 29 12:11:03 volumio.pi4 dhcpcd[576]: forked to background, child pid 946 Nov 29 12:11:03 volumio.pi4 ifplugd(eth0)[799]: client: ifup: interface eth0 already configured Nov 29 12:11:03 volumio.pi4 sh[530]: eth0=eth0 Nov 29 12:11:03 volumio.pi4 dhcpcd[890]: eth0: offered 192.168.102.21 from 192.168.102.1 Nov 29 12:11:03 volumio.pi4 dhcpcd[890]: eth0: probing address 192.168.102.21/24 Nov 29 12:11:03 volumio.pi4 ifplugd(eth0)[799]: Program executed successfully. Nov 29 12:11:03 volumio.pi4 dhcpcd[946]: eth0: leased 192.168.102.21 for 28800 seconds Nov 29 12:11:03 volumio.pi4 dhcpcd[946]: dhcp_bind: No space left on device Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: Registering new address record for 192.168.102.21 on eth0.IPv4. Nov 29 12:11:03 volumio.pi4 dhcpcd[946]: eth0: adding route to 192.168.102.0/24 Nov 29 12:11:03 volumio.pi4 dhcpcd[946]: eth0: changing default route via 192.168.102.1 Nov 29 12:11:03 volumio.pi4 dhcpcd[946]: eth0: deleting route to 169.254.0.0/16 Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: Withdrawing address record for 169.254.75.140 on eth0. Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.75.140. Nov 29 12:11:03 volumio.pi4 avahi-daemon[605]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.102.21. Nov 29 12:11:03 volumio.pi4 dhcpcd[946]: eth0: pid 946 deleted default route via 192.168.102.1 Nov 29 12:11:03 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:03 volumio.pi4 sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:03 volumio.pi4 sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:03 volumio.pi4 sudo[1012]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:04 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:04 volumio.pi4 sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:04 volumio.pi4 sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:04 volumio.pi4 sudo[1015]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:04 volumio.pi4 wpa_supplicant[903]: wlan0: Trying to associate with SSID 'ASUS_FRONT' Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: carrier acquired Nov 29 12:11:05 volumio.pi4 kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: connected to Access Point `ASUS_FRONT' Nov 29 12:11:05 volumio.pi4 wpa_supplicant[903]: wlan0: Associated with 78:9a:18:94:ad:8a Nov 29 12:11:05 volumio.pi4 wpa_supplicant[903]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:9a:18:94:ad:8a completed [id=0 id_str=] Nov 29 12:11:05 volumio.pi4 wpa_supplicant[903]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 29 12:11:05 volumio.pi4 wpa_supplicant[903]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=HN Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: IAID 01:e1:b4:f9 Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: adding address fe80::6f48:34e7:b084:ff49 Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: ipv6_addaddr1: Permission denied Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: carrier lost Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: carrier acquired Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: IAID 01:e1:b4:f9 Nov 29 12:11:05 volumio.pi4 dhcpcd[890]: wlan0: soliciting an IPv6 router Nov 29 12:11:05 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Fetching time from Volumio... Nov 29 12:11:05 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:05 volumio.pi4 sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:05 volumio.pi4 sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:05 volumio.pi4 sudo[1046]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:06 volumio.pi4 dhcpcd[890]: wlan0: rebinding lease of 192.168.102.26 Nov 29 12:11:06 volumio.pi4 volumio-time-update[640]: volumio-time-update-util: Setting system time to: 2024-11-29 12:12:09 Nov 29 12:11:06 volumio.pi4 sudo[1049]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-11-29 12:12:09 Nov 29 12:11:06 volumio.pi4 sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:06 volumio.pi4 dhcpcd[890]: wlan0: probing address 192.168.102.26/24 Nov 29 12:11:06 volumio.pi4 dbus-daemon[626]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1050 comm="timedatectl set-time 2024-11-29 12:12:09 ") Nov 29 12:11:06 volumio.pi4 systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Nov 29 12:11:06 volumio.pi4 systemd[1]: systemd-timedated.service: Failed with result 'resources'. Nov 29 12:11:06 volumio.pi4 systemd[1]: Failed to start Time & Date Service. Nov 29 12:11:06 volumio.pi4 ntpd[805]: Listen normally on 3 eth0 192.168.102.21:123 Nov 29 12:11:06 volumio.pi4 ntpd[805]: new interface(s) found: waking up resolver Nov 29 12:11:06 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:06 volumio.pi4 sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:06 volumio.pi4 sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:06 volumio.pi4 sudo[1052]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:07 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:07 volumio.pi4 sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:07 volumio.pi4 sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:07 volumio.pi4 sudo[1055]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:07 volumio.pi4 nmbd[745]: [2024/11/29 12:11:07.779236, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 29 12:11:07 volumio.pi4 nmbd[745]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 29 12:11:07 volumio.pi4 systemd[1]: Started Samba NMB Daemon. Nov 29 12:11:07 volumio.pi4 systemd[1]: Starting Samba Winbind Daemon... Nov 29 12:11:08 volumio.pi4 winbindd[1057]: [2024/11/29 12:11:08.074849, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 29 12:11:08 volumio.pi4 winbindd[1057]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 29 12:11:08 volumio.pi4 winbindd[1057]: [2024/11/29 12:11:08.121265, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 29 12:11:08 volumio.pi4 winbindd[1057]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 29 12:11:08 volumio.pi4 systemd[1]: Started Samba Winbind Daemon. Nov 29 12:11:08 volumio.pi4 systemd[1]: Starting Samba SMB Daemon... Nov 29 12:11:08 volumio.pi4 smbd[1062]: [2024/11/29 12:11:08.584350, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 29 12:11:08 volumio.pi4 smbd[1062]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 29 12:11:08 volumio.pi4 systemd[1]: Started Samba SMB Daemon. Nov 29 12:11:08 volumio.pi4 dhcpcd[890]: eth0: leased 192.168.102.21 for 28800 seconds Nov 29 12:11:08 volumio.pi4 dhcpcd[890]: dhcp_bind: No space left on device Nov 29 12:11:08 volumio.pi4 dhcpcd[890]: eth0: adding route to 192.168.102.0/24 Nov 29 12:11:08 volumio.pi4 dhcpcd[890]: eth0: adding default route via 192.168.102.1 Nov 29 12:11:08 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:08 volumio.pi4 sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:08 volumio.pi4 sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:08 volumio.pi4 sudo[1081]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:08 volumio.pi4 dhcpcd[890]: forked to background, child pid 1094 Nov 29 12:11:08 volumio.pi4 sudo[889]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:09 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:09 volumio.pi4 sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:09 volumio.pi4 sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:09 volumio.pi4 sudo[1096]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:10 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:10 volumio.pi4 sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:10 volumio.pi4 sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:10 volumio.pi4 sudo[1099]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:11 volumio.pi4 dhcpcd[1094]: wlan0: leased 192.168.102.26 for 28800 seconds Nov 29 12:11:11 volumio.pi4 avahi-daemon[605]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.102.26. Nov 29 12:11:11 volumio.pi4 dhcpcd[1094]: wlan0: adding route to 192.168.102.0/24 Nov 29 12:11:11 volumio.pi4 dhcpcd[1094]: wlan0: adding default route via 192.168.102.1 Nov 29 12:11:11 volumio.pi4 avahi-daemon[605]: New relevant interface wlan0.IPv4 for mDNS. Nov 29 12:11:11 volumio.pi4 avahi-daemon[605]: Registering new address record for 192.168.102.26 on wlan0.IPv4. Nov 29 12:11:11 volumio.pi4 wireless.js[630]: trying... Nov 29 12:11:11 volumio.pi4 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 29 12:11:11 volumio.pi4 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:11 volumio.pi4 sudo[1123]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:11 volumio.pi4 wireless.js[630]: Connected to: ----ASUS_FRONT Nov 29 12:11:11 volumio.pi4 wireless.js[630]: ---- Nov 29 12:11:11 volumio.pi4 sudo[1126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 12:11:11 volumio.pi4 sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:11 volumio.pi4 sudo[1126]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:11 volumio.pi4 wireless.js[630]: ... joined AP, wlan0 IPv4 is 192.168.102.26, ipV6 is undefined Nov 29 12:11:11 volumio.pi4 wireless.js[630]: It's done! AP Nov 29 12:11:11 volumio.pi4 systemd[1]: Started Wireless Services. Nov 29 12:11:11 volumio.pi4 systemd[1]: Started Volumio Backend Module. Nov 29 12:11:11 volumio.pi4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 29 12:11:11 volumio.pi4 systemd[1]: Started Volumio Cpu Tweaker. Nov 29 12:11:12 volumio.pi4 volumio-cpu-tweak[1132]: Setting RT Priority for mpd Nov 29 12:11:12 volumio.pi4 volumio-cpu-tweak[1132]: Setting MPD Affinity Nov 29 12:11:12 volumio.pi4 volumio-cpu-tweak[1132]: pid 820's current affinity mask: f Nov 29 12:11:12 volumio.pi4 volumio-cpu-tweak[1132]: pid 820's new affinity mask: 3 Nov 29 12:11:12 volumio.pi4 volumio-cpu-tweak[1132]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 29 12:11:12 volumio.pi4 volumio-cpu-tweak[1132]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 29 12:11:12 volumio.pi4 systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 29 12:11:12 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:12] [info] asio async_connect error: system:111 (Connection refused) Nov 29 12:11:12 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 29 12:11:12 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:12] [error] handle_connect error: Underlying Transport Error Nov 29 12:11:13 volumio.pi4 ntpd[805]: Listen normally on 4 wlan0 192.168.102.26:123 Nov 29 12:11:13 volumio.pi4 ntpd[805]: new interface(s) found: waking up resolver Nov 29 12:11:13 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:13 volumio.pi4 volumio[1131]: info: ----- Volumio3 ---- Nov 29 12:11:13 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:13 volumio.pi4 volumio[1131]: info: ----- System startup ---- Nov 29 12:11:13 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: MYVOLUMIO Environment detected Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Plugin folders cleanup Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning into folder /volumio/app/plugins/ Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category audio_interface Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category miscellanea Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category music_service Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category plugins.json Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category system_controller Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category user_interface Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning into folder /data/plugins/ Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category audio_interface Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category music_service Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Scanning category user_interface Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Plugin folders cleanup completed Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: ----- Core plugins startup ---- Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Adding plugin upnp to MyMusic Plugins Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Loading plugins from folder /data/plugins/ Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Loading plugin "system"... Nov 29 12:11:14 volumio.pi4 volumio[1131]: info: Loading plugin "appearance"... Nov 29 12:11:15 volumio.pi4 volumio[1131]: info: Loading plugin "network"... Nov 29 12:11:15 volumio.pi4 volumio[1131]: info: Refreshing Cached IP Addresses Nov 29 12:11:15 volumio.pi4 sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 12:11:15 volumio.pi4 sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:15 volumio.pi4 sudo[1165]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:15 volumio.pi4 sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 12:11:15 volumio.pi4 sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:15 volumio.pi4 sudo[1167]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:15 volumio.pi4 volumio[1131]: info: Loading plugin "services"... Nov 29 12:11:15 volumio.pi4 volumio[1131]: info: Loading plugin "alsa_controller"... Nov 29 12:11:15 volumio.pi4 sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 29 12:11:15 volumio.pi4 sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "wizard"... Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "networkfs"... Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Starting Udev Watcher for removable devices Nov 29 12:11:16 volumio.pi4 sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Carlos,password=Casino20,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.102.10/Music /mnt/NAS/gdinarte Nov 29 12:11:16 volumio.pi4 sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Ignoring mount for partition: boot Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Ignoring mount for partition: volumio Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Ignoring mount for partition: volumio_data Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "volumio_command_line_client"... Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "upnp"... Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: [1732903876223] Starting Upmpd Daemon Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "my_music"... Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "mpd"... Nov 29 12:11:16 volumio.pi4 kernel: Key type cifs.spnego registered Nov 29 12:11:16 volumio.pi4 kernel: Key type cifs.idmap registered Nov 29 12:11:16 volumio.pi4 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. Nov 29 12:11:16 volumio.pi4 kernel: CIFS: Attempting to mount //192.168.102.10/Music Nov 29 12:11:16 volumio.pi4 dbus-daemon[626]: [system] Failed to activate service 'org.freedesktop.hostname1': timed out (service_start_timeout=25000ms) Nov 29 12:11:16 volumio.pi4 sudo[1193]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "upnp_browser"... Nov 29 12:11:16 volumio.pi4 volumio[1131]: info: Loading plugin "alarm-clock"... Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Loading plugin "airplay_emulation"... Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Starting Shairport Sync Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Loading plugin "last_100"... Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Loading plugin "webradio"... Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Loading plugin "i2s_dacs"... Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: I2S DAC not set, start Auto-detection Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Loading plugin "volumiodiscovery"... Nov 29 12:11:17 volumio.pi4 volumio[1131]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 12:11:17 volumio.pi4 volumio[1131]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 12:11:17 volumio.pi4 volumio[1131]: *** WARNING *** For more information see Nov 29 12:11:17 volumio.pi4 node[1131]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 12:11:17 volumio.pi4 volumio[1131]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 12:11:17 volumio.pi4 volumio[1131]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 12:11:17 volumio.pi4 volumio[1131]: *** WARNING *** For more information see Nov 29 12:11:17 volumio.pi4 node[1131]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 12:11:17 volumio.pi4 node[1131]: *** WARNING *** For more information see Nov 29 12:11:17 volumio.pi4 node[1131]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 12:11:17 volumio.pi4 node[1131]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 12:11:17 volumio.pi4 node[1131]: *** WARNING *** For more information see Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Discovery: Started advertising with name: Volumio.pi4 Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 12:11:17 volumio.pi4 volumio[1131]: info: Loading plugin "spop"... Nov 29 12:11:17 volumio.pi4 systemd[1]: systemd-fsckd.service: Succeeded. Nov 29 12:11:18 volumio.pi4 volumio[1131]: info: Loading plugin "outputs"... Nov 29 12:11:18 volumio.pi4 volumio[1131]: info: Loading plugin "albumart"... Nov 29 12:11:18 volumio.pi4 volumio[1131]: info: Plugin example_plugin is not enabled Nov 29 12:11:18 volumio.pi4 volumio[1131]: info: Loading plugin "inputs"... Nov 29 12:11:18 volumio.pi4 volumio[1131]: info: Loading plugin "updater_comm"... Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Plugin mpdemulation is not enabled Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Loading plugin "rest_api"... Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Loading plugin "websocket"... Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Starting Socket.io Server version 2.3.0 Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Loading plugin "fusiondsp"... Nov 29 12:11:19 volumio.pi4 volumio[1131]: Forking 3 albumart workers Nov 29 12:11:19 volumio.pi4 volumio[1131]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 29 12:11:19 volumio.pi4 volumio[1131]: Starting albumart workers Nov 29 12:11:19 volumio.pi4 volumio[1131]: Starting albumart workers Nov 29 12:11:19 volumio.pi4 volumio[1131]: Starting albumart workers Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Plugin lastfm is not enabled Nov 29 12:11:19 volumio.pi4 volumio[1131]: info: Loading i18n strings for locale en Nov 29 12:11:20 volumio.pi4 volumio[1131]: Updating browse sources language Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:11:20 volumio.pi4 sudo[1176]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::initPlayerControls Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: Express server listening on port 3000 Nov 29 12:11:20 volumio.pi4 volumio[1131]: [Metrics] WebUI: 7s 70.89ms Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::resetVolumioState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::getcurrentVolume Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Cannot read play queue from file Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Volumio Network Manager: Network status updated: 3 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::setRepeat null single undefined Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::pushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioPushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::setRandom null Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::pushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioPushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: VolumeController:: Volume=86 Mute =false Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::pushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioPushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::updateTrackBlock Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrackBlock Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Setting Device type: Raspberry PI Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Completed loading Core Plugins Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Preparing to generate the ALSA configuration file Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Discovery: adding 122b0ccd-6265-4eab-86e5-66b5a8c4020e Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Discovery: Found device Volumio.pi4 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Discovery: this is already registered, 122b0ccd-6265-4eab-86e5-66b5a8c4020e Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Discovery: Found device Volumio.pi4 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Reading ALSA contributions from plugins. Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: VolumeController:: Volume=86 Mute =false Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreStateMachine::pushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioPushState Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Asound.conf file unchanged, so no further update is needed Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Output device has changed, restarting MPD Nov 29 12:11:20 volumio.pi4 sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Output device has changed, restarting Shairport Sync Nov 29 12:11:20 volumio.pi4 sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:20 volumio.pi4 sudo[1286]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:20 volumio.pi4 sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 12:11:20 volumio.pi4 sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:20 volumio.pi4 systemd[1]: Stopping Music Player Daemon... Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: ___________ START PLUGINS ___________ Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: ControllerMpd::onStart: Initializing MPD Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Creating MPD Configuration file Nov 29 12:11:20 volumio.pi4 sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 12:11:20 volumio.pi4 sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: [1732903880611] CoreMusicLibrary::Adding element Media Servers Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:11:20 volumio.pi4 sudo[1295]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:20 volumio.pi4 sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 12:11:20 volumio.pi4 sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: [1732903880708] CoreMusicLibrary::Adding element Last_100 Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: [1732903880710] CoreMusicLibrary::Adding element Webradio Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Initializing BBC Radios Nov 29 12:11:20 volumio.pi4 systemd[1]: mpd.service: Succeeded. Nov 29 12:11:20 volumio.pi4 systemd[1]: Stopped Music Player Daemon. Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Creating Spotify config file Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Loading i18n strings for locale en Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: FusionDsp - mixtype--------------------- Hardware Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Volumio Calling Home Nov 29 12:11:20 volumio.pi4 systemd[1]: Starting Music Player Daemon... Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Preparing to generate the ALSA configuration file Nov 29 12:11:20 volumio.pi4 sudo[1308]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 12:11:20 volumio.pi4 sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:20 volumio.pi4 sudo[1308]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Reading ALSA contributions from plugins. Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: MPD Permissions set Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: MPD Permissions set Nov 29 12:11:20 volumio.pi4 volumio[1131]: info: Spotify config file written Nov 29 12:11:21 volumio.pi4 sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 29 12:11:21 volumio.pi4 sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 volumio[1131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: No need to fix Spotify hosts Nov 29 12:11:21 volumio.pi4 systemd[1]: Started go-librespot Daemon. Nov 29 12:11:21 volumio.pi4 go-librespot[1325]: Librespot-go daemon starting... Nov 29 12:11:21 volumio.pi4 sudo[1323]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Volumio called home Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Starting Shairport Sync Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Starting Shairport Sync Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Starting Shairport Sync Nov 29 12:11:21 volumio.pi4 sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:11:21 volumio.pi4 sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:11:21 volumio.pi4 sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 12:11:21 volumio.pi4 systemd[1]: shairport-sync.service: Succeeded. Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 12:11:21 volumio.pi4 sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:11:21 volumio.pi4 sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CorePlayQueue::getTrack 0 Nov 29 12:11:21 volumio.pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:11:21 volumio.pi4 sudo[1336]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Asound.conf file unchanged, so no further update is needed Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Output device has changed, restarting MPD Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 12:11:21 volumio.pi4 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 12:11:21 volumio.pi4 systemd[1]: shairport-sync.service: Succeeded. Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 12:11:21 volumio.pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:11:21 volumio.pi4 sudo[1338]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 sudo[1341]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Output device has changed, restarting Shairport Sync Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:21 volumio.pi4 sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 12:11:21 volumio.pi4 sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 12:11:21 volumio.pi4 sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 sudo[1351]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:11:21 volumio.pi4 systemd[1]: mpd.service: Succeeded. Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopped Music Player Daemon. Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: MPD Permissions set Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Shairport-Sync Started Nov 29 12:11:21 volumio.pi4 volumio[1131]: Error adding Membership: Error: addMembership EINVAL Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Shairport-Sync Started Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Shairport-Sync Started Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Starting Shairport Sync Nov 29 12:11:21 volumio.pi4 systemd[1]: Starting Music Player Daemon... Nov 29 12:11:21 volumio.pi4 sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:11:21 volumio.pi4 sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 12:11:21 volumio.pi4 systemd[1]: shairport-sync.service: Succeeded. Nov 29 12:11:21 volumio.pi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 12:11:21 volumio.pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:11:21 volumio.pi4 sudo[1368]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 volumio[1131]: info: Shairport-Sync Started Nov 29 12:11:21 volumio.pi4 sudo[1362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 12:11:21 volumio.pi4 sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:21 volumio.pi4 sudo[1362]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:21 volumio.pi4 volumio[1131]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 29 12:11:21 volumio.pi4 go-librespot[1325]: time="2024-11-29T12:11:21-06:00" level=info msg="generated new device id: b1d0d4b1a12f7c75d3822dc8b456c1e2cc976b17" Nov 29 12:11:22 volumio.pi4 go-librespot[1325]: time="2024-11-29T12:11:22-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 29 12:11:22 volumio.pi4 go-librespot[1325]: time="2024-11-29T12:11:22-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 29 12:11:22 volumio.pi4 go-librespot[1325]: time="2024-11-29T12:11:22-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 29 12:11:22 volumio.pi4 go-librespot[1325]: time="2024-11-29T12:11:22-06:00" level=debug msg="zeroconf server listening on port 39671" Nov 29 12:11:22 volumio.pi4 mpd[1373]: Nov 29 12:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 12:11:22 volumio.pi4 systemd[1]: Started Music Player Daemon. Nov 29 12:11:22 volumio.pi4 sudo[1298]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:22 volumio.pi4 sudo[1354]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:22 volumio.pi4 sudo[1288]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: Completed starting Core Plugins Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: ----- MyVolumio plugins startup ---- Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: ------------------------------------------- Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: MPD running with PID1373 Nov 29 12:11:22 volumio.pi4 volumio[1131]: ,establishing connection Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: MPD error: The expression evaluated to a falsy value: Nov 29 12:11:22 volumio.pi4 volumio[1131]: assert.ok(self.idling) Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: The expression evaluated to a falsy value: Nov 29 12:11:22 volumio.pi4 volumio[1131]: assert.ok(self.idling) Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: MPD error: The expression evaluated to a falsy value: Nov 29 12:11:22 volumio.pi4 volumio[1131]: assert.ok(self.idling) Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: The expression evaluated to a falsy value: Nov 29 12:11:22 volumio.pi4 volumio[1131]: assert.ok(self.idling) Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: MPD error: The expression evaluated to a falsy value: Nov 29 12:11:22 volumio.pi4 volumio[1131]: assert.ok(self.idling) Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: The expression evaluated to a falsy value: Nov 29 12:11:22 volumio.pi4 volumio[1131]: assert.ok(self.idling) Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: updateQueue error: null Nov 29 12:11:22 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:22] [connect] Successful connection Nov 29 12:11:22 volumio.pi4 volumio-remote-updater[621]: [2024-11-29 12:11:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732903882 101 Nov 29 12:11:22 volumio.pi4 volumio[1131]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: camilladsp service started and running in background, instance 1 Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:11:22 volumio.pi4 volumio[1131]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 12:11:22 volumio.pi4 volumio[1131]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 29 12:11:22 volumio.pi4 volumio[1131]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: FusionDsp loaded Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:11:22 volumio.pi4 sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 29 12:11:22 volumio.pi4 sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: Adding Signal Path Element [object Object] Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: Adding fusiondspeq DSP Signal Path Element Nov 29 12:11:22 volumio.pi4 volumio[1131]: info: FusionDsp - ---- installed callbackRead Nov 29 12:11:22 volumio.pi4 volumio[1131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 12:11:22 volumio.pi4 sudo[1391]: pam_unix(sudo:session): session closed for user root Nov 29 12:11:22 volumio.pi4 volumio[1131]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 29 12:11:22 volumio.pi4 volumio[1131]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 29 12:11:22 volumio.pi4 volumio[1131]: at onErrorNT (internal/child_process.js:465:16) Nov 29 12:11:22 volumio.pi4 volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 29 12:11:22 volumio.pi4 volumio[1131]: errno: -13, Nov 29 12:11:22 volumio.pi4 volumio[1131]: code: 'EACCES', Nov 29 12:11:22 volumio.pi4 volumio[1131]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 12:11:22 volumio.pi4 volumio[1131]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 29 12:11:22 volumio.pi4 volumio[1131]: spawnargs: [ Nov 29 12:11:22 volumio.pi4 volumio[1131]: '-p', Nov 29 12:11:22 volumio.pi4 volumio[1131]: 9876, Nov 29 12:11:22 volumio.pi4 volumio[1131]: '-o', Nov 29 12:11:22 volumio.pi4 volumio[1131]: '/tmp/camilladsp.log', Nov 29 12:11:22 volumio.pi4 volumio[1131]: '-l', Nov 29 12:11:22 volumio.pi4 volumio[1131]: 'warn', Nov 29 12:11:22 volumio.pi4 volumio[1131]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 29 12:11:22 volumio.pi4 volumio[1131]: ] Nov 29 12:11:22 volumio.pi4 volumio[1131]: } Nov 29 12:11:22 volumio.pi4 volumio[1131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 12:11:23 volumio.pi4 sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 12:10 Nov 29 12:11:23 volumio.pi4 sudo[1402]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"