-- Logs begin at Fri 2022-01-28 22:02:24 UTC, end at Fri 2022-01-28 22:03:05 UTC. -- Jan 28 22:02:24 volumio kernel: Booting Linux on physical CPU 0x0 Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys cpuset Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys cpu Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys cpuacct Jan 28 22:02:24 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 Jan 28 22:02:24 volumio kernel: Boot CPU: AArch64 Processor [410fd034] Jan 28 22:02:24 volumio kernel: earlycon: Early serial console at MMIO32 0xff130000 (options '') Jan 28 22:02:24 volumio kernel: bootconsole [uart0] enabled Jan 28 22:02:24 volumio kernel: On node 0 totalpages: 261632 Jan 28 22:02:24 volumio kernel: DMA zone: 4088 pages used for memmap Jan 28 22:02:24 volumio kernel: DMA zone: 0 pages reserved Jan 28 22:02:24 volumio kernel: DMA zone: 261632 pages, LIFO batch:31 Jan 28 22:02:24 volumio kernel: psci: probing for conduit method from DT. Jan 28 22:02:24 volumio kernel: psci: PSCIv1.0 detected in firmware. Jan 28 22:02:24 volumio kernel: psci: Using standard PSCI v0.2 function IDs Jan 28 22:02:24 volumio kernel: psci: MIGRATE_INFO_TYPE not supported. Jan 28 22:02:24 volumio kernel: PERCPU: Embedded 21 pages/cpu @ffffffc03ff42000 s46248 r8192 d31576 u86016 Jan 28 22:02:24 volumio kernel: pcpu-alloc: s46248 r8192 d31576 u86016 alloc=21*4096 Jan 28 22:02:24 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jan 28 22:02:24 volumio kernel: Detected VIPT I-cache on CPU0 Jan 28 22:02:24 volumio kernel: CPU features: enabling workaround for ARM erratum 845719 Jan 28 22:02:24 volumio kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 257544 Jan 28 22:02:24 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 Jan 28 22:02:24 volumio kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Jan 28 22:02:24 volumio kernel: Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) Jan 28 22:02:24 volumio kernel: Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) Jan 28 22:02:24 volumio kernel: software IO TLB [mem 0x3fdf9000-0x3fe39000] (0MB) mapped at [ffffffc03fdf9000-ffffffc03fe38fff] Jan 28 22:02:24 volumio kernel: Memory: 1001920K/1046528K available (11966K kernel code, 1714K rwdata, 4328K rodata, 1216K init, 764K bss, 44608K reserved, 0K cma-reserved) Jan 28 22:02:24 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) Jan 28 22:02:24 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jan 28 22:02:24 volumio kernel: Hierarchical RCU implementation. Jan 28 22:02:24 volumio kernel: Build-time adjustment of leaf fanout to 64. Jan 28 22:02:24 volumio kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Jan 28 22:02:24 volumio kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 Jan 28 22:02:24 volumio kernel: NR_IRQS:64 nr_irqs:64 0 Jan 28 22:02:24 volumio kernel: GIC: Using split EOI/Deactivate mode Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: rockchip_mmc_get_phase: invalid clk rate Jan 28 22:02:24 volumio kernel: Architected cp15 timer(s) running at 24.00MHz (phys). Jan 28 22:02:24 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Jan 28 22:02:24 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Jan 28 22:02:24 volumio kernel: Console: colour dummy device 80x25 Jan 28 22:02:24 volumio kernel: console [tty1] enabled Jan 28 22:02:24 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Jan 28 22:02:24 volumio kernel: pid_max: default: 32768 minimum: 301 Jan 28 22:02:24 volumio kernel: Security Framework initialized Jan 28 22:02:24 volumio kernel: Yama: becoming mindful. Jan 28 22:02:24 volumio kernel: AppArmor: AppArmor disabled by boot time parameter Jan 28 22:02:24 volumio kernel: Mount-cache hash table entries: 2048 (order: 2, 16384 bytes) Jan 28 22:02:24 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes) Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys io Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys memory Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys devices Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys freezer Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys net_cls Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys perf_event Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys net_prio Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys hugetlb Jan 28 22:02:24 volumio kernel: Initializing cgroup subsys pids Jan 28 22:02:24 volumio kernel: ftrace: allocating 44342 entries in 174 pages Jan 28 22:02:24 volumio kernel: sched-energy: CPU device node has no sched-energy-costs Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU0 Jan 28 22:02:24 volumio kernel: CPU0: update cpu_capacity 1024 Jan 28 22:02:24 volumio kernel: ASID allocator initialised with 32768 entries Jan 28 22:02:24 volumio kernel: Detected VIPT I-cache on CPU1 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU1 Jan 28 22:02:24 volumio kernel: CPU1: update cpu_capacity 1024 Jan 28 22:02:24 volumio kernel: CPU1: Booted secondary processor [410fd034] Jan 28 22:02:24 volumio kernel: Detected VIPT I-cache on CPU2 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU2 Jan 28 22:02:24 volumio kernel: CPU2: update cpu_capacity 1024 Jan 28 22:02:24 volumio kernel: CPU2: Booted secondary processor [410fd034] Jan 28 22:02:24 volumio kernel: Detected VIPT I-cache on CPU3 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU3 Jan 28 22:02:24 volumio kernel: CPU3: update cpu_capacity 1024 Jan 28 22:02:24 volumio kernel: CPU3: Booted secondary processor [410fd034] Jan 28 22:02:24 volumio kernel: Brought up 4 CPUs Jan 28 22:02:24 volumio kernel: SMP: Total of 4 processors activated. Jan 28 22:02:24 volumio kernel: CPU features: kernel page table isolation forced OFF by command line option Jan 28 22:02:24 volumio kernel: CPU: All CPU(s) started at EL2 Jan 28 22:02:24 volumio kernel: alternatives: patching kernel code Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU3 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for Cluster3 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU2 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for Cluster2 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU1 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for Cluster1 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for CPU0 Jan 28 22:02:24 volumio kernel: Invalid sched_group_energy for Cluster0 Jan 28 22:02:24 volumio kernel: devtmpfs: initialized Jan 28 22:02:24 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Jan 28 22:02:24 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Jan 28 22:02:24 volumio kernel: xor: measuring software checksum speed Jan 28 22:02:24 volumio kernel: 8regs : 1080.000 MB/sec Jan 28 22:02:24 volumio kernel: 8regs_prefetch: 968.000 MB/sec Jan 28 22:02:24 volumio kernel: 32regs : 1458.000 MB/sec Jan 28 22:02:24 volumio kernel: 32regs_prefetch: 1224.000 MB/sec Jan 28 22:02:24 volumio kernel: xor: using function: 32regs (1458.000 MB/sec) Jan 28 22:02:24 volumio kernel: pinctrl core: initialized pinctrl subsystem Jan 28 22:02:24 volumio kernel: regulator-dummy: no parameters Jan 28 22:02:24 volumio kernel: Failed to find legacy iommu devices Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 16 Jan 28 22:02:24 volumio kernel: cpuidle: using governor ladder Jan 28 22:02:24 volumio kernel: cpuidle: using governor menu Jan 28 22:02:24 volumio kernel: vdso: 2 pages (1 code @ ffffff8008c36000, 1 data @ ffffff80091a4000) Jan 28 22:02:24 volumio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jan 28 22:02:24 volumio kernel: DMA: preallocated 256 KiB pool for atomic allocations Jan 28 22:02:24 volumio kernel: gpiochip_add_data: registered GPIOs 0 to 31 on device: gpio0 Jan 28 22:02:24 volumio kernel: gpiochip_add_data: registered GPIOs 32 to 63 on device: gpio1 Jan 28 22:02:24 volumio kernel: gpiochip_add_data: registered GPIOs 64 to 95 on device: gpio2 Jan 28 22:02:24 volumio kernel: gpiochip_add_data: registered GPIOs 96 to 127 on device: gpio3 Jan 28 22:02:24 volumio kernel: raid6: int64x1 gen() 201 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x1 xor() 182 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x2 gen() 323 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x2 xor() 261 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x4 gen() 467 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x4 xor() 331 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x8 gen() 394 MB/s Jan 28 22:02:24 volumio kernel: raid6: int64x8 xor() 302 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx1 gen() 360 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx1 xor() 354 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx2 gen() 564 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx2 xor() 457 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx4 gen() 756 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx4 xor() 559 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx8 gen() 773 MB/s Jan 28 22:02:24 volumio kernel: raid6: neonx8 xor() 569 MB/s Jan 28 22:02:24 volumio kernel: raid6: using algorithm neonx8 gen() 773 MB/s Jan 28 22:02:24 volumio kernel: raid6: .... xor() 569 MB/s, rmw enabled Jan 28 22:02:24 volumio kernel: raid6: using intx1 recovery algorithm Jan 28 22:02:24 volumio kernel: mpp venc_srv: mpp_probe enter Jan 28 22:02:24 volumio kernel: mpp venc_srv: init success Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/vcc-phy-regulator[0]' Jan 28 22:02:24 volumio kernel: vcc_phy: no parameters Jan 28 22:02:24 volumio kernel: reg-fixed-voltage vcc-phy-regulator: vcc_phy supplying 0uV Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/vcc-sys[0]' Jan 28 22:02:24 volumio kernel: vcc_sys: 5000 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage vcc-sys: vcc_sys supplying 5000000uV Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpio' property of node '/sdmmc-regulator[0]' - status (0) Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: vcc_sd: 3300 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: regulator get failed, ret=-517 Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: vcc_sd supplying 3300000uV Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpio' property of node '/vcc-host-5v-regulator[0]' - status (0) Jan 28 22:02:24 volumio kernel: reg-fixed-voltage vcc-host-5v-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_host_5v: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.4 err -2 Jan 28 22:02:24 volumio kernel: vcc_host_5v: at 5000 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: reg-fixed-voltage vcc-host-5v-regulator: vcc_host_5v supplying 0uV Jan 28 22:02:24 volumio kernel: iommu: Adding device ff350000.vpu_service to group 0 Jan 28 22:02:24 volumio kernel: iommu: Adding device ff351000.avsd to group 1 Jan 28 22:02:24 volumio kernel: iommu: Adding device ff360000.rkvdec to group 2 Jan 28 22:02:24 volumio kernel: iommu: Adding device ff330000.h265e to group 3 Jan 28 22:02:24 volumio kernel: iommu: Adding device ff340000.vepu to group 4 Jan 28 22:02:24 volumio kernel: iommu: Adding device ff370000.vop to group 5 Jan 28 22:02:24 volumio kernel: rk_iommu ff360480.iommu: can't get aclk Jan 28 22:02:24 volumio kernel: rk_iommu ff360480.iommu: can't get hclk Jan 28 22:02:24 volumio kernel: SCSI subsystem initialized Jan 28 22:02:24 volumio kernel: libata version 3.00 loaded. Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver usbfs Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver hub Jan 28 22:02:24 volumio kernel: usbcore: registered new device driver usb Jan 28 22:02:24 volumio kernel: media: Linux media interface: v0.10 Jan 28 22:02:24 volumio kernel: Linux video capture interface: v2.00 Jan 28 22:02:24 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Jan 28 22:02:24 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 28 22:02:24 volumio kernel: PTP clock support registered Jan 28 22:02:24 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Jan 28 22:02:24 volumio kernel: Bluetooth: Core ver 2.21 Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 31 Jan 28 22:02:24 volumio kernel: Bluetooth: HCI device and connection manager initialized Jan 28 22:02:24 volumio kernel: Bluetooth: HCI socket layer initialized Jan 28 22:02:24 volumio kernel: Bluetooth: L2CAP socket layer initialized Jan 28 22:02:24 volumio kernel: Bluetooth: SCO socket layer initialized Jan 28 22:02:24 volumio kernel: NetLabel: Initializing Jan 28 22:02:24 volumio kernel: NetLabel: domain hash size = 128 Jan 28 22:02:24 volumio kernel: NetLabel: protocols = UNLABELED CIPSOv4 Jan 28 22:02:24 volumio kernel: NetLabel: unlabeled traffic allowed by default Jan 28 22:02:24 volumio kernel: rockchip-cpuinfo cpuinfo: Serial : c441c49a7ab2865c Jan 28 22:02:24 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 2 Jan 28 22:02:24 volumio kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes) Jan 28 22:02:24 volumio kernel: TCP bind hash table entries: 8192 (order: 6, 262144 bytes) Jan 28 22:02:24 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Jan 28 22:02:24 volumio kernel: UDP hash table entries: 512 (order: 3, 49152 bytes) Jan 28 22:02:24 volumio kernel: UDP-Lite hash table entries: 512 (order: 3, 49152 bytes) Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 1 Jan 28 22:02:24 volumio kernel: RPC: Registered named UNIX socket transport module. Jan 28 22:02:24 volumio kernel: RPC: Registered udp transport module. Jan 28 22:02:24 volumio kernel: RPC: Registered tcp transport module. Jan 28 22:02:24 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 28 22:02:24 volumio kernel: PCI: CLS 0 bytes, default 64 Jan 28 22:02:24 volumio kernel: Trying to unpack rootfs image as initramfs... Jan 28 22:02:24 volumio kernel: Freeing initrd memory: 5476K Jan 28 22:02:24 volumio kernel: hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available Jan 28 22:02:24 volumio kernel: kvm [1]: 8-bit VMID Jan 28 22:02:24 volumio kernel: kvm [1]: Hyp mode initialized successfully Jan 28 22:02:24 volumio kernel: kvm [1]: interrupt-controller@ff814000 IRQ44 Jan 28 22:02:24 volumio kernel: kvm [1]: timer IRQ3 Jan 28 22:02:24 volumio kernel: audit: initializing netlink subsys (disabled) Jan 28 22:02:24 volumio kernel: audit: type=2000 audit(2.004:1): initialized Jan 28 22:02:24 volumio kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages Jan 28 22:02:24 volumio kernel: VFS: Disk quotas dquot_6.6.0 Jan 28 22:02:24 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 28 22:02:24 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 28 22:02:24 volumio kernel: NFS: Registering the id_resolver key type Jan 28 22:02:24 volumio kernel: Key type id_resolver registered Jan 28 22:02:24 volumio kernel: Key type id_legacy registered Jan 28 22:02:24 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Jan 28 22:02:24 volumio kernel: fuse init (API version 7.23) Jan 28 22:02:24 volumio kernel: JFS: nTxBlock = 7870, nTxLock = 62962 Jan 28 22:02:24 volumio kernel: SGI XFS with ACLs, security attributes, realtime, no debug enabled Jan 28 22:02:24 volumio kernel: Key type big_key registered Jan 28 22:02:24 volumio kernel: TEE Core Framework initialization (ver 1:0.1) Jan 28 22:02:24 volumio kernel: TEE armv7 Driver initialization Jan 28 22:02:24 volumio kernel: tz_tee_probe: name="armv7sec", id=0, pdev_name="armv7sec.0" Jan 28 22:02:24 volumio kernel: TEE core: Alloc the misc device "opteearmtz00" (id=0) Jan 28 22:02:24 volumio kernel: TEE Core: Register the misc device "opteearmtz00" (id=0,minor=62) Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 38 Jan 28 22:02:24 volumio kernel: Key type asymmetric registered Jan 28 22:02:24 volumio kernel: Asymmetric key parser 'x509' registered Jan 28 22:02:24 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) Jan 28 22:02:24 volumio kernel: io scheduler noop registered Jan 28 22:02:24 volumio kernel: io scheduler deadline registered (default) Jan 28 22:02:24 volumio kernel: io scheduler cfq registered Jan 28 22:02:24 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply from device tree Jan 28 22:02:24 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 Jan 28 22:02:24 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply from device tree Jan 28 22:02:24 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 Jan 28 22:02:24 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up vbus-supply from device tree Jan 28 22:02:24 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 Jan 28 22:02:24 volumio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Failed to get VBUS supply regulator Jan 28 22:02:24 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Looking up vbus-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Looking up vbus-supply property in node /usb3-phy@ff470000 failed Jan 28 22:02:24 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Failed to get VBUS supply regulator Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.2: Looking up phy-supply from device tree Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.2: Looking up phy-supply property in node /usb3-phy@ff470000/utmi@ff470000 failed Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.3: Looking up phy-supply from device tree Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.3: Looking up phy-supply property in node /usb3-phy@ff470000/pipe@ff478000 failed Jan 28 22:02:24 volumio kernel: rockchip-u3phy ff470000.usb3-phy: Rockchip u3phy initialized successfully Jan 28 22:02:24 volumio kernel: phy phy-ff430000.hdmiphy.4: Looking up phy-supply from device tree Jan 28 22:02:24 volumio kernel: phy phy-ff430000.hdmiphy.4: Looking up phy-supply property in node /hdmiphy@ff430000 failed Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: Looking up vcodec-supply from device tree Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: Looking up vcodec-supply property in node /vpu_combo failed Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: no regulator for vcodec Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: failed on clk_get clk_cabac Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: failed on clk_get clk_core Jan 28 22:02:24 volumio kernel: platform ff350000.vpu_service: probe device Jan 28 22:02:24 volumio kernel: platform ff350000.vpu_service: drm allocator with mmu enabled Jan 28 22:02:24 volumio kernel: platform ff351000.avsd: probe device Jan 28 22:02:24 volumio kernel: platform ff351000.avsd: drm allocator with mmu enabled Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: could not find power_model node Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: init success Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: Looking up vcodec-supply from device tree Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: Looking up vcodec-supply property in node /rkvdec@ff36000 failed Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: no regulator for vcodec Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: parent devfreq is disabled Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: probe device Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: drm allocator with mmu enabled Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: init success Jan 28 22:02:24 volumio kernel: probe device ff330000.h265e Jan 28 22:02:24 volumio kernel: mpp_dev ff330000.h265e: try to get iommu dev ffffffc03e02dc10 Jan 28 22:02:24 volumio kernel: mpp_dev ff330000.h265e: resource ready, register device Jan 28 22:02:24 volumio kernel: probe device ff340000.vepu Jan 28 22:02:24 volumio kernel: mpp_dev ff340000.vepu: try to get iommu dev ffffffc03e02e410 Jan 28 22:02:24 volumio kernel: mpp_dev ff340000.vepu: resource ready, register device Jan 28 22:02:24 volumio kernel: dma-pl330 ff1f0000.dmac: Loaded driver for PL330 DMAC-241330 Jan 28 22:02:24 volumio kernel: dma-pl330 ff1f0000.dmac: DBUFF-128x8bytes Num_Chans-8 Num_Peri-20 Num_Events-16 Jan 28 22:02:24 volumio kernel: Serial: 8250/16550 driver, 5 ports, IRQ sharing disabled Jan 28 22:02:24 volumio kernel: console [ttyS2] disabled Jan 28 22:02:24 volumio kernel: ff130000.serial: ttyS2 at MMIO 0xff130000 (irq = 13, base_baud = 1500000) is a 16550A Jan 28 22:02:24 volumio kernel: console [ttyS2] enabled Jan 28 22:02:24 volumio kernel: bootconsole [uart0] disabled Jan 28 22:02:24 volumio kernel: [drm] Initialized drm 1.1.0 20060810 Jan 28 22:02:24 volumio kernel: [drm] Rockchip DRM driver version: v1.0.1 Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: devfreq is not set Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: bound ff370000.vop (ops 0xffffff8008d02df0) Jan 28 22:02:24 volumio kernel: i2c i2c-4: of_i2c: modalias failure on /hdmi@ff3c0000/ports Jan 28 22:02:24 volumio kernel: dwhdmi-rockchip ff3c0000.hdmi: registered DesignWare HDMI I2C bus driver Jan 28 22:02:24 volumio kernel: dwhdmi-rockchip ff3c0000.hdmi: Detected HDMI TX controller v2.11a with HDCP (inno_dw_hdmi_phy2) Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: bound ff3c0000.hdmi (ops 0xffffff8008cf8c50) Jan 28 22:02:24 volumio kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). Jan 28 22:02:24 volumio kernel: [drm] No driver support for vblank timestamp query. Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: failed to parse display resources Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: No connectors reported connected with modes Jan 28 22:02:24 volumio kernel: [drm] Cannot find any crtc or sizes - going 1024x768 Jan 28 22:02:24 volumio kernel: Console: switching to colour frame buffer device 128x48 Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: fb0: frame buffer device Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver udl Jan 28 22:02:24 volumio kernel: Unable to detect cache hierarchy for CPU 0 Jan 28 22:02:24 volumio kernel: brd: module loaded Jan 28 22:02:24 volumio kernel: loop: module loaded Jan 28 22:02:24 volumio kernel: lkdtm: No crash points registered, enable through debugfs Jan 28 22:02:24 volumio kernel: rockchip-spi ff190000.spi: no high_speed pinctrl state Jan 28 22:02:24 volumio kernel: m25p80 spi32766.0: unrecognized JEDEC id bytes: ff, ff, ff Jan 28 22:02:24 volumio kernel: m25p80: probe of spi32766.0 failed with error -2 Jan 28 22:02:24 volumio kernel: force_sf_dma_mode is ignored if force_thresh_dma_mode is set. Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: Looking up phy-supply from device tree Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: clock input or output? (input). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: TX delay(0x26). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: RX delay(0x11). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: integrated PHY? (no). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: cannot get clock clk_mac_speed Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: clock input from PHY Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: init for RGMII Jan 28 22:02:24 volumio kernel: stmmac - user ID: 0x10, Synopsys ID: 0x35 Jan 28 22:02:24 volumio kernel: Ring mode enabled Jan 28 22:02:24 volumio kernel: DMA HW capability register supported Jan 28 22:02:24 volumio kernel: Normal descriptors Jan 28 22:02:24 volumio kernel: RX Checksum Offload Engine supported (type 2) Jan 28 22:02:24 volumio kernel: Wake-Up On Lan supported Jan 28 22:02:24 volumio kernel: Enable RX Mitigation via HW Watchdog Timer Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'snps,reset-gpio' property of node '/ethernet@ff540000[0]' - status (0) Jan 28 22:02:24 volumio kernel: libphy: stmmac: probed Jan 28 22:02:24 volumio kernel: eth%d: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active Jan 28 22:02:24 volumio kernel: eth%d: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01) Jan 28 22:02:24 volumio kernel: Rockchip WiFi SYS interface (V1.00) ... Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver cdc_ether Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver rndis_host Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.2: u3phy u2 power on Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.3: u3phy u3 power on Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply from device tree Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply property in node /usb@ff580000 failed Jan 28 22:02:24 volumio kernel: ff580000.usb supply vusb_d not found, using dummy regulator Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply from device tree Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply property in node /usb@ff580000 failed Jan 28 22:02:24 volumio kernel: ff580000.usb supply vusb_a not found, using dummy regulator Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: DWC OTG Controller Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: new USB bus registered, assigned bus number 1 Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: irq 41, io mem 0xff580000 Jan 28 22:02:24 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Jan 28 22:02:24 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb1: Product: DWC OTG Controller Jan 28 22:02:24 volumio kernel: usb usb1: Manufacturer: Linux 4.4.154 dwc2_hsotg Jan 28 22:02:24 volumio kernel: usb usb1: SerialNumber: ff580000.usb Jan 28 22:02:24 volumio kernel: hub 1-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 1-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Jan 28 22:02:24 volumio kernel: ehci-pci: EHCI PCI platform driver Jan 28 22:02:24 volumio kernel: ehci-platform: EHCI generic platform driver Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: EHCI Host Controller Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: new USB bus registered, assigned bus number 2 Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: irq 42, io mem 0xff5c0000 Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: USB 2.0 started, EHCI 1.00 Jan 28 22:02:24 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 Jan 28 22:02:24 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb2: Product: EHCI Host Controller Jan 28 22:02:24 volumio kernel: usb usb2: Manufacturer: Linux 4.4.154 ehci_hcd Jan 28 22:02:24 volumio kernel: usb usb2: SerialNumber: ff5c0000.usb Jan 28 22:02:24 volumio kernel: hub 2-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 2-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Jan 28 22:02:24 volumio kernel: ohci-platform: OHCI generic platform driver Jan 28 22:02:24 volumio kernel: ohci-platform ff5d0000.usb: Generic Platform OHCI controller Jan 28 22:02:24 volumio kernel: ohci-platform ff5d0000.usb: new USB bus registered, assigned bus number 3 Jan 28 22:02:24 volumio kernel: ohci-platform ff5d0000.usb: irq 43, io mem 0xff5d0000 Jan 28 22:02:24 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0001 Jan 28 22:02:24 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb3: Product: Generic Platform OHCI controller Jan 28 22:02:24 volumio kernel: usb usb3: Manufacturer: Linux 4.4.154 ohci_hcd Jan 28 22:02:24 volumio kernel: usb usb3: SerialNumber: ff5d0000.usb Jan 28 22:02:24 volumio kernel: hub 3-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 3-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 4 Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x00210010 Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: irq 179, io mem 0xff600000 Jan 28 22:02:24 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002 Jan 28 22:02:24 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb4: Product: xHCI Host Controller Jan 28 22:02:24 volumio kernel: usb usb4: Manufacturer: Linux 4.4.154 xhci-hcd Jan 28 22:02:24 volumio kernel: usb usb4: SerialNumber: xhci-hcd.9.auto Jan 28 22:02:24 volumio kernel: hub 4-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 4-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Jan 28 22:02:24 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc2 Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 5 Jan 28 22:02:24 volumio kernel: usb usb5: We don't know the algorithms for LPM for this host, disabling LPM. Jan 28 22:02:24 volumio kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0003 Jan 28 22:02:24 volumio kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb5: Product: xHCI Host Controller Jan 28 22:02:24 volumio kernel: usb usb5: Manufacturer: Linux 4.4.154 xhci-hcd Jan 28 22:02:24 volumio kernel: usb usb5: SerialNumber: xhci-hcd.9.auto Jan 28 22:02:24 volumio kernel: hub 5-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 5-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver iforce Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver xpad Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver usbtouchscreen Jan 28 22:02:24 volumio kernel: i2c /dev entries driver Jan 28 22:02:24 volumio kernel: rk808 1-0018: Pmic Chip id: 0x8050 Jan 28 22:02:24 volumio kernel: rk808 1-0018: source: on=0x40, off=0x00 Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc1-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG1: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.5 err -2 Jan 28 22:02:24 volumio kernel: vdd_logic: 712 <--> 1450 mV at 1100 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc2-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG2: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.6 err -2 Jan 28 22:02:24 volumio kernel: vdd_arm: 712 <--> 1450 mV at 1100 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc3-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG3: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.7 err -2 Jan 28 22:02:24 volumio kernel: vcc_ddr: at 5000 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG4: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.8 err -2 Jan 28 22:02:24 volumio kernel: vcc_io: 3300 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: supplied by vcc_io Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jan 28 22:02:24 volumio kernel: LDO_REG1: supplied by vcc_io Jan 28 22:02:24 volumio kernel: vcc_io: could not add device link regulator.9 err -2 Jan 28 22:02:24 volumio kernel: vdd_18: 1800 mV Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jan 28 22:02:24 volumio kernel: LDO_REG2: supplied by vcc_io Jan 28 22:02:24 volumio kernel: vcc_io: could not add device link regulator.10 err -2 Jan 28 22:02:24 volumio kernel: vcc18_emmc: 1800 mV Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc6-supply from device tree Jan 28 22:02:24 volumio kernel: LDO_REG3: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.11 err -2 Jan 28 22:02:24 volumio kernel: vdd_10: 1000 mV Jan 28 22:02:24 volumio kernel: rk808 1-0018: register rk8050 regulators Jan 28 22:02:24 volumio kernel: gpiochip_find_base: found new base at 510 Jan 28 22:02:24 volumio kernel: gpiochip_add_data: registered GPIOs 510 to 511 on device: rk8xx-gpio Jan 28 22:02:24 volumio kernel: rk8xx-gpio rk8xx-gpio: register rk8050 gpio successful Jan 28 22:02:24 volumio kernel: input: rk8xx_pwrkey as /devices/platform/ff160000.i2c/i2c-1/1-0018/input/input0 Jan 28 22:02:24 volumio kernel: rk808-rtc rk808-rtc: rtc core: registered rk808-rtc as rtc0 Jan 28 22:02:24 volumio kernel: rk3x-i2c ff160000.i2c: Initialized RK3xxx I2C bus at ffffff8009a76000 Jan 28 22:02:24 volumio kernel: IR NEC protocol handler initialized Jan 28 22:02:24 volumio kernel: IR RC5(x/sz) protocol handler initialized Jan 28 22:02:24 volumio kernel: IR RC6 protocol handler initialized Jan 28 22:02:24 volumio kernel: IR JVC protocol handler initialized Jan 28 22:02:24 volumio kernel: IR Sony protocol handler initialized Jan 28 22:02:24 volumio kernel: IR SANYO protocol handler initialized Jan 28 22:02:24 volumio kernel: usb 4-1: new high-speed USB device number 2 using xhci-hcd Jan 28 22:02:24 volumio kernel: IR Sharp protocol handler initialized Jan 28 22:02:24 volumio kernel: IR MCE Keyboard/mouse protocol handler initialized Jan 28 22:02:24 volumio kernel: IR XMP protocol handler initialized Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio4-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio5-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio6-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up pmuio-supply from device tree Jan 28 22:02:24 volumio kernel: rockchip-thermal ff250000.tsadc: tsadc is probed successfully! Jan 28 22:02:24 volumio kernel: device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com Jan 28 22:02:24 volumio kernel: Bluetooth: Virtual HCI driver ver 1.5 Jan 28 22:02:24 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Jan 28 22:02:24 volumio kernel: Bluetooth: HCI UART protocol H4 registered Jan 28 22:02:24 volumio kernel: Bluetooth: HCI UART protocol LL registered Jan 28 22:02:24 volumio kernel: Bluetooth: HCI UART protocol ATH3K registered Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver bfusb Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver btusb Jan 28 22:02:24 volumio kernel: cpu cpu0: Failed to get leakage Jan 28 22:02:24 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Jan 28 22:02:24 volumio kernel: cpu cpu0: Failed to get pvtm Jan 28 22:02:24 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Jan 28 22:02:24 volumio kernel: cpu cpu0: Looking up cpu-supply from device tree Jan 28 22:02:24 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Jan 28 22:02:24 volumio kernel: sdhci: Copyright(c) Pierre Ossman Jan 28 22:02:24 volumio kernel: Synopsys Designware Multimedia Card Interface Driver Jan 28 22:02:24 volumio kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=8750 Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: IDMAC supports 32-bit address mode. Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Using internal DMA controller. Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Version ID is 270a Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: DW MMC controller at irq 38,32 bit host data width,256 deep fifo Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Looking up vmmc-supply from device tree Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Looking up vqmmc-supply from device tree Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: GPIO lookup for consumer wp Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: using device tree for GPIO lookup Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff520000[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff520000[0]' Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: using lookup tables for GPIO lookup Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: lookup for GPIO wp failed Jan 28 22:02:24 volumio kernel: mmc_host mmc0: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) Jan 28 22:02:24 volumio kernel: usb 4-1: New USB device found, idVendor=05e3, idProduct=0610 Jan 28 22:02:24 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 28 22:02:24 volumio kernel: usb 4-1: Product: USB2.0 Hub Jan 28 22:02:24 volumio kernel: usb 4-1: Manufacturer: GenesysLogic Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: 1 slots initialized Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: IDMAC supports 32-bit address mode. Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Using internal DMA controller. Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Version ID is 270a Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: DW MMC controller at irq 39,32 bit host data width,256 deep fifo Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Looking up vmmc-supply from device tree Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: Looking up vqmmc-supply from device tree Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: GPIO lookup for consumer cd Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using device tree for GPIO lookup Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/dwmmc@ff500000[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/dwmmc@ff500000[0]' Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using lookup tables for GPIO lookup Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: lookup for GPIO cd failed Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: GPIO lookup for consumer wp Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using device tree for GPIO lookup Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff500000[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff500000[0]' Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: using lookup tables for GPIO lookup Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: lookup for GPIO wp failed Jan 28 22:02:24 volumio kernel: hub 4-1:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 4-1:1.0: 4 ports detected Jan 28 22:02:24 volumio kernel: mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) Jan 28 22:02:24 volumio kernel: mmc0: MAN_BKOPS_EN bit is not set Jan 28 22:02:24 volumio kernel: mmc_host mmc0: Bus speed (slot 0) = 200000000Hz (slot req 200000000Hz, actual 200000000HZ div = 0) Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff500000.dwmmc: 1 slots initialized Jan 28 22:02:24 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/standby-led[0]' - status (0) Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/power-led[0]' - status (0) Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/switches/usb-switch[0]' - status (0) Jan 28 22:02:24 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 28 22:02:24 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver usbhid Jan 28 22:02:24 volumio kernel: usbhid: USB HID core driver Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jan 28 22:02:24 volumio kernel: rk3328-codec ff410000.codec: spk_depop_time use default value. Jan 28 22:02:24 volumio kernel: u32 classifier Jan 28 22:02:24 volumio kernel: Netfilter messages via NETLINK v0.30. Jan 28 22:02:24 volumio kernel: ip_set: protocol 6 Jan 28 22:02:24 volumio kernel: Initializing XFRM netlink socket Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 10 Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 17 Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 15 Jan 28 22:02:24 volumio kernel: bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. Jan 28 22:02:24 volumio kernel: Bridge firewalling registered Jan 28 22:02:24 volumio kernel: Bluetooth: RFCOMM socket layer initialized Jan 28 22:02:24 volumio kernel: Bluetooth: RFCOMM ver 1.11 Jan 28 22:02:24 volumio kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2 Jan 28 22:02:24 volumio kernel: Bluetooth: HIDP socket layer initialized Jan 28 22:02:24 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 28 22:02:24 volumio kernel: [WLAN_RFKILL]: Enter rfkill_wlan_init Jan 28 22:02:24 volumio kernel: [BT_RFKILL]: Enter rfkill_rk_init Jan 28 22:02:24 volumio kernel: Key type dns_resolver registered Jan 28 22:02:24 volumio kernel: Registered cp15_barrier emulation handler Jan 28 22:02:24 volumio kernel: Registered setend emulation handler Jan 28 22:02:24 volumio kernel: registered taskstats version 1 Jan 28 22:02:24 volumio kernel: Btrfs loaded, integrity-checker=on Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running btrfs free space cache tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running extent only tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running bitmap only tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running bitmap and extent tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running space stealing from bitmap to extent Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Free space cache tests finished Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running extent buffer operation tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running btrfs_split_item tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running find delalloc tests Jan 28 22:02:24 volumio kernel: usb 5-1: new SuperSpeed USB device number 2 using xhci-hcd Jan 28 22:02:24 volumio kernel: usb 5-1: New USB device found, idVendor=05e3, idProduct=0612 Jan 28 22:02:24 volumio kernel: usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 28 22:02:24 volumio kernel: usb 5-1: Product: USB3.1 Hub Jan 28 22:02:24 volumio kernel: usb 5-1: Manufacturer: GenesysLogic Jan 28 22:02:24 volumio kernel: hub 5-1:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 5-1:1.0: 4 ports detected Jan 28 22:02:24 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jan 28 22:02:24 volumio kernel: usb 1-1: Product: E70 Velvet Jan 28 22:02:24 volumio kernel: usb 1-1: Manufacturer: Topping Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running btrfs_get_extent tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running hole first btrfs_get_extent test Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running outstanding_extents tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running qgroup tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Qgroup basic add Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Qgroup multiple refs test Jan 28 22:02:24 volumio kernel: Key type encrypted registered Jan 28 22:02:24 volumio kernel: pvtm list NULL Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/sound[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/sound[0]' Jan 28 22:02:24 volumio kernel: dwmmc_rockchip ff520000.dwmmc: Successfully tuned phase to 248 Jan 28 22:02:24 volumio kernel: mmc0: new HS200 MMC card at address 0001 Jan 28 22:02:24 volumio kernel: mmcblk0: mmc0:0001 DG4008 7.28 GiB Jan 28 22:02:24 volumio kernel: mmcblk0boot0: mmc0:0001 DG4008 partition 1 4.00 MiB Jan 28 22:02:24 volumio kernel: asoc-simple-card sound: rk3328-hifi <-> ff010000.i2s mapping ok Jan 28 22:02:24 volumio kernel: asoc-simple-card sound: snd-soc-dummy-dai <-> ff010000.i2s mapping ok Jan 28 22:02:24 volumio kernel: mmcblk0boot1: mmc0:0001 DG4008 partition 2 4.00 MiB Jan 28 22:02:24 volumio kernel: mmcblk0rpmb: mmc0:0001 DG4008 partition 3 4.00 MiB Jan 28 22:02:24 volumio kernel: mmcblk0: p1 p2 p3 Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/spdif-sound[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/spdif-sound[0]' Jan 28 22:02:24 volumio kernel: asoc-simple-card spdif-sound: dit-hifi <-> ff030000.spdif mapping ok Jan 28 22:02:24 volumio kernel: rk808-rtc rk808-rtc: setting system clock to 2016-01-21 08:50:05 UTC (1453366205) Jan 28 22:02:24 volumio kernel: of_cfs_init Jan 28 22:02:24 volumio kernel: of_cfs_init: OK Jan 28 22:02:24 volumio kernel: vcc_sd: disabling Jan 28 22:02:24 volumio kernel: ALSA device list: Jan 28 22:02:24 volumio kernel: #0: Topping E70 Velvet at usb-ff580000.usb-1, high speed Jan 28 22:02:24 volumio kernel: #1: I2S Jan 28 22:02:24 volumio kernel: #2: SPDIF Jan 28 22:02:24 volumio kernel: of_dma_request_slave_channel: dma-names property of node '/serial@ff130000' missing or empty Jan 28 22:02:24 volumio kernel: ttyS2 - failed to request DMA, use interrupt mode Jan 28 22:02:24 volumio kernel: Freeing unused kernel memory: 1216K Jan 28 22:02:24 volumio kernel: usb 1-1: USB disconnect, device number 2 Jan 28 22:02:24 volumio kernel: usb 1-1: new high-speed USB device number 3 using dwc2 Jan 28 22:02:24 volumio kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=8750 Jan 28 22:02:24 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jan 28 22:02:24 volumio kernel: usb 1-1: Product: E70 Velvet Jan 28 22:02:24 volumio kernel: usb 1-1: Manufacturer: Topping Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: Timeout while waiting for setup device command Jan 28 22:02:24 volumio initramfs: Booting Volumio for Rock64 Jan 28 22:02:24 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Jan 28 22:02:24 volumio initramfs: IMGPART=/dev/mmcblk0p2 Jan 28 22:02:24 volumio initramfs: IMGFILE=/volumio_current.sqsh Jan 28 22:02:24 volumio initramfs: DATAPART=/dev/mmcblk0p3 Jan 28 22:02:24 volumio initramfs: BOOTPART=/dev/mmcblk0p1 Jan 28 22:02:24 volumio initramfs: BOOTCONFIG=/boot//extlinux/extlinux.conf Jan 28 22:02:24 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 28 22:02:24 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jan 28 22:02:24 volumio initramfs: Checking for USB updates if you did not boot from USB... Jan 28 22:02:24 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 28 22:02:24 volumio kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 28 22:02:24 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 28 22:02:24 volumio kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 28 22:02:24 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 28 22:02:24 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 28 22:02:24 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jan 28 22:02:24 volumio initramfs: With Option: Jan 28 22:02:24 volumio initramfs: found fstab entry with UUID=7FB8-6D8F for /boot Jan 28 22:02:24 volumio initramfs: VOLUMIO_VERSION="3.212" Jan 28 22:02:24 volumio initramfs: Finish initramfs, continue booting Volumio Jan 28 22:02:24 volumio systemd[1]: System time before build time, advancing clock. Jan 28 22:02:24 volumio systemd[1]: Inserted module 'autofs4' Jan 28 22:02:24 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Jan 28 22:02:24 volumio kernel: cgroup: cgroup2: unknown option "nsdelegate" Jan 28 22:02:24 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) Jan 28 22:02:24 volumio systemd[1]: Detected architecture arm64. Jan 28 22:02:24 volumio systemd[1]: Set hostname to . Jan 28 22:02:24 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. Jan 28 22:02:24 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Jan 28 22:02:24 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. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Created slice system-getty.slice. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on udev Kernel Socket. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Created slice system-serial\x2dgetty.slice. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Socket. Jan 28 22:02:24 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 64 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Starting Restore / save the current clock... Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 65 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 65 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Mounting POSIX Message Queue File System... Jan 28 22:02:24 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Jan 28 22:02:24 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:24 volumio systemd[1]: Created slice User and Session Slice. Jan 28 22:02:24 volumio systemd[1]: Listening on udev Control Socket. Jan 28 22:02:24 volumio systemd[1]: Listening on fsck to fsckd communication Socket. Jan 28 22:02:24 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Jan 28 22:02:24 volumio systemd[1]: Starting Create list of required static device nodes for the current kernel... Jan 28 22:02:24 volumio systemd[1]: Starting Load Kernel Modules... Jan 28 22:02:24 volumio systemd[1]: Reached target System Time Synchronized. Jan 28 22:02:24 volumio systemd[1]: Reached target Slices. Jan 28 22:02:24 volumio systemd[1]: Mounting RPC Pipe File System... Jan 28 22:02:24 volumio systemd[1]: Mounting Kernel Debug File System... Jan 28 22:02:24 volumio systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Jan 28 22:02:24 volumio systemd[1]: Starting udev Coldplug all Devices... Jan 28 22:02:24 volumio systemd[1]: Listening on RPCbind Server Activation Socket. Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Socket (/dev/log). Jan 28 22:02:24 volumio systemd[1]: Mounting Huge Pages File System... Jan 28 22:02:24 volumio systemd[1]: Reached target Swap. Jan 28 22:02:24 volumio systemd[1]: Listening on initctl Compatibility Named Pipe. Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Audit Socket. Jan 28 22:02:24 volumio systemd[1]: Starting Journal Service... Jan 28 22:02:24 volumio systemd[1]: Started Restore / save the current clock. Jan 28 22:02:24 volumio systemd[1]: Mounted POSIX Message Queue File System. Jan 28 22:02:24 volumio systemd[1]: Started Remount Root and Kernel File Systems. Jan 28 22:02:24 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:24 volumio systemd[1]: Started Create list of required static device nodes for the current kernel. Jan 28 22:02:24 volumio systemd[1]: Started Load Kernel Modules. Jan 28 22:02:24 volumio systemd[1]: Mounted RPC Pipe File System. Jan 28 22:02:24 volumio systemd[1]: Mounted Kernel Debug File System. Jan 28 22:02:24 volumio systemd[1]: Mounted Huge Pages File System. Jan 28 22:02:24 volumio systemd[1]: Mounting Kernel Configuration File System... Jan 28 22:02:24 volumio systemd[1]: Starting Apply Kernel Variables... Jan 28 22:02:24 volumio systemd[1]: Mounting FUSE Control File System... Jan 28 22:02:24 volumio systemd[1]: Starting Create System Users... Jan 28 22:02:24 volumio kernel: Removed cp15_barrier emulation handler Jan 28 22:02:24 volumio kernel: Enabled cp15_barrier support Jan 28 22:02:24 volumio systemd[1]: Starting Load/Save Random Seed... Jan 28 22:02:24 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 28 22:02:24 volumio systemd[1]: Mounted Kernel Configuration File System. Jan 28 22:02:24 volumio systemd[1]: Started Apply Kernel Variables. Jan 28 22:02:24 volumio systemd[1]: Mounted FUSE Control File System. Jan 28 22:02:24 volumio systemd-journald[295]: Journal started Jan 28 22:02:24 volumio systemd-journald[295]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 3.7M, max 30.0M, 26.2M free. Jan 28 22:02:24 volumio fake-hwclock[273]: Fri Jan 28 22:02:24 UTC 2022 Jan 28 22:02:24 volumio systemd[1]: Started Journal Service. Jan 28 22:02:24 volumio systemd[1]: Started Create System Users. Jan 28 22:02:24 volumio systemd[1]: Started Load/Save Random Seed. Jan 28 22:02:24 volumio systemd[1]: Started udev Coldplug all Devices. Jan 28 22:02:24 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 28 22:02:24 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Jan 28 22:02:24 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 28 22:02:24 volumio systemd[1]: Started Create Static Device Nodes in /dev. Jan 28 22:02:24 volumio systemd[1]: Reached target Local File Systems (Pre). Jan 28 22:02:24 volumio systemd[1]: Mounting /var/spool/cups... Jan 28 22:02:24 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 28 22:02:24 volumio systemd[1]: Mounting /var/log... Jan 28 22:02:24 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 28 22:02:24 volumio systemd[1]: Mounting /tmp... Jan 28 22:02:24 volumio systemd[1]: Starting udev Kernel Device Manager... Jan 28 22:02:24 volumio systemd[1]: Mounted /var/spool/cups. Jan 28 22:02:24 volumio systemd[1]: Mounted /var/log. Jan 28 22:02:24 volumio systemd[1]: Mounted /tmp. Jan 28 22:02:24 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Jan 28 22:02:24 volumio systemd[1]: Mounting /var/spool/cups/tmp... Jan 28 22:02:24 volumio systemd[1]: Started udev Kernel Device Manager. Jan 28 22:02:24 volumio systemd[1]: Mounted /var/spool/cups/tmp. Jan 28 22:02:24 volumio systemd-journald[295]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 3.7M, max 30.0M, 26.2M free. Jan 28 22:02:24 volumio systemd[1]: Starting Show Plymouth Boot Screen... Jan 28 22:02:24 volumio systemd[1]: Started Flush Journal to Persistent Storage. Jan 28 22:02:24 volumio systemd[1]: Received SIGRTMIN+20 from PID 324 (plymouthd). Jan 28 22:02:24 volumio systemd[1]: Started Show Plymouth Boot Screen. Jan 28 22:02:24 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 28 22:02:24 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 28 22:02:24 volumio systemd[1]: Reached target Paths. Jan 28 22:02:24 volumio systemd[1]: Reached target Local Encrypted Volumes. Jan 28 22:02:24 volumio kernel: cfg80211: World regulatory domain updated: Jan 28 22:02:24 volumio kernel: cfg80211: DFS Master region: unset Jan 28 22:02:24 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Jan 28 22:02:24 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Jan 28 22:02:24 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Jan 28 22:02:24 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Jan 28 22:02:24 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Jan 28 22:02:24 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Jan 28 22:02:24 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Jan 28 22:02:24 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Jan 28 22:02:24 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Jan 28 22:02:24 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'. Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: mali_platform_device->num_resources = 9 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[0].start = 0x0x00000000ff300000 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[1].start = 0x0x00000000ff300000 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[2].start = 0x0x0000000000000012 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[3].start = 0x0x0000000000000013 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[4].start = 0x0x0000000000000014 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[5].start = 0x0x0000000000000015 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[6].start = 0x0x0000000000000016 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[7].start = 0x0x0000000000000017 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[8].start = 0x0x0000000000000018 Jan 28 22:02:24 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. Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Looking up mali-supply from device tree Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Failed to get leakage Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Looking up mali-supply from device tree Jan 28 22:02:24 volumio kernel: vdd_logic: could not add device link ff300000.gpu err -17 Jan 28 22:02:24 volumio kernel: vdd_logic: Failed to create debugfs directory Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Failed to get pvtm Jan 28 22:02:24 volumio kernel: Mali: Mali device driver loaded Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/hdmi-sound[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/hdmi-sound[0]' Jan 28 22:02:24 volumio kernel: asoc-simple-card hdmi-sound: i2s-hifi <-> ff000000.i2s mapping ok Jan 28 22:02:24 volumio systemd[1]: Found device /dev/ttyS2. Jan 28 22:02:24 volumio systemd-udevd[314]: Using default interface naming scheme 'v240'. Jan 28 22:02:24 volumio systemd-udevd[314]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:02:24 volumio systemd-udevd[314]: link_config: could not get ethtool features for eth0 Jan 28 22:02:24 volumio systemd-udevd[314]: Could not set offload features of eth0: Device or resource busy Jan 28 22:02:25 volumio kernel: devfreq ff300000.gpu: Couldn't update frequency transition information. Jan 28 22:02:25 volumio systemd-udevd[312]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 28 22:02:25 volumio systemd-udevd[318]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:02:25 volumio systemd[1]: Found device /dev/disk/by-uuid/7FB8-6D8F. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jan 28 22:02:25 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 28 22:02:25 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/7FB8-6D8F... Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 28 22:02:25 volumio systemd[1]: Started File System Check Daemon to report status. Jan 28 22:02:25 volumio systemd-fsck[374]: fsck.fat 4.1 (2017-01-24) Jan 28 22:02:25 volumio systemd-fsck[374]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 28 22:02:25 volumio systemd-fsck[374]: Automatically removing dirty bit. Jan 28 22:02:25 volumio systemd-fsck[374]: Performing changes. Jan 28 22:02:25 volumio systemd-fsck[374]: /dev/mmcblk0p1: 8 files, 12492/31161 clusters Jan 28 22:02:25 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/7FB8-6D8F. Jan 28 22:02:25 volumio systemd[1]: Mounting /boot... Jan 28 22:02:25 volumio kernel: FAT-fs (mmcblk0p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 28 22:02:25 volumio systemd[1]: Mounted /boot. Jan 28 22:02:25 volumio systemd[1]: Reached target Local File Systems. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 28 22:02:25 volumio systemd[1]: Starting Raise network interfaces... Jan 28 22:02:25 volumio systemd[1]: Starting Preprocess NFS configuration... Jan 28 22:02:25 volumio systemd[1]: Started ifup for eth0. Jan 28 22:02:25 volumio systemd[1]: Starting Create Volatile Files and Directories... Jan 28 22:02:25 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 28 22:02:25 volumio systemd[1]: nfs-config.service: Succeeded. Jan 28 22:02:25 volumio systemd[1]: Started Preprocess NFS configuration. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 28 22:02:25 volumio systemd[1]: Reached target NFS client services. Jan 28 22:02:25 volumio systemd[1]: Received SIGRTMIN+20 from PID 324 (plymouthd). Jan 28 22:02:25 volumio systemd[1]: plymouth-read-write.service: Succeeded. Jan 28 22:02:25 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 28 22:02:25 volumio sh[381]: command failed: No such device (-19) Jan 28 22:02:25 volumio ifup[379]: command failed: No such device (-19) Jan 28 22:02:25 volumio systemd[1]: Started Create Volatile Files and Directories. Jan 28 22:02:25 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 28 22:02:25 volumio systemd[1]: Starting RPC bind portmap service... Jan 28 22:02:25 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 28 22:02:25 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 28 22:02:25 volumio systemd[1]: Reached target System Initialization. Jan 28 22:02:25 volumio ifup[379]: command failed: No such device (-19) Jan 28 22:02:25 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 28 22:02:25 volumio systemd[1]: Listening on triggerhappy.socket. Jan 28 22:02:25 volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Sat 2025-01-11 02:00:14 UTC as it is in the future. Jan 28 22:02:25 volumio systemd[1]: Started Daily apt download activities. Jan 28 22:02:25 volumio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sat 2025-01-11 02:00:14 UTC as it is in the future. Jan 28 22:02:25 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jan 28 22:02:25 volumio systemd[1]: Listening on mpd.socket. Jan 28 22:02:25 volumio systemd[1]: Listening on lircd.socket. Jan 28 22:02:25 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 28 22:02:25 volumio systemd[1]: Reached target Timers. Jan 28 22:02:25 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 28 22:02:25 volumio systemd[1]: Reached target Sockets. Jan 28 22:02:25 volumio systemd[1]: Reached target Basic System. Jan 28 22:02:25 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jan 28 22:02:25 volumio systemd[1]: Starting Login Service... Jan 28 22:02:25 volumio systemd[1]: Started D-Bus System Message Bus. Jan 28 22:02:25 volumio sh[381]: eth0: waiting for carrier Jan 28 22:02:25 volumio sh[381]: eth0: carrier acquired Jan 28 22:02:25 volumio dhcpcd[421]: eth0: waiting for carrier Jan 28 22:02:25 volumio dhcpcd[421]: eth0: carrier acquired Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 28 22:02:25 volumio systemd[1]: Started volumio-remote-updater.service. Jan 28 22:02:25 volumio systemd[1]: Starting Wireless Services... Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 28 22:02:25 volumio sh[381]: DUID 00:01:00:01:29:87:24:71:ae:d4:82:bf:d9:37 Jan 28 22:02:25 volumio sh[381]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:25 volumio sh[381]: eth0: adding address fe80::3d00:2e9e:1083:ded6 Jan 28 22:02:25 volumio dhcpcd[421]: DUID 00:01:00:01:29:87:24:71:ae:d4:82:bf:d9:37 Jan 28 22:02:25 volumio sh[381]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio dhcpcd[421]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:25 volumio dhcpcd[421]: eth0: adding address fe80::3d00:2e9e:1083:ded6 Jan 28 22:02:25 volumio dhcpcd[421]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 22:02:25 volumio systemd[1]: Starting DHCP Client Daemon... Jan 28 22:02:25 volumio systemd[1]: Started Volumio Iptables Module. Jan 28 22:02:26 volumio systemd[1]: Starting WPA supplicant... Jan 28 22:02:26 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Jan 28 22:02:26 volumio systemd[1]: Started Manage Sound Card State (restore and store). Jan 28 22:02:26 volumio systemd[1]: Starting Save/Restore Sound Card State... Jan 28 22:02:26 volumio sh[381]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:26 volumio dhcpcd[421]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:26 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 28 22:02:26 volumio alsactl[464]: alsactl 1.1.8 daemon started Jan 28 22:02:26 volumio systemd[1]: Started RPC bind portmap service. Jan 28 22:02:26 volumio systemd[1]: Reached target Remote File Systems (Pre). Jan 28 22:02:26 volumio systemd[1]: Reached target Remote File Systems. Jan 28 22:02:26 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 28 22:02:26 volumio systemd[1]: Reached target RPC Port Mapper. Jan 28 22:02:26 volumio systemd-logind[450]: New seat seat0. Jan 28 22:02:26 volumio systemd-logind[450]: Watching system buttons on /dev/input/event0 (rk8xx_pwrkey) Jan 28 22:02:26 volumio thd[462]: Unable to parse trigger line: Jan 28 22:02:26 volumio thd[462]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 28 22:02:26 volumio thd[462]: Unable to parse trigger line: Jan 28 22:02:26 volumio thd[462]: Unable to parse trigger line: Jan 28 22:02:26 volumio thd[462]: Found socket passed from systemd Jan 28 22:02:26 volumio dhcpcd[457]: DUID 00:01:00:01:29:87:24:71:ae:d4:82:bf:d9:37 Jan 28 22:02:26 volumio dhcpcd[457]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:26 volumio dhcpcd[457]: eth0: adding address fe80::3d00:2e9e:1083:ded6 Jan 28 22:02:26 volumio dhcpcd[457]: ipv6_addaddr1: Permission denied Jan 28 22:02:26 volumio dhcpcd[457]: DUID 00:01:00:01:29:87:24:71:ae:d4:82:bf:d9:37 Jan 28 22:02:26 volumio dhcpcd[457]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:26 volumio dhcpcd[457]: eth0: adding address fe80::3d00:2e9e:1083:ded6 Jan 28 22:02:26 volumio dhcpcd[457]: ipv6_addaddr1: Permission denied Jan 28 22:02:26 volumio avahi-daemon[468]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Jan 28 22:02:26 volumio avahi-daemon[468]: Successfully dropped root privileges. Jan 28 22:02:26 volumio avahi-daemon[468]: avahi-daemon 0.7 starting up. Jan 28 22:02:26 volumio dhcpcd[457]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio dhcpcd[457]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio systemd[1]: Started Login Service. Jan 28 22:02:26 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jan 28 22:02:26 volumio systemd[1]: Started Save/Restore Sound Card State. Jan 28 22:02:26 volumio wpa_supplicant[460]: Successfully initialized wpa_supplicant Jan 28 22:02:26 volumio avahi-daemon[468]: Successfully called chroot(). Jan 28 22:02:26 volumio avahi-daemon[468]: Successfully dropped remaining capabilities. Jan 28 22:02:26 volumio avahi-daemon[468]: No service file found in /etc/avahi/services. Jan 28 22:02:26 volumio avahi-daemon[468]: Network interface enumeration completed. Jan 28 22:02:26 volumio avahi-daemon[468]: Server startup complete. Host name is volumio.local. Local service cookie is 1185347158. Jan 28 22:02:26 volumio kernel: nf_tables: (c) 2007-2009 Patrick McHardy Jan 28 22:02:26 volumio systemd[1]: Started WPA supplicant. Jan 28 22:02:26 volumio kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Jan 28 22:02:26 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 28 22:02:26 volumio systemd[1]: Reached target Sound Card. Jan 28 22:02:26 volumio kernel: nf_conntrack version 0.5.0 (7879 buckets, 31516 max) Jan 28 22:02:26 volumio systemd[1]: Started Raise network interfaces. Jan 28 22:02:26 volumio systemd[1]: Reached target Network. Jan 28 22:02:26 volumio volumio-remote-updater[453]: Error: No active session Jan 28 22:02:26 volumio volumio-remote-updater[453]: [2022-01-28 22:02:26] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:02:26 volumio volumio-remote-updater[453]: [2022-01-28 22:02:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:02:26 volumio systemd[1]: Starting Permit User Sessions... Jan 28 22:02:26 volumio volumio-remote-updater[453]: [2022-01-28 22:02:26] [error] handle_connect error: Underlying Transport Error Jan 28 22:02:26 volumio systemd[1]: Starting Network Time Service... Jan 28 22:02:26 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 28 22:02:26 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:26 volumio volumio[456]: Could not open config: /tmp/upmpdcli.conf Jan 28 22:02:26 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jan 28 22:02:26 volumio kernel: usb 5-1.1: new SuperSpeed USB device number 3 using xhci-hcd Jan 28 22:02:26 volumio kernel: random: nonblocking pool is initialized Jan 28 22:02:26 volumio systemd[1]: Reached target Network is Online. Jan 28 22:02:26 volumio kernel: usb 5-1.1: New USB device found, idVendor=05e3, idProduct=0735 Jan 28 22:02:26 volumio kernel: usb 5-1.1: New USB device strings: Mfr=4, Product=5, SerialNumber=6 Jan 28 22:02:26 volumio kernel: usb 5-1.1: Product: USB to S-ATA Jan 28 22:02:26 volumio kernel: usb 5-1.1: Manufacturer: Genesyslogic Jan 28 22:02:26 volumio kernel: usb 5-1.1: SerialNumber: 0000000000000014 Jan 28 22:02:26 volumio systemd[1]: Started Lyrion Music Server. Jan 28 22:02:26 volumio systemd[1]: Starting Samba NMB Daemon... Jan 28 22:02:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 22:02:26 volumio sh[381]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio dhcpcd[421]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jan 28 22:02:26 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jan 28 22:02:26 volumio loadcpufreq[469]: Loading cpufreq kernel modules...done (none). Jan 28 22:02:26 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:02:26 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 22:02:26 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 28 22:02:26 volumio systemd[1]: Started Permit User Sessions. Jan 28 22:02:26 volumio ntpd[522]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:02:26 volumio ntpd[522]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:02:26 volumio systemd[1]: Started Network Time Service. Jan 28 22:02:27 volumio systemd[1]: Started /etc/rc.local Compatibility. Jan 28 22:02:27 volumio kernel: usb-storage 5-1.1:1.0: USB Mass Storage device detected Jan 28 22:02:27 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 28 22:02:27 volumio kernel: scsi host0: usb-storage 5-1.1:1.0 Jan 28 22:02:27 volumio kernel: usbcore: registered new interface driver usb-storage Jan 28 22:02:27 volumio ntpd[551]: proto: precision = 0.875 usec (-20) Jan 28 22:02:27 volumio systemd[1]: Starting Hold until boot process finishes up... Jan 28 22:02:27 volumio kernel: usbcore: registered new interface driver uas Jan 28 22:02:27 volumio sshd[553]: Server listening on 0.0.0.0 port 22. Jan 28 22:02:27 volumio sshd[553]: Server listening on :: port 22. Jan 28 22:02:27 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 28 22:02:27 volumio systemd[1]: Started OpenBSD Secure Shell server. Jan 28 22:02:27 volumio systemd[1]: Received SIGRTMIN+21 from PID 324 (plymouthd). Jan 28 22:02:27 volumio dhcpcd[457]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:27 volumio dhcpcd[457]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:27 volumio ntpd[551]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:02:27 volumio systemd[1]: plymouth-start.service: Succeeded. Jan 28 22:02:27 volumio ntpd[551]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 28 22:02:27 volumio systemd[1]: plymouth-quit.service: Succeeded. Jan 28 22:02:27 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jan 28 22:02:27 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 28 22:02:27 volumio systemd[1]: Started Hold until boot process finishes up. Jan 28 22:02:27 volumio systemd[1]: Received SIGRTMIN+21 from PID 324 (n/a). Jan 28 22:02:27 volumio ntpd[551]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:02:27 volumio ntpd[551]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:02:27 volumio ntpd[551]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:02:27 volumio ntpd[551]: Listening on routing socket on fd #19 for interface updates Jan 28 22:02:27 volumio ntpd[551]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:27 volumio ntpd[551]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:27 volumio systemd[1]: Started Getty on tty1. Jan 28 22:02:27 volumio systemd[1]: Started Serial Getty on ttyS2. Jan 28 22:02:27 volumio systemd[1]: Reached target Login Prompts. Jan 28 22:02:27 volumio ifplugd(eth0)[585]: ifplugd 0.28 initializing. Jan 28 22:02:27 volumio ifplugd(eth0)[585]: Using interface eth0/AE:D4:82:BF:D9:37 with driver (version: March_2013) Jan 28 22:02:27 volumio ifplugd(eth0)[585]: Using detection mode: SIOCETHTOOL Jan 28 22:02:27 volumio ifplugd(eth0)[585]: Initialization complete, link beat detected. Jan 28 22:02:27 volumio ifplugd(eth0)[585]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 28 22:02:27 volumio lircd-setup[448]: /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. Jan 28 22:02:27 volumio lircd-setup[448]: parser = configparser.SafeConfigParser() Jan 28 22:02:27 volumio ifplugd(eth0)[585]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 28 22:02:27 volumio systemd[1]: lircd-setup.service: Succeeded. Jan 28 22:02:27 volumio systemd[1]: Started lircd(8) initialization helper tool. Jan 28 22:02:27 volumio systemd[1]: Starting Flexible IR remote input/output application support... Jan 28 22:02:27 volumio lircd-0.10.1[598]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Version: lircd 0.10.1 Jan 28 22:02:27 volumio cpufrequtils[566]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 28 22:02:27 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: System info: Linux volumio 4.4.154 #5 SMP Sat Jan 23 18:17:37 CET 2021 aarch64 GNU/Linux Jan 28 22:02:27 volumio lircd-0.10.1[598]: Info: Initial device: auto Jan 28 22:02:27 volumio lircd-0.10.1[598]: Info: Initial device: auto Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: driver: devinput Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: output: /var/run/lirc/lircd Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: nodaemon: 1 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: logfile: syslog Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: immediate-init: 0 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: permission: 666 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: driver-options: Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: listen: 0 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: connect: (null) Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: userelease: 0 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: effective_user: (null) Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: release_suffix: _EVUP Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: allow_simulate: 0 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: repeat_max: 600 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Options: dynamic_codes: (null) Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Current driver: devinput Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Driver API version: 4 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Driver version: 0.10.0 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: Using systemd fd Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Warning: Running as root Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Info: Using remote: devinput-64. Jan 28 22:02:27 volumio lircd-0.10.1[598]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: Using systemd fd Jan 28 22:02:27 volumio lircd-0.10.1[598]: Warning: Running as root Jan 28 22:02:27 volumio lircd-0.10.1[598]: Info: Using remote: devinput-64. Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Info: Using remote: devinput-32. Jan 28 22:02:27 volumio lircd-0.10.1[598]: Info: Using remote: devinput-32. Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Jan 28 22:02:27 volumio systemd[1]: Started Flexible IR remote input/output application support. Jan 28 22:02:27 volumio lircd[598]: lircd-0.10.1[598]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Jan 28 22:02:27 volumio lircd-0.10.1[598]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Jan 28 22:02:27 volumio systemd[1]: iptables.service: Succeeded. Jan 28 22:02:27 volumio dhcpcd[457]: eth0: carrier lost Jan 28 22:02:27 volumio dhcpcd[457]: eth0: carrier lost Jan 28 22:02:27 volumio sh[381]: eth0: carrier lost Jan 28 22:02:27 volumio dhcpcd[421]: eth0: carrier lost Jan 28 22:02:27 volumio ntpd[551]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 22:02:28 volumio sh[381]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Jan 28 22:02:28 volumio haveged[412]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Jan 28 22:02:28 volumio haveged[412]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Jan 28 22:02:28 volumio haveged[412]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00085 Jan 28 22:02:28 volumio haveged[412]: haveged: fills: 0, generated: 0 Jan 28 22:02:28 volumio sh[381]: cat: /run/dhcpcd/resolv.conf.eth0.dhcp: No such file or directory Jan 28 22:02:28 volumio nmbd[558]: [2022/01/28 22:02:28.164719, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 28 22:02:28 volumio nmbd[558]: started asyncdns process 643 Jan 28 22:02:28 volumio nmbd[558]: [2022/01/28 22:02:28.170378, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 28 22:02:28 volumio nmbd[558]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 28 22:02:28 volumio nmbd[558]: [2022/01/28 22:02:28.170652, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 28 22:02:28 volumio nmbd[558]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 28 22:02:28 volumio wireless.js[455]: WIRELESS DAEMON: start Jan 28 22:02:28 volumio wireless.js[455]: WIRELESS: Loaded configuration Jan 28 22:02:28 volumio wireless.js[455]: Cleaning previous... Jan 28 22:02:28 volumio sudo[647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:02:28 volumio sudo[647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:28 volumio sudo[647]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:28 volumio wireless.js[455]: Stopped aP Jan 28 22:02:28 volumio ntpd[551]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 28 22:02:29 volumio sudo[658]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:02:29 volumio sudo[658]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:29 volumio sudo[658]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:29 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:29 volumio hostapd[672]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:29 volumio kernel: scsi 0:0:0:0: Direct-Access Genesys USB3/SATA Bridge TB12 PQ: 0 ANSI: 6 Jan 28 22:02:29 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Jan 28 22:02:29 volumio hostapd[672]: Could not read interface wlan0 flags: No such device Jan 28 22:02:29 volumio hostapd[672]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:29 volumio hostapd[672]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:29 volumio hostapd[672]: Could not read interface wlan0 flags: No such device Jan 28 22:02:29 volumio hostapd[672]: nl80211 driver initialization failed. Jan 28 22:02:29 volumio hostapd[672]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:29 volumio hostapd[672]: wlan0: AP-DISABLED Jan 28 22:02:29 volumio hostapd[672]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:29 volumio hostapd[672]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:29 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:29 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:29 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:29 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:29 volumio dnsmasq[681]: dnsmasq: syntax check OK. Jan 28 22:02:29 volumio iw[682]: command failed: No such device (-19) Jan 28 22:02:29 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=237/KEYRING Jan 28 22:02:29 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Jan 28 22:02:29 volumio systemd[1]: Failed to start Wireless Services. Jan 28 22:02:29 volumio systemd[1]: Started Volumio Backend Module. Jan 28 22:02:29 volumio systemd[1]: Started Volumio Streaming Daemon. Jan 28 22:02:29 volumio volumio-streaming-daemon[689]: ############################ Jan 28 22:02:29 volumio volumio-streaming-daemon[689]: # Volumio Streaming Daemon # Jan 28 22:02:29 volumio volumio-streaming-daemon[689]: # Running on port 7777 # Jan 28 22:02:29 volumio volumio-streaming-daemon[689]: ############################ Jan 28 22:02:29 volumio volumio-streaming-daemon[689]: Environment: production Jan 28 22:02:29 volumio dnsmasq[703]: started, version 2.80 cachesize 150 Jan 28 22:02:29 volumio dnsmasq[703]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:29 volumio dnsmasq[703]: warning: interface wlan0 does not currently exist Jan 28 22:02:29 volumio dnsmasq-dhcp[703]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:29 volumio dnsmasq[703]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:29 volumio dnsmasq[703]: read /etc/hosts - 5 addresses Jan 28 22:02:29 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:29 volumio systemd[1]: Reached target Host and Network Name Lookups. Jan 28 22:02:29 volumio ntpd[551]: Soliciting pool server 192.168.211.1 Jan 28 22:02:30 volumio mpd[592]: Jan 28 22:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:30 volumio mpd[592]: Jan 28 22:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:30 volumio mpd[592]: Jan 28 22:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:30 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:30 volumio dhcpcd[457]: eth0: carrier acquired Jan 28 22:02:30 volumio dhcpcd[457]: eth0: carrier acquired Jan 28 22:02:30 volumio sh[381]: eth0: carrier acquired Jan 28 22:02:30 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Jan 28 22:02:30 volumio dhcpcd[421]: eth0: carrier acquired Jan 28 22:02:30 volumio sh[381]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:30 volumio dhcpcd[421]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:30 volumio dhcpcd[457]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:30 volumio dhcpcd[457]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:30 volumio ntpd[551]: Soliciting pool server 192.168.211.1 Jan 28 22:02:31 volumio dhcpcd[457]: eth0: soliciting an IPv6 router Jan 28 22:02:31 volumio dhcpcd[457]: eth0: soliciting an IPv6 router Jan 28 22:02:31 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:31 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 1. Jan 28 22:02:31 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:31 volumio dhcpcd[457]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:31 volumio dhcpcd[457]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:31 volumio dnsmasq[703]: exiting on receipt of SIGTERM Jan 28 22:02:31 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:31 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:31 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:31 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:31 volumio hostapd[717]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:31 volumio hostapd[717]: Could not read interface wlan0 flags: No such device Jan 28 22:02:31 volumio hostapd[717]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:31 volumio hostapd[717]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:31 volumio volumio-remote-updater[453]: [2022-01-28 22:02:31] [connect] Successful connection Jan 28 22:02:31 volumio hostapd[717]: Could not read interface wlan0 flags: No such device Jan 28 22:02:31 volumio hostapd[717]: nl80211 driver initialization failed. Jan 28 22:02:31 volumio hostapd[717]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:31 volumio hostapd[717]: wlan0: AP-DISABLED Jan 28 22:02:31 volumio hostapd[717]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:31 volumio hostapd[717]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:31 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:31 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:31 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:31 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:31 volumio dnsmasq[724]: dnsmasq: syntax check OK. Jan 28 22:02:31 volumio sh[381]: eth0: soliciting an IPv6 router Jan 28 22:02:31 volumio dhcpcd[421]: eth0: soliciting an IPv6 router Jan 28 22:02:31 volumio dnsmasq[730]: started, version 2.80 cachesize 150 Jan 28 22:02:31 volumio dnsmasq[730]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:31 volumio dnsmasq[730]: warning: interface wlan0 does not currently exist Jan 28 22:02:31 volumio dnsmasq-dhcp[730]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:31 volumio dnsmasq[730]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:31 volumio dnsmasq[730]: read /etc/hosts - 5 addresses Jan 28 22:02:31 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:31 volumio sh[381]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:31 volumio dhcpcd[421]: eth0: rebinding lease of 192.168.1.133 Jan 28 22:02:32 volumio volumio[688]: info: ------------------------------------------- Jan 28 22:02:32 volumio volumio[688]: info: ----- Volumio3 ---- Jan 28 22:02:32 volumio volumio[688]: info: ------------------------------------------- Jan 28 22:02:32 volumio volumio[688]: info: ----- System startup ---- Jan 28 22:02:32 volumio volumio[688]: info: ------------------------------------------- Jan 28 22:02:33 volumio volumio[688]: info: MYVOLUMIO Environment detected Jan 28 22:02:33 volumio volumio[688]: info: Plugin folders cleanup Jan 28 22:02:33 volumio volumio[688]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:33 volumio volumio[688]: info: Scanning category audio_interface Jan 28 22:02:33 volumio volumio[688]: info: Scanning category miscellanea Jan 28 22:02:33 volumio volumio[688]: info: Scanning category music_service Jan 28 22:02:33 volumio volumio[688]: info: Scanning category plugins.json Jan 28 22:02:33 volumio volumio[688]: info: Scanning category system_controller Jan 28 22:02:33 volumio volumio[688]: info: Scanning category user_interface Jan 28 22:02:33 volumio volumio[688]: info: Scanning into folder /data/plugins/ Jan 28 22:02:33 volumio volumio[688]: info: Scanning category music_service Jan 28 22:02:33 volumio volumio[688]: info: Plugin folders cleanup completed Jan 28 22:02:33 volumio volumio[688]: info: ------------------------------------------- Jan 28 22:02:33 volumio volumio[688]: info: ----- Core plugins startup ---- Jan 28 22:02:33 volumio volumio[688]: info: ------------------------------------------- Jan 28 22:02:33 volumio volumio[688]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:33 volumio volumio[688]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:33 volumio volumio[688]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:33 volumio volumio[688]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:33 volumio volumio[688]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:33 volumio volumio[688]: info: Loading plugin "system"... Jan 28 22:02:33 volumio volumio[688]: info: Loading plugin "appearance"... Jan 28 22:02:33 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:33 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2. Jan 28 22:02:33 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:33 volumio dnsmasq[730]: exiting on receipt of SIGTERM Jan 28 22:02:33 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:33 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:33 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:33 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:33 volumio hostapd[750]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:33 volumio hostapd[750]: Could not read interface wlan0 flags: No such device Jan 28 22:02:33 volumio hostapd[750]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:33 volumio hostapd[750]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:33 volumio hostapd[750]: Could not read interface wlan0 flags: No such device Jan 28 22:02:33 volumio hostapd[750]: nl80211 driver initialization failed. Jan 28 22:02:33 volumio hostapd[750]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:33 volumio hostapd[750]: wlan0: AP-DISABLED Jan 28 22:02:33 volumio hostapd[750]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:33 volumio hostapd[750]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:33 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:33 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:33 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:33 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:33 volumio dnsmasq[757]: dnsmasq: syntax check OK. Jan 28 22:02:33 volumio dhcpcd[457]: eth0: NAK: Invalid Request Message from 192.168.3.1 Jan 28 22:02:33 volumio dhcpcd[457]: eth0: message: Invalid Request Message Jan 28 22:02:33 volumio sh[381]: eth0: NAK: Invalid Request Message from 192.168.3.1 Jan 28 22:02:33 volumio sh[381]: eth0: message: Invalid Request Message Jan 28 22:02:33 volumio dhcpcd[457]: eth0: NAK: Invalid Request Message from 192.168.3.1 Jan 28 22:02:33 volumio dhcpcd[457]: eth0: message: Invalid Request Message Jan 28 22:02:33 volumio dhcpcd[421]: eth0: NAK: Invalid Request Message from 192.168.3.1 Jan 28 22:02:33 volumio dhcpcd[421]: eth0: message: Invalid Request Message Jan 28 22:02:33 volumio dhcpcd[457]: cat: /run/dhcpcd/resolv.conf.eth0.dhcp: No such file or directory Jan 28 22:02:34 volumio sh[381]: eth0: soliciting a DHCP lease Jan 28 22:02:34 volumio dhcpcd[421]: eth0: soliciting a DHCP lease Jan 28 22:02:34 volumio dhcpcd[457]: eth0: soliciting a DHCP lease Jan 28 22:02:34 volumio sh[381]: eth0: offered 192.168.3.4 from 192.168.3.1 Jan 28 22:02:34 volumio dhcpcd[457]: eth0: soliciting a DHCP lease Jan 28 22:02:34 volumio dhcpcd[421]: eth0: offered 192.168.3.4 from 192.168.3.1 Jan 28 22:02:34 volumio dnsmasq[781]: started, version 2.80 cachesize 150 Jan 28 22:02:34 volumio dnsmasq[781]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:34 volumio dnsmasq[781]: warning: interface wlan0 does not currently exist Jan 28 22:02:34 volumio dnsmasq-dhcp[781]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:34 volumio dnsmasq[781]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:34 volumio dnsmasq[781]: read /etc/hosts - 5 addresses Jan 28 22:02:34 volumio dhcpcd[457]: eth0: offered 192.168.3.4 from 192.168.3.1 Jan 28 22:02:34 volumio dhcpcd[457]: eth0: offered 192.168.3.4 from 192.168.3.1 Jan 28 22:02:34 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:34 volumio sh[381]: eth0: probing address 192.168.3.4/24 Jan 28 22:02:34 volumio dhcpcd[421]: eth0: probing address 192.168.3.4/24 Jan 28 22:02:34 volumio dhcpcd[457]: eth0: probing address 192.168.3.4/24 Jan 28 22:02:34 volumio dhcpcd[457]: eth0: probing address 192.168.3.4/24 Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "network"... Jan 28 22:02:35 volumio volumio[688]: info: Refreshing Cached IP Addresses Jan 28 22:02:35 volumio sudo[789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:35 volumio sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:35 volumio sudo[789]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "services"... Jan 28 22:02:35 volumio sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:35 volumio sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "alsa_controller"... Jan 28 22:02:35 volumio sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:35 volumio sudo[791]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:35 volumio sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:35 volumio sudo[794]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "wizard"... Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "upnp"... Jan 28 22:02:35 volumio volumio[688]: info: [1643407355580] Starting Upmpd Daemon Jan 28 22:02:35 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "my_music"... Jan 28 22:02:35 volumio volumio[688]: info: Loading plugin "mpd"... Jan 28 22:02:36 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:36 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 3. Jan 28 22:02:36 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:36 volumio volumio[688]: info: Creating MPD Configuration file Jan 28 22:02:36 volumio dnsmasq[781]: exiting on receipt of SIGTERM Jan 28 22:02:36 volumio volumio[688]: info: Loading plugin "upnp_browser"... Jan 28 22:02:36 volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:36 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:36 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:36 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:36 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:36 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:36 volumio sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:36 volumio sudo[807]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:36 volumio sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:36 volumio hostapd[812]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:36 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:36 volumio hostapd[812]: Could not read interface wlan0 flags: No such device Jan 28 22:02:36 volumio hostapd[812]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:36 volumio hostapd[812]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:36 volumio hostapd[812]: Could not read interface wlan0 flags: No such device Jan 28 22:02:36 volumio hostapd[812]: nl80211 driver initialization failed. Jan 28 22:02:36 volumio hostapd[812]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:36 volumio hostapd[812]: wlan0: AP-DISABLED Jan 28 22:02:36 volumio hostapd[812]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:36 volumio hostapd[812]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:36 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:36 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:36 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:36 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:36 volumio dnsmasq[820]: dnsmasq: syntax check OK. Jan 28 22:02:36 volumio dnsmasq[826]: started, version 2.80 cachesize 150 Jan 28 22:02:36 volumio dnsmasq[826]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:36 volumio dnsmasq[826]: warning: interface wlan0 does not currently exist Jan 28 22:02:36 volumio dnsmasq-dhcp[826]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:36 volumio dnsmasq[826]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:36 volumio dnsmasq[826]: read /etc/hosts - 5 addresses Jan 28 22:02:36 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:36 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:36 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:36 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:36 volumio volumio[688]: info: Loading plugin "networkfs"... Jan 28 22:02:36 volumio volumio[688]: info: Starting Udev Watcher for removable devices Jan 28 22:02:37 volumio volumio[688]: info: Ignoring mount for partition: boot Jan 28 22:02:37 volumio volumio[688]: info: Ignoring mount for partition: volumio Jan 28 22:02:37 volumio volumio[688]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:37 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "alarm-clock"... Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "airplay_emulation"... Jan 28 22:02:37 volumio volumio[688]: info: Starting Shairport Sync Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "last_100"... Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "webradio"... Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "i2s_dacs"... Jan 28 22:02:37 volumio volumio[688]: info: I2S DAC not set, start Auto-detection Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "volumiodiscovery"... Jan 28 22:02:37 volumio volumio[688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:37 volumio volumio[688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:37 volumio volumio[688]: *** WARNING *** For more information see Jan 28 22:02:37 volumio node[688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:37 volumio volumio[688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:37 volumio volumio[688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:37 volumio volumio[688]: *** WARNING *** For more information see Jan 28 22:02:37 volumio node[688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:37 volumio node[688]: *** WARNING *** For more information see Jan 28 22:02:37 volumio node[688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:37 volumio node[688]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:37 volumio node[688]: *** WARNING *** For more information see Jan 28 22:02:37 volumio volumio[688]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:02:37 volumio volumio[688]: info: Discovery: Started advertising with name: Volumio Jan 28 22:02:37 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:37 volumio volumio[688]: info: Loading plugin "squeezelite_mc"... Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 4. Jan 28 22:02:38 volumio mpd[834]: Jan 28 22:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:38 volumio mpd[834]: Jan 28 22:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:38 volumio mpd[834]: Jan 28 22:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:38 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:38 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:38 volumio sudo[811]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:38 volumio dnsmasq[826]: exiting on receipt of SIGTERM Jan 28 22:02:38 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:38 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:38 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:38 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:38 volumio hostapd[844]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:38 volumio hostapd[844]: Could not read interface wlan0 flags: No such device Jan 28 22:02:38 volumio hostapd[844]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:38 volumio hostapd[844]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:38 volumio hostapd[844]: Could not read interface wlan0 flags: No such device Jan 28 22:02:38 volumio hostapd[844]: nl80211 driver initialization failed. Jan 28 22:02:38 volumio hostapd[844]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:38 volumio hostapd[844]: wlan0: AP-DISABLED Jan 28 22:02:38 volumio hostapd[844]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:38 volumio hostapd[844]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:38 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:38 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:38 volumio dnsmasq[851]: dnsmasq: syntax check OK. Jan 28 22:02:38 volumio dnsmasq[857]: started, version 2.80 cachesize 150 Jan 28 22:02:38 volumio dnsmasq[857]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:38 volumio dnsmasq[857]: warning: interface wlan0 does not currently exist Jan 28 22:02:38 volumio dnsmasq-dhcp[857]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:38 volumio dnsmasq[857]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:38 volumio dnsmasq[857]: read /etc/hosts - 5 addresses Jan 28 22:02:38 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:38 volumio dhcpcd[457]: eth0: leased 192.168.3.4 for 86400 seconds Jan 28 22:02:38 volumio dhcpcd[457]: eth0: leased 192.168.3.4 for 86400 seconds Jan 28 22:02:38 volumio dhcpcd[457]: eth0: adding route to 192.168.3.0/24 Jan 28 22:02:38 volumio dhcpcd[457]: eth0: adding default route via 192.168.3.1 Jan 28 22:02:38 volumio dhcpcd[457]: eth0: adding route to 192.168.3.0/24 Jan 28 22:02:38 volumio dhcpcd[457]: eth0: adding default route via 192.168.3.1 Jan 28 22:02:38 volumio avahi-daemon[468]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.4. Jan 28 22:02:38 volumio avahi-daemon[468]: New relevant interface eth0.IPv4 for mDNS. Jan 28 22:02:38 volumio avahi-daemon[468]: Registering new address record for 192.168.3.4 on eth0.IPv4. Jan 28 22:02:38 volumio dnsmasq[857]: reading /etc/resolv.conf Jan 28 22:02:38 volumio dnsmasq[857]: using nameserver 192.168.3.1#53 Jan 28 22:02:38 volumio dnsmasq[857]: using nameserver 208.67.222.222#53 Jan 28 22:02:38 volumio dnsmasq[857]: using nameserver 208.67.220.220#53 Jan 28 22:02:38 volumio dhcpcd[457]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Jan 28 22:02:39 volumio volumio[688]: info: Loading plugin "outputs"... Jan 28 22:02:39 volumio ntpd[551]: ntpd exiting on signal 15 (Terminated) Jan 28 22:02:39 volumio systemd[1]: Stopping Network Time Service... Jan 28 22:02:39 volumio volumio[688]: info: Loading plugin "albumart"... Jan 28 22:02:39 volumio systemd[1]: ntp.service: Succeeded. Jan 28 22:02:39 volumio systemd[1]: Stopped Network Time Service. Jan 28 22:02:39 volumio volumio[688]: info: Plugin example_plugin is not enabled Jan 28 22:02:39 volumio volumio[688]: info: Loading plugin "inputs"... Jan 28 22:02:39 volumio systemd[1]: Starting Network Time Service... Jan 28 22:02:39 volumio volumio[688]: info: Loading plugin "updater_comm"... Jan 28 22:02:39 volumio ntpd[897]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:02:39 volumio ntpd[897]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:02:39 volumio systemd[1]: Started Network Time Service. Jan 28 22:02:39 volumio dhcpcd[457]: forked to background, child pid 909 Jan 28 22:02:39 volumio dhcpcd[457]: forked to background, child pid 909 Jan 28 22:02:39 volumio systemd[1]: Started DHCP Client Daemon. Jan 28 22:02:39 volumio ntpd[908]: proto: precision = 0.875 usec (-20) Jan 28 22:02:39 volumio ntpd[908]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:02:39 volumio ntpd[908]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 28 22:02:39 volumio ntpd[908]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:02:39 volumio ntpd[908]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:02:39 volumio ntpd[908]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:02:39 volumio ntpd[908]: Listen normally on 3 eth0 192.168.3.4:123 Jan 28 22:02:39 volumio ntpd[908]: Listening on routing socket on fd #20 for interface updates Jan 28 22:02:39 volumio ntpd[908]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:39 volumio ntpd[908]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:39 volumio volumio[688]: info: Plugin mpdemulation is not enabled Jan 28 22:02:39 volumio volumio[688]: info: Loading plugin "rest_api"... Jan 28 22:02:39 volumio volumio[688]: info: Loading plugin "websocket"... Jan 28 22:02:39 volumio volumio[688]: info: Plugin RoonBridge is not enabled Jan 28 22:02:39 volumio volumio[688]: info: Loading i18n strings for locale zh Jan 28 22:02:39 volumio sh[381]: eth0: leased 192.168.3.4 for 86400 seconds Jan 28 22:02:39 volumio sh[381]: eth0: adding route to 192.168.3.0/24 Jan 28 22:02:39 volumio sh[381]: eth0: adding default route via 192.168.3.1 Jan 28 22:02:39 volumio dhcpcd[421]: eth0: leased 192.168.3.4 for 86400 seconds Jan 28 22:02:39 volumio dhcpcd[421]: eth0: adding route to 192.168.3.0/24 Jan 28 22:02:39 volumio dhcpcd[421]: eth0: adding default route via 192.168.3.1 Jan 28 22:02:39 volumio volumio[688]: Updating browse sources language Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::initPlayerControls Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:39 volumio volumio[688]: Express server listening on port 3000 Jan 28 22:02:39 volumio volumio[688]: [Metrics] WebUI: 8s 22.86ms Jan 28 22:02:39 volumio sh[381]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Jan 28 22:02:39 volumio volumio[688]: info: CoreStateMachine::resetVolumioState Jan 28 22:02:39 volumio volumio[688]: info: CoreStateMachine::getcurrentVolume Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:39 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:39 volumio volumio[688]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:02:39 volumio volumio[688]: info: Completed loading Core Plugins Jan 28 22:02:39 volumio volumio[688]: info: Preparing to generate the ALSA configuration file Jan 28 22:02:39 volumio volumio[688]: Forking 3 albumart workers Jan 28 22:02:39 volumio volumio[688]: info: Cannot read play queue from file Jan 28 22:02:39 volumio volumio[688]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:02:39 volumio ntpd[908]: ntpd exiting on signal 15 (Terminated) Jan 28 22:02:39 volumio systemd[1]: Stopping Network Time Service... Jan 28 22:02:39 volumio volumio[688]: info: MPD Permissions set Jan 28 22:02:39 volumio volumio[688]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:02:39 volumio volumio[688]: wlan0 Interface doesn't support scanning. Jan 28 22:02:39 volumio volumio[688]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:02:39 volumio systemd[1]: ntp.service: Succeeded. Jan 28 22:02:39 volumio systemd[1]: Stopped Network Time Service. Jan 28 22:02:39 volumio systemd[1]: Starting Network Time Service... Jan 28 22:02:39 volumio sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:02:39 volumio sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:39 volumio sudo[969]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:39 volumio volumio[688]: command failed: No such device (-19) Jan 28 22:02:39 volumio volumio[688]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:02:39 volumio volumio[688]: command failed: No such device (-19) Jan 28 22:02:39 volumio ntpd[970]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:02:39 volumio ntpd[970]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:02:39 volumio systemd[1]: Started Network Time Service. Jan 28 22:02:39 volumio sh[381]: forked to background, child pid 982 Jan 28 22:02:39 volumio dhcpcd[421]: forked to background, child pid 982 Jan 28 22:02:39 volumio ntpd[981]: proto: precision = 0.875 usec (-20) Jan 28 22:02:39 volumio ntpd[981]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:02:39 volumio ntpd[981]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 28 22:02:39 volumio ntpd[981]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:02:39 volumio ntpd[981]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:02:39 volumio ntpd[981]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:02:39 volumio ntpd[981]: Listen normally on 3 eth0 192.168.3.4:123 Jan 28 22:02:39 volumio ntpd[981]: Listening on routing socket on fd #20 for interface updates Jan 28 22:02:39 volumio ntpd[981]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:39 volumio ntpd[981]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:39 volumio dhcpcd[909]: eth0: carrier lost Jan 28 22:02:39 volumio dhcpcd[982]: eth0: carrier lost Jan 28 22:02:39 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jan 28 22:02:39 volumio volumio[688]: info: MPD running with PID834 Jan 28 22:02:39 volumio volumio[688]: ,establishing connection Jan 28 22:02:39 volumio volumio[688]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:02:40 volumio avahi-daemon[468]: Withdrawing address record for 192.168.3.4 on eth0. Jan 28 22:02:40 volumio avahi-daemon[468]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.3.4. Jan 28 22:02:40 volumio avahi-daemon[468]: Interface eth0.IPv4 no longer relevant for mDNS. Jan 28 22:02:40 volumio dnsmasq[857]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:40 volumio ifplugd(eth0)[585]: client: ifup: interface eth0 already configured Jan 28 22:02:40 volumio dhcpcd[982]: eth0: deleting route to 192.168.3.0/24 Jan 28 22:02:40 volumio dhcpcd[982]: eth0: deleting default route via 192.168.3.1 Jan 28 22:02:40 volumio sh[381]: eth0=eth0 Jan 28 22:02:40 volumio volumio[688]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:40 volumio volumio[688]: assert.ok(self.idling) Jan 28 22:02:40 volumio volumio[688]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:40 volumio volumio[688]: assert.ok(self.idling) Jan 28 22:02:40 volumio dhcpcd[909]: eth0: deleting route to 192.168.3.0/24 Jan 28 22:02:40 volumio dhcpcd[909]: eth0: deleting default route via 192.168.3.1 Jan 28 22:02:40 volumio dnsmasq[857]: reading /etc/resolv.conf Jan 28 22:02:40 volumio dnsmasq[857]: using nameserver 208.67.222.222#53 Jan 28 22:02:40 volumio dnsmasq[857]: using nameserver 208.67.220.220#53 Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio ifplugd[545]: Network Interface Plugging Daemon...start eth0...done. Jan 28 22:02:40 volumio ifplugd(eth0)[585]: Program executed successfully. Jan 28 22:02:40 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio ifplugd(eth0)[585]: Link beat lost. Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio dnsmasq[857]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:02:40 volumio volumio[688]: info: CoreStateMachine::pushState Jan 28 22:02:40 volumio volumio[688]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:40 volumio volumio[688]: info: CoreStateMachine::updateTrackBlock Jan 28 22:02:40 volumio volumio[688]: info: CorePlayQueue::getTrackBlock Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:40 volumio volumio[688]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:02:40 volumio volumio[688]: info: Output device has changed, restarting MPD Jan 28 22:02:40 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:40 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 5. Jan 28 22:02:40 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:40 volumio volumio[688]: info: Output device has changed, restarting Shairport Sync Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:40 volumio sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:40 volumio sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:40 volumio sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:40 volumio sudo[1078]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:40 volumio sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:40 volumio dnsmasq[857]: exiting on receipt of SIGTERM Jan 28 22:02:40 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:40 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:40 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:40 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:40 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:40 volumio volumio[688]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:40 volumio volumio[688]: info: ___________ START PLUGINS ___________ Jan 28 22:02:40 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:40 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:40 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:40 volumio volumio[688]: info: [1643407360760] CoreMusicLibrary::Adding element Media Servers Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:40 volumio volumio[688]: Cannot find translation for sourceMedia Servers Jan 28 22:02:40 volumio hostapd[1087]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:40 volumio ntpd[981]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:40 volumio volumio[688]: Starting albumart workers Jan 28 22:02:40 volumio hostapd[1087]: Could not read interface wlan0 flags: No such device Jan 28 22:02:40 volumio hostapd[1087]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:40 volumio hostapd[1087]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:40 volumio hostapd[1087]: Could not read interface wlan0 flags: No such device Jan 28 22:02:40 volumio hostapd[1087]: nl80211 driver initialization failed. Jan 28 22:02:40 volumio hostapd[1087]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:40 volumio hostapd[1087]: wlan0: AP-DISABLED Jan 28 22:02:40 volumio hostapd[1087]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:40 volumio hostapd[1087]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:40 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:40 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:40 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:40 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:40 volumio dnsmasq[1098]: dnsmasq: syntax check OK. Jan 28 22:02:40 volumio volumio[688]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:41 volumio volumio[688]: Starting albumart workers Jan 28 22:02:41 volumio volumio[688]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:41 volumio volumio[688]: info: [1643407361152] CoreMusicLibrary::Adding element Last_100 Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:41 volumio dnsmasq[1108]: started, version 2.80 cachesize 150 Jan 28 22:02:41 volumio dnsmasq[1108]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:41 volumio dnsmasq[1108]: warning: interface wlan0 does not currently exist Jan 28 22:02:41 volumio dnsmasq-dhcp[1108]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:41 volumio dnsmasq[1108]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:41 volumio dnsmasq[1108]: read /etc/hosts - 5 addresses Jan 28 22:02:41 volumio volumio[688]: Cannot find translation for sourceMedia Servers Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:41 volumio volumio[688]: info: [1643407361163] CoreMusicLibrary::Adding element Webradio Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:41 volumio volumio[688]: Cannot find translation for sourceMedia Servers Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:02:41 volumio volumio[688]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:02:41 volumio volumio[688]: info: Volumio Calling Home Jan 28 22:02:41 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:41 volumio volumio[688]: Starting albumart workers Jan 28 22:02:41 volumio volumio[688]: info: [squeezelite_mc] Proxy server started on port 35132 Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:41 volumio volumio[688]: info: CoreStateMachine::setRepeat null single undefined Jan 28 22:02:41 volumio volumio[688]: info: CoreStateMachine::pushState Jan 28 22:02:41 volumio volumio[688]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:41 volumio volumio[688]: info: CoreStateMachine::setRandom null Jan 28 22:02:41 volumio volumio[688]: info: CoreStateMachine::pushState Jan 28 22:02:41 volumio volumio[688]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:41 volumio volumio[688]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:41 volumio volumio[688]: error: updateQueue error: null Jan 28 22:02:41 volumio volumio[688]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 22:02:41 volumio ntpd[981]: Deleting interface #3 eth0, 192.168.3.4#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jan 28 22:02:41 volumio ntpd[981]: Soliciting pool server 192.168.211.1 Jan 28 22:02:41 volumio volumio[688]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 22:02:41 volumio volumio[688]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 22:02:41 volumio volumio[688]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 22:02:41 volumio volumio[688]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 22:02:41 volumio volumio[688]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 22:02:41 volumio volumio[688]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 22:02:41 volumio volumio[688]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 22:02:41 volumio volumio[688]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 22:02:41 volumio volumio[688]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 22:02:41 volumio volumio[688]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 22:02:41 volumio volumio[688]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 22:02:41 volumio volumio[688]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 22:02:41 volumio volumio[688]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 22:02:41 volumio volumio[688]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 22:02:41 volumio volumio[688]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 22:02:41 volumio volumio[688]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 22:02:41 volumio volumio[688]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 22:02:41 volumio volumio[688]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:02:41 volumio volumio[688]: info: MPD Permissions set Jan 28 22:02:41 volumio volumio[688]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:02:42 volumio volumio[688]: info: mDNS: A device disapperared from network Jan 28 22:02:42 volumio volumio[688]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 28 22:02:42 volumio volumio[688]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:02:42 volumio volumio[688]: info: CoreStateMachine::pushState Jan 28 22:02:42 volumio volumio[688]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:42 volumio volumio[688]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:02:42 volumio volumio[688]: info: CoreStateMachine::pushState Jan 28 22:02:42 volumio volumio[688]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:42 volumio volumio[688]: info: [squeezelite_mc] Server discovery started Jan 28 22:02:42 volumio volumio[688]: info: [squeezelite_mc] Player finder started Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:42 volumio volumio[688]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:02:42 volumio volumio[688]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:42 volumio volumio[688]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:02:42 volumio volumio[688]: at doSend (dgram.js:714:16) Jan 28 22:02:42 volumio volumio[688]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:02:42 volumio volumio[688]: at afterDns (dgram.js:660:5) Jan 28 22:02:42 volumio volumio[688]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:02:42 volumio volumio[688]: errno: -101, Jan 28 22:02:42 volumio volumio[688]: code: 'ENETUNREACH', Jan 28 22:02:42 volumio volumio[688]: syscall: 'send', Jan 28 22:02:42 volumio volumio[688]: address: '255.255.255.255', Jan 28 22:02:42 volumio volumio[688]: port: 3483 Jan 28 22:02:42 volumio volumio[688]: } Jan 28 22:02:42 volumio volumio[688]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:42 volumio ntpd[981]: Soliciting pool server 192.168.211.1 Jan 28 22:02:43 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:43 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 6. Jan 28 22:02:43 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:43 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:01 Jan 28 22:02:43 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:43 volumio dnsmasq[1108]: exiting on receipt of SIGTERM Jan 28 22:02:43 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:43 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:43 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:43 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:43 volumio hostapd[1169]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:43 volumio hostapd[1169]: Could not read interface wlan0 flags: No such device Jan 28 22:02:43 volumio hostapd[1169]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:43 volumio hostapd[1169]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:43 volumio hostapd[1169]: Could not read interface wlan0 flags: No such device Jan 28 22:02:43 volumio hostapd[1169]: nl80211 driver initialization failed. Jan 28 22:02:43 volumio hostapd[1169]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:43 volumio hostapd[1169]: wlan0: AP-DISABLED Jan 28 22:02:43 volumio hostapd[1169]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:43 volumio hostapd[1169]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:43 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:43 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:43 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:43 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:43 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:43 volumio dnsmasq[1176]: dnsmasq: syntax check OK. Jan 28 22:02:43 volumio dnsmasq[1186]: started, version 2.80 cachesize 150 Jan 28 22:02:43 volumio dnsmasq[1186]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:43 volumio dnsmasq[1186]: warning: interface wlan0 does not currently exist Jan 28 22:02:43 volumio dnsmasq-dhcp[1186]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:43 volumio dnsmasq[1186]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:43 volumio dnsmasq[1186]: read /etc/hosts - 5 addresses Jan 28 22:02:43 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:43 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:02:43 volumio sudo[1079]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 22:02:43 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:43 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 28 22:02:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 28 22:02:43 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:43 volumio systemd[1]: Stopped Volumio Backend Module. Jan 28 22:02:43 volumio systemd[1]: Started Volumio Backend Module. Jan 28 22:02:43 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:43 volumio mpd[1113]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:43 volumio mpd[1113]: output: No 'audio_output' defined in config file Jan 28 22:02:43 volumio mpd[1113]: output: Attempt to detect audio output device Jan 28 22:02:43 volumio mpd[1113]: output: Attempting to detect a alsa audio device Jan 28 22:02:43 volumio mpd[1113]: output: Successfully detected a alsa audio device Jan 28 22:02:43 volumio mpd[1113]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:43 volumio mpd[1113]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:43 volumio mpd[1113]: zeroconf: No global port, disabling zeroconf Jan 28 22:02:43 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:43 volumio ntpd[981]: Soliciting pool server 192.168.211.1 Jan 28 22:02:44 volumio volumio-remote-updater[453]: [2022-01-28 22:02:44] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:02:44 volumio volumio-remote-updater[453]: [2022-01-28 22:02:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:02:44 volumio volumio-remote-updater[453]: [2022-01-28 22:02:44] [error] handle_connect error: Underlying Transport Error Jan 28 22:02:45 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:45 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 7. Jan 28 22:02:45 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:45 volumio dnsmasq[1186]: exiting on receipt of SIGTERM Jan 28 22:02:45 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:45 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:45 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:45 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:45 volumio hostapd[1226]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:45 volumio hostapd[1226]: Could not read interface wlan0 flags: No such device Jan 28 22:02:45 volumio hostapd[1226]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:45 volumio hostapd[1226]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:45 volumio hostapd[1226]: Could not read interface wlan0 flags: No such device Jan 28 22:02:45 volumio hostapd[1226]: nl80211 driver initialization failed. Jan 28 22:02:45 volumio hostapd[1226]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:45 volumio hostapd[1226]: wlan0: AP-DISABLED Jan 28 22:02:45 volumio hostapd[1226]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:45 volumio hostapd[1226]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:45 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:45 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:45 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:45 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:45 volumio dnsmasq[1233]: dnsmasq: syntax check OK. Jan 28 22:02:45 volumio dnsmasq[1239]: started, version 2.80 cachesize 150 Jan 28 22:02:45 volumio dnsmasq[1239]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:45 volumio dnsmasq[1239]: warning: interface wlan0 does not currently exist Jan 28 22:02:45 volumio dnsmasq-dhcp[1239]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:45 volumio dnsmasq[1239]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:45 volumio dnsmasq[1239]: read /etc/hosts - 5 addresses Jan 28 22:02:45 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:45 volumio volumio[1203]: info: ------------------------------------------- Jan 28 22:02:45 volumio volumio[1203]: info: ----- Volumio3 ---- Jan 28 22:02:45 volumio volumio[1203]: info: ------------------------------------------- Jan 28 22:02:45 volumio volumio[1203]: info: ----- System startup ---- Jan 28 22:02:45 volumio volumio[1203]: info: ------------------------------------------- Jan 28 22:02:46 volumio volumio[1203]: info: MYVOLUMIO Environment detected Jan 28 22:02:46 volumio volumio[1203]: info: Plugin folders cleanup Jan 28 22:02:46 volumio volumio[1203]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category audio_interface Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category miscellanea Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category music_service Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category plugins.json Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category system_controller Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category user_interface Jan 28 22:02:46 volumio volumio[1203]: info: Scanning into folder /data/plugins/ Jan 28 22:02:46 volumio volumio[1203]: info: Scanning category music_service Jan 28 22:02:46 volumio volumio[1203]: info: Plugin folders cleanup completed Jan 28 22:02:46 volumio volumio[1203]: info: ------------------------------------------- Jan 28 22:02:46 volumio volumio[1203]: info: ----- Core plugins startup ---- Jan 28 22:02:46 volumio volumio[1203]: info: ------------------------------------------- Jan 28 22:02:46 volumio volumio[1203]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:46 volumio volumio[1203]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:46 volumio volumio[1203]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:46 volumio volumio[1203]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:46 volumio volumio[1203]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:46 volumio volumio[1203]: info: Loading plugin "system"... Jan 28 22:02:46 volumio volumio[1203]: info: Loading plugin "appearance"... Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "network"... Jan 28 22:02:47 volumio volumio[1203]: info: Refreshing Cached IP Addresses Jan 28 22:02:47 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:47 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "services"... Jan 28 22:02:47 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:47 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "alsa_controller"... Jan 28 22:02:47 volumio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:47 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:47 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 8. Jan 28 22:02:47 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:47 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio dnsmasq[1239]: exiting on receipt of SIGTERM Jan 28 22:02:47 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:47 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:47 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:47 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "wizard"... Jan 28 22:02:47 volumio hostapd[1266]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "upnp"... Jan 28 22:02:47 volumio volumio[1203]: info: [1643407367635] Starting Upmpd Daemon Jan 28 22:02:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "my_music"... Jan 28 22:02:47 volumio volumio[1203]: info: Loading plugin "mpd"... Jan 28 22:02:47 volumio hostapd[1266]: Could not read interface wlan0 flags: No such device Jan 28 22:02:47 volumio hostapd[1266]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:47 volumio hostapd[1266]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:47 volumio hostapd[1266]: Could not read interface wlan0 flags: No such device Jan 28 22:02:47 volumio hostapd[1266]: nl80211 driver initialization failed. Jan 28 22:02:47 volumio hostapd[1266]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:47 volumio hostapd[1266]: wlan0: AP-DISABLED Jan 28 22:02:47 volumio hostapd[1266]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:47 volumio hostapd[1266]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:47 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:47 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:47 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:47 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:47 volumio dnsmasq[1273]: dnsmasq: syntax check OK. Jan 28 22:02:47 volumio dnsmasq[1279]: started, version 2.80 cachesize 150 Jan 28 22:02:47 volumio dnsmasq[1279]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:47 volumio dnsmasq[1279]: warning: interface wlan0 does not currently exist Jan 28 22:02:47 volumio dnsmasq-dhcp[1279]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:47 volumio dnsmasq[1279]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:47 volumio dnsmasq[1279]: read /etc/hosts - 5 addresses Jan 28 22:02:47 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:48 volumio volumio[1203]: info: Creating MPD Configuration file Jan 28 22:02:48 volumio volumio[1203]: info: Loading plugin "upnp_browser"... Jan 28 22:02:48 volumio sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:48 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:48 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:48 volumio sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:48 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:48 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:48 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:48 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:48 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:48 volumio volumio[1203]: info: Loading plugin "networkfs"... Jan 28 22:02:48 volumio volumio[1203]: info: Starting Udev Watcher for removable devices Jan 28 22:02:48 volumio volumio[1203]: info: Ignoring mount for partition: boot Jan 28 22:02:48 volumio volumio[1203]: info: Ignoring mount for partition: volumio Jan 28 22:02:48 volumio volumio[1203]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:48 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:48 volumio volumio[1203]: info: Loading plugin "alarm-clock"... Jan 28 22:02:49 volumio volumio[1203]: info: Loading plugin "airplay_emulation"... Jan 28 22:02:49 volumio volumio[1203]: info: Starting Shairport Sync Jan 28 22:02:49 volumio volumio[1203]: info: Loading plugin "last_100"... Jan 28 22:02:49 volumio volumio[1203]: info: Loading plugin "webradio"... Jan 28 22:02:49 volumio volumio[1203]: info: Loading plugin "i2s_dacs"... Jan 28 22:02:49 volumio volumio[1203]: info: I2S DAC not set, start Auto-detection Jan 28 22:02:49 volumio volumio[1203]: info: Loading plugin "volumiodiscovery"... Jan 28 22:02:49 volumio volumio[1203]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:49 volumio volumio[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio volumio[1203]: *** WARNING *** For more information see Jan 28 22:02:49 volumio volumio[1203]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:49 volumio volumio[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio volumio[1203]: *** WARNING *** For more information see Jan 28 22:02:49 volumio node[1203]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:49 volumio node[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio node[1203]: *** WARNING *** For more information see Jan 28 22:02:49 volumio node[1203]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:49 volumio node[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio node[1203]: *** WARNING *** For more information see Jan 28 22:02:49 volumio volumio[1203]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:02:49 volumio volumio[1203]: info: Discovery: Started advertising with name: Volumio Jan 28 22:02:49 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:49 volumio volumio[1203]: info: Loading plugin "squeezelite_mc"... Jan 28 22:02:49 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:49 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 9. Jan 28 22:02:49 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:49 volumio dnsmasq[1279]: exiting on receipt of SIGTERM Jan 28 22:02:49 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:49 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:49 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:49 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:49 volumio hostapd[1301]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:49 volumio hostapd[1301]: Could not read interface wlan0 flags: No such device Jan 28 22:02:49 volumio hostapd[1301]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:49 volumio hostapd[1301]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:49 volumio hostapd[1301]: Could not read interface wlan0 flags: No such device Jan 28 22:02:49 volumio hostapd[1301]: nl80211 driver initialization failed. Jan 28 22:02:49 volumio hostapd[1301]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:49 volumio hostapd[1301]: wlan0: AP-DISABLED Jan 28 22:02:49 volumio hostapd[1301]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:49 volumio hostapd[1301]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:49 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:49 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:49 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:49 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:50 volumio dnsmasq[1308]: dnsmasq: syntax check OK. Jan 28 22:02:50 volumio dnsmasq[1314]: started, version 2.80 cachesize 150 Jan 28 22:02:50 volumio dnsmasq[1314]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:50 volumio dnsmasq[1314]: warning: interface wlan0 does not currently exist Jan 28 22:02:50 volumio dnsmasq-dhcp[1314]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:50 volumio dnsmasq[1314]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:50 volumio dnsmasq[1314]: read /etc/hosts - 5 addresses Jan 28 22:02:50 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:50 volumio mpd[1293]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:50 volumio mpd[1293]: output: No 'audio_output' defined in config file Jan 28 22:02:50 volumio mpd[1293]: output: Attempt to detect audio output device Jan 28 22:02:50 volumio mpd[1293]: output: Attempting to detect a alsa audio device Jan 28 22:02:50 volumio mpd[1293]: output: Successfully detected a alsa audio device Jan 28 22:02:50 volumio mpd[1293]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:50 volumio mpd[1293]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:50 volumio mpd[1293]: zeroconf: No global port, disabling zeroconf Jan 28 22:02:50 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:50 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:50 volumio ifplugd(eth0)[585]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Jan 28 22:02:50 volumio ifplugd(eth0)[585]: client: sending commands to master dhcpcd process Jan 28 22:02:50 volumio dhcpcd[1330]: sending commands to master dhcpcd process Jan 28 22:02:50 volumio dhcpcd[909]: control command: /sbin/dhcpcd -k eth0 Jan 28 22:02:50 volumio dhcpcd[909]: eth0: removing interface Jan 28 22:02:50 volumio dnsmasq[1314]: reading /etc/resolv.conf Jan 28 22:02:50 volumio dnsmasq[1314]: using nameserver 208.67.222.222#53 Jan 28 22:02:50 volumio dnsmasq[1314]: using nameserver 208.67.220.220#53 Jan 28 22:02:50 volumio volumio[1203]: info: Loading plugin "outputs"... Jan 28 22:02:50 volumio volumio[1203]: info: Loading plugin "albumart"... Jan 28 22:02:50 volumio ifplugd(eth0)[585]: Program executed successfully. Jan 28 22:02:50 volumio volumio[1203]: info: Plugin example_plugin is not enabled Jan 28 22:02:50 volumio volumio[1203]: info: Loading plugin "inputs"... Jan 28 22:02:50 volumio volumio[1203]: info: Loading plugin "updater_comm"... Jan 28 22:02:50 volumio volumio[1203]: info: Plugin mpdemulation is not enabled Jan 28 22:02:50 volumio volumio[1203]: info: Loading plugin "rest_api"... Jan 28 22:02:50 volumio volumio[1203]: info: Loading plugin "websocket"... Jan 28 22:02:50 volumio volumio[1203]: info: Plugin RoonBridge is not enabled Jan 28 22:02:50 volumio volumio[1203]: info: Loading i18n strings for locale zh Jan 28 22:02:50 volumio volumio[1203]: Updating browse sources language Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::initPlayerControls Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: Express server listening on port 3000 Jan 28 22:02:50 volumio volumio[1203]: [Metrics] WebUI: 5s 827.93ms Jan 28 22:02:50 volumio volumio[1203]: info: CoreStateMachine::resetVolumioState Jan 28 22:02:50 volumio volumio[1203]: info: CoreStateMachine::getcurrentVolume Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:50 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:50 volumio volumio[1203]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:02:50 volumio volumio[1203]: info: Completed loading Core Plugins Jan 28 22:02:50 volumio volumio[1203]: info: Preparing to generate the ALSA configuration file Jan 28 22:02:50 volumio volumio[1203]: info: Cannot read play queue from file Jan 28 22:02:50 volumio volumio[1203]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:02:50 volumio mpd[1293]: client: [0] opened from local Jan 28 22:02:50 volumio volumio[1203]: info: MPD Permissions set Jan 28 22:02:50 volumio volumio[1203]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:02:50 volumio volumio[1203]: wlan0 Interface doesn't support scanning. Jan 28 22:02:50 volumio volumio[1203]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:02:50 volumio volumio[1203]: Forking 3 albumart workers Jan 28 22:02:50 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:02:50 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:51 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:51 volumio volumio[1203]: command failed: No such device (-19) Jan 28 22:02:51 volumio volumio[1203]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:02:51 volumio volumio[1203]: command failed: No such device (-19) Jan 28 22:02:51 volumio volumio[1203]: info: MPD running with PID1293 Jan 28 22:02:51 volumio volumio[1203]: ,establishing connection Jan 28 22:02:51 volumio mpd[1293]: client: [1] opened from local Jan 28 22:02:51 volumio volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:02:51 volumio volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:51 volumio volumio[1203]: assert.ok(self.idling) Jan 28 22:02:51 volumio volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:51 volumio volumio[1203]: assert.ok(self.idling) Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio mpd[1293]: exception: No database Jan 28 22:02:51 volumio volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:02:51 volumio volumio[1203]: info: CoreStateMachine::pushState Jan 28 22:02:51 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:51 volumio volumio[1203]: info: CoreStateMachine::updateTrackBlock Jan 28 22:02:51 volumio volumio[1203]: info: CorePlayQueue::getTrackBlock Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:51 volumio volumio[1203]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:02:51 volumio volumio[1203]: info: Output device has changed, restarting MPD Jan 28 22:02:51 volumio volumio[1203]: info: Output device has changed, restarting Shairport Sync Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:51 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:51 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:51 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:51 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:51 volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:51 volumio volumio[1203]: info: ___________ START PLUGINS ___________ Jan 28 22:02:51 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:51 volumio volumio[1203]: info: [1643407371580] CoreMusicLibrary::Adding element Media Servers Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:51 volumio volumio[1203]: Cannot find translation for sourceMedia Servers Jan 28 22:02:51 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:51 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:51 volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:51 volumio volumio[1203]: info: [1643407371906] CoreMusicLibrary::Adding element Last_100 Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:51 volumio volumio[1203]: Cannot find translation for sourceMedia Servers Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:51 volumio volumio[1203]: info: [1643407371913] CoreMusicLibrary::Adding element Webradio Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:51 volumio volumio[1203]: Cannot find translation for sourceMedia Servers Jan 28 22:02:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:02:51 volumio volumio[1203]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:02:51 volumio volumio[1203]: info: Volumio Calling Home Jan 28 22:02:52 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:52 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 10. Jan 28 22:02:52 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:52 volumio volumio[1203]: info: [squeezelite_mc] Proxy server started on port 42686 Jan 28 22:02:52 volumio dnsmasq[1314]: exiting on receipt of SIGTERM Jan 28 22:02:52 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:52 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:52 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:52 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:52 volumio hostapd[1426]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:52 volumio volumio[1203]: info: CoreStateMachine::setRepeat null single undefined Jan 28 22:02:52 volumio volumio[1203]: info: CoreStateMachine::pushState Jan 28 22:02:52 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:52 volumio volumio[1203]: info: CoreStateMachine::setRandom null Jan 28 22:02:52 volumio volumio[1203]: info: CoreStateMachine::pushState Jan 28 22:02:52 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:52 volumio volumio[1203]: error: updateQueue error: null Jan 28 22:02:52 volumio volumio[1203]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 28 22:02:52 volumio volumio[1203]: Starting albumart workers Jan 28 22:02:52 volumio hostapd[1426]: Could not read interface wlan0 flags: No such device Jan 28 22:02:52 volumio hostapd[1426]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:52 volumio hostapd[1426]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:52 volumio volumio[1203]: Starting albumart workers Jan 28 22:02:52 volumio hostapd[1426]: Could not read interface wlan0 flags: No such device Jan 28 22:02:52 volumio hostapd[1426]: nl80211 driver initialization failed. Jan 28 22:02:52 volumio hostapd[1426]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:52 volumio hostapd[1426]: wlan0: AP-DISABLED Jan 28 22:02:52 volumio hostapd[1426]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:52 volumio hostapd[1426]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:52 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:52 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:52 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:52 volumio volumio[1203]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 22:02:52 volumio volumio[1203]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 22:02:52 volumio volumio[1203]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 22:02:52 volumio volumio[1203]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 22:02:52 volumio volumio[1203]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 22:02:52 volumio volumio[1203]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 22:02:52 volumio volumio[1203]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 22:02:52 volumio volumio[1203]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 22:02:52 volumio volumio[1203]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 22:02:52 volumio volumio[1203]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 22:02:52 volumio volumio[1203]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 22:02:52 volumio volumio[1203]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 22:02:52 volumio volumio[1203]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 22:02:52 volumio volumio[1203]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 22:02:52 volumio volumio[1203]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 22:02:52 volumio volumio[1203]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 22:02:52 volumio volumio[1203]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 22:02:52 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:52 volumio dnsmasq[1434]: dnsmasq: syntax check OK. Jan 28 22:02:52 volumio volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:02:52 volumio volumio[1203]: info: MPD Permissions set Jan 28 22:02:52 volumio volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:02:52 volumio dnsmasq[1442]: started, version 2.80 cachesize 150 Jan 28 22:02:52 volumio dnsmasq[1442]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:52 volumio dnsmasq[1442]: warning: interface wlan0 does not currently exist Jan 28 22:02:52 volumio dnsmasq-dhcp[1442]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:52 volumio dnsmasq[1442]: reading /etc/resolv.conf Jan 28 22:02:52 volumio dnsmasq[1442]: using nameserver 208.67.222.222#53 Jan 28 22:02:52 volumio dnsmasq[1442]: using nameserver 208.67.220.220#53 Jan 28 22:02:52 volumio dnsmasq[1442]: read /etc/hosts - 5 addresses Jan 28 22:02:52 volumio volumio[1203]: Starting albumart workers Jan 28 22:02:52 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:52 volumio volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:02:52 volumio volumio[1203]: info: CoreStateMachine::pushState Jan 28 22:02:53 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:53 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:53 volumio volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:02:53 volumio volumio[1203]: info: CoreStateMachine::pushState Jan 28 22:02:53 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:53 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:53 volumio volumio[1203]: info: [squeezelite_mc] Server discovery started Jan 28 22:02:53 volumio volumio[1203]: info: [squeezelite_mc] Player finder started Jan 28 22:02:53 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:53 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:53 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:53 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:53 volumio volumio[1203]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:02:53 volumio volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:53 volumio volumio[1203]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:02:53 volumio volumio[1203]: at doSend (dgram.js:714:16) Jan 28 22:02:53 volumio volumio[1203]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:02:53 volumio volumio[1203]: at afterDns (dgram.js:660:5) Jan 28 22:02:53 volumio volumio[1203]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:02:53 volumio volumio[1203]: errno: -101, Jan 28 22:02:53 volumio volumio[1203]: code: 'ENETUNREACH', Jan 28 22:02:53 volumio volumio[1203]: syscall: 'send', Jan 28 22:02:53 volumio volumio[1203]: address: '255.255.255.255', Jan 28 22:02:53 volumio volumio[1203]: port: 3483 Jan 28 22:02:53 volumio volumio[1203]: } Jan 28 22:02:53 volumio volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:54 volumio sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:01 Jan 28 22:02:54 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:54 volumio volumio-remote-updater[453]: [2022-01-28 22:02:54] [connect] Successful connection Jan 28 22:02:54 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:54 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:02:54 volumio volumio-remote-updater[453]: [2022-01-28 22:02:54] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jan 28 22:02:54 volumio volumio-remote-updater[453]: [2022-01-28 22:02:54] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Jan 28 22:02:54 volumio volumio-remote-updater[453]: [2022-01-28 22:02:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:02:54 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:54 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 22:02:54 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:54 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:54 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 11. Jan 28 22:02:54 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:54 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:54 volumio dnsmasq[1442]: exiting on receipt of SIGTERM Jan 28 22:02:54 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:54 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:54 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:54 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:54 volumio hostapd[1507]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:54 volumio dhcpcd[982]: eth0: carrier acquired Jan 28 22:02:54 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Jan 28 22:02:54 volumio dhcpcd[982]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:54 volumio ifplugd(eth0)[585]: Link beat detected. Jan 28 22:02:54 volumio hostapd[1507]: Could not read interface wlan0 flags: No such device Jan 28 22:02:54 volumio hostapd[1507]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:54 volumio hostapd[1507]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:54 volumio hostapd[1507]: Could not read interface wlan0 flags: No such device Jan 28 22:02:54 volumio hostapd[1507]: nl80211 driver initialization failed. Jan 28 22:02:54 volumio hostapd[1507]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:54 volumio hostapd[1507]: wlan0: AP-DISABLED Jan 28 22:02:54 volumio hostapd[1507]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:54 volumio hostapd[1507]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:54 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:54 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:54 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:54 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 28 22:02:54 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jan 28 22:02:54 volumio systemd[1]: Stopped Volumio Backend Module. Jan 28 22:02:54 volumio systemd[1]: Started Volumio Backend Module. Jan 28 22:02:54 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:54 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:54 volumio mpd[1425]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:54 volumio mpd[1425]: output: No 'audio_output' defined in config file Jan 28 22:02:54 volumio mpd[1425]: output: Attempt to detect audio output device Jan 28 22:02:54 volumio mpd[1425]: output: Attempting to detect a alsa audio device Jan 28 22:02:54 volumio mpd[1425]: output: Successfully detected a alsa audio device Jan 28 22:02:54 volumio mpd[1425]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:54 volumio mpd[1425]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:54 volumio dnsmasq[1517]: dnsmasq: syntax check OK. Jan 28 22:02:54 volumio mpd[1425]: zeroconf: No global port, disabling zeroconf Jan 28 22:02:54 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:54 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:54 volumio ifplugd(eth0)[585]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 28 22:02:54 volumio dhcpcd[982]: eth0: rebinding lease of 192.168.3.4 Jan 28 22:02:54 volumio dnsmasq[1548]: started, version 2.80 cachesize 150 Jan 28 22:02:54 volumio dnsmasq[1548]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:54 volumio dnsmasq[1548]: warning: interface wlan0 does not currently exist Jan 28 22:02:54 volumio dnsmasq-dhcp[1548]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:54 volumio dnsmasq[1548]: reading /etc/resolv.conf Jan 28 22:02:54 volumio dnsmasq[1548]: using nameserver 208.67.222.222#53 Jan 28 22:02:54 volumio dnsmasq[1548]: using nameserver 208.67.220.220#53 Jan 28 22:02:54 volumio dnsmasq[1548]: read /etc/hosts - 5 addresses Jan 28 22:02:54 volumio ifplugd(eth0)[585]: client: command failed: No such device (-19) Jan 28 22:02:54 volumio ifplugd(eth0)[585]: client: sending commands to master dhcpcd process Jan 28 22:02:54 volumio dhcpcd[1561]: sending commands to master dhcpcd process Jan 28 22:02:54 volumio dhcpcd[909]: control command: /sbin/dhcpcd eth0 Jan 28 22:02:54 volumio dhcpcd[909]: eth0: carrier acquired Jan 28 22:02:54 volumio dhcpcd[909]: eth0: IAID 82:bf:d9:37 Jan 28 22:02:54 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:54 volumio ifplugd(eth0)[585]: Program executed successfully. Jan 28 22:02:54 volumio dhcpcd[982]: eth0: soliciting an IPv6 router Jan 28 22:02:55 volumio dhcpcd[909]: eth0: soliciting an IPv6 router Jan 28 22:02:55 volumio dhcpcd[909]: eth0: rebinding lease of 192.168.3.4 Jan 28 22:02:55 volumio dhcpcd[909]: eth0: NAK: wrong address from 192.168.1.1 Jan 28 22:02:55 volumio dhcpcd[909]: eth0: message: wrong address Jan 28 22:02:55 volumio dhcpcd[909]: eth0: soliciting a DHCP lease Jan 28 22:02:55 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 28 22:02:56 volumio volumio[1515]: info: ------------------------------------------- Jan 28 22:02:56 volumio volumio[1515]: info: ----- Volumio3 ---- Jan 28 22:02:56 volumio volumio[1515]: info: ------------------------------------------- Jan 28 22:02:56 volumio volumio[1515]: info: ----- System startup ---- Jan 28 22:02:56 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:56 volumio volumio[1515]: info: ------------------------------------------- Jan 28 22:02:56 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 12. Jan 28 22:02:56 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:56 volumio dnsmasq[1548]: exiting on receipt of SIGTERM Jan 28 22:02:56 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:56 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:56 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:56 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:56 volumio hostapd[1610]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:56 volumio hostapd[1610]: Could not read interface wlan0 flags: No such device Jan 28 22:02:56 volumio hostapd[1610]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:56 volumio hostapd[1610]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:56 volumio hostapd[1610]: Could not read interface wlan0 flags: No such device Jan 28 22:02:56 volumio hostapd[1610]: nl80211 driver initialization failed. Jan 28 22:02:56 volumio hostapd[1610]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:56 volumio hostapd[1610]: wlan0: AP-DISABLED Jan 28 22:02:56 volumio hostapd[1610]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:56 volumio hostapd[1610]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:56 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:56 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:56 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:56 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:56 volumio dnsmasq[1617]: dnsmasq: syntax check OK. Jan 28 22:02:56 volumio dnsmasq[1623]: started, version 2.80 cachesize 150 Jan 28 22:02:56 volumio dnsmasq[1623]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:56 volumio dnsmasq[1623]: warning: interface wlan0 does not currently exist Jan 28 22:02:56 volumio dnsmasq-dhcp[1623]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:56 volumio dnsmasq[1623]: reading /etc/resolv.conf Jan 28 22:02:56 volumio dnsmasq[1623]: using nameserver 208.67.222.222#53 Jan 28 22:02:56 volumio dnsmasq[1623]: using nameserver 208.67.220.220#53 Jan 28 22:02:56 volumio dnsmasq[1623]: read /etc/hosts - 5 addresses Jan 28 22:02:56 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:57 volumio volumio[1515]: info: MYVOLUMIO Environment detected Jan 28 22:02:57 volumio volumio[1515]: info: Plugin folders cleanup Jan 28 22:02:57 volumio volumio[1515]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category audio_interface Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category miscellanea Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category music_service Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category plugins.json Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category system_controller Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category user_interface Jan 28 22:02:57 volumio volumio[1515]: info: Scanning into folder /data/plugins/ Jan 28 22:02:57 volumio volumio[1515]: info: Scanning category music_service Jan 28 22:02:57 volumio volumio[1515]: info: Plugin folders cleanup completed Jan 28 22:02:57 volumio volumio[1515]: info: ------------------------------------------- Jan 28 22:02:57 volumio volumio[1515]: info: ----- Core plugins startup ---- Jan 28 22:02:57 volumio volumio[1515]: info: ------------------------------------------- Jan 28 22:02:57 volumio volumio[1515]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:57 volumio volumio[1515]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:57 volumio volumio[1515]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:57 volumio volumio[1515]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:57 volumio volumio[1515]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:57 volumio volumio[1515]: info: Loading plugin "system"... Jan 28 22:02:57 volumio volumio[1515]: info: Loading plugin "appearance"... Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "network"... Jan 28 22:02:58 volumio volumio[1515]: info: Refreshing Cached IP Addresses Jan 28 22:02:58 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:58 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "services"... Jan 28 22:02:58 volumio sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:58 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "alsa_controller"... Jan 28 22:02:58 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:58 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:58 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "wizard"... Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "upnp"... Jan 28 22:02:58 volumio volumio[1515]: info: [1643407378634] Starting Upmpd Daemon Jan 28 22:02:58 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "my_music"... Jan 28 22:02:58 volumio volumio[1515]: info: Loading plugin "mpd"... Jan 28 22:02:58 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:58 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 13. Jan 28 22:02:58 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:58 volumio dnsmasq[1623]: exiting on receipt of SIGTERM Jan 28 22:02:58 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:58 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:58 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:58 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:58 volumio hostapd[1650]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:58 volumio hostapd[1650]: Could not read interface wlan0 flags: No such device Jan 28 22:02:58 volumio hostapd[1650]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:58 volumio hostapd[1650]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:58 volumio hostapd[1650]: Could not read interface wlan0 flags: No such device Jan 28 22:02:58 volumio hostapd[1650]: nl80211 driver initialization failed. Jan 28 22:02:58 volumio hostapd[1650]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:58 volumio hostapd[1650]: wlan0: AP-DISABLED Jan 28 22:02:58 volumio hostapd[1650]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:58 volumio hostapd[1650]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:58 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:58 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:58 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:58 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:58 volumio dnsmasq[1657]: dnsmasq: syntax check OK. Jan 28 22:02:58 volumio dnsmasq[1663]: started, version 2.80 cachesize 150 Jan 28 22:02:58 volumio dnsmasq[1663]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:58 volumio dnsmasq[1663]: warning: interface wlan0 does not currently exist Jan 28 22:02:58 volumio dnsmasq-dhcp[1663]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:58 volumio dnsmasq[1663]: reading /etc/resolv.conf Jan 28 22:02:58 volumio dnsmasq[1663]: using nameserver 208.67.222.222#53 Jan 28 22:02:58 volumio dnsmasq[1663]: using nameserver 208.67.220.220#53 Jan 28 22:02:58 volumio dnsmasq[1663]: read /etc/hosts - 5 addresses Jan 28 22:02:59 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:59 volumio volumio[1515]: info: Creating MPD Configuration file Jan 28 22:02:59 volumio volumio[1515]: info: Loading plugin "upnp_browser"... Jan 28 22:02:59 volumio sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:59 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:59 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:59 volumio sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:59 volumio sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:59 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:59 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:59 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:59 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:59 volumio volumio[1515]: info: Loading plugin "networkfs"... Jan 28 22:02:59 volumio dhcpcd[982]: eth0: probing for an IPv4LL address Jan 28 22:02:59 volumio volumio[1515]: info: Starting Udev Watcher for removable devices Jan 28 22:02:59 volumio dhcpcd[982]: eth0: DHCP lease expired Jan 28 22:02:59 volumio dhcpcd[982]: eth0: soliciting a DHCP lease Jan 28 22:02:59 volumio volumio[1515]: info: Ignoring mount for partition: boot Jan 28 22:02:59 volumio volumio[1515]: info: Ignoring mount for partition: volumio Jan 28 22:02:59 volumio volumio[1515]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:59 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:59 volumio volumio[1515]: info: Loading plugin "alarm-clock"... Jan 28 22:03:00 volumio volumio[1515]: info: Loading plugin "airplay_emulation"... Jan 28 22:03:00 volumio volumio[1515]: info: Starting Shairport Sync Jan 28 22:03:00 volumio volumio[1515]: info: Loading plugin "last_100"... Jan 28 22:03:00 volumio volumio[1515]: info: Loading plugin "webradio"... Jan 28 22:03:00 volumio volumio[1515]: info: Loading plugin "i2s_dacs"... Jan 28 22:03:00 volumio volumio[1515]: info: I2S DAC not set, start Auto-detection Jan 28 22:03:00 volumio volumio[1515]: info: Loading plugin "volumiodiscovery"... Jan 28 22:03:00 volumio volumio[1515]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:03:00 volumio volumio[1515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:03:00 volumio volumio[1515]: *** WARNING *** For more information see Jan 28 22:03:00 volumio volumio[1515]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:03:00 volumio node[1515]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:03:00 volumio volumio[1515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:03:00 volumio volumio[1515]: *** WARNING *** For more information see Jan 28 22:03:00 volumio node[1515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:03:00 volumio node[1515]: *** WARNING *** For more information see Jan 28 22:03:00 volumio node[1515]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:03:00 volumio node[1515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:03:00 volumio node[1515]: *** WARNING *** For more information see Jan 28 22:03:00 volumio volumio[1515]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:03:00 volumio volumio[1515]: info: Discovery: Started advertising with name: Volumio Jan 28 22:03:00 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:03:00 volumio volumio[1515]: info: Loading plugin "squeezelite_mc"... Jan 28 22:03:00 volumio dhcpcd[909]: eth0: probing for an IPv4LL address Jan 28 22:03:01 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:03:01 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 14. Jan 28 22:03:01 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:01 volumio dnsmasq[1663]: exiting on receipt of SIGTERM Jan 28 22:03:01 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:03:01 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:01 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:01 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:03:01 volumio hostapd[1692]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:03:01 volumio hostapd[1692]: Could not read interface wlan0 flags: No such device Jan 28 22:03:01 volumio hostapd[1692]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:03:01 volumio hostapd[1692]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:03:01 volumio hostapd[1692]: Could not read interface wlan0 flags: No such device Jan 28 22:03:01 volumio hostapd[1692]: nl80211 driver initialization failed. Jan 28 22:03:01 volumio hostapd[1692]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:03:01 volumio hostapd[1692]: wlan0: AP-DISABLED Jan 28 22:03:01 volumio hostapd[1692]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:03:01 volumio hostapd[1692]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:03:01 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:03:01 volumio mpd[1677]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:03:01 volumio mpd[1677]: output: No 'audio_output' defined in config file Jan 28 22:03:01 volumio mpd[1677]: output: Attempt to detect audio output device Jan 28 22:03:01 volumio mpd[1677]: output: Attempting to detect a alsa audio device Jan 28 22:03:01 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:03:01 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:01 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:01 volumio mpd[1677]: output: Successfully detected a alsa audio device Jan 28 22:03:01 volumio mpd[1677]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:03:01 volumio mpd[1677]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:03:01 volumio mpd[1677]: zeroconf: No global port, disabling zeroconf Jan 28 22:03:01 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:03:01 volumio sudo[1672]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:01 volumio dnsmasq[1699]: dnsmasq: syntax check OK. Jan 28 22:03:01 volumio dnsmasq[1707]: started, version 2.80 cachesize 150 Jan 28 22:03:01 volumio dnsmasq[1707]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:03:01 volumio dnsmasq[1707]: warning: interface wlan0 does not currently exist Jan 28 22:03:01 volumio dnsmasq-dhcp[1707]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:03:01 volumio dnsmasq[1707]: reading /etc/resolv.conf Jan 28 22:03:01 volumio dnsmasq[1707]: using nameserver 208.67.222.222#53 Jan 28 22:03:01 volumio dnsmasq[1707]: using nameserver 208.67.220.220#53 Jan 28 22:03:01 volumio dnsmasq[1707]: read /etc/hosts - 5 addresses Jan 28 22:03:01 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:01 volumio volumio[1515]: info: Loading plugin "outputs"... Jan 28 22:03:01 volumio volumio[1515]: info: Loading plugin "albumart"... Jan 28 22:03:01 volumio volumio[1515]: info: Plugin example_plugin is not enabled Jan 28 22:03:01 volumio volumio[1515]: info: Loading plugin "inputs"... Jan 28 22:03:01 volumio volumio[1515]: info: Loading plugin "updater_comm"... Jan 28 22:03:01 volumio volumio[1515]: info: Plugin mpdemulation is not enabled Jan 28 22:03:01 volumio volumio[1515]: info: Loading plugin "rest_api"... Jan 28 22:03:01 volumio volumio[1515]: info: Loading plugin "websocket"... Jan 28 22:03:01 volumio volumio[1515]: info: Plugin RoonBridge is not enabled Jan 28 22:03:01 volumio volumio[1515]: info: Loading i18n strings for locale zh Jan 28 22:03:01 volumio volumio[1515]: Updating browse sources language Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::initPlayerControls Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: Express server listening on port 3000 Jan 28 22:03:01 volumio volumio[1515]: [Metrics] WebUI: 5s 809.23ms Jan 28 22:03:01 volumio volumio[1515]: info: CoreStateMachine::resetVolumioState Jan 28 22:03:01 volumio volumio[1515]: info: CoreStateMachine::getcurrentVolume Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:03:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:01 volumio volumio[1515]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:03:01 volumio volumio[1515]: info: Completed loading Core Plugins Jan 28 22:03:01 volumio volumio[1515]: info: Preparing to generate the ALSA configuration file Jan 28 22:03:01 volumio volumio[1515]: info: Cannot read play queue from file Jan 28 22:03:01 volumio volumio[1515]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:03:01 volumio mpd[1677]: client: [0] opened from local Jan 28 22:03:01 volumio volumio[1515]: Forking 3 albumart workers Jan 28 22:03:01 volumio volumio[1515]: info: MPD Permissions set Jan 28 22:03:01 volumio volumio[1515]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:03:01 volumio volumio[1515]: wlan0 Interface doesn't support scanning. Jan 28 22:03:01 volumio volumio[1515]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:03:01 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:03:01 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:02 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:02 volumio volumio[1515]: command failed: No such device (-19) Jan 28 22:03:02 volumio volumio[1515]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:03:02 volumio volumio[1515]: command failed: No such device (-19) Jan 28 22:03:02 volumio volumio[1515]: info: MPD running with PID1677 Jan 28 22:03:02 volumio volumio[1515]: ,establishing connection Jan 28 22:03:02 volumio mpd[1677]: client: [1] opened from local Jan 28 22:03:02 volumio volumio[1515]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:03:02 volumio volumio[1515]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:03:02 volumio volumio[1515]: assert.ok(self.idling) Jan 28 22:03:02 volumio volumio[1515]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:03:02 volumio volumio[1515]: assert.ok(self.idling) Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio mpd[1677]: exception: No database Jan 28 22:03:02 volumio volumio[1515]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:03:02 volumio volumio[1515]: info: CoreStateMachine::pushState Jan 28 22:03:02 volumio volumio[1515]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:02 volumio volumio[1515]: info: CoreStateMachine::updateTrackBlock Jan 28 22:03:02 volumio volumio[1515]: info: CorePlayQueue::getTrackBlock Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:03:02 volumio volumio[1515]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:03:02 volumio volumio[1515]: info: Output device has changed, restarting MPD Jan 28 22:03:02 volumio volumio[1515]: info: Output device has changed, restarting Shairport Sync Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:02 volumio sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:03:02 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:03:02 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:02 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:02 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:02 volumio volumio[1515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:03:02 volumio volumio[1515]: info: ___________ START PLUGINS ___________ Jan 28 22:03:02 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:03:02 volumio volumio[1515]: info: [1643407382801] CoreMusicLibrary::Adding element Media Servers Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:02 volumio volumio[1515]: Cannot find translation for sourceMedia Servers Jan 28 22:03:02 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:03:02 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:03:02 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:03 volumio volumio[1515]: Starting albumart workers Jan 28 22:03:03 volumio volumio[1515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:03:03 volumio volumio[1515]: info: [1643407383087] CoreMusicLibrary::Adding element Last_100 Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:03 volumio volumio[1515]: Cannot find translation for sourceMedia Servers Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:03:03 volumio volumio[1515]: info: [1643407383094] CoreMusicLibrary::Adding element Webradio Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:03 volumio volumio[1515]: Cannot find translation for sourceMedia Servers Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:03:03 volumio volumio[1515]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:03:03 volumio volumio[1515]: info: Volumio Calling Home Jan 28 22:03:03 volumio volumio[1515]: Starting albumart workers Jan 28 22:03:03 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:03:03 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 15. Jan 28 22:03:03 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:03 volumio volumio[1515]: info: [squeezelite_mc] Proxy server started on port 46496 Jan 28 22:03:03 volumio dnsmasq[1707]: exiting on receipt of SIGTERM Jan 28 22:03:03 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:03:03 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:03 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:03 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:03:03 volumio volumio[1515]: Starting albumart workers Jan 28 22:03:03 volumio hostapd[1789]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:03:03 volumio hostapd[1789]: Could not read interface wlan0 flags: No such device Jan 28 22:03:03 volumio hostapd[1789]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:03:03 volumio hostapd[1789]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:03:03 volumio hostapd[1789]: Could not read interface wlan0 flags: No such device Jan 28 22:03:03 volumio hostapd[1789]: nl80211 driver initialization failed. Jan 28 22:03:03 volumio hostapd[1789]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:03:03 volumio hostapd[1789]: wlan0: AP-DISABLED Jan 28 22:03:03 volumio hostapd[1789]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:03:03 volumio hostapd[1789]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:03:03 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:03:03 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:03:03 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:03:03 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:03 volumio dnsmasq[1798]: dnsmasq: syntax check OK. Jan 28 22:03:03 volumio volumio[1515]: info: CoreStateMachine::setRepeat null single undefined Jan 28 22:03:03 volumio volumio[1515]: info: CoreStateMachine::pushState Jan 28 22:03:03 volumio volumio[1515]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:03 volumio volumio[1515]: info: CoreStateMachine::setRandom null Jan 28 22:03:03 volumio volumio[1515]: info: CoreStateMachine::pushState Jan 28 22:03:03 volumio volumio[1515]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:03 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:03 volumio volumio[1515]: error: updateQueue error: null Jan 28 22:03:03 volumio volumio[1515]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 28 22:03:03 volumio volumio[1515]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 22:03:03 volumio volumio[1515]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 22:03:03 volumio volumio[1515]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 22:03:03 volumio volumio[1515]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 22:03:03 volumio volumio[1515]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 22:03:03 volumio volumio[1515]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 22:03:03 volumio volumio[1515]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 22:03:03 volumio volumio[1515]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 22:03:03 volumio volumio[1515]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 22:03:03 volumio volumio[1515]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 22:03:03 volumio volumio[1515]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 22:03:03 volumio volumio[1515]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 22:03:03 volumio volumio[1515]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 22:03:03 volumio volumio[1515]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 22:03:03 volumio volumio[1515]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 22:03:03 volumio volumio[1515]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 22:03:03 volumio volumio[1515]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 22:03:03 volumio dnsmasq[1804]: started, version 2.80 cachesize 150 Jan 28 22:03:03 volumio dnsmasq[1804]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:03:03 volumio dnsmasq[1804]: warning: interface wlan0 does not currently exist Jan 28 22:03:03 volumio dnsmasq-dhcp[1804]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:03:03 volumio dnsmasq[1804]: reading /etc/resolv.conf Jan 28 22:03:03 volumio dnsmasq[1804]: using nameserver 208.67.222.222#53 Jan 28 22:03:03 volumio dnsmasq[1804]: using nameserver 208.67.220.220#53 Jan 28 22:03:03 volumio dnsmasq[1804]: read /etc/hosts - 5 addresses Jan 28 22:03:03 volumio volumio[1515]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:03:03 volumio volumio[1515]: info: MPD Permissions set Jan 28 22:03:03 volumio volumio[1515]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 28 22:03:03 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:04 volumio volumio[1515]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jan 28 22:03:04 volumio volumio[1515]: info: VolumeController:: Volume=undefined Mute =false Jan 28 22:03:04 volumio volumio[1515]: info: CoreStateMachine::pushState Jan 28 22:03:04 volumio volumio[1515]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:04 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:04 volumio volumio[1515]: info: [squeezelite_mc] Server discovery started Jan 28 22:03:04 volumio volumio[1515]: info: [squeezelite_mc] Player finder started Jan 28 22:03:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:04 volumio volumio[1515]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:03:04 volumio volumio[1515]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:03:04 volumio volumio[1515]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:03:04 volumio volumio[1515]: at doSend (dgram.js:714:16) Jan 28 22:03:04 volumio volumio[1515]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:03:04 volumio volumio[1515]: at afterDns (dgram.js:660:5) Jan 28 22:03:04 volumio volumio[1515]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:03:04 volumio volumio[1515]: errno: -101, Jan 28 22:03:04 volumio volumio[1515]: code: 'ENETUNREACH', Jan 28 22:03:04 volumio volumio[1515]: syscall: 'send', Jan 28 22:03:04 volumio volumio[1515]: address: '255.255.255.255', Jan 28 22:03:04 volumio volumio[1515]: port: 3483 Jan 28 22:03:04 volumio volumio[1515]: } Jan 28 22:03:04 volumio volumio[1515]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:03:04 volumio volumio-remote-updater[453]: [2022-01-28 22:03:04] [connect] Successful connection Jan 28 22:03:04 volumio dhcpcd[982]: eth0: using IPv4LL address 169.254.65.213 Jan 28 22:03:04 volumio dhcpcd[982]: eth0: adding route to 169.254.0.0/16 Jan 28 22:03:04 volumio dhcpcd[982]: eth0: adding default route Jan 28 22:03:04 volumio avahi-daemon[468]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.65.213. Jan 28 22:03:04 volumio avahi-daemon[468]: New relevant interface eth0.IPv4 for mDNS. Jan 28 22:03:04 volumio avahi-daemon[468]: Registering new address record for 169.254.65.213 on eth0.IPv4. Jan 28 22:03:04 volumio dhcpcd[982]: eth0: offered 192.168.1.133 from 192.168.1.1 Jan 28 22:03:05 volumio dhcpcd[982]: eth0: probing address 192.168.1.133/24 Jan 28 22:03:05 volumio sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:02 Jan 28 22:03:05 volumio sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="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"