-- Logs begin at Thu 2024-09-12 13:21:45 UTC, end at Thu 2024-09-12 13:22:20 UTC. -- Sep 12 13:21:45 volumio kernel: Booting Linux on physical CPU 0x0 Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys cpuset Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys cpu Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys cpuacct Sep 12 13:21:45 volumio kernel: Linux version 4.4.154 (rock64@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #5 SMP Sat Jan 23 18:17:37 CET 2021 Sep 12 13:21:45 volumio kernel: Boot CPU: AArch64 Processor [410fd034] Sep 12 13:21:45 volumio kernel: earlycon: Early serial console at MMIO32 0xff130000 (options '') Sep 12 13:21:45 volumio kernel: bootconsole [uart0] enabled Sep 12 13:21:45 volumio kernel: On node 0 totalpages: 261632 Sep 12 13:21:45 volumio kernel: DMA zone: 4088 pages used for memmap Sep 12 13:21:45 volumio kernel: DMA zone: 0 pages reserved Sep 12 13:21:45 volumio kernel: DMA zone: 261632 pages, LIFO batch:31 Sep 12 13:21:45 volumio kernel: psci: probing for conduit method from DT. Sep 12 13:21:45 volumio kernel: psci: PSCIv1.0 detected in firmware. Sep 12 13:21:45 volumio kernel: psci: Using standard PSCI v0.2 function IDs Sep 12 13:21:45 volumio kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 12 13:21:45 volumio kernel: PERCPU: Embedded 21 pages/cpu @ffffffc03ff42000 s46248 r8192 d31576 u86016 Sep 12 13:21:45 volumio kernel: pcpu-alloc: s46248 r8192 d31576 u86016 alloc=21*4096 Sep 12 13:21:45 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 12 13:21:45 volumio kernel: Detected VIPT I-cache on CPU0 Sep 12 13:21:45 volumio kernel: CPU features: enabling workaround for ARM erratum 845719 Sep 12 13:21:45 volumio kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 257544 Sep 12 13:21:45 volumio kernel: Kernel command line: earlycon=uart8250,mmio32,0xff130000 swiotlb=1 kpti=0 earlycon=uart8250,mmio32,0xff130000 swiotlb=1 kpti=0 console=tty1 console=ttyS2,1500000n8 imgpart=UUID=1d7668e6-ebf6-4241-a57c-43ff26b4cf60 imgfile=/volumio_current.sqsh hwdevice=Rock64 bootpart=UUID=7FB8-6D8F datapart=UUID=0a805d05-9d86-4f15-9082-44e509673e97 bootconfig=/extlinux/extlinux.conf loglevel=7 Sep 12 13:21:45 volumio kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Sep 12 13:21:45 volumio kernel: Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) Sep 12 13:21:45 volumio kernel: Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) Sep 12 13:21:45 volumio kernel: software IO TLB [mem 0x3fdf9000-0x3fe39000] (0MB) mapped at [ffffffc03fdf9000-ffffffc03fe38fff] Sep 12 13:21:45 volumio kernel: Memory: 1001920K/1046528K available (11966K kernel code, 1714K rwdata, 4328K rodata, 1216K init, 764K bss, 44608K reserved, 0K cma-reserved) Sep 12 13:21:45 volumio kernel: Virtual kernel memory layout: modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) vmalloc : 0xffffff8008000000 - 0xffffffbdbfff0000 ( 246 GB) .init : 0xffffff8009070000 - 0xffffff80091a0000 ( 1216 KB) .text : 0xffffff8008080000 - 0xffffff8008c30000 ( 11968 KB) .rodata : 0xffffff8008c30000 - 0xffffff8009070000 ( 4352 KB) .data : 0xffffff80091a0000 - 0xffffff800934c808 ( 1715 KB) vmemmap : 0xffffffbdc0000000 - 0xffffffbfc0000000 ( 8 GB maximum) 0xffffffbdc0008000 - 0xffffffbdc1000000 ( 15 MB actual) fixed : 0xffffffbffe7fb000 - 0xffffffbffec00000 ( 4116 KB) PCI I/O : 0xffffffbffee00000 - 0xffffffbfffe00000 ( 16 MB) memory : 0xffffffc000200000 - 0xffffffc040000000 ( 1022 MB) Sep 12 13:21:45 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 12 13:21:45 volumio kernel: Hierarchical RCU implementation. Sep 12 13:21:45 volumio kernel: Build-time adjustment of leaf fanout to 64. Sep 12 13:21:45 volumio kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Sep 12 13:21:45 volumio kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 Sep 12 13:21:45 volumio kernel: NR_IRQS:64 nr_irqs:64 0 Sep 12 13:21:45 volumio kernel: GIC: Using split EOI/Deactivate mode Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Sep 12 13:21:45 volumio kernel: Architected cp15 timer(s) running at 24.00MHz (phys). Sep 12 13:21:45 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Sep 12 13:21:45 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Sep 12 13:21:45 volumio kernel: Console: colour dummy device 80x25 Sep 12 13:21:45 volumio kernel: console [tty1] enabled Sep 12 13:21:45 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Sep 12 13:21:45 volumio kernel: pid_max: default: 32768 minimum: 301 Sep 12 13:21:45 volumio kernel: Security Framework initialized Sep 12 13:21:45 volumio kernel: Yama: becoming mindful. Sep 12 13:21:45 volumio kernel: AppArmor: AppArmor disabled by boot time parameter Sep 12 13:21:45 volumio kernel: Mount-cache hash table entries: 2048 (order: 2, 16384 bytes) Sep 12 13:21:45 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes) Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys io Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys memory Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys devices Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys freezer Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys net_cls Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys perf_event Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys net_prio Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys hugetlb Sep 12 13:21:45 volumio kernel: Initializing cgroup subsys pids Sep 12 13:21:45 volumio kernel: ftrace: allocating 44342 entries in 174 pages Sep 12 13:21:45 volumio kernel: sched-energy: CPU device node has no sched-energy-costs Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU0 Sep 12 13:21:45 volumio kernel: CPU0: update cpu_capacity 1024 Sep 12 13:21:45 volumio kernel: ASID allocator initialised with 32768 entries Sep 12 13:21:45 volumio kernel: Detected VIPT I-cache on CPU1 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU1 Sep 12 13:21:45 volumio kernel: CPU1: update cpu_capacity 1024 Sep 12 13:21:45 volumio kernel: CPU1: Booted secondary processor [410fd034] Sep 12 13:21:45 volumio kernel: Detected VIPT I-cache on CPU2 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU2 Sep 12 13:21:45 volumio kernel: CPU2: update cpu_capacity 1024 Sep 12 13:21:45 volumio kernel: CPU2: Booted secondary processor [410fd034] Sep 12 13:21:45 volumio kernel: Detected VIPT I-cache on CPU3 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU3 Sep 12 13:21:45 volumio kernel: CPU3: update cpu_capacity 1024 Sep 12 13:21:45 volumio kernel: CPU3: Booted secondary processor [410fd034] Sep 12 13:21:45 volumio kernel: Brought up 4 CPUs Sep 12 13:21:45 volumio kernel: SMP: Total of 4 processors activated. Sep 12 13:21:45 volumio kernel: CPU features: kernel page table isolation forced OFF by command line option Sep 12 13:21:45 volumio kernel: CPU: All CPU(s) started at EL2 Sep 12 13:21:45 volumio kernel: alternatives: patching kernel code Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU3 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for Cluster3 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU2 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for Cluster2 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU1 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for Cluster1 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for CPU0 Sep 12 13:21:45 volumio kernel: Invalid sched_group_energy for Cluster0 Sep 12 13:21:45 volumio kernel: devtmpfs: initialized Sep 12 13:21:45 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Sep 12 13:21:45 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Sep 12 13:21:45 volumio kernel: xor: measuring software checksum speed Sep 12 13:21:45 volumio kernel: 8regs : 1080.000 MB/sec Sep 12 13:21:45 volumio kernel: 8regs_prefetch: 968.000 MB/sec Sep 12 13:21:45 volumio kernel: 32regs : 1458.000 MB/sec Sep 12 13:21:45 volumio kernel: 32regs_prefetch: 1224.000 MB/sec Sep 12 13:21:45 volumio kernel: xor: using function: 32regs (1458.000 MB/sec) Sep 12 13:21:45 volumio kernel: pinctrl core: initialized pinctrl subsystem Sep 12 13:21:45 volumio kernel: regulator-dummy: no parameters Sep 12 13:21:45 volumio kernel: Failed to find legacy iommu devices Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 16 Sep 12 13:21:45 volumio kernel: cpuidle: using governor ladder Sep 12 13:21:45 volumio kernel: cpuidle: using governor menu Sep 12 13:21:45 volumio kernel: vdso: 2 pages (1 code @ ffffff8008c36000, 1 data @ ffffff80091a4000) Sep 12 13:21:45 volumio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 12 13:21:45 volumio kernel: DMA: preallocated 256 KiB pool for atomic allocations Sep 12 13:21:45 volumio kernel: gpiochip_add_data: registered GPIOs 0 to 31 on device: gpio0 Sep 12 13:21:45 volumio kernel: gpiochip_add_data: registered GPIOs 32 to 63 on device: gpio1 Sep 12 13:21:45 volumio kernel: gpiochip_add_data: registered GPIOs 64 to 95 on device: gpio2 Sep 12 13:21:45 volumio kernel: gpiochip_add_data: registered GPIOs 96 to 127 on device: gpio3 Sep 12 13:21:45 volumio kernel: raid6: int64x1 gen() 202 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x1 xor() 181 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x2 gen() 323 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x2 xor() 261 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x4 gen() 466 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x4 xor() 331 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x8 gen() 394 MB/s Sep 12 13:21:45 volumio kernel: raid6: int64x8 xor() 303 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx1 gen() 360 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx1 xor() 354 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx2 gen() 564 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx2 xor() 457 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx4 gen() 756 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx4 xor() 559 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx8 gen() 773 MB/s Sep 12 13:21:45 volumio kernel: raid6: neonx8 xor() 569 MB/s Sep 12 13:21:45 volumio kernel: raid6: using algorithm neonx8 gen() 773 MB/s Sep 12 13:21:45 volumio kernel: raid6: .... xor() 569 MB/s, rmw enabled Sep 12 13:21:45 volumio kernel: raid6: using intx1 recovery algorithm Sep 12 13:21:45 volumio kernel: mpp venc_srv: mpp_probe enter Sep 12 13:21:45 volumio kernel: mpp venc_srv: init success Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/vcc-phy-regulator[0]' Sep 12 13:21:45 volumio kernel: vcc_phy: no parameters Sep 12 13:21:45 volumio kernel: reg-fixed-voltage vcc-phy-regulator: vcc_phy supplying 0uV Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/vcc-sys[0]' Sep 12 13:21:45 volumio kernel: vcc_sys: 5000 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage vcc-sys: vcc_sys supplying 5000000uV Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: parsed 'gpio' property of node '/sdmmc-regulator[0]' - status (0) Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: unable to resolve supply Sep 12 13:21:45 volumio kernel: vcc_sd: 3300 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: regulator get failed, ret=-517 Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: unable to resolve supply Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: vcc_sd supplying 3300000uV Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: parsed 'gpio' property of node '/vcc-host-5v-regulator[0]' - status (0) Sep 12 13:21:45 volumio kernel: reg-fixed-voltage vcc-host-5v-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_host_5v: supplied by vcc_sys Sep 12 13:21:45 volumio kernel: vcc_sys: could not add device link regulator.4 err -2 Sep 12 13:21:45 volumio kernel: vcc_host_5v: at 5000 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: unable to resolve supply Sep 12 13:21:45 volumio kernel: reg-fixed-voltage vcc-host-5v-regulator: vcc_host_5v supplying 0uV Sep 12 13:21:45 volumio kernel: iommu: Adding device ff350000.vpu_service to group 0 Sep 12 13:21:45 volumio kernel: iommu: Adding device ff351000.avsd to group 1 Sep 12 13:21:45 volumio kernel: iommu: Adding device ff360000.rkvdec to group 2 Sep 12 13:21:45 volumio kernel: iommu: Adding device ff330000.h265e to group 3 Sep 12 13:21:45 volumio kernel: iommu: Adding device ff340000.vepu to group 4 Sep 12 13:21:45 volumio kernel: iommu: Adding device ff370000.vop to group 5 Sep 12 13:21:45 volumio kernel: rk_iommu ff360480.iommu: can't get aclk Sep 12 13:21:45 volumio kernel: rk_iommu ff360480.iommu: can't get hclk Sep 12 13:21:45 volumio kernel: SCSI subsystem initialized Sep 12 13:21:45 volumio kernel: libata version 3.00 loaded. Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver usbfs Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver hub Sep 12 13:21:45 volumio kernel: usbcore: registered new device driver usb Sep 12 13:21:45 volumio kernel: media: Linux media interface: v0.10 Sep 12 13:21:45 volumio kernel: Linux video capture interface: v2.00 Sep 12 13:21:45 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Sep 12 13:21:45 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 12 13:21:45 volumio kernel: PTP clock support registered Sep 12 13:21:45 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Sep 12 13:21:45 volumio kernel: Bluetooth: Core ver 2.21 Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 31 Sep 12 13:21:45 volumio kernel: Bluetooth: HCI device and connection manager initialized Sep 12 13:21:45 volumio kernel: Bluetooth: HCI socket layer initialized Sep 12 13:21:45 volumio kernel: Bluetooth: L2CAP socket layer initialized Sep 12 13:21:45 volumio kernel: Bluetooth: SCO socket layer initialized Sep 12 13:21:45 volumio kernel: NetLabel: Initializing Sep 12 13:21:45 volumio kernel: NetLabel: domain hash size = 128 Sep 12 13:21:45 volumio kernel: NetLabel: protocols = UNLABELED CIPSOv4 Sep 12 13:21:45 volumio kernel: NetLabel: unlabeled traffic allowed by default Sep 12 13:21:45 volumio kernel: rockchip-cpuinfo cpuinfo: Serial : eb8075cf1f424525 Sep 12 13:21:45 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 2 Sep 12 13:21:45 volumio kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes) Sep 12 13:21:45 volumio kernel: TCP bind hash table entries: 8192 (order: 6, 262144 bytes) Sep 12 13:21:45 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Sep 12 13:21:45 volumio kernel: UDP hash table entries: 512 (order: 3, 49152 bytes) Sep 12 13:21:45 volumio kernel: UDP-Lite hash table entries: 512 (order: 3, 49152 bytes) Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 1 Sep 12 13:21:45 volumio kernel: RPC: Registered named UNIX socket transport module. Sep 12 13:21:45 volumio kernel: RPC: Registered udp transport module. Sep 12 13:21:45 volumio kernel: RPC: Registered tcp transport module. Sep 12 13:21:45 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 12 13:21:45 volumio kernel: PCI: CLS 0 bytes, default 64 Sep 12 13:21:45 volumio kernel: Trying to unpack rootfs image as initramfs... Sep 12 13:21:45 volumio kernel: Freeing initrd memory: 5476K Sep 12 13:21:45 volumio kernel: hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available Sep 12 13:21:45 volumio kernel: kvm [1]: 8-bit VMID Sep 12 13:21:45 volumio kernel: kvm [1]: Hyp mode initialized successfully Sep 12 13:21:45 volumio kernel: kvm [1]: interrupt-controller@ff814000 IRQ44 Sep 12 13:21:45 volumio kernel: kvm [1]: timer IRQ3 Sep 12 13:21:45 volumio kernel: audit: initializing netlink subsys (disabled) Sep 12 13:21:45 volumio kernel: audit: type=2000 audit(2.000:1): initialized Sep 12 13:21:45 volumio kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages Sep 12 13:21:45 volumio kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 13:21:45 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 13:21:45 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 13:21:45 volumio kernel: NFS: Registering the id_resolver key type Sep 12 13:21:45 volumio kernel: Key type id_resolver registered Sep 12 13:21:45 volumio kernel: Key type id_legacy registered Sep 12 13:21:45 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Sep 12 13:21:45 volumio kernel: fuse init (API version 7.23) Sep 12 13:21:45 volumio kernel: JFS: nTxBlock = 7870, nTxLock = 62962 Sep 12 13:21:45 volumio kernel: SGI XFS with ACLs, security attributes, realtime, no debug enabled Sep 12 13:21:45 volumio kernel: Key type big_key registered Sep 12 13:21:45 volumio kernel: TEE Core Framework initialization (ver 1:0.1) Sep 12 13:21:45 volumio kernel: TEE armv7 Driver initialization Sep 12 13:21:45 volumio kernel: tz_tee_probe: name="armv7sec", id=0, pdev_name="armv7sec.0" Sep 12 13:21:45 volumio kernel: TEE core: Alloc the misc device "opteearmtz00" (id=0) Sep 12 13:21:45 volumio kernel: TEE Core: Register the misc device "opteearmtz00" (id=0,minor=62) Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 38 Sep 12 13:21:45 volumio kernel: Key type asymmetric registered Sep 12 13:21:45 volumio kernel: Asymmetric key parser 'x509' registered Sep 12 13:21:45 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) Sep 12 13:21:45 volumio kernel: io scheduler noop registered Sep 12 13:21:45 volumio kernel: io scheduler deadline registered (default) Sep 12 13:21:45 volumio kernel: io scheduler cfq registered Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply from device tree Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply property in node /syscon@ff450000/usb2-phy@100/host-port failed Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply from device tree Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply property in node /syscon@ff450000/usb2-phy@100/otg-port failed Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up vbus-supply from device tree Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up vbus-supply property in node /syscon@ff450000/usb2-phy@100/otg-port failed Sep 12 13:21:45 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Failed to get VBUS supply regulator Sep 12 13:21:45 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Looking up vbus-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Looking up vbus-supply property in node /usb3-phy@ff470000 failed Sep 12 13:21:45 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Failed to get VBUS supply regulator Sep 12 13:21:45 volumio kernel: phy phy-ff470000.usb3-phy.2: Looking up phy-supply from device tree Sep 12 13:21:45 volumio kernel: phy phy-ff470000.usb3-phy.2: Looking up phy-supply property in node /usb3-phy@ff470000/utmi@ff470000 failed Sep 12 13:21:45 volumio kernel: phy phy-ff470000.usb3-phy.3: Looking up phy-supply from device tree Sep 12 13:21:45 volumio kernel: phy phy-ff470000.usb3-phy.3: Looking up phy-supply property in node /usb3-phy@ff470000/pipe@ff478000 failed Sep 12 13:21:45 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Rockchip u3phy initialized successfully Sep 12 13:21:45 volumio kernel: phy phy-ff430000.hdmiphy.4: Looking up phy-supply from device tree Sep 12 13:21:45 volumio kernel: phy phy-ff430000.hdmiphy.4: Looking up phy-supply property in node /hdmiphy@ff430000 failed Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: Looking up vcodec-supply from device tree Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: Looking up vcodec-supply property in node /vpu_combo failed Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: no regulator for vcodec Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: failed on clk_get clk_cabac Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: failed on clk_get clk_core Sep 12 13:21:45 volumio kernel: platform ff350000.vpu_service: probe device Sep 12 13:21:45 volumio kernel: platform ff350000.vpu_service: drm allocator with mmu enabled Sep 12 13:21:45 volumio kernel: platform ff351000.avsd: probe device Sep 12 13:21:45 volumio kernel: platform ff351000.avsd: drm allocator with mmu enabled Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: could not find power_model node Sep 12 13:21:45 volumio kernel: rk-vcodec vpu_combo: init success Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: Looking up vcodec-supply from device tree Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: Looking up vcodec-supply property in node /rkvdec@ff36000 failed Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: no regulator for vcodec Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: parent devfreq is disabled Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: probe device Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: drm allocator with mmu enabled Sep 12 13:21:45 volumio kernel: rk-vcodec ff360000.rkvdec: init success Sep 12 13:21:45 volumio kernel: probe device ff330000.h265e Sep 12 13:21:45 volumio kernel: mpp_dev ff330000.h265e: try to get iommu dev ffffffc03e02dc10 Sep 12 13:21:45 volumio kernel: mpp_dev ff330000.h265e: resource ready, register device Sep 12 13:21:45 volumio kernel: probe device ff340000.vepu Sep 12 13:21:45 volumio kernel: mpp_dev ff340000.vepu: try to get iommu dev ffffffc03e02e410 Sep 12 13:21:45 volumio kernel: mpp_dev ff340000.vepu: resource ready, register device Sep 12 13:21:45 volumio kernel: dma-pl330 ff1f0000.dmac: Loaded driver for PL330 DMAC-241330 Sep 12 13:21:45 volumio kernel: dma-pl330 ff1f0000.dmac: DBUFF-128x8bytes Num_Chans-8 Num_Peri-20 Num_Events-16 Sep 12 13:21:45 volumio kernel: Serial: 8250/16550 driver, 5 ports, IRQ sharing disabled Sep 12 13:21:45 volumio kernel: console [ttyS2] disabled Sep 12 13:21:45 volumio kernel: ff130000.serial: ttyS2 at MMIO 0xff130000 (irq = 13, base_baud = 1500000) is a 16550A Sep 12 13:21:45 volumio kernel: console [ttyS2] enabled Sep 12 13:21:45 volumio kernel: bootconsole [uart0] disabled Sep 12 13:21:45 volumio kernel: [drm] Initialized drm 1.1.0 20060810 Sep 12 13:21:45 volumio kernel: [drm] Rockchip DRM driver version: v1.0.1 Sep 12 13:21:45 volumio kernel: rockchip-drm display-subsystem: devfreq is not set Sep 12 13:21:45 volumio kernel: rockchip-drm display-subsystem: bound ff370000.vop (ops 0xffffff8008d02df0) Sep 12 13:21:45 volumio kernel: i2c i2c-4: of_i2c: modalias failure on /hdmi@ff3c0000/ports Sep 12 13:21:45 volumio kernel: dwhdmi-rockchip ff3c0000.hdmi: registered DesignWare HDMI I2C bus driver Sep 12 13:21:45 volumio kernel: dwhdmi-rockchip ff3c0000.hdmi: Detected HDMI TX controller v2.11a with HDCP (inno_dw_hdmi_phy2) Sep 12 13:21:45 volumio kernel: rockchip-drm display-subsystem: bound ff3c0000.hdmi (ops 0xffffff8008cf8c50) Sep 12 13:21:45 volumio kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). Sep 12 13:21:45 volumio kernel: [drm] No driver support for vblank timestamp query. Sep 12 13:21:45 volumio kernel: rockchip-drm display-subsystem: failed to parse display resources Sep 12 13:21:45 volumio kernel: rockchip-drm display-subsystem: No connectors reported connected with modes Sep 12 13:21:45 volumio kernel: [drm] Cannot find any crtc or sizes - going 1024x768 Sep 12 13:21:45 volumio kernel: Console: switching to colour frame buffer device 128x48 Sep 12 13:21:45 volumio kernel: rockchip-drm display-subsystem: fb0: frame buffer device Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver udl Sep 12 13:21:45 volumio kernel: Unable to detect cache hierarchy for CPU 0 Sep 12 13:21:45 volumio kernel: brd: module loaded Sep 12 13:21:45 volumio kernel: loop: module loaded Sep 12 13:21:45 volumio kernel: lkdtm: No crash points registered, enable through debugfs Sep 12 13:21:45 volumio kernel: rockchip-spi ff190000.spi: no high_speed pinctrl state Sep 12 13:21:45 volumio kernel: m25p80 spi32766.0: unrecognized JEDEC id bytes: ff, ff, ff Sep 12 13:21:45 volumio kernel: m25p80: probe of spi32766.0 failed with error -2 Sep 12 13:21:45 volumio kernel: force_sf_dma_mode is ignored if force_thresh_dma_mode is set. Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: Looking up phy-supply from device tree Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: clock input or output? (input). Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: TX delay(0x26). Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: RX delay(0x11). Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: integrated PHY? (no). Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: cannot get clock clk_mac_speed Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: clock input from PHY Sep 12 13:21:45 volumio kernel: rk_gmac-dwmac ff540000.ethernet: init for RGMII Sep 12 13:21:45 volumio kernel: stmmac - user ID: 0x10, Synopsys ID: 0x35 Sep 12 13:21:45 volumio kernel: Ring mode enabled Sep 12 13:21:45 volumio kernel: DMA HW capability register supported Sep 12 13:21:45 volumio kernel: Normal descriptors Sep 12 13:21:45 volumio kernel: RX Checksum Offload Engine supported (type 2) Sep 12 13:21:45 volumio kernel: Wake-Up On Lan supported Sep 12 13:21:45 volumio kernel: Enable RX Mitigation via HW Watchdog Timer Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: parsed 'snps,reset-gpio' property of node '/ethernet@ff540000[0]' - status (0) Sep 12 13:21:45 volumio kernel: libphy: stmmac: probed Sep 12 13:21:45 volumio kernel: eth%d: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active Sep 12 13:21:45 volumio kernel: eth%d: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01) Sep 12 13:21:45 volumio kernel: Rockchip WiFi SYS interface (V1.00) ... Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver cdc_ether Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver rndis_host Sep 12 13:21:45 volumio kernel: phy phy-ff470000.usb3-phy.2: u3phy u2 power on Sep 12 13:21:45 volumio kernel: phy phy-ff470000.usb3-phy.3: u3phy u3 power on Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply from device tree Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply property in node /usb@ff580000 failed Sep 12 13:21:45 volumio kernel: ff580000.usb supply vusb_d not found, using dummy regulator Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply from device tree Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply property in node /usb@ff580000 failed Sep 12 13:21:45 volumio kernel: ff580000.usb supply vusb_a not found, using dummy regulator Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: DWC OTG Controller Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: new USB bus registered, assigned bus number 1 Sep 12 13:21:45 volumio kernel: dwc2 ff580000.usb: irq 41, io mem 0xff580000 Sep 12 13:21:45 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Sep 12 13:21:45 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 12 13:21:45 volumio kernel: usb usb1: Product: DWC OTG Controller Sep 12 13:21:45 volumio kernel: usb usb1: Manufacturer: Linux 4.4.154 dwc2_hsotg Sep 12 13:21:45 volumio kernel: usb usb1: SerialNumber: ff580000.usb Sep 12 13:21:45 volumio kernel: hub 1-0:1.0: USB hub found Sep 12 13:21:45 volumio kernel: hub 1-0:1.0: 1 port detected Sep 12 13:21:45 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Sep 12 13:21:45 volumio kernel: ehci-pci: EHCI PCI platform driver Sep 12 13:21:45 volumio kernel: ehci-platform: EHCI generic platform driver Sep 12 13:21:45 volumio kernel: ehci-platform ff5c0000.usb: EHCI Host Controller Sep 12 13:21:45 volumio kernel: ehci-platform ff5c0000.usb: new USB bus registered, assigned bus number 2 Sep 12 13:21:45 volumio kernel: ehci-platform ff5c0000.usb: irq 42, io mem 0xff5c0000 Sep 12 13:21:45 volumio kernel: ehci-platform ff5c0000.usb: USB 2.0 started, EHCI 1.00 Sep 12 13:21:45 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 Sep 12 13:21:45 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 12 13:21:45 volumio kernel: usb usb2: Product: EHCI Host Controller Sep 12 13:21:45 volumio kernel: usb usb2: Manufacturer: Linux 4.4.154 ehci_hcd Sep 12 13:21:45 volumio kernel: usb usb2: SerialNumber: ff5c0000.usb Sep 12 13:21:45 volumio kernel: hub 2-0:1.0: USB hub found Sep 12 13:21:45 volumio kernel: hub 2-0:1.0: 1 port detected Sep 12 13:21:45 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Sep 12 13:21:45 volumio kernel: ohci-platform: OHCI generic platform driver Sep 12 13:21:45 volumio kernel: ohci-platform ff5d0000.usb: Generic Platform OHCI controller Sep 12 13:21:45 volumio kernel: ohci-platform ff5d0000.usb: new USB bus registered, assigned bus number 3 Sep 12 13:21:45 volumio kernel: ohci-platform ff5d0000.usb: irq 43, io mem 0xff5d0000 Sep 12 13:21:45 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0001 Sep 12 13:21:45 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 12 13:21:45 volumio kernel: usb usb3: Product: Generic Platform OHCI controller Sep 12 13:21:45 volumio kernel: usb usb3: Manufacturer: Linux 4.4.154 ohci_hcd Sep 12 13:21:45 volumio kernel: usb usb3: SerialNumber: ff5d0000.usb Sep 12 13:21:45 volumio kernel: hub 3-0:1.0: USB hub found Sep 12 13:21:45 volumio kernel: hub 3-0:1.0: 1 port detected Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 4 Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x00210010 Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: irq 179, io mem 0xff600000 Sep 12 13:21:45 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002 Sep 12 13:21:45 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 12 13:21:45 volumio kernel: usb usb4: Product: xHCI Host Controller Sep 12 13:21:45 volumio kernel: usb usb4: Manufacturer: Linux 4.4.154 xhci-hcd Sep 12 13:21:45 volumio kernel: usb usb4: SerialNumber: xhci-hcd.9.auto Sep 12 13:21:45 volumio kernel: hub 4-0:1.0: USB hub found Sep 12 13:21:45 volumio kernel: hub 4-0:1.0: 1 port detected Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 5 Sep 12 13:21:45 volumio kernel: usb usb5: We don't know the algorithms for LPM for this host, disabling LPM. Sep 12 13:21:45 volumio kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0003 Sep 12 13:21:45 volumio kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 12 13:21:45 volumio kernel: usb usb5: Product: xHCI Host Controller Sep 12 13:21:45 volumio kernel: usb usb5: Manufacturer: Linux 4.4.154 xhci-hcd Sep 12 13:21:45 volumio kernel: usb usb5: SerialNumber: xhci-hcd.9.auto Sep 12 13:21:45 volumio kernel: hub 5-0:1.0: USB hub found Sep 12 13:21:45 volumio kernel: hub 5-0:1.0: 1 port detected Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver iforce Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver xpad Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver usbtouchscreen Sep 12 13:21:45 volumio kernel: i2c /dev entries driver Sep 12 13:21:45 volumio kernel: rk808 1-0018: Pmic Chip id: 0x8050 Sep 12 13:21:45 volumio kernel: rk808 1-0018: source: on=0x40, off=0x00 Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc1-supply from device tree Sep 12 13:21:45 volumio kernel: DCDC_REG1: supplied by vcc_sys Sep 12 13:21:45 volumio kernel: vcc_sys: could not add device link regulator.5 err -2 Sep 12 13:21:45 volumio kernel: vdd_logic: 712 <--> 1450 mV at 1100 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: unable to resolve supply Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc2-supply from device tree Sep 12 13:21:45 volumio kernel: DCDC_REG2: supplied by vcc_sys Sep 12 13:21:45 volumio kernel: vcc_sys: could not add device link regulator.6 err -2 Sep 12 13:21:45 volumio kernel: vdd_arm: 712 <--> 1450 mV at 1100 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: unable to resolve supply Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc3-supply from device tree Sep 12 13:21:45 volumio kernel: DCDC_REG3: supplied by vcc_sys Sep 12 13:21:45 volumio kernel: vcc_sys: could not add device link regulator.7 err -2 Sep 12 13:21:45 volumio kernel: vcc_ddr: at 5000 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: unable to resolve supply Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc4-supply from device tree Sep 12 13:21:45 volumio kernel: DCDC_REG4: supplied by vcc_sys Sep 12 13:21:45 volumio kernel: vcc_sys: could not add device link regulator.8 err -2 Sep 12 13:21:45 volumio kernel: vcc_io: 3300 mV Sep 12 13:21:45 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 12 13:21:45 volumio kernel: vcc_sd: supplied by vcc_io Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Sep 12 13:21:45 volumio kernel: LDO_REG1: supplied by vcc_io Sep 12 13:21:45 volumio kernel: vcc_io: could not add device link regulator.9 err -2 Sep 12 13:21:45 volumio kernel: vdd_18: 1800 mV Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Sep 12 13:21:45 volumio kernel: LDO_REG2: supplied by vcc_io Sep 12 13:21:45 volumio kernel: vcc_io: could not add device link regulator.10 err -2 Sep 12 13:21:45 volumio kernel: vcc18_emmc: 1800 mV Sep 12 13:21:45 volumio kernel: rk808 1-0018: Looking up vcc6-supply from device tree Sep 12 13:21:45 volumio kernel: LDO_REG3: supplied by vcc_sys Sep 12 13:21:45 volumio kernel: vcc_sys: could not add device link regulator.11 err -2 Sep 12 13:21:45 volumio kernel: vdd_10: 1000 mV Sep 12 13:21:45 volumio kernel: rk808 1-0018: register rk8050 regulators Sep 12 13:21:45 volumio kernel: gpiochip_find_base: found new base at 510 Sep 12 13:21:45 volumio kernel: gpiochip_add_data: registered GPIOs 510 to 511 on device: rk8xx-gpio Sep 12 13:21:45 volumio kernel: rk8xx-gpio rk8xx-gpio: register rk8050 gpio successful Sep 12 13:21:45 volumio kernel: input: rk8xx_pwrkey as /devices/platform/ff160000.i2c/i2c-1/1-0018/input/input0 Sep 12 13:21:45 volumio kernel: rk808-rtc rk808-rtc: rtc core: registered rk808-rtc as rtc0 Sep 12 13:21:45 volumio kernel: rk3x-i2c ff160000.i2c: Initialized RK3xxx I2C bus at ffffff8009a76000 Sep 12 13:21:45 volumio kernel: IR NEC protocol handler initialized Sep 12 13:21:45 volumio kernel: IR RC5(x/sz) protocol handler initialized Sep 12 13:21:45 volumio kernel: IR RC6 protocol handler initialized Sep 12 13:21:45 volumio kernel: IR JVC protocol handler initialized Sep 12 13:21:45 volumio kernel: IR Sony protocol handler initialized Sep 12 13:21:45 volumio kernel: IR SANYO protocol handler initialized Sep 12 13:21:45 volumio kernel: IR Sharp protocol handler initialized Sep 12 13:21:45 volumio kernel: usb 4-1: new high-speed USB device number 2 using xhci-hcd Sep 12 13:21:45 volumio kernel: IR MCE Keyboard/mouse protocol handler initialized Sep 12 13:21:45 volumio kernel: IR XMP protocol handler initialized Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio4-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio5-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio6-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up pmuio-supply from device tree Sep 12 13:21:45 volumio kernel: rockchip-thermal ff250000.tsadc: tsadc is probed successfully! Sep 12 13:21:45 volumio kernel: device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com Sep 12 13:21:45 volumio kernel: Bluetooth: Virtual HCI driver ver 1.5 Sep 12 13:21:45 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Sep 12 13:21:45 volumio kernel: Bluetooth: HCI UART protocol H4 registered Sep 12 13:21:45 volumio kernel: Bluetooth: HCI UART protocol LL registered Sep 12 13:21:45 volumio kernel: Bluetooth: HCI UART protocol ATH3K registered Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver bfusb Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver btusb Sep 12 13:21:45 volumio kernel: cpu cpu0: Failed to get leakage Sep 12 13:21:45 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Sep 12 13:21:45 volumio kernel: cpu cpu0: Failed to get pvtm Sep 12 13:21:45 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Sep 12 13:21:45 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Sep 12 13:21:45 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Sep 12 13:21:45 volumio kernel: sdhci: Copyright(c) Pierre Ossman Sep 12 13:21:45 volumio kernel: Synopsys Designware Multimedia Card Interface Driver Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: IDMAC supports 32-bit address mode. Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Using internal DMA controller. Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Version ID is 270a Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: DW MMC controller at irq 38,32 bit host data width,256 deep fifo Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Looking up vmmc-supply from device tree Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Looking up vqmmc-supply from device tree Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: GPIO lookup for consumer wp Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: using device tree for GPIO lookup Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff520000[0]' Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff520000[0]' Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: using lookup tables for GPIO lookup Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: lookup for GPIO wp failed Sep 12 13:21:45 volumio kernel: mmc_host mmc0: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) Sep 12 13:21:45 volumio kernel: usb 4-1: New USB device found, idVendor=05e3, idProduct=0610 Sep 12 13:21:45 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 12 13:21:45 volumio kernel: usb 4-1: Product: USB2.0 Hub Sep 12 13:21:45 volumio kernel: usb 4-1: Manufacturer: GenesysLogic Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: 1 slots initialized Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: IDMAC supports 32-bit address mode. Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Using internal DMA controller. Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Version ID is 270a Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: DW MMC controller at irq 39,32 bit host data width,256 deep fifo Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Looking up vmmc-supply from device tree Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Looking up vqmmc-supply from device tree Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: GPIO lookup for consumer cd Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using device tree for GPIO lookup Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/dwmmc@ff500000[0]' Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/dwmmc@ff500000[0]' Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using lookup tables for GPIO lookup Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: lookup for GPIO cd failed Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: GPIO lookup for consumer wp Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using device tree for GPIO lookup Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff500000[0]' Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff500000[0]' Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using lookup tables for GPIO lookup Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: lookup for GPIO wp failed Sep 12 13:21:45 volumio kernel: hub 4-1:1.0: USB hub found Sep 12 13:21:45 volumio kernel: hub 4-1:1.0: 4 ports detected Sep 12 13:21:45 volumio kernel: mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff500000.dwmmc: 1 slots initialized Sep 12 13:21:45 volumio kernel: mmc0: MAN_BKOPS_EN bit is not set Sep 12 13:21:45 volumio kernel: mmc_host mmc0: Bus speed (slot 0) = 200000000Hz (slot req 200000000Hz, actual 200000000HZ div = 0) Sep 12 13:21:45 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Sep 12 13:21:45 volumio kernel: usb 5-1: new SuperSpeed USB device number 2 using xhci-hcd Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/standby-led[0]' - status (0) Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/power-led[0]' - status (0) Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/switches/usb-switch[0]' - status (0) Sep 12 13:21:45 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Sep 12 13:21:45 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Sep 12 13:21:45 volumio kernel: usb 5-1: New USB device found, idVendor=05e3, idProduct=0612 Sep 12 13:21:45 volumio kernel: usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 12 13:21:45 volumio kernel: usb 5-1: Product: USB3.1 Hub Sep 12 13:21:45 volumio kernel: usb 5-1: Manufacturer: GenesysLogic Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver usbhid Sep 12 13:21:45 volumio kernel: usbhid: USB HID core driver Sep 12 13:21:45 volumio kernel: hub 5-1:1.0: USB hub found Sep 12 13:21:45 volumio kernel: usbcore: registered new interface driver snd-usb-audio Sep 12 13:21:45 volumio kernel: rk3328-codec ff410000.codec: spk_depop_time use default value. Sep 12 13:21:45 volumio kernel: u32 classifier Sep 12 13:21:45 volumio kernel: Netfilter messages via NETLINK v0.30. Sep 12 13:21:45 volumio kernel: ip_set: protocol 6 Sep 12 13:21:45 volumio kernel: Initializing XFRM netlink socket Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 10 Sep 12 13:21:45 volumio kernel: hub 5-1:1.0: 4 ports detected Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 17 Sep 12 13:21:45 volumio kernel: NET: Registered protocol family 15 Sep 12 13:21:45 volumio kernel: bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. Sep 12 13:21:45 volumio kernel: Bridge firewalling registered Sep 12 13:21:45 volumio kernel: Bluetooth: RFCOMM socket layer initialized Sep 12 13:21:45 volumio kernel: Bluetooth: RFCOMM ver 1.11 Sep 12 13:21:45 volumio kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2 Sep 12 13:21:45 volumio kernel: Bluetooth: HIDP socket layer initialized Sep 12 13:21:45 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 12 13:21:45 volumio kernel: [WLAN_RFKILL]: Enter rfkill_wlan_init Sep 12 13:21:45 volumio kernel: [BT_RFKILL]: Enter rfkill_rk_init Sep 12 13:21:45 volumio kernel: Key type dns_resolver registered Sep 12 13:21:45 volumio kernel: Registered cp15_barrier emulation handler Sep 12 13:21:45 volumio kernel: Registered setend emulation handler Sep 12 13:21:45 volumio kernel: registered taskstats version 1 Sep 12 13:21:45 volumio kernel: Btrfs loaded, integrity-checker=on Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running btrfs free space cache tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running extent only tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running bitmap only tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running bitmap and extent tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running space stealing from bitmap to extent Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Free space cache tests finished Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running extent buffer operation tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running btrfs_split_item tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running find delalloc tests Sep 12 13:21:45 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Successfully tuned phase to 230 Sep 12 13:21:45 volumio kernel: mmc0: new HS200 MMC card at address 0001 Sep 12 13:21:45 volumio kernel: mmcblk0: mmc0:0001 DG4008 7.28 GiB Sep 12 13:21:45 volumio kernel: mmcblk0boot0: mmc0:0001 DG4008 partition 1 4.00 MiB Sep 12 13:21:45 volumio kernel: mmcblk0boot1: mmc0:0001 DG4008 partition 2 4.00 MiB Sep 12 13:21:45 volumio kernel: mmcblk0rpmb: mmc0:0001 DG4008 partition 3 4.00 MiB Sep 12 13:21:45 volumio kernel: mmcblk0: p1 p2 p3 Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running btrfs_get_extent tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running hole first btrfs_get_extent test Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running outstanding_extents tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Running qgroup tests Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Qgroup basic add Sep 12 13:21:45 volumio kernel: BTRFS: selftest: Qgroup multiple refs test Sep 12 13:21:45 volumio kernel: Key type encrypted registered Sep 12 13:21:45 volumio kernel: pvtm list NULL Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/sound[0]' Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/sound[0]' Sep 12 13:21:45 volumio kernel: asoc-simple-card sound: rk3328-hifi <-> ff010000.i2s mapping ok Sep 12 13:21:45 volumio kernel: asoc-simple-card sound: snd-soc-dummy-dai <-> ff010000.i2s mapping ok Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/spdif-sound[0]' Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/spdif-sound[0]' Sep 12 13:21:45 volumio kernel: asoc-simple-card spdif-sound: dit-hifi <-> ff030000.spdif mapping ok Sep 12 13:21:45 volumio kernel: rk808-rtc rk808-rtc: setting system clock to 2016-01-21 08:50:05 UTC (1453366205) Sep 12 13:21:45 volumio kernel: of_cfs_init Sep 12 13:21:45 volumio kernel: of_cfs_init: OK Sep 12 13:21:45 volumio kernel: vcc_sd: disabling Sep 12 13:21:45 volumio kernel: ALSA device list: Sep 12 13:21:45 volumio kernel: #0: I2S Sep 12 13:21:45 volumio kernel: #1: SPDIF Sep 12 13:21:45 volumio kernel: of_dma_request_slave_channel: dma-names property of node '/serial@ff130000' missing or empty Sep 12 13:21:45 volumio kernel: ttyS2 - failed to request DMA, use interrupt mode Sep 12 13:21:45 volumio kernel: Freeing unused kernel memory: 1216K Sep 12 13:21:45 volumio kernel: xhci-hcd xhci-hcd.9.auto: Timeout while waiting for setup device command Sep 12 13:21:45 volumio initramfs: Booting Volumio for Rock64 Sep 12 13:21:45 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Sep 12 13:21:45 volumio initramfs: IMGPART=/dev/mmcblk0p2 Sep 12 13:21:45 volumio initramfs: IMGFILE=/volumio_current.sqsh Sep 12 13:21:45 volumio initramfs: DATAPART=/dev/mmcblk0p3 Sep 12 13:21:45 volumio initramfs: BOOTPART=/dev/mmcblk0p1 Sep 12 13:21:45 volumio initramfs: BOOTCONFIG=/boot//extlinux/extlinux.conf Sep 12 13:21:45 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Sep 12 13:21:45 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Sep 12 13:21:45 volumio initramfs: Checking for USB updates if you did not boot from USB... Sep 12 13:21:45 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Sep 12 13:21:45 volumio kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 12 13:21:45 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 12 13:21:45 volumio kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 12 13:21:45 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 12 13:21:45 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Sep 12 13:21:45 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Sep 12 13:21:45 volumio initramfs: With Option: Sep 12 13:21:45 volumio initramfs: found fstab entry with UUID=7FB8-6D8F for /boot Sep 12 13:21:45 volumio initramfs: VOLUMIO_VERSION="3.212" Sep 12 13:21:45 volumio initramfs: Finish initramfs, continue booting Volumio Sep 12 13:21:45 volumio systemd[1]: System time before build time, advancing clock. Sep 12 13:21:45 volumio systemd[1]: Inserted module 'autofs4' Sep 12 13:21:45 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Sep 12 13:21:45 volumio kernel: cgroup: cgroup2: unknown option "nsdelegate" Sep 12 13:21:45 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) Sep 12 13:21:45 volumio systemd[1]: Detected architecture arm64. Sep 12 13:21:45 volumio systemd[1]: Set hostname to . Sep 12 13:21:45 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Sep 12 13:21:45 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Sep 12 13:21:45 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. Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Created slice User and Session Slice. Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Listening on fsck to fsckd communication Socket. Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Created slice system-serial\x2dgetty.slice. Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Listening on Journal Socket. Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Starting Load Kernel Modules... Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 74 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Starting Create list of required static device nodes for the current kernel... Sep 12 13:21:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 74 bits of entropy available) Sep 12 13:21:45 volumio systemd[1]: Mounting Huge Pages File System... Sep 12 13:21:45 volumio systemd[1]: Listening on udev Kernel Socket. Sep 12 13:21:45 volumio systemd[1]: Listening on Journal Audit Socket. Sep 12 13:21:45 volumio systemd[1]: Listening on RPCbind Server Activation Socket. Sep 12 13:21:45 volumio systemd[1]: Mounting POSIX Message Queue File System... Sep 12 13:21:45 volumio systemd[1]: Started dynamicswap service. Sep 12 13:21:45 volumio systemd[1]: Listening on initctl Compatibility Named Pipe. Sep 12 13:21:45 volumio systemd[1]: Mounting Kernel Debug File System... Sep 12 13:21:45 volumio systemd[1]: Reached target System Time Synchronized. Sep 12 13:21:45 volumio systemd[1]: Starting Restore / save the current clock... Sep 12 13:21:45 volumio systemd[1]: Listening on udev Control Socket. Sep 12 13:21:45 volumio systemd[1]: Starting udev Coldplug all Devices... Sep 12 13:21:45 volumio systemd[1]: Reached target Slices. Sep 12 13:21:45 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Sep 12 13:21:45 volumio systemd[1]: Created slice system-getty.slice. Sep 12 13:21:45 volumio systemd[1]: Mounting RPC Pipe File System... Sep 12 13:21:45 volumio systemd[1]: Reached target Swap. Sep 12 13:21:45 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Sep 12 13:21:45 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Sep 12 13:21:45 volumio systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Sep 12 13:21:45 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 12 13:21:45 volumio systemd[1]: Listening on Journal Socket (/dev/log). Sep 12 13:21:45 volumio systemd[1]: Starting Journal Service... Sep 12 13:21:45 volumio systemd[1]: Started Load Kernel Modules. Sep 12 13:21:45 volumio systemd[1]: Started Create list of required static device nodes for the current kernel. Sep 12 13:21:45 volumio systemd[1]: Mounted Huge Pages File System. Sep 12 13:21:45 volumio systemd[1]: Mounted POSIX Message Queue File System. Sep 12 13:21:45 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 12 13:21:45 volumio systemd[1]: Mounted Kernel Debug File System. Sep 12 13:21:45 volumio systemd[1]: Started Restore / save the current clock. Sep 12 13:21:45 volumio systemd[1]: Mounted RPC Pipe File System. Sep 12 13:21:45 volumio systemd[1]: Started Remount Root and Kernel File Systems. Sep 12 13:21:45 volumio systemd[1]: Starting Load/Save Random Seed... Sep 12 13:21:45 volumio systemd[1]: Starting Create System Users... Sep 12 13:21:45 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 12 13:21:45 volumio systemd[1]: Mounting Kernel Configuration File System... Sep 12 13:21:45 volumio systemd[1]: Starting Apply Kernel Variables... Sep 12 13:21:45 volumio systemd[1]: Mounting FUSE Control File System... Sep 12 13:21:45 volumio systemd[1]: Started Load/Save Random Seed. Sep 12 13:21:45 volumio systemd[1]: Mounted Kernel Configuration File System. Sep 12 13:21:45 volumio systemd[1]: Mounted FUSE Control File System. Sep 12 13:21:45 volumio kernel: Removed cp15_barrier emulation handler Sep 12 13:21:45 volumio kernel: Enabled cp15_barrier support Sep 12 13:21:45 volumio systemd-journald[295]: Journal started Sep 12 13:21:45 volumio systemd-journald[295]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 3.7M, max 30.0M, 26.2M free. Sep 12 13:21:45 volumio fake-hwclock[283]: Thu Sep 12 13:21:45 UTC 2024 Sep 12 13:21:45 volumio systemd[1]: Started Journal Service. Sep 12 13:21:45 volumio systemd[1]: Started udev Coldplug all Devices. Sep 12 13:21:45 volumio systemd[1]: Started Create System Users. Sep 12 13:21:45 volumio systemd[1]: Started Apply Kernel Variables. Sep 12 13:21:45 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Sep 12 13:21:45 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 12 13:21:45 volumio systemd[1]: Started Create Static Device Nodes in /dev. Sep 12 13:21:45 volumio systemd[1]: Starting udev Kernel Device Manager... Sep 12 13:21:45 volumio systemd[1]: Reached target Local File Systems (Pre). Sep 12 13:21:45 volumio systemd[1]: Mounting /var/spool/cups... Sep 12 13:21:45 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 12 13:21:45 volumio systemd[1]: Mounting /tmp... Sep 12 13:21:45 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 12 13:21:45 volumio systemd[1]: Mounting /var/log... Sep 12 13:21:45 volumio systemd[1]: Mounted /var/spool/cups. Sep 12 13:21:45 volumio systemd[1]: Mounted /tmp. Sep 12 13:21:45 volumio systemd[1]: Mounted /var/log. Sep 12 13:21:45 volumio systemd[1]: Started udev Kernel Device Manager. Sep 12 13:21:45 volumio systemd[1]: Starting Show Plymouth Boot Screen... Sep 12 13:21:45 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Sep 12 13:21:45 volumio systemd[1]: Mounting /var/spool/cups/tmp... Sep 12 13:21:45 volumio systemd[1]: Received SIGRTMIN+20 from PID 324 (plymouthd). Sep 12 13:21:45 volumio systemd[1]: Mounted /var/spool/cups/tmp. Sep 12 13:21:45 volumio systemd[1]: Started Show Plymouth Boot Screen. Sep 12 13:21:45 volumio systemd-journald[295]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 3.7M, max 30.0M, 26.2M free. Sep 12 13:21:45 volumio systemd[1]: Started Flush Journal to Persistent Storage. Sep 12 13:21:45 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 12 13:21:45 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 12 13:21:45 volumio systemd[1]: Reached target Local Encrypted Volumes. Sep 12 13:21:45 volumio systemd[1]: Reached target Paths. Sep 12 13:21:45 volumio systemd[1]: Found device /dev/ttyS2. Sep 12 13:21:45 volumio systemd-udevd[321]: Using default interface naming scheme 'v240'. Sep 12 13:21:45 volumio kernel: I : [File] : drivers/gpu/arm/mali400/mali/linux/mali_kernel_linux.c; [Line] : 417; [Func] : mali_module_init(); svn_rev_string_from_arm of this mali_ko is '', rk_ko_ver is '5', built at '18:16:11', on 'Jan 23 2021'. Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/hdmi-sound[0]' Sep 12 13:21:45 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/hdmi-sound[0]' Sep 12 13:21:45 volumio kernel: asoc-simple-card hdmi-sound: i2s-hifi <-> ff000000.i2s mapping ok Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: mali_platform_device->num_resources = 9 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[0].start = 0x0x00000000ff300000 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[1].start = 0x0x00000000ff300000 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[2].start = 0x0x0000000000000012 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[3].start = 0x0x0000000000000013 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[4].start = 0x0x0000000000000014 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[5].start = 0x0x0000000000000015 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[6].start = 0x0x0000000000000016 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[7].start = 0x0x0000000000000017 Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: resource[8].start = 0x0x0000000000000018 Sep 12 13:21:45 volumio kernel: D : [File] : drivers/gpu/arm/mali400/mali/platform/rk/rk.c; [Line] : 623; [Func] : mali_platform_device_init(); to add platform_specific_data to platform_device_of_mali. Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: Looking up mali-supply from device tree Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: Failed to get leakage Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: Looking up mali-supply from device tree Sep 12 13:21:45 volumio kernel: vdd_logic: could not add device link ff300000.gpu err -17 Sep 12 13:21:45 volumio kernel: vdd_logic: Failed to create debugfs directory Sep 12 13:21:45 volumio kernel: mali-utgard ff300000.gpu: Failed to get pvtm Sep 12 13:21:45 volumio kernel: Mali: Mali device driver loaded Sep 12 13:21:45 volumio systemd-udevd[321]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 12 13:21:45 volumio systemd-udevd[321]: link_config: could not get ethtool features for eth0 Sep 12 13:21:45 volumio systemd-udevd[321]: Could not set offload features of eth0: Device or resource busy Sep 12 13:21:45 volumio kernel: devfreq ff300000.gpu: Couldn't update frequency transition information. Sep 12 13:21:45 volumio systemd-udevd[318]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 12 13:21:46 volumio systemd[1]: Found device /dev/disk/by-uuid/7FB8-6D8F. Sep 12 13:21:46 volumio systemd-udevd[316]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Sep 12 13:21:46 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 12 13:21:46 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/7FB8-6D8F... Sep 12 13:21:46 volumio systemd[1]: Started File System Check Daemon to report status. Sep 12 13:21:46 volumio systemd-fsck[379]: fsck.fat 4.1 (2017-01-24) Sep 12 13:21:46 volumio systemd-fsck[379]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sep 12 13:21:46 volumio systemd-fsck[379]: Automatically removing dirty bit. Sep 12 13:21:46 volumio systemd-fsck[379]: Performing changes. Sep 12 13:21:46 volumio systemd-fsck[379]: /dev/mmcblk0p1: 8 files, 12492/31161 clusters Sep 12 13:21:46 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/7FB8-6D8F. Sep 12 13:21:46 volumio systemd[1]: Mounting /boot... Sep 12 13:21:46 volumio kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 12 13:21:46 volumio systemd[1]: Mounted /boot. Sep 12 13:21:46 volumio systemd[1]: Reached target Local File Systems. Sep 12 13:21:46 volumio systemd[1]: Started ifup for eth0. Sep 12 13:21:46 volumio systemd[1]: Starting Preprocess NFS configuration... Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 12 13:21:46 volumio systemd[1]: Starting Create Volatile Files and Directories... Sep 12 13:21:46 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 12 13:21:46 volumio systemd[1]: nfs-config.service: Succeeded. Sep 12 13:21:46 volumio systemd[1]: Started Preprocess NFS configuration. Sep 12 13:21:46 volumio systemd[1]: plymouth-read-write.service: Succeeded. Sep 12 13:21:46 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 12 13:21:46 volumio systemd[1]: Received SIGRTMIN+20 from PID 324 (plymouthd). Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 12 13:21:46 volumio systemd[1]: Reached target NFS client services. Sep 12 13:21:46 volumio sh[384]: command failed: No such device (-19) Sep 12 13:21:46 volumio systemd[1]: Started Create Volatile Files and Directories. Sep 12 13:21:46 volumio systemd[1]: Starting RPC bind portmap service... Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 12 13:21:46 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 12 13:21:46 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 12 13:21:46 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 12 13:21:46 volumio systemd[1]: Reached target System Initialization. Sep 12 13:21:46 volumio systemd[1]: Listening on triggerhappy.socket. Sep 12 13:21:46 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 12 13:21:46 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 12 13:21:46 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 12 13:21:46 volumio systemd[1]: Listening on lircd.socket. Sep 12 13:21:46 volumio sh[384]: eth0: waiting for carrier Sep 12 13:21:46 volumio sh[384]: eth0: carrier acquired Sep 12 13:21:46 volumio dhcpcd[409]: eth0: waiting for carrier Sep 12 13:21:46 volumio dhcpcd[409]: eth0: carrier acquired Sep 12 13:21:46 volumio systemd[1]: Started Daily apt download activities. Sep 12 13:21:46 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 12 13:21:46 volumio systemd[1]: Reached target Timers. Sep 12 13:21:46 volumio sh[384]: DUID 00:01:00:01:29:87:24:71:a6:7e:fc:02:70:c5 Sep 12 13:21:46 volumio sh[384]: eth0: IAID fc:02:70:c5 Sep 12 13:21:46 volumio sh[384]: eth0: adding address fe80::a47e:fcff:fe02:70c5 Sep 12 13:21:46 volumio sh[384]: ipv6_addaddr1: Permission denied Sep 12 13:21:46 volumio dhcpcd[409]: DUID 00:01:00:01:29:87:24:71:a6:7e:fc:02:70:c5 Sep 12 13:21:46 volumio dhcpcd[409]: eth0: IAID fc:02:70:c5 Sep 12 13:21:46 volumio dhcpcd[409]: eth0: adding address fe80::a47e:fcff:fe02:70c5 Sep 12 13:21:46 volumio dhcpcd[409]: ipv6_addaddr1: Permission denied Sep 12 13:21:46 volumio systemd[1]: Listening on mpd.socket. Sep 12 13:21:46 volumio systemd[1]: Reached target Sockets. Sep 12 13:21:46 volumio systemd[1]: Reached target Basic System. Sep 12 13:21:46 volumio systemd[1]: Starting Login Service... Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 12 13:21:46 volumio systemd[1]: Starting DHCP Client Daemon... Sep 12 13:21:46 volumio systemd[1]: Starting lircd(8) initialization helper tool... Sep 12 13:21:46 volumio systemd[1]: Starting Wireless Services... Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 12 13:21:46 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 12 13:21:46 volumio systemd[1]: Started Manage Sound Card State (restore and store). Sep 12 13:21:46 volumio systemd[1]: Starting Save/Restore Sound Card State... Sep 12 13:21:46 volumio systemd[1]: Started volumio-remote-updater.service. Sep 12 13:21:46 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Sep 12 13:21:46 volumio alsactl[426]: alsactl 1.1.8 daemon started Sep 12 13:21:46 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 12 13:21:46 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 12 13:21:46 volumio systemd[1]: Started D-Bus System Message Bus. Sep 12 13:21:46 volumio dhcpcd[423]: DUID 00:01:00:01:29:87:24:71:a6:7e:fc:02:70:c5 Sep 12 13:21:46 volumio dhcpcd[423]: eth0: IAID fc:02:70:c5 Sep 12 13:21:46 volumio dhcpcd[423]: eth0: adding address fe80::a47e:fcff:fe02:70c5 Sep 12 13:21:46 volumio dhcpcd[423]: ipv6_addaddr1: Permission denied Sep 12 13:21:46 volumio dhcpcd[423]: DUID 00:01:00:01:29:87:24:71:a6:7e:fc:02:70:c5 Sep 12 13:21:46 volumio systemd-logind[422]: New seat seat0. Sep 12 13:21:46 volumio thd[431]: Unable to parse trigger line: Sep 12 13:21:46 volumio thd[431]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 12 13:21:46 volumio thd[431]: Unable to parse trigger line: Sep 12 13:21:46 volumio thd[431]: Found socket passed from systemd Sep 12 13:21:46 volumio dhcpcd[423]: eth0: IAID fc:02:70:c5 Sep 12 13:21:46 volumio systemd-logind[422]: Watching system buttons on /dev/input/event0 (rk8xx_pwrkey) Sep 12 13:21:46 volumio dhcpcd[423]: eth0: adding address fe80::a47e:fcff:fe02:70c5 Sep 12 13:21:46 volumio systemd[1]: Starting WPA supplicant... Sep 12 13:21:46 volumio dhcpcd[423]: ipv6_addaddr1: Permission denied Sep 12 13:21:46 volumio systemd[1]: Started Volumio Iptables Module. Sep 12 13:21:46 volumio systemd[1]: Started RPC bind portmap service. Sep 12 13:21:46 volumio systemd[1]: Started triggerhappy global hotkey daemon. Sep 12 13:21:46 volumio systemd[1]: Reached target Remote File Systems (Pre). Sep 12 13:21:46 volumio systemd[1]: Reached target Remote File Systems. Sep 12 13:21:46 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 12 13:21:46 volumio dhcpcd[423]: eth0: soliciting an IPv6 router Sep 12 13:21:46 volumio dhcpcd[423]: eth0: soliciting an IPv6 router Sep 12 13:21:46 volumio systemd[1]: Reached target RPC Port Mapper. Sep 12 13:21:46 volumio systemd[1]: Started Save/Restore Sound Card State. Sep 12 13:21:46 volumio systemd[1]: Reached target Sound Card. Sep 12 13:21:46 volumio avahi-daemon[433]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Sep 12 13:21:46 volumio avahi-daemon[433]: Successfully dropped root privileges. Sep 12 13:21:46 volumio avahi-daemon[433]: avahi-daemon 0.7 starting up. Sep 12 13:21:46 volumio sh[384]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:46 volumio dhcpcd[409]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:47 volumio systemd[1]: Started Login Service. Sep 12 13:21:47 volumio avahi-daemon[433]: Successfully called chroot(). Sep 12 13:21:47 volumio avahi-daemon[433]: Successfully dropped remaining capabilities. Sep 12 13:21:47 volumio avahi-daemon[433]: No service file found in /etc/avahi/services. Sep 12 13:21:47 volumio avahi-daemon[433]: Network interface enumeration completed. Sep 12 13:21:47 volumio avahi-daemon[433]: Server startup complete. Host name is volumio.local. Local service cookie is 2369302448. Sep 12 13:21:47 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 12 13:21:47 volumio kernel: nf_tables: (c) 2007-2009 Patrick McHardy Sep 12 13:21:47 volumio kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Sep 12 13:21:47 volumio systemd[1]: Started WPA supplicant. Sep 12 13:21:47 volumio wpa_supplicant[436]: Successfully initialized wpa_supplicant Sep 12 13:21:47 volumio kernel: nf_conntrack version 0.5.0 (7879 buckets, 31516 max) Sep 12 13:21:47 volumio kernel: random: nonblocking pool is initialized Sep 12 13:21:47 volumio loadcpufreq[438]: Loading cpufreq kernel modules...done (none). Sep 12 13:21:47 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 12 13:21:47 volumio kernel: usb 5-1.1: new SuperSpeed USB device number 3 using xhci-hcd Sep 12 13:21:47 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 12 13:21:47 volumio kernel: usb 5-1.1: New USB device found, idVendor=05e3, idProduct=0735 Sep 12 13:21:47 volumio kernel: usb 5-1.1: New USB device strings: Mfr=4, Product=5, SerialNumber=6 Sep 12 13:21:47 volumio kernel: usb 5-1.1: Product: USB to S-ATA Sep 12 13:21:47 volumio kernel: usb 5-1.1: Manufacturer: Genesyslogic Sep 12 13:21:47 volumio kernel: usb 5-1.1: SerialNumber: 0000000000000014 Sep 12 13:21:47 volumio volumio-remote-updater[429]: Error: No active session Sep 12 13:21:47 volumio volumio-remote-updater[429]: [2024-09-12 13:21:47] [info] asio async_connect error: system:111 (Connection refused) Sep 12 13:21:47 volumio volumio-remote-updater[429]: [2024-09-12 13:21:47] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 12 13:21:47 volumio volumio-remote-updater[429]: [2024-09-12 13:21:47] [error] handle_connect error: Underlying Transport Error Sep 12 13:21:47 volumio volumio[432]: Could not open config: /tmp/upmpdcli.conf Sep 12 13:21:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 12 13:21:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 12 13:21:47 volumio sh[384]: eth0: soliciting an IPv6 router Sep 12 13:21:47 volumio dhcpcd[409]: eth0: soliciting an IPv6 router Sep 12 13:21:47 volumio cpufrequtils[473]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Sep 12 13:21:47 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 12 13:21:47 volumio kernel: usb-storage 5-1.1:1.0: USB Mass Storage device detected Sep 12 13:21:47 volumio kernel: scsi host0: usb-storage 5-1.1:1.0 Sep 12 13:21:47 volumio kernel: usbcore: registered new interface driver usb-storage Sep 12 13:21:47 volumio kernel: usbcore: registered new interface driver uas Sep 12 13:21:47 volumio lircd-setup[424]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Sep 12 13:21:47 volumio lircd-setup[424]: parser = configparser.SafeConfigParser() Sep 12 13:21:47 volumio dhcpcd[423]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:47 volumio dhcpcd[423]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:47 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Sep 12 13:21:47 volumio systemd[1]: Starting Raise network interfaces... Sep 12 13:21:47 volumio systemd[1]: lircd-setup.service: Succeeded. Sep 12 13:21:47 volumio systemd[1]: Started lircd(8) initialization helper tool. Sep 12 13:21:47 volumio ifup[500]: command failed: No such device (-19) Sep 12 13:21:47 volumio ifup[500]: command failed: No such device (-19) Sep 12 13:21:48 volumio kernel: cfg80211: World regulatory domain updated: Sep 12 13:21:48 volumio kernel: cfg80211: DFS Master region: unset Sep 12 13:21:48 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Sep 12 13:21:48 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Sep 12 13:21:48 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Sep 12 13:21:48 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Sep 12 13:21:48 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Sep 12 13:21:48 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Sep 12 13:21:48 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Sep 12 13:21:48 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Sep 12 13:21:48 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Sep 12 13:21:48 volumio ifup[500]: command failed: No such device (-19) Sep 12 13:21:48 volumio systemd[1]: Started Raise network interfaces. Sep 12 13:21:48 volumio systemd[1]: Reached target Network. Sep 12 13:21:48 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 12 13:21:48 volumio systemd[1]: Starting OpenBSD Secure Shell server... Sep 12 13:21:48 volumio systemd[1]: Starting Permit User Sessions... Sep 12 13:21:48 volumio systemd[1]: Starting Music Player Daemon... Sep 12 13:21:48 volumio systemd[1]: Starting Flexible IR remote input/output application support... Sep 12 13:21:48 volumio systemd[1]: Starting Network Time Service... Sep 12 13:21:48 volumio systemd[1]: Reached target Network is Online. Sep 12 13:21:48 volumio systemd[1]: Starting Samba NMB Daemon... Sep 12 13:21:48 volumio lircd-0.10.1[615]: Info: lircd: Opening log, level: Info Sep 12 13:21:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Version: lircd 0.10.1 Sep 12 13:21:48 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Sep 12 13:21:48 volumio systemd[1]: Started Logitech Media Server. Sep 12 13:21:48 volumio systemd[1]: Starting /etc/rc.local Compatibility... Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: System info: Linux volumio 4.4.154 #5 SMP Sat Jan 23 18:17:37 CET 2021 aarch64 GNU/Linux Sep 12 13:21:48 volumio systemd[1]: Started Permit User Sessions. Sep 12 13:21:48 volumio ntpd[616]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 12 13:21:48 volumio ntpd[616]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 12 13:21:48 volumio systemd[1]: Started Network Time Service. Sep 12 13:21:48 volumio ntpd[634]: proto: precision = 0.875 usec (-20) Sep 12 13:21:48 volumio systemd[1]: Started /etc/rc.local Compatibility. Sep 12 13:21:48 volumio systemd[1]: iptables.service: Succeeded. Sep 12 13:21:48 volumio ntpd[634]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 12 13:21:48 volumio ntpd[634]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 12 13:21:48 volumio ntpd[634]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 808 days ago Sep 12 13:21:48 volumio ntpd[634]: Listen and drop on 0 v6wildcard [::]:123 Sep 12 13:21:48 volumio ntpd[634]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 12 13:21:48 volumio ntpd[634]: Listen normally on 2 lo 127.0.0.1:123 Sep 12 13:21:48 volumio ntpd[634]: Listening on routing socket on fd #19 for interface updates Sep 12 13:21:48 volumio ntpd[634]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:21:48 volumio ntpd[634]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:21:48 volumio sshd[631]: Server listening on 0.0.0.0 port 22. Sep 12 13:21:48 volumio sshd[631]: Server listening on :: port 22. Sep 12 13:21:48 volumio systemd[1]: Started OpenBSD Secure Shell server. Sep 12 13:21:48 volumio systemd[1]: Starting Hold until boot process finishes up... Sep 12 13:21:48 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 12 13:21:48 volumio systemd[1]: Received SIGRTMIN+21 from PID 324 (plymouthd). Sep 12 13:21:48 volumio sh[384]: eth0: carrier lost Sep 12 13:21:48 volumio dhcpcd[423]: eth0: carrier lost Sep 12 13:21:48 volumio dhcpcd[409]: eth0: carrier lost Sep 12 13:21:48 volumio dhcpcd[423]: eth0: carrier lost Sep 12 13:21:48 volumio systemd[1]: Received SIGRTMIN+21 from PID 324 (plymouthd). Sep 12 13:21:48 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 12 13:21:48 volumio systemd[1]: Started Hold until boot process finishes up. Sep 12 13:21:48 volumio systemd[1]: plymouth-start.service: Succeeded. Sep 12 13:21:48 volumio systemd[1]: plymouth-quit.service: Succeeded. Sep 12 13:21:48 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Sep 12 13:21:48 volumio ifplugd(eth0)[652]: ifplugd 0.28 initializing. Sep 12 13:21:48 volumio lircd-0.10.1[615]: Info: Initial device: auto Sep 12 13:21:48 volumio lircd-0.10.1[615]: Info: Initial device: auto Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: driver: devinput Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Info: lircd: Opening log, level: Info Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: Using systemd fd Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Warning: Running as root Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: output: /var/run/lirc/lircd Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: nodaemon: 1 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: logfile: syslog Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: immediate-init: 0 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: permission: 666 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: driver-options: Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Info: Using remote: devinput-64. Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: listen: 0 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: connect: (null) Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: userelease: 0 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: effective_user: (null) Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: release_suffix: _EVUP Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: allow_simulate: 0 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: repeat_max: 600 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: configfile: /etc/lirc/lircd.conf Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Options: dynamic_codes: (null) Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Current driver: devinput Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Driver API version: 4 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Driver version: 0.10.0 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Sep 12 13:21:48 volumio lircd-0.10.1[615]: Info: lircd: Opening log, level: Info Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: Using systemd fd Sep 12 13:21:48 volumio lircd-0.10.1[615]: Warning: Running as root Sep 12 13:21:48 volumio lircd-0.10.1[615]: Info: Using remote: devinput-64. Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Info: Using remote: devinput-32. Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Sep 12 13:21:48 volumio ifplugd(eth0)[652]: Using interface eth0/A6:7E:FC:02:70:C5 with driver (version: March_2013) Sep 12 13:21:48 volumio ifplugd(eth0)[652]: Using detection mode: SIOCETHTOOL Sep 12 13:21:48 volumio ifplugd(eth0)[652]: Initialization complete, link beat not detected. Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Sep 12 13:21:48 volumio lircd-0.10.1[615]: Info: Using remote: devinput-32. Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Sep 12 13:21:48 volumio systemd[1]: Started Serial Getty on ttyS2. Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Sep 12 13:21:48 volumio systemd[1]: Started Getty on tty1. Sep 12 13:21:48 volumio systemd[1]: Reached target Login Prompts. Sep 12 13:21:48 volumio haveged[405]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Sep 12 13:21:48 volumio haveged[405]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Sep 12 13:21:48 volumio haveged[405]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00187 Sep 12 13:21:48 volumio haveged[405]: haveged: fills: 0, generated: 0 Sep 12 13:21:48 volumio systemd[1]: Started Flexible IR remote input/output application support. Sep 12 13:21:48 volumio lircd[615]: lircd-0.10.1[615]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Sep 12 13:21:48 volumio lircd-0.10.1[615]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Sep 12 13:21:48 volumio ifplugd[622]: Network Interface Plugging Daemon...start eth0...done. Sep 12 13:21:48 volumio systemd[1]: Started LSB: Brings up/down network automatically. Sep 12 13:21:48 volumio dhcpcd[423]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Sep 12 13:21:49 volumio wireless.js[425]: WIRELESS DAEMON: start Sep 12 13:21:49 volumio wireless.js[425]: WIRELESS: Loaded configuration Sep 12 13:21:49 volumio wireless.js[425]: Cleaning previous... Sep 12 13:21:49 volumio nmbd[633]: [2024/09/12 13:21:49.237251, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 12 13:21:49 volumio nmbd[633]: started asyncdns process 693 Sep 12 13:21:49 volumio nmbd[633]: [2024/09/12 13:21:49.241194, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 12 13:21:49 volumio nmbd[633]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 12 13:21:49 volumio nmbd[633]: [2024/09/12 13:21:49.241544, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 12 13:21:49 volumio nmbd[633]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 12 13:21:49 volumio sudo[694]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 12 13:21:49 volumio sudo[694]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:49 volumio sudo[694]: pam_unix(sudo:session): session closed for user root Sep 12 13:21:49 volumio wireless.js[425]: Stopped aP Sep 12 13:21:49 volumio ntpd[634]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 12 13:21:49 volumio sudo[705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 12 13:21:49 volumio sudo[705]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:49 volumio sudo[705]: pam_unix(sudo:session): session closed for user root Sep 12 13:21:49 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:21:49 volumio hostapd[719]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:21:49 volumio hostapd[719]: Could not read interface wlan0 flags: No such device Sep 12 13:21:49 volumio hostapd[719]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:21:49 volumio hostapd[719]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:21:49 volumio hostapd[719]: Could not read interface wlan0 flags: No such device Sep 12 13:21:49 volumio hostapd[719]: nl80211 driver initialization failed. Sep 12 13:21:49 volumio hostapd[719]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:21:49 volumio hostapd[719]: wlan0: AP-DISABLED Sep 12 13:21:49 volumio hostapd[719]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:21:49 volumio hostapd[719]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:21:49 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:21:49 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:21:49 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:49 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:49 volumio dnsmasq[726]: dnsmasq: syntax check OK. Sep 12 13:21:49 volumio iw[727]: command failed: No such device (-19) Sep 12 13:21:49 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=237/KEYRING Sep 12 13:21:49 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Sep 12 13:21:49 volumio systemd[1]: Failed to start Wireless Services. Sep 12 13:21:49 volumio systemd[1]: Started Volumio Backend Module. Sep 12 13:21:49 volumio systemd[1]: Started Volumio Streaming Daemon. Sep 12 13:21:49 volumio kernel: scsi 0:0:0:0: Direct-Access Genesys USB3/SATA Bridge TB12 PQ: 0 ANSI: 6 Sep 12 13:21:49 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Sep 12 13:21:50 volumio dnsmasq[749]: started, version 2.80 cachesize 150 Sep 12 13:21:50 volumio dnsmasq[749]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:21:50 volumio dnsmasq[749]: warning: interface wlan0 does not currently exist Sep 12 13:21:50 volumio dnsmasq-dhcp[749]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:21:50 volumio dnsmasq[749]: reading /etc/resolv.conf Sep 12 13:21:50 volumio dnsmasq[749]: using nameserver 208.67.222.222#53 Sep 12 13:21:50 volumio dnsmasq[749]: using nameserver 208.67.220.220#53 Sep 12 13:21:50 volumio dnsmasq[749]: read /etc/hosts - 5 addresses Sep 12 13:21:50 volumio volumio-streaming-daemon[734]: ############################ Sep 12 13:21:50 volumio volumio-streaming-daemon[734]: # Volumio Streaming Daemon # Sep 12 13:21:50 volumio volumio-streaming-daemon[734]: # Running on port 7777 # Sep 12 13:21:50 volumio volumio-streaming-daemon[734]: ############################ Sep 12 13:21:50 volumio volumio-streaming-daemon[734]: Environment: Sep 12 13:21:50 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:50 volumio systemd[1]: Reached target Host and Network Name Lookups. Sep 12 13:21:50 volumio ntpd[634]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 12 13:21:51 volumio ntpd[634]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 12 13:21:51 volumio dhcpcd[423]: eth0: carrier acquired Sep 12 13:21:51 volumio dhcpcd[423]: eth0: carrier acquired Sep 12 13:21:51 volumio sh[384]: eth0: carrier acquired Sep 12 13:21:51 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 12 13:21:51 volumio dhcpcd[409]: eth0: carrier acquired Sep 12 13:21:51 volumio dhcpcd[423]: eth0: IAID fc:02:70:c5 Sep 12 13:21:51 volumio dhcpcd[423]: eth0: IAID fc:02:70:c5 Sep 12 13:21:51 volumio sh[384]: eth0: IAID fc:02:70:c5 Sep 12 13:21:51 volumio dhcpcd[409]: eth0: IAID fc:02:70:c5 Sep 12 13:21:51 volumio dhcpcd[423]: eth0: soliciting an IPv6 router Sep 12 13:21:51 volumio dhcpcd[423]: eth0: soliciting an IPv6 router Sep 12 13:21:51 volumio mpd[689]: Sep 12 13:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 12 13:21:51 volumio mpd[689]: Sep 12 13:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 12 13:21:51 volumio mpd[689]: Sep 12 13:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 12 13:21:51 volumio ifplugd(eth0)[652]: Link beat detected. Sep 12 13:21:51 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:21:51 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 1. Sep 12 13:21:51 volumio systemd[1]: Started Music Player Daemon. Sep 12 13:21:51 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:51 volumio sh[384]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:51 volumio dhcpcd[409]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:51 volumio sh[384]: eth0: probing address 192.168.10.82/24 Sep 12 13:21:51 volumio dhcpcd[409]: eth0: probing address 192.168.10.82/24 Sep 12 13:21:51 volumio dnsmasq[749]: exiting on receipt of SIGTERM Sep 12 13:21:51 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:21:51 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:51 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:51 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:21:51 volumio hostapd[764]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:21:51 volumio dhcpcd[423]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:51 volumio dhcpcd[423]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:21:51 volumio dhcpcd[423]: eth0: probing address 192.168.10.82/24 Sep 12 13:21:51 volumio dhcpcd[423]: eth0: probing address 192.168.10.82/24 Sep 12 13:21:51 volumio ifplugd(eth0)[652]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 12 13:21:51 volumio hostapd[764]: Could not read interface wlan0 flags: No such device Sep 12 13:21:51 volumio hostapd[764]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:21:51 volumio hostapd[764]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:21:51 volumio ifplugd(eth0)[652]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Sep 12 13:21:52 volumio hostapd[764]: Could not read interface wlan0 flags: No such device Sep 12 13:21:52 volumio hostapd[764]: nl80211 driver initialization failed. Sep 12 13:21:52 volumio hostapd[764]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:21:52 volumio hostapd[764]: wlan0: AP-DISABLED Sep 12 13:21:52 volumio hostapd[764]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:21:52 volumio hostapd[764]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:21:52 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:21:52 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:21:52 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:52 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:52 volumio dnsmasq[776]: dnsmasq: syntax check OK. Sep 12 13:21:52 volumio dnsmasq[782]: started, version 2.80 cachesize 150 Sep 12 13:21:52 volumio dnsmasq[782]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:21:52 volumio dnsmasq[782]: warning: interface wlan0 does not currently exist Sep 12 13:21:52 volumio dnsmasq-dhcp[782]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:21:52 volumio dnsmasq[782]: reading /etc/resolv.conf Sep 12 13:21:52 volumio dnsmasq[782]: using nameserver 208.67.222.222#53 Sep 12 13:21:52 volumio dnsmasq[782]: using nameserver 208.67.220.220#53 Sep 12 13:21:52 volumio dnsmasq[782]: read /etc/hosts - 5 addresses Sep 12 13:21:52 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:52 volumio sh[384]: eth0: soliciting an IPv6 router Sep 12 13:21:52 volumio dhcpcd[409]: eth0: soliciting an IPv6 router Sep 12 13:21:52 volumio ntpd[634]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 12 13:21:52 volumio volumio-remote-updater[429]: [2024-09-12 13:21:52] [connect] Successful connection Sep 12 13:21:53 volumio volumio[732]: info: ------------------------------------------- Sep 12 13:21:53 volumio volumio[732]: info: ----- Volumio3 ---- Sep 12 13:21:53 volumio volumio[732]: info: ------------------------------------------- Sep 12 13:21:53 volumio volumio[732]: info: ----- System startup ---- Sep 12 13:21:53 volumio volumio[732]: info: ------------------------------------------- Sep 12 13:21:54 volumio volumio[732]: info: MYVOLUMIO Environment detected Sep 12 13:21:54 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:21:54 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2. Sep 12 13:21:54 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:54 volumio dnsmasq[782]: exiting on receipt of SIGTERM Sep 12 13:21:54 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:21:54 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:54 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:54 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:21:54 volumio hostapd[802]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:21:54 volumio hostapd[802]: Could not read interface wlan0 flags: No such device Sep 12 13:21:54 volumio hostapd[802]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:21:54 volumio hostapd[802]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:21:54 volumio hostapd[802]: Could not read interface wlan0 flags: No such device Sep 12 13:21:54 volumio hostapd[802]: nl80211 driver initialization failed. Sep 12 13:21:54 volumio hostapd[802]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:21:54 volumio hostapd[802]: wlan0: AP-DISABLED Sep 12 13:21:54 volumio hostapd[802]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:21:54 volumio hostapd[802]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:21:54 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:21:54 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:21:54 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:54 volumio volumio[732]: info: Plugin folders cleanup Sep 12 13:21:54 volumio volumio[732]: info: Scanning into folder /volumio/app/plugins/ Sep 12 13:21:54 volumio volumio[732]: info: Scanning category audio_interface Sep 12 13:21:54 volumio volumio[732]: info: Scanning category miscellanea Sep 12 13:21:54 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:54 volumio volumio[732]: info: Scanning category music_service Sep 12 13:21:54 volumio volumio[732]: info: Scanning category plugins.json Sep 12 13:21:54 volumio volumio[732]: info: Scanning category system_controller Sep 12 13:21:54 volumio dnsmasq[809]: dnsmasq: syntax check OK. Sep 12 13:21:54 volumio volumio[732]: info: Scanning category user_interface Sep 12 13:21:54 volumio volumio[732]: info: Scanning into folder /data/plugins/ Sep 12 13:21:54 volumio volumio[732]: info: Scanning category music_service Sep 12 13:21:54 volumio volumio[732]: info: Plugin folders cleanup completed Sep 12 13:21:54 volumio volumio[732]: info: ------------------------------------------- Sep 12 13:21:54 volumio volumio[732]: info: ----- Core plugins startup ---- Sep 12 13:21:54 volumio volumio[732]: info: ------------------------------------------- Sep 12 13:21:54 volumio volumio[732]: info: Loading plugins from folder /volumio/app/plugins/ Sep 12 13:21:54 volumio volumio[732]: info: Adding plugin upnp to MyMusic Plugins Sep 12 13:21:54 volumio volumio[732]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 12 13:21:54 volumio volumio[732]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 12 13:21:54 volumio volumio[732]: info: Loading plugins from folder /data/plugins/ Sep 12 13:21:54 volumio volumio[732]: info: Loading plugin "system"... Sep 12 13:21:54 volumio dnsmasq[815]: started, version 2.80 cachesize 150 Sep 12 13:21:54 volumio dnsmasq[815]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:21:54 volumio dnsmasq[815]: warning: interface wlan0 does not currently exist Sep 12 13:21:54 volumio dnsmasq-dhcp[815]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:21:54 volumio dnsmasq[815]: reading /etc/resolv.conf Sep 12 13:21:54 volumio dnsmasq[815]: using nameserver 208.67.222.222#53 Sep 12 13:21:54 volumio dnsmasq[815]: using nameserver 208.67.220.220#53 Sep 12 13:21:54 volumio dnsmasq[815]: read /etc/hosts - 5 addresses Sep 12 13:21:54 volumio volumio[732]: info: Loading plugin "appearance"... Sep 12 13:21:54 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "network"... Sep 12 13:21:56 volumio dhcpcd[423]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:21:56 volumio dhcpcd[423]: eth0: adding route to 192.168.10.0/24 Sep 12 13:21:56 volumio dhcpcd[423]: eth0: adding default route via 192.168.10.1 Sep 12 13:21:56 volumio volumio[732]: info: Refreshing Cached IP Addresses Sep 12 13:21:56 volumio dhcpcd[423]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:21:56 volumio dhcpcd[423]: eth0: adding route to 192.168.10.0/24 Sep 12 13:21:56 volumio dhcpcd[423]: eth0: adding default route via 192.168.10.1 Sep 12 13:21:56 volumio avahi-daemon[433]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.82. Sep 12 13:21:56 volumio avahi-daemon[433]: New relevant interface eth0.IPv4 for mDNS. Sep 12 13:21:56 volumio avahi-daemon[433]: Registering new address record for 192.168.10.82 on eth0.IPv4. Sep 12 13:21:56 volumio sudo[827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 12 13:21:56 volumio sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:56 volumio dnsmasq[815]: reading /etc/resolv.conf Sep 12 13:21:56 volumio dnsmasq[815]: using nameserver 192.168.10.1#53 Sep 12 13:21:56 volumio dnsmasq[815]: using nameserver 208.67.222.222#53 Sep 12 13:21:56 volumio dnsmasq[815]: using nameserver 208.67.220.220#53 Sep 12 13:21:56 volumio sudo[827]: pam_unix(sudo:session): session closed for user root Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "services"... Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "alsa_controller"... Sep 12 13:21:56 volumio sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 12 13:21:56 volumio sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:56 volumio sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 12 13:21:56 volumio sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:56 volumio sudo[845]: pam_unix(sudo:session): session closed for user root Sep 12 13:21:56 volumio dhcpcd[423]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Sep 12 13:21:56 volumio sudo[842]: pam_unix(sudo:session): session closed for user root Sep 12 13:21:56 volumio volumio[732]: warn: Unable to locate the audio output device SilkLine USB Audio 2.0. Please configure a valid output device. Sep 12 13:21:56 volumio volumio[732]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Sep 12 13:21:56 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:21:56 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 3. Sep 12 13:21:56 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:56 volumio ntpd[634]: ntpd exiting on signal 15 (Terminated) Sep 12 13:21:56 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 12 13:21:56 volumio volumio[732]: info: Found match in Cards Database: setting mixer HDMI for card HDMI Sep 12 13:21:56 volumio systemd[1]: Stopping Network Time Service... Sep 12 13:21:56 volumio systemd[1]: ntp.service: Succeeded. Sep 12 13:21:56 volumio systemd[1]: Stopped Network Time Service. Sep 12 13:21:56 volumio systemd[1]: Starting Network Time Service... Sep 12 13:21:56 volumio dnsmasq[815]: exiting on receipt of SIGTERM Sep 12 13:21:56 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:21:56 volumio volumio[732]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "wizard"... Sep 12 13:21:56 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:56 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:56 volumio ntpd[880]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 12 13:21:56 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:21:56 volumio ntpd[880]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 12 13:21:56 volumio systemd[1]: Started Network Time Service. Sep 12 13:21:56 volumio ntpd[888]: proto: precision = 0.874 usec (-20) Sep 12 13:21:56 volumio ntpd[888]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 12 13:21:56 volumio dhcpcd[423]: forked to background, child pid 889 Sep 12 13:21:56 volumio dhcpcd[423]: forked to background, child pid 889 Sep 12 13:21:56 volumio ntpd[888]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 12 13:21:56 volumio ntpd[888]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 808 days ago Sep 12 13:21:56 volumio ntpd[888]: Listen and drop on 0 v6wildcard [::]:123 Sep 12 13:21:56 volumio systemd[1]: Started DHCP Client Daemon. Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "volumio_command_line_client"... Sep 12 13:21:56 volumio ntpd[888]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 12 13:21:56 volumio ntpd[888]: Listen normally on 2 lo 127.0.0.1:123 Sep 12 13:21:56 volumio ntpd[888]: Listen normally on 3 eth0 192.168.10.82:123 Sep 12 13:21:56 volumio ntpd[888]: Listening on routing socket on fd #20 for interface updates Sep 12 13:21:56 volumio ntpd[888]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:21:56 volumio ntpd[888]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "upnp"... Sep 12 13:21:56 volumio hostapd[887]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:21:56 volumio volumio[732]: info: [1726147316539] Starting Upmpd Daemon Sep 12 13:21:56 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "my_music"... Sep 12 13:21:56 volumio volumio[732]: info: Loading plugin "mpd"... Sep 12 13:21:56 volumio hostapd[887]: Could not read interface wlan0 flags: No such device Sep 12 13:21:56 volumio hostapd[887]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:21:56 volumio hostapd[887]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:21:56 volumio hostapd[887]: Could not read interface wlan0 flags: No such device Sep 12 13:21:56 volumio hostapd[887]: nl80211 driver initialization failed. Sep 12 13:21:56 volumio hostapd[887]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:21:56 volumio hostapd[887]: wlan0: AP-DISABLED Sep 12 13:21:56 volumio hostapd[887]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:21:56 volumio hostapd[887]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:21:56 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:21:56 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:21:56 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:56 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:56 volumio dnsmasq[898]: dnsmasq: syntax check OK. Sep 12 13:21:56 volumio dnsmasq[904]: started, version 2.80 cachesize 150 Sep 12 13:21:56 volumio dnsmasq[904]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:21:56 volumio dnsmasq[904]: warning: interface wlan0 does not currently exist Sep 12 13:21:56 volumio dnsmasq-dhcp[904]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:21:56 volumio dnsmasq[904]: reading /etc/resolv.conf Sep 12 13:21:56 volumio dnsmasq[904]: using nameserver 192.168.10.1#53 Sep 12 13:21:56 volumio dnsmasq[904]: using nameserver 208.67.222.222#53 Sep 12 13:21:56 volumio dnsmasq[904]: using nameserver 208.67.220.220#53 Sep 12 13:21:56 volumio dnsmasq[904]: read /etc/hosts - 5 addresses Sep 12 13:21:56 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:56 volumio sh[384]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:21:56 volumio sh[384]: eth0: adding route to 192.168.10.0/24 Sep 12 13:21:56 volumio sh[384]: eth0: adding default route via 192.168.10.1 Sep 12 13:21:56 volumio dhcpcd[409]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:21:56 volumio dhcpcd[409]: eth0: adding route to 192.168.10.0/24 Sep 12 13:21:56 volumio dhcpcd[409]: eth0: adding default route via 192.168.10.1 Sep 12 13:21:56 volumio sh[384]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Sep 12 13:21:57 volumio ntpd[888]: ntpd exiting on signal 15 (Terminated) Sep 12 13:21:57 volumio systemd[1]: Stopping Network Time Service... Sep 12 13:21:57 volumio systemd[1]: ntp.service: Succeeded. Sep 12 13:21:57 volumio systemd[1]: Stopped Network Time Service. Sep 12 13:21:57 volumio systemd[1]: Starting Network Time Service... Sep 12 13:21:57 volumio ntpd[943]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 12 13:21:57 volumio ntpd[943]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 12 13:21:57 volumio systemd[1]: Started Network Time Service. Sep 12 13:21:57 volumio ntpd[949]: proto: precision = 0.875 usec (-20) Sep 12 13:21:57 volumio dhcpcd[409]: forked to background, child pid 950 Sep 12 13:21:57 volumio sh[384]: forked to background, child pid 950 Sep 12 13:21:57 volumio ntpd[949]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 12 13:21:57 volumio ntpd[949]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 12 13:21:57 volumio ntpd[949]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 808 days ago Sep 12 13:21:57 volumio ntpd[949]: Listen and drop on 0 v6wildcard [::]:123 Sep 12 13:21:57 volumio ntpd[949]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 12 13:21:57 volumio ntpd[949]: Listen normally on 2 lo 127.0.0.1:123 Sep 12 13:21:57 volumio ntpd[949]: Listen normally on 3 eth0 192.168.10.82:123 Sep 12 13:21:57 volumio ntpd[949]: Listening on routing socket on fd #20 for interface updates Sep 12 13:21:57 volumio ntpd[949]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:21:57 volumio ntpd[949]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:21:57 volumio volumio[732]: info: Creating MPD Configuration file Sep 12 13:21:57 volumio volumio[732]: info: Loading plugin "upnp_browser"... Sep 12 13:21:57 volumio sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 12 13:21:57 volumio sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:57 volumio sudo[960]: pam_unix(sudo:session): session closed for user root Sep 12 13:21:57 volumio sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 12 13:21:57 volumio sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:21:57 volumio systemd[1]: Stopping Music Player Daemon... Sep 12 13:21:57 volumio ifplugd(eth0)[652]: client: ifup: interface eth0 already configured Sep 12 13:21:57 volumio sh[384]: eth0=eth0 Sep 12 13:21:57 volumio ifplugd(eth0)[652]: Program executed successfully. Sep 12 13:21:57 volumio systemd[1]: mpd.service: Succeeded. Sep 12 13:21:57 volumio systemd[1]: Stopped Music Player Daemon. Sep 12 13:21:57 volumio systemd[1]: Starting Music Player Daemon... Sep 12 13:21:57 volumio volumio[732]: info: Loading plugin "networkfs"... Sep 12 13:21:58 volumio volumio[732]: info: Starting Udev Watcher for removable devices Sep 12 13:21:58 volumio ntpd[949]: Soliciting pool server 193.182.111.143 Sep 12 13:21:58 volumio volumio[732]: info: Ignoring mount for partition: boot Sep 12 13:21:58 volumio volumio[732]: info: Ignoring mount for partition: volumio Sep 12 13:21:58 volumio volumio[732]: info: Ignoring mount for partition: volumio_data Sep 12 13:21:58 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 12 13:21:58 volumio volumio[732]: info: Loading plugin "alarm-clock"... Sep 12 13:21:58 volumio volumio[732]: info: Loading plugin "airplay_emulation"... Sep 12 13:21:58 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:21:58 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 4. Sep 12 13:21:58 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:58 volumio volumio[732]: info: Starting Shairport Sync Sep 12 13:21:58 volumio volumio[732]: info: Loading plugin "last_100"... Sep 12 13:21:58 volumio volumio[732]: info: Loading plugin "webradio"... Sep 12 13:21:58 volumio dnsmasq[904]: exiting on receipt of SIGTERM Sep 12 13:21:58 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:21:58 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:58 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:58 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:21:58 volumio hostapd[992]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:21:59 volumio hostapd[992]: Could not read interface wlan0 flags: No such device Sep 12 13:21:59 volumio hostapd[992]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:21:59 volumio hostapd[992]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:21:59 volumio hostapd[992]: Could not read interface wlan0 flags: No such device Sep 12 13:21:59 volumio hostapd[992]: nl80211 driver initialization failed. Sep 12 13:21:59 volumio hostapd[992]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:21:59 volumio hostapd[992]: wlan0: AP-DISABLED Sep 12 13:21:59 volumio hostapd[992]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:21:59 volumio hostapd[992]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:21:59 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:21:59 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:21:59 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:21:59 volumio volumio[732]: info: Loading plugin "i2s_dacs"... Sep 12 13:21:59 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:21:59 volumio dnsmasq[999]: dnsmasq: syntax check OK. Sep 12 13:21:59 volumio volumio[732]: info: I2S DAC not set, start Auto-detection Sep 12 13:21:59 volumio volumio[732]: info: Loading plugin "volumiodiscovery"... Sep 12 13:21:59 volumio ntpd[949]: Soliciting pool server 193.182.111.14 Sep 12 13:21:59 volumio dnsmasq[1005]: started, version 2.80 cachesize 150 Sep 12 13:21:59 volumio dnsmasq[1005]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:21:59 volumio dnsmasq[1005]: warning: interface wlan0 does not currently exist Sep 12 13:21:59 volumio dnsmasq-dhcp[1005]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:21:59 volumio dnsmasq[1005]: reading /etc/resolv.conf Sep 12 13:21:59 volumio dnsmasq[1005]: using nameserver 192.168.10.1#53 Sep 12 13:21:59 volumio dnsmasq[1005]: using nameserver 208.67.222.222#53 Sep 12 13:21:59 volumio dnsmasq[1005]: using nameserver 208.67.220.220#53 Sep 12 13:21:59 volumio dnsmasq[1005]: read /etc/hosts - 5 addresses Sep 12 13:21:59 volumio volumio[732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 12 13:21:59 volumio volumio[732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:21:59 volumio volumio[732]: *** WARNING *** For more information see Sep 12 13:21:59 volumio node[732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 12 13:21:59 volumio volumio[732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 12 13:21:59 volumio volumio[732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:21:59 volumio volumio[732]: *** WARNING *** For more information see Sep 12 13:21:59 volumio node[732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:21:59 volumio node[732]: *** WARNING *** For more information see Sep 12 13:21:59 volumio node[732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 12 13:21:59 volumio node[732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:21:59 volumio node[732]: *** WARNING *** For more information see Sep 12 13:21:59 volumio volumio[732]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 12 13:21:59 volumio volumio[732]: info: Discovery: Started advertising with name: Volumio Sep 12 13:21:59 volumio ntpd[949]: Soliciting pool server 202.118.1.81 Sep 12 13:21:59 volumio nmbd[633]: [2024/09/12 13:21:59.246493, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 12 13:21:59 volumio nmbd[633]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 12 13:21:59 volumio systemd[1]: Started Samba NMB Daemon. Sep 12 13:21:59 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 12 13:21:59 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:21:59 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 12 13:21:59 volumio volumio[732]: info: Loading plugin "squeezelite_mc"... Sep 12 13:21:59 volumio winbindd[1012]: [2024/09/12 13:21:59.675097, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 12 13:21:59 volumio winbindd[1012]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 12 13:21:59 volumio systemd[1]: Started Samba Winbind Daemon. Sep 12 13:21:59 volumio systemd[1]: Starting Samba SMB Daemon... Sep 12 13:21:59 volumio winbindd[1012]: [2024/09/12 13:21:59.704626, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 12 13:21:59 volumio winbindd[1012]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 12 13:21:59 volumio mpd[986]: Sep 12 13:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 12 13:21:59 volumio mpd[986]: Sep 12 13:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 12 13:21:59 volumio mpd[986]: Sep 12 13:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 12 13:21:59 volumio systemd[1]: Started Music Player Daemon. Sep 12 13:21:59 volumio sudo[973]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:00 volumio ntpd[949]: Soliciting pool server 176.126.78.152 Sep 12 13:22:00 volumio ntpd[949]: Soliciting pool server 162.159.200.123 Sep 12 13:22:00 volumio ntpd[949]: Soliciting pool server 202.118.1.130 Sep 12 13:22:00 volumio winbindd[1012]: [2024/09/12 13:22:00.299930, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Sep 12 13:22:00 volumio winbindd[1012]: res_names->count = 3, expected 4 Sep 12 13:22:00 volumio systemd[1]: Started Samba SMB Daemon. Sep 12 13:22:00 volumio systemd[1]: Reached target Multi-User System. Sep 12 13:22:00 volumio systemd[1]: Reached target Graphical Interface. Sep 12 13:22:00 volumio smbd[1018]: [2024/09/12 13:22:00.329473, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 12 13:22:00 volumio smbd[1018]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 12 13:22:00 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 12 13:22:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 12 13:22:00 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 12 13:22:00 volumio systemd[1]: Startup finished in 10.136s (kernel) + 16.367s (userspace) = 26.504s. Sep 12 13:22:00 volumio volumio[732]: info: Loading plugin "outputs"... Sep 12 13:22:00 volumio volumio[732]: info: Loading plugin "albumart"... Sep 12 13:22:00 volumio volumio[732]: info: Plugin example_plugin is not enabled Sep 12 13:22:00 volumio volumio[732]: info: Loading plugin "inputs"... Sep 12 13:22:00 volumio volumio[732]: info: Loading plugin "updater_comm"... Sep 12 13:22:01 volumio volumio[732]: info: Plugin mpdemulation is not enabled Sep 12 13:22:01 volumio volumio[732]: info: Loading plugin "rest_api"... Sep 12 13:22:01 volumio volumio[732]: info: Loading plugin "websocket"... Sep 12 13:22:01 volumio volumio[732]: info: Loading plugin "RoonBridge"... Sep 12 13:22:01 volumio ntpd[949]: Soliciting pool server 162.159.200.1 Sep 12 13:22:01 volumio volumio[732]: Forking 3 albumart workers Sep 12 13:22:01 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:01 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 5. Sep 12 13:22:01 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:01 volumio dnsmasq[1005]: exiting on receipt of SIGTERM Sep 12 13:22:01 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:01 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:01 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:01 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:01 volumio hostapd[1060]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:01 volumio volumio[732]: info: Applying required configuration parameters for plugin RoonBridge Sep 12 13:22:01 volumio volumio[732]: info: Loading i18n strings for locale zh Sep 12 13:22:01 volumio hostapd[1060]: Could not read interface wlan0 flags: No such device Sep 12 13:22:01 volumio hostapd[1060]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:01 volumio hostapd[1060]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:01 volumio hostapd[1060]: Could not read interface wlan0 flags: No such device Sep 12 13:22:01 volumio hostapd[1060]: nl80211 driver initialization failed. Sep 12 13:22:01 volumio hostapd[1060]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:01 volumio hostapd[1060]: wlan0: AP-DISABLED Sep 12 13:22:01 volumio hostapd[1060]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:01 volumio hostapd[1060]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:01 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:01 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:01 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:01 volumio volumio[732]: Updating browse sources language Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:01 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:01 volumio dnsmasq[1068]: dnsmasq: syntax check OK. Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::initPlayerControls Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:01 volumio volumio[732]: Express server listening on port 3000 Sep 12 13:22:01 volumio volumio[732]: [Metrics] WebUI: 9s 605.16ms Sep 12 13:22:01 volumio dhcpcd[950]: eth0: carrier lost Sep 12 13:22:01 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Sep 12 13:22:01 volumio dhcpcd[889]: eth0: carrier lost Sep 12 13:22:01 volumio volumio[732]: info: CoreStateMachine::resetVolumioState Sep 12 13:22:01 volumio volumio[732]: info: CoreStateMachine::getcurrentVolume Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:01 volumio dnsmasq[1085]: started, version 2.80 cachesize 150 Sep 12 13:22:01 volumio dnsmasq[1085]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:01 volumio dnsmasq[1085]: warning: interface wlan0 does not currently exist Sep 12 13:22:01 volumio dnsmasq-dhcp[1085]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:01 volumio dnsmasq[1085]: reading /etc/resolv.conf Sep 12 13:22:01 volumio dnsmasq[1085]: using nameserver 192.168.10.1#53 Sep 12 13:22:01 volumio dnsmasq[1085]: using nameserver 208.67.222.222#53 Sep 12 13:22:01 volumio dnsmasq[1085]: using nameserver 208.67.220.220#53 Sep 12 13:22:01 volumio dnsmasq[1085]: read /etc/hosts - 5 addresses Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 12 13:22:01 volumio volumio[732]: info: CoreStateMachine::getcurrentVolume Sep 12 13:22:01 volumio volumio[732]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:01 volumio dnsmasq[1085]: reading /etc/resolv.conf Sep 12 13:22:01 volumio dnsmasq[1085]: using nameserver 192.168.10.1#53 Sep 12 13:22:01 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:01 volumio volumio[732]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 12 13:22:01 volumio volumio[732]: info: Completed loading Core Plugins Sep 12 13:22:01 volumio dnsmasq[1085]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:01 volumio volumio[732]: info: Preparing to generate the ALSA configuration file Sep 12 13:22:01 volumio avahi-daemon[433]: Withdrawing address record for 192.168.10.82 on eth0. Sep 12 13:22:01 volumio avahi-daemon[433]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.10.82. Sep 12 13:22:01 volumio avahi-daemon[433]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 12 13:22:01 volumio dhcpcd[889]: eth0: deleting route to 192.168.10.0/24 Sep 12 13:22:01 volumio dhcpcd[889]: eth0: deleting default route via 192.168.10.1 Sep 12 13:22:01 volumio dhcpcd[950]: eth0: deleting route to 192.168.10.0/24 Sep 12 13:22:01 volumio dhcpcd[950]: eth0: deleting default route via 192.168.10.1 Sep 12 13:22:02 volumio volumio[732]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Sep 12 13:22:02 volumio volumio[732]: info: MPD Permissions set Sep 12 13:22:02 volumio volumio[732]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 12 13:22:02 volumio volumio[732]: wlan0 Interface doesn't support scanning. Sep 12 13:22:02 volumio volumio[732]: info: Cannot use regular scanning, forcing with ap-force Sep 12 13:22:02 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 12 13:22:02 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:02 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:02 volumio volumio[732]: command failed: No such device (-19) Sep 12 13:22:02 volumio volumio[732]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 12 13:22:02 volumio volumio[732]: command failed: No such device (-19) Sep 12 13:22:02 volumio ifplugd(eth0)[652]: Link beat lost. Sep 12 13:22:02 volumio volumio[732]: info: MPD running with PID986 Sep 12 13:22:02 volumio volumio[732]: ,establishing connection Sep 12 13:22:02 volumio volumio[732]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:02 volumio volumio[732]: Starting albumart workers Sep 12 13:22:02 volumio volumio[732]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:02 volumio volumio[732]: Starting albumart workers Sep 12 13:22:02 volumio volumio[732]: error: MPD error: The expression evaluated to a falsy value: Sep 12 13:22:02 volumio volumio[732]: assert.ok(self.idling) Sep 12 13:22:02 volumio volumio[732]: error: MPD error: The expression evaluated to a falsy value: Sep 12 13:22:02 volumio volumio[732]: assert.ok(self.idling) Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:02 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:03 volumio volumio[732]: info: Reloading queue from file Sep 12 13:22:03 volumio volumio[732]: Starting albumart workers Sep 12 13:22:03 volumio volumio[732]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:03 volumio volumio[732]: info: CoreStateMachine::pushState Sep 12 13:22:03 volumio volumio[732]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:03 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock Sep 12 13:22:03 volumio volumio[732]: info: CorePlayQueue::getTrackBlock Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:03 volumio volumio[732]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:03 volumio volumio[732]: info: CoreStateMachine::pushState Sep 12 13:22:03 volumio volumio[732]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:03 volumio ntpd[949]: Deleting interface #3 eth0, 192.168.10.82#123, interface stats: received=5, sent=10, dropped=2, active_time=6 secs Sep 12 13:22:03 volumio ntpd[949]: 202.118.1.130 local addr 192.168.10.82 -> Sep 12 13:22:03 volumio ntpd[949]: 193.182.111.14 local addr 192.168.10.82 -> Sep 12 13:22:03 volumio ntpd[949]: 202.118.1.81 local addr 192.168.10.82 -> Sep 12 13:22:03 volumio ntpd[949]: 193.182.111.143 local addr 192.168.10.82 -> Sep 12 13:22:03 volumio volumio[732]: info: CoreStateMachine::updateTrackBlock Sep 12 13:22:03 volumio volumio[732]: info: CorePlayQueue::getTrackBlock Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:03 volumio volumio[732]: info: Asound.conf file unchanged, so no further update is needed Sep 12 13:22:03 volumio volumio[732]: info: Output device has changed, restarting MPD Sep 12 13:22:03 volumio volumio[732]: info: Output device has changed, restarting Shairport Sync Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:03 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 12 13:22:03 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:03 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.281493, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:03 volumio nmbd[633]: Packet send failed to 192.168.10.255(137) ERRNO=Invalid argument Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.281832, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Sep 12 13:22:03 volumio nmbd[633]: retransmit_or_expire_response_records: Failed to resend packet id 14560 to IP 192.168.10.255 on subnet 192.168.10.82 Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.281994, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:03 volumio nmbd[633]: Packet send failed to 192.168.10.255(137) ERRNO=Invalid argument Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282117, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Sep 12 13:22:03 volumio nmbd[633]: retransmit_or_expire_response_records: Failed to resend packet id 14561 to IP 192.168.10.255 on subnet 192.168.10.82 Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282232, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:03 volumio nmbd[633]: Packet send failed to 192.168.10.255(137) ERRNO=Invalid argument Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282350, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Sep 12 13:22:03 volumio nmbd[633]: retransmit_or_expire_response_records: Failed to resend packet id 14562 to IP 192.168.10.255 on subnet 192.168.10.82 Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282460, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:03 volumio nmbd[633]: Packet send failed to 192.168.10.255(137) ERRNO=Invalid argument Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282573, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Sep 12 13:22:03 volumio nmbd[633]: retransmit_or_expire_response_records: Failed to resend packet id 14563 to IP 192.168.10.255 on subnet 192.168.10.82 Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282686, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:03 volumio nmbd[633]: Packet send failed to 192.168.10.255(137) ERRNO=Invalid argument Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282791, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Sep 12 13:22:03 volumio nmbd[633]: retransmit_or_expire_response_records: Failed to resend packet id 14564 to IP 192.168.10.255 on subnet 192.168.10.82 Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282890, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:03 volumio nmbd[633]: Packet send failed to 192.168.10.255(137) ERRNO=Network is unreachable Sep 12 13:22:03 volumio nmbd[633]: [2024/09/12 13:22:03.282995, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Sep 12 13:22:03 volumio nmbd[633]: retransmit_or_expire_response_records: Failed to resend packet id 14571 to IP 192.168.10.255 on subnet 192.168.10.82 Sep 12 13:22:03 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 12 13:22:03 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:03 volumio systemd[1]: Stopping Music Player Daemon... Sep 12 13:22:03 volumio volumio[732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 12 13:22:03 volumio volumio[732]: info: ___________ START PLUGINS ___________ Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 12 13:22:03 volumio volumio[732]: info: [1726147323396] CoreMusicLibrary::Adding element Media Servers Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:03 volumio volumio[732]: Cannot find translation for sourceMedia Servers Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:03 volumio systemd[1]: mpd.service: Succeeded. Sep 12 13:22:03 volumio systemd[1]: Stopped Music Player Daemon. Sep 12 13:22:03 volumio systemd[1]: Starting Music Player Daemon... Sep 12 13:22:03 volumio volumio[732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:03 volumio volumio[732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 12 13:22:03 volumio volumio[732]: info: [1726147323571] CoreMusicLibrary::Adding element Last_100 Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:03 volumio volumio[732]: Cannot find translation for sourceMedia Servers Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 12 13:22:03 volumio volumio[732]: info: [1726147323586] CoreMusicLibrary::Adding element Webradio Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:03 volumio volumio[732]: Cannot find translation for sourceMedia Servers Sep 12 13:22:03 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 13:22:03 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:03 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 6. Sep 12 13:22:03 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:03 volumio volumio[732]: info: [squeezelite_mc] Starting proxy server... Sep 12 13:22:03 volumio dhcpcd[950]: eth0: carrier acquired Sep 12 13:22:03 volumio dhcpcd[889]: eth0: carrier acquired Sep 12 13:22:03 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 12 13:22:03 volumio volumio[732]: info: Volumio Calling Home Sep 12 13:22:03 volumio dhcpcd[950]: eth0: IAID fc:02:70:c5 Sep 12 13:22:03 volumio dhcpcd[889]: eth0: IAID fc:02:70:c5 Sep 12 13:22:03 volumio dnsmasq[1085]: exiting on receipt of SIGTERM Sep 12 13:22:03 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:03 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:03 volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 12 13:22:03 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:03 volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:03 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:03 volumio hostapd[1205]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:03 volumio volumio[732]: info: [squeezelite_mc] Proxy server started on port 44130 Sep 12 13:22:03 volumio systemd[1]: Started RoonBridge. Sep 12 13:22:03 volumio sudo[1199]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:03 volumio dhcpcd[889]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:22:03 volumio dhcpcd[889]: eth0: probing address 192.168.10.82/24 Sep 12 13:22:03 volumio hostapd[1205]: Could not read interface wlan0 flags: No such device Sep 12 13:22:03 volumio hostapd[1205]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:03 volumio hostapd[1205]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:03 volumio hostapd[1205]: Could not read interface wlan0 flags: No such device Sep 12 13:22:03 volumio hostapd[1205]: nl80211 driver initialization failed. Sep 12 13:22:03 volumio hostapd[1205]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:03 volumio hostapd[1205]: wlan0: AP-DISABLED Sep 12 13:22:03 volumio hostapd[1205]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:03 volumio hostapd[1205]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:03 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:03 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:03 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:04 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:04 volumio volumio[732]: info: CoreStateMachine::setRepeat true single undefined Sep 12 13:22:04 volumio volumio[732]: info: CoreStateMachine::pushState Sep 12 13:22:04 volumio volumio[732]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 13:22:04 volumio dnsmasq[1227]: dnsmasq: syntax check OK. Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:04 volumio volumio[732]: info: CoreStateMachine::setRandom false Sep 12 13:22:04 volumio volumio[732]: info: CoreStateMachine::pushState Sep 12 13:22:04 volumio volumio[732]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:04 volumio volumio[732]: error: updateQueue error: null Sep 12 13:22:04 volumio volumio[732]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 12 13:22:04 volumio volumio[732]: Unhandled rejection Error: No sockets available, cannot start. Sep 12 13:22:04 volumio volumio[732]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 12 13:22:04 volumio volumio[732]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 12 13:22:04 volumio volumio[732]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 12 13:22:04 volumio volumio[732]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 12 13:22:04 volumio volumio[732]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 12 13:22:04 volumio volumio[732]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 12 13:22:04 volumio volumio[732]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 12 13:22:04 volumio volumio[732]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 12 13:22:04 volumio volumio[732]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 12 13:22:04 volumio volumio[732]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 12 13:22:04 volumio volumio[732]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 12 13:22:04 volumio volumio[732]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 12 13:22:04 volumio volumio[732]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 12 13:22:04 volumio volumio[732]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 12 13:22:04 volumio volumio[732]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 12 13:22:04 volumio volumio[732]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 12 13:22:04 volumio volumio[732]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:04 volumio volumio[732]: info: MPD Permissions set Sep 12 13:22:04 volumio volumio[732]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:04 volumio dnsmasq[1240]: started, version 2.80 cachesize 150 Sep 12 13:22:04 volumio dnsmasq[1240]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:04 volumio dnsmasq[1240]: warning: interface wlan0 does not currently exist Sep 12 13:22:04 volumio dnsmasq-dhcp[1240]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:04 volumio dnsmasq[1240]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:04 volumio dnsmasq[1240]: read /etc/hosts - 5 addresses Sep 12 13:22:04 volumio volumio[732]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:04 volumio volumio[732]: info: mDNS: A device disapperared from network Sep 12 13:22:04 volumio nmbd[633]: [2024/09/12 13:22:04.284428, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 12 13:22:04 volumio nmbd[633]: Packet send failed to 192.168.10.255(138) ERRNO=Invalid argument Sep 12 13:22:04 volumio volumio[732]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 12 13:22:04 volumio volumio[732]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:04 volumio volumio[732]: info: CoreStateMachine::pushState Sep 12 13:22:04 volumio volumio[732]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:04 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:04 volumio volumio[732]: info: [squeezelite_mc] Server discovery started Sep 12 13:22:04 volumio volumio[732]: info: [squeezelite_mc] Player finder started Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:04 volumio volumio[732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:04 volumio volumio[732]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 12 13:22:04 volumio dhcpcd[950]: eth0: soliciting an IPv6 router Sep 12 13:22:04 volumio dhcpcd[889]: eth0: soliciting an IPv6 router Sep 12 13:22:04 volumio volumio[732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 13:22:04 volumio volumio[732]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 12 13:22:04 volumio volumio[732]: at doSend (dgram.js:714:16) Sep 12 13:22:04 volumio volumio[732]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Sep 12 13:22:04 volumio volumio[732]: at afterDns (dgram.js:660:5) Sep 12 13:22:04 volumio volumio[732]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Sep 12 13:22:04 volumio volumio[732]: errno: -101, Sep 12 13:22:04 volumio volumio[732]: code: 'ENETUNREACH', Sep 12 13:22:04 volumio volumio[732]: syscall: 'send', Sep 12 13:22:04 volumio volumio[732]: address: '255.255.255.255', Sep 12 13:22:04 volumio volumio[732]: port: 3483 Sep 12 13:22:04 volumio volumio[732]: } Sep 12 13:22:04 volumio volumio[732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 13:22:04 volumio ifplugd(eth0)[652]: Link beat detected. Sep 12 13:22:04 volumio dhcpcd[950]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:22:04 volumio dhcpcd[950]: eth0: probing address 192.168.10.82/24 Sep 12 13:22:05 volumio volumio-remote-updater[429]: [2024-09-12 13:22:05] [connect] Successful connection Sep 12 13:22:05 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-12 13:21 Sep 12 13:22:05 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:05 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:05 volumio volumio-remote-updater[429]: [2024-09-12 13:22:05] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Sep 12 13:22:05 volumio volumio-remote-updater[429]: [2024-09-12 13:22:05] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Sep 12 13:22:05 volumio volumio-remote-updater[429]: [2024-09-12 13:22:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 12 13:22:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 12 13:22:05 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:05 volumio RoonBridge[1207]: 00:00:00.022 Warn: get lock file path: /tmp/.rnbgem0- Sep 12 13:22:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 12 13:22:05 volumio systemd[1]: Started dynamicswap service. Sep 12 13:22:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 12 13:22:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 12 13:22:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 12 13:22:05 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:05 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 7. Sep 12 13:22:06 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:06 volumio systemd[1]: Started dynamicswap service. Sep 12 13:22:06 volumio systemd[1]: Stopped Volumio Backend Module. Sep 12 13:22:06 volumio systemd[1]: Started Volumio Backend Module. Sep 12 13:22:06 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 12 13:22:06 volumio dnsmasq[1240]: exiting on receipt of SIGTERM Sep 12 13:22:06 volumio ntpd[949]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 12 13:22:06 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:06 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:06 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:06 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:06 volumio hostapd[1288]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:00.820 Trace: [childprocess] using unix child process Sep 12 13:22:06 volumio hostapd[1288]: Could not read interface wlan0 flags: No such device Sep 12 13:22:06 volumio hostapd[1288]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:06 volumio hostapd[1288]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:06 volumio hostapd[1288]: Could not read interface wlan0 flags: No such device Sep 12 13:22:06 volumio hostapd[1288]: nl80211 driver initialization failed. Sep 12 13:22:06 volumio hostapd[1288]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:06 volumio hostapd[1288]: wlan0: AP-DISABLED Sep 12 13:22:06 volumio hostapd[1288]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:06 volumio hostapd[1288]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:06 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:06 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:06 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:06 volumio mpd[1225]: Sep 12 13:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 12 13:22:06 volumio mpd[1225]: Sep 12 13:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 12 13:22:06 volumio mpd[1225]: Sep 12 13:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 12 13:22:06 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:06 volumio systemd[1]: Started Music Player Daemon. Sep 12 13:22:06 volumio dnsmasq[1297]: dnsmasq: syntax check OK. Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.019 Debug: PathForResource, filename: ../.update Sep 12 13:22:06 volumio dnsmasq[1305]: started, version 2.80 cachesize 150 Sep 12 13:22:06 volumio dnsmasq[1305]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:06 volumio dnsmasq[1305]: warning: interface wlan0 does not currently exist Sep 12 13:22:06 volumio dnsmasq-dhcp[1305]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.076 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Sep 12 13:22:06 volumio dnsmasq[1305]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:06 volumio dnsmasq[1305]: read /etc/hosts - 5 addresses Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.094 Debug: PathForResource, filename: RAATServer Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.095 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.097 Debug: PathForResource, filename: RoonBridgeHelper Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.098 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 12 13:22:06 volumio RoonBridge[1207]: Initializing Sep 12 13:22:06 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.185 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 12 13:22:06 volumio RoonBridge[1207]: 00:00:01.243 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 12 13:22:06 volumio RoonBridge[1207]: Not Running (.o) Sep 12 13:22:07 volumio RoonBridge[1207]: Running Sep 12 13:22:07 volumio RoonBridge[1207]: 00:00:00.057 Warn: get lock file path: /tmp/.rnbhgem0- Sep 12 13:22:08 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:08 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 8. Sep 12 13:22:08 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:08 volumio dnsmasq[1305]: exiting on receipt of SIGTERM Sep 12 13:22:08 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:08 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:08 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:08 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:08 volumio hostapd[1348]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:08 volumio hostapd[1348]: Could not read interface wlan0 flags: No such device Sep 12 13:22:08 volumio hostapd[1348]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:08 volumio hostapd[1348]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:08 volumio hostapd[1348]: Could not read interface wlan0 flags: No such device Sep 12 13:22:08 volumio hostapd[1348]: nl80211 driver initialization failed. Sep 12 13:22:08 volumio hostapd[1348]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:08 volumio hostapd[1348]: wlan0: AP-DISABLED Sep 12 13:22:08 volumio hostapd[1348]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:08 volumio hostapd[1348]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:08 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:08 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:08 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:08 volumio dhcpcd[889]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:22:08 volumio dhcpcd[889]: eth0: adding route to 192.168.10.0/24 Sep 12 13:22:08 volumio dhcpcd[889]: eth0: adding default route via 192.168.10.1 Sep 12 13:22:08 volumio avahi-daemon[433]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.82. Sep 12 13:22:08 volumio avahi-daemon[433]: New relevant interface eth0.IPv4 for mDNS. Sep 12 13:22:08 volumio avahi-daemon[433]: Registering new address record for 192.168.10.82 on eth0.IPv4. Sep 12 13:22:08 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:08 volumio dnsmasq[1356]: dnsmasq: syntax check OK. Sep 12 13:22:08 volumio dnsmasq[1372]: started, version 2.80 cachesize 150 Sep 12 13:22:08 volumio dnsmasq[1372]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:08 volumio dnsmasq[1372]: warning: interface wlan0 does not currently exist Sep 12 13:22:08 volumio dnsmasq-dhcp[1372]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:08 volumio dnsmasq[1372]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:08 volumio dnsmasq[1372]: read /etc/hosts - 5 addresses Sep 12 13:22:08 volumio dnsmasq[1372]: reading /etc/resolv.conf Sep 12 13:22:08 volumio dnsmasq[1372]: using nameserver 192.168.10.1#53 Sep 12 13:22:08 volumio dnsmasq[1372]: using nameserver 208.67.222.222#53 Sep 12 13:22:08 volumio dnsmasq[1372]: using nameserver 208.67.220.220#53 Sep 12 13:22:09 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:09 volumio volumio[1274]: info: ------------------------------------------- Sep 12 13:22:09 volumio volumio[1274]: info: ----- Volumio3 ---- Sep 12 13:22:09 volumio volumio[1274]: info: ------------------------------------------- Sep 12 13:22:09 volumio volumio[1274]: info: ----- System startup ---- Sep 12 13:22:09 volumio volumio[1274]: info: ------------------------------------------- Sep 12 13:22:09 volumio kernel: usb 4-1.2: new high-speed USB device number 3 using xhci-hcd Sep 12 13:22:09 volumio ntpd[949]: ntpd exiting on signal 15 (Terminated) Sep 12 13:22:09 volumio systemd[1]: Stopping Network Time Service... Sep 12 13:22:09 volumio systemd[1]: ntp.service: Succeeded. Sep 12 13:22:09 volumio systemd[1]: Stopped Network Time Service. Sep 12 13:22:09 volumio systemd[1]: Starting Network Time Service... Sep 12 13:22:09 volumio kernel: usb 4-1.2: New USB device found, idVendor=040d, idProduct=3410 Sep 12 13:22:09 volumio kernel: usb 4-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 12 13:22:09 volumio kernel: usb 4-1.2: Product: Audio-gd Sep 12 13:22:09 volumio kernel: usb 4-1.2: Manufacturer: Audio-gd Sep 12 13:22:09 volumio ntpd[1401]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 12 13:22:09 volumio ntpd[1401]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 12 13:22:09 volumio systemd[1]: Started Network Time Service. Sep 12 13:22:09 volumio ntpd[1408]: proto: precision = 0.874 usec (-20) Sep 12 13:22:09 volumio ntpd[1408]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 12 13:22:09 volumio ntpd[1408]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 12 13:22:09 volumio ntpd[1408]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 808 days ago Sep 12 13:22:09 volumio ntpd[1408]: Listen and drop on 0 v6wildcard [::]:123 Sep 12 13:22:09 volumio ntpd[1408]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 12 13:22:09 volumio ntpd[1408]: Listen normally on 2 lo 127.0.0.1:123 Sep 12 13:22:09 volumio ntpd[1408]: Listen normally on 3 eth0 192.168.10.82:123 Sep 12 13:22:09 volumio ntpd[1408]: Listening on routing socket on fd #20 for interface updates Sep 12 13:22:09 volumio ntpd[1408]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:22:09 volumio ntpd[1408]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:22:09 volumio dhcpcd[950]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:22:09 volumio dhcpcd[950]: eth0: adding route to 192.168.10.0/24 Sep 12 13:22:09 volumio dhcpcd[950]: eth0: adding default route via 192.168.10.1 Sep 12 13:22:09 volumio kernel: input: Audio-gd Audio-gd as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.9.auto/usb4/4-1/4-1.2/4-1.2:1.2/0003:040D:3410.0001/input/input1 Sep 12 13:22:09 volumio kernel: hid-generic 0003:040D:3410.0001: input,hidraw0: USB HID v1.00 Device [Audio-gd Audio-gd] on usb-xhci-hcd.9.auto-1.2/input2 Sep 12 13:22:09 volumio kernel: usb 4-1.2: cannot get ctl value: req = 0x81, wValue = 0x202, wIndex = 0xc00, type = 4 Sep 12 13:22:09 volumio kernel: usb 4-1.2: cannot get ctl value: req = 0x81, wValue = 0x202, wIndex = 0xc00, type = 4 Sep 12 13:22:09 volumio kernel: usb 4-1.2: cannot get ctl value: req = 0x81, wValue = 0x100, wIndex = 0xc00, type = 1 Sep 12 13:22:09 volumio systemd-udevd[1406]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 3' failed with exit code 99. Sep 12 13:22:10 volumio systemd-udevd[1423]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 12 13:22:10 volumio kernel: usb 4-1.2: USB disconnect, device number 3 Sep 12 13:22:10 volumio ntpd[1408]: ntpd exiting on signal 15 (Terminated) Sep 12 13:22:10 volumio systemd[1]: Stopping Network Time Service... Sep 12 13:22:10 volumio systemd[1]: ntp.service: Succeeded. Sep 12 13:22:10 volumio systemd[1]: Stopped Network Time Service. Sep 12 13:22:10 volumio systemd[1]: Starting Network Time Service... Sep 12 13:22:10 volumio ntpd[1448]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 12 13:22:10 volumio ntpd[1448]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 12 13:22:10 volumio systemd[1]: Started Network Time Service. Sep 12 13:22:10 volumio ntpd[1454]: proto: precision = 0.875 usec (-20) Sep 12 13:22:10 volumio ntpd[1454]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 12 13:22:10 volumio ntpd[1454]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 12 13:22:10 volumio ntpd[1454]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 808 days ago Sep 12 13:22:10 volumio ntpd[1454]: Listen and drop on 0 v6wildcard [::]:123 Sep 12 13:22:10 volumio ntpd[1454]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 12 13:22:10 volumio ntpd[1454]: Listen normally on 2 lo 127.0.0.1:123 Sep 12 13:22:10 volumio ntpd[1454]: Listen normally on 3 eth0 192.168.10.82:123 Sep 12 13:22:10 volumio ntpd[1454]: Listening on routing socket on fd #20 for interface updates Sep 12 13:22:10 volumio ntpd[1454]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:22:10 volumio ntpd[1454]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:22:10 volumio volumio[1274]: info: MYVOLUMIO Environment detected Sep 12 13:22:10 volumio kernel: usb 4-1.2: new full-speed USB device number 4 using xhci-hcd Sep 12 13:22:10 volumio kernel: usb 4-1.2: device descriptor read/64, error -32 Sep 12 13:22:10 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:10 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 9. Sep 12 13:22:10 volumio volumio[1274]: info: Plugin folders cleanup Sep 12 13:22:10 volumio volumio[1274]: info: Scanning into folder /volumio/app/plugins/ Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category audio_interface Sep 12 13:22:10 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category miscellanea Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category music_service Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category plugins.json Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category system_controller Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category user_interface Sep 12 13:22:10 volumio volumio[1274]: info: Scanning into folder /data/plugins/ Sep 12 13:22:10 volumio volumio[1274]: info: Scanning category music_service Sep 12 13:22:10 volumio volumio[1274]: info: Plugin folders cleanup completed Sep 12 13:22:10 volumio volumio[1274]: info: ------------------------------------------- Sep 12 13:22:10 volumio volumio[1274]: info: ----- Core plugins startup ---- Sep 12 13:22:10 volumio volumio[1274]: info: ------------------------------------------- Sep 12 13:22:10 volumio volumio[1274]: info: Loading plugins from folder /volumio/app/plugins/ Sep 12 13:22:10 volumio volumio[1274]: info: Adding plugin upnp to MyMusic Plugins Sep 12 13:22:10 volumio volumio[1274]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 12 13:22:10 volumio volumio[1274]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 12 13:22:10 volumio volumio[1274]: info: Loading plugins from folder /data/plugins/ Sep 12 13:22:10 volumio dnsmasq[1372]: exiting on receipt of SIGTERM Sep 12 13:22:10 volumio volumio[1274]: info: Loading plugin "system"... Sep 12 13:22:10 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:10 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:10 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:10 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:10 volumio volumio[1274]: info: Loading plugin "appearance"... Sep 12 13:22:10 volumio kernel: usb 4-1.2: new high-speed USB device number 5 using xhci-hcd Sep 12 13:22:10 volumio hostapd[1464]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:11 volumio hostapd[1464]: Could not read interface wlan0 flags: No such device Sep 12 13:22:11 volumio hostapd[1464]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:11 volumio hostapd[1464]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:11 volumio kernel: usb 4-1.2: New USB device found, idVendor=040d, idProduct=3410 Sep 12 13:22:11 volumio kernel: usb 4-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 12 13:22:11 volumio kernel: usb 4-1.2: Product: Audio-gd Sep 12 13:22:11 volumio kernel: usb 4-1.2: Manufacturer: Audio-gd Sep 12 13:22:11 volumio hostapd[1464]: Could not read interface wlan0 flags: No such device Sep 12 13:22:11 volumio hostapd[1464]: nl80211 driver initialization failed. Sep 12 13:22:11 volumio hostapd[1464]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:11 volumio hostapd[1464]: wlan0: AP-DISABLED Sep 12 13:22:11 volumio hostapd[1464]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:11 volumio hostapd[1464]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:11 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:11 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:11 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:11 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:11 volumio dnsmasq[1471]: dnsmasq: syntax check OK. Sep 12 13:22:11 volumio dnsmasq[1478]: started, version 2.80 cachesize 150 Sep 12 13:22:11 volumio dnsmasq[1478]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:11 volumio dnsmasq[1478]: warning: interface wlan0 does not currently exist Sep 12 13:22:11 volumio dnsmasq-dhcp[1478]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:11 volumio dnsmasq[1478]: reading /etc/resolv.conf Sep 12 13:22:11 volumio dnsmasq[1478]: using nameserver 192.168.10.1#53 Sep 12 13:22:11 volumio dnsmasq[1478]: using nameserver 208.67.222.222#53 Sep 12 13:22:11 volumio dnsmasq[1478]: using nameserver 208.67.220.220#53 Sep 12 13:22:11 volumio dnsmasq[1478]: read /etc/hosts - 5 addresses Sep 12 13:22:11 volumio kernel: input: Audio-gd Audio-gd as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.9.auto/usb4/4-1/4-1.2/4-1.2:1.2/0003:040D:3410.0002/input/input2 Sep 12 13:22:11 volumio kernel: hid-generic 0003:040D:3410.0002: input,hidraw0: USB HID v1.00 Device [Audio-gd Audio-gd] on usb-xhci-hcd.9.auto-1.2/input2 Sep 12 13:22:11 volumio ntpd[1454]: Soliciting pool server 193.182.111.14 Sep 12 13:22:11 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:11 volumio systemd-udevd[1423]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 3' failed with exit code 99. Sep 12 13:22:11 volumio systemd-udevd[1420]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 12 13:22:12 volumio volumio[1274]: info: Loading plugin "network"... Sep 12 13:22:12 volumio volumio[1274]: info: Refreshing Cached IP Addresses Sep 12 13:22:12 volumio volumio[1274]: info: Loading plugin "services"... Sep 12 13:22:12 volumio volumio[1274]: info: Loading plugin "alsa_controller"... Sep 12 13:22:12 volumio sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 12 13:22:12 volumio sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:12 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 12 13:22:12 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:12 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:12 volumio sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 12 13:22:12 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:12 volumio sudo[1500]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:12 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:13 volumio volumio[1274]: warn: Unable to locate the audio output device SilkLine USB Audio 2.0. Please configure a valid output device. Sep 12 13:22:13 volumio volumio[1274]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Sep 12 13:22:13 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:13 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 10. Sep 12 13:22:13 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:13 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 12 13:22:13 volumio volumio[1274]: info: Found match in Cards Database: setting mixer HDMI for card HDMI Sep 12 13:22:13 volumio dnsmasq[1478]: exiting on receipt of SIGTERM Sep 12 13:22:13 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:13 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:13 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:13 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:13 volumio hostapd[1526]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:13 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 12 13:22:13 volumio volumio[1274]: info: Loading plugin "wizard"... Sep 12 13:22:13 volumio volumio[1274]: info: Loading plugin "volumio_command_line_client"... Sep 12 13:22:13 volumio hostapd[1526]: Could not read interface wlan0 flags: No such device Sep 12 13:22:13 volumio hostapd[1526]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:13 volumio hostapd[1526]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:13 volumio volumio[1274]: info: Loading plugin "upnp"... Sep 12 13:22:13 volumio volumio[1274]: info: [1726147333539] Starting Upmpd Daemon Sep 12 13:22:13 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 12 13:22:13 volumio volumio[1274]: info: Loading plugin "my_music"... Sep 12 13:22:13 volumio volumio[1274]: info: Loading plugin "mpd"... Sep 12 13:22:13 volumio hostapd[1526]: Could not read interface wlan0 flags: No such device Sep 12 13:22:13 volumio hostapd[1526]: nl80211 driver initialization failed. Sep 12 13:22:13 volumio hostapd[1526]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:13 volumio hostapd[1526]: wlan0: AP-DISABLED Sep 12 13:22:13 volumio hostapd[1526]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:13 volumio hostapd[1526]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:13 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:13 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:13 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:13 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:13 volumio dnsmasq[1542]: dnsmasq: syntax check OK. Sep 12 13:22:13 volumio dhcpcd[889]: eth0: carrier lost Sep 12 13:22:13 volumio dhcpcd[950]: eth0: carrier lost Sep 12 13:22:13 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Sep 12 13:22:13 volumio dnsmasq[1556]: started, version 2.80 cachesize 150 Sep 12 13:22:13 volumio dnsmasq[1556]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:13 volumio dnsmasq[1556]: warning: interface wlan0 does not currently exist Sep 12 13:22:13 volumio dnsmasq-dhcp[1556]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:13 volumio dnsmasq[1556]: reading /etc/resolv.conf Sep 12 13:22:13 volumio dnsmasq[1556]: using nameserver 192.168.10.1#53 Sep 12 13:22:13 volumio dnsmasq[1556]: using nameserver 208.67.222.222#53 Sep 12 13:22:13 volumio dnsmasq[1556]: using nameserver 208.67.220.220#53 Sep 12 13:22:13 volumio dnsmasq[1556]: read /etc/hosts - 5 addresses Sep 12 13:22:13 volumio dnsmasq[1556]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:13 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:13 volumio avahi-daemon[433]: Withdrawing address record for 192.168.10.82 on eth0. Sep 12 13:22:13 volumio avahi-daemon[433]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.10.82. Sep 12 13:22:13 volumio avahi-daemon[433]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 12 13:22:13 volumio dhcpcd[889]: eth0: deleting route to 192.168.10.0/24 Sep 12 13:22:13 volumio dhcpcd[889]: eth0: deleting default route via 192.168.10.1 Sep 12 13:22:13 volumio dhcpcd[950]: eth0: deleting route to 192.168.10.0/24 Sep 12 13:22:13 volumio dhcpcd[950]: eth0: deleting default route via 192.168.10.1 Sep 12 13:22:13 volumio dnsmasq[1556]: reading /etc/resolv.conf Sep 12 13:22:13 volumio dnsmasq[1556]: using nameserver 208.67.222.222#53 Sep 12 13:22:13 volumio dnsmasq[1556]: using nameserver 208.67.220.220#53 Sep 12 13:22:13 volumio dnsmasq[1556]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:14 volumio volumio[1274]: info: Creating MPD Configuration file Sep 12 13:22:14 volumio volumio[1274]: info: Loading plugin "upnp_browser"... Sep 12 13:22:14 volumio sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 12 13:22:14 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:14 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:14 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 12 13:22:14 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:14 volumio systemd[1]: Stopping Music Player Daemon... Sep 12 13:22:14 volumio systemd[1]: mpd.service: Succeeded. Sep 12 13:22:14 volumio systemd[1]: Stopped Music Player Daemon. Sep 12 13:22:14 volumio systemd[1]: Starting Music Player Daemon... Sep 12 13:22:14 volumio volumio[1274]: info: Loading plugin "networkfs"... Sep 12 13:22:14 volumio ifplugd(eth0)[652]: Link beat lost. Sep 12 13:22:14 volumio volumio[1274]: info: Starting Udev Watcher for removable devices Sep 12 13:22:14 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 12 13:22:14 volumio dhcpcd[889]: eth0: carrier acquired Sep 12 13:22:14 volumio dhcpcd[950]: eth0: carrier acquired Sep 12 13:22:14 volumio dhcpcd[950]: eth0: IAID fc:02:70:c5 Sep 12 13:22:14 volumio dhcpcd[889]: eth0: IAID fc:02:70:c5 Sep 12 13:22:15 volumio volumio[1274]: info: Ignoring mount for partition: boot Sep 12 13:22:15 volumio volumio[1274]: info: Ignoring mount for partition: volumio Sep 12 13:22:15 volumio volumio[1274]: info: Ignoring mount for partition: volumio_data Sep 12 13:22:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "alarm-clock"... Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "airplay_emulation"... Sep 12 13:22:15 volumio dhcpcd[950]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:22:15 volumio volumio[1274]: info: Starting Shairport Sync Sep 12 13:22:15 volumio dhcpcd[950]: eth0: probing address 192.168.10.82/24 Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "last_100"... Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "webradio"... Sep 12 13:22:15 volumio dhcpcd[889]: eth0: rebinding lease of 192.168.10.82 Sep 12 13:22:15 volumio dhcpcd[889]: eth0: probing address 192.168.10.82/24 Sep 12 13:22:15 volumio dhcpcd[889]: eth0: soliciting an IPv6 router Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "i2s_dacs"... Sep 12 13:22:15 volumio volumio[1274]: info: I2S DAC not set, start Auto-detection Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "volumiodiscovery"... Sep 12 13:22:15 volumio volumio-remote-updater[429]: [2024-09-12 13:22:15] [connect] Successful connection Sep 12 13:22:15 volumio volumio[1274]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 12 13:22:15 volumio volumio[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:22:15 volumio volumio[1274]: *** WARNING *** For more information see Sep 12 13:22:15 volumio node[1274]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 12 13:22:15 volumio volumio[1274]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 12 13:22:15 volumio volumio[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:22:15 volumio volumio[1274]: *** WARNING *** For more information see Sep 12 13:22:15 volumio node[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:22:15 volumio node[1274]: *** WARNING *** For more information see Sep 12 13:22:15 volumio node[1274]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 12 13:22:15 volumio node[1274]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 12 13:22:15 volumio node[1274]: *** WARNING *** For more information see Sep 12 13:22:15 volumio volumio[1274]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 12 13:22:15 volumio volumio[1274]: info: Discovery: Started advertising with name: Volumio Sep 12 13:22:15 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 12 13:22:15 volumio volumio[1274]: info: Loading plugin "squeezelite_mc"... Sep 12 13:22:15 volumio dhcpcd[950]: eth0: soliciting an IPv6 router Sep 12 13:22:15 volumio ifplugd(eth0)[652]: Link beat detected. Sep 12 13:22:15 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:15 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 11. Sep 12 13:22:15 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:15 volumio dnsmasq[1556]: exiting on receipt of SIGTERM Sep 12 13:22:15 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:15 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:15 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:15 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:15 volumio hostapd[1626]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:15 volumio hostapd[1626]: Could not read interface wlan0 flags: No such device Sep 12 13:22:15 volumio hostapd[1626]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:15 volumio hostapd[1626]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:15 volumio hostapd[1626]: Could not read interface wlan0 flags: No such device Sep 12 13:22:15 volumio hostapd[1626]: nl80211 driver initialization failed. Sep 12 13:22:15 volumio hostapd[1626]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:15 volumio hostapd[1626]: wlan0: AP-DISABLED Sep 12 13:22:15 volumio hostapd[1626]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:15 volumio hostapd[1626]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:15 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:15 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:15 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:15 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:15 volumio dnsmasq[1633]: dnsmasq: syntax check OK. Sep 12 13:22:16 volumio dnsmasq[1639]: started, version 2.80 cachesize 150 Sep 12 13:22:16 volumio dnsmasq[1639]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:16 volumio dnsmasq[1639]: warning: interface wlan0 does not currently exist Sep 12 13:22:16 volumio dnsmasq-dhcp[1639]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:16 volumio dnsmasq[1639]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:16 volumio dnsmasq[1639]: read /etc/hosts - 5 addresses Sep 12 13:22:16 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:16 volumio ntpd[1454]: Deleting interface #3 eth0, 192.168.10.82#123, interface stats: received=0, sent=1, dropped=0, active_time=6 secs Sep 12 13:22:16 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "outputs"... Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "albumart"... Sep 12 13:22:16 volumio mpd[1616]: Sep 12 13:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 12 13:22:16 volumio mpd[1616]: Sep 12 13:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 12 13:22:16 volumio mpd[1616]: Sep 12 13:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 12 13:22:16 volumio systemd[1]: Started Music Player Daemon. Sep 12 13:22:16 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:16 volumio volumio[1274]: info: Plugin example_plugin is not enabled Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "inputs"... Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "updater_comm"... Sep 12 13:22:16 volumio volumio[1274]: info: Plugin mpdemulation is not enabled Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "rest_api"... Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "websocket"... Sep 12 13:22:16 volumio volumio[1274]: info: Loading plugin "RoonBridge"... Sep 12 13:22:17 volumio volumio[1274]: Forking 3 albumart workers Sep 12 13:22:17 volumio volumio[1274]: info: Applying required configuration parameters for plugin RoonBridge Sep 12 13:22:17 volumio volumio[1274]: info: Loading i18n strings for locale zh Sep 12 13:22:17 volumio volumio[1274]: Updating browse sources language Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::initPlayerControls Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: Express server listening on port 3000 Sep 12 13:22:17 volumio volumio[1274]: [Metrics] WebUI: 9s 335.37ms Sep 12 13:22:17 volumio volumio[1274]: info: CoreStateMachine::resetVolumioState Sep 12 13:22:17 volumio ntpd[1454]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 12 13:22:17 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:17 volumio ntpd[1454]: Soliciting pool server 192.168.211.1 Sep 12 13:22:17 volumio ntpd[1454]: Soliciting pool server 192.168.211.1 Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 12 13:22:17 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Sep 12 13:22:17 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:17 volumio volumio[1274]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 12 13:22:17 volumio volumio[1274]: info: Completed loading Core Plugins Sep 12 13:22:17 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Sep 12 13:22:17 volumio volumio[1274]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:17 volumio volumio[1274]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Sep 12 13:22:17 volumio volumio[1274]: info: MPD Permissions set Sep 12 13:22:17 volumio volumio[1274]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 12 13:22:17 volumio volumio[1274]: wlan0 Interface doesn't support scanning. Sep 12 13:22:17 volumio volumio[1274]: info: Cannot use regular scanning, forcing with ap-force Sep 12 13:22:17 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 12 13:22:17 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:17 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:17 volumio volumio[1274]: command failed: No such device (-19) Sep 12 13:22:17 volumio volumio[1274]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 12 13:22:17 volumio volumio[1274]: command failed: No such device (-19) Sep 12 13:22:17 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:17 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 12. Sep 12 13:22:17 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:17 volumio volumio-remote-updater[429]: [2024-09-12 13:22:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1726147335 101 Sep 12 13:22:18 volumio volumio[1274]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 12 13:22:18 volumio volumio[1274]: info: MPD running with PID1616 Sep 12 13:22:18 volumio volumio[1274]: ,establishing connection Sep 12 13:22:18 volumio dnsmasq[1639]: exiting on receipt of SIGTERM Sep 12 13:22:18 volumio volumio[1274]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:18 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:18 volumio volumio[1274]: info: CoreStateMachine::pushState Sep 12 13:22:18 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:18 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 13:22:18 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:18 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:18 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Sep 12 13:22:18 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:18 volumio hostapd[1693]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:18 volumio volumio[1274]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:18 volumio hostapd[1693]: Could not read interface wlan0 flags: No such device Sep 12 13:22:18 volumio hostapd[1693]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:18 volumio hostapd[1693]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:18 volumio hostapd[1693]: Could not read interface wlan0 flags: No such device Sep 12 13:22:18 volumio hostapd[1693]: nl80211 driver initialization failed. Sep 12 13:22:18 volumio hostapd[1693]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:18 volumio hostapd[1693]: wlan0: AP-DISABLED Sep 12 13:22:18 volumio hostapd[1693]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:18 volumio hostapd[1693]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:18 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:18 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:18 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:18 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:18 volumio dnsmasq[1702]: dnsmasq: syntax check OK. Sep 12 13:22:18 volumio volumio[1274]: error: MPD error: The expression evaluated to a falsy value: Sep 12 13:22:18 volumio volumio[1274]: assert.ok(self.idling) Sep 12 13:22:18 volumio volumio[1274]: error: MPD error: The expression evaluated to a falsy value: Sep 12 13:22:18 volumio volumio[1274]: assert.ok(self.idling) Sep 12 13:22:18 volumio volumio[1274]: Starting albumart workers Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: Starting albumart workers Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 12 13:22:18 volumio dnsmasq[1716]: started, version 2.80 cachesize 150 Sep 12 13:22:18 volumio dnsmasq[1716]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:18 volumio dnsmasq[1716]: warning: interface wlan0 does not currently exist Sep 12 13:22:18 volumio dnsmasq-dhcp[1716]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:18 volumio dnsmasq[1716]: no servers found in /etc/resolv.conf, will retry Sep 12 13:22:18 volumio dnsmasq[1716]: read /etc/hosts - 5 addresses Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: Starting albumart workers Sep 12 13:22:18 volumio volumio[1274]: info: Reloading queue from file Sep 12 13:22:18 volumio volumio[1274]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:18 volumio volumio[1274]: info: CoreStateMachine::pushState Sep 12 13:22:18 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:18 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Sep 12 13:22:18 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:18 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:18 volumio volumio[1274]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:18 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Sep 12 13:22:18 volumio volumio[1274]: info: Output device has changed, restarting MPD Sep 12 13:22:18 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:18 volumio sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 12 13:22:18 volumio sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 12 13:22:18 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:18 volumio sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:18 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:18 volumio systemd[1]: Stopping Music Player Daemon... Sep 12 13:22:18 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 12 13:22:18 volumio volumio[1274]: info: ___________ START PLUGINS ___________ Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 12 13:22:18 volumio volumio[1274]: info: [1726147338978] CoreMusicLibrary::Adding element Media Servers Sep 12 13:22:18 volumio systemd[1]: mpd.service: Succeeded. Sep 12 13:22:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 12 13:22:18 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:18 volumio volumio[1274]: Cannot find translation for sourceMedia Servers Sep 12 13:22:18 volumio systemd[1]: Starting Music Player Daemon... Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:19 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:22:19 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 12 13:22:19 volumio volumio[1274]: info: [1726147339273] CoreMusicLibrary::Adding element Last_100 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:19 volumio volumio[1274]: Cannot find translation for sourceMedia Servers Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 12 13:22:19 volumio volumio[1274]: info: [1726147339282] CoreMusicLibrary::Adding element Webradio Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 13:22:19 volumio volumio[1274]: Cannot find translation for sourceMedia Servers Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 13:22:19 volumio volumio[1274]: info: [squeezelite_mc] Starting proxy server... Sep 12 13:22:19 volumio volumio[1274]: info: Volumio Calling Home Sep 12 13:22:19 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 12 13:22:19 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:19 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Sep 12 13:22:19 volumio volumio[1274]: info: [squeezelite_mc] Proxy server started on port 41756 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Sep 12 13:22:19 volumio volumio[1274]: info: CoreStateMachine::setRepeat true single undefined Sep 12 13:22:19 volumio volumio[1274]: info: CoreStateMachine::pushState Sep 12 13:22:19 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:19 volumio volumio[1274]: info: CoreStateMachine::setRandom false Sep 12 13:22:19 volumio volumio[1274]: info: CoreStateMachine::pushState Sep 12 13:22:19 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:19 volumio volumio[1274]: error: updateQueue error: null Sep 12 13:22:19 volumio volumio[1274]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 12 13:22:19 volumio volumio[1274]: Unhandled rejection Error: No sockets available, cannot start. Sep 12 13:22:19 volumio volumio[1274]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 12 13:22:19 volumio volumio[1274]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 12 13:22:19 volumio volumio[1274]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 12 13:22:19 volumio volumio[1274]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 12 13:22:19 volumio volumio[1274]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 12 13:22:19 volumio volumio[1274]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 12 13:22:19 volumio volumio[1274]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 12 13:22:19 volumio volumio[1274]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 12 13:22:19 volumio volumio[1274]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 12 13:22:19 volumio volumio[1274]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 12 13:22:19 volumio volumio[1274]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 12 13:22:19 volumio volumio[1274]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 12 13:22:19 volumio volumio[1274]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 12 13:22:19 volumio volumio[1274]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 12 13:22:19 volumio volumio[1274]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 12 13:22:19 volumio volumio[1274]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 12 13:22:19 volumio volumio[1274]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:19 volumio volumio[1274]: info: MPD Permissions set Sep 12 13:22:19 volumio volumio[1274]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:19 volumio volumio[1274]: info: CoreStateMachine::pushState Sep 12 13:22:19 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:19 volumio volumio[1274]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Sep 12 13:22:19 volumio volumio[1274]: info: VolumeController:: Volume=undefined Mute =false Sep 12 13:22:19 volumio volumio[1274]: info: CoreStateMachine::pushState Sep 12 13:22:19 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Sep 12 13:22:19 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Sep 12 13:22:19 volumio volumio[1274]: info: [squeezelite_mc] Server discovery started Sep 12 13:22:19 volumio volumio[1274]: info: [squeezelite_mc] Player finder started Sep 12 13:22:20 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:20 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:20 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 13:22:20 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 13:22:20 volumio volumio[1274]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 12 13:22:20 volumio volumio[1274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 13:22:20 volumio volumio[1274]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 12 13:22:20 volumio volumio[1274]: at doSend (dgram.js:714:16) Sep 12 13:22:20 volumio volumio[1274]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Sep 12 13:22:20 volumio volumio[1274]: at afterDns (dgram.js:660:5) Sep 12 13:22:20 volumio volumio[1274]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Sep 12 13:22:20 volumio volumio[1274]: errno: -101, Sep 12 13:22:20 volumio volumio[1274]: code: 'ENETUNREACH', Sep 12 13:22:20 volumio volumio[1274]: syscall: 'send', Sep 12 13:22:20 volumio volumio[1274]: address: '255.255.255.255', Sep 12 13:22:20 volumio volumio[1274]: port: 3483 Sep 12 13:22:20 volumio volumio[1274]: } Sep 12 13:22:20 volumio volumio[1274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 13:22:20 volumio dhcpcd[950]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:22:20 volumio dhcpcd[950]: eth0: adding route to 192.168.10.0/24 Sep 12 13:22:20 volumio dhcpcd[950]: eth0: adding default route via 192.168.10.1 Sep 12 13:22:20 volumio avahi-daemon[433]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.82. Sep 12 13:22:20 volumio avahi-daemon[433]: New relevant interface eth0.IPv4 for mDNS. Sep 12 13:22:20 volumio avahi-daemon[433]: Registering new address record for 192.168.10.82 on eth0.IPv4. Sep 12 13:22:20 volumio dnsmasq[1716]: reading /etc/resolv.conf Sep 12 13:22:20 volumio dnsmasq[1716]: using nameserver 192.168.10.1#53 Sep 12 13:22:20 volumio dnsmasq[1716]: using nameserver 208.67.222.222#53 Sep 12 13:22:20 volumio dnsmasq[1716]: using nameserver 208.67.220.220#53 Sep 12 13:22:20 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Sep 12 13:22:20 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 13. Sep 12 13:22:20 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:20 volumio dnsmasq[1716]: exiting on receipt of SIGTERM Sep 12 13:22:20 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 12 13:22:20 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 12 13:22:20 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:20 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 12 13:22:20 volumio hostapd[1819]: Configuration file: /etc/hostapd/hostapd.conf Sep 12 13:22:20 volumio hostapd[1819]: Could not read interface wlan0 flags: No such device Sep 12 13:22:20 volumio hostapd[1819]: nl80211: Driver does not support authentication/association or connect commands Sep 12 13:22:20 volumio hostapd[1819]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 12 13:22:20 volumio ntpd[1454]: ntpd exiting on signal 15 (Terminated) Sep 12 13:22:20 volumio systemd[1]: Stopping Network Time Service... Sep 12 13:22:20 volumio hostapd[1819]: Could not read interface wlan0 flags: No such device Sep 12 13:22:20 volumio hostapd[1819]: nl80211 driver initialization failed. Sep 12 13:22:20 volumio hostapd[1819]: wlan0: interface state UNINITIALIZED->DISABLED Sep 12 13:22:20 volumio hostapd[1819]: wlan0: AP-DISABLED Sep 12 13:22:20 volumio hostapd[1819]: wlan0: CTRL-EVENT-TERMINATING Sep 12 13:22:20 volumio hostapd[1819]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 12 13:22:20 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 12 13:22:20 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 12 13:22:20 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 12 13:22:20 volumio systemd[1]: ntp.service: Succeeded. Sep 12 13:22:20 volumio systemd[1]: Stopped Network Time Service. Sep 12 13:22:20 volumio systemd[1]: Starting Network Time Service... Sep 12 13:22:20 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 12 13:22:20 volumio dnsmasq[1832]: dnsmasq: syntax check OK. Sep 12 13:22:20 volumio dhcpcd[889]: eth0: leased 192.168.10.82 for 86400 seconds Sep 12 13:22:20 volumio dhcpcd[889]: eth0: adding route to 192.168.10.0/24 Sep 12 13:22:20 volumio dhcpcd[889]: eth0: adding default route via 192.168.10.1 Sep 12 13:22:20 volumio ntpd[1831]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 12 13:22:20 volumio ntpd[1831]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 12 13:22:20 volumio systemd[1]: Started Network Time Service. Sep 12 13:22:20 volumio ntpd[1840]: proto: precision = 0.874 usec (-20) Sep 12 13:22:20 volumio ntpd[1840]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 12 13:22:20 volumio ntpd[1840]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 12 13:22:20 volumio ntpd[1840]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 808 days ago Sep 12 13:22:20 volumio ntpd[1840]: Listen and drop on 0 v6wildcard [::]:123 Sep 12 13:22:20 volumio ntpd[1840]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 12 13:22:20 volumio ntpd[1840]: Listen normally on 2 lo 127.0.0.1:123 Sep 12 13:22:20 volumio ntpd[1840]: Listen normally on 3 eth0 192.168.10.82:123 Sep 12 13:22:20 volumio ntpd[1840]: Listening on routing socket on fd #20 for interface updates Sep 12 13:22:20 volumio ntpd[1840]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:22:20 volumio ntpd[1840]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 12 13:22:20 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-12 13:21 Sep 12 13:22:20 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 13:22:20 volumio dnsmasq[1868]: started, version 2.80 cachesize 150 Sep 12 13:22:20 volumio dnsmasq[1868]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 12 13:22:20 volumio dnsmasq[1868]: warning: interface wlan0 does not currently exist Sep 12 13:22:20 volumio dnsmasq-dhcp[1868]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 12 13:22:20 volumio dnsmasq[1868]: reading /etc/resolv.conf Sep 12 13:22:20 volumio dnsmasq[1868]: using nameserver 192.168.10.1#53 Sep 12 13:22:20 volumio dnsmasq[1868]: using nameserver 208.67.222.222#53 Sep 12 13:22:20 volumio dnsmasq[1868]: using nameserver 208.67.220.220#53 Sep 12 13:22:20 volumio dnsmasq[1868]: read /etc/hosts - 5 addresses Sep 12 13:22:21 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. 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="613d705c083fd29de3027cf6e63a923a6020da8e" VOLUMIO_FE_VERSION="575f38b17bfc836dbd47f560a440d36ecd98d5af" VOLUMIO_FE3_VERSION="af818e7eb038d49989134ddd7bf2b2ffaa8cf083" VOLUMIO_BE_VERSION="5936439c04ed803864b9ef314729c659b841752e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 28 Jan 2022 11:02:24 PM CET" VOLUMIO_VERSION="3.212" VOLUMIO_HARDWARE="rock64" VOLUMIO_DEVICENAME="Rock64" VOLUMIO_HASH="d29879c89844222d6122014844c7549a"