-- Logs begin at Fri 2022-01-28 22:02:24 UTC, end at Fri 2022-01-28 22:03:04 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() 303 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() 563 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 : 4a90b2a11166060b 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.000: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: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 5 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: 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: 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: 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: 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: 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: 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: mmc_host mmc0: Bus speed (slot 0) = 200000000Hz (slot req 200000000Hz, actual 200000000HZ div = 0) 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 4-1: new high-speed USB device number 2 using xhci-hcd 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: usb 1-1: New USB device found, idVendor=16d0, idProduct=0a23 Jan 28 22:02:24 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 28 22:02:24 volumio kernel: usb 1-1: Product: Quloos QU02 Jan 28 22:02:24 volumio kernel: usb 1-1: Manufacturer: Quloos Jan 28 22:02:24 volumio kernel: usb 1-1: SerialNumber: 414-001 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: Successfully tuned phase to 235 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: 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: 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: 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: Key type encrypted registered Jan 28 22:02:24 volumio kernel: pvtm list NULL Jan 28 22:02:24 volumio kernel: hub 5-1:1.0: 4 ports detected 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: 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: 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: #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: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Jan 28 22:02:24 volumio kernel: usb 1-1: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Jan 28 22:02:24 volumio kernel: usb 1-1: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Jan 28 22:02:24 volumio kernel: usb 1-1: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 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, 59 bits of entropy available) Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 59 bits of entropy available) Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 59 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, 59 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, 60 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Created slice User and Session Slice. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 60 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, 60 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, 60 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Reached target Slices. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 60 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Audit Socket. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 60 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Jan 28 22:02:24 volumio systemd[1]: Listening on udev Kernel Socket. Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Socket (/dev/log). Jan 28 22:02:24 volumio systemd[1]: Reached target System Time Synchronized. Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Socket. Jan 28 22:02:24 volumio systemd[1]: Mounting POSIX Message Queue File System... Jan 28 22:02:24 volumio systemd[1]: Mounting Huge Pages File System... Jan 28 22:02:24 volumio systemd[1]: Starting Restore / save the current clock... Jan 28 22:02:24 volumio systemd[1]: Mounting Kernel Debug File System... Jan 28 22:02:24 volumio systemd[1]: Mounting RPC Pipe 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]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 28 22:02:24 volumio systemd[1]: Starting Load Kernel Modules... Jan 28 22:02:24 volumio systemd[1]: Listening on udev Control Socket. Jan 28 22:02:24 volumio systemd[1]: Starting udev Coldplug all Devices... 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]: Listening on fsck to fsckd communication Socket. Jan 28 22:02:24 volumio systemd[1]: Listening on RPCbind Server Activation Socket. Jan 28 22:02:24 volumio systemd[1]: Starting Journal Service... Jan 28 22:02:24 volumio systemd[1]: Started dynamicswap service. 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]: Starting Remount Root and Kernel File Systems... Jan 28 22:02:24 volumio systemd[1]: Mounted POSIX Message Queue File System. Jan 28 22:02:24 volumio systemd[1]: Mounted Huge Pages File System. Jan 28 22:02:24 volumio systemd[1]: Started Restore / save the current clock. Jan 28 22:02:24 volumio systemd[1]: Mounted Kernel Debug File System. Jan 28 22:02:24 volumio systemd[1]: Mounted RPC Pipe File System. Jan 28 22:02:24 volumio systemd[1]: Started Load Kernel Modules. 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]: dynamicswap.service: Succeeded. Jan 28 22:02:24 volumio systemd[1]: Started Remount Root and Kernel File Systems. 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]: Starting Load/Save Random Seed... Jan 28 22:02:24 volumio systemd[1]: Starting Create System Users... Jan 28 22:02:24 volumio systemd[1]: Mounting Kernel Configuration File System... Jan 28 22:02:24 volumio systemd[1]: Mounting FUSE Control File System... Jan 28 22:02:24 volumio systemd[1]: Starting Apply Kernel Variables... Jan 28 22:02:24 volumio systemd-journald[286]: Journal started Jan 28 22:02:24 volumio systemd-journald[286]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 3.7M, max 30.0M, 26.2M free. Jan 28 22:02:24 volumio fake-hwclock[276]: 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 Load/Save Random Seed. Jan 28 22:02:24 volumio systemd[1]: Mounted Kernel Configuration File System. Jan 28 22:02:24 volumio systemd[1]: Started Create System Users. Jan 28 22:02:24 volumio systemd[1]: Mounted FUSE Control File System. 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]: Started udev Coldplug all Devices. Jan 28 22:02:24 volumio systemd[1]: Started Apply Kernel Variables. 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]: Starting udev Kernel Device Manager... Jan 28 22:02:24 volumio systemd[1]: Reached target Local File Systems (Pre). 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]: Mounting /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]: Mounted /var/spool/cups. Jan 28 22:02:24 volumio systemd[1]: Mounting /var/spool/cups/tmp... Jan 28 22:02:24 volumio systemd[1]: Starting Flush Journal to Persistent Storage... 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[1]: Starting Show Plymouth Boot Screen... Jan 28 22:02:24 volumio systemd[1]: Received SIGRTMIN+20 from PID 326 (plymouthd). Jan 28 22:02:24 volumio systemd-journald[286]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 3.7M, max 30.0M, 26.2M free. 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 systemd[1]: Started Flush Journal to Persistent Storage. 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 systemd[1]: Found device /dev/ttyS2. 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 systemd-udevd[322]: Using default interface naming scheme 'v240'. 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: 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 kernel: Mali: Mali device driver loaded Jan 28 22:02:24 volumio systemd-udevd[322]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:02:24 volumio systemd-udevd[322]: link_config: could not get ethtool features for eth0 Jan 28 22:02:24 volumio systemd-udevd[322]: 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[314]: 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 Set Up Additional Binary Formats being skipped. 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 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[380]: fsck.fat 4.1 (2017-01-24) Jan 28 22:02:25 volumio systemd-fsck[380]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 28 22:02:25 volumio systemd-fsck[380]: Automatically removing dirty bit. Jan 28 22:02:25 volumio systemd-fsck[380]: Performing changes. Jan 28 22:02:25 volumio systemd-fsck[380]: /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]: Started ifup for eth0. 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 Preprocess NFS configuration... Jan 28 22:02:25 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 28 22:02:25 volumio systemd[1]: Starting Raise network interfaces... Jan 28 22:02:25 volumio systemd[1]: Starting Create Volatile Files and Directories... 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]: 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 systemd[1]: Received SIGRTMIN+20 from PID 326 (plymouthd). 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]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 28 22:02:25 volumio systemd[1]: Reached target NFS client services. Jan 28 22:02:25 volumio ifup[389]: command failed: No such device (-19) Jan 28 22:02:25 volumio sh[385]: 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]: Starting Update UTMP about System Boot/Shutdown... Jan 28 22:02:25 volumio systemd[1]: Starting RPC bind portmap service... 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 Entropy daemon using the HAVEGE algorithm. 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 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 28 22:02:25 volumio systemd[1]: Listening on lircd.socket. Jan 28 22:02:25 volumio systemd[1]: Listening on mpd.socket. Jan 28 22:02:25 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 28 22:02:25 volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Fri 2024-07-26 12:20:32 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 Fri 2024-07-26 12:20:32 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 triggerhappy.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]: Started UPnP Renderer front-end to MPD. Jan 28 22:02:25 volumio ifup[389]: command failed: No such device (-19) Jan 28 22:02:25 volumio systemd[1]: Starting Login Service... Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 28 22:02:25 volumio systemd[1]: Starting Wireless Services... 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 sh[385]: eth0: waiting for carrier Jan 28 22:02:25 volumio sh[385]: eth0: carrier acquired Jan 28 22:02:25 volumio dhcpcd[425]: eth0: waiting for carrier Jan 28 22:02:25 volumio dhcpcd[425]: eth0: carrier acquired Jan 28 22:02:25 volumio systemd[1]: Starting DHCP Client Daemon... Jan 28 22:02:25 volumio sh[385]: DUID 00:01:00:01:29:87:24:71:72:40:73:5a:e7:92 Jan 28 22:02:25 volumio sh[385]: eth0: IAID 73:5a:e7:92 Jan 28 22:02:25 volumio dhcpcd[425]: DUID 00:01:00:01:29:87:24:71:72:40:73:5a:e7:92 Jan 28 22:02:25 volumio dhcpcd[425]: eth0: IAID 73:5a:e7:92 Jan 28 22:02:25 volumio sh[385]: eth0: adding address fe80::7e0f:c2ab:fab2:3a10 Jan 28 22:02:25 volumio sh[385]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio dhcpcd[425]: eth0: adding address fe80::7e0f:c2ab:fab2:3a10 Jan 28 22:02:25 volumio dhcpcd[425]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio systemd[1]: Started D-Bus System Message Bus. Jan 28 22:02:25 volumio systemd[1]: Started volumio-remote-updater.service. Jan 28 22:02:25 volumio systemd-logind[450]: New seat seat0. Jan 28 22:02:25 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 28 22:02:25 volumio systemd-logind[450]: Watching system buttons on /dev/input/event0 (rk8xx_pwrkey) Jan 28 22:02:25 volumio systemd[1]: Started Manage Sound Card State (restore and store). Jan 28 22:02:25 volumio systemd[1]: Starting Save/Restore Sound Card State... Jan 28 22:02:25 volumio systemd[1]: Starting lircd(8) initialization helper tool... 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 Volumio Iptables Module. Jan 28 22:02:26 volumio dhcpcd[425]: eth0: rebinding lease of 192.168.0.146 Jan 28 22:02:26 volumio sh[385]: eth0: rebinding lease of 192.168.0.146 Jan 28 22:02:26 volumio alsactl[463]: alsactl 1.1.8 daemon started Jan 28 22:02:26 volumio dhcpcd[454]: DUID 00:01:00:01:29:87:24:71:72:40:73:5a:e7:92 Jan 28 22:02:26 volumio dhcpcd[454]: DUID 00:01:00:01:29:87:24:71:72:40:73:5a:e7:92 Jan 28 22:02:26 volumio dhcpcd[454]: eth0: IAID 73:5a:e7:92 Jan 28 22:02:26 volumio dhcpcd[454]: eth0: adding address fe80::7e0f:c2ab:fab2:3a10 Jan 28 22:02:26 volumio dhcpcd[454]: ipv6_addaddr1: Permission denied Jan 28 22:02:26 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 28 22:02:26 volumio dhcpcd[454]: eth0: IAID 73:5a:e7:92 Jan 28 22:02:26 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 28 22:02:26 volumio dhcpcd[454]: eth0: adding address fe80::7e0f:c2ab:fab2:3a10 Jan 28 22:02:26 volumio systemd[1]: Reached target Timers. Jan 28 22:02:26 volumio dhcpcd[454]: ipv6_addaddr1: Permission denied 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 thd[468]: Unable to parse trigger line: Jan 28 22:02:26 volumio thd[468]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 28 22:02:26 volumio thd[468]: Unable to parse trigger line: Jan 28 22:02:26 volumio thd[468]: Unable to parse trigger line: Jan 28 22:02:26 volumio thd[468]: Found socket passed from systemd Jan 28 22:02:26 volumio avahi-daemon[461]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Jan 28 22:02:26 volumio avahi-daemon[461]: Successfully dropped root privileges. Jan 28 22:02:26 volumio avahi-daemon[461]: avahi-daemon 0.7 starting up. Jan 28 22:02:26 volumio dhcpcd[454]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio dhcpcd[454]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jan 28 22:02:26 volumio kernel: nf_tables: (c) 2007-2009 Patrick McHardy Jan 28 22:02:26 volumio systemd[1]: Started Save/Restore Sound Card State. Jan 28 22:02:26 volumio avahi-daemon[461]: Successfully called chroot(). Jan 28 22:02:26 volumio avahi-daemon[461]: Successfully dropped remaining capabilities. Jan 28 22:02:26 volumio avahi-daemon[461]: No service file found in /etc/avahi/services. Jan 28 22:02:26 volumio avahi-daemon[461]: Network interface enumeration completed. Jan 28 22:02:26 volumio avahi-daemon[461]: Server startup complete. Host name is volumio.local. Local service cookie is 529441856. Jan 28 22:02:26 volumio wpa_supplicant[466]: Successfully initialized 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 Login Service. Jan 28 22:02:26 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. 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 WPA supplicant. Jan 28 22:02:26 volumio systemd[1]: Reached target Sound Card. Jan 28 22:02:26 volumio volumio-remote-updater[459]: Error: No active session Jan 28 22:02:26 volumio volumio-remote-updater[459]: [2022-01-28 22:02:26] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:02:26 volumio volumio-remote-updater[459]: [2022-01-28 22:02:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:02:26 volumio volumio-remote-updater[459]: [2022-01-28 22:02:26] [error] handle_connect error: Underlying Transport Error 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: 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 dhcpcd[454]: eth0: rebinding lease of 192.168.0.146 Jan 28 22:02:26 volumio dhcpcd[454]: eth0: rebinding lease of 192.168.0.146 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: 0000000000000016 Jan 28 22:02:26 volumio volumio[449]: Could not open config: /tmp/upmpdcli.conf 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 Raise network interfaces. Jan 28 22:02:26 volumio systemd[1]: Reached target Network. Jan 28 22:02:26 volumio systemd[1]: Starting Network Time Service... Jan 28 22:02:26 volumio systemd[1]: Reached target Network is Online. Jan 28 22:02:26 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jan 28 22:02:26 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jan 28 22:02:26 volumio systemd[1]: Starting Permit User Sessions... Jan 28 22:02:26 volumio systemd[1]: Starting Samba NMB Daemon... 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 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 22:02:26 volumio systemd[1]: Started /etc/rc.local Compatibility. Jan 28 22:02:26 volumio sh[385]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio dhcpcd[425]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio kernel: usb-storage 5-1.1:1.0: USB Mass Storage device detected Jan 28 22:02:26 volumio kernel: scsi host0: usb-storage 5-1.1:1.0 Jan 28 22:02:26 volumio kernel: usbcore: registered new interface driver usb-storage Jan 28 22:02:26 volumio kernel: random: nonblocking pool is initialized Jan 28 22:02:27 volumio kernel: usbcore: registered new interface driver uas Jan 28 22:02:27 volumio systemd[1]: Started Permit User Sessions. Jan 28 22:02:27 volumio systemd[1]: Starting Hold until boot process finishes up... Jan 28 22:02:27 volumio loadcpufreq[470]: Loading cpufreq kernel modules...done (none). Jan 28 22:02:27 volumio ntpd[529]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:02:27 volumio ntpd[529]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:02:27 volumio ntpd[571]: proto: precision = 0.875 usec (-20) Jan 28 22:02:27 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 28 22:02:27 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 28 22:02:27 volumio systemd[1]: Started Network Time Service. Jan 28 22:02:27 volumio systemd[1]: Received SIGRTMIN+21 from PID 326 (plymouthd). Jan 28 22:02:27 volumio ntpd[571]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:02:27 volumio ntpd[571]: 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 ntpd[571]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:02:27 volumio ntpd[571]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:02:27 volumio ntpd[571]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:02:27 volumio ntpd[571]: Listening on routing socket on fd #19 for interface updates Jan 28 22:02:27 volumio ntpd[571]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:27 volumio ntpd[571]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:27 volumio systemd[1]: plymouth-start.service: Succeeded. 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]: 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]: Received SIGRTMIN+21 from PID 326 (n/a). Jan 28 22:02:27 volumio systemd[1]: Started Serial Getty on ttyS2. Jan 28 22:02:27 volumio systemd[1]: Started Getty on tty1. Jan 28 22:02:27 volumio systemd[1]: Reached target Login Prompts. Jan 28 22:02:27 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 28 22:02:27 volumio ifplugd(eth0)[583]: ifplugd 0.28 initializing. Jan 28 22:02:27 volumio ifplugd(eth0)[583]: Using interface eth0/72:40:73:5A:E7:92 with driver (version: March_2013) Jan 28 22:02:27 volumio ifplugd(eth0)[583]: Using detection mode: SIOCETHTOOL Jan 28 22:02:27 volumio ifplugd(eth0)[583]: Initialization complete, link beat detected. Jan 28 22:02:27 volumio ifplugd(eth0)[583]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 28 22:02:27 volumio ifplugd(eth0)[583]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 28 22:02:27 volumio lircd-setup[465]: /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[465]: parser = configparser.SafeConfigParser() 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[603]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Version: lircd 0.10.1 Jan 28 22:02:27 volumio cpufrequtils[585]: 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[603]: 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[603]: Info: Initial device: auto Jan 28 22:02:27 volumio lircd-0.10.1[603]: Info: Initial device: auto Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: driver: devinput Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: output: /var/run/lirc/lircd Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: nodaemon: 1 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: logfile: syslog Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: immediate-init: 0 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: permission: 666 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: driver-options: Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: listen: 0 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: connect: (null) Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: userelease: 0 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: effective_user: (null) Jan 28 22:02:27 volumio lircd[603]: lircd-0.10.1[603]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd[603]: lircd-0.10.1[603]: Notice: Using systemd fd Jan 28 22:02:27 volumio lircd[603]: lircd-0.10.1[603]: Warning: Running as root Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: release_suffix: _EVUP Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: allow_simulate: 0 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: repeat_max: 600 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Options: dynamic_codes: (null) Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Current driver: devinput Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Driver API version: 4 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Driver version: 0.10.0 Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Jan 28 22:02:27 volumio lircd-0.10.1[603]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd-0.10.1[603]: Notice: Using systemd fd Jan 28 22:02:27 volumio lircd-0.10.1[603]: Warning: Running as root Jan 28 22:02:27 volumio lircd[603]: lircd-0.10.1[603]: Info: Using remote: devinput-64. Jan 28 22:02:27 volumio lircd-0.10.1[603]: Info: Using remote: devinput-64. Jan 28 22:02:27 volumio lircd[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: Info: Using remote: devinput-32. Jan 28 22:02:27 volumio lircd-0.10.1[603]: Info: Using remote: devinput-32. Jan 28 22:02:27 volumio lircd[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: 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[603]: 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[603]: lircd-0.10.1[603]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Jan 28 22:02:27 volumio lircd-0.10.1[603]: 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 haveged[421]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Jan 28 22:02:27 volumio haveged[421]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Jan 28 22:02:27 volumio haveged[421]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99846 Jan 28 22:02:27 volumio haveged[421]: haveged: fills: 0, generated: 0 Jan 28 22:02:27 volumio nmbd[553]: [2022/01/28 22:02:27.837030, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 28 22:02:27 volumio nmbd[553]: started asyncdns process 610 Jan 28 22:02:27 volumio nmbd[553]: [2022/01/28 22:02:27.839140, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 28 22:02:27 volumio nmbd[553]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 28 22:02:27 volumio nmbd[553]: [2022/01/28 22:02:27.839331, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 28 22:02:27 volumio nmbd[553]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 28 22:02:27 volumio dhcpcd[454]: eth0: carrier lost Jan 28 22:02:27 volumio sh[385]: eth0: carrier lost Jan 28 22:02:27 volumio dhcpcd[454]: eth0: carrier lost Jan 28 22:02:27 volumio dhcpcd[425]: eth0: carrier lost Jan 28 22:02:27 volumio sh[385]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Jan 28 22:02:28 volumio ntpd[571]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 22:02:28 volumio wireless.js[452]: WIRELESS DAEMON: start Jan 28 22:02:28 volumio wireless.js[452]: WIRELESS: Loaded configuration Jan 28 22:02:28 volumio wireless.js[452]: Cleaning previous... Jan 28 22:02:28 volumio sudo[648]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:02:28 volumio sudo[648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:28 volumio sudo[648]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:28 volumio wireless.js[452]: Stopped aP Jan 28 22:02:28 volumio sudo[659]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:02:28 volumio sudo[659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:28 volumio sudo[659]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:28 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:28 volumio hostapd[673]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:28 volumio hostapd[673]: Could not read interface wlan0 flags: No such device Jan 28 22:02:28 volumio hostapd[673]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:28 volumio hostapd[673]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:28 volumio hostapd[673]: Could not read interface wlan0 flags: No such device Jan 28 22:02:28 volumio hostapd[673]: nl80211 driver initialization failed. Jan 28 22:02:28 volumio hostapd[673]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:28 volumio hostapd[673]: wlan0: AP-DISABLED Jan 28 22:02:28 volumio hostapd[673]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:28 volumio hostapd[673]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:28 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:28 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:28 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:28 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:28 volumio dnsmasq[680]: dnsmasq: syntax check OK. Jan 28 22:02:28 volumio iw[682]: command failed: No such device (-19) Jan 28 22:02:28 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=237/KEYRING Jan 28 22:02:28 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Jan 28 22:02:28 volumio systemd[1]: Failed to start Wireless Services. Jan 28 22:02:28 volumio systemd[1]: Started Volumio Backend Module. Jan 28 22:02:28 volumio systemd[1]: Started Volumio Streaming Daemon. Jan 28 22:02:28 volumio dnsmasq[700]: started, version 2.80 cachesize 150 Jan 28 22:02:28 volumio dnsmasq[700]: 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:28 volumio dnsmasq[700]: warning: interface wlan0 does not currently exist Jan 28 22:02:28 volumio dnsmasq-dhcp[700]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:28 volumio dnsmasq[700]: reading /etc/resolv.conf Jan 28 22:02:28 volumio dnsmasq[700]: using nameserver 208.67.222.222#53 Jan 28 22:02:28 volumio dnsmasq[700]: using nameserver 208.67.220.220#53 Jan 28 22:02:28 volumio dnsmasq[700]: read /etc/hosts - 5 addresses Jan 28 22:02:28 volumio volumio-streaming-daemon[688]: ############################ Jan 28 22:02:28 volumio volumio-streaming-daemon[688]: # Volumio Streaming Daemon # Jan 28 22:02:28 volumio volumio-streaming-daemon[688]: # Running on port 7777 # Jan 28 22:02:28 volumio volumio-streaming-daemon[688]: ############################ Jan 28 22:02:28 volumio volumio-streaming-daemon[688]: Environment: production Jan 28 22:02:28 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:28 volumio systemd[1]: Reached target Host and Network Name Lookups. Jan 28 22:02:29 volumio ntpd[571]: error resolving pool 1.debian.pool.ntp.org: System error (-11) 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 mpd[602]: Jan 28 22:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:29 volumio mpd[602]: Jan 28 22:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:29 volumio mpd[602]: Jan 28 22:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:29 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:30 volumio ntpd[571]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 28 22:02:30 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:30 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 1. Jan 28 22:02:30 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:31 volumio dnsmasq[700]: 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[715]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:31 volumio hostapd[715]: Could not read interface wlan0 flags: No such device Jan 28 22:02:31 volumio hostapd[715]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:31 volumio hostapd[715]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:31 volumio hostapd[715]: Could not read interface wlan0 flags: No such device Jan 28 22:02:31 volumio hostapd[715]: nl80211 driver initialization failed. Jan 28 22:02:31 volumio hostapd[715]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:31 volumio hostapd[715]: wlan0: AP-DISABLED Jan 28 22:02:31 volumio hostapd[715]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:31 volumio hostapd[715]: 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 ntpd[571]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 28 22:02:31 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:31 volumio dnsmasq[722]: dnsmasq: syntax check OK. Jan 28 22:02:31 volumio dnsmasq[728]: started, version 2.80 cachesize 150 Jan 28 22:02:31 volumio dnsmasq[728]: 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[728]: warning: interface wlan0 does not currently exist Jan 28 22:02:31 volumio dnsmasq-dhcp[728]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:31 volumio dnsmasq[728]: reading /etc/resolv.conf Jan 28 22:02:31 volumio dnsmasq[728]: using nameserver 208.67.222.222#53 Jan 28 22:02:31 volumio dnsmasq[728]: using nameserver 208.67.220.220#53 Jan 28 22:02:31 volumio dnsmasq[728]: 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 volumio[687]: info: ------------------------------------------- Jan 28 22:02:31 volumio volumio[687]: info: ----- Volumio3 ---- Jan 28 22:02:31 volumio volumio[687]: info: ------------------------------------------- Jan 28 22:02:31 volumio volumio[687]: info: ----- System startup ---- Jan 28 22:02:31 volumio volumio[687]: info: ------------------------------------------- Jan 28 22:02:31 volumio volumio-remote-updater[459]: [2022-01-28 22:02:31] [connect] Successful connection Jan 28 22:02:32 volumio volumio[687]: info: MYVOLUMIO Environment detected Jan 28 22:02:32 volumio volumio[687]: info: Plugin folders cleanup Jan 28 22:02:32 volumio volumio[687]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:32 volumio volumio[687]: info: Scanning category audio_interface Jan 28 22:02:32 volumio volumio[687]: info: Scanning category miscellanea Jan 28 22:02:32 volumio volumio[687]: info: Scanning category music_service Jan 28 22:02:32 volumio volumio[687]: info: Scanning category plugins.json Jan 28 22:02:32 volumio volumio[687]: info: Scanning category system_controller Jan 28 22:02:32 volumio volumio[687]: info: Scanning category user_interface Jan 28 22:02:32 volumio volumio[687]: info: Scanning into folder /data/plugins/ Jan 28 22:02:32 volumio volumio[687]: info: Scanning category music_service Jan 28 22:02:32 volumio volumio[687]: info: Plugin folders cleanup completed Jan 28 22:02:32 volumio volumio[687]: info: ------------------------------------------- Jan 28 22:02:32 volumio volumio[687]: info: ----- Core plugins startup ---- Jan 28 22:02:32 volumio volumio[687]: info: ------------------------------------------- Jan 28 22:02:32 volumio volumio[687]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:32 volumio volumio[687]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:32 volumio volumio[687]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:32 volumio volumio[687]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:32 volumio volumio[687]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:32 volumio volumio[687]: info: Loading plugin "system"... Jan 28 22:02:32 volumio volumio[687]: 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[728]: 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[748]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:33 volumio hostapd[748]: Could not read interface wlan0 flags: No such device Jan 28 22:02:33 volumio hostapd[748]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:33 volumio hostapd[748]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:33 volumio hostapd[748]: Could not read interface wlan0 flags: No such device Jan 28 22:02:33 volumio hostapd[748]: nl80211 driver initialization failed. Jan 28 22:02:33 volumio hostapd[748]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:33 volumio hostapd[748]: wlan0: AP-DISABLED Jan 28 22:02:33 volumio hostapd[748]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:33 volumio hostapd[748]: 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[755]: dnsmasq: syntax check OK. Jan 28 22:02:33 volumio dnsmasq[761]: started, version 2.80 cachesize 150 Jan 28 22:02:33 volumio dnsmasq[761]: 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:33 volumio dnsmasq[761]: warning: interface wlan0 does not currently exist Jan 28 22:02:33 volumio dnsmasq-dhcp[761]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:33 volumio dnsmasq[761]: reading /etc/resolv.conf Jan 28 22:02:33 volumio dnsmasq[761]: using nameserver 208.67.222.222#53 Jan 28 22:02:33 volumio dnsmasq[761]: using nameserver 208.67.220.220#53 Jan 28 22:02:33 volumio dnsmasq[761]: read /etc/hosts - 5 addresses Jan 28 22:02:33 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "network"... Jan 28 22:02:34 volumio volumio[687]: info: Refreshing Cached IP Addresses Jan 28 22:02:34 volumio sudo[768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:34 volumio sudo[768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio sudo[768]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "services"... Jan 28 22:02:34 volumio sudo[770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:34 volumio sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "alsa_controller"... Jan 28 22:02:34 volumio sudo[773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:34 volumio sudo[773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio sudo[770]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio sudo[773]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "wizard"... Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "upnp"... Jan 28 22:02:34 volumio volumio[687]: info: [1643407354476] Starting Upmpd Daemon Jan 28 22:02:34 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "my_music"... Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "mpd"... Jan 28 22:02:34 volumio volumio[687]: info: Creating MPD Configuration file Jan 28 22:02:34 volumio volumio[687]: info: Loading plugin "upnp_browser"... Jan 28 22:02:34 volumio sudo[785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:34 volumio sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio sudo[785]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:34 volumio sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:35 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:35 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:35 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:35 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:35 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 3. Jan 28 22:02:35 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:35 volumio dnsmasq[761]: exiting on receipt of SIGTERM Jan 28 22:02:35 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:35 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:35 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:35 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:35 volumio hostapd[796]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:35 volumio hostapd[796]: Could not read interface wlan0 flags: No such device Jan 28 22:02:35 volumio hostapd[796]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:35 volumio hostapd[796]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:35 volumio volumio[687]: info: Loading plugin "networkfs"... Jan 28 22:02:35 volumio hostapd[796]: Could not read interface wlan0 flags: No such device Jan 28 22:02:35 volumio hostapd[796]: nl80211 driver initialization failed. Jan 28 22:02:35 volumio hostapd[796]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:35 volumio hostapd[796]: wlan0: AP-DISABLED Jan 28 22:02:35 volumio hostapd[796]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:35 volumio hostapd[796]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:35 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:35 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:35 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:35 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:35 volumio dnsmasq[804]: dnsmasq: syntax check OK. Jan 28 22:02:35 volumio dnsmasq[810]: started, version 2.80 cachesize 150 Jan 28 22:02:35 volumio dnsmasq[810]: 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:35 volumio dnsmasq[810]: warning: interface wlan0 does not currently exist Jan 28 22:02:35 volumio dnsmasq-dhcp[810]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:35 volumio dnsmasq[810]: reading /etc/resolv.conf Jan 28 22:02:35 volumio dnsmasq[810]: using nameserver 208.67.222.222#53 Jan 28 22:02:35 volumio dnsmasq[810]: using nameserver 208.67.220.220#53 Jan 28 22:02:35 volumio dnsmasq[810]: read /etc/hosts - 5 addresses Jan 28 22:02:35 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:35 volumio volumio[687]: info: Starting Udev Watcher for removable devices Jan 28 22:02:36 volumio volumio[687]: info: Ignoring mount for partition: boot Jan 28 22:02:36 volumio volumio[687]: info: Ignoring mount for partition: volumio Jan 28 22:02:36 volumio volumio[687]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:36 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "alarm-clock"... Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "airplay_emulation"... Jan 28 22:02:36 volumio volumio[687]: info: Starting Shairport Sync Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "last_100"... Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "webradio"... Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "i2s_dacs"... Jan 28 22:02:36 volumio volumio[687]: info: I2S DAC not set, start Auto-detection Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "volumiodiscovery"... Jan 28 22:02:36 volumio volumio[687]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:36 volumio volumio[687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio volumio[687]: *** WARNING *** For more information see Jan 28 22:02:36 volumio volumio[687]: *** 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:36 volumio volumio[687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio node[687]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:36 volumio volumio[687]: *** WARNING *** For more information see Jan 28 22:02:36 volumio node[687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio node[687]: *** WARNING *** For more information see Jan 28 22:02:36 volumio node[687]: *** 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:36 volumio node[687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio node[687]: *** WARNING *** For more information see Jan 28 22:02:36 volumio volumio[687]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:02:36 volumio volumio[687]: info: Discovery: Started advertising with name: Volumio Jan 28 22:02:36 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:36 volumio volumio[687]: info: Loading plugin "squeezelite_mc"... Jan 28 22:02:37 volumio mpd[803]: Jan 28 22:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:37 volumio mpd[803]: Jan 28 22:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:37 volumio mpd[803]: Jan 28 22:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:37 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:37 volumio sudo[787]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:37 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:37 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 4. Jan 28 22:02:37 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:37 volumio dnsmasq[810]: exiting on receipt of SIGTERM Jan 28 22:02:37 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:37 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:37 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:37 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:37 volumio hostapd[825]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:37 volumio hostapd[825]: Could not read interface wlan0 flags: No such device Jan 28 22:02:37 volumio hostapd[825]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:37 volumio hostapd[825]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:37 volumio hostapd[825]: Could not read interface wlan0 flags: No such device Jan 28 22:02:37 volumio hostapd[825]: nl80211 driver initialization failed. Jan 28 22:02:37 volumio hostapd[825]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:37 volumio hostapd[825]: wlan0: AP-DISABLED Jan 28 22:02:37 volumio hostapd[825]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:37 volumio hostapd[825]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:37 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:37 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:37 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:37 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:37 volumio dnsmasq[832]: dnsmasq: syntax check OK. Jan 28 22:02:37 volumio dnsmasq[838]: started, version 2.80 cachesize 150 Jan 28 22:02:37 volumio dnsmasq[838]: 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:37 volumio dnsmasq[838]: warning: interface wlan0 does not currently exist Jan 28 22:02:37 volumio dnsmasq-dhcp[838]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:37 volumio dnsmasq[838]: reading /etc/resolv.conf Jan 28 22:02:37 volumio dnsmasq[838]: using nameserver 208.67.222.222#53 Jan 28 22:02:37 volumio dnsmasq[838]: using nameserver 208.67.220.220#53 Jan 28 22:02:37 volumio dnsmasq[838]: read /etc/hosts - 5 addresses Jan 28 22:02:37 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:37 volumio volumio[687]: info: Loading plugin "outputs"... Jan 28 22:02:37 volumio volumio[687]: info: Loading plugin "albumart"... Jan 28 22:02:37 volumio volumio[687]: info: Plugin example_plugin is not enabled Jan 28 22:02:37 volumio volumio[687]: info: Loading plugin "inputs"... Jan 28 22:02:37 volumio volumio[687]: info: Loading plugin "updater_comm"... Jan 28 22:02:37 volumio volumio[687]: info: Plugin mpdemulation is not enabled Jan 28 22:02:37 volumio volumio[687]: info: Loading plugin "rest_api"... Jan 28 22:02:38 volumio volumio[687]: info: Loading plugin "websocket"... Jan 28 22:02:38 volumio volumio[687]: info: Loading plugin "RoonBridge"... Jan 28 22:02:38 volumio volumio[687]: Forking 3 albumart workers Jan 28 22:02:38 volumio volumio[687]: info: Applying required configuration parameters for plugin RoonBridge Jan 28 22:02:38 volumio volumio[687]: info: Loading i18n strings for locale zh Jan 28 22:02:38 volumio volumio[687]: Updating browse sources language Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::initPlayerControls Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:38 volumio volumio[687]: Express server listening on port 3000 Jan 28 22:02:38 volumio volumio[687]: [Metrics] WebUI: 7s 786.93ms Jan 28 22:02:38 volumio volumio[687]: info: CoreStateMachine::resetVolumioState Jan 28 22:02:38 volumio volumio[687]: info: CoreStateMachine::getcurrentVolume Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:38 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:38 volumio volumio[687]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:02:38 volumio volumio[687]: info: Completed loading Core Plugins Jan 28 22:02:38 volumio volumio[687]: info: Preparing to generate the ALSA configuration file Jan 28 22:02:38 volumio volumio[687]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:02:38 volumio volumio[687]: info: MPD Permissions set Jan 28 22:02:38 volumio volumio[687]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:02:38 volumio volumio[687]: wlan0 Interface doesn't support scanning. Jan 28 22:02:38 volumio volumio[687]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:02:38 volumio sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:02:38 volumio sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:38 volumio sudo[880]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:38 volumio volumio[687]: command failed: No such device (-19) Jan 28 22:02:38 volumio volumio[687]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:02:38 volumio volumio[687]: command failed: No such device (-19) Jan 28 22:02:38 volumio volumio[687]: info: MPD running with PID803 Jan 28 22:02:38 volumio volumio[687]: ,establishing connection Jan 28 22:02:39 volumio volumio[687]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:39 volumio volumio[687]: assert.ok(self.idling) Jan 28 22:02:39 volumio volumio[687]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:39 volumio volumio[687]: assert.ok(self.idling) Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:39 volumio volumio[687]: Starting albumart workers Jan 28 22:02:39 volumio volumio[687]: Starting albumart workers Jan 28 22:02:39 volumio volumio[687]: Starting albumart workers Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: Reloading queue from file Jan 28 22:02:39 volumio volumio[687]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:02:39 volumio volumio[687]: info: CoreStateMachine::pushState Jan 28 22:02:39 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:39 volumio volumio[687]: info: CoreStateMachine::updateTrackBlock Jan 28 22:02:39 volumio volumio[687]: info: CorePlayQueue::getTrackBlock Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:39 volumio volumio[687]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:02:39 volumio volumio[687]: info: Output device has changed, restarting MPD Jan 28 22:02:39 volumio volumio[687]: info: Output device has changed, restarting Shairport Sync Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:39 volumio sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:39 volumio sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:39 volumio sudo[898]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:39 volumio sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:39 volumio sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:39 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:39 volumio volumio[687]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:39 volumio volumio[687]: info: ___________ START PLUGINS ___________ Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:39 volumio volumio[687]: info: [1643407359505] CoreMusicLibrary::Adding element Media Servers Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:39 volumio volumio[687]: Cannot find translation for sourceMedia Servers Jan 28 22:02:39 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:39 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:39 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:39 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 5. Jan 28 22:02:39 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:39 volumio volumio[687]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:39 volumio volumio[687]: info: [1643407359772] CoreMusicLibrary::Adding element Last_100 Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:39 volumio volumio[687]: Cannot find translation for sourceMedia Servers Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:39 volumio volumio[687]: info: [1643407359782] CoreMusicLibrary::Adding element Webradio Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:39 volumio volumio[687]: Cannot find translation for sourceMedia Servers Jan 28 22:02:39 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:02:39 volumio dnsmasq[838]: exiting on receipt of SIGTERM Jan 28 22:02:39 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:39 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:39 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:39 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:39 volumio hostapd[917]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:39 volumio volumio[687]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:02:39 volumio volumio[687]: info: Volumio Calling Home Jan 28 22:02:40 volumio hostapd[917]: Could not read interface wlan0 flags: No such device Jan 28 22:02:40 volumio hostapd[917]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:40 volumio hostapd[917]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:40 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 28 22:02:40 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:40 volumio hostapd[917]: Could not read interface wlan0 flags: No such device Jan 28 22:02:40 volumio hostapd[917]: nl80211 driver initialization failed. Jan 28 22:02:40 volumio hostapd[917]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:40 volumio hostapd[917]: wlan0: AP-DISABLED Jan 28 22:02:40 volumio hostapd[917]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:40 volumio hostapd[917]: 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]: Started RoonBridge. Jan 28 22:02:40 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:40 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:40 volumio dnsmasq[956]: dnsmasq: syntax check OK. Jan 28 22:02:40 volumio volumio[687]: info: [squeezelite_mc] Proxy server started on port 37727 Jan 28 22:02:40 volumio dnsmasq[969]: started, version 2.80 cachesize 150 Jan 28 22:02:40 volumio dnsmasq[969]: 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:40 volumio dnsmasq[969]: warning: interface wlan0 does not currently exist Jan 28 22:02:40 volumio dnsmasq-dhcp[969]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:40 volumio dnsmasq[969]: reading /etc/resolv.conf Jan 28 22:02:40 volumio dnsmasq[969]: using nameserver 208.67.222.222#53 Jan 28 22:02:40 volumio dnsmasq[969]: using nameserver 208.67.220.220#53 Jan 28 22:02:40 volumio dnsmasq[969]: read /etc/hosts - 5 addresses Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:40 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:40 volumio volumio[687]: info: CoreStateMachine::setRepeat null single undefined Jan 28 22:02:40 volumio volumio[687]: info: CoreStateMachine::pushState Jan 28 22:02:40 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:40 volumio volumio[687]: info: CoreStateMachine::setRandom null Jan 28 22:02:40 volumio volumio[687]: info: CoreStateMachine::pushState Jan 28 22:02:40 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:40 volumio volumio[687]: error: updateQueue error: null Jan 28 22:02:40 volumio volumio[687]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 22:02:40 volumio volumio[687]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 22:02:40 volumio volumio[687]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 22:02:40 volumio volumio[687]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 22:02:40 volumio volumio[687]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 22:02:40 volumio volumio[687]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 22:02:40 volumio volumio[687]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 22:02:40 volumio volumio[687]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 22:02:40 volumio volumio[687]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 22:02:40 volumio volumio[687]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 22:02:40 volumio volumio[687]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 22:02:40 volumio volumio[687]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 22:02:40 volumio volumio[687]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 22:02:40 volumio volumio[687]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 22:02:40 volumio volumio[687]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 22:02:40 volumio volumio[687]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 22:02:40 volumio volumio[687]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 22:02:40 volumio volumio[687]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 22:02:40 volumio volumio[687]: info: MPD Permissions set Jan 28 22:02:40 volumio volumio[687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:02:40 volumio volumio[687]: info: CoreStateMachine::pushState Jan 28 22:02:40 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:40 volumio volumio[687]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:02:40 volumio volumio[687]: info: CoreStateMachine::pushState Jan 28 22:02:40 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:40 volumio volumio[687]: info: [squeezelite_mc] Server discovery started Jan 28 22:02:40 volumio volumio[687]: info: [squeezelite_mc] Player finder started Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:40 volumio volumio[687]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:02:40 volumio volumio[687]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:40 volumio volumio[687]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:02:40 volumio volumio[687]: at doSend (dgram.js:714:16) Jan 28 22:02:40 volumio volumio[687]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:02:40 volumio volumio[687]: at afterDns (dgram.js:660:5) Jan 28 22:02:40 volumio volumio[687]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:02:40 volumio volumio[687]: errno: -101, Jan 28 22:02:40 volumio volumio[687]: code: 'ENETUNREACH', Jan 28 22:02:40 volumio volumio[687]: syscall: 'send', Jan 28 22:02:40 volumio volumio[687]: address: '255.255.255.255', Jan 28 22:02:40 volumio volumio[687]: port: 3483 Jan 28 22:02:40 volumio volumio[687]: } Jan 28 22:02:40 volumio volumio[687]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:41 volumio sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:01 Jan 28 22:02:41 volumio sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:41 volumio sudo[997]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:41 volumio RoonBridge[955]: 00:00:00.019 Warn: get lock file path: /tmp/.rnbgem0- Jan 28 22:02:41 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:02:41 volumio sudo[900]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:41 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 22:02:41 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:41 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:41 volumio mpd[940]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:41 volumio mpd[940]: output: No 'audio_output' defined in config file Jan 28 22:02:41 volumio mpd[940]: output: Attempt to detect audio output device Jan 28 22:02:41 volumio mpd[940]: output: Attempting to detect a alsa audio device Jan 28 22:02:41 volumio mpd[940]: output: Successfully detected a alsa audio device Jan 28 22:02:41 volumio mpd[940]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:41 volumio mpd[940]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:41 volumio mpd[940]: zeroconf: No global port, disabling zeroconf Jan 28 22:02:41 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:41 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 28 22:02:41 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 28 22:02:41 volumio systemd[1]: Stopped Volumio Backend Module. Jan 28 22:02:41 volumio systemd[1]: Started Volumio Backend Module. Jan 28 22:02:41 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:41 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.690 Trace: [childprocess] using unix child process Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.793 Debug: PathForResource, filename: ../.update Jan 28 22:02:42 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:42 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 6. Jan 28 22:02:42 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:42 volumio dnsmasq[969]: exiting on receipt of SIGTERM Jan 28 22:02:42 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.844 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jan 28 22:02:42 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:42 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:42 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.858 Debug: PathForResource, filename: RAATServer Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.858 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.860 Debug: PathForResource, filename: RoonBridgeHelper Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.860 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jan 28 22:02:42 volumio RoonBridge[955]: Initializing Jan 28 22:02:42 volumio hostapd[1030]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:42 volumio hostapd[1030]: Could not read interface wlan0 flags: No such device Jan 28 22:02:42 volumio hostapd[1030]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:42 volumio hostapd[1030]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:42 volumio hostapd[1030]: Could not read interface wlan0 flags: No such device Jan 28 22:02:42 volumio hostapd[1030]: nl80211 driver initialization failed. Jan 28 22:02:42 volumio hostapd[1030]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:42 volumio hostapd[1030]: wlan0: AP-DISABLED Jan 28 22:02:42 volumio hostapd[1030]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:42 volumio hostapd[1030]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:42 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:42 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:42 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:42 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.947 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jan 28 22:02:42 volumio dnsmasq[1042]: dnsmasq: syntax check OK. Jan 28 22:02:42 volumio RoonBridge[955]: 00:00:00.994 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 22:02:42 volumio dnsmasq[1048]: started, version 2.80 cachesize 150 Jan 28 22:02:42 volumio dnsmasq[1048]: 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:42 volumio dnsmasq[1048]: warning: interface wlan0 does not currently exist Jan 28 22:02:42 volumio dnsmasq-dhcp[1048]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:42 volumio dnsmasq[1048]: reading /etc/resolv.conf Jan 28 22:02:42 volumio dnsmasq[1048]: using nameserver 208.67.222.222#53 Jan 28 22:02:42 volumio dnsmasq[1048]: using nameserver 208.67.220.220#53 Jan 28 22:02:42 volumio dnsmasq[1048]: read /etc/hosts - 5 addresses Jan 28 22:02:42 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:42 volumio RoonBridge[955]: Not Running (.o) Jan 28 22:02:42 volumio RoonBridge[955]: Running Jan 28 22:02:43 volumio RoonBridge[955]: 00:00:00.054 Warn: get lock file path: /tmp/.rnbhgem0- Jan 28 22:02:44 volumio volumio-remote-updater[459]: [2022-01-28 22:02:44] [connect] Successful connection Jan 28 22:02:44 volumio volumio[1012]: info: ------------------------------------------- Jan 28 22:02:44 volumio volumio[1012]: info: ----- Volumio3 ---- Jan 28 22:02:44 volumio volumio[1012]: info: ------------------------------------------- Jan 28 22:02:44 volumio volumio[1012]: info: ----- System startup ---- Jan 28 22:02:44 volumio volumio[1012]: info: ------------------------------------------- Jan 28 22:02:44 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:44 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 7. Jan 28 22:02:44 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:44 volumio dnsmasq[1048]: exiting on receipt of SIGTERM Jan 28 22:02:44 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:44 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:44 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:44 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:44 volumio hostapd[1087]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:44 volumio hostapd[1087]: Could not read interface wlan0 flags: No such device Jan 28 22:02:44 volumio hostapd[1087]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:44 volumio hostapd[1087]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:44 volumio hostapd[1087]: Could not read interface wlan0 flags: No such device Jan 28 22:02:44 volumio hostapd[1087]: nl80211 driver initialization failed. Jan 28 22:02:44 volumio hostapd[1087]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:44 volumio hostapd[1087]: wlan0: AP-DISABLED Jan 28 22:02:44 volumio hostapd[1087]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:44 volumio hostapd[1087]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:44 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:44 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:44 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:44 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:44 volumio dnsmasq[1094]: dnsmasq: syntax check OK. Jan 28 22:02:44 volumio dnsmasq[1100]: started, version 2.80 cachesize 150 Jan 28 22:02:44 volumio dnsmasq[1100]: 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:44 volumio dnsmasq[1100]: warning: interface wlan0 does not currently exist Jan 28 22:02:44 volumio dnsmasq-dhcp[1100]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:44 volumio dnsmasq[1100]: reading /etc/resolv.conf Jan 28 22:02:44 volumio dnsmasq[1100]: using nameserver 208.67.222.222#53 Jan 28 22:02:44 volumio dnsmasq[1100]: using nameserver 208.67.220.220#53 Jan 28 22:02:44 volumio dnsmasq[1100]: read /etc/hosts - 5 addresses Jan 28 22:02:44 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:45 volumio volumio[1012]: info: MYVOLUMIO Environment detected Jan 28 22:02:45 volumio volumio[1012]: info: Plugin folders cleanup Jan 28 22:02:45 volumio volumio[1012]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category audio_interface Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category miscellanea Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category music_service Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category plugins.json Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category system_controller Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category user_interface Jan 28 22:02:45 volumio volumio[1012]: info: Scanning into folder /data/plugins/ Jan 28 22:02:45 volumio volumio[1012]: info: Scanning category music_service Jan 28 22:02:45 volumio volumio[1012]: info: Plugin folders cleanup completed Jan 28 22:02:45 volumio volumio[1012]: info: ------------------------------------------- Jan 28 22:02:45 volumio volumio[1012]: info: ----- Core plugins startup ---- Jan 28 22:02:45 volumio volumio[1012]: info: ------------------------------------------- Jan 28 22:02:45 volumio volumio[1012]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:45 volumio volumio[1012]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:45 volumio volumio[1012]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:45 volumio volumio[1012]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:45 volumio volumio[1012]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:45 volumio volumio[1012]: info: Loading plugin "system"... Jan 28 22:02:45 volumio volumio[1012]: info: Loading plugin "appearance"... Jan 28 22:02:46 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:46 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 8. Jan 28 22:02:46 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:46 volumio dnsmasq[1100]: exiting on receipt of SIGTERM Jan 28 22:02:46 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:46 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:46 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:46 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:46 volumio hostapd[1124]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:46 volumio hostapd[1124]: Could not read interface wlan0 flags: No such device Jan 28 22:02:46 volumio hostapd[1124]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:46 volumio hostapd[1124]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:46 volumio volumio[1012]: info: Loading plugin "network"... Jan 28 22:02:46 volumio hostapd[1124]: Could not read interface wlan0 flags: No such device Jan 28 22:02:46 volumio hostapd[1124]: nl80211 driver initialization failed. Jan 28 22:02:46 volumio hostapd[1124]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:46 volumio hostapd[1124]: wlan0: AP-DISABLED Jan 28 22:02:46 volumio hostapd[1124]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:46 volumio hostapd[1124]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:46 volumio volumio[1012]: info: Refreshing Cached IP Addresses Jan 28 22:02:46 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:46 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:46 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:46 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:46 volumio dnsmasq[1132]: dnsmasq: syntax check OK. Jan 28 22:02:47 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "services"... Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "alsa_controller"... Jan 28 22:02:47 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:47 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:47 volumio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio sudo[1140]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio dnsmasq[1152]: started, version 2.80 cachesize 150 Jan 28 22:02:47 volumio dnsmasq[1152]: 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[1152]: warning: interface wlan0 does not currently exist Jan 28 22:02:47 volumio dnsmasq-dhcp[1152]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:47 volumio dnsmasq[1152]: reading /etc/resolv.conf Jan 28 22:02:47 volumio dnsmasq[1152]: using nameserver 208.67.222.222#53 Jan 28 22:02:47 volumio dnsmasq[1152]: using nameserver 208.67.220.220#53 Jan 28 22:02:47 volumio dnsmasq[1152]: 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:47 volumio volumio[1012]: info: Loading plugin "wizard"... Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "upnp"... Jan 28 22:02:47 volumio volumio[1012]: info: [1643407367264] Starting Upmpd Daemon Jan 28 22:02:47 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "my_music"... Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "mpd"... Jan 28 22:02:47 volumio volumio[1012]: info: Creating MPD Configuration file Jan 28 22:02:47 volumio volumio[1012]: info: Loading plugin "upnp_browser"... Jan 28 22:02:47 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:47 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:47 volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:47 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:47 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:47 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:47 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:47 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:48 volumio volumio[1012]: info: Loading plugin "networkfs"... Jan 28 22:02:48 volumio volumio[1012]: info: Starting Udev Watcher for removable devices Jan 28 22:02:48 volumio volumio[1012]: info: Ignoring mount for partition: boot Jan 28 22:02:48 volumio volumio[1012]: info: Ignoring mount for partition: volumio Jan 28 22:02:48 volumio volumio[1012]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:48 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:48 volumio volumio[1012]: info: Loading plugin "alarm-clock"... Jan 28 22:02:48 volumio volumio[1012]: info: Loading plugin "airplay_emulation"... Jan 28 22:02:48 volumio volumio[1012]: info: Starting Shairport Sync Jan 28 22:02:48 volumio volumio[1012]: info: Loading plugin "last_100"... Jan 28 22:02:48 volumio volumio[1012]: info: Loading plugin "webradio"... Jan 28 22:02:48 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:48 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 9. Jan 28 22:02:48 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:49 volumio dnsmasq[1152]: 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[1184]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:49 volumio volumio[1012]: info: Loading plugin "i2s_dacs"... Jan 28 22:02:49 volumio volumio[1012]: info: I2S DAC not set, start Auto-detection Jan 28 22:02:49 volumio volumio[1012]: info: Loading plugin "volumiodiscovery"... Jan 28 22:02:49 volumio hostapd[1184]: Could not read interface wlan0 flags: No such device Jan 28 22:02:49 volumio hostapd[1184]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:49 volumio hostapd[1184]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:49 volumio hostapd[1184]: Could not read interface wlan0 flags: No such device Jan 28 22:02:49 volumio hostapd[1184]: nl80211 driver initialization failed. Jan 28 22:02:49 volumio hostapd[1184]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:49 volumio hostapd[1184]: wlan0: AP-DISABLED Jan 28 22:02:49 volumio hostapd[1184]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:49 volumio hostapd[1184]: 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:49 volumio volumio[1012]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:49 volumio volumio[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio volumio[1012]: *** WARNING *** For more information see Jan 28 22:02:49 volumio volumio[1012]: *** 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[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio volumio[1012]: *** WARNING *** For more information see Jan 28 22:02:49 volumio dnsmasq[1191]: dnsmasq: syntax check OK. Jan 28 22:02:49 volumio node[1012]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:49 volumio volumio[1012]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:02:49 volumio node[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio node[1012]: *** WARNING *** For more information see Jan 28 22:02:49 volumio node[1012]: *** 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[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:49 volumio node[1012]: *** WARNING *** For more information see Jan 28 22:02:49 volumio volumio[1012]: info: Discovery: Started advertising with name: Volumio Jan 28 22:02:49 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:49 volumio volumio[1012]: info: Loading plugin "squeezelite_mc"... Jan 28 22:02:49 volumio dnsmasq[1199]: started, version 2.80 cachesize 150 Jan 28 22:02:49 volumio dnsmasq[1199]: 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:49 volumio dnsmasq[1199]: warning: interface wlan0 does not currently exist Jan 28 22:02:49 volumio dnsmasq-dhcp[1199]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:49 volumio dnsmasq[1199]: reading /etc/resolv.conf Jan 28 22:02:49 volumio dnsmasq[1199]: using nameserver 208.67.222.222#53 Jan 28 22:02:49 volumio dnsmasq[1199]: using nameserver 208.67.220.220#53 Jan 28 22:02:49 volumio dnsmasq[1199]: read /etc/hosts - 5 addresses Jan 28 22:02:49 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:49 volumio mpd[1177]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:49 volumio mpd[1177]: output: No 'audio_output' defined in config file Jan 28 22:02:49 volumio mpd[1177]: output: Attempt to detect audio output device Jan 28 22:02:49 volumio mpd[1177]: output: Attempting to detect a alsa audio device Jan 28 22:02:49 volumio mpd[1177]: output: Successfully detected a alsa audio device Jan 28 22:02:49 volumio mpd[1177]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:49 volumio mpd[1177]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:49 volumio mpd[1177]: zeroconf: No global port, disabling zeroconf Jan 28 22:02:49 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:49 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "outputs"... Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "albumart"... Jan 28 22:02:50 volumio volumio[1012]: info: Plugin example_plugin is not enabled Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "inputs"... Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "updater_comm"... Jan 28 22:02:50 volumio volumio[1012]: info: Plugin mpdemulation is not enabled Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "rest_api"... Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "websocket"... Jan 28 22:02:50 volumio volumio[1012]: info: Loading plugin "RoonBridge"... Jan 28 22:02:50 volumio volumio[1012]: info: Applying required configuration parameters for plugin RoonBridge Jan 28 22:02:50 volumio volumio[1012]: Forking 3 albumart workers Jan 28 22:02:50 volumio volumio[1012]: info: Loading i18n strings for locale zh Jan 28 22:02:50 volumio volumio[1012]: Updating browse sources language Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::initPlayerControls Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: Express server listening on port 3000 Jan 28 22:02:50 volumio volumio[1012]: [Metrics] WebUI: 7s 15.09ms Jan 28 22:02:50 volumio volumio[1012]: info: CoreStateMachine::resetVolumioState Jan 28 22:02:50 volumio volumio[1012]: info: CoreStateMachine::getcurrentVolume Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:50 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:50 volumio volumio[1012]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:02:50 volumio volumio[1012]: info: Completed loading Core Plugins Jan 28 22:02:50 volumio volumio[1012]: info: Preparing to generate the ALSA configuration file Jan 28 22:02:50 volumio volumio[1012]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:02:50 volumio mpd[1177]: client: [0] opened from local Jan 28 22:02:50 volumio volumio[1012]: info: MPD Permissions set Jan 28 22:02:50 volumio volumio[1012]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:02:50 volumio volumio[1012]: wlan0 Interface doesn't support scanning. Jan 28 22:02:50 volumio volumio[1012]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:02:50 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:02:50 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:50 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:50 volumio volumio[1012]: command failed: No such device (-19) Jan 28 22:02:50 volumio volumio[1012]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:02:50 volumio volumio[1012]: command failed: No such device (-19) Jan 28 22:02:50 volumio volumio[1012]: info: MPD running with PID1177 Jan 28 22:02:50 volumio volumio[1012]: ,establishing connection Jan 28 22:02:50 volumio mpd[1177]: client: [1] opened from local Jan 28 22:02:51 volumio volumio[1012]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:51 volumio volumio[1012]: assert.ok(self.idling) Jan 28 22:02:51 volumio volumio[1012]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:51 volumio volumio[1012]: assert.ok(self.idling) Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:51 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 10. Jan 28 22:02:51 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: Reloading queue from file Jan 28 22:02:51 volumio mpd[1177]: exception: No database Jan 28 22:02:51 volumio dnsmasq[1199]: exiting on receipt of SIGTERM Jan 28 22:02:51 volumio volumio[1012]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:02:51 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:51 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jan 28 22:02:51 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:02:51 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:51 volumio hostapd[1262]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:51 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock Jan 28 22:02:51 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:51 volumio volumio[1012]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:02:51 volumio volumio[1012]: info: Output device has changed, restarting MPD Jan 28 22:02:51 volumio volumio[1012]: info: Output device has changed, restarting Shairport Sync Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio hostapd[1262]: Could not read interface wlan0 flags: No such device Jan 28 22:02:51 volumio hostapd[1262]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:51 volumio hostapd[1262]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:51 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:51 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:51 volumio volumio[1012]: Starting albumart workers Jan 28 22:02:51 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:51 volumio hostapd[1262]: Could not read interface wlan0 flags: No such device Jan 28 22:02:51 volumio hostapd[1262]: nl80211 driver initialization failed. Jan 28 22:02:51 volumio hostapd[1262]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:51 volumio hostapd[1262]: wlan0: AP-DISABLED Jan 28 22:02:51 volumio hostapd[1262]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:51 volumio hostapd[1262]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:51 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:51 volumio volumio[1012]: Starting albumart workers Jan 28 22:02:51 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:51 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:51 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:51 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:51 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:51 volumio dnsmasq[1278]: dnsmasq: syntax check OK. Jan 28 22:02:51 volumio volumio[1012]: Starting albumart workers Jan 28 22:02:51 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:51 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:51 volumio volumio[1012]: info: ___________ START PLUGINS ___________ Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:51 volumio volumio[1012]: info: [1643407371648] CoreMusicLibrary::Adding element Media Servers Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:51 volumio volumio[1012]: Cannot find translation for sourceMedia Servers Jan 28 22:02:51 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:51 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:51 volumio dnsmasq[1289]: started, version 2.80 cachesize 150 Jan 28 22:02:51 volumio dnsmasq[1289]: 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:51 volumio dnsmasq[1289]: warning: interface wlan0 does not currently exist Jan 28 22:02:51 volumio dnsmasq-dhcp[1289]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:51 volumio dnsmasq[1289]: reading /etc/resolv.conf Jan 28 22:02:51 volumio dnsmasq[1289]: using nameserver 208.67.222.222#53 Jan 28 22:02:51 volumio dnsmasq[1289]: using nameserver 208.67.220.220#53 Jan 28 22:02:51 volumio dnsmasq[1289]: read /etc/hosts - 5 addresses Jan 28 22:02:51 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:51 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:51 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:51 volumio volumio[1012]: info: [1643407371893] CoreMusicLibrary::Adding element Last_100 Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:51 volumio volumio[1012]: Cannot find translation for sourceMedia Servers Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:51 volumio volumio[1012]: info: [1643407371900] CoreMusicLibrary::Adding element Webradio Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:51 volumio volumio[1012]: Cannot find translation for sourceMedia Servers Jan 28 22:02:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:02:51 volumio volumio[1012]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:02:51 volumio volumio[1012]: info: Volumio Calling Home Jan 28 22:02:52 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 28 22:02:52 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:52 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:52 volumio volumio[1012]: info: [squeezelite_mc] Proxy server started on port 36665 Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:52 volumio volumio[1012]: info: CoreStateMachine::setRepeat null single undefined Jan 28 22:02:52 volumio volumio[1012]: info: CoreStateMachine::pushState Jan 28 22:02:52 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:52 volumio volumio[1012]: info: CoreStateMachine::setRandom null Jan 28 22:02:52 volumio volumio[1012]: info: CoreStateMachine::pushState Jan 28 22:02:52 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:52 volumio volumio[1012]: error: updateQueue error: null Jan 28 22:02:52 volumio volumio[1012]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 28 22:02:52 volumio volumio[1012]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 22:02:52 volumio volumio[1012]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 22:02:52 volumio volumio[1012]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 22:02:52 volumio volumio[1012]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 22:02:52 volumio volumio[1012]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 22:02:52 volumio volumio[1012]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 22:02:52 volumio volumio[1012]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 22:02:52 volumio volumio[1012]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 22:02:52 volumio volumio[1012]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 22:02:52 volumio volumio[1012]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 22:02:52 volumio volumio[1012]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 22:02:52 volumio volumio[1012]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 22:02:52 volumio volumio[1012]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 22:02:52 volumio volumio[1012]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 22:02:52 volumio volumio[1012]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 22:02:52 volumio volumio[1012]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 22:02:52 volumio volumio[1012]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 22:02:52 volumio volumio[1012]: info: MPD Permissions set Jan 28 22:02:52 volumio volumio[1012]: 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[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:02:52 volumio volumio[1012]: info: CoreStateMachine::pushState Jan 28 22:02:52 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:52 volumio volumio[1012]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:02:52 volumio volumio[1012]: info: CoreStateMachine::pushState Jan 28 22:02:52 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:52 volumio volumio[1012]: info: [squeezelite_mc] Server discovery started Jan 28 22:02:52 volumio volumio[1012]: info: [squeezelite_mc] Player finder started Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:52 volumio volumio[1012]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:02:52 volumio volumio[1012]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:52 volumio volumio[1012]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:02:52 volumio volumio[1012]: at doSend (dgram.js:714:16) Jan 28 22:02:52 volumio volumio[1012]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:02:52 volumio volumio[1012]: at afterDns (dgram.js:660:5) Jan 28 22:02:52 volumio volumio[1012]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:02:52 volumio volumio[1012]: errno: -101, Jan 28 22:02:52 volumio volumio[1012]: code: 'ENETUNREACH', Jan 28 22:02:52 volumio volumio[1012]: syscall: 'send', Jan 28 22:02:52 volumio volumio[1012]: address: '255.255.255.255', Jan 28 22:02:52 volumio volumio[1012]: port: 3483 Jan 28 22:02:52 volumio volumio[1012]: } Jan 28 22:02:52 volumio volumio[1012]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:53 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:01 Jan 28 22:02:53 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:53 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:53 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:53 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 11. Jan 28 22:02:53 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:53 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:02:53 volumio dnsmasq[1289]: exiting on receipt of SIGTERM Jan 28 22:02:53 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:53 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:53 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:53 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 22:02:53 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:53 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:53 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:53 volumio hostapd[1362]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:53 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:53 volumio hostapd[1362]: Could not read interface wlan0 flags: No such device Jan 28 22:02:53 volumio hostapd[1362]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:53 volumio hostapd[1362]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:53 volumio hostapd[1362]: Could not read interface wlan0 flags: No such device Jan 28 22:02:53 volumio hostapd[1362]: nl80211 driver initialization failed. Jan 28 22:02:53 volumio hostapd[1362]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:53 volumio hostapd[1362]: wlan0: AP-DISABLED Jan 28 22:02:53 volumio hostapd[1362]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:53 volumio hostapd[1362]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:53 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:53 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 dnsmasq[1376]: dnsmasq: syntax check OK. Jan 28 22:02:54 volumio systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:02:54 volumio dnsmasq[1392]: started, version 2.80 cachesize 150 Jan 28 22:02:54 volumio dnsmasq[1392]: 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[1392]: warning: interface wlan0 does not currently exist Jan 28 22:02:54 volumio dnsmasq-dhcp[1392]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:54 volumio dnsmasq[1392]: reading /etc/resolv.conf Jan 28 22:02:54 volumio dnsmasq[1392]: using nameserver 208.67.222.222#53 Jan 28 22:02:54 volumio dnsmasq[1392]: using nameserver 208.67.220.220#53 Jan 28 22:02:54 volumio dnsmasq[1392]: read /etc/hosts - 5 addresses Jan 28 22:02:54 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:54 volumio mpd[1310]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:54 volumio mpd[1310]: output: No 'audio_output' defined in config file Jan 28 22:02:54 volumio mpd[1310]: output: Attempt to detect audio output device Jan 28 22:02:54 volumio mpd[1310]: output: Attempting to detect a alsa audio device Jan 28 22:02:54 volumio mpd[1310]: output: Successfully detected a alsa audio device Jan 28 22:02:54 volumio mpd[1310]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:54 volumio mpd[1310]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:54 volumio mpd[1310]: zeroconf: No global port, disabling zeroconf Jan 28 22:02:54 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:55 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 28 22:02:55 volumio sh[385]: timed out Jan 28 22:02:55 volumio dhcpcd[425]: timed out Jan 28 22:02:55 volumio sh[385]: dhcpcd exited Jan 28 22:02:55 volumio dhcpcd[425]: dhcpcd exited Jan 28 22:02:55 volumio sh[385]: ifup: failed to bring up eth0 Jan 28 22:02:55 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:02:55 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 28 22:02:55 volumio ifplugd(eth0)[583]: client: command failed: No such device (-19) Jan 28 22:02:55 volumio ifplugd(eth0)[583]: client: sending commands to master dhcpcd process Jan 28 22:02:55 volumio dhcpcd[1427]: sending commands to master dhcpcd process Jan 28 22:02:55 volumio dhcpcd[454]: control command: /sbin/dhcpcd eth0 Jan 28 22:02:55 volumio dhcpcd[454]: control command: /sbin/dhcpcd eth0 Jan 28 22:02:55 volumio dnsmasq[1392]: reading /etc/resolv.conf Jan 28 22:02:55 volumio dnsmasq[1392]: using nameserver 208.67.222.222#53 Jan 28 22:02:55 volumio dnsmasq[1392]: using nameserver 208.67.220.220#53 Jan 28 22:02:56 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. 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 dhcpcd[454]: timed out Jan 28 22:02:56 volumio dhcpcd[454]: timed out Jan 28 22:02:56 volumio dhcpcd[454]: forked to background, child pid 1456 Jan 28 22:02:56 volumio dhcpcd[454]: forked to background, child pid 1456 Jan 28 22:02:56 volumio systemd[1]: Started DHCP Client Daemon. Jan 28 22:02:56 volumio dnsmasq[1392]: 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[1467]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:56 volumio hostapd[1467]: Could not read interface wlan0 flags: No such device Jan 28 22:02:56 volumio hostapd[1467]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:56 volumio hostapd[1467]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:56 volumio hostapd[1467]: Could not read interface wlan0 flags: No such device Jan 28 22:02:56 volumio hostapd[1467]: nl80211 driver initialization failed. Jan 28 22:02:56 volumio hostapd[1467]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:56 volumio hostapd[1467]: wlan0: AP-DISABLED Jan 28 22:02:56 volumio hostapd[1467]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:56 volumio hostapd[1467]: 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[1474]: dnsmasq: syntax check OK. Jan 28 22:02:56 volumio ifplugd(eth0)[583]: Program executed successfully. Jan 28 22:02:56 volumio ifplugd(eth0)[583]: Link beat lost. Jan 28 22:02:56 volumio ifplugd[534]: Network Interface Plugging Daemon...start eth0...done. Jan 28 22:02:56 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jan 28 22:02:56 volumio volumio[1374]: info: ------------------------------------------- Jan 28 22:02:56 volumio volumio[1374]: info: ----- Volumio3 ---- Jan 28 22:02:56 volumio volumio[1374]: info: ------------------------------------------- Jan 28 22:02:56 volumio volumio[1374]: info: ----- System startup ---- Jan 28 22:02:56 volumio volumio[1374]: info: ------------------------------------------- Jan 28 22:02:56 volumio dnsmasq[1480]: started, version 2.80 cachesize 150 Jan 28 22:02:56 volumio dnsmasq[1480]: 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[1480]: warning: interface wlan0 does not currently exist Jan 28 22:02:56 volumio dnsmasq-dhcp[1480]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:56 volumio dnsmasq[1480]: reading /etc/resolv.conf Jan 28 22:02:56 volumio dnsmasq[1480]: using nameserver 208.67.222.222#53 Jan 28 22:02:56 volumio dnsmasq[1480]: using nameserver 208.67.220.220#53 Jan 28 22:02:56 volumio dnsmasq[1480]: 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:56 volumio dhcpcd[1456]: eth0: carrier acquired Jan 28 22:02:56 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jan 28 22:02:56 volumio dhcpcd[1456]: eth0: IAID 73:5a:e7:92 Jan 28 22:02:56 volumio volumio[1374]: info: MYVOLUMIO Environment detected Jan 28 22:02:57 volumio volumio[1374]: info: Plugin folders cleanup Jan 28 22:02:57 volumio volumio[1374]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category audio_interface Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category miscellanea Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category music_service Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category plugins.json Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category system_controller Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category user_interface Jan 28 22:02:57 volumio volumio[1374]: info: Scanning into folder /data/plugins/ Jan 28 22:02:57 volumio volumio[1374]: info: Scanning category music_service Jan 28 22:02:57 volumio volumio[1374]: info: Plugin folders cleanup completed Jan 28 22:02:57 volumio volumio[1374]: info: ------------------------------------------- Jan 28 22:02:57 volumio volumio[1374]: info: ----- Core plugins startup ---- Jan 28 22:02:57 volumio volumio[1374]: info: ------------------------------------------- Jan 28 22:02:57 volumio volumio[1374]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:57 volumio volumio[1374]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:57 volumio volumio[1374]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:57 volumio volumio[1374]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:57 volumio volumio[1374]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:57 volumio volumio[1374]: info: Loading plugin "system"... Jan 28 22:02:57 volumio volumio[1374]: info: Loading plugin "appearance"... Jan 28 22:02:57 volumio dhcpcd[1456]: eth0: soliciting an IPv6 router Jan 28 22:02:57 volumio ifplugd(eth0)[583]: Link beat detected. Jan 28 22:02:57 volumio dhcpcd[1456]: eth0: rebinding lease of 192.168.0.146 Jan 28 22:02:57 volumio dhcpcd[1456]: eth0: probing address 192.168.0.146/24 Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "network"... Jan 28 22:02:58 volumio volumio[1374]: info: Refreshing Cached IP Addresses Jan 28 22:02:58 volumio sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:58 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "services"... Jan 28 22:02:58 volumio sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:58 volumio sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "alsa_controller"... Jan 28 22:02:58 volumio sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:58 volumio sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio sudo[1495]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:58 volumio sudo[1498]: pam_unix(sudo:session): session closed for user root 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 volumio[1374]: info: Loading plugin "wizard"... Jan 28 22:02:58 volumio dnsmasq[1480]: 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 volumio[1374]: info: Loading plugin "volumio_command_line_client"... 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 volumio[1374]: info: Loading plugin "upnp"... Jan 28 22:02:58 volumio volumio[1374]: info: [1643407378274] Starting Upmpd Daemon Jan 28 22:02:58 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "my_music"... Jan 28 22:02:58 volumio hostapd[1513]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "mpd"... Jan 28 22:02:58 volumio hostapd[1513]: Could not read interface wlan0 flags: No such device Jan 28 22:02:58 volumio hostapd[1513]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:58 volumio hostapd[1513]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:58 volumio hostapd[1513]: Could not read interface wlan0 flags: No such device Jan 28 22:02:58 volumio hostapd[1513]: nl80211 driver initialization failed. Jan 28 22:02:58 volumio hostapd[1513]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:58 volumio hostapd[1513]: wlan0: AP-DISABLED Jan 28 22:02:58 volumio hostapd[1513]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:58 volumio hostapd[1513]: 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[1520]: dnsmasq: syntax check OK. Jan 28 22:02:58 volumio dnsmasq[1526]: started, version 2.80 cachesize 150 Jan 28 22:02:58 volumio dnsmasq[1526]: 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[1526]: warning: interface wlan0 does not currently exist Jan 28 22:02:58 volumio dnsmasq-dhcp[1526]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:58 volumio dnsmasq[1526]: reading /etc/resolv.conf Jan 28 22:02:58 volumio dnsmasq[1526]: using nameserver 208.67.222.222#53 Jan 28 22:02:58 volumio dnsmasq[1526]: using nameserver 208.67.220.220#53 Jan 28 22:02:58 volumio dnsmasq[1526]: read /etc/hosts - 5 addresses Jan 28 22:02:58 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:58 volumio volumio[1374]: info: Creating MPD Configuration file Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "upnp_browser"... Jan 28 22:02:58 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:58 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:58 volumio sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:58 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:58 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:58 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:58 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:58 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:58 volumio volumio[1374]: info: Loading plugin "networkfs"... Jan 28 22:02:59 volumio volumio-remote-updater[459]: [2022-01-28 22:02:59] [connect] Successful connection Jan 28 22:02:59 volumio volumio[1374]: info: Starting Udev Watcher for removable devices Jan 28 22:02:59 volumio volumio[1374]: info: Ignoring mount for partition: boot Jan 28 22:02:59 volumio volumio[1374]: info: Ignoring mount for partition: volumio Jan 28 22:02:59 volumio volumio[1374]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:59 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:59 volumio volumio[1374]: info: Loading plugin "alarm-clock"... Jan 28 22:02:59 volumio volumio[1374]: info: Loading plugin "airplay_emulation"... Jan 28 22:02:59 volumio volumio[1374]: info: Starting Shairport Sync Jan 28 22:02:59 volumio volumio[1374]: info: Loading plugin "last_100"... Jan 28 22:02:59 volumio volumio[1374]: info: Loading plugin "webradio"... Jan 28 22:02:59 volumio volumio[1374]: info: Loading plugin "i2s_dacs"... Jan 28 22:02:59 volumio volumio[1374]: info: I2S DAC not set, start Auto-detection Jan 28 22:02:59 volumio volumio[1374]: info: Loading plugin "volumiodiscovery"... Jan 28 22:02:59 volumio volumio[1374]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:59 volumio volumio[1374]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:59 volumio volumio[1374]: *** WARNING *** For more information see Jan 28 22:02:59 volumio node[1374]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:59 volumio volumio[1374]: *** 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:59 volumio volumio[1374]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:59 volumio volumio[1374]: *** WARNING *** For more information see Jan 28 22:02:59 volumio node[1374]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:59 volumio node[1374]: *** WARNING *** For more information see Jan 28 22:02:59 volumio node[1374]: *** 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:59 volumio node[1374]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:59 volumio node[1374]: *** WARNING *** For more information see Jan 28 22:02:59 volumio volumio[1374]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:02:59 volumio volumio[1374]: info: Discovery: Started advertising with name: Volumio Jan 28 22:03:00 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:03:00 volumio volumio[1374]: info: Loading plugin "squeezelite_mc"... Jan 28 22:03:00 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:03:00 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 14. Jan 28 22:03:00 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:00 volumio dnsmasq[1526]: exiting on receipt of SIGTERM Jan 28 22:03:00 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:03:00 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:00 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:00 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:03:00 volumio hostapd[1548]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:03:00 volumio hostapd[1548]: Could not read interface wlan0 flags: No such device Jan 28 22:03:00 volumio hostapd[1548]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:03:00 volumio hostapd[1548]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:03:00 volumio hostapd[1548]: Could not read interface wlan0 flags: No such device Jan 28 22:03:00 volumio hostapd[1548]: nl80211 driver initialization failed. Jan 28 22:03:00 volumio hostapd[1548]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:03:00 volumio hostapd[1548]: wlan0: AP-DISABLED Jan 28 22:03:00 volumio hostapd[1548]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:03:00 volumio hostapd[1548]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:03:00 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:03:00 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:03:00 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:00 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:00 volumio dnsmasq[1555]: dnsmasq: syntax check OK. Jan 28 22:03:00 volumio mpd[1540]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:03:00 volumio mpd[1540]: output: No 'audio_output' defined in config file Jan 28 22:03:00 volumio mpd[1540]: output: Attempt to detect audio output device Jan 28 22:03:00 volumio mpd[1540]: output: Attempting to detect a alsa audio device Jan 28 22:03:00 volumio mpd[1540]: output: Successfully detected a alsa audio device Jan 28 22:03:00 volumio mpd[1540]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:03:00 volumio mpd[1540]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:03:00 volumio mpd[1540]: zeroconf: No global port, disabling zeroconf Jan 28 22:03:00 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:03:00 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:00 volumio dnsmasq[1563]: started, version 2.80 cachesize 150 Jan 28 22:03:00 volumio dnsmasq[1563]: 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:00 volumio dnsmasq[1563]: warning: interface wlan0 does not currently exist Jan 28 22:03:00 volumio dnsmasq-dhcp[1563]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:03:00 volumio dnsmasq[1563]: reading /etc/resolv.conf Jan 28 22:03:00 volumio dnsmasq[1563]: using nameserver 208.67.222.222#53 Jan 28 22:03:00 volumio dnsmasq[1563]: using nameserver 208.67.220.220#53 Jan 28 22:03:00 volumio dnsmasq[1563]: read /etc/hosts - 5 addresses Jan 28 22:03:00 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:00 volumio volumio[1374]: info: Loading plugin "outputs"... Jan 28 22:03:00 volumio volumio[1374]: info: Loading plugin "albumart"... Jan 28 22:03:00 volumio volumio[1374]: info: Plugin example_plugin is not enabled Jan 28 22:03:00 volumio volumio[1374]: info: Loading plugin "inputs"... Jan 28 22:03:00 volumio volumio[1374]: info: Loading plugin "updater_comm"... Jan 28 22:03:01 volumio volumio[1374]: info: Plugin mpdemulation is not enabled Jan 28 22:03:01 volumio volumio[1374]: info: Loading plugin "rest_api"... Jan 28 22:03:01 volumio volumio[1374]: info: Loading plugin "websocket"... Jan 28 22:03:01 volumio volumio[1374]: info: Loading plugin "RoonBridge"... Jan 28 22:03:01 volumio volumio[1374]: info: Applying required configuration parameters for plugin RoonBridge Jan 28 22:03:01 volumio volumio[1374]: Forking 3 albumart workers Jan 28 22:03:01 volumio volumio[1374]: info: Loading i18n strings for locale zh Jan 28 22:03:01 volumio volumio[1374]: Updating browse sources language Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::initPlayerControls Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: Express server listening on port 3000 Jan 28 22:03:01 volumio volumio[1374]: [Metrics] WebUI: 5s 779.23ms Jan 28 22:03:01 volumio volumio[1374]: info: CoreStateMachine::resetVolumioState Jan 28 22:03:01 volumio volumio[1374]: info: CoreStateMachine::getcurrentVolume Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:03:01 volumio volumio[1374]: info: Completed loading Core Plugins Jan 28 22:03:01 volumio volumio[1374]: info: Preparing to generate the ALSA configuration file Jan 28 22:03:01 volumio volumio[1374]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:03:01 volumio mpd[1540]: client: [0] opened from local Jan 28 22:03:01 volumio volumio[1374]: info: MPD Permissions set Jan 28 22:03:01 volumio volumio[1374]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:03:01 volumio volumio[1374]: wlan0 Interface doesn't support scanning. Jan 28 22:03:01 volumio volumio[1374]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:03:01 volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:03:01 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:01 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:01 volumio volumio[1374]: command failed: No such device (-19) Jan 28 22:03:01 volumio volumio[1374]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:03:01 volumio volumio[1374]: command failed: No such device (-19) Jan 28 22:03:01 volumio volumio-remote-updater[459]: [2022-01-28 22:03:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1643407379 101 Jan 28 22:03:01 volumio volumio[1374]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jan 28 22:03:01 volumio volumio[1374]: info: MPD running with PID1540 Jan 28 22:03:01 volumio volumio[1374]: ,establishing connection Jan 28 22:03:01 volumio mpd[1540]: client: [1] opened from local Jan 28 22:03:01 volumio volumio[1374]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:03:01 volumio volumio[1374]: assert.ok(self.idling) Jan 28 22:03:01 volumio volumio[1374]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:03:01 volumio volumio[1374]: assert.ok(self.idling) Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1374]: info: Reloading queue from file Jan 28 22:03:02 volumio mpd[1540]: exception: No database Jan 28 22:03:02 volumio volumio[1374]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:03:02 volumio volumio[1374]: info: CoreStateMachine::pushState Jan 28 22:03:02 volumio volumio[1374]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:02 volumio volumio[1374]: info: CoreStateMachine::updateTrackBlock Jan 28 22:03:02 volumio volumio[1374]: info: CorePlayQueue::getTrackBlock Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:03:02 volumio volumio[1374]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:03:02 volumio volumio[1374]: info: Output device has changed, restarting MPD Jan 28 22:03:02 volumio volumio[1374]: info: Output device has changed, restarting Shairport Sync Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:02 volumio sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:03:02 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:02 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:02 volumio sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:03:02 volumio sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:02 volumio volumio[1374]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:03:02 volumio volumio[1374]: info: ___________ START PLUGINS ___________ Jan 28 22:03:02 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:03:02 volumio volumio[1374]: info: [1643407382248] CoreMusicLibrary::Adding element Media Servers Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:02 volumio volumio[1374]: Cannot find translation for sourceMedia Servers Jan 28 22:03:02 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:02 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:03:02 volumio volumio[1374]: Starting albumart workers Jan 28 22:03:02 volumio volumio[1374]: Starting albumart workers Jan 28 22:03:02 volumio volumio[1374]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:02 volumio volumio[1374]: Starting albumart workers Jan 28 22:03:02 volumio volumio[1374]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:03:02 volumio volumio[1374]: info: [1643407382426] CoreMusicLibrary::Adding element Last_100 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:02 volumio volumio[1374]: Cannot find translation for sourceMedia Servers Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:03:02 volumio volumio[1374]: info: [1643407382433] CoreMusicLibrary::Adding element Webradio Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:03:02 volumio volumio[1374]: Cannot find translation for sourceMedia Servers Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:03:02 volumio volumio[1374]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:03:02 volumio volumio[1374]: info: Volumio Calling Home Jan 28 22:03:02 volumio sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 28 22:03:02 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:03:02 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:03:02 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 15. Jan 28 22:03:02 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:02 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Jan 28 22:03:02 volumio dnsmasq[1563]: exiting on receipt of SIGTERM Jan 28 22:03:02 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:03:02 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:02 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:02 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:03:02 volumio hostapd[1647]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:03:02 volumio volumio[1374]: info: [squeezelite_mc] Proxy server started on port 46050 Jan 28 22:03:02 volumio hostapd[1647]: Could not read interface wlan0 flags: No such device Jan 28 22:03:02 volumio hostapd[1647]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:03:02 volumio hostapd[1647]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:03:02 volumio volumio[1374]: info: CoreStateMachine::setRepeat null single undefined Jan 28 22:03:02 volumio volumio[1374]: info: CoreStateMachine::pushState Jan 28 22:03:02 volumio hostapd[1647]: Could not read interface wlan0 flags: No such device Jan 28 22:03:02 volumio hostapd[1647]: nl80211 driver initialization failed. Jan 28 22:03:02 volumio hostapd[1647]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:03:02 volumio hostapd[1647]: wlan0: AP-DISABLED Jan 28 22:03:02 volumio hostapd[1647]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:03:02 volumio hostapd[1647]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:03:02 volumio volumio[1374]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:02 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:03:02 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:03:02 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:03:02 volumio volumio[1374]: info: CoreStateMachine::setRandom null Jan 28 22:03:02 volumio volumio[1374]: info: CoreStateMachine::pushState Jan 28 22:03:02 volumio volumio[1374]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:02 volumio volumio[1374]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:02 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:03:02 volumio volumio[1374]: error: updateQueue error: null Jan 28 22:03:02 volumio dnsmasq[1656]: dnsmasq: syntax check OK. Jan 28 22:03:02 volumio volumio[1374]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 28 22:03:02 volumio volumio[1374]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 22:03:02 volumio volumio[1374]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 22:03:02 volumio volumio[1374]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 22:03:02 volumio volumio[1374]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 22:03:02 volumio volumio[1374]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 22:03:02 volumio volumio[1374]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 22:03:02 volumio volumio[1374]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 22:03:02 volumio volumio[1374]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 22:03:02 volumio volumio[1374]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 22:03:02 volumio volumio[1374]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 22:03:02 volumio volumio[1374]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 22:03:02 volumio volumio[1374]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 22:03:02 volumio volumio[1374]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 22:03:02 volumio volumio[1374]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 22:03:02 volumio volumio[1374]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 22:03:02 volumio volumio[1374]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 22:03:02 volumio volumio[1374]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 22:03:03 volumio dnsmasq[1662]: started, version 2.80 cachesize 150 Jan 28 22:03:03 volumio dnsmasq[1662]: 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[1662]: warning: interface wlan0 does not currently exist Jan 28 22:03:03 volumio dnsmasq-dhcp[1662]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:03:03 volumio dnsmasq[1662]: reading /etc/resolv.conf Jan 28 22:03:03 volumio dnsmasq[1662]: using nameserver 208.67.222.222#53 Jan 28 22:03:03 volumio dnsmasq[1662]: using nameserver 208.67.220.220#53 Jan 28 22:03:03 volumio dnsmasq[1662]: read /etc/hosts - 5 addresses Jan 28 22:03:03 volumio volumio[1374]: info: MPD Permissions set Jan 28 22:03:03 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:03:03 volumio volumio[1374]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:03:03 volumio volumio[1374]: info: CoreStateMachine::pushState Jan 28 22:03:03 volumio volumio[1374]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:03 volumio volumio[1374]: info: VolumeController:: Volume=100 Mute =false Jan 28 22:03:03 volumio volumio[1374]: info: CoreStateMachine::pushState Jan 28 22:03:03 volumio volumio[1374]: info: CorePlayQueue::getTrack 0 Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::volumioPushState Jan 28 22:03:03 volumio volumio[1374]: info: [squeezelite_mc] Server discovery started Jan 28 22:03:03 volumio volumio[1374]: info: [squeezelite_mc] Player finder started Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:03:03 volumio volumio[1374]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:03:03 volumio volumio[1374]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:03:03 volumio volumio[1374]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:03:03 volumio volumio[1374]: at doSend (dgram.js:714:16) Jan 28 22:03:03 volumio volumio[1374]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:03:03 volumio volumio[1374]: at afterDns (dgram.js:660:5) Jan 28 22:03:03 volumio volumio[1374]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:03:03 volumio volumio[1374]: errno: -101, Jan 28 22:03:03 volumio volumio[1374]: code: 'ENETUNREACH', Jan 28 22:03:03 volumio volumio[1374]: syscall: 'send', Jan 28 22:03:03 volumio volumio[1374]: address: '255.255.255.255', Jan 28 22:03:03 volumio volumio[1374]: port: 3483 Jan 28 22:03:03 volumio volumio[1374]: } Jan 28 22:03:03 volumio volumio[1374]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:03:03 volumio dhcpcd[1456]: eth0: leased 192.168.0.146 for 36000 seconds Jan 28 22:03:03 volumio dhcpcd[1456]: eth0: adding route to 192.168.0.0/24 Jan 28 22:03:03 volumio dhcpcd[1456]: eth0: adding default route via 192.168.0.1 Jan 28 22:03:03 volumio avahi-daemon[461]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.146. Jan 28 22:03:03 volumio avahi-daemon[461]: New relevant interface eth0.IPv4 for mDNS. Jan 28 22:03:03 volumio avahi-daemon[461]: Registering new address record for 192.168.0.146 on eth0.IPv4. Jan 28 22:03:03 volumio dnsmasq[1662]: reading /etc/resolv.conf Jan 28 22:03:03 volumio dnsmasq[1662]: using nameserver 192.168.0.1#53 Jan 28 22:03:03 volumio dnsmasq[1662]: using nameserver 114.114.114.114#53 Jan 28 22:03:03 volumio dnsmasq[1662]: using nameserver 208.67.222.222#53 Jan 28 22:03:03 volumio dnsmasq[1662]: using nameserver 208.67.220.220#53 Jan 28 22:03:03 volumio ntpd[571]: ntpd exiting on signal 15 (Terminated) Jan 28 22:03:03 volumio systemd[1]: Stopping Network Time Service... Jan 28 22:03:03 volumio systemd[1]: ntp.service: Succeeded. Jan 28 22:03:03 volumio systemd[1]: Stopped Network Time Service. Jan 28 22:03:03 volumio systemd[1]: Starting Network Time Service... Jan 28 22:03:03 volumio ntpd[1743]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:03:03 volumio ntpd[1743]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:03:03 volumio systemd[1]: Started Network Time Service. Jan 28 22:03:03 volumio ntpd[1749]: proto: precision = 0.875 usec (-20) Jan 28 22:03:03 volumio ntpd[1749]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:03:03 volumio ntpd[1749]: 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:03:03 volumio ntpd[1749]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:03:03 volumio ntpd[1749]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:03:03 volumio ntpd[1749]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:03:03 volumio ntpd[1749]: Listen normally on 3 eth0 192.168.0.146:123 Jan 28 22:03:03 volumio ntpd[1749]: Listening on routing socket on fd #20 for interface updates Jan 28 22:03:03 volumio ntpd[1749]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:03:03 volumio ntpd[1749]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:03:04 volumio sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:02 Jan 28 22:03:04 volumio sudo[1752]: 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"