-- Logs begin at Sun 2024-04-28 11:51:48 MSK, end at Sun 2024-04-28 11:52:27 MSK. -- Apr 28 11:51:48 volumio-pi kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Apr 28 11:51:48 volumio-pi kernel: Linux version 6.1.69-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1710 SMP PREEMPT Thu Dec 21 13:21:40 GMT 2023 Apr 28 11:51:48 volumio-pi kernel: random: crng init done Apr 28 11:51:48 volumio-pi kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Apr 28 11:51:48 volumio-pi kernel: efi: UEFI not found. Apr 28 11:51:48 volumio-pi kernel: Reserved memory: created CMA memory pool at 0x000000001a000000, size 320 MiB Apr 28 11:51:48 volumio-pi kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Apr 28 11:51:48 volumio-pi kernel: Zone ranges: Apr 28 11:51:48 volumio-pi kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Apr 28 11:51:48 volumio-pi kernel: DMA32 empty Apr 28 11:51:48 volumio-pi kernel: Normal empty Apr 28 11:51:48 volumio-pi kernel: Movable zone start for each node Apr 28 11:51:48 volumio-pi kernel: Early memory node ranges Apr 28 11:51:48 volumio-pi kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Apr 28 11:51:48 volumio-pi kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Apr 28 11:51:48 volumio-pi kernel: node 0: [mem 0x0000000040000000-0x00000000ffffffff] Apr 28 11:51:48 volumio-pi kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000ffffffff] Apr 28 11:51:48 volumio-pi kernel: On node 0, zone DMA: 1024 pages in unavailable ranges Apr 28 11:51:48 volumio-pi kernel: psci: probing for conduit method from DT. Apr 28 11:51:48 volumio-pi kernel: psci: PSCIv1.1 detected in firmware. Apr 28 11:51:48 volumio-pi kernel: psci: Using standard PSCI v0.2 function IDs Apr 28 11:51:48 volumio-pi kernel: psci: MIGRATE_INFO_TYPE not supported. Apr 28 11:51:48 volumio-pi kernel: psci: SMC Calling Convention v1.2 Apr 28 11:51:48 volumio-pi kernel: percpu: Embedded 29 pages/cpu s79208 r8192 d31384 u118784 Apr 28 11:51:48 volumio-pi kernel: pcpu-alloc: s79208 r8192 d31384 u118784 alloc=29*4096 Apr 28 11:51:48 volumio-pi kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Apr 28 11:51:48 volumio-pi kernel: Detected PIPT I-cache on CPU0 Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Virtualization Host Extensions Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Hardware dirty bit management Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Spectre-v4 Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Spectre-BHB Apr 28 11:51:48 volumio-pi kernel: CPU features: kernel page table isolation forced ON by KASLR Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Kernel page table isolation (KPTI) Apr 28 11:51:48 volumio-pi kernel: alternatives: applying boot alternatives Apr 28 11:51:48 volumio-pi kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031168 Apr 28 11:51:48 volumio-pi kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 smsc95xx.macaddr=D8:3A:DD:CD:F4:E2 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop 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 Apr 28 11:51:48 volumio-pi kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Apr 28 11:51:48 volumio-pi kernel: Unknown kernel command line parameters "splash imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh bootdelay=5", will be passed to user space. Apr 28 11:51:48 volumio-pi kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 28 11:51:48 volumio-pi kernel: Memory: 3743416K/4190208K available (12224K kernel code, 2172K rwdata, 4116K rodata, 4352K init, 1082K bss, 119112K reserved, 327680K cma-reserved) Apr 28 11:51:48 volumio-pi kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 28 11:51:48 volumio-pi kernel: ftrace: allocating 41372 entries in 162 pages Apr 28 11:51:48 volumio-pi kernel: ftrace: allocated 162 pages with 3 groups Apr 28 11:51:48 volumio-pi kernel: trace event string verifier disabled Apr 28 11:51:48 volumio-pi kernel: rcu: Preemptible hierarchical RCU implementation. Apr 28 11:51:48 volumio-pi kernel: rcu: RCU event tracing is enabled. Apr 28 11:51:48 volumio-pi kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Apr 28 11:51:48 volumio-pi kernel: Trampoline variant of Tasks RCU enabled. Apr 28 11:51:48 volumio-pi kernel: Rude variant of Tasks RCU enabled. Apr 28 11:51:48 volumio-pi kernel: Tracing variant of Tasks RCU enabled. Apr 28 11:51:48 volumio-pi kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Apr 28 11:51:48 volumio-pi kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Apr 28 11:51:48 volumio-pi kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 28 11:51:48 volumio-pi kernel: Root IRQ handler: gic_handle_irq Apr 28 11:51:48 volumio-pi kernel: GIC: Using split EOI/Deactivate mode Apr 28 11:51:48 volumio-pi kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 28 11:51:48 volumio-pi kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Apr 28 11:51:48 volumio-pi kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Apr 28 11:51:48 volumio-pi kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Apr 28 11:51:48 volumio-pi kernel: Console: colour dummy device 80x25 Apr 28 11:51:48 volumio-pi kernel: printk: console [tty1] enabled Apr 28 11:51:48 volumio-pi kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Apr 28 11:51:48 volumio-pi kernel: pid_max: default: 32768 minimum: 301 Apr 28 11:51:48 volumio-pi kernel: LSM: Security Framework initializing Apr 28 11:51:48 volumio-pi kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: cgroup: Disabling memory control group subsystem Apr 28 11:51:48 volumio-pi kernel: cblist_init_generic: Setting adjustable number of callback queues. Apr 28 11:51:48 volumio-pi kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Apr 28 11:51:48 volumio-pi kernel: cblist_init_generic: Setting adjustable number of callback queues. Apr 28 11:51:48 volumio-pi kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Apr 28 11:51:48 volumio-pi kernel: cblist_init_generic: Setting adjustable number of callback queues. Apr 28 11:51:48 volumio-pi kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Apr 28 11:51:48 volumio-pi kernel: rcu: Hierarchical SRCU implementation. Apr 28 11:51:48 volumio-pi kernel: rcu: Max phase no-delay instances is 1000. Apr 28 11:51:48 volumio-pi kernel: EFI services will not be available. Apr 28 11:51:48 volumio-pi kernel: smp: Bringing up secondary CPUs ... Apr 28 11:51:48 volumio-pi kernel: Detected PIPT I-cache on CPU1 Apr 28 11:51:48 volumio-pi kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Apr 28 11:51:48 volumio-pi kernel: Detected PIPT I-cache on CPU2 Apr 28 11:51:48 volumio-pi kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Apr 28 11:51:48 volumio-pi kernel: Detected PIPT I-cache on CPU3 Apr 28 11:51:48 volumio-pi kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Apr 28 11:51:48 volumio-pi kernel: smp: Brought up 1 node, 4 CPUs Apr 28 11:51:48 volumio-pi kernel: SMP: Total of 4 processors activated. Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: 32-bit EL0 Support Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Common not Private translations Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: CRC32 instructions Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: RCpc load-acquire (LDAPR) Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: LSE atomic instructions Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Privileged Access Never Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: RAS Extension Support Apr 28 11:51:48 volumio-pi kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Apr 28 11:51:48 volumio-pi kernel: CPU: All CPU(s) started at EL2 Apr 28 11:51:48 volumio-pi kernel: alternatives: applying system-wide alternatives Apr 28 11:51:48 volumio-pi kernel: devtmpfs: initialized Apr 28 11:51:48 volumio-pi kernel: Enabled cp15_barrier support Apr 28 11:51:48 volumio-pi kernel: Enabled setend support Apr 28 11:51:48 volumio-pi kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Apr 28 11:51:48 volumio-pi kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: pinctrl core: initialized pinctrl subsystem Apr 28 11:51:48 volumio-pi kernel: DMI not present or invalid. Apr 28 11:51:48 volumio-pi kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 28 11:51:48 volumio-pi kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Apr 28 11:51:48 volumio-pi kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 28 11:51:48 volumio-pi kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 28 11:51:48 volumio-pi kernel: audit: initializing netlink subsys (disabled) Apr 28 11:51:48 volumio-pi kernel: audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 Apr 28 11:51:48 volumio-pi kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 28 11:51:48 volumio-pi kernel: cpuidle: using governor menu Apr 28 11:51:48 volumio-pi kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 28 11:51:48 volumio-pi kernel: ASID allocator initialised with 32768 entries Apr 28 11:51:48 volumio-pi kernel: Serial: AMBA PL011 UART driver Apr 28 11:51:48 volumio-pi kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Apr 28 11:51:48 volumio-pi kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 Apr 28 11:51:48 volumio-pi kernel: printk: console [ttyAMA10] enabled Apr 28 11:51:48 volumio-pi kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-30T16:45:10, variant start_cd Apr 28 11:51:48 volumio-pi kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30de0ba500000000000000000000000000000000 Apr 28 11:51:48 volumio-pi kernel: KASLR enabled Apr 28 11:51:48 volumio-pi kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Apr 28 11:51:48 volumio-pi kernel: iommu: Default domain type: Translated Apr 28 11:51:48 volumio-pi kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 28 11:51:48 volumio-pi kernel: SCSI subsystem initialized Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver usbfs Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver hub Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new device driver usb Apr 28 11:51:48 volumio-pi kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Apr 28 11:51:48 volumio-pi kernel: pps_core: LinuxPPS API ver. 1 registered Apr 28 11:51:48 volumio-pi kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 28 11:51:48 volumio-pi kernel: PTP clock support registered Apr 28 11:51:48 volumio-pi kernel: vgaarb: loaded Apr 28 11:51:48 volumio-pi kernel: clocksource: Switched to clocksource arch_sys_counter Apr 28 11:51:48 volumio-pi kernel: VFS: Disk quotas dquot_6.6.0 Apr 28 11:51:48 volumio-pi kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 28 11:51:48 volumio-pi kernel: FS-Cache: Loaded Apr 28 11:51:48 volumio-pi kernel: CacheFiles: Loaded Apr 28 11:51:48 volumio-pi kernel: NET: Registered PF_INET protocol family Apr 28 11:51:48 volumio-pi kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 28 11:51:48 volumio-pi kernel: MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 28 11:51:48 volumio-pi kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 28 11:51:48 volumio-pi kernel: RPC: Registered named UNIX socket transport module. Apr 28 11:51:48 volumio-pi kernel: RPC: Registered udp transport module. Apr 28 11:51:48 volumio-pi kernel: RPC: Registered tcp transport module. Apr 28 11:51:48 volumio-pi kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 28 11:51:48 volumio-pi kernel: PCI: CLS 0 bytes, default 64 Apr 28 11:51:48 volumio-pi kernel: Trying to unpack rootfs image as initramfs... Apr 28 11:51:48 volumio-pi kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available Apr 28 11:51:48 volumio-pi kernel: kvm [1]: IPA Size Limit: 40 bits Apr 28 11:51:48 volumio-pi kernel: kvm [1]: vgic interrupt IRQ9 Apr 28 11:51:48 volumio-pi kernel: kvm [1]: VHE mode initialized successfully Apr 28 11:51:48 volumio-pi kernel: Freeing initrd memory: 13624K Apr 28 11:51:48 volumio-pi kernel: Initialise system trusted keyrings Apr 28 11:51:48 volumio-pi kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Apr 28 11:51:48 volumio-pi kernel: zbud: loaded Apr 28 11:51:48 volumio-pi kernel: NFS: Registering the id_resolver key type Apr 28 11:51:48 volumio-pi kernel: Key type id_resolver registered Apr 28 11:51:48 volumio-pi kernel: Key type id_legacy registered Apr 28 11:51:48 volumio-pi kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Apr 28 11:51:48 volumio-pi kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Apr 28 11:51:48 volumio-pi kernel: Key type asymmetric registered Apr 28 11:51:48 volumio-pi kernel: Asymmetric key parser 'x509' registered Apr 28 11:51:48 volumio-pi kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Apr 28 11:51:48 volumio-pi kernel: io scheduler mq-deadline registered Apr 28 11:51:48 volumio-pi kernel: io scheduler kyber registered Apr 28 11:51:48 volumio-pi kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 31) Apr 28 11:51:48 volumio-pi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 32) Apr 28 11:51:48 volumio-pi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 33) Apr 28 11:51:48 volumio-pi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 34) Apr 28 11:51:48 volumio-pi kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 35) Apr 28 11:51:48 volumio-pi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 36) Apr 28 11:51:48 volumio-pi kernel: gpio-461 (RP1 RUN pin): hogged as output/high Apr 28 11:51:48 volumio-pi kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Apr 28 11:51:48 volumio-pi kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 38, base_baud = 6000000) is a 16550A Apr 28 11:51:48 volumio-pi kernel: serial serial0: tty port ttyS0 registered Apr 28 11:51:48 volumio-pi kernel: iproc-rng200 107d208000.rng: hwrng registered Apr 28 11:51:48 volumio-pi kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Apr 28 11:51:48 volumio-pi kernel: brd: module loaded Apr 28 11:51:48 volumio-pi kernel: loop: module loaded Apr 28 11:51:48 volumio-pi kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Apr 28 11:51:48 volumio-pi kernel: Loading iSCSI transport class v2.0-870. Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new device driver r8152-cfgselector Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver r8152 Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver lan78xx Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver smsc95xx Apr 28 11:51:48 volumio-pi kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Apr 28 11:51:48 volumio-pi kernel: dwc_otg: FIQ enabled Apr 28 11:51:48 volumio-pi kernel: dwc_otg: NAK holdoff enabled Apr 28 11:51:48 volumio-pi kernel: dwc_otg: FIQ split-transaction FSM enabled Apr 28 11:51:48 volumio-pi kernel: Module dwc_common_port init Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver uas Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver usb-storage Apr 28 11:51:48 volumio-pi kernel: mousedev: PS/2 mouse device common for all mice Apr 28 11:51:48 volumio-pi kernel: rpi-rtc soc:rpi_rtc: registered as rtc0 Apr 28 11:51:48 volumio-pi kernel: rpi-rtc soc:rpi_rtc: setting system clock to 2024-04-28T08:51:41 UTC (1714294301) Apr 28 11:51:48 volumio-pi kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Apr 28 11:51:48 volumio-pi kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Apr 28 11:51:48 volumio-pi kernel: sdhci: Secure Digital Host Controller Interface driver Apr 28 11:51:48 volumio-pi kernel: sdhci: Copyright(c) Pierre Ossman Apr 28 11:51:48 volumio-pi kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 28 11:51:48 volumio-pi kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 28 11:51:48 volumio-pi kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Apr 28 11:51:48 volumio-pi kernel: hid: raw HID events driver (C) Jiri Kosina Apr 28 11:51:48 volumio-pi kernel: usbcore: registered new interface driver usbhid Apr 28 11:51:48 volumio-pi kernel: usbhid: USB HID core driver Apr 28 11:51:48 volumio-pi kernel: NET: Registered PF_PACKET protocol family Apr 28 11:51:48 volumio-pi kernel: Key type dns_resolver registered Apr 28 11:51:48 volumio-pi kernel: registered taskstats version 1 Apr 28 11:51:48 volumio-pi kernel: Loading compiled-in X.509 certificates Apr 28 11:51:48 volumio-pi kernel: Key type .fscrypt registered Apr 28 11:51:48 volumio-pi kernel: Key type fscrypt-provisioning registered Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: Forcing gen 2 Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00 Apr 28 11:51:48 volumio-pi kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 28 11:51:48 volumio-pi kernel: pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Apr 28 11:51:48 volumio-pi kernel: pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Apr 28 11:51:48 volumio-pi kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000 Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: supports D1 Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold Apr 28 11:51:48 volumio-pi kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Apr 28 11:51:48 volumio-pi kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Apr 28 11:51:48 volumio-pi kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Apr 28 11:51:48 volumio-pi kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Apr 28 11:51:48 volumio-pi kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 39 Apr 28 11:51:48 volumio-pi kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 39 Apr 28 11:51:48 volumio-pi kernel: rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Apr 28 11:51:48 volumio-pi kernel: rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Apr 28 11:51:48 volumio-pi kernel: rp1 0000:01:00.0: enabling device (0000 -> 0002) Apr 28 11:51:48 volumio-pi kernel: rp1 0000:01:00.0: chip_id 0x20001927 Apr 28 11:51:48 volumio-pi kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (d8:3a:dd:cd:f4:e2) Apr 28 11:51:48 volumio-pi kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000000000010810 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Apr 28 11:51:48 volumio-pi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Apr 28 11:51:48 volumio-pi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 28 11:51:48 volumio-pi kernel: usb usb1: Product: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: usb usb1: Manufacturer: Linux 6.1.69-v8+ xhci-hcd Apr 28 11:51:48 volumio-pi kernel: usb usb1: SerialNumber: xhci-hcd.0 Apr 28 11:51:48 volumio-pi kernel: hub 1-0:1.0: USB hub found Apr 28 11:51:48 volumio-pi kernel: hub 1-0:1.0: 2 ports detected Apr 28 11:51:48 volumio-pi kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Apr 28 11:51:48 volumio-pi kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 28 11:51:48 volumio-pi kernel: usb usb2: Product: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: usb usb2: Manufacturer: Linux 6.1.69-v8+ xhci-hcd Apr 28 11:51:48 volumio-pi kernel: usb usb2: SerialNumber: xhci-hcd.0 Apr 28 11:51:48 volumio-pi kernel: hub 2-0:1.0: USB hub found Apr 28 11:51:48 volumio-pi kernel: hub 2-0:1.0: 1 port detected Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000000000010810 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Apr 28 11:51:48 volumio-pi kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Apr 28 11:51:48 volumio-pi kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Apr 28 11:51:48 volumio-pi kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 28 11:51:48 volumio-pi kernel: usb usb3: Product: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: usb usb3: Manufacturer: Linux 6.1.69-v8+ xhci-hcd Apr 28 11:51:48 volumio-pi kernel: usb usb3: SerialNumber: xhci-hcd.1 Apr 28 11:51:48 volumio-pi kernel: hub 3-0:1.0: USB hub found Apr 28 11:51:48 volumio-pi kernel: hub 3-0:1.0: 2 ports detected Apr 28 11:51:48 volumio-pi kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Apr 28 11:51:48 volumio-pi kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 28 11:51:48 volumio-pi kernel: usb usb4: Product: xHCI Host Controller Apr 28 11:51:48 volumio-pi kernel: usb usb4: Manufacturer: Linux 6.1.69-v8+ xhci-hcd Apr 28 11:51:48 volumio-pi kernel: usb usb4: SerialNumber: xhci-hcd.1 Apr 28 11:51:48 volumio-pi kernel: hub 4-0:1.0: USB hub found Apr 28 11:51:48 volumio-pi kernel: hub 4-0:1.0: 1 port detected Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Apr 28 11:51:48 volumio-pi kernel: platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: platform 1000800000.codec: Adding to iommu group 0 Apr 28 11:51:48 volumio-pi kernel: platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: platform 1000880000.pisp_be: Adding to iommu group 0 Apr 28 11:51:48 volumio-pi kernel: platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Apr 28 11:51:48 volumio-pi kernel: platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Apr 28 11:51:48 volumio-pi kernel: platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Apr 28 11:51:48 volumio-pi kernel: platform axi:gpu: Adding to iommu group 1 Apr 28 11:51:48 volumio-pi kernel: platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Apr 28 11:51:48 volumio-pi kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Apr 28 11:51:48 volumio-pi kernel: sdhci-brcmstb 1000fff000.mmc: there is not valid maps for state default Apr 28 11:51:48 volumio-pi kernel: mmc0: CQHCI version 5.10 Apr 28 11:51:48 volumio-pi kernel: mmc1: CQHCI version 5.10 Apr 28 11:51:48 volumio-pi kernel: of_cfs_init Apr 28 11:51:48 volumio-pi kernel: of_cfs_init: OK Apr 28 11:51:48 volumio-pi kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Apr 28 11:51:48 volumio-pi kernel: mmc0: new ultra high speed SDR104 SDHC card at address 0007 Apr 28 11:51:48 volumio-pi kernel: mmcblk0: mmc0:0007 SD16G 14.4 GiB Apr 28 11:51:48 volumio-pi kernel: mmcblk0: p1 p2 p3 Apr 28 11:51:48 volumio-pi kernel: mmcblk0: mmc0:0007 SD16G 14.4 GiB Apr 28 11:51:48 volumio-pi kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Apr 28 11:51:48 volumio-pi kernel: Freeing unused kernel memory: 4352K Apr 28 11:51:48 volumio-pi kernel: Run /init as init process Apr 28 11:51:48 volumio-pi kernel: with arguments: Apr 28 11:51:48 volumio-pi kernel: /init Apr 28 11:51:48 volumio-pi kernel: splash Apr 28 11:51:48 volumio-pi kernel: with environment: Apr 28 11:51:48 volumio-pi kernel: HOME=/ Apr 28 11:51:48 volumio-pi kernel: TERM=linux Apr 28 11:51:48 volumio-pi kernel: imgpart=/dev/mmcblk0p2 Apr 28 11:51:48 volumio-pi kernel: imgfile=/volumio_current.sqsh Apr 28 11:51:48 volumio-pi kernel: bootdelay=5 Apr 28 11:51:48 volumio-pi kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Apr 28 11:51:48 volumio-pi initramfs: Booting Volumio for Apr 28 11:51:48 volumio-pi initramfs: This script mounts rootfs RO with an overlay RW layer. Apr 28 11:51:48 volumio-pi kernel: usb 3-2: new high-speed USB device number 2 using xhci-hcd Apr 28 11:51:48 volumio-pi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 28 11:51:48 volumio-pi initramfs: IMGPART=/dev/mmcblk0p2 Apr 28 11:51:48 volumio-pi initramfs: IMGFILE=/volumio_current.sqsh Apr 28 11:51:48 volumio-pi initramfs: Boot delay (except first time) will be 5 seconds Apr 28 11:51:48 volumio-pi initramfs: /dev/mmcblk0p2: Apr 28 11:51:48 volumio-pi kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 28 11:51:48 volumio-pi kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Apr 28 11:51:48 volumio-pi initramfs: Doing a 5 second delay here to give kernel load a headstart Apr 28 11:51:48 volumio-pi kernel: usb 3-2: New USB device found, idVendor=0495, idProduct=809b, bcdDevice= 0.09 Apr 28 11:51:48 volumio-pi kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 28 11:51:48 volumio-pi kernel: usb 3-2: Product: Arcam A25 Apr 28 11:51:48 volumio-pi kernel: usb 3-2: Manufacturer: Arcam Apr 28 11:51:48 volumio-pi kernel: usb 3-2: SerialNumber: 1 Apr 28 11:51:48 volumio-pi kernel: usbhid 3-2:1.2: couldn't find an input interrupt endpoint Apr 28 11:51:48 volumio-pi initramfs: Checking for USB updates Apr 28 11:51:48 volumio-pi initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Apr 28 11:51:48 volumio-pi kernel: loop0: detected capacity change from 0 to 967536 Apr 28 11:51:48 volumio-pi kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 28 11:51:48 volumio-pi kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Apr 28 11:51:48 volumio-pi kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038 (0x7fffffff) Apr 28 11:51:48 volumio-pi initramfs: With Option: Apr 28 11:51:48 volumio-pi initramfs: VOLUMIO_VERSION="3.631" Apr 28 11:51:48 volumio-pi initramfs: Finish initramfs, continue booting Volumio Apr 28 11:51:48 volumio-pi kernel: NET: Registered PF_INET6 protocol family Apr 28 11:51:48 volumio-pi kernel: Segment Routing with IPv6 Apr 28 11:51:48 volumio-pi kernel: In-situ OAM (IOAM) with IPv6 Apr 28 11:51:48 volumio-pi 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) Apr 28 11:51:48 volumio-pi systemd[1]: Detected architecture arm64. Apr 28 11:51:48 volumio-pi systemd[1]: Set hostname to . Apr 28 11:51:48 volumio-pi kernel: uart-pl011 107d001000.serial: no DMA platform data Apr 28 11:51:48 volumio-pi 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. Apr 28 11:51:48 volumio-pi systemd[1]: Created slice system-systemd\x2dfsck.slice. Apr 28 11:51:48 volumio-pi systemd[1]: Listening on initctl Compatibility Named Pipe. Apr 28 11:51:48 volumio-pi systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Apr 28 11:51:48 volumio-pi systemd[1]: Created slice system-serial\x2dgetty.slice. Apr 28 11:51:48 volumio-pi systemd[1]: Started Forward Password Requests to Wall Directory Watch. Apr 28 11:51:48 volumio-pi systemd[1]: Created slice system-getty.slice. Apr 28 11:51:48 volumio-pi kernel: i2c_dev: i2c /dev entries driver Apr 28 11:51:48 volumio-pi systemd-journald[211]: Journal started Apr 28 11:51:48 volumio-pi systemd-journald[211]: Runtime journal (/run/log/journal/9522fdb065c2fbf4e8ff0b3a65b90e23) is 3.7M, max 30.0M, 26.2M free. Apr 28 11:51:48 volumio-pi systemd-modules-load[208]: Inserted module 'i2c_dev' Apr 28 11:51:48 volumio-pi systemd[1]: Mounted RPC Pipe File System. Apr 28 11:51:48 volumio-pi systemd[1]: Mounted Kernel Debug File System. Apr 28 11:51:48 volumio-pi systemd[1]: Mounted Kernel Configuration File System. Apr 28 11:51:48 volumio-pi systemd[1]: Mounted POSIX Message Queue File System. Apr 28 11:51:48 volumio-pi systemd[1]: Started Remount Root and Kernel File Systems. Apr 28 11:51:48 volumio-pi systemd[1]: Starting Load/Save Random Seed... Apr 28 11:51:48 volumio-pi fake-hwclock[209]: Current system time: 2024-04-28 08:51:48 Apr 28 11:51:48 volumio-pi fake-hwclock[209]: fake-hwclock saved clock information is in the past: 2024-04-28 08:51:32 Apr 28 11:51:48 volumio-pi fake-hwclock[209]: To set system time to this saved clock anyway, use "force" Apr 28 11:51:48 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 28 11:51:48 volumio-pi systemd[1]: Starting Create System Users... Apr 28 11:51:48 volumio-pi systemd[1]: Started Restore / save the current clock. Apr 28 11:51:48 volumio-pi systemd[1]: Started Apply Kernel Variables. Apr 28 11:51:48 volumio-pi systemd[1]: Started Load/Save Random Seed. Apr 28 11:51:48 volumio-pi systemd[1]: dynamicswap.service: Succeeded. Apr 28 11:51:48 volumio-pi systemd[1]: Started Create System Users. Apr 28 11:51:48 volumio-pi systemd[1]: Starting Create Static Device Nodes in /dev... Apr 28 11:51:48 volumio-pi systemd[1]: Started udev Coldplug all Devices. Apr 28 11:51:48 volumio-pi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 28 11:51:48 volumio-pi systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 28 11:51:48 volumio-pi systemd[1]: Started Create Static Device Nodes in /dev. Apr 28 11:51:48 volumio-pi systemd[1]: Reached target Local File Systems (Pre). Apr 28 11:51:48 volumio-pi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 28 11:51:48 volumio-pi systemd[1]: Mounting /var/log... Apr 28 11:51:48 volumio-pi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 28 11:51:48 volumio-pi systemd[1]: Mounting /tmp... Apr 28 11:51:48 volumio-pi systemd[1]: Mounting /var/spool/cups... Apr 28 11:51:48 volumio-pi systemd[1]: Starting udev Kernel Device Manager... Apr 28 11:51:48 volumio-pi systemd[1]: Mounted /var/log. Apr 28 11:51:48 volumio-pi systemd[1]: Mounted /tmp. Apr 28 11:51:48 volumio-pi systemd[1]: Mounted /var/spool/cups. Apr 28 11:51:48 volumio-pi systemd[1]: Mounting /var/spool/cups/tmp... Apr 28 11:51:48 volumio-pi systemd[1]: Starting Flush Journal to Persistent Storage... Apr 28 11:51:48 volumio-pi systemd[1]: Mounted /var/spool/cups/tmp. Apr 28 11:51:48 volumio-pi systemd-udevd[236]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 28 11:51:48 volumio-pi systemd-journald[211]: Runtime journal (/run/log/journal/9522fdb065c2fbf4e8ff0b3a65b90e23) is 3.7M, max 30.0M, 26.2M free. Apr 28 11:51:48 volumio-pi systemd[1]: Started Flush Journal to Persistent Storage. Apr 28 11:51:48 volumio-pi systemd[1]: Started udev Kernel Device Manager. Apr 28 11:51:48 volumio-pi systemd[1]: Starting Show Plymouth Boot Screen... Apr 28 11:51:48 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 271 (plymouthd). Apr 28 11:51:48 volumio-pi kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Apr 28 11:51:48 volumio-pi kernel: mc: Linux media interface: v0.10 Apr 28 11:51:48 volumio-pi systemd[1]: Started Show Plymouth Boot Screen. Apr 28 11:51:48 volumio-pi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 28 11:51:48 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 28 11:51:48 volumio-pi systemd[1]: Reached target Local Encrypted Volumes. Apr 28 11:51:48 volumio-pi systemd[1]: Reached target Paths. Apr 28 11:51:48 volumio-pi systemd-udevd[265]: Using default interface naming scheme 'v240'. Apr 28 11:51:48 volumio-pi kernel: brcmstb-i2c 107d508200.i2c: @200000hz registered in interrupt mode Apr 28 11:51:48 volumio-pi kernel: brcmstb-i2c 107d508280.i2c: @200000hz registered in interrupt mode Apr 28 11:51:48 volumio-pi kernel: videodev: Linux video capture interface: v2.00 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Apr 28 11:51:48 volumio-pi kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Apr 28 11:51:48 volumio-pi systemd-udevd[265]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 28 11:51:48 volumio-pi systemd[1]: Found device /dev/ttyAMA10. Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: pispbe_probe: HW version: 0x02252700 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: pispbe_probe: Status: 0x00000000 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: Register nodes for group 0 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: input device node registered as /dev/video20 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: config device node registered as /dev/video28 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: Register nodes for group 1 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: input device node registered as /dev/video29 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36 Apr 28 11:51:48 volumio-pi kernel: pispbe 1000880000.pisp_be: config device node registered as /dev/video37 Apr 28 11:51:48 volumio-pi kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 11:51:48 volumio-pi kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Apr 28 11:51:48 volumio-pi kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Apr 28 11:51:48 volumio-pi systemd-udevd[241]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 28 11:51:48 volumio-pi kernel: [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 0 Apr 28 11:51:48 volumio-pi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 28 11:51:49 volumio-pi kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 28 11:51:49 volumio-pi kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 28 11:51:49 volumio-pi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 28 11:51:49 volumio-pi kernel: Bluetooth: Core ver 2.22 Apr 28 11:51:49 volumio-pi kernel: NET: Registered PF_BLUETOOTH protocol family Apr 28 11:51:49 volumio-pi kernel: Bluetooth: HCI device and connection manager initialized Apr 28 11:51:49 volumio-pi kernel: Bluetooth: HCI socket layer initialized Apr 28 11:51:49 volumio-pi kernel: Bluetooth: L2CAP socket layer initialized Apr 28 11:51:49 volumio-pi kernel: Bluetooth: SCO socket layer initialized Apr 28 11:51:49 volumio-pi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Apr 28 11:51:49 volumio-pi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 28 11:51:49 volumio-pi kernel: usbcore: registered new interface driver brcmfmac Apr 28 11:51:49 volumio-pi kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Apr 28 11:51:49 volumio-pi kernel: Bluetooth: HCI UART driver ver 2.3 Apr 28 11:51:49 volumio-pi kernel: Bluetooth: HCI UART protocol H4 registered Apr 28 11:51:49 volumio-pi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 28 11:51:49 volumio-pi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Apr 28 11:51:49 volumio-pi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Apr 28 11:51:49 volumio-pi kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 28 11:51:49 volumio-pi systemd-udevd[244]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 28 11:51:49 volumio-pi 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 Apr 28 11:51:49 volumio-pi systemd-udevd[244]: Using default interface naming scheme 'v240'. Apr 28 11:51:49 volumio-pi systemd-udevd[244]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 28 11:51:49 volumio-pi systemd[1]: Found device /dev/mmcblk0p1. Apr 28 11:51:49 volumio-pi kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Created slice system-bthelper.slice. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Apr 28 11:51:49 volumio-pi kernel: Registered IR keymap rc-cec Apr 28 11:51:49 volumio-pi kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Apr 28 11:51:49 volumio-pi kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input1 Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Apr 28 11:51:49 volumio-pi kernel: Registered IR keymap rc-cec Apr 28 11:51:49 volumio-pi kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Apr 28 11:51:49 volumio-pi kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input2 Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Apr 28 11:51:49 volumio-pi systemd[1]: Starting File System Check on /dev/mmcblk0p1... Apr 28 11:51:49 volumio-pi kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 1 Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Apr 28 11:51:49 volumio-pi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Apr 28 11:51:49 volumio-pi kernel: Bluetooth: hci0: BCM: chip id 107 Apr 28 11:51:49 volumio-pi kernel: Bluetooth: hci0: BCM: features 0x2f Apr 28 11:51:49 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 Apr 28 11:51:49 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Apr 28 11:51:49 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Apr 28 11:51:49 volumio-pi systemd-udevd[254]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 28 11:51:49 volumio-pi systemd-udevd[257]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 28 11:51:49 volumio-pi systemd-fsck[507]: fsck.fat 4.1 (2017-01-24) Apr 28 11:51:49 volumio-pi systemd-fsck[507]: /dev/mmcblk0p1: 364 files, 33346/46774 clusters Apr 28 11:51:49 volumio-pi systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Apr 28 11:51:49 volumio-pi systemd[1]: Started File System Check on /dev/mmcblk0p1. Apr 28 11:51:49 volumio-pi systemd[1]: Started File System Check Daemon to report status. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Mounting /boot... Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Started Load/Save RF Kill Switch Status. Apr 28 11:51:49 volumio-pi systemd[1]: Mounted /boot. Apr 28 11:51:49 volumio-pi systemd[1]: Reached target Local File Systems. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Create Volatile Files and Directories... Apr 28 11:51:49 volumio-pi systemd[1]: Started ifup for eth0. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Raise network interfaces... Apr 28 11:51:49 volumio-pi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Started ifup for wlan0. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Preprocess NFS configuration... Apr 28 11:51:49 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 271 (plymouthd). Apr 28 11:51:49 volumio-pi systemd[1]: plymouth-read-write.service: Succeeded. Apr 28 11:51:49 volumio-pi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 28 11:51:49 volumio-pi systemd[1]: nfs-config.service: Succeeded. Apr 28 11:51:49 volumio-pi systemd[1]: Started Preprocess NFS configuration. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Reached target NFS client services. Apr 28 11:51:49 volumio-pi systemd[1]: Started Create Volatile Files and Directories. Apr 28 11:51:49 volumio-pi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 28 11:51:49 volumio-pi systemd[1]: Starting RPC bind portmap service... Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 28 11:51:49 volumio-pi systemd[1]: Reached target System Initialization. Apr 28 11:51:49 volumio-pi systemd[1]: Started Volumio Splash. Apr 28 11:51:49 volumio-pi systemd[598]: volumiosplash.service: Failed to execute command: No such file or directory Apr 28 11:51:49 volumio-pi systemd[598]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Apr 28 11:51:49 volumio-pi systemd[1]: Started Daily apt download activities. Apr 28 11:51:49 volumio-pi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 28 11:51:49 volumio-pi systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 28 11:51:49 volumio-pi systemd[1]: Started Daily apt upgrade and clean activities. Apr 28 11:51:49 volumio-pi systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 28 11:51:49 volumio-pi systemd[1]: Reached target Timers. Apr 28 11:51:49 volumio-pi systemd[1]: Listening on triggerhappy.socket. Apr 28 11:51:49 volumio-pi systemd[1]: Reached target Sockets. Apr 28 11:51:49 volumio-pi systemd[1]: Reached target Basic System. Apr 28 11:51:49 volumio-pi systemd[1]: Started Manage Sound Card State (restore and store). Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 28 11:51:49 volumio-pi alsactl[608]: alsactl 1.1.8 daemon started Apr 28 11:51:49 volumio-pi systemd[1]: Starting Wireless Services... Apr 28 11:51:49 volumio-pi systemd[1]: Starting Login Service... Apr 28 11:51:49 volumio-pi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Save/Restore Sound Card State... Apr 28 11:51:49 volumio-pi systemd[1]: Started volumio-remote-updater.service. Apr 28 11:51:49 volumio-pi systemd[1]: Starting dhcpcd on all interfaces... Apr 28 11:51:49 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 11:51:49 volumio-pi systemd[1]: Started Volumio Iptables Module. Apr 28 11:51:49 volumio-pi systemd[1]: Started D-Bus System Message Bus. Apr 28 11:51:49 volumio-pi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 28 11:51:49 volumio-pi systemd[1]: Starting WPA supplicant... Apr 28 11:51:49 volumio-pi systemd[1]: Starting triggerhappy global hotkey daemon... Apr 28 11:51:49 volumio-pi systemd[1]: Started UPnP Renderer front-end to MPD. Apr 28 11:51:49 volumio-pi systemd[1]: Started Volumio Log Rotation Service. Apr 28 11:51:49 volumio-pi systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 28 11:51:49 volumio-pi systemd[1]: haveged.service: Main process exited, code=killed, status=11/SEGV Apr 28 11:51:49 volumio-pi systemd[1]: haveged.service: Failed with result 'signal'. Apr 28 11:51:49 volumio-pi systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Apr 28 11:51:49 volumio-pi systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Apr 28 11:51:49 volumio-pi dhcpcd[617]: Not running dhcpcd because /etc/network/interfaces Apr 28 11:51:49 volumio-pi dhcpcd[617]: defines some interfaces that will use a Apr 28 11:51:49 volumio-pi dhcpcd[617]: DHCP client or static address Apr 28 11:51:49 volumio-pi kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 28 11:51:49 volumio-pi sh[540]: wlan0=wlan0 Apr 28 11:51:49 volumio-pi thd[634]: Unable to parse trigger line: Apr 28 11:51:49 volumio-pi thd[634]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 28 11:51:49 volumio-pi thd[634]: Unable to parse trigger line: Apr 28 11:51:49 volumio-pi thd[634]: Found socket passed from systemd Apr 28 11:51:49 volumio-pi systemd-logind[613]: New seat seat0. Apr 28 11:51:50 volumio-pi systemd-logind[613]: Watching system buttons on /dev/input/event0 (pwr_button) Apr 28 11:51:50 volumio-pi systemd-logind[613]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Apr 28 11:51:50 volumio-pi systemd-logind[613]: Watching system buttons on /dev/input/event2 (vc4-hdmi-1) Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Successfully dropped root privileges. Apr 28 11:51:50 volumio-pi avahi-daemon[614]: avahi-daemon 0.7 starting up. Apr 28 11:51:50 volumio-pi kernel: Bluetooth: hci0: BCM: features 0x2f Apr 28 11:51:50 volumio-pi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Apr 28 11:51:50 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Apr 28 11:51:50 volumio-pi kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Apr 28 11:51:50 volumio-pi bthelper[639]: Raspberry Pi BDADDR already set Apr 28 11:51:50 volumio-pi kernel: usbcore: registered new interface driver snd-usb-audio Apr 28 11:51:50 volumio-pi kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Apr 28 11:51:50 volumio-pi kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Apr 28 11:51:50 volumio-pi sh[535]: eth0: waiting for carrier Apr 28 11:51:50 volumio-pi dhcpcd[592]: eth0: waiting for carrier Apr 28 11:51:50 volumio-pi kernel: pps pps0: new PPS source ptp0 Apr 28 11:51:50 volumio-pi kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Apr 28 11:51:50 volumio-pi systemd[1]: Started triggerhappy global hotkey daemon. Apr 28 11:51:50 volumio-pi systemd[1]: Started RPC bind portmap service. Apr 28 11:51:50 volumio-pi wpa_supplicant[632]: Successfully initialized wpa_supplicant Apr 28 11:51:50 volumio-pi systemd[1]: Started Save/Restore Sound Card State. Apr 28 11:51:50 volumio-pi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 28 11:51:50 volumio-pi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Successfully called chroot(). Apr 28 11:51:50 volumio-pi systemd[1]: Failed to start dhcpcd on all interfaces. Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Successfully dropped remaining capabilities. Apr 28 11:51:50 volumio-pi systemd[1]: Started Raspberry Pi bluetooth helper. Apr 28 11:51:50 volumio-pi volumio-remote-updater[616]: Error: No active session Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Loading service file /services/volumio.service. Apr 28 11:51:50 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:51:50] [info] asio async_connect error: system:111 (Connection refused) Apr 28 11:51:50 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:51:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 28 11:51:50 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:51:50] [error] handle_connect error: Underlying Transport Error Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Network interface enumeration completed. Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Server startup complete. Host name is volumio-pi.local. Local service cookie is 3783699056. Apr 28 11:51:50 volumio-pi avahi-daemon[614]: Service "Volumio-pi" (/services/volumio.service) successfully established. Apr 28 11:51:50 volumio-pi volumio[635]: Could not open config: /tmp/upmpdcli.conf Apr 28 11:51:50 volumio-pi systemd-udevd[254]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Apr 28 11:51:50 volumio-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 28 11:51:50 volumio-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 28 11:51:50 volumio-pi systemd[1]: iptables.service: Succeeded. Apr 28 11:51:50 volumio-pi systemd[1]: Started Login Service. Apr 28 11:51:50 volumio-pi systemd[1]: Started WPA supplicant. Apr 28 11:51:50 volumio-pi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 28 11:51:50 volumio-pi systemd[1]: Starting Bluetooth service... Apr 28 11:51:50 volumio-pi systemd[1]: Reached target Sound Card. Apr 28 11:51:50 volumio-pi systemd[1]: Reached target Remote File Systems (Pre). Apr 28 11:51:50 volumio-pi systemd[1]: Reached target Remote File Systems. Apr 28 11:51:50 volumio-pi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 28 11:51:50 volumio-pi systemd[1]: Reached target RPC Port Mapper. Apr 28 11:51:50 volumio-pi systemd[1]: Started Raise network interfaces. Apr 28 11:51:50 volumio-pi systemd[1]: Reached target Network. Apr 28 11:51:50 volumio-pi systemd[1]: Starting Network Time Service... Apr 28 11:51:50 volumio-pi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 28 11:51:50 volumio-pi systemd[1]: Starting Permit User Sessions... Apr 28 11:51:50 volumio-pi systemd[1]: Reached target Network is Online. Apr 28 11:51:50 volumio-pi systemd[1]: Starting /etc/rc.local Compatibility... Apr 28 11:51:50 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 11:51:50 volumio-pi systemd[1]: Starting LSB: Brings up/down network automatically... Apr 28 11:51:50 volumio-pi systemd[1]: Starting Samba NMB Daemon... Apr 28 11:51:50 volumio-pi systemd[1]: Started /etc/rc.local Compatibility. Apr 28 11:51:50 volumio-pi systemd[1]: Started Permit User Sessions. Apr 28 11:51:50 volumio-pi systemd[1]: Starting Hold until boot process finishes up... Apr 28 11:51:50 volumio-pi systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 28 11:51:50 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 271 (plymouthd). Apr 28 11:51:50 volumio-pi systemd[1]: plymouth-quit.service: Succeeded. Apr 28 11:51:50 volumio-pi systemd[1]: Started Terminate Plymouth Boot Screen. Apr 28 11:51:50 volumio-pi systemd[1]: plymouth-start.service: Succeeded. Apr 28 11:51:50 volumio-pi systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 28 11:51:50 volumio-pi systemd[1]: Started Hold until boot process finishes up. Apr 28 11:51:50 volumio-pi systemd[1]: Started Getty on tty1. Apr 28 11:51:50 volumio-pi systemd[1]: Started Serial Getty on ttyAMA10. Apr 28 11:51:50 volumio-pi systemd[1]: Reached target Login Prompts. Apr 28 11:51:51 volumio-pi ntpd[739]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 28 11:51:51 volumio-pi ntpd[739]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 28 11:51:51 volumio-pi systemd[1]: Started Network Time Service. Apr 28 11:51:51 volumio-pi ifplugd(eth0)[778]: ifplugd 0.28 initializing. Apr 28 11:51:51 volumio-pi ifplugd(eth0)[778]: Using interface eth0/D8:3A:DD:CD:F4:E2 with driver (version: 6.1.69-v8+) Apr 28 11:51:51 volumio-pi ifplugd(eth0)[778]: Using detection mode: SIOCETHTOOL Apr 28 11:51:51 volumio-pi ifplugd(eth0)[778]: Initialization complete, link beat not detected. Apr 28 11:51:51 volumio-pi bluetoothd[737]: Bluetooth daemon 5.50 Apr 28 11:51:51 volumio-pi ntpd[769]: proto: precision = 1.241 usec (-20) Apr 28 11:51:51 volumio-pi bluetoothd[737]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 28 11:51:51 volumio-pi systemd[1]: Started Bluetooth service. Apr 28 11:51:51 volumio-pi systemd[1]: Reached target Bluetooth. Apr 28 11:51:51 volumio-pi ntpd[769]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 28 11:51:51 volumio-pi ntpd[769]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 28 11:51:51 volumio-pi ntpd[769]: Listen and drop on 0 v6wildcard [::]:123 Apr 28 11:51:51 volumio-pi ntpd[769]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 28 11:51:51 volumio-pi ntpd[769]: Listen normally on 2 lo 127.0.0.1:123 Apr 28 11:51:51 volumio-pi ntpd[769]: Listening on routing socket on fd #19 for interface updates Apr 28 11:51:51 volumio-pi ntpd[769]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 28 11:51:51 volumio-pi ntpd[769]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 28 11:51:51 volumio-pi bluetoothd[737]: Starting SDP server Apr 28 11:51:51 volumio-pi bluetoothd[737]: Excluding (cli) sap Apr 28 11:51:51 volumio-pi loadcpufreq[738]: Loading cpufreq kernel modules...done (none). Apr 28 11:51:51 volumio-pi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 28 11:51:51 volumio-pi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 28 11:51:51 volumio-pi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 28 11:51:51 volumio-pi kernel: Bluetooth: BNEP filters: protocol multicast Apr 28 11:51:51 volumio-pi kernel: Bluetooth: BNEP socket layer initialized Apr 28 11:51:51 volumio-pi bluetoothd[737]: Bluetooth management interface 1.22 initialized Apr 28 11:51:51 volumio-pi dbus-daemon[628]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=737 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 28 11:51:51 volumio-pi kernel: Bluetooth: MGMT ver 1.22 Apr 28 11:51:51 volumio-pi ifplugd[743]: Network Interface Plugging Daemon...start eth0...done. Apr 28 11:51:51 volumio-pi systemd[1]: Started LSB: Brings up/down network automatically. Apr 28 11:51:51 volumio-pi cpufrequtils[798]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 28 11:51:51 volumio-pi systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 28 11:51:51 volumio-pi systemd[1]: Starting Hostname Service... Apr 28 11:51:51 volumio-pi bluetoothd[737]: Failed to set privacy: Rejected (0x0b) Apr 28 11:51:51 volumio-pi dbus-daemon[628]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 28 11:51:51 volumio-pi systemd[1]: Started Hostname Service. Apr 28 11:51:51 volumio-pi kernel: new mount options do not match the existing superblock, will be ignored Apr 28 11:51:51 volumio-pi nmbd[748]: [2024/04/28 11:51:51.568241, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 28 11:51:51 volumio-pi nmbd[748]: started asyncdns process 817 Apr 28 11:51:51 volumio-pi nmbd[748]: [2024/04/28 11:51:51.569732, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 28 11:51:51 volumio-pi nmbd[748]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 28 11:51:51 volumio-pi nmbd[748]: [2024/04/28 11:51:51.570023, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 28 11:51:51 volumio-pi nmbd[748]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 28 11:51:51 volumio-pi volumio[611]: cset: --> shielding system active with Apr 28 11:51:51 volumio-pi volumio[611]: cset: "system" cpuset of CPUSPEC(1-3) with 76 tasks running Apr 28 11:51:51 volumio-pi volumio[611]: cset: "user" cpuset of CPUSPEC(0) with 0 tasks running Apr 28 11:51:51 volumio-pi wireless.js[612]: Cleaning previous... Apr 28 11:51:51 volumio-pi systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 28 11:51:51 volumio-pi sudo[828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 28 11:51:51 volumio-pi sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:51 volumio-pi sudo[828]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:51 volumio-pi sudo[830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 28 11:51:51 volumio-pi sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:52 volumio-pi ntpd[769]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 28 11:51:52 volumio-pi sudo[830]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:52 volumio-pi wireless.js[612]: Stopped aP Apr 28 11:51:52 volumio-pi sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 28 11:51:52 volumio-pi sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:52 volumio-pi sudo[837]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:52 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 11:51:52 volumio-pi sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 28 11:51:52 volumio-pi sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:52 volumio-pi sudo[839]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:52 volumio-pi sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 28 11:51:52 volumio-pi sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:52 volumio-pi sudo[846]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:52 volumio-pi sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 28 11:51:52 volumio-pi sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:53 volumio-pi ntpd[769]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 28 11:51:54 volumio-pi ntpd[769]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 28 11:51:54 volumio-pi systemd[1]: systemd-rfkill.service: Succeeded. Apr 28 11:51:55 volumio-pi sudo[848]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:55 volumio-pi wireless.js[612]: SETTING APPROPRIATE REG DOMAIN: RU Apr 28 11:51:55 volumio-pi sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 28 11:51:55 volumio-pi sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:55 volumio-pi sudo[853]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:55 volumio-pi sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set RU Apr 28 11:51:55 volumio-pi sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:55 volumio-pi sudo[855]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:55 volumio-pi wireless.js[612]: SUCCESSFULLY SET NEW REGDOMAIN: RU Apr 28 11:51:55 volumio-pi wireless.js[612]: Start wireless flow Apr 28 11:51:55 volumio-pi wireless.js[612]: Stopped hotspot (if there).. Apr 28 11:51:55 volumio-pi sudo[860]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 28 11:51:55 volumio-pi sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:55 volumio-pi sudo[860]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:55 volumio-pi sudo[862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 28 11:51:55 volumio-pi sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:55 volumio-pi ntpd[769]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 28 11:51:55 volumio-pi bthelper[639]: Changing power off succeeded Apr 28 11:51:55 volumio-pi bthelper[639]: [CHG] Controller D8:3A:DD:CD:F4:E4 Class: 0x0000041c Apr 28 11:51:55 volumio-pi bthelper[639]: Changing power on succeeded Apr 28 11:51:55 volumio-pi sudo[862]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:55 volumio-pi wireless.js[612]: DHCP IP Apr 28 11:51:55 volumio-pi wireless.js[612]: Start ap Apr 28 11:51:55 volumio-pi wpa_supplicant[865]: Successfully initialized wpa_supplicant Apr 28 11:51:55 volumio-pi sudo[866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 28 11:51:55 volumio-pi sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:55 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 11:51:55 volumio-pi dhcpcd[867]: dev: loaded udev Apr 28 11:51:55 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:51:55] [info] asio async_connect error: system:111 (Connection refused) Apr 28 11:51:55 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:51:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 28 11:51:55 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:51:55] [error] handle_connect error: Underlying Transport Error Apr 28 11:51:55 volumio-pi dhcpcd-run-hooks[885]: wlan0: starting wpa_supplicant Apr 28 11:51:55 volumio-pi dhcpcd-run-hooks[890]: wlan0: failed to start wpa_supplicant Apr 28 11:51:55 volumio-pi dhcpcd-run-hooks[891]: 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 Apr 28 11:51:55 volumio-pi dhcpcd[867]: wlan0: connected to Access Point `' Apr 28 11:51:55 volumio-pi dhcpcd[867]: no interfaces have a carrier Apr 28 11:51:55 volumio-pi dhcpcd[867]: forked to background, child pid 901 Apr 28 11:51:55 volumio-pi dhcpcd[901]: eth0: waiting for carrier Apr 28 11:51:55 volumio-pi dhcpcd[901]: wlan0: waiting for carrier Apr 28 11:51:55 volumio-pi sudo[866]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:56 volumio-pi wireless.js[612]: trying... Apr 28 11:51:56 volumio-pi sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:51:56 volumio-pi sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:56 volumio-pi sudo[903]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:57 volumio-pi wireless.js[612]: trying... Apr 28 11:51:57 volumio-pi sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:51:57 volumio-pi sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:57 volumio-pi sudo[906]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:58 volumio-pi wpa_supplicant[889]: wlan0: Trying to associate with SSID 'meps' Apr 28 11:51:58 volumio-pi wireless.js[612]: trying... Apr 28 11:51:58 volumio-pi sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:51:58 volumio-pi sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:58 volumio-pi sudo[909]: pam_unix(sudo:session): session closed for user root Apr 28 11:51:59 volumio-pi wireless.js[612]: trying... Apr 28 11:51:59 volumio-pi sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:51:59 volumio-pi sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:51:59 volumio-pi sudo[912]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:00 volumio-pi wireless.js[612]: trying... Apr 28 11:52:00 volumio-pi sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:00 volumio-pi sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:00 volumio-pi sudo[915]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: carrier acquired Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: connected to Access Point `meps' Apr 28 11:52:01 volumio-pi wpa_supplicant[889]: wlan0: Associated with 50:ff:20:77:f9:ee Apr 28 11:52:01 volumio-pi wpa_supplicant[889]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:77:f9:ee completed [id=0 id_str=] Apr 28 11:52:01 volumio-pi wpa_supplicant[889]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 28 11:52:01 volumio-pi wpa_supplicant[889]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU Apr 28 11:52:01 volumio-pi kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Apr 28 11:52:01 volumio-pi dhcpcd[901]: DUID 00:01:00:01:2d:5e:6e:30:d8:3a:dd:cd:f4:e3 Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: IAID dd:cd:f4:e3 Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: adding address fe80::1ea5:c147:3758:2491 Apr 28 11:52:01 volumio-pi dhcpcd[901]: ipv6_addaddr1: Permission denied Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: carrier lost Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: carrier acquired Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: IAID dd:cd:f4:e3 Apr 28 11:52:01 volumio-pi wireless.js[612]: trying... Apr 28 11:52:01 volumio-pi sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:01 volumio-pi sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:01 volumio-pi sudo[930]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: soliciting an IPv6 router Apr 28 11:52:01 volumio-pi dhcpcd[901]: wlan0: rebinding lease of 192.168.1.30 Apr 28 11:52:02 volumio-pi dhcpcd[901]: wlan0: probing address 192.168.1.30/24 Apr 28 11:52:02 volumio-pi wireless.js[612]: trying... Apr 28 11:52:02 volumio-pi sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:02 volumio-pi sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:02 volumio-pi sudo[933]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:03 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:52:03] [info] asio async_connect error: system:111 (Connection refused) Apr 28 11:52:03 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:52:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 28 11:52:03 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:52:03] [error] handle_connect error: Underlying Transport Error Apr 28 11:52:03 volumio-pi wireless.js[612]: trying... Apr 28 11:52:03 volumio-pi sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:03 volumio-pi sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:03 volumio-pi sudo[936]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:04 volumio-pi wireless.js[612]: trying... Apr 28 11:52:04 volumio-pi sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:04 volumio-pi sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:04 volumio-pi sudo[939]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:05 volumio-pi wireless.js[612]: trying... Apr 28 11:52:05 volumio-pi sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:05 volumio-pi sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:05 volumio-pi sudo[942]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:06 volumio-pi wireless.js[612]: trying... Apr 28 11:52:06 volumio-pi sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:06 volumio-pi sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:06 volumio-pi sudo[945]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:07 volumio-pi dhcpcd[901]: wlan0: leased 192.168.1.30 for 259200 seconds Apr 28 11:52:07 volumio-pi avahi-daemon[614]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.30. Apr 28 11:52:07 volumio-pi avahi-daemon[614]: New relevant interface wlan0.IPv4 for mDNS. Apr 28 11:52:07 volumio-pi avahi-daemon[614]: Registering new address record for 192.168.1.30 on wlan0.IPv4. Apr 28 11:52:07 volumio-pi dhcpcd[901]: wlan0: adding route to 192.168.1.0/24 Apr 28 11:52:07 volumio-pi dhcpcd[901]: wlan0: adding default route via 192.168.1.1 Apr 28 11:52:07 volumio-pi wireless.js[612]: trying... Apr 28 11:52:07 volumio-pi sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 28 11:52:07 volumio-pi sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:07 volumio-pi sudo[952]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:07 volumio-pi wireless.js[612]: Connected to: ----meps Apr 28 11:52:07 volumio-pi wireless.js[612]: ---- Apr 28 11:52:07 volumio-pi sudo[969]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 11:52:07 volumio-pi sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:07 volumio-pi sudo[969]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:07 volumio-pi wireless.js[612]: ... joined AP, wlan0 IPv4 is 192.168.1.30, ipV6 is undefined Apr 28 11:52:07 volumio-pi wireless.js[612]: It's done! AP Apr 28 11:52:07 volumio-pi systemd[1]: Started Wireless Services. Apr 28 11:52:07 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 11:52:07 volumio-pi systemd[1]: Started Volumio Backend Module. Apr 28 11:52:07 volumio-pi systemd[1]: Started Volumio Cpu Tweaker. Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: Setting RT Priority for mpd Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: pid 35's current scheduling policy: SCHED_OTHER Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: pid 35's current scheduling priority: 0 Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: Setting MPD Affinity Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: pid 3's current affinity mask: f Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 28 11:52:07 volumio-pi volumio-cpu-tweak[977]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Apr 28 11:52:07 volumio-pi systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 28 11:52:08 volumio-pi volumio[976]: info: ------------------------------------------- Apr 28 11:52:08 volumio-pi volumio[976]: info: ----- Volumio3 ---- Apr 28 11:52:08 volumio-pi volumio[976]: info: ------------------------------------------- Apr 28 11:52:08 volumio-pi volumio[976]: info: ----- System startup ---- Apr 28 11:52:08 volumio-pi volumio[976]: info: ------------------------------------------- Apr 28 11:52:08 volumio-pi volumio[976]: info: MYVOLUMIO Environment detected Apr 28 11:52:08 volumio-pi volumio[976]: info: Plugin folders cleanup Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning into folder /volumio/app/plugins/ Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category audio_interface Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category miscellanea Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category music_service Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category plugins.json Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category system_controller Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category user_interface Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning into folder /data/plugins/ Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category music_service Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category system_controller Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category system_hardware Apr 28 11:52:08 volumio-pi volumio[976]: info: Scanning category user_interface Apr 28 11:52:08 volumio-pi volumio[976]: info: Plugin folders cleanup completed Apr 28 11:52:08 volumio-pi volumio[976]: info: ------------------------------------------- Apr 28 11:52:08 volumio-pi volumio[976]: info: ----- Core plugins startup ---- Apr 28 11:52:08 volumio-pi volumio[976]: info: ------------------------------------------- Apr 28 11:52:08 volumio-pi volumio[976]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 11:52:08 volumio-pi volumio[976]: info: Adding plugin upnp to MyMusic Plugins Apr 28 11:52:08 volumio-pi volumio[976]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 11:52:08 volumio-pi volumio[976]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 11:52:08 volumio-pi volumio[976]: info: Loading plugins from folder /data/plugins/ Apr 28 11:52:08 volumio-pi volumio[976]: info: Loading plugin "system"... Apr 28 11:52:08 volumio-pi volumio[976]: info: Loading plugin "appearance"... Apr 28 11:52:09 volumio-pi ntpd[769]: Listen normally on 3 wlan0 192.168.1.30:123 Apr 28 11:52:09 volumio-pi ntpd[769]: new interface(s) found: waking up resolver Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "network"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Refreshing Cached IP Addresses Apr 28 11:52:09 volumio-pi sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 11:52:09 volumio-pi sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:09 volumio-pi sudo[1007]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:09 volumio-pi sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "services"... Apr 28 11:52:09 volumio-pi sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "alsa_controller"... Apr 28 11:52:09 volumio-pi sudo[1009]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:09 volumio-pi sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 28 11:52:09 volumio-pi sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:09 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "wizard"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "networkfs"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Starting Udev Watcher for removable devices Apr 28 11:52:09 volumio-pi sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/Music /mnt/NAS/Music Apr 28 11:52:09 volumio-pi sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 11:52:09 volumio-pi volumio[976]: info: Ignoring mount for partition: boot Apr 28 11:52:09 volumio-pi volumio[976]: info: Ignoring mount for partition: volumio Apr 28 11:52:09 volumio-pi volumio[976]: info: Ignoring mount for partition: volumio_data Apr 28 11:52:09 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "volumio_command_line_client"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Plugin upnp is not enabled Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "my_music"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "mpd"... Apr 28 11:52:09 volumio-pi kernel: Key type cifs.spnego registered Apr 28 11:52:09 volumio-pi kernel: Key type cifs.idmap registered Apr 28 11:52:09 volumio-pi 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. Apr 28 11:52:09 volumio-pi kernel: CIFS: Attempting to mount \\192.168.1.5\Music Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "upnp_browser"... Apr 28 11:52:09 volumio-pi sudo[1020]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "alarm-clock"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "airplay_emulation"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Starting Shairport Sync Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "last_100"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "webradio"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "i2s_dacs"... Apr 28 11:52:09 volumio-pi volumio[976]: info: I2S DAC not set, start Auto-detection Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "volumiodiscovery"... Apr 28 11:52:09 volumio-pi volumio[976]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 11:52:09 volumio-pi node[976]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 11:52:09 volumio-pi node[976]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 11:52:09 volumio-pi volumio[976]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 11:52:09 volumio-pi volumio[976]: *** WARNING *** For more information see Apr 28 11:52:09 volumio-pi volumio[976]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 11:52:09 volumio-pi volumio[976]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 11:52:09 volumio-pi volumio[976]: *** WARNING *** For more information see Apr 28 11:52:09 volumio-pi node[976]: *** WARNING *** For more information see Apr 28 11:52:09 volumio-pi node[976]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 11:52:09 volumio-pi node[976]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 11:52:09 volumio-pi node[976]: *** WARNING *** For more information see Apr 28 11:52:09 volumio-pi volumio[976]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 11:52:09 volumio-pi volumio[976]: info: Discovery: Started advertising with name: Volumio-pi Apr 28 11:52:09 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "autostart"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Applying required configuration parameters for plugin autostart Apr 28 11:52:09 volumio-pi volumio[976]: info: AutoStart - onVolumioStart - read config.json Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "outputs"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "albumart"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Plugin example_plugin is not enabled Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "inputs"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "updater_comm"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Plugin mpdemulation is not enabled Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "rest_api"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "websocket"... Apr 28 11:52:09 volumio-pi volumio[976]: info: Loading plugin "radio_paradise"... Apr 28 11:52:09 volumio-pi volumio[976]: Forking 3 albumart workers Apr 28 11:52:10 volumio-pi volumio[976]: info: Applying required configuration parameters for plugin radio_paradise Apr 28 11:52:10 volumio-pi volumio[976]: info: [1714294330140] [RadioParadise] API delay: 5 Apr 28 11:52:10 volumio-pi volumio[976]: info: Loading plugin "backup_restore"... Apr 28 11:52:10 volumio-pi volumio[976]: Starting albumart workers Apr 28 11:52:10 volumio-pi volumio[976]: Starting albumart workers Apr 28 11:52:10 volumio-pi volumio[976]: info: Applying required configuration parameters for plugin backup_restore Apr 28 11:52:10 volumio-pi volumio[976]: info: Loading plugin "music_services_shield"... Apr 28 11:52:10 volumio-pi volumio[976]: Starting albumart workers Apr 28 11:52:10 volumio-pi volumio[976]: info: Applying required configuration parameters for plugin music_services_shield Apr 28 11:52:10 volumio-pi volumio[976]: info: Loading plugin "Systeminfo"... Apr 28 11:52:10 volumio-pi volumio[976]: info: Loading plugin "randomizer"... Apr 28 11:52:10 volumio-pi volumio[976]: info: Applying required configuration parameters for plugin randomizer Apr 28 11:52:10 volumio-pi volumio[976]: info: Loading i18n strings for locale en Apr 28 11:52:10 volumio-pi volumio[976]: Updating browse sources language Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::initPlayerControls Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 11:52:10 volumio-pi volumio[976]: Express server listening on port 3000 Apr 28 11:52:10 volumio-pi volumio[976]: [Metrics] WebUI: 2s 827.12ms Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreStateMachine::resetVolumioState Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreStateMachine::getcurrentVolume Apr 28 11:52:10 volumio-pi volumio[976]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 11:52:11 volumio-pi volumio[976]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.52 UA: okhttp/4.9.2 Total Clients: 1 Apr 28 11:52:11 volumio-pi volumio[976]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.52 UA: okhttp/4.9.2 Total Clients: 2 Apr 28 11:52:11 volumio-pi volumio[976]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.52 UA: okhttp/4.9.2 Total Clients: 3 Apr 28 11:52:11 volumio-pi volumio[976]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.52 UA: okhttp/4.9.2 Total Clients: 4 Apr 28 11:52:11 volumio-pi volumio[976]: info: Reloading queue from file Apr 28 11:52:11 volumio-pi volumio[976]: info: VolumeController:: Volume=61 Mute =false Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::pushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioPushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::updateTrackBlock Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrackBlock Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 11:52:11 volumio-pi volumio[976]: info: Setting Device type: Raspberry PI Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::setRepeat null single undefined Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::pushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioPushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::setRandom false Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::pushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioPushState Apr 28 11:52:11 volumio-pi volumio[976]: info: VolumeController:: Volume=61 Mute =false Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreStateMachine::pushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioPushState Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetState Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:11 volumio-pi volumio[976]: info: Discovery: adding aff3567f-7220-4772-87c0-e0cd7e96cb66 Apr 28 11:52:11 volumio-pi volumio[976]: info: Discovery: Found device Volumio-pi Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetState Apr 28 11:52:11 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 28 11:52:11 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 28 11:52:11 volumio-pi nmbd[748]: [2024/04/28 11:52:11.573574, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 11:52:11 volumio-pi nmbd[748]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 28 11:52:11 volumio-pi systemd[1]: Started Samba NMB Daemon. Apr 28 11:52:11 volumio-pi systemd[1]: Starting Samba Winbind Daemon... Apr 28 11:52:11 volumio-pi winbindd[1114]: [2024/04/28 11:52:11.716177, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 28 11:52:11 volumio-pi winbindd[1114]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 28 11:52:11 volumio-pi winbindd[1114]: [2024/04/28 11:52:11.925208, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 11:52:11 volumio-pi winbindd[1114]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 28 11:52:11 volumio-pi systemd[1]: Started Samba Winbind Daemon. Apr 28 11:52:11 volumio-pi systemd[1]: Starting Samba SMB Daemon... Apr 28 11:52:12 volumio-pi systemd[1]: Started Samba SMB Daemon. Apr 28 11:52:12 volumio-pi systemd[1]: Reached target Multi-User System. Apr 28 11:52:12 volumio-pi systemd[1]: Reached target Graphical Interface. Apr 28 11:52:12 volumio-pi smbd[1119]: [2024/04/28 11:52:12.626453, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 11:52:12 volumio-pi smbd[1119]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 28 11:52:12 volumio-pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 28 11:52:12 volumio-pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 28 11:52:12 volumio-pi systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 28 11:52:12 volumio-pi systemd[1]: Startup finished in 6.668s (kernel) + 24.894s (userspace) = 31.562s. Apr 28 11:52:13 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:52:13] [connect] Successful connection Apr 28 11:52:13 volumio-pi volumio-remote-updater[616]: [2024-04-28 11:52:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714294333 101 Apr 28 11:52:13 volumio-pi volumio[976]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Apr 28 11:52:13 volumio-pi sudo[1012]: pam_unix(sudo:session): session closed for user root Apr 28 11:52:13 volumio-pi volumio[976]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.52 UA: okhttp/4.9.2 Total Clients: 6 Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 28 11:52:13 volumio-pi volumio[976]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 28 11:52:13 volumio-pi volumio[976]: info: Received Get System Version Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 11:52:13 volumio-pi volumio[976]: info: Received Get System Info Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 11:52:13 volumio-pi volumio[976]: info: Discovery: Getting this device information Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetState Apr 28 11:52:13 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetVisibleSources Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetState Apr 28 11:52:13 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 28 11:52:13 volumio-pi volumio[976]: info: Received Get System Info Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 28 11:52:13 volumio-pi volumio[976]: info: Discovery: Getting this device information Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetState Apr 28 11:52:13 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 11:52:13 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetState Apr 28 11:52:13 volumio-pi volumio[976]: info: CorePlayQueue::getTrack 0 Apr 28 11:52:13 volumio-pi volumio[976]: info: Listing playlists Apr 28 11:52:19 volumio-pi systemd[1]: systemd-fsckd.service: Succeeded. Apr 28 11:52:20 volumio-pi sh[535]: timed out Apr 28 11:52:20 volumio-pi dhcpcd[592]: timed out Apr 28 11:52:20 volumio-pi sh[535]: dhcpcd exited Apr 28 11:52:20 volumio-pi dhcpcd[592]: dhcpcd exited Apr 28 11:52:20 volumio-pi sh[535]: ifup: failed to bring up eth0 Apr 28 11:52:20 volumio-pi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 28 11:52:20 volumio-pi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 28 11:52:21 volumio-pi systemd[1]: systemd-hostnamed.service: Succeeded. Apr 28 11:52:25 volumio-pi volumio[976]: info: CoreCommandRouter::volumioGetQueue Apr 28 11:52:25 volumio-pi volumio[976]: info: CoreStateMachine::getQueue Apr 28 11:52:25 volumio-pi volumio[976]: info: CorePlayQueue::getQueue Apr 28 11:52:27 volumio-pi volumio[976]: info: Preload queue cleared Apr 28 11:52:27 volumio-pi volumio[976]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 28 11:52:27 volumio-pi volumio[976]: info: CoreStateMachine::ClearQueue Apr 28 11:52:27 volumio-pi volumio[976]: info: CoreStateMachine::stop Apr 28 11:52:27 volumio-pi volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 11:52:27 volumio-pi volumio[976]: info: CorePlayQueue::clearPlayQueue Apr 28 11:52:27 volumio-pi volumio[976]: info: CorePlayQueue::saveQueue Apr 28 11:52:27 volumio-pi volumio[976]: info: CoreCommandRouter::volumioPushQueue Apr 28 11:52:27 volumio-pi volumio[976]: info: CoreStateMachine::addQueueItems Apr 28 11:52:27 volumio-pi volumio[976]: info: CorePlayQueue::addQueueItems Apr 28 11:52:27 volumio-pi volumio[976]: info: Preload queue cleared Apr 28 11:52:27 volumio-pi volumio[976]: info: Adding Item to queue: webrp/0 Apr 28 11:52:27 volumio-pi volumio[976]: info: Exploding uri webrp/0 in service radio_paradise Apr 28 11:52:27 volumio-pi volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 11:52:27 volumio-pi volumio[976]: TypeError: Cannot read property 'rparadise' of undefined Apr 28 11:52:27 volumio-pi volumio[976]: at ControllerRadioParadise.explodeUri (/data/plugins/music_service/radio_paradise/index.js:354:45) Apr 28 11:52:27 volumio-pi volumio[976]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 28 11:52:27 volumio-pi volumio[976]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 28 11:52:27 volumio-pi volumio[976]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 28 11:52:27 volumio-pi volumio[976]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 28 11:52:27 volumio-pi volumio[976]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 28 11:52:27 volumio-pi volumio[976]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Apr 28 11:52:27 volumio-pi volumio[976]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 28 11:52:27 volumio-pi volumio[976]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 28 11:52:27 volumio-pi volumio[976]: at Socket.emit (events.js:315:20) Apr 28 11:52:27 volumio-pi volumio[976]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 28 11:52:27 volumio-pi volumio[976]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 28 11:52:27 volumio-pi volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 11:52:27 volumio-pi sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 11:51 Apr 28 11:52:27 volumio-pi sudo[1140]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"