-- Logs begin at Wed 2024-10-30 16:00:05 CST, end at Wed 2024-10-30 16:00:51 CST. -- Oct 30 16:00:05 volumio kernel: Booting Linux on physical CPU 0x0000000000 [0x412fd050] Oct 30 16:00:05 volumio kernel: Linux version 5.10.198-rk356x (zzm24@zzm24) (aarch64-none-linux-gnu-gcc (GNU Toolchain for the Arm Architecture 11.2-2022.02 (arm-11.14)) 11.2.1 20220111, GNU ld (GNU Toolchain for the Arm Architecture 11.2-2022.02 (arm-11.14)) 2.37.20220122) #25 SMP Wed Oct 30 06:47:21 UTC 2024 Oct 30 16:00:05 volumio kernel: Machine model: Rockchip RK3566 Orange Pi CM4 Board Oct 30 16:00:05 volumio kernel: OF: fdt: Reserved memory: failed to reserve memory for node 'drm-logo@00000000': base 0x0000000000000000, size 0 MiB Oct 30 16:00:05 volumio kernel: OF: fdt: Reserved memory: failed to reserve memory for node 'drm-cubic-lut@00000000': base 0x0000000000000000, size 0 MiB Oct 30 16:00:05 volumio kernel: Zone ranges: Oct 30 16:00:05 volumio kernel: DMA [mem 0x0000000000200000-0x00000000ffffffff] Oct 30 16:00:05 volumio kernel: DMA32 empty Oct 30 16:00:05 volumio kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Oct 30 16:00:05 volumio kernel: Movable zone start for each node Oct 30 16:00:05 volumio kernel: Early memory node ranges Oct 30 16:00:05 volumio kernel: node 0: [mem 0x0000000000200000-0x00000000efffffff] Oct 30 16:00:05 volumio kernel: node 0: [mem 0x00000001f0000000-0x00000001ffffffff] Oct 30 16:00:05 volumio kernel: Initmem setup node 0 [mem 0x0000000000200000-0x00000001ffffffff] Oct 30 16:00:05 volumio kernel: On node 0 totalpages: 1048064 Oct 30 16:00:05 volumio kernel: DMA zone: 15352 pages used for memmap Oct 30 16:00:05 volumio kernel: DMA zone: 0 pages reserved Oct 30 16:00:05 volumio kernel: DMA zone: 982528 pages, LIFO batch:63 Oct 30 16:00:05 volumio kernel: Normal zone: 1024 pages used for memmap Oct 30 16:00:05 volumio kernel: Normal zone: 65536 pages, LIFO batch:15 Oct 30 16:00:05 volumio kernel: cma: Reserved 16 MiB at 0x00000000ef000000 Oct 30 16:00:05 volumio kernel: psci: probing for conduit method from DT. Oct 30 16:00:05 volumio kernel: psci: PSCIv1.1 detected in firmware. Oct 30 16:00:05 volumio kernel: psci: Using standard PSCI v0.2 function IDs Oct 30 16:00:05 volumio kernel: psci: MIGRATE_INFO_TYPE not supported. Oct 30 16:00:05 volumio kernel: psci: SMC Calling Convention v1.2 Oct 30 16:00:05 volumio kernel: percpu: Embedded 31 pages/cpu s88360 r8192 d30424 u126976 Oct 30 16:00:05 volumio kernel: pcpu-alloc: s88360 r8192 d30424 u126976 alloc=31*4096 Oct 30 16:00:05 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 30 16:00:05 volumio kernel: Detected VIPT I-cache on CPU0 Oct 30 16:00:05 volumio kernel: CPU features: detected: GIC system register CPU interface Oct 30 16:00:05 volumio kernel: CPU features: detected: Virtualization Host Extensions Oct 30 16:00:05 volumio kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Oct 30 16:00:05 volumio kernel: alternatives: patching kernel code Oct 30 16:00:05 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031688 Oct 30 16:00:05 volumio kernel: Kernel command line: root=/dev/mmcblk0p2 rootwait rootfstype=ext4 console=ttyS2,1500000 net.ifnames=0 max_loop=128 cgroup_enable=cpuset cgroup_memory=1 cgroup_enable=memory swapaccount=1 Oct 30 16:00:05 volumio kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 30 16:00:05 volumio kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 30 16:00:05 volumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 30 16:00:05 volumio kernel: software IO TLB: mapped [mem 0x00000000eb000000-0x00000000ef000000] (64MB) Oct 30 16:00:05 volumio kernel: Memory: 3994344K/4192256K available (18622K kernel code, 3380K rwdata, 5796K rodata, 5696K init, 679K bss, 181528K reserved, 16384K cma-reserved) Oct 30 16:00:05 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 30 16:00:05 volumio kernel: ftrace: allocating 55116 entries in 216 pages Oct 30 16:00:05 volumio kernel: ftrace: allocated 216 pages with 4 groups Oct 30 16:00:05 volumio kernel: rcu: Hierarchical RCU implementation. Oct 30 16:00:05 volumio kernel: rcu: RCU event tracing is enabled. Oct 30 16:00:05 volumio kernel: rcu: RCU dyntick-idle grace-period acceleration is enabled. Oct 30 16:00:05 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Oct 30 16:00:05 volumio kernel: Rude variant of Tasks RCU enabled. Oct 30 16:00:05 volumio kernel: Tracing variant of Tasks RCU enabled. Oct 30 16:00:05 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 30 jiffies. Oct 30 16:00:05 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 30 16:00:05 volumio kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 30 16:00:05 volumio kernel: GICv3: GIC: Using split EOI/Deactivate mode Oct 30 16:00:05 volumio kernel: GICv3: 320 SPIs implemented Oct 30 16:00:05 volumio kernel: GICv3: 0 Extended SPIs implemented Oct 30 16:00:05 volumio kernel: GICv3: Distributor has no Range Selector support Oct 30 16:00:05 volumio kernel: GICv3: 16 PPIs implemented Oct 30 16:00:05 volumio kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000fd460000 Oct 30 16:00:05 volumio kernel: ITS [mem 0xfd440000-0xfd45ffff] Oct 30 16:00:05 volumio kernel: ITS@0x00000000fd440000: allocated 8192 Devices @2810000 (indirect, esz 8, psz 64K, shr 0) Oct 30 16:00:05 volumio kernel: ITS@0x00000000fd440000: allocated 32768 Interrupt Collections @2820000 (flat, esz 2, psz 64K, shr 0) Oct 30 16:00:05 volumio kernel: ITS: using cache flushing for cmd queue Oct 30 16:00:05 volumio kernel: GICv3: using LPI property table @0x0000000002830000 Oct 30 16:00:05 volumio kernel: GIC: using cache flushing for LPI property table Oct 30 16:00:05 volumio kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000002840000 Oct 30 16:00:05 volumio kernel: rcu: Offload RCU callbacks from CPUs: (none). Oct 30 16:00:05 volumio kernel: arch_timer: cp15 timer(s) running at 24.00MHz (phys). Oct 30 16:00:05 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Oct 30 16:00:05 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Oct 30 16:00:05 volumio kernel: Console: colour dummy device 80x25 Oct 30 16:00:05 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=80000) Oct 30 16:00:05 volumio kernel: pid_max: default: 32768 minimum: 301 Oct 30 16:00:05 volumio kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 16:00:05 volumio kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 16:00:05 volumio kernel: rcu: Hierarchical SRCU implementation. Oct 30 16:00:05 volumio kernel: Platform MSI: interrupt-controller@fd440000 domain created Oct 30 16:00:05 volumio kernel: PCI/MSI: /interrupt-controller@fd400000/interrupt-controller@fd440000 domain created Oct 30 16:00:05 volumio kernel: smp: Bringing up secondary CPUs ... Oct 30 16:00:05 volumio kernel: Detected VIPT I-cache on CPU1 Oct 30 16:00:05 volumio kernel: GICv3: CPU1: found redistributor 100 region 0:0x00000000fd480000 Oct 30 16:00:05 volumio kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000002850000 Oct 30 16:00:05 volumio kernel: CPU1: Booted secondary processor 0x0000000100 [0x412fd050] Oct 30 16:00:05 volumio kernel: Detected VIPT I-cache on CPU2 Oct 30 16:00:05 volumio kernel: GICv3: CPU2: found redistributor 200 region 0:0x00000000fd4a0000 Oct 30 16:00:05 volumio kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000002860000 Oct 30 16:00:05 volumio kernel: CPU2: Booted secondary processor 0x0000000200 [0x412fd050] Oct 30 16:00:05 volumio kernel: Detected VIPT I-cache on CPU3 Oct 30 16:00:05 volumio kernel: GICv3: CPU3: found redistributor 300 region 0:0x00000000fd4c0000 Oct 30 16:00:05 volumio kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000002870000 Oct 30 16:00:05 volumio kernel: CPU3: Booted secondary processor 0x0000000300 [0x412fd050] Oct 30 16:00:05 volumio kernel: smp: Brought up 1 node, 4 CPUs Oct 30 16:00:05 volumio kernel: SMP: Total of 4 processors activated. Oct 30 16:00:05 volumio kernel: CPU features: detected: Privileged Access Never Oct 30 16:00:05 volumio kernel: CPU features: detected: LSE atomic instructions Oct 30 16:00:05 volumio kernel: CPU features: detected: User Access Override Oct 30 16:00:05 volumio kernel: CPU features: detected: 32-bit EL0 Support Oct 30 16:00:05 volumio kernel: CPU features: detected: Common not Private translations Oct 30 16:00:05 volumio kernel: CPU features: detected: RAS Extension Support Oct 30 16:00:05 volumio kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 30 16:00:05 volumio kernel: CPU features: detected: CRC32 instructions Oct 30 16:00:05 volumio kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 30 16:00:05 volumio kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 30 16:00:05 volumio kernel: CPU: All CPU(s) started at EL2 Oct 30 16:00:05 volumio kernel: devtmpfs: initialized Oct 30 16:00:05 volumio kernel: Registered cp15_barrier emulation handler Oct 30 16:00:05 volumio kernel: Registered setend emulation handler Oct 30 16:00:05 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns Oct 30 16:00:05 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 30 16:00:05 volumio kernel: pinctrl core: initialized pinctrl subsystem Oct 30 16:00:05 volumio kernel: regulator-dummy: no parameters, enabled Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 16 Oct 30 16:00:05 volumio kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 30 16:00:05 volumio kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 30 16:00:05 volumio kernel: Registered FIQ tty driver Oct 30 16:00:05 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Oct 30 16:00:05 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 30 16:00:05 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Oct 30 16:00:05 volumio kernel: thermal_sys: Registered thermal governor 'power_allocator' Oct 30 16:00:05 volumio kernel: thermal thermal_zone1: power_allocator: sustainable_power will be estimated Oct 30 16:00:05 volumio kernel: cpuidle: using governor menu Oct 30 16:00:05 volumio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 30 16:00:05 volumio kernel: ASID allocator initialised with 65536 entries Oct 30 16:00:05 volumio kernel: rockchip-gpio fdd60000.gpio0: probed /pinctrl/gpio0@fdd60000 Oct 30 16:00:05 volumio kernel: rockchip-gpio fe740000.gpio1: probed /pinctrl/gpio1@fe740000 Oct 30 16:00:05 volumio kernel: rockchip-gpio fe750000.gpio2: probed /pinctrl/gpio2@fe750000 Oct 30 16:00:05 volumio kernel: rockchip-gpio fe760000.gpio3: probed /pinctrl/gpio3@fe760000 Oct 30 16:00:05 volumio kernel: rockchip-gpio fe770000.gpio4: probed /pinctrl/gpio4@fe770000 Oct 30 16:00:05 volumio kernel: rockchip-pinctrl pinctrl: probed pinctrl Oct 30 16:00:05 volumio kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 30 16:00:05 volumio kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 30 16:00:05 volumio kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 30 16:00:05 volumio kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 30 16:00:05 volumio kernel: fiq_debugger fiq_debugger.0: IRQ fiq not found Oct 30 16:00:05 volumio kernel: fiq_debugger fiq_debugger.0: IRQ wakeup not found Oct 30 16:00:05 volumio kernel: fiq_debugger_probe: could not install nmi irq handler Oct 30 16:00:05 volumio kernel: printk: console [ttyFIQ0] enabled Oct 30 16:00:05 volumio kernel: Registered fiq debugger ttyFIQ0 Oct 30 16:00:05 volumio kernel: vcc12v_dcin: 12000 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc12v-dcin-regulator: vcc12v_dcin supplying 12000000uV Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc-5v-regulator: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vcc_5v: supplied by vcc12v_dcin Oct 30 16:00:05 volumio kernel: vcc12v_dcin: could not add device link regulator.2: -ENOENT Oct 30 16:00:05 volumio kernel: vcc_5v: 5000 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc-5v-regulator: vcc_5v supplying 5000000uV Oct 30 16:00:05 volumio kernel: vbus: 5000 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vbus: vbus supplying 5000000uV Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc3v3-pcie-regulator: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vcc3v3_pcie: supplied by vcc12v_dcin Oct 30 16:00:05 volumio kernel: vcc12v_dcin: could not add device link regulator.4: -ENOENT Oct 30 16:00:05 volumio kernel: vcc3v3_pcie: 3300 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc3v3-pcie-regulator: vcc3v3_pcie supplying 3300000uV Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc3v3-sys: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vcc3v3_sys: supplied by vbus Oct 30 16:00:05 volumio kernel: vbus: could not add device link regulator.5: -ENOENT Oct 30 16:00:05 volumio kernel: vcc3v3_sys: 3300 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc3v3-sys: vcc3v3_sys supplying 3300000uV Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc5v0-sys: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vcc5v0_sys: supplied by vbus Oct 30 16:00:05 volumio kernel: vbus: could not add device link regulator.6: -ENOENT Oct 30 16:00:05 volumio kernel: vcc5v0_sys: 5000 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc5v0-sys: vcc5v0_sys supplying 5000000uV Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc5v0-usb: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vcc5v0_usb: supplied by vbus Oct 30 16:00:05 volumio kernel: vbus: could not add device link regulator.7: -ENOENT Oct 30 16:00:05 volumio kernel: vcc5v0_usb: 5000 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc5v0-usb: vcc5v0_usb supplying 5000000uV Oct 30 16:00:05 volumio kernel: vcc_camera_ov5647: 3300 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc-camera-ov5647: vcc_camera_ov5647 supplying 3300000uV Oct 30 16:00:05 volumio kernel: vcc_sd: 3300 mV, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc-sd: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vcc_sd: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc-sd: vcc_sd supplying 3300000uV Oct 30 16:00:05 volumio kernel: vcc5v0_otg: no parameters, enabled Oct 30 16:00:05 volumio kernel: reg-fixed-voltage vcc5v0-otg-regulator: vcc5v0_otg supplying 0uV Oct 30 16:00:05 volumio kernel: iommu: Default domain type: Translated Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up vpu-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up vpu-supply property in node /power-management@fdd90000/power-controller failed Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up rga-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up rga-supply property in node /power-management@fdd90000/power-controller failed Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up rkvdec-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up rkvdec-supply property in node /power-management@fdd90000/power-controller failed Oct 30 16:00:05 volumio kernel: SCSI subsystem initialized Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver usbfs Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver hub Oct 30 16:00:05 volumio kernel: usbcore: registered new device driver usb Oct 30 16:00:05 volumio kernel: mc: Linux media interface: v0.10 Oct 30 16:00:05 volumio kernel: videodev: Linux video capture interface: v2.00 Oct 30 16:00:05 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Oct 30 16:00:05 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 30 16:00:05 volumio kernel: PTP clock support registered Oct 30 16:00:05 volumio kernel: arm-scmi firmware:scmi: SCMI Notifications - Core Enabled. Oct 30 16:00:05 volumio kernel: arm-scmi firmware:scmi: SCMI Protocol v2.0 'rockchip:' Firmware version 0x0 Oct 30 16:00:05 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Oct 30 16:00:05 volumio kernel: Bluetooth: Core ver 2.22 Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 31 Oct 30 16:00:05 volumio kernel: Bluetooth: HCI device and connection manager initialized Oct 30 16:00:05 volumio kernel: Bluetooth: HCI socket layer initialized Oct 30 16:00:05 volumio kernel: Bluetooth: L2CAP socket layer initialized Oct 30 16:00:05 volumio kernel: Bluetooth: SCO socket layer initialized Oct 30 16:00:05 volumio kernel: rockchip-cpuinfo cpuinfo: SoC : 35662000 Oct 30 16:00:05 volumio kernel: rockchip-cpuinfo cpuinfo: Serial : 98269e8a544cf265 Oct 30 16:00:05 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 2 Oct 30 16:00:05 volumio kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 30 16:00:05 volumio kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 4, 81920 bytes, linear) Oct 30 16:00:05 volumio kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 30 16:00:05 volumio kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 30 16:00:05 volumio kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 30 16:00:05 volumio kernel: UDP hash table entries: 2048 (order: 5, 196608 bytes, linear) Oct 30 16:00:05 volumio kernel: UDP-Lite hash table entries: 2048 (order: 5, 196608 bytes, linear) Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 1 Oct 30 16:00:05 volumio kernel: RPC: Registered named UNIX socket transport module. Oct 30 16:00:05 volumio kernel: RPC: Registered udp transport module. Oct 30 16:00:05 volumio kernel: RPC: Registered tcp transport module. Oct 30 16:00:05 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 30 16:00:05 volumio kernel: PCI: CLS 0 bytes, default 64 Oct 30 16:00:05 volumio kernel: rockchip-thermal fe710000.tsadc: tsadc is probed successfully! Oct 30 16:00:05 volumio kernel: hw perfevents: enabled with armv8_cortex_a55 PMU driver, 7 counters available Oct 30 16:00:05 volumio kernel: Initialise system trusted keyrings Oct 30 16:00:05 volumio kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Oct 30 16:00:05 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 30 16:00:05 volumio kernel: NFS: Registering the id_resolver key type Oct 30 16:00:05 volumio kernel: Key type id_resolver registered Oct 30 16:00:05 volumio kernel: Key type id_legacy registered Oct 30 16:00:05 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 30 16:00:05 volumio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Oct 30 16:00:05 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Oct 30 16:00:05 volumio kernel: Key type cifs.idmap registered Oct 30 16:00:05 volumio kernel: ntfs: driver 2.1.32 [Flags: R/W DEBUG]. Oct 30 16:00:05 volumio kernel: jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc. Oct 30 16:00:05 volumio kernel: fuse: init (API version 7.32) Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 38 Oct 30 16:00:05 volumio kernel: Key type asymmetric registered Oct 30 16:00:05 volumio kernel: Asymmetric key parser 'x509' registered Oct 30 16:00:05 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) Oct 30 16:00:05 volumio kernel: io scheduler mq-deadline registered Oct 30 16:00:05 volumio kernel: io scheduler kyber registered Oct 30 16:00:05 volumio kernel: phy phy-fe8a0000.usb2-phy.0: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: vbus: could not add device link phy-fe8a0000.usb2-phy.0: -ENOENT Oct 30 16:00:05 volumio kernel: phy phy-fe8a0000.usb2-phy.1: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe8a0000.usb2-phy.1: Looking up phy-supply property in node /usb2-phy@fe8a0000/otg-port failed Oct 30 16:00:05 volumio kernel: phy phy-fe8a0000.usb2-phy.1: Looking up vbus-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe8a0000.usb2-phy.1: Looking up vbus-supply property in node /usb2-phy@fe8a0000/otg-port failed Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.2: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.2: Looking up phy-supply property in node /usb2-phy@fe8b0000/host-port failed Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.3: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.3: Looking up phy-supply property in node /usb2-phy@fe8b0000/otg-port failed Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.3: Looking up vbus-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.3: Looking up vbus-supply property in node /usb2-phy@fe8b0000/otg-port failed Oct 30 16:00:05 volumio kernel: phy phy-fe830000.phy.4: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe830000.phy.4: Looking up phy-supply property in node /phy@fe830000 failed Oct 30 16:00:05 volumio kernel: phy phy-fe840000.phy.5: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: phy phy-fe840000.phy.5: Looking up phy-supply property in node /phy@fe840000 failed Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: invalid prsnt-gpios property in node Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: Looking up vpcie3v3-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: IRQ msi not found Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: use outband MSI support Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: Missing *config* reg space Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: host bridge /pcie@fe260000 ranges: Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: err 0x00f4000000..0x00f40fffff -> 0x00f4000000 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: IO 0x00f4100000..0x00f41fffff -> 0x00f4100000 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: MEM 0x00f4200000..0x00f5ffffff -> 0x00f4200000 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: MEM 0x0300000000..0x033fffffff -> 0x0300000000 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: Missing *config* reg space Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: invalid resource Oct 30 16:00:05 volumio kernel: iep: Module initialized. Oct 30 16:00:05 volumio kernel: mpp_service mpp-srv: unknown mpp version for missing VCS info Oct 30 16:00:05 volumio kernel: mpp_service mpp-srv: probe start Oct 30 16:00:05 volumio kernel: mpp_vdpu2 fdea0400.vdpu: Adding to iommu group 0 Oct 30 16:00:05 volumio kernel: mpp_vdpu2 fdea0400.vdpu: probe device Oct 30 16:00:05 volumio kernel: mpp_vdpu2 fdea0400.vdpu: probing finish Oct 30 16:00:05 volumio kernel: mpp_vepu2 fdee0000.vepu: Adding to iommu group 2 Oct 30 16:00:05 volumio kernel: mpp_vepu2 fdee0000.vepu: probing start Oct 30 16:00:05 volumio kernel: mpp_vepu2 fdee0000.vepu: probing finish Oct 30 16:00:05 volumio kernel: mpp-iep2 fdef0000.iep: Adding to iommu group 3 Oct 30 16:00:05 volumio kernel: mpp-iep2 fdef0000.iep: probe device Oct 30 16:00:05 volumio kernel: mpp-iep2 fdef0000.iep: allocate roi buffer failed Oct 30 16:00:05 volumio kernel: mpp-iep2 fdef0000.iep: probing finish Oct 30 16:00:05 volumio kernel: mpp_jpgdec fded0000.jpegd: Adding to iommu group 1 Oct 30 16:00:05 volumio kernel: mpp_jpgdec fded0000.jpegd: probe device Oct 30 16:00:05 volumio kernel: mpp_jpgdec fded0000.jpegd: probing finish Oct 30 16:00:05 volumio kernel: mpp_service mpp-srv: probe success Oct 30 16:00:05 volumio kernel: dma-pl330 fe530000.dmac: Loaded driver for PL330 DMAC-241330 Oct 30 16:00:05 volumio kernel: dma-pl330 fe530000.dmac: DBUFF-128x8bytes Num_Chans-8 Num_Peri-32 Num_Events-16 Oct 30 16:00:05 volumio kernel: dma-pl330 fe550000.dmac: Loaded driver for PL330 DMAC-241330 Oct 30 16:00:05 volumio kernel: dma-pl330 fe550000.dmac: DBUFF-128x8bytes Num_Chans-8 Num_Peri-32 Num_Events-16 Oct 30 16:00:05 volumio kernel: rockchip-pvtm fde00000.pvtm: pvtm@0 probed Oct 30 16:00:05 volumio kernel: rockchip-pvtm fde80000.pvtm: pvtm@1 probed Oct 30 16:00:05 volumio kernel: rockchip-pvtm fde90000.pvtm: pvtm@2 probed Oct 30 16:00:05 volumio kernel: rockchip-system-monitor rockchip-system-monitor: system monitor probe Oct 30 16:00:05 volumio kernel: arm-scmi firmware:scmi: Failed. SCMI protocol 22 not active. Oct 30 16:00:05 volumio kernel: Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled Oct 30 16:00:05 volumio kernel: fe650000.serial: ttyS1 at MMIO 0xfe650000 (irq = 56, base_baud = 1500000) is a 16550A Oct 30 16:00:05 volumio kernel: random: crng init done Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: Adding to iommu group 5 Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: [drm:vop2_bind] vp0 assign plane mask: 0x3f, primary plane phy id: 4 Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: [drm:vop2_bind] vp1 assign plane mask: 0x0, primary plane phy id: -1 Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: [drm:vop2_bind] vp2 assign plane mask: 0x0, primary plane phy id: -1 Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: [drm:vop2_bind] Cluster0-win0 as cursor plane for vp0 Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: [drm:vop2_bind] VP1 plane_mask is zero, so ignore register crtc Oct 30 16:00:05 volumio kernel: rockchip-vop2 fe040000.vop: [drm:vop2_bind] VP2 plane_mask is zero, so ignore register crtc Oct 30 16:00:05 volumio kernel: [drm] failed to init overlay plane Cluster0-win1 Oct 30 16:00:05 volumio kernel: rockchip-drm display-subsystem: bound fe040000.vop (ops 0xffffffc00931ddf0) Oct 30 16:00:05 volumio kernel: rockchip-drm display-subsystem: failed to parse loader memory Oct 30 16:00:05 volumio kernel: rockchip-drm display-subsystem: [drm] Cannot find any crtc or sizes Oct 30 16:00:05 volumio kernel: [drm] Initialized rockchip 3.0.0 20140818 for display-subsystem on minor 0 Oct 30 16:00:05 volumio kernel: cacheinfo: Unable to detect cache hierarchy for CPU 0 Oct 30 16:00:05 volumio kernel: brd: module loaded Oct 30 16:00:05 volumio kernel: loop: module loaded Oct 30 16:00:05 volumio kernel: zram: Added device: zram0 Oct 30 16:00:05 volumio kernel: lkdtm: No crash points registered, enable through debugfs Oct 30 16:00:05 volumio kernel: system_heap: orders[0] = 6 Oct 30 16:00:05 volumio kernel: system_heap: orders[1] = 4 Oct 30 16:00:05 volumio kernel: system_heap: orders[2] = 0 Oct 30 16:00:05 volumio kernel: spi-nor spi4.0: unrecognized JEDEC id bytes: ff ff ff ff ff ff Oct 30 16:00:05 volumio kernel: spi-nor: probe of spi4.0 failed with error -2 Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: IRQ eth_lpi not found Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: Looking up phy-supply from device tree Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: Looking up phy-supply property in node /ethernet@fe010000 failed Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: supply phy not found, using dummy regulator Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: clock input or output? (input). Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: TX delay(0x30). Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: RX delay(0x10). Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: integrated PHY? (no). Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: clock input from PHY Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: init for RGMII Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: User ID: 0x30, Synopsys ID: 0x51 Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: DWMAC4/5 Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: DMA HW capability register supported Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: RX Checksum Offload Engine supported Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: TX Checksum insertion supported Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: Wake-Up On Lan supported Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: TSO supported Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: Enable RX Mitigation via HW Watchdog Timer Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: rk_get_eth_addr: rk_vendor_read eth mac address failed (-1) Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: rk_get_eth_addr: generate random eth mac address: 12:45:36:a1:5d:57 Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: rk_get_eth_addr: rk_vendor_write eth mac address failed (-1) Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: rk_get_eth_addr: id: 1 rk_vendor_read eth mac address failed (-1) Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: rk_get_eth_addr: mac address: 12:45:36:a1:5d:57 Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: device MAC address 12:45:36:a1:5d:57 Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: TSO feature enabled Oct 30 16:00:05 volumio kernel: rk_gmac-dwmac fe010000.ethernet: Using 32 bits DMA width Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:05 volumio kernel: SLIP: version 0.8.4-NET3.019-NEWTTY (dynamic channels, max=256). Oct 30 16:00:05 volumio kernel: CSLIP: code copyright 1989 Regents of the University of California. Oct 30 16:00:05 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Oct 30 16:00:05 volumio kernel: ehci-pci: EHCI PCI platform driver Oct 30 16:00:05 volumio kernel: ehci-platform: EHCI generic platform driver Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.3: illegal mode Oct 30 16:00:05 volumio kernel: ehci-platform fd800000.usb: EHCI Host Controller Oct 30 16:00:05 volumio kernel: ehci-platform fd800000.usb: new USB bus registered, assigned bus number 1 Oct 30 16:00:05 volumio kernel: ehci-platform fd800000.usb: irq 19, io mem 0xfd800000 Oct 30 16:00:05 volumio kernel: ehci-platform fd800000.usb: USB 2.0 started, EHCI 1.00 Oct 30 16:00:05 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb1: Product: EHCI Host Controller Oct 30 16:00:05 volumio kernel: usb usb1: Manufacturer: Linux 5.10.198-rk356x ehci_hcd Oct 30 16:00:05 volumio kernel: usb usb1: SerialNumber: fd800000.usb Oct 30 16:00:05 volumio kernel: hub 1-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 1-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: ehci-platform fd880000.usb: EHCI Host Controller Oct 30 16:00:05 volumio kernel: ehci-platform fd880000.usb: new USB bus registered, assigned bus number 2 Oct 30 16:00:05 volumio kernel: ehci-platform fd880000.usb: irq 21, io mem 0xfd880000 Oct 30 16:00:05 volumio kernel: ehci-platform fd880000.usb: USB 2.0 started, EHCI 1.00 Oct 30 16:00:05 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb2: Product: EHCI Host Controller Oct 30 16:00:05 volumio kernel: usb usb2: Manufacturer: Linux 5.10.198-rk356x ehci_hcd Oct 30 16:00:05 volumio kernel: usb usb2: SerialNumber: fd880000.usb Oct 30 16:00:05 volumio kernel: hub 2-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 2-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Oct 30 16:00:05 volumio kernel: ohci-platform: OHCI generic platform driver Oct 30 16:00:05 volumio kernel: phy phy-fe8b0000.usb2-phy.3: illegal mode Oct 30 16:00:05 volumio kernel: ohci-platform fd840000.usb: Generic Platform OHCI controller Oct 30 16:00:05 volumio kernel: ohci-platform fd840000.usb: new USB bus registered, assigned bus number 3 Oct 30 16:00:05 volumio kernel: ohci-platform fd840000.usb: irq 20, io mem 0xfd840000 Oct 30 16:00:05 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb3: Product: Generic Platform OHCI controller Oct 30 16:00:05 volumio kernel: usb usb3: Manufacturer: Linux 5.10.198-rk356x ohci_hcd Oct 30 16:00:05 volumio kernel: usb usb3: SerialNumber: fd840000.usb Oct 30 16:00:05 volumio kernel: hub 3-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 3-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: ohci-platform fd8c0000.usb: Generic Platform OHCI controller Oct 30 16:00:05 volumio kernel: ohci-platform fd8c0000.usb: new USB bus registered, assigned bus number 4 Oct 30 16:00:05 volumio kernel: ohci-platform fd8c0000.usb: irq 22, io mem 0xfd8c0000 Oct 30 16:00:05 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb4: Product: Generic Platform OHCI controller Oct 30 16:00:05 volumio kernel: usb usb4: Manufacturer: Linux 5.10.198-rk356x ohci_hcd Oct 30 16:00:05 volumio kernel: usb usb4: SerialNumber: fd8c0000.usb Oct 30 16:00:05 volumio kernel: hub 4-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 4-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 5 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x0000002022010010 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: irq 70, io mem 0xfcc00000 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 6 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed Oct 30 16:00:05 volumio kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb5: Product: xHCI Host Controller Oct 30 16:00:05 volumio kernel: usb usb5: Manufacturer: Linux 5.10.198-rk356x xhci-hcd Oct 30 16:00:05 volumio kernel: usb usb5: SerialNumber: xhci-hcd.0.auto Oct 30 16:00:05 volumio kernel: hub 5-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 5-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: usb usb6: We don't know the algorithms for LPM for this host, disabling LPM. Oct 30 16:00:05 volumio kernel: usb usb6: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb6: Product: xHCI Host Controller Oct 30 16:00:05 volumio kernel: usb usb6: Manufacturer: Linux 5.10.198-rk356x xhci-hcd Oct 30 16:00:05 volumio kernel: usb usb6: SerialNumber: xhci-hcd.0.auto Oct 30 16:00:05 volumio kernel: hub 6-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 6-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: xHCI Host Controller Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 7 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x0000800002010010 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: irq 71, io mem 0xfd000000 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: xHCI Host Controller Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 8 Oct 30 16:00:05 volumio kernel: xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed Oct 30 16:00:05 volumio kernel: usb usb7: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb7: Product: xHCI Host Controller Oct 30 16:00:05 volumio kernel: usb usb7: Manufacturer: Linux 5.10.198-rk356x xhci-hcd Oct 30 16:00:05 volumio kernel: usb usb7: SerialNumber: xhci-hcd.1.auto Oct 30 16:00:05 volumio kernel: hub 7-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 7-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: usb usb8: We don't know the algorithms for LPM for this host, disabling LPM. Oct 30 16:00:05 volumio kernel: usb usb8: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Oct 30 16:00:05 volumio kernel: usb usb8: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 16:00:05 volumio kernel: usb usb8: Product: xHCI Host Controller Oct 30 16:00:05 volumio kernel: usb usb8: Manufacturer: Linux 5.10.198-rk356x xhci-hcd Oct 30 16:00:05 volumio kernel: usb usb8: SerialNumber: xhci-hcd.1.auto Oct 30 16:00:05 volumio kernel: hub 8-0:1.0: USB hub found Oct 30 16:00:05 volumio kernel: hub 8-0:1.0: 1 port detected Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver cdc_acm Oct 30 16:00:05 volumio kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver cdc_wdm Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver uas Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver usb-storage Oct 30 16:00:05 volumio kernel: i2c /dev entries driver Oct 30 16:00:05 volumio kernel: fan53555-regulator 0-0040: FAN53555 Option[8] Rev[1] Detected! Oct 30 16:00:05 volumio kernel: vdd_cpu: override max_uV, 1390000 -> 1387500 Oct 30 16:00:05 volumio kernel: fan53555-regulator 0-0040: Looking up vin-supply from device tree Oct 30 16:00:05 volumio kernel: vdd_cpu: supplied by vcc5v0_sys Oct 30 16:00:05 volumio kernel: vcc5v0_sys: could not add device link regulator.11: -ENOENT Oct 30 16:00:05 volumio kernel: vdd_cpu: 712 <--> 1387 mV at 1000 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: chip id: 0x8090 Oct 30 16:00:05 volumio kernel: rk808 0-0020: No cache defaults, reading back from HW Oct 30 16:00:05 volumio kernel: rk808 0-0020: source: on=0x10, off=0x00 Oct 30 16:00:05 volumio kernel: rk808 0-0020: support dcdc3 fb mode:-22, 1 Oct 30 16:00:05 volumio kernel: rk808 0-0020: support pmic reset mode:0,0 Oct 30 16:00:05 volumio kernel: rk808-regulator rk808-regulator: there is no dvs0 gpio Oct 30 16:00:05 volumio kernel: rk808-regulator rk808-regulator: there is no dvs1 gpio Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc1-supply from device tree Oct 30 16:00:05 volumio kernel: vdd_logic: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.12: -ENOENT Oct 30 16:00:05 volumio kernel: vdd_logic: 500 <--> 1350 mV at 900 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc2-supply from device tree Oct 30 16:00:05 volumio kernel: vdd_gpu: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.13: -ENOENT Oct 30 16:00:05 volumio kernel: vdd_gpu: 500 <--> 1350 mV at 900 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc3-supply from device tree Oct 30 16:00:05 volumio kernel: vcc_ddr: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.14: -ENOENT Oct 30 16:00:05 volumio kernel: vcc_ddr: at 500 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc4-supply from device tree Oct 30 16:00:05 volumio kernel: vdd_npu: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.15: -ENOENT Oct 30 16:00:05 volumio kernel: vdd_npu: 500 <--> 1350 mV at 900 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc9-supply from device tree Oct 30 16:00:05 volumio kernel: vcc_1v8: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.16: -ENOENT Oct 30 16:00:05 volumio kernel: vcc_1v8: 1800 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc5-supply from device tree Oct 30 16:00:05 volumio kernel: vdda0v9_image: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.17: -ENOENT Oct 30 16:00:05 volumio kernel: vdda0v9_image: 900 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc5-supply from device tree Oct 30 16:00:05 volumio kernel: vdda_0v9: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.18: -ENOENT Oct 30 16:00:05 volumio kernel: vdda_0v9: 900 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc5-supply from device tree Oct 30 16:00:05 volumio kernel: vdda0v9_pmu: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.19: -ENOENT Oct 30 16:00:05 volumio kernel: vdda0v9_pmu: 900 mV, enabled Oct 30 16:00:05 volumio kernel: vccio_acodec: Bringing 3300000uV into 3000000-3000000uV Oct 30 16:00:05 volumio kernel: vccio_acodec: ramp_delay not set Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc6-supply from device tree Oct 30 16:00:05 volumio kernel: vccio_acodec: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.20: -ENOENT Oct 30 16:00:05 volumio kernel: vccio_acodec: 3000 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc6-supply from device tree Oct 30 16:00:05 volumio kernel: vccio_sd: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.21: -ENOENT Oct 30 16:00:05 volumio kernel: vccio_sd: 1800 <--> 3300 mV at 3300 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc6-supply from device tree Oct 30 16:00:05 volumio kernel: vcc3v3_pmu: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.22: -ENOENT Oct 30 16:00:05 volumio kernel: vcc3v3_pmu: 3300 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc7-supply from device tree Oct 30 16:00:05 volumio kernel: vcca_1v8: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.23: -ENOENT Oct 30 16:00:05 volumio kernel: vcca_1v8: 1800 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc7-supply from device tree Oct 30 16:00:05 volumio kernel: vcca1v8_pmu: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.24: -ENOENT Oct 30 16:00:05 volumio kernel: vcca1v8_pmu: 1800 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc7-supply from device tree Oct 30 16:00:05 volumio kernel: vcca1v8_image: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.25: -ENOENT Oct 30 16:00:05 volumio kernel: vcca1v8_image: 1800 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc9-supply from device tree Oct 30 16:00:05 volumio kernel: vcc_3v3: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.26: -ENOENT Oct 30 16:00:05 volumio kernel: vcc_3v3: at 3300 mV, enabled Oct 30 16:00:05 volumio kernel: rk808 0-0020: Looking up vcc8-supply from device tree Oct 30 16:00:05 volumio kernel: vcc3v3_sd: supplied by vcc3v3_sys Oct 30 16:00:05 volumio kernel: vcc3v3_sys: could not add device link regulator.27: -ENOENT Oct 30 16:00:05 volumio kernel: vcc3v3_sd: at 3300 mV, enabled Oct 30 16:00:05 volumio kernel: rk817-battery: Failed to locate of_node [id: -1] Oct 30 16:00:05 volumio kernel: rk817-battery rk817-battery: Failed to find matching dt id Oct 30 16:00:05 volumio kernel: rk817-charger: Failed to locate of_node [id: -1] Oct 30 16:00:05 volumio kernel: rk817-charger rk817-charger: Failed to find matching dt id Oct 30 16:00:05 volumio kernel: input: rk805 pwrkey as /devices/platform/fdd40000.i2c/i2c-0/0-0020/rk805-pwrkey/input/input0 Oct 30 16:00:05 volumio kernel: rk808-rtc rk808-rtc: registered as rtc0 Oct 30 16:00:05 volumio kernel: rk808-rtc rk808-rtc: setting system clock to 2024-10-30T08:00:04 UTC (1730275204) Oct 30 16:00:05 volumio kernel: pwm-fan pwm-fan: Looking up fan-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-fan pwm-fan: Looking up fan-supply property in node /pwm-fan failed Oct 30 16:00:05 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Oct 30 16:00:05 volumio kernel: Bluetooth: HCI UART protocol H4 registered Oct 30 16:00:05 volumio kernel: cpu cpu0: bin=0 Oct 30 16:00:05 volumio kernel: cpu cpu0: leakage=28 Oct 30 16:00:05 volumio kernel: cpu cpu0: pvtm = 87910, from nvmem Oct 30 16:00:05 volumio kernel: cpu cpu0: pvtm-volt-sel=2 Oct 30 16:00:05 volumio kernel: cpu cpu0: soc version=0, speed=2 Oct 30 16:00:05 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Oct 30 16:00:05 volumio kernel: cpu cpu0: avs=0 Oct 30 16:00:05 volumio kernel: cpu cpu0: EM: OPP:600000 is inefficient Oct 30 16:00:05 volumio kernel: cpu cpu0: EM: OPP:408000 is inefficient Oct 30 16:00:05 volumio kernel: cpu cpu0: EM: created perf domain Oct 30 16:00:05 volumio kernel: cpu cpu0: l=0 h=2147483647 hyst=5000 l_limit=0 h_limit=0 h_table=0 Oct 30 16:00:05 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Oct 30 16:00:05 volumio kernel: sdhci: Copyright(c) Pierre Ossman Oct 30 16:00:05 volumio kernel: Synopsys Designware Multimedia Card Interface Driver Oct 30 16:00:05 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: No normal pinctrl state Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: No idle pinctrl state Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: IDMAC supports 32-bit address mode. Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: Using internal DMA controller. Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: Version ID is 270a Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: No normal pinctrl state Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: No idle pinctrl state Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: DW MMC controller at irq 47,32 bit host data width,256 deep fifo Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: IDMAC supports 32-bit address mode. Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: Looking up vmmc-supply from device tree Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Using internal DMA controller. Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Version ID is 270a Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: DW MMC controller at irq 48,32 bit host data width,256 deep fifo Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Looking up vmmc-supply from device tree Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Looking up vmmc-supply property in node /dwmmc@fe2c0000 failed Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Looking up vqmmc-supply from device tree Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2b0000.dwmmc: Looking up vqmmc-supply from device tree Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Looking up vqmmc-supply property in node /dwmmc@fe2c0000 failed Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: allocated mmc-pwrseq Oct 30 16:00:05 volumio kernel: mmc_host mmc2: card is non-removable. Oct 30 16:00:05 volumio kernel: sdhci-dwcmshc fe310000.sdhci: Looking up vmmc-supply from device tree Oct 30 16:00:05 volumio kernel: arm-scmi firmware:scmi: Failed. SCMI protocol 17 not active. Oct 30 16:00:05 volumio kernel: sdhci-dwcmshc fe310000.sdhci: Looking up vmmc-supply property in node /sdhci@fe310000 failed Oct 30 16:00:05 volumio kernel: sdhci-dwcmshc fe310000.sdhci: Looking up vqmmc-supply from device tree Oct 30 16:00:05 volumio kernel: sdhci-dwcmshc fe310000.sdhci: Looking up vqmmc-supply property in node /sdhci@fe310000 failed Oct 30 16:00:05 volumio kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Oct 30 16:00:05 volumio kernel: cryptodev: driver 1.12 loaded. Oct 30 16:00:05 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 30 16:00:05 volumio kernel: xt_time: kernel timezone is -0000 Oct 30 16:00:05 volumio kernel: ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully Oct 30 16:00:05 volumio kernel: Initializing XFRM netlink socket Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 10 Oct 30 16:00:05 volumio kernel: Segment Routing with IPv6 Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 17 Oct 30 16:00:05 volumio kernel: NET: Registered protocol family 15 Oct 30 16:00:05 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Oct 30 16:00:05 volumio kernel: Bluetooth: RFCOMM socket layer initialized Oct 30 16:00:05 volumio kernel: Bluetooth: RFCOMM ver 1.11 Oct 30 16:00:05 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 30 16:00:05 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 30 16:00:05 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 30 16:00:05 volumio kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2 Oct 30 16:00:05 volumio kernel: Bluetooth: HIDP socket layer initialized Oct 30 16:00:05 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: Enter rfkill_rk_init Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: Enter rfkill_wlan_init Oct 30 16:00:05 volumio kernel: mmc_host mmc1: Bus speed (slot 0) = 375000Hz (slot req 400000Hz, actual 375000HZ div = 0) Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: Enter rfkill_wlan_probe Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: wifi_chip_type = ap6256 Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: enable wifi power control. Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: wifi power controled by gpio. Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: WIFI,host_wake_irq = 30, flags = 0. Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: The ref_wifi_clk not found ! Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rfkill_wlan_probe: init gpio Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rfkill_set_wifi_bt_power: 1 Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: Exit rfkill_wlan_probe Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: uart_rts_gpios = 77. Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: BT,reset_gpio = 79. Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: BT,wake_gpio = 81. Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: BT,wake_host_irq = 80. Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: Request irq for bt wakeup host Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: ** disable irq Oct 30 16:00:05 volumio kernel: [BT_RFKILL]: bt_default device registered. Oct 30 16:00:05 volumio kernel: Key type dns_resolver registered Oct 30 16:00:05 volumio kernel: Loading compiled-in X.509 certificates Oct 30 16:00:05 volumio kernel: rga: rga2, irq = 29, match scheduler Oct 30 16:00:05 volumio kernel: rga: rga2 hardware loaded successfully, hw_version:3.2.63318. Oct 30 16:00:05 volumio kernel: rga: rga2 probe successfully Oct 30 16:00:05 volumio kernel: rga_iommu: IOMMU binding successfully, default mapping core[0x4] Oct 30 16:00:05 volumio kernel: rga: Module initialized. v1.3.1 Oct 30 16:00:05 volumio kernel: mmc0: SDHCI controller on fe310000.sdhci [fe310000.sdhci] using ADMA Oct 30 16:00:05 volumio kernel: mmc0: Host Software Queue enabled Oct 30 16:00:05 volumio kernel: mmc0: new HS200 MMC card at address 0001 Oct 30 16:00:05 volumio kernel: mmcblk0: mmc0:0001 DV4032 29.1 GiB Oct 30 16:00:05 volumio kernel: mmcblk0boot0: mmc0:0001 DV4032 partition 1 4.00 MiB Oct 30 16:00:05 volumio kernel: mmcblk0boot1: mmc0:0001 DV4032 partition 2 4.00 MiB Oct 30 16:00:05 volumio kernel: mmcblk0rpmb: mmc0:0001 DV4032 partition 3 16.0 MiB, chardev (238:0) Oct 30 16:00:05 volumio kernel: Alternate GPT is invalid, using primary GPT. Oct 30 16:00:05 volumio kernel: mmcblk0: p1 p2 Oct 30 16:00:05 volumio kernel: vendor storage:20190527 ret = 0 Oct 30 16:00:05 volumio kernel: mmc_host mmc2: Bus speed (slot 0) = 375000Hz (slot req 400000Hz, actual 375000HZ div = 0) Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: Adding to iommu group 4 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: rkvdec, probing start Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: shared_niu_a is not found! Oct 30 16:00:05 volumio kernel: rkvdec2_init:1022: No niu aclk reset resource define Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: shared_niu_h is not found! Oct 30 16:00:05 volumio kernel: rkvdec2_init:1025: No niu hclk reset resource define Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: Looking up vdec-supply from device tree Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: bin=0 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: leakage=55 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: leakage-volt-sel=0 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: pvtm = 87910, from nvmem Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: pvtm-volt-sel=1 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: avs=0 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: failed to find power_model node Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: failed to initialize power model Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: failed to get dynamic-coefficient Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: sram_start 0x00000000fdcc0000 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: rcb_iova 0x0000000010000000 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: sram_size 45056 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: rcb_size 65536 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: min_width 512 Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: link mode probe finish Oct 30 16:00:05 volumio kernel: mpp_rkvdec2 fdf80200.rkvdec: probing finish Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up pmuio1-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: pmuio1(3300000 uV) supplied by vcc3v3_pmu Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up pmuio2-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: pmuio2(3300000 uV) supplied by vcc3v3_pmu Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio1-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio1(3300000 uV) supplied by vcc_3v3 Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio2-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio2(1800000 uV) supplied by vcc_1v8 Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio3-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio3(3300000 uV) supplied by vccio_sd Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio4-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio4(1800000 uV) supplied by vcc_1v8 Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio5-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio5(3300000 uV) supplied by vcc_3v3 Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio6-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio6(3300000 uV) supplied by vcc_3v3 Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: Looking up vccio7-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-iodomain fdc20000.syscon:io-domains: vccio7(3300000 uV) supplied by vcc_3v3 Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up rkvenc-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up rkvenc-supply property in node /power-management@fdd90000/power-controller failed Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up vi-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up vi-supply property in node /power-management@fdd90000/power-controller failed Oct 30 16:00:05 volumio kernel: [dhd] _dhd_module_init: in Dongle Host Driver, version 101.10.591.46.22 (20231003-3)(a5ec15e) drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Oct 30 2024 at 06:47:12 Oct 30 16:00:05 volumio kernel: [dhd] ANDROID_VERSION = 14 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_static_buf_init : 101.10.361.31 (wlan=r892223-20230427-1) Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_init_wlan_mem : prealloc ok for index 0: 6624256(6469K) Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_get_oob_irq: Enter Oct 30 16:00:05 volumio kernel: [dhd] dhd_wlan_init_gpio: WL_HOST_WAKE=-1, oob_irq=98, oob_irq_flags=0x4 Oct 30 16:00:05 volumio kernel: [dhd] dhd_wlan_init_gpio: WL_REG_ON=-1 Oct 30 16:00:05 volumio kernel: [dhd] dhd_wifi_platform_load: Enter Oct 30 16:00:05 volumio kernel: [dhd] Power-up adapter 'DHD generic adapter' Oct 30 16:00:05 volumio kernel: [dhd] wifi_platform_set_power = 1, delay: 200 msec Oct 30 16:00:05 volumio kernel: [dhd] ======== PULL WL_REG_ON(-1) HIGH! ======== Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: 1 Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wifi turn on power [GPIO-1-0] Oct 30 16:00:05 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 30 16:00:05 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 30 16:00:05 volumio kernel: rockchip-pm rockchip-suspend: not set pwm-regulator-config Oct 30 16:00:05 volumio kernel: rockchip-suspend not set sleep-mode-config for mem-lite Oct 30 16:00:05 volumio kernel: rockchip-suspend not set wakeup-config for mem-lite Oct 30 16:00:05 volumio kernel: rockchip-suspend not set sleep-mode-config for mem-ultra Oct 30 16:00:05 volumio kernel: rockchip-suspend not set wakeup-config for mem-ultra Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up vo-supply from device tree Oct 30 16:00:05 volumio kernel: rockchip-pm-domain fdd90000.power-management:power-controller: Looking up vo-supply property in node /power-management@fdd90000/power-controller failed Oct 30 16:00:05 volumio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Oct 30 16:00:05 volumio kernel: cfg80211: failed to load regulatory.db Oct 30 16:00:05 volumio kernel: ALSA device list: Oct 30 16:00:05 volumio kernel: No soundcards found. Oct 30 16:00:05 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (2 bytes) Oct 30 16:00:05 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (3 bytes) Oct 30 16:00:05 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (3 bytes) Oct 30 16:00:05 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (7 bytes) Oct 30 16:00:05 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Oct 30 16:00:05 volumio kernel: VFS: Mounted root (ext4 filesystem) readonly on device 179:2. Oct 30 16:00:05 volumio kernel: devtmpfs: mounted Oct 30 16:00:05 volumio kernel: mmc2: queuing unknown CIS tuple 0x81 (9 bytes) Oct 30 16:00:05 volumio kernel: Freeing unused kernel memory: 5696K Oct 30 16:00:05 volumio kernel: Run /sbin/init as init process Oct 30 16:00:05 volumio kernel: with arguments: Oct 30 16:00:05 volumio kernel: /sbin/init Oct 30 16:00:05 volumio kernel: with environment: Oct 30 16:00:05 volumio kernel: HOME=/ Oct 30 16:00:05 volumio kernel: TERM=linux Oct 30 16:00:05 volumio kernel: cgroup_enable=memory Oct 30 16:00:05 volumio kernel: cgroup_memory=1 Oct 30 16:00:05 volumio kernel: mmc_host mmc2: Bus speed (slot 0) = 148500000Hz (slot req 150000000Hz, actual 148500000HZ div = 0) Oct 30 16:00:05 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Successfully tuned phase to 145 Oct 30 16:00:05 volumio kernel: mmc2: new ultra high speed SDR104 SDIO card at address 0001 Oct 30 16:00:05 volumio kernel: [dhd] dummy_probe: enter Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: [dhd] dummy_probe: enter Oct 30 16:00:05 volumio kernel: [dhd] dummy_probe: enter Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: [dhd] wifi_platform_set_power = 1, sleep done: 200 msec Oct 30 16:00:05 volumio kernel: [dhd] wifi_platform_bus_enumerate device present 1 Oct 30 16:00:05 volumio kernel: [dhd] ======== Card detection to detect SDIO card! ======== Oct 30 16:00:05 volumio kernel: [dhd] bcmsdh_register: register client driver Oct 30 16:00:05 volumio kernel: [dhd] bcmsdh_sdmmc_probe: Enter num=1 Oct 30 16:00:05 volumio kernel: [dhd] bcmsdh_sdmmc_probe: Enter num=2 Oct 30 16:00:05 volumio kernel: [dhd] bus num (host idx)=2, slot num (rca)=1 Oct 30 16:00:05 volumio kernel: [dhd] found adapter info 'DHD generic adapter' Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 3, size 139264 Oct 30 16:00:05 volumio kernel: [dhd] succeed to alloc static buf Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 4, size 0 Oct 30 16:00:05 volumio kernel: [dhd] sdioh_attach: set sd_f2_blocksize 256 Oct 30 16:00:05 volumio kernel: [dhd] sdioh_attach: sd clock rate = 150000000 Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio kernel: [dhd] dhdsdio_probe : no mutex held Oct 30 16:00:05 volumio kernel: [dhd] dhdsdio_probe : set mutex lock Oct 30 16:00:05 volumio kernel: [dhd] F1 signature read @0x18000000=0x15294345 Oct 30 16:00:05 volumio kernel: [dhd] F1 signature OK, socitype:0x1 chip:0x4345 rev:0x9 pkg:0x2 Oct 30 16:00:05 volumio kernel: [dhd] DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 7, size 43224 Oct 30 16:00:05 volumio kernel: [dhd] dhd_conf_set_chiprev : devid=0xa9bf, chip=0x4345, chiprev=9 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 0, size 10320 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 5, size 65536 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 19, size 65712 Oct 30 16:00:05 volumio kernel: [dhd] dhd_log_dump_init: kernel log buf size = 256KB; logdump_prsrv_tailsize = 80KB; limit prsrv tail size to = 38KB Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 15, size 4194304 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 16, size 8192 Oct 30 16:00:05 volumio kernel: [dhd] dhd_attach(): thread:dhd_watchdog_thread:b2 started Oct 30 16:00:05 volumio kernel: [dhd] dhd_attach(): thread:dhd_dpc:b3 started Oct 30 16:00:05 volumio kernel: [dhd] dhd_attach(): thread:dhd_rxf:b4 started Oct 30 16:00:05 volumio kernel: [dhd] dhd_deferred_work_init: work queue initialized Oct 30 16:00:05 volumio kernel: [dhd] dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 2 Oct 30 16:00:05 volumio kernel: [dhd] dhd_tcpack_suppress_set: TCPACK_INFO_MAXNUM=40, TCPDATA_INFO_MAXNUM=40 Oct 30 16:00:05 volumio kernel: [dhd] dhd_get_memdump_info: MEMDUMP ENABLED = 3 Oct 30 16:00:05 volumio kernel: [dhd] sdioh_cis_read: func_cis_ptr[0]=0x10ac Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 1, size 10300 Oct 30 16:00:05 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 2, size 65536 Oct 30 16:00:05 volumio kernel: [dhd] dhdsdio_probe_init: making DHD_BUS_DOWN Oct 30 16:00:05 volumio kernel: [dhd] Dongle Host Driver, version 101.10.591.46.22 (20231003-3)(a5ec15e) drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Oct 30 2024 at 06:47:12 Oct 30 16:00:05 volumio kernel: [dhd] Register interface [wlan0] MAC: b8:2d:28:39:5f:be Oct 30 16:00:05 volumio kernel: [dhd] [wlan0] wl_android_wifi_off : g_wifi_on=1 force_off=1 Oct 30 16:00:05 volumio kernel: [dhd] dhd_bus_devreset: == Power OFF == Oct 30 16:00:05 volumio kernel: [dhd] dhd_bus_stop: making DHD_BUS_DOWN Oct 30 16:00:05 volumio kernel: [dhd] bcmsdh_oob_intr_unregister: Enter Oct 30 16:00:05 volumio kernel: [dhd] bcmsdh_oob_intr_unregister: irq is not registered Oct 30 16:00:05 volumio kernel: [dhd] dhd_bus_devreset: making dhdpub up FALSE Oct 30 16:00:05 volumio kernel: [dhd] dhd_txglom_enable: enable 0 Oct 30 16:00:05 volumio kernel: [dhd] dhd_bus_devreset: making DHD_BUS_DOWN Oct 30 16:00:05 volumio kernel: [dhd] wifi_platform_set_power = 0, delay: 0 msec Oct 30 16:00:05 volumio kernel: [dhd] ======== PULL WL_REG_ON(-1) LOW! ======== Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: 0 Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Oct 30 16:00:05 volumio kernel: [WLAN_RFKILL]: wifi shut off power [GPIO-1-1] Oct 30 16:00:05 volumio kernel: [dhd] [wlan0] wl_android_wifi_off : out Oct 30 16:00:05 volumio kernel: [dhd] Register interface [wlan1] MAC: ba:2d:28:39:5f:be Oct 30 16:00:05 volumio kernel: [dhd] wl_android_post_init: 0 Oct 30 16:00:05 volumio kernel: [dhd] dhdsdio_probe : mutex is released. Oct 30 16:00:05 volumio kernel: [dhd] bcmsdh_sdmmc_probe: Enter num=3 Oct 30 16:00:05 volumio kernel: [dhd] _dhd_module_init: Exit err=0 Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply from device tree Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: Looking up power-supply property in node /backlight failed Oct 30 16:00:05 volumio kernel: pwm-backlight backlight: supply power not found, using dummy regulator Oct 30 16:00:05 volumio systemd[1]: Failed to lookup module alias 'autofs4': Function not implemented Oct 30 16:00:05 volumio 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) Oct 30 16:00:05 volumio systemd[1]: Detected architecture arm64. Oct 30 16:00:05 volumio systemd[1]: Set hostname to . Oct 30 16:00:05 volumio 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. Oct 30 16:00:05 volumio systemd[1]: Created slice system-getty.slice. Oct 30 16:00:05 volumio systemd[1]: Listening on Journal Socket (/dev/log). Oct 30 16:00:05 volumio systemd[1]: Condition check resulted in Journal Audit Socket being skipped. Oct 30 16:00:05 volumio systemd[1]: Listening on udev Control Socket. Oct 30 16:00:05 volumio systemd[1]: Listening on Journal Socket. Oct 30 16:00:05 volumio systemd[1]: Starting Journal Service... Oct 30 16:00:05 volumio kernel: Removed cp15_barrier emulation handler Oct 30 16:00:05 volumio kernel: Enabled cp15_barrier support Oct 30 16:00:05 volumio kernel: EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) Oct 30 16:00:05 volumio systemd-journald[196]: Journal started Oct 30 16:00:05 volumio systemd-journald[196]: Runtime journal (/run/log/journal/6f2a9ac546ea4c7cecaa41ce6669ffa1) is 3.7M, max 30.0M, 26.2M free. Oct 30 16:00:05 volumio fake-hwclock[200]: Current system time: 2024-10-30 08:00:05 Oct 30 16:00:05 volumio fake-hwclock[200]: fake-hwclock saved clock information is in the past: 2024-10-30 08:00:00 Oct 30 16:00:05 volumio fake-hwclock[200]: To set system time to this saved clock anyway, use "force" Oct 30 16:00:05 volumio systemd-fsck[203]: /dev/mmcblk0p2: clean, 54337/131072 files, 356858/524288 blocks Oct 30 16:00:05 volumio systemd[1]: Started Create Static Device Nodes in /dev. Oct 30 16:00:05 volumio systemd[1]: Reached target Local File Systems (Pre). Oct 30 16:00:05 volumio systemd[1]: Mounting /var/spool/cups... Oct 30 16:00:05 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 30 16:00:05 volumio systemd[1]: Mounting /tmp... Oct 30 16:00:05 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 30 16:00:05 volumio systemd[1]: Mounting /var/log... Oct 30 16:00:05 volumio systemd[1]: Starting udev Kernel Device Manager... Oct 30 16:00:05 volumio systemd[1]: Mounted /var/spool/cups. Oct 30 16:00:05 volumio systemd[1]: Mounted /tmp. Oct 30 16:00:05 volumio systemd[1]: Mounting /var/spool/cups/tmp... Oct 30 16:00:05 volumio systemd[1]: Mounted /var/log. Oct 30 16:00:05 volumio systemd-udevd[233]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 30 16:00:05 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Oct 30 16:00:05 volumio systemd[1]: Mounted /var/spool/cups/tmp. Oct 30 16:00:05 volumio systemd[1]: Reached target Local File Systems. Oct 30 16:00:05 volumio systemd[1]: Starting Preprocess NFS configuration... Oct 30 16:00:05 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 30 16:00:05 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 30 16:00:05 volumio systemd[1]: nfs-config.service: Succeeded. Oct 30 16:00:05 volumio systemd[1]: Started Preprocess NFS configuration. Oct 30 16:00:05 volumio systemd[1]: Started udev Kernel Device Manager. Oct 30 16:00:05 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 30 16:00:05 volumio systemd-journald[196]: Runtime journal (/run/log/journal/6f2a9ac546ea4c7cecaa41ce6669ffa1) is 3.7M, max 30.0M, 26.2M free. Oct 30 16:00:05 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 30 16:00:05 volumio systemd[1]: Reached target NFS client services. Oct 30 16:00:05 volumio systemd[1]: plymouth-read-write.service: Succeeded. Oct 30 16:00:05 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 30 16:00:05 volumio systemd[1]: Started Flush Journal to Persistent Storage. Oct 30 16:00:05 volumio systemd[1]: Starting Create Volatile Files and Directories... Oct 30 16:00:06 volumio systemd[1]: Started Create Volatile Files and Directories. Oct 30 16:00:06 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 30 16:00:06 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 30 16:00:06 volumio systemd[1]: Starting RPC bind portmap service... Oct 30 16:00:06 volumio systemd-udevd[246]: could not open moddep file '/lib/modules/5.10.198-rk356x/modules.dep.bin' Oct 30 16:00:06 volumio systemd-udevd[246]: could not open moddep file '/lib/modules/5.10.198-rk356x/modules.dep.bin' Oct 30 16:00:06 volumio systemd[1]: Started udev Coldplug all Devices. Oct 30 16:00:06 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 30 16:00:06 volumio systemd[1]: Starting Show Plymouth Boot Screen... Oct 30 16:00:06 volumio systemd[1]: Started RPC bind portmap service. Oct 30 16:00:06 volumio systemd-udevd[263]: Using default interface naming scheme 'v240'. Oct 30 16:00:06 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 30 16:00:06 volumio systemd-udevd[263]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 30 16:00:06 volumio systemd-udevd[263]: link_config: could not get ethtool features for eth0 Oct 30 16:00:06 volumio systemd-udevd[263]: Could not set offload features of eth0: Device or resource busy Oct 30 16:00:06 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 30 16:00:06 volumio systemd[1]: Starting Raise network interfaces... Oct 30 16:00:06 volumio systemd[1]: Reached target RPC Port Mapper. Oct 30 16:00:06 volumio systemd[1]: Reached target Remote File Systems (Pre). Oct 30 16:00:06 volumio systemd[1]: Reached target Remote File Systems. Oct 30 16:00:06 volumio systemd[1]: Received SIGRTMIN+20 from PID 286 (plymouthd). Oct 30 16:00:06 volumio systemd-udevd[239]: Using default interface naming scheme 'v240'. Oct 30 16:00:06 volumio systemd-udevd[269]: Using default interface naming scheme 'v240'. Oct 30 16:00:06 volumio systemd-udevd[239]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 30 16:00:06 volumio systemd-udevd[269]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 30 16:00:06 volumio systemd-udevd[245]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 30 16:00:06 volumio ifup[289]: command failed: Input/output error (-5) Oct 30 16:00:06 volumio systemd[1]: Started Show Plymouth Boot Screen. Oct 30 16:00:06 volumio systemd[1]: Found device /dev/ttyFIQ0. Oct 30 16:00:06 volumio ifup[289]: command failed: Input/output error (-5) Oct 30 16:00:06 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Link Fail, LTSSM is 0x3, hw_retries=0 Oct 30 16:00:06 volumio systemd-udevd[245]: Using default interface naming scheme 'v240'. Oct 30 16:00:06 volumio systemd-udevd[245]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 30 16:00:06 volumio systemd-udevd[245]: Could not generate persistent MAC address for dummy0: No such file or directory Oct 30 16:00:06 volumio systemd-udevd[251]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 30 16:00:07 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Oct 30 16:00:07 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Oct 30 16:00:07 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 30 16:00:07 volumio systemd[1]: Started ifup for wlan0. Oct 30 16:00:07 volumio systemd[1]: Started ifup for eth0. Oct 30 16:00:07 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 30 16:00:07 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 30 16:00:07 volumio systemd[1]: Reached target Paths. Oct 30 16:00:07 volumio systemd[1]: Reached target Local Encrypted Volumes. Oct 30 16:00:07 volumio systemd[1]: Reached target System Initialization. Oct 30 16:00:07 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 30 16:00:07 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 30 16:00:07 volumio systemd[1]: Started Daily apt download activities. Oct 30 16:00:07 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 30 16:00:07 volumio systemd[1]: Reached target Timers. Oct 30 16:00:07 volumio systemd[1]: Listening on triggerhappy.socket. Oct 30 16:00:07 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 30 16:00:07 volumio systemd[1]: Reached target Sockets. Oct 30 16:00:07 volumio systemd[1]: Reached target Basic System. Oct 30 16:00:07 volumio systemd[1]: Starting Wireless Services... Oct 30 16:00:07 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 30 16:00:07 volumio sh[1200]: command failed: Input/output error (-5) Oct 30 16:00:07 volumio sh[1209]: command failed: Input/output error (-5) Oct 30 16:00:07 volumio systemd[1]: Started Volumio Iptables Module. Oct 30 16:00:07 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 30 16:00:07 volumio systemd[1]: Starting DHCP Client Daemon... Oct 30 16:00:07 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 30 16:00:07 volumio systemd[1]: Started D-Bus System Message Bus. Oct 30 16:00:07 volumio kernel: [dhd] WEXT-ERROR) wl_iw_get_essid : Error getting the SSID -36 Oct 30 16:00:07 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Oct 30 16:00:07 volumio systemd[1]: Started volumio-remote-updater.service. Oct 30 16:00:07 volumio sh[1209]: sending commands to master dhcpcd process Oct 30 16:00:07 volumio dhcpcd[1382]: sending commands to master dhcpcd process Oct 30 16:00:07 volumio systemd[1]: Started Volumio Log Rotation Service. Oct 30 16:00:07 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 16:00:07 volumio thd[1371]: Unable to parse trigger line: Oct 30 16:00:07 volumio thd[1371]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 30 16:00:07 volumio thd[1371]: Unable to parse trigger line: Oct 30 16:00:07 volumio thd[1371]: Unable to parse trigger line: Oct 30 16:00:07 volumio thd[1371]: Found socket passed from systemd Oct 30 16:00:07 volumio systemd[1]: Starting WPA supplicant... Oct 30 16:00:07 volumio kernel: [dhd] dhd_pri_open : no mutex held Oct 30 16:00:07 volumio kernel: [dhd] dhd_pri_open : set mutex lock Oct 30 16:00:07 volumio kernel: [dhd] [wlan0] dhd_open : Enter Oct 30 16:00:07 volumio kernel: [dhd] Dongle Host Driver, version 101.10.591.46.22 (20231003-3)(a5ec15e) drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Oct 30 2024 at 06:47:12 Oct 30 16:00:07 volumio kernel: [dhd] dhd_open: ######### called for ifidx=0 ######### Oct 30 16:00:07 volumio kernel: [dhd] [wlan0] wl_android_wifi_on : in g_wifi_on=0 Oct 30 16:00:07 volumio kernel: [dhd] wifi_platform_set_power = 1, delay: 200 msec Oct 30 16:00:07 volumio kernel: [dhd] ======== PULL WL_REG_ON(-1) HIGH! ======== Oct 30 16:00:07 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: 1 Oct 30 16:00:07 volumio kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Oct 30 16:00:07 volumio kernel: [WLAN_RFKILL]: wifi turn on power [GPIO-1-0] Oct 30 16:00:07 volumio systemd[1]: Starting Login Service... Oct 30 16:00:07 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 30 16:00:07 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 30 16:00:07 volumio systemd[1]: Started triggerhappy global hotkey daemon. Oct 30 16:00:07 volumio wpa_supplicant[1420]: Successfully initialized wpa_supplicant Oct 30 16:00:07 volumio avahi-daemon[1466]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Oct 30 16:00:07 volumio avahi-daemon[1466]: Successfully dropped root privileges. Oct 30 16:00:07 volumio avahi-daemon[1466]: avahi-daemon 0.7 starting up. Oct 30 16:00:07 volumio avahi-daemon[1466]: Successfully called chroot(). Oct 30 16:00:07 volumio avahi-daemon[1466]: Successfully dropped remaining capabilities. Oct 30 16:00:07 volumio avahi-daemon[1466]: No service file found in /etc/avahi/services. Oct 30 16:00:07 volumio systemd[1]: iptables.service: Succeeded. Oct 30 16:00:07 volumio systemd-logind[1442]: New seat seat0. Oct 30 16:00:07 volumio volumio-remote-updater[1381]: Error: No active session Oct 30 16:00:07 volumio kernel: [dhd] wifi_platform_set_power = 1, sleep done: 200 msec Oct 30 16:00:07 volumio kernel: [dhd] sdio_sw_reset: call mmc_hw_reset Oct 30 16:00:07 volumio systemd-logind[1442]: Watching system buttons on /dev/input/event0 (rk805 pwrkey) Oct 30 16:00:07 volumio volumio[1415]: Could not open config: /tmp/upmpdcli.conf Oct 30 16:00:07 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 30 16:00:07 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 30 16:00:08 volumio systemd[1]: Started Raise network interfaces. Oct 30 16:00:08 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 30 16:00:08 volumio systemd[1]: Started Login Service. Oct 30 16:00:08 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 30 16:00:08 volumio systemd[1]: Started WPA supplicant. Oct 30 16:00:08 volumio sh[1209]: eth0=eth0 Oct 30 16:00:08 volumio systemd[1]: Reached target Network. Oct 30 16:00:08 volumio systemd[1]: Starting Permit User Sessions... Oct 30 16:00:08 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 30 16:00:08 volumio systemd[1]: Starting Network Time Service... Oct 30 16:00:08 volumio systemd[1]: Reached target Network is Online. Oct 30 16:00:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:08 volumio kernel: [BT_RFKILL]: bt shut off power Oct 30 16:00:08 volumio systemd[1]: Starting Samba NMB Daemon... Oct 30 16:00:08 volumio kernel: mmc_host mmc2: Bus speed (slot 0) = 375000Hz (slot req 400000Hz, actual 375000HZ div = 0) Oct 30 16:00:08 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Oct 30 16:00:08 volumio systemd[1]: Starting /etc/rc.local Compatibility... Oct 30 16:00:08 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Oct 30 16:00:08 volumio dhcpcd[1324]: no interfaces have a carrier Oct 30 16:00:08 volumio dhcpcd[1324]: no interfaces have a carrier Oct 30 16:00:08 volumio dhcpcd[1324]: forked to background, child pid 1709 Oct 30 16:00:08 volumio dhcpcd[1324]: forked to background, child pid 1709 Oct 30 16:00:08 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (2 bytes) Oct 30 16:00:08 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (3 bytes) Oct 30 16:00:08 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (3 bytes) Oct 30 16:00:08 volumio systemd[1]: Started DHCP Client Daemon. Oct 30 16:00:08 volumio kernel: mmc2: queuing unknown CIS tuple 0x80 (7 bytes) Oct 30 16:00:08 volumio kernel: mmc2: queuing unknown CIS tuple 0x81 (9 bytes) Oct 30 16:00:08 volumio ntpd[1673]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 30 16:00:08 volumio ntpd[1673]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 30 16:00:08 volumio systemd[1]: Started Permit User Sessions. Oct 30 16:00:08 volumio systemd[1]: Started Network Time Service. Oct 30 16:00:08 volumio loadcpufreq[1277]: Loading cpufreq kernel modules...done (none). Oct 30 16:00:08 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 30 16:00:08 volumio systemd[1]: Started /etc/rc.local Compatibility. Oct 30 16:00:08 volumio kernel: mmc_host mmc2: Bus speed (slot 0) = 148500000Hz (slot req 150000000Hz, actual 148500000HZ div = 0) Oct 30 16:00:08 volumio kernel: dwmmc_rockchip fe2c0000.dwmmc: Successfully tuned phase to 145 Oct 30 16:00:08 volumio kernel: [dhd] sdioh_start: set sd_f2_blocksize 256 Oct 30 16:00:08 volumio kernel: [dhd] dhd_bus_devreset: == Power ON == Oct 30 16:00:08 volumio kernel: [dhd] F1 signature read @0x18000000=0x15294345 Oct 30 16:00:08 volumio kernel: [dhd] F1 signature OK, socitype:0x1 chip:0x4345 rev:0x9 pkg:0x2 Oct 30 16:00:08 volumio kernel: [dhd] DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 Oct 30 16:00:08 volumio kernel: [dhd] dhd_bus_devreset: making DHD_BUS_DOWN Oct 30 16:00:08 volumio kernel: [dhd] dhdsdio_probe_init: making DHD_BUS_DOWN Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_path_params : Final fw_path=/fw_bcm43456c5_ag.bin Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_path_params : Final nv_path=/nvram_ap6256.txt Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_path_params : Final clm_path=/clm_bcm43456c5_ag.blob Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_path_params : Final conf_path=/config.txt Oct 30 16:00:08 volumio kernel: bcmsdh_sdmmc mmc2:0001:2: Direct firmware load for /config.txt failed with error -2 Oct 30 16:00:08 volumio kernel: [dhd] dhd_os_get_img_fwreq: request_firmware /config.txt err: -2 Oct 30 16:00:08 volumio kernel: [dhd] dhd_os_get_img(Request Firmware API) error : -30 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_read_config : Ignore config file /config.txt Oct 30 16:00:08 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 30 16:00:08 volumio kernel: [dhd] dhd_os_get_img_fwreq: /fw_bcm43456c5_ag.bin (629584 bytes) open success Oct 30 16:00:08 volumio systemd[1]: Starting Hold until boot process finishes up... Oct 30 16:00:08 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 30 16:00:08 volumio ifplugd(eth0)[1734]: ifplugd 0.28 initializing. Oct 30 16:00:08 volumio systemd[1]: Received SIGRTMIN+21 from PID 286 (plymouthd). Oct 30 16:00:08 volumio systemd[1]: plymouth-start.service: Succeeded. Oct 30 16:00:08 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 30 16:00:08 volumio systemd[1]: Started Hold until boot process finishes up. Oct 30 16:00:08 volumio systemd[1]: plymouth-quit.service: Succeeded. Oct 30 16:00:08 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Oct 30 16:00:08 volumio systemd[1]: Received SIGRTMIN+21 from PID 286 (n/a). Oct 30 16:00:08 volumio systemd[1]: Started Serial Getty on ttyFIQ0. Oct 30 16:00:08 volumio kernel: [dhd] dhd_os_get_img_fwreq: /nvram_ap6256.txt (2732 bytes) open success Oct 30 16:00:08 volumio kernel: [dhd] #AP6256_NVRAM_V1.4_06112021 Oct 30 16:00:08 volumio kernel: [dhd] dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Oct 30 16:00:08 volumio systemd[1]: Started Getty on tty1. Oct 30 16:00:08 volumio systemd[1]: Reached target Login Prompts. Oct 30 16:00:08 volumio kernel: [dhd] dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Oct 30 16:00:08 volumio kernel: [dhd] bcmsdh_oob_intr_register: HW_OOB irq=98 flags=0x4 Oct 30 16:00:08 volumio kernel: [dhd] dhd_get_memdump_info: MEMDUMP ENABLED = 3 Oct 30 16:00:08 volumio kernel: [dhd] wlc_ver_major 4, wlc_ver_minor 1 Oct 30 16:00:08 volumio kernel: [dhd] dhd_bus_check_srmemsize : srmem_size no need to change. Oct 30 16:00:08 volumio kernel: [dhd] dhd_tcpack_suppress_set: TCP ACK Suppress mode 2 -> mode 1 Oct 30 16:00:08 volumio kernel: [dhd] dhd_tcpack_suppress_set: TCPACK_INFO_MAXNUM=40, TCPDATA_INFO_MAXNUM=40 Oct 30 16:00:08 volumio kernel: [dhd] dhd_legacy_preinit_ioctls: use firmware generated mac_address b8:2d:28:39:5f:be Oct 30 16:00:08 volumio kernel: [dhd] dhd_os_get_img_fwreq: /clm_bcm43456c5_ag.blob (14036 bytes) open success Oct 30 16:00:08 volumio kernel: [dhd] dhd_check_current_clm_data: ----- This FW is included CLM data ----- Oct 30 16:00:08 volumio kernel: [dhd] dhd_apply_default_clm: CLM already exist in F/W, new CLM data will be added to the end of existing CLM data! Oct 30 16:00:08 volumio kernel: [dhd] dhd_apply_default_clm: CLM download succeeded Oct 30 16:00:08 volumio kernel: [dhd] dhd_check_current_clm_data: ----- This FW is included CLM data ----- Oct 30 16:00:08 volumio kernel: [dhd] Firmware up: op_mode=0x0005, MAC=b8:2d:28:39:5f:be Oct 30 16:00:08 volumio kernel: [dhd] dhd_legacy_preinit_ioctls: event_log_max_sets: 40 ret: -23 Oct 30 16:00:08 volumio kernel: [dhd] dhd_legacy_preinit_ioctls set event_log_tag_control fail -23 Oct 30 16:00:08 volumio kernel: [dhd] arp_enable:1 arp_ol:0 Oct 30 16:00:08 volumio kernel: [dhd] Driver: 101.10.591.46.22 (20231003-3) [dhd] Firmware: wl0: Apr 16 2024 11:22:54 version 7.45.96.203 (g23f64344) FWID 01-4763f75d es7.c5.n4.a3 [dhd] CLM: 9.10.105 (2018-03-09 18:56:28) Oct 30 16:00:08 volumio kernel: [dhd] dhd_txglom_enable: enable 1 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_txglom_params : txglom_mode=copy Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_txglom_params : txglomsize=36, deferred_tx_len=0 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_txglom_params : txinrx_thres=128, dhd_txminmax=-1 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_txglom_params : tx_max_offset=0, txctl_tmo_fix=300 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_get_disable_proptx : fw_proptx=1, disable_proptx=-1 Oct 30 16:00:08 volumio kernel: [dhd] dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 Oct 30 16:00:08 volumio kernel: [dhd] dhd_pno_init: Support Android Location Service Oct 30 16:00:08 volumio kernel: [dhd] rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-4 Oct 30 16:00:08 volumio kernel: [dhd] dhd_rtt_init : FTM is not supported Oct 30 16:00:08 volumio kernel: [dhd] dhd_rtt_init EXIT, err = 0 Oct 30 16:00:08 volumio cpufrequtils[1722]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 30 16:00:08 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 30 16:00:08 volumio kernel: [dhd] dhd_legacy_preinit_ioctls: Failed to get preserve log # ! Oct 30 16:00:08 volumio kernel: [dhd] dhd_legacy_preinit_ioctls: d3_hostwake_delay IOVAR not present, proceed Oct 30 16:00:08 volumio haveged[256]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Oct 30 16:00:08 volumio haveged[256]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Oct 30 16:00:08 volumio haveged[256]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99731 Oct 30 16:00:08 volumio haveged[256]: haveged: fills: 0, generated: 0 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_map_country_list : CN/38 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_map_country_list : CN/38 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_country : set country CN, revision 38 Oct 30 16:00:08 volumio kernel: [dhd] dhd_conf_set_country : Country code: CN (CN/38) Oct 30 16:00:08 volumio kernel: [dhd] [wlan0] wl_android_wifi_on : Success Oct 30 16:00:08 volumio nmbd[1717]: [2024/10/30 16:00:08.625774, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 30 16:00:08 volumio kernel: [dhd] CFG80211-ERROR) init_roam_cache : roamscan_mode iovar failed. -23 Oct 30 16:00:08 volumio kernel: [dhd] CFG80211-ERROR) wl_cfg80211_up : Failed to enable RCC. Oct 30 16:00:08 volumio kernel: [dhd] [wlan0] dhd_open : Exit ret=0 Oct 30 16:00:08 volumio kernel: [dhd] [wlan0] dhd_pri_open : tx queue started Oct 30 16:00:08 volumio kernel: [dhd] dhd_pri_open : mutex is released. Oct 30 16:00:08 volumio avahi-daemon[1466]: Network interface enumeration completed. Oct 30 16:00:08 volumio nmbd[1717]: started asyncdns process 1747 Oct 30 16:00:08 volumio avahi-daemon[1466]: Server startup complete. Host name is volumio.local. Local service cookie is 2107327536. Oct 30 16:00:08 volumio dhcpcd[1709]: dummy0: waiting for carrier Oct 30 16:00:08 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:08] [info] asio async_connect error: system:111 (Connection refused) Oct 30 16:00:08 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 16:00:08 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:08] [error] handle_connect error: Underlying Transport Error Oct 30 16:00:08 volumio kernel: rk_gmac-dwmac fe010000.ethernet eth0: PHY [stmmac-1:00] driver [YT8531 Gigabit Ethernet] (irq=POLL) Oct 30 16:00:08 volumio kernel: dwmac4: Master AXI performs any burst length Oct 30 16:00:08 volumio kernel: rk_gmac-dwmac fe010000.ethernet eth0: No Safety Features support found Oct 30 16:00:08 volumio kernel: rk_gmac-dwmac fe010000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported Oct 30 16:00:08 volumio kernel: rk_gmac-dwmac fe010000.ethernet eth0: registered PTP clock Oct 30 16:00:08 volumio kernel: rk_gmac-dwmac fe010000.ethernet eth0: configuring for phy/rgmii link mode Oct 30 16:00:08 volumio kernel: [dhd] dhd_static_if_open : no mutex held Oct 30 16:00:08 volumio kernel: [dhd] dhd_static_if_open : set mutex lock Oct 30 16:00:08 volumio kernel: [dhd] [wlan1] dhd_static_if_open : Enter Oct 30 16:00:08 volumio kernel: [dhd] [wlan0] dhd_open : Primary net_device is already up Oct 30 16:00:08 volumio kernel: [dhd] CFG80211-ERROR) wl_cfg80211_deinit_p2p_discovery : Disabling P2P Discovery Interface Oct 30 16:00:08 volumio kernel: [dhd] CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Oct 30 16:00:08 volumio kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : section 8, size 44584 Oct 30 16:00:08 volumio kernel: [dhd] dhd_wlfc_enable: ret=0 Oct 30 16:00:08 volumio ntpd[1712]: proto: precision = 0.875 usec (-20) Oct 30 16:00:08 volumio kernel: [dhd] dhd_clear_del_in_progress Oct 30 16:00:08 volumio kernel: [dhd] [wlan1] dhd_static_if_open : Exit ret=0 Oct 30 16:00:08 volumio kernel: [dhd] dhd_static_if_open : mutex is released. Oct 30 16:00:08 volumio ifplugd(eth0)[1734]: Using interface eth0/12:45:36:A1:5D:57 with driver (version: Jan_2016) Oct 30 16:00:08 volumio ifplugd(eth0)[1734]: Using detection mode: SIOCETHTOOL Oct 30 16:00:08 volumio ifplugd(eth0)[1734]: Initialization complete, link beat not detected. Oct 30 16:00:08 volumio dhcpcd[1709]: eth0: waiting for carrier Oct 30 16:00:08 volumio dhcpcd[1709]: wlan0: waiting for carrier Oct 30 16:00:08 volumio ntpd[1712]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 30 16:00:08 volumio ntpd[1712]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 30 16:00:08 volumio ntpd[1712]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 673 days ago Oct 30 16:00:08 volumio ntpd[1712]: Listen and drop on 0 v6wildcard [::]:123 Oct 30 16:00:08 volumio ntpd[1712]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 30 16:00:08 volumio dhcpcd[1709]: wlan1: waiting for carrier Oct 30 16:00:08 volumio nmbd[1717]: [2024/10/30 16:00:08.657048, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 30 16:00:08 volumio nmbd[1717]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 30 16:00:08 volumio nmbd[1717]: [2024/10/30 16:00:08.657308, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 30 16:00:08 volumio nmbd[1717]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 30 16:00:08 volumio ntpd[1712]: Listen normally on 2 lo 127.0.0.1:123 Oct 30 16:00:08 volumio ntpd[1712]: Listening on routing socket on fd #19 for interface updates Oct 30 16:00:08 volumio ntpd[1712]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 16:00:08 volumio ntpd[1712]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 16:00:08 volumio dhcpcd[1709]: wlan0: carrier acquired Oct 30 16:00:08 volumio dhcpcd[1709]: DUID 00:01:00:01:2d:fc:bc:99:ea:83:38:f6:0c:30 Oct 30 16:00:08 volumio dhcpcd[1709]: wlan0: IAID 28:39:5f:be Oct 30 16:00:08 volumio dhcpcd[1709]: wlan0: IAID conflicts with one assigned to wlan1 Oct 30 16:00:08 volumio dhcpcd[1709]: wlan0: adding address fe80::37ec:fad2:c327:8052 Oct 30 16:00:08 volumio dhcpcd[1709]: ipv6_addaddr1: Permission denied Oct 30 16:00:08 volumio dhcpcd[1709]: control command: /sbin/dhcpcd eth0 Oct 30 16:00:08 volumio dhcpcd[1709]: dummy0: carrier acquired Oct 30 16:00:08 volumio dhcpcd[1709]: dummy0: IAID 26:4a:29:2e Oct 30 16:00:08 volumio dhcpcd[1709]: dummy0: adding address fe80::5463:7ba1:21d7:5d28 Oct 30 16:00:08 volumio dhcpcd[1709]: ipv6_addaddr1: Permission denied Oct 30 16:00:08 volumio dhcpcd[1709]: eth0: carrier acquired Oct 30 16:00:08 volumio dhcpcd[1709]: eth0: IAID 36:a1:5d:57 Oct 30 16:00:08 volumio dhcpcd[1709]: eth0: adding address fe80::2a3f:c060:60b3:8e33 Oct 30 16:00:08 volumio dhcpcd[1709]: ipv6_addaddr1: Permission denied Oct 30 16:00:08 volumio dhcpcd[1709]: wlan1: carrier acquired Oct 30 16:00:08 volumio dhcpcd[1709]: wlan1: IAID 28:39:5f:be Oct 30 16:00:08 volumio dhcpcd[1709]: wlan1: IAID conflicts with one assigned to wlan0 Oct 30 16:00:08 volumio dhcpcd[1709]: wlan1: adding address fe80::3a53:4f7a:bd80:1385 Oct 30 16:00:08 volumio dhcpcd[1709]: ipv6_addaddr1: Permission denied Oct 30 16:00:08 volumio dhcpcd[1709]: eth0: carrier lost Oct 30 16:00:08 volumio ifplugd[1692]: Network Interface Plugging Daemon...start eth0...done. Oct 30 16:00:08 volumio systemd[1]: Started LSB: Brings up/down network automatically. Oct 30 16:00:08 volumio sh[1200]: wlan0=wlan0 Oct 30 16:00:08 volumio dhcpcd[1709]: wlan1: soliciting a DHCP lease Oct 30 16:00:08 volumio dhcpcd[1709]: wlan0: soliciting an IPv6 router Oct 30 16:00:08 volumio wireless.js[1264]: Cleaning previous... Oct 30 16:00:08 volumio dhcpcd[1709]: dummy0: soliciting a DHCP lease Oct 30 16:00:09 volumio sudo[1796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 30 16:00:09 volumio kernel: rk_pcie_establish_link: 131 callbacks suppressed Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:09 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio sudo[1805]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] dhd_pri_stop : tx queue stopped Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] dhd_stop : Enter Oct 30 16:00:09 volumio dhcpcd[1709]: wlan0: carrier lost Oct 30 16:00:09 volumio kernel: [dhd] dhd_stop: ######### called for ifidx=0 ######### Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] dhd_stop : static if operational. skip chip reset. Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] dhd_stop : Exit Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio wireless.js[1264]: Stopped aP Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio ntpd[1712]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 30 16:00:09 volumio sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 30 16:00:09 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Linking... LTSSM is 0x3 Oct 30 16:00:09 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:09 volumio dhcpcd[1709]: wlan0: carrier acquired Oct 30 16:00:09 volumio kernel: [dhd] dhd_pri_open : no mutex held Oct 30 16:00:09 volumio kernel: [dhd] dhd_pri_open : set mutex lock Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] dhd_open : Primary net_device is already up Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] dhd_pri_open : tx queue started Oct 30 16:00:09 volumio kernel: [dhd] dhd_pri_open : mutex is released. Oct 30 16:00:09 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:09 volumio dhcpcd[1709]: wlan0: IAID 28:39:5f:be Oct 30 16:00:09 volumio dhcpcd[1709]: wlan0: IAID conflicts with one assigned to wlan1 Oct 30 16:00:09 volumio sudo[1836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 30 16:00:09 volumio sudo[1836]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:09 volumio sudo[1836]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:09 volumio sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 30 16:00:09 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:09 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:09 volumio sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 30 16:00:09 volumio sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:09 volumio dhcpcd[1709]: dummy0: soliciting an IPv6 router Oct 30 16:00:09 volumio kernel: [dhd] [wlan0] wl_run_escan : LEGACY_SCAN sync ID: 0, bssidx: 0 Oct 30 16:00:09 volumio dhcpcd[1709]: wlan1: soliciting an IPv6 router Oct 30 16:00:10 volumio dhcpcd[1709]: wlan0: soliciting an IPv6 router Oct 30 16:00:10 volumio dhcpcd[1709]: wlan0: soliciting a DHCP lease Oct 30 16:00:10 volumio ntpd[1712]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 30 16:00:10 volumio kernel: rk-pcie 3c0000000.pcie: PCIe Link Fail, LTSSM is 0x3, hw_retries=1 Oct 30 16:00:11 volumio ntpd[1712]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 30 16:00:11 volumio kernel: rk-pcie 3c0000000.pcie: failed to initialize host Oct 30 16:00:11 volumio dhcpcd[1709]: eth0: carrier acquired Oct 30 16:00:11 volumio kernel: rk_gmac-dwmac fe010000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Oct 30 16:00:11 volumio dhcpcd[1709]: eth0: IAID 36:a1:5d:57 Oct 30 16:00:11 volumio ifplugd(eth0)[1734]: Link beat detected. Oct 30 16:00:11 volumio ifplugd(eth0)[1734]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 30 16:00:11 volumio ifplugd(eth0)[1734]: client: ifup: interface eth0 already configured Oct 30 16:00:11 volumio sudo[1859]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:11 volumio dhcpcd[1709]: eth0: rebinding lease of 192.168.5.225 Oct 30 16:00:11 volumio dhcpcd[1709]: eth0: NAK: address in use from 192.168.5.1 Oct 30 16:00:11 volumio dhcpcd[1709]: eth0: message: address in use Oct 30 16:00:11 volumio wireless.js[1264]: SETTING APPROPRIATE REG DOMAIN: CN Oct 30 16:00:11 volumio dhcpcd[1709]: eth0: soliciting a DHCP lease Oct 30 16:00:11 volumio sudo[1885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 30 16:00:11 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:11 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:11 volumio ifplugd(eth0)[1734]: Program executed successfully. Oct 30 16:00:11 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Oct 30 16:00:12 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:12 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:12 volumio wireless.js[1264]: SUCCESSFULLY SET NEW REGDOMAIN: CN Oct 30 16:00:12 volumio wireless.js[1264]: Start wireless flow Oct 30 16:00:12 volumio wireless.js[1264]: Stopped hotspot (if there).. Oct 30 16:00:12 volumio sudo[1906]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 30 16:00:12 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:12 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:12 volumio sudo[1915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 30 16:00:12 volumio sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] dhd_pri_stop : tx queue stopped Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] dhd_stop : Enter Oct 30 16:00:12 volumio dhcpcd[1709]: wlan0: carrier lost Oct 30 16:00:12 volumio sudo[1915]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:12 volumio kernel: [dhd] dhd_stop: ######### called for ifidx=0 ######### Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] dhd_stop : static if operational. skip chip reset. Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] dhd_stop : Exit Oct 30 16:00:12 volumio ntpd[1712]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 30 16:00:12 volumio wireless.js[1264]: DHCP IP Oct 30 16:00:12 volumio wireless.js[1264]: Start ap Oct 30 16:00:12 volumio wpa_supplicant[1933]: Successfully initialized wpa_supplicant Oct 30 16:00:12 volumio wpa_supplicant[1933]: nl80211: kernel reports: Authentication algorithm number required Oct 30 16:00:12 volumio dhcpcd[1709]: wlan0: carrier acquired Oct 30 16:00:12 volumio kernel: [dhd] dhd_pri_open : no mutex held Oct 30 16:00:12 volumio kernel: [dhd] dhd_pri_open : set mutex lock Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] dhd_open : Primary net_device is already up Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] dhd_pri_open : tx queue started Oct 30 16:00:12 volumio kernel: [dhd] dhd_pri_open : mutex is released. Oct 30 16:00:12 volumio sudo[1938]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 30 16:00:12 volumio dhcpcd[1709]: wlan0: IAID 28:39:5f:be Oct 30 16:00:12 volumio dhcpcd[1709]: wlan0: IAID conflicts with one assigned to wlan1 Oct 30 16:00:12 volumio dhcpcd[1709]: wlan0: carrier lost Oct 30 16:00:12 volumio sudo[1938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:12 volumio dhcpcd[1952]: sending commands to master dhcpcd process Oct 30 16:00:12 volumio dhcpcd[1709]: control command: /sbin/dhcpcd Oct 30 16:00:12 volumio sudo[1938]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:12 volumio kernel: [dhd] P2P interface registered Oct 30 16:00:12 volumio wpa_supplicant[1933]: nl80211: kernel reports: Registration to specific type not supported Oct 30 16:00:12 volumio kernel: [41B blob data] Oct 30 16:00:12 volumio kernel: [dhd] P2P interface started Oct 30 16:00:12 volumio kernel: [dhd] [wlan0] wl_run_escan : LEGACY_SCAN sync ID: 1, bssidx: 0 Oct 30 16:00:12 volumio dhcpcd[1709]: eth0: soliciting an IPv6 router Oct 30 16:00:13 volumio systemd[1]: systemd-rfkill.service: Succeeded. Oct 30 16:00:13 volumio wireless.js[1264]: trying... Oct 30 16:00:13 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:13 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:13 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:13 volumio kernel: ttyFIQ ttyFIQ0: tty_port_close_start: tty->count = 1 port count = 2 Oct 30 16:00:13 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:13] [info] asio async_connect error: system:111 (Connection refused) Oct 30 16:00:13 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 16:00:13 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:13] [error] handle_connect error: Underlying Transport Error Oct 30 16:00:13 volumio dhcpcd[1709]: wlan1: probing for an IPv4LL address Oct 30 16:00:14 volumio wireless.js[1264]: trying... Oct 30 16:00:14 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:14 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:14 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:14 volumio wpa_supplicant[1958]: wlan0: Trying to associate with SSID '2lou' Oct 30 16:00:14 volumio wpa_supplicant[1958]: nl80211: kernel reports: Authentication algorithm number required Oct 30 16:00:14 volumio kernel: [dhd] do_iovar_aml_enable aml failed -23 Oct 30 16:00:14 volumio kernel: [dhd] CFG80211-ERROR) wl_set_set_cipher : set wsec_info error (0) Oct 30 16:00:14 volumio kernel: [dhd] CFG80211-ERROR) wl_set_set_cipher : set wsec_info error (0) Oct 30 16:00:14 volumio kernel: [dhd] [wlan0] wl_conn_debug_info : Connecting with 3a:ce:e8:8f:60:e0 ssid "2lou", len (4), channel=2g-6(chan_cnt=1), sec=wpa2/psk/mfpn/aes, rssi=-54 Oct 30 16:00:14 volumio kernel: [dhd] [wlan0] wl_iw_event : Link UP with 3a:ce:e8:8f:60:e0 Oct 30 16:00:14 volumio kernel: [dhd] [wlan0] wl_ext_iapsta_link : [S] Link UP with 3a:ce:e8:8f:60:e0 Oct 30 16:00:14 volumio wpa_supplicant[1958]: wlan0: Associated with 3a:ce:e8:8f:60:e0 Oct 30 16:00:14 volumio kernel: [dhd] [wlan0] wl_bss_connect_done : Report connect result - connection succeeded Oct 30 16:00:14 volumio wpa_supplicant[1958]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 30 16:00:14 volumio kernel: [dhd] [wlan0] wl_add_keyext : key index (0) for 3a:ce:e8:8f:60:e0 Oct 30 16:00:14 volumio wpa_supplicant[1958]: wlan0: WPA: Key negotiation completed with 3a:ce:e8:8f:60:e0 [PTK=CCMP GTK=CCMP] Oct 30 16:00:14 volumio wpa_supplicant[1958]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3a:ce:e8:8f:60:e0 completed [id=0 id_str=] Oct 30 16:00:14 volumio dhcpcd[1709]: wlan0: carrier acquired Oct 30 16:00:14 volumio kernel: [dhd] [wlan0] wl_cfg80211_set_suspend_bcn_li_dtim : bcn_li_dtim:0 lpas:0 bcn_to_dly:0 Oct 30 16:00:14 volumio dhcpcd[1709]: wlan0: IAID 28:39:5f:be Oct 30 16:00:14 volumio dhcpcd[1709]: wlan0: IAID conflicts with one assigned to wlan1 Oct 30 16:00:14 volumio dhcpcd[1709]: eth0: offered 192.168.5.110 from 192.168.5.1 Oct 30 16:00:14 volumio dhcpcd[1709]: eth0: probing address 192.168.5.110/24 Oct 30 16:00:15 volumio dhcpcd[1709]: wlan0: soliciting an IPv6 router Oct 30 16:00:15 volumio wireless.js[1264]: trying... Oct 30 16:00:15 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:15 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:15 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:15 volumio dhcpcd[1709]: wlan0: rebinding lease of 10.0.0.189 Oct 30 16:00:15 volumio dhcpcd[1709]: wlan0: probing address 10.0.0.189/24 Oct 30 16:00:16 volumio wireless.js[1264]: trying... Oct 30 16:00:16 volumio sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:16 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:16 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:17 volumio wireless.js[1264]: trying... Oct 30 16:00:17 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:17 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:17 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:18 volumio wireless.js[1264]: trying... Oct 30 16:00:18 volumio sudo[2011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:18 volumio sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:18 volumio sudo[2011]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:18 volumio dhcpcd[1709]: wlan1: using IPv4LL address 169.254.28.255 Oct 30 16:00:18 volumio avahi-daemon[1466]: Joining mDNS multicast group on interface wlan1.IPv4 with address 169.254.28.255. Oct 30 16:00:18 volumio avahi-daemon[1466]: New relevant interface wlan1.IPv4 for mDNS. Oct 30 16:00:18 volumio dhcpcd[1709]: wlan1: adding route to 169.254.0.0/16 Oct 30 16:00:18 volumio avahi-daemon[1466]: Registering new address record for 169.254.28.255 on wlan1.IPv4. Oct 30 16:00:18 volumio dhcpcd[1709]: wlan1: adding default route Oct 30 16:00:19 volumio wireless.js[1264]: trying... Oct 30 16:00:19 volumio sudo[2030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:19 volumio sudo[2030]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:19 volumio sudo[2030]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:19 volumio wireless.js[1264]: Connected to: ---- Oct 30 16:00:19 volumio wireless.js[1264]: ---- Oct 30 16:00:19 volumio sudo[2040]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:19 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:19 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:19 volumio wireless.js[1264]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Oct 30 16:00:20 volumio ntpd[1712]: Listen normally on 3 wlan1 169.254.28.255:123 Oct 30 16:00:20 volumio ntpd[1712]: new interface(s) found: waking up resolver Oct 30 16:00:20 volumio wireless.js[1264]: trying... Oct 30 16:00:20 volumio sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:20 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:20 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:20 volumio wireless.js[1264]: Connected to: ---- Oct 30 16:00:20 volumio wireless.js[1264]: ---- Oct 30 16:00:20 volumio sudo[2060]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:20 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:20 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:20 volumio dhcpcd[1709]: eth0: leased 192.168.5.110 for 43200 seconds Oct 30 16:00:20 volumio dhcpcd[1709]: eth0: adding route to 192.168.5.0/24 Oct 30 16:00:20 volumio avahi-daemon[1466]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.5.110. Oct 30 16:00:20 volumio dhcpcd[1709]: eth0: adding default route via 192.168.5.1 Oct 30 16:00:20 volumio avahi-daemon[1466]: New relevant interface eth0.IPv4 for mDNS. Oct 30 16:00:20 volumio dhcpcd[1709]: wlan1: deleting default route Oct 30 16:00:20 volumio avahi-daemon[1466]: Registering new address record for 192.168.5.110 on eth0.IPv4. Oct 30 16:00:20 volumio wireless.js[1264]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Oct 30 16:00:20 volumio ntpd[1712]: ntpd exiting on signal 15 (Terminated) Oct 30 16:00:20 volumio systemd[1]: Stopping Network Time Service... Oct 30 16:00:20 volumio systemd[1]: ntp.service: Succeeded. Oct 30 16:00:20 volumio systemd[1]: Stopped Network Time Service. Oct 30 16:00:20 volumio systemd[1]: Starting Network Time Service... Oct 30 16:00:20 volumio ntpd[2103]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 30 16:00:20 volumio ntpd[2103]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 30 16:00:20 volumio systemd[1]: Started Network Time Service. Oct 30 16:00:20 volumio ntpd[2109]: proto: precision = 0.583 usec (-21) Oct 30 16:00:20 volumio dhcpcd[1709]: wlan0: leased 10.0.0.189 for 43200 seconds Oct 30 16:00:20 volumio ntpd[2109]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 30 16:00:20 volumio ntpd[2109]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 30 16:00:20 volumio ntpd[2109]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 673 days ago Oct 30 16:00:20 volumio avahi-daemon[1466]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.189. Oct 30 16:00:20 volumio ntpd[2109]: Listen and drop on 0 v6wildcard [::]:123 Oct 30 16:00:20 volumio ntpd[2109]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 30 16:00:20 volumio avahi-daemon[1466]: New relevant interface wlan0.IPv4 for mDNS. Oct 30 16:00:20 volumio dhcpcd[1709]: wlan0: adding route to 10.0.0.0/24 Oct 30 16:00:20 volumio avahi-daemon[1466]: Registering new address record for 10.0.0.189 on wlan0.IPv4. Oct 30 16:00:20 volumio dhcpcd[1709]: wlan0: adding default route via 10.0.0.1 Oct 30 16:00:20 volumio ntpd[2109]: Listen normally on 2 lo 127.0.0.1:123 Oct 30 16:00:20 volumio ntpd[2109]: Listen normally on 3 eth0 192.168.5.110:123 Oct 30 16:00:20 volumio ntpd[2109]: Listen normally on 4 wlan0 10.0.0.189:123 Oct 30 16:00:20 volumio ntpd[2109]: Listen normally on 5 wlan1 169.254.28.255:123 Oct 30 16:00:20 volumio ntpd[2109]: Listening on routing socket on fd #22 for interface updates Oct 30 16:00:20 volumio ntpd[2109]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 16:00:20 volumio ntpd[2109]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 16:00:20 volumio ntpd[2109]: ntpd exiting on signal 15 (Terminated) Oct 30 16:00:20 volumio systemd[1]: Stopping Network Time Service... Oct 30 16:00:20 volumio systemd[1]: ntp.service: Succeeded. Oct 30 16:00:20 volumio systemd[1]: Stopped Network Time Service. Oct 30 16:00:20 volumio systemd[1]: Starting Network Time Service... Oct 30 16:00:20 volumio ntpd[2145]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 30 16:00:20 volumio ntpd[2145]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 30 16:00:20 volumio systemd[1]: Started Network Time Service. Oct 30 16:00:20 volumio ntpd[2151]: proto: precision = 0.875 usec (-20) Oct 30 16:00:20 volumio ntpd[2151]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 30 16:00:20 volumio ntpd[2151]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 30 16:00:20 volumio ntpd[2151]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 673 days ago Oct 30 16:00:20 volumio ntpd[2151]: Listen and drop on 0 v6wildcard [::]:123 Oct 30 16:00:20 volumio ntpd[2151]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 30 16:00:20 volumio ntpd[2151]: Listen normally on 2 lo 127.0.0.1:123 Oct 30 16:00:20 volumio ntpd[2151]: Listen normally on 3 eth0 192.168.5.110:123 Oct 30 16:00:20 volumio ntpd[2151]: Listen normally on 4 wlan0 10.0.0.189:123 Oct 30 16:00:20 volumio ntpd[2151]: Listen normally on 5 wlan1 169.254.28.255:123 Oct 30 16:00:20 volumio ntpd[2151]: Listening on routing socket on fd #22 for interface updates Oct 30 16:00:20 volumio ntpd[2151]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 16:00:20 volumio ntpd[2151]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 16:00:21 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:21] [info] asio async_connect error: system:111 (Connection refused) Oct 30 16:00:21 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 16:00:21 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:21] [error] handle_connect error: Underlying Transport Error Oct 30 16:00:21 volumio wireless.js[1264]: trying... Oct 30 16:00:21 volumio sudo[2154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 16:00:21 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:21 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:21 volumio wireless.js[1264]: Connected to: ----2lou Oct 30 16:00:21 volumio wireless.js[1264]: ---- Oct 30 16:00:21 volumio sudo[2164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:21 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:21 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:21 volumio wireless.js[1264]: ... joined AP, wlan0 IPv4 is 10.0.0.189, ipV6 is undefined Oct 30 16:00:21 volumio wireless.js[1264]: It's done! AP Oct 30 16:00:21 volumio systemd[1]: Started Wireless Services. Oct 30 16:00:21 volumio systemd[1]: Started Volumio Backend Module. Oct 30 16:00:21 volumio systemd[1]: Started Volumio Cpu Tweaker. Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: Setting RT Priority for mpd Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: pid 35's current scheduling policy: SCHED_OTHER Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: pid 35's current scheduling priority: 0 Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: Setting MPD Affinity Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: pid 3's current affinity mask: f Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 30 16:00:21 volumio volumio-cpu-tweak[2177]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 30 16:00:21 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 30 16:00:21 volumio ntpd[2151]: Soliciting pool server 162.159.200.1 Oct 30 16:00:22 volumio ntpd[2151]: Soliciting pool server 162.159.200.123 Oct 30 16:00:22 volumio ntpd[2151]: Soliciting pool server 144.126.242.176 Oct 30 16:00:23 volumio kernel: ttyFIQ ttyFIQ0: tty_port_close_start: tty->count = 1 port count = 2 Oct 30 16:00:23 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:23 volumio volumio[2176]: info: ----- Volumio3 ---- Oct 30 16:00:23 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:23 volumio volumio[2176]: info: ----- System startup ---- Oct 30 16:00:23 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:23 volumio nmbd[1717]: [2024/10/30 16:00:23.666057, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 16:00:23 volumio kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. Oct 30 16:00:23 volumio nmbd[1717]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 30 16:00:23 volumio systemd[1]: Started Samba NMB Daemon. Oct 30 16:00:23 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 30 16:00:23 volumio ntpd[2151]: Soliciting pool server 47.241.41.246 Oct 30 16:00:23 volumio ntpd[2151]: Soliciting pool server 51.79.159.86 Oct 30 16:00:23 volumio ntpd[2151]: Soliciting pool server 193.182.111.143 Oct 30 16:00:23 volumio winbindd[2203]: [2024/10/30 16:00:23.943558, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 30 16:00:23 volumio winbindd[2203]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 30 16:00:23 volumio winbindd[2203]: [2024/10/30 16:00:23.952946, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 16:00:23 volumio winbindd[2203]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 30 16:00:23 volumio systemd[1]: Started Samba Winbind Daemon. Oct 30 16:00:23 volumio systemd[1]: Starting Samba SMB Daemon... Oct 30 16:00:24 volumio smbd[2208]: [2024/10/30 16:00:24.367443, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 16:00:24 volumio smbd[2208]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 30 16:00:24 volumio systemd[1]: Started Samba SMB Daemon. Oct 30 16:00:24 volumio systemd[1]: Reached target Multi-User System. Oct 30 16:00:24 volumio systemd[1]: Reached target Graphical Interface. Oct 30 16:00:24 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 30 16:00:24 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 30 16:00:24 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 30 16:00:24 volumio systemd[1]: Startup finished in 2.569s (kernel) + 19.330s (userspace) = 21.900s. Oct 30 16:00:24 volumio kernel: ttyFIQ ttyFIQ0: tty_port_close_start: tty->count = 1 port count = 2 Oct 30 16:00:24 volumio volumio[2176]: info: MYVOLUMIO Environment detected Oct 30 16:00:24 volumio login[2202]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Oct 30 16:00:24 volumio login[2201]: pam_unix(login:session): session opened for user root by LOGIN(uid=0) Oct 30 16:00:24 volumio systemd[1]: Created slice User Slice of UID 0. Oct 30 16:00:24 volumio systemd[1]: Starting User Runtime Directory /run/user/0... Oct 30 16:00:24 volumio systemd-logind[1442]: New session c1 of user root. Oct 30 16:00:24 volumio volumio[2176]: info: Plugin folders cleanup Oct 30 16:00:24 volumio volumio[2176]: info: Scanning into folder /volumio/app/plugins/ Oct 30 16:00:24 volumio volumio[2176]: info: Scanning category audio_interface Oct 30 16:00:24 volumio volumio[2176]: info: Scanning category miscellanea Oct 30 16:00:24 volumio volumio[2176]: info: Scanning category music_service Oct 30 16:00:24 volumio volumio[2176]: info: Scanning category plugins.json Oct 30 16:00:24 volumio volumio[2176]: info: Scanning category system_controller Oct 30 16:00:24 volumio volumio[2176]: info: Scanning category user_interface Oct 30 16:00:24 volumio volumio[2176]: info: Scanning into folder /data/plugins/ Oct 30 16:00:24 volumio volumio[2176]: info: Plugin folders cleanup completed Oct 30 16:00:24 volumio systemd[1]: Started User Runtime Directory /run/user/0. Oct 30 16:00:24 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:24 volumio volumio[2176]: info: ----- Core plugins startup ---- Oct 30 16:00:24 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:24 volumio volumio[2176]: info: Loading plugins from folder /volumio/app/plugins/ Oct 30 16:00:24 volumio systemd[1]: Starting User Manager for UID 0... Oct 30 16:00:24 volumio volumio[2176]: info: Adding plugin upnp to MyMusic Plugins Oct 30 16:00:24 volumio volumio[2176]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 30 16:00:24 volumio volumio[2176]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 30 16:00:24 volumio volumio[2176]: info: Loading plugins from folder /data/plugins/ Oct 30 16:00:24 volumio volumio[2176]: info: Loading plugin "system"... Oct 30 16:00:24 volumio systemd[2231]: pam_unix(systemd-user:session): session opened for user root by (uid=0) Oct 30 16:00:24 volumio volumio[2176]: info: Loading plugin "appearance"... Oct 30 16:00:24 volumio systemd[2231]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Oct 30 16:00:24 volumio systemd[2231]: Reached target Paths. Oct 30 16:00:24 volumio systemd[2231]: Listening on GnuPG cryptographic agent and passphrase cache. Oct 30 16:00:24 volumio systemd[2231]: Reached target Timers. Oct 30 16:00:24 volumio systemd[2231]: Condition check resulted in Sound System being skipped. Oct 30 16:00:24 volumio systemd[2231]: Starting D-Bus User Message Bus Socket. Oct 30 16:00:24 volumio systemd[2231]: Listening on GnuPG network certificate management daemon. Oct 30 16:00:24 volumio systemd[2231]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 30 16:00:24 volumio systemd[2231]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Oct 30 16:00:24 volumio systemd[2231]: Listening on D-Bus User Message Bus Socket. Oct 30 16:00:24 volumio systemd[2231]: Reached target Sockets. Oct 30 16:00:24 volumio systemd[2231]: Reached target Basic System. Oct 30 16:00:24 volumio systemd[1]: Started User Manager for UID 0. Oct 30 16:00:24 volumio systemd[2231]: Starting Music Player Daemon... Oct 30 16:00:24 volumio systemd[1]: Started Session c1 of user root. Oct 30 16:00:24 volumio ntpd[2151]: Soliciting pool server 54.251.47.36 Oct 30 16:00:24 volumio ntpd[2151]: Soliciting pool server 54.251.47.36 Oct 30 16:00:24 volumio ntpd[2151]: Soliciting pool server 193.182.111.142 Oct 30 16:00:24 volumio login[2251]: ROOT LOGIN on '/dev/ttyFIQ0' Oct 30 16:00:25 volumio login[2202]: pam_unix(login:session): session opened for user root by LOGIN(uid=0) Oct 30 16:00:25 volumio systemd-logind[1442]: New session c2 of user root. Oct 30 16:00:25 volumio systemd[1]: Started Session c2 of user root. Oct 30 16:00:25 volumio login[2264]: ROOT LOGIN on '/dev/tty1' Oct 30 16:00:25 volumio ntpd[2151]: Soliciting pool server 137.184.250.82 Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "network"... Oct 30 16:00:26 volumio volumio[2176]: info: Refreshing Cached IP Addresses Oct 30 16:00:26 volumio sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "services"... Oct 30 16:00:26 volumio sudo[2270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "alsa_controller"... Oct 30 16:00:26 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:26 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:26 volumio sudo[2270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:26 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 30 16:00:26 volumio sudo[2270]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:26 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:26 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:26 volumio volumio[2176]: warn: The ALSA output card is not set, defaulting to card null Oct 30 16:00:26 volumio volumio[2176]: warn: Unable to locate the audio output device 没有可用的音频设备. Please configure a valid output device. Oct 30 16:00:26 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "wizard"... Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "networkfs"... Oct 30 16:00:26 volumio kernel: [dhd] [wlan0] wl_escan_set_scan : LEGACY_SCAN Oct 30 16:00:26 volumio kernel: [dhd] CFG80211-ERROR) wl_escan_handler : escan is not ready. drv_scan_status 0x0 e_type 69 e_status 8 Oct 30 16:00:26 volumio kernel: [dhd] CFG80211-ERROR) wl_escan_handler : [Repeats 0 times] Oct 30 16:00:26 volumio volumio[2176]: info: Starting Udev Watcher for removable devices Oct 30 16:00:26 volumio volumio[2176]: error: Cannot associate FS Label, not mounting Oct 30 16:00:26 volumio volumio[2176]: info: Ignoring mount for partition: 57f8f4bc-abf4-655f-bf67-946fc0f9f25b Oct 30 16:00:26 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "volumio_command_line_client"... Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "upnp"... Oct 30 16:00:26 volumio volumio[2176]: info: [1730275226432] Starting Upmpd Daemon Oct 30 16:00:26 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "my_music"... Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "mpd"... Oct 30 16:00:26 volumio ntpd[2151]: Soliciting pool server 17.253.60.253 Oct 30 16:00:26 volumio volumio[2176]: info: Loading plugin "upnp_browser"... Oct 30 16:00:27 volumio mpd[2247]: Oct 30 16:00 : server_socket: bind to '0.0.0.0:6600' failed (continuing anyway, because binding to '[::]:6600' succeeded): Failed to bind socket: Address already in use Oct 30 16:00:27 volumio mpd[2247]: Oct 30 16:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "alarm-clock"... Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "airplay_emulation"... Oct 30 16:00:27 volumio volumio[2176]: info: Starting Shairport Sync Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "last_100"... Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "webradio"... Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "i2s_dacs"... Oct 30 16:00:27 volumio volumio[2176]: info: I2S DAC not set, start Auto-detection Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "volumiodiscovery"... Oct 30 16:00:27 volumio volumio[2176]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 16:00:27 volumio volumio[2176]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 16:00:27 volumio volumio[2176]: *** WARNING *** For more information see Oct 30 16:00:27 volumio volumio[2176]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 16:00:27 volumio node[2176]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 16:00:27 volumio volumio[2176]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 16:00:27 volumio volumio[2176]: *** WARNING *** For more information see Oct 30 16:00:27 volumio node[2176]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 16:00:27 volumio node[2176]: *** WARNING *** For more information see Oct 30 16:00:27 volumio node[2176]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 16:00:27 volumio node[2176]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 16:00:27 volumio node[2176]: *** WARNING *** For more information see Oct 30 16:00:27 volumio volumio[2176]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 30 16:00:27 volumio volumio[2176]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/volumiodiscovery/config.json' Oct 30 16:00:27 volumio volumio[2176]: info: Discovery: Started advertising with name: Volumio Oct 30 16:00:27 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "outputs"... Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "albumart"... Oct 30 16:00:27 volumio volumio[2176]: info: Plugin example_plugin is not enabled Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "inputs"... Oct 30 16:00:27 volumio volumio[2176]: info: Loading plugin "updater_comm"... Oct 30 16:00:27 volumio ntpd[2151]: Soliciting pool server 106.10.186.201 Oct 30 16:00:28 volumio volumio[2176]: info: Plugin mpdemulation is not enabled Oct 30 16:00:28 volumio volumio[2176]: info: Loading plugin "rest_api"... Oct 30 16:00:28 volumio volumio[2176]: info: Loading plugin "websocket"... Oct 30 16:00:28 volumio volumio[2176]: info: Starting Socket.io Server version 2.3.0 Oct 30 16:00:28 volumio volumio[2176]: info: Loading i18n strings for locale zh Oct 30 16:00:28 volumio volumio[2176]: Updating browse sources language Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:28 volumio volumio[2176]: Forking 3 albumart workers Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::initPlayerControls Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: Express server listening on port 3000 Oct 30 16:00:28 volumio volumio[2176]: [Metrics] WebUI: 5s 503.41ms Oct 30 16:00:28 volumio volumio[2176]: info: CoreStateMachine::resetVolumioState Oct 30 16:00:28 volumio volumio[2176]: info: CoreStateMachine::getcurrentVolume Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 30 16:00:28 volumio volumio[2176]: info: Completed loading Core Plugins Oct 30 16:00:28 volumio volumio[2176]: info: Preparing to generate the ALSA configuration file Oct 30 16:00:28 volumio volumio[2176]: info: Cannot read play queue from file Oct 30 16:00:28 volumio volumio[2176]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 30 16:00:28 volumio sudo[2276]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:28 volumio volumio[2176]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 30 16:00:28 volumio volumio[2176]: info: VolumeController:: Volume=undefined Mute =false Oct 30 16:00:28 volumio volumio[2176]: info: CoreStateMachine::pushState Oct 30 16:00:28 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:28 volumio volumio[2176]: info: CoreStateMachine::updateTrackBlock Oct 30 16:00:28 volumio volumio[2176]: info: CorePlayQueue::getTrackBlock Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 16:00:28 volumio volumio[2176]: info: Asound.conf file unchanged, so no further update is needed Oct 30 16:00:28 volumio volumio[2176]: info: Output device has changed, restarting MPD Oct 30 16:00:28 volumio volumio[2176]: info: Output device has changed, restarting Shairport Sync Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:28 volumio sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 16:00:28 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:28 volumio volumio[2176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 16:00:28 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:28 volumio volumio[2176]: info: ___________ START PLUGINS ___________ Oct 30 16:00:28 volumio sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 16:00:28 volumio volumio[2176]: info: ControllerMpd::onStart: Initializing MPD Oct 30 16:00:28 volumio volumio[2176]: info: Creating MPD Configuration file Oct 30 16:00:28 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:28 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 16:00:28 volumio volumio[2176]: info: [1730275228819] CoreMusicLibrary::Adding element 媒体服务器 Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:28 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:28 volumio sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio sudo[2370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:28 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:28 volumio systemd[2382]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Oct 30 16:00:28 volumio systemd[1]: mpd.socket: Failed to receive listening socket ([::]:6600): Input/output error Oct 30 16:00:28 volumio systemd[1]: mpd.socket: Failed to listen on sockets: Input/output error Oct 30 16:00:28 volumio sudo[2370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:28 volumio systemd[1]: mpd.socket: Failed with result 'resources'. Oct 30 16:00:28 volumio systemd[1]: Failed to listen on mpd.socket. Oct 30 16:00:28 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:28 volumio volumio[2176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 16:00:28 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:28 volumio systemd[1]: Starting Music Player Daemon... Oct 30 16:00:28 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:28 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:28 volumio volumio[2176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 16:00:28 volumio volumio[2176]: aplay: device_list:272: no soundcards found... Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 16:00:28 volumio volumio[2176]: info: [1730275228974] CoreMusicLibrary::Adding element Last_100 Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 16:00:28 volumio volumio[2176]: info: [1730275228980] CoreMusicLibrary::Adding element Webradio Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:28 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 30 16:00:28 volumio systemd[1]: mpd.service: Succeeded. Oct 30 16:00:28 volumio systemd[1]: Stopped Music Player Daemon. Oct 30 16:00:28 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 16:00:28 volumio systemd[2396]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Oct 30 16:00:28 volumio systemd[1]: mpd.socket: Failed to receive listening socket ([::]:6600): Input/output error Oct 30 16:00:28 volumio systemd[1]: mpd.socket: Failed to listen on sockets: Input/output error Oct 30 16:00:28 volumio systemd[1]: mpd.socket: Failed with result 'resources'. Oct 30 16:00:29 volumio systemd[1]: Failed to listen on mpd.socket. Oct 30 16:00:29 volumio volumio[2176]: info: Initializing BBC Radios Oct 30 16:00:29 volumio systemd[1]: Starting Music Player Daemon... Oct 30 16:00:29 volumio volumio[2176]: Starting albumart workers Oct 30 16:00:29 volumio sudo[2397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 16:00:29 volumio volumio[2176]: Starting albumart workers Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: Volumio Calling Home Oct 30 16:00:29 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:29 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:29 volumio volumio[2176]: Starting albumart workers Oct 30 16:00:29 volumio volumio[2176]: info: CoreStateMachine::setRepeat null single undefined Oct 30 16:00:29 volumio volumio[2176]: info: CoreStateMachine::pushState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:29 volumio volumio[2176]: info: CoreStateMachine::setRandom null Oct 30 16:00:29 volumio volumio[2176]: info: CoreStateMachine::pushState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:29 volumio volumio[2176]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 30 16:00:29 volumio volumio[2176]: info: MPD Permissions set Oct 30 16:00:29 volumio volumio[2176]: info: MPD Permissions set Oct 30 16:00:29 volumio volumio[2176]: info: Discovery: adding 02c43566-8848-4079-8da0-f9992c9e8de5 Oct 30 16:00:29 volumio volumio[2176]: info: Discovery: Found device Volumio Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio volumio[2176]: info: VolumeController:: Volume=undefined Mute =false Oct 30 16:00:29 volumio volumio[2176]: info: CoreStateMachine::pushState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioPushState Oct 30 16:00:29 volumio volumio[2176]: info: Discovery: this is already registered, 02c43566-8848-4079-8da0-f9992c9e8de5 Oct 30 16:00:29 volumio volumio[2176]: info: Discovery: Found device Volumio Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio volumio[2176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 30 16:00:29 volumio volumio[2176]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 16:00:29 volumio ntpd[2151]: receive: Unexpected origin timestamp 0xeacc661d.e14bc9a4 does not match aorg 0000000000.00000000 from server@144.126.242.176 xmt 0xeacc661d.abafd802 Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 16:00:29 volumio ntpd[2151]: receive: Unexpected origin timestamp 0xeacc661d.e1410893 does not match aorg 0000000000.00000000 from server@54.251.47.36 xmt 0xeacc661d.b75185ba Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 16:00:29 volumio volumio[2176]: info: Volumio called home Oct 30 16:00:29 volumio volumio[2176]: info: Discovery: this is already registered, 02c43566-8848-4079-8da0-f9992c9e8de5 Oct 30 16:00:29 volumio volumio[2176]: info: Discovery: Found device Volumio Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio volumio[2176]: info: Starting Shairport Sync Oct 30 16:00:29 volumio volumio[2176]: info: Starting Shairport Sync Oct 30 16:00:29 volumio volumio[2176]: info: Starting Shairport Sync Oct 30 16:00:29 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 16:00:29 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:29 volumio sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 16:00:29 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:29 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:29 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:29 volumio sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 16:00:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 16:00:29 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 30 16:00:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:29 volumio sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:29 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 16:00:29 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 30 16:00:29 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 30 16:00:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:30 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:30 volumio volumio[2176]: info: Shairport-Sync Started Oct 30 16:00:30 volumio volumio[2176]: Error adding Membership: Error: addMembership EINVAL Oct 30 16:00:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 16:00:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 30 16:00:30 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 30 16:00:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 16:00:30 volumio volumio[2176]: info: Shairport-Sync Started Oct 30 16:00:30 volumio sudo[2471]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:30 volumio volumio[2176]: info: Shairport-Sync Started Oct 30 16:00:30 volumio mpd[2418]: Oct 30 16:00 : exception: Failed to bind to '[::]:6600'; Failed to bind socket: Address already in use Oct 30 16:00:30 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Oct 30 16:00:30 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Oct 30 16:00:30 volumio systemd[1]: Failed to start Music Player Daemon. Oct 30 16:00:30 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:30 volumio sudo[2370]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:30 volumio volumio[2176]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 30 16:00:30 volumio volumio[2176]: Job for mpd.service failed because the control process exited with error code. Oct 30 16:00:30 volumio volumio[2176]: See "systemctl status mpd.service" and "journalctl -xe" for details. Oct 30 16:00:30 volumio volumio[2176]: info: Completed starting Core Plugins Oct 30 16:00:30 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:30 volumio volumio[2176]: info: ----- MyVolumio plugins startup ---- Oct 30 16:00:30 volumio volumio[2176]: info: ------------------------------------------- Oct 30 16:00:30 volumio volumio[2176]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 30 16:00:30 volumio volumio[2176]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 30 16:00:30 volumio volumio[2176]: Job for mpd.service failed because the control process exited with error code. Oct 30 16:00:30 volumio volumio[2176]: See "systemctl status mpd.service" and "journalctl -xe" for details. Oct 30 16:00:30 volumio volumio[2176]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Oct 30 16:00:30 volumio volumio[2176]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Oct 30 16:00:30 volumio volumio[2176]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Oct 30 16:00:30 volumio volumio[2176]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Oct 30 16:00:31 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:31] [connect] Successful connection Oct 30 16:00:31 volumio volumio-remote-updater[1381]: [2024-10-30 16:00:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730275231 101 Oct 30 16:00:31 volumio volumio[2176]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Oct 30 16:00:31 volumio volumio[2176]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Oct 30 16:00:35 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 30 16:00:36 volumio kernel: vcc_sd: disabling Oct 30 16:00:36 volumio sudo[2503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 16:00:36 volumio sudo[2503]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:36 volumio sudo[2505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:36 volumio sudo[2503]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:36 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:36 volumio sudo[2513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 30 16:00:36 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:36 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 16:00:36 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:36 volumio volumio[2176]: info: Upmpdcli Daemon Started Oct 30 16:00:36 volumio volumio[2529]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 30 16:00:39 volumio sudo[2549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 16:00:39 volumio sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:39 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:39 volumio sudo[2549]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:39 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:39 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:39 volumio volumio[2176]: verbose: New Socket.io Connection to 192.168.5.110 from 192.168.5.155 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 30 16:00:39 volumio volumio[2176]: info: Adding plugin bluetooth to MyMusic Plugins Oct 30 16:00:39 volumio volumio[2176]: info: Adding plugin multiroom to MyMusic Plugins Oct 30 16:00:39 volumio volumio[2176]: info: Adding plugin metavolumio to MyMusic Plugins Oct 30 16:00:39 volumio volumio[2176]: info: Adding plugin cd_controller to MyMusic Plugins Oct 30 16:00:39 volumio volumio[2176]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 30 16:00:39 volumio volumio[2176]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 30 16:00:39 volumio volumio[2176]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 30 16:00:40 volumio volumio[2529]: ...............................................................................++++ Oct 30 16:00:40 volumio volumio[2176]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 30 16:00:40 volumio volumio[2176]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 30 16:00:40 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:40 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 16:00:40 volumio volumio[2176]: info: Starting MyVolumio Remote Streaming Endpoints Oct 30 16:00:40 volumio volumio[2176]: info: MyVolumio not started Oct 30 16:00:40 volumio volumio[2176]: info: Initializing device activation check Oct 30 16:00:40 volumio volumio[2176]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 30 16:00:40 volumio volumio[2176]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 30 16:00:42 volumio volumio[2176]: info: Starting Streaming Service Transparent Proxy Oct 30 16:00:42 volumio volumio[2176]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 30 16:00:42 volumio volumio[2176]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 30 16:00:42 volumio volumio[2176]: info: Streaming services startup Oct 30 16:00:42 volumio volumio[2176]: info: Starting Streaming Daemon Oct 30 16:00:42 volumio sudo[2575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 16:00:42 volumio volumio[2176]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 30 16:00:42 volumio sudo[2575]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:42 volumio sudo[2575]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:42 volumio volumio[2176]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Oct 30 16:00:42 volumio sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 16:00:42 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:42 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:42 volumio volumio[2176]: error: Cannot start Volumio Streaming Daemon Oct 30 16:00:42 volumio sudo[2594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 16:00:42 volumio volumio[2176]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 16:00:42 volumio volumio[2176]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 16:00:42 volumio sudo[2594]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 16:00:42 volumio sudo[2594]: pam_unix(sudo:session): session closed for user root Oct 30 16:00:42 volumio volumio[2176]: verbose: New Socket.io Connection to 192.168.5.110 from 192.168.5.155 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:42 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 16:00:42 volumio volumio[2176]: info: Received Get System Info Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 16:00:42 volumio volumio[2176]: info: Discovery: Getting this device information Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:42 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:42 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:42 volumio volumio[2176]: info: Listing playlists Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 16:00:42 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 16:00:43 volumio volumio[2176]: STREAMING PROXY: Starting server on port 3245 Oct 30 16:00:43 volumio volumio[2176]: Node JS runtime: 14 Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:43 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 16:00:43 volumio volumio[2176]: info: Received Get System Info Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 16:00:43 volumio volumio[2176]: info: Discovery: Getting this device information Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::volumioGetState Oct 30 16:00:43 volumio volumio[2176]: info: CorePlayQueue::getTrack 0 Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 16:00:43 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 30 16:00:47 volumio volumio[2529]: .......................................................................................................................................++++ Oct 30 16:00:47 volumio volumio[2529]: e is 65537 (0x010001) Oct 30 16:00:47 volumio volumio[2529]: writing RSA key Oct 30 16:00:48 volumio volumio[2176]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 30 16:00:48 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 30 16:00:48 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 30 16:00:48 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 30 16:00:48 volumio volumio[2176]: info: Received Get System Version Oct 30 16:00:48 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 16:00:51 volumio volumio[2176]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Oct 30 16:00:51 volumio volumio[2176]: /usr/bin/touch: cannot touch '/data/wizard': Permission denied Oct 30 16:00:51 volumio volumio[2176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 16:00:51 volumio volumio[2176]: Error: Command failed: /usr/bin/touch /data/wizard Oct 30 16:00:51 volumio volumio[2176]: /usr/bin/touch: cannot touch '/data/wizard': Permission denied Oct 30 16:00:51 volumio volumio[2176]: at checkExecSyncError (child_process.js:790:11) Oct 30 16:00:51 volumio volumio[2176]: at execSync (child_process.js:863:15) Oct 30 16:00:51 volumio volumio[2176]: at volumioWizard.openWizard (/volumio/app/plugins/miscellanea/wizard/index.js:277:5) Oct 30 16:00:51 volumio volumio[2176]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Oct 30 16:00:51 volumio volumio[2176]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1600:26) Oct 30 16:00:51 volumio volumio[2176]: at Socket.emit (events.js:400:28) Oct 30 16:00:51 volumio volumio[2176]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 30 16:00:51 volumio volumio[2176]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 30 16:00:51 volumio volumio[2176]: status: 1, Oct 30 16:00:51 volumio volumio[2176]: signal: null, Oct 30 16:00:51 volumio volumio[2176]: output: [ Oct 30 16:00:51 volumio volumio[2176]: null, Oct 30 16:00:51 volumio volumio[2176]: , Oct 30 16:00:51 volumio volumio[2176]: Oct 30 16:00:51 volumio volumio[2176]: ], Oct 30 16:00:51 volumio volumio[2176]: pid: 2624, Oct 30 16:00:51 volumio volumio[2176]: stdout: , Oct 30 16:00:51 volumio volumio[2176]: stderr: Oct 30 16:00:51 volumio volumio[2176]: } Oct 30 16:00:51 volumio volumio[2176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 16:00:51 volumio sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 15:59 Oct 30 16:00:51 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="6e682b9410d28f3874f90b2d7789db824a859264" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="218f986668198768ef5630191ffb17431779722f" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Jun 2024 10:07:52 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="odroidm1s" VOLUMIO_DEVICENAME="Odroid M1S" VOLUMIO_HASH="8e8313fb3db92f972e3c67e9a9aee148"