-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Fri 2022-01-28 22:02:40 UTC. -- Jan 28 22:02:24 volumio kernel: phy phy-ff430000.hdmiphy.4: Looking up phy-supply from device tree Jan 28 22:02:24 volumio kernel: phy phy-ff430000.hdmiphy.4: Looking up phy-supply property in node /hdmiphy@ff430000 failed Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: Looking up vcodec-supply from device tree Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: Looking up vcodec-supply property in node /vpu_combo failed Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: no regulator for vcodec Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: failed on clk_get clk_cabac Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: failed on clk_get clk_core Jan 28 22:02:24 volumio kernel: platform ff350000.vpu_service: probe device Jan 28 22:02:24 volumio kernel: platform ff350000.vpu_service: drm allocator with mmu enabled Jan 28 22:02:24 volumio kernel: platform ff351000.avsd: probe device Jan 28 22:02:24 volumio kernel: platform ff351000.avsd: drm allocator with mmu enabled Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: could not find power_model node Jan 28 22:02:24 volumio kernel: rk-vcodec vpu_combo: init success Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: Looking up vcodec-supply from device tree Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: Looking up vcodec-supply property in node /rkvdec@ff36000 failed Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: no regulator for vcodec Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: parent devfreq is disabled Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: probe device Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: drm allocator with mmu enabled Jan 28 22:02:24 volumio kernel: rk-vcodec ff360000.rkvdec: init success Jan 28 22:02:24 volumio kernel: probe device ff330000.h265e Jan 28 22:02:24 volumio kernel: mpp_dev ff330000.h265e: try to get iommu dev ffffffc03e02dc10 Jan 28 22:02:24 volumio kernel: mpp_dev ff330000.h265e: resource ready, register device Jan 28 22:02:24 volumio kernel: probe device ff340000.vepu Jan 28 22:02:24 volumio kernel: mpp_dev ff340000.vepu: try to get iommu dev ffffffc03e02e410 Jan 28 22:02:24 volumio kernel: mpp_dev ff340000.vepu: resource ready, register device Jan 28 22:02:24 volumio kernel: dma-pl330 ff1f0000.dmac: Loaded driver for PL330 DMAC-241330 Jan 28 22:02:24 volumio kernel: dma-pl330 ff1f0000.dmac: DBUFF-128x8bytes Num_Chans-8 Num_Peri-20 Num_Events-16 Jan 28 22:02:24 volumio kernel: Serial: 8250/16550 driver, 5 ports, IRQ sharing disabled Jan 28 22:02:24 volumio kernel: console [ttyS2] disabled Jan 28 22:02:24 volumio kernel: ff130000.serial: ttyS2 at MMIO 0xff130000 (irq = 13, base_baud = 1500000) is a 16550A Jan 28 22:02:24 volumio kernel: console [ttyS2] enabled Jan 28 22:02:24 volumio kernel: bootconsole [uart0] disabled Jan 28 22:02:24 volumio kernel: [drm] Initialized drm 1.1.0 20060810 Jan 28 22:02:24 volumio kernel: [drm] Rockchip DRM driver version: v1.0.1 Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: devfreq is not set Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: bound ff370000.vop (ops 0xffffff8008d02df0) Jan 28 22:02:24 volumio kernel: i2c i2c-4: of_i2c: modalias failure on /hdmi@ff3c0000/ports Jan 28 22:02:24 volumio kernel: dwhdmi-rockchip ff3c0000.hdmi: registered DesignWare HDMI I2C bus driver Jan 28 22:02:24 volumio kernel: dwhdmi-rockchip ff3c0000.hdmi: Detected HDMI TX controller v2.11a with HDCP (inno_dw_hdmi_phy2) Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: bound ff3c0000.hdmi (ops 0xffffff8008cf8c50) Jan 28 22:02:24 volumio kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). Jan 28 22:02:24 volumio kernel: [drm] No driver support for vblank timestamp query. Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: failed to parse display resources Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: No connectors reported connected with modes Jan 28 22:02:24 volumio kernel: [drm] Cannot find any crtc or sizes - going 1024x768 Jan 28 22:02:24 volumio kernel: Console: switching to colour frame buffer device 128x48 Jan 28 22:02:24 volumio kernel: rockchip-drm display-subsystem: fb0: frame buffer device Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver udl Jan 28 22:02:24 volumio kernel: Unable to detect cache hierarchy for CPU 0 Jan 28 22:02:24 volumio kernel: brd: module loaded Jan 28 22:02:24 volumio kernel: loop: module loaded Jan 28 22:02:24 volumio kernel: lkdtm: No crash points registered, enable through debugfs Jan 28 22:02:24 volumio kernel: rockchip-spi ff190000.spi: no high_speed pinctrl state Jan 28 22:02:24 volumio kernel: m25p80 spi32766.0: unrecognized JEDEC id bytes: ff, ff, ff Jan 28 22:02:24 volumio kernel: m25p80: probe of spi32766.0 failed with error -2 Jan 28 22:02:24 volumio kernel: force_sf_dma_mode is ignored if force_thresh_dma_mode is set. Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: Looking up phy-supply from device tree Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: clock input or output? (input). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: TX delay(0x26). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: RX delay(0x11). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: integrated PHY? (no). Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: cannot get clock clk_mac_speed Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: clock input from PHY Jan 28 22:02:24 volumio kernel: rk_gmac-dwmac ff540000.ethernet: init for RGMII Jan 28 22:02:24 volumio kernel: stmmac - user ID: 0x10, Synopsys ID: 0x35 Jan 28 22:02:24 volumio kernel: Ring mode enabled Jan 28 22:02:24 volumio kernel: DMA HW capability register supported Jan 28 22:02:24 volumio kernel: Normal descriptors Jan 28 22:02:24 volumio kernel: RX Checksum Offload Engine supported (type 2) Jan 28 22:02:24 volumio kernel: Wake-Up On Lan supported Jan 28 22:02:24 volumio kernel: Enable RX Mitigation via HW Watchdog Timer Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'snps,reset-gpio' property of node '/ethernet@ff540000[0]' - status (0) Jan 28 22:02:24 volumio kernel: libphy: stmmac: probed Jan 28 22:02:24 volumio kernel: eth%d: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active Jan 28 22:02:24 volumio kernel: eth%d: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01) Jan 28 22:02:24 volumio kernel: Rockchip WiFi SYS interface (V1.00) ... Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver cdc_ether Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver rndis_host Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.2: u3phy u2 power on Jan 28 22:02:24 volumio kernel: phy phy-ff470000.usb3-phy.3: u3phy u3 power on Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply from device tree Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply property in node /usb@ff580000 failed Jan 28 22:02:24 volumio kernel: ff580000.usb supply vusb_d not found, using dummy regulator Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply from device tree Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply property in node /usb@ff580000 failed Jan 28 22:02:24 volumio kernel: ff580000.usb supply vusb_a not found, using dummy regulator Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: DWC OTG Controller Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: new USB bus registered, assigned bus number 1 Jan 28 22:02:24 volumio kernel: dwc2 ff580000.usb: irq 41, io mem 0xff580000 Jan 28 22:02:24 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Jan 28 22:02:24 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb1: Product: DWC OTG Controller Jan 28 22:02:24 volumio kernel: usb usb1: Manufacturer: Linux 4.4.154 dwc2_hsotg Jan 28 22:02:24 volumio kernel: usb usb1: SerialNumber: ff580000.usb Jan 28 22:02:24 volumio kernel: hub 1-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 1-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Jan 28 22:02:24 volumio kernel: ehci-pci: EHCI PCI platform driver Jan 28 22:02:24 volumio kernel: ehci-platform: EHCI generic platform driver Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: EHCI Host Controller Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: new USB bus registered, assigned bus number 2 Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: irq 42, io mem 0xff5c0000 Jan 28 22:02:24 volumio kernel: ehci-platform ff5c0000.usb: USB 2.0 started, EHCI 1.00 Jan 28 22:02:24 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 Jan 28 22:02:24 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb2: Product: EHCI Host Controller Jan 28 22:02:24 volumio kernel: usb usb2: Manufacturer: Linux 4.4.154 ehci_hcd Jan 28 22:02:24 volumio kernel: usb usb2: SerialNumber: ff5c0000.usb Jan 28 22:02:24 volumio kernel: hub 2-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 2-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Jan 28 22:02:24 volumio kernel: ohci-platform: OHCI generic platform driver Jan 28 22:02:24 volumio kernel: ohci-platform ff5d0000.usb: Generic Platform OHCI controller Jan 28 22:02:24 volumio kernel: ohci-platform ff5d0000.usb: new USB bus registered, assigned bus number 3 Jan 28 22:02:24 volumio kernel: ohci-platform ff5d0000.usb: irq 43, io mem 0xff5d0000 Jan 28 22:02:24 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0001 Jan 28 22:02:24 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb3: Product: Generic Platform OHCI controller Jan 28 22:02:24 volumio kernel: usb usb3: Manufacturer: Linux 4.4.154 ohci_hcd Jan 28 22:02:24 volumio kernel: usb usb3: SerialNumber: ff5d0000.usb Jan 28 22:02:24 volumio kernel: hub 3-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 3-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 4 Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x00210010 Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: irq 179, io mem 0xff600000 Jan 28 22:02:24 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002 Jan 28 22:02:24 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb4: Product: xHCI Host Controller Jan 28 22:02:24 volumio kernel: usb usb4: Manufacturer: Linux 4.4.154 xhci-hcd Jan 28 22:02:24 volumio kernel: usb usb4: SerialNumber: xhci-hcd.9.auto Jan 28 22:02:24 volumio kernel: hub 4-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 4-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Jan 28 22:02:24 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc2 Jan 28 22:02:24 volumio kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 5 Jan 28 22:02:24 volumio kernel: usb usb5: We don't know the algorithms for LPM for this host, disabling LPM. Jan 28 22:02:24 volumio kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0003 Jan 28 22:02:24 volumio kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:02:24 volumio kernel: usb usb5: Product: xHCI Host Controller Jan 28 22:02:24 volumio kernel: usb usb5: Manufacturer: Linux 4.4.154 xhci-hcd Jan 28 22:02:24 volumio kernel: usb usb5: SerialNumber: xhci-hcd.9.auto Jan 28 22:02:24 volumio kernel: hub 5-0:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 5-0:1.0: 1 port detected Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver iforce Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver xpad Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver usbtouchscreen Jan 28 22:02:24 volumio kernel: i2c /dev entries driver Jan 28 22:02:24 volumio kernel: rk808 1-0018: Pmic Chip id: 0x8050 Jan 28 22:02:24 volumio kernel: rk808 1-0018: source: on=0x40, off=0x00 Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc1-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG1: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.5 err -2 Jan 28 22:02:24 volumio kernel: vdd_logic: 712 <--> 1450 mV at 1100 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc2-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG2: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.6 err -2 Jan 28 22:02:24 volumio kernel: vdd_arm: 712 <--> 1450 mV at 1100 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc3-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG3: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.7 err -2 Jan 28 22:02:24 volumio kernel: vcc_ddr: at 5000 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: unable to resolve supply Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jan 28 22:02:24 volumio kernel: DCDC_REG4: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.8 err -2 Jan 28 22:02:24 volumio kernel: vcc_io: 3300 mV Jan 28 22:02:24 volumio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Jan 28 22:02:24 volumio kernel: vcc_sd: supplied by vcc_io Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jan 28 22:02:24 volumio kernel: LDO_REG1: supplied by vcc_io Jan 28 22:02:24 volumio kernel: vcc_io: could not add device link regulator.9 err -2 Jan 28 22:02:24 volumio kernel: vdd_18: 1800 mV Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jan 28 22:02:24 volumio kernel: LDO_REG2: supplied by vcc_io Jan 28 22:02:24 volumio kernel: vcc_io: could not add device link regulator.10 err -2 Jan 28 22:02:24 volumio kernel: vcc18_emmc: 1800 mV Jan 28 22:02:24 volumio kernel: rk808 1-0018: Looking up vcc6-supply from device tree Jan 28 22:02:24 volumio kernel: LDO_REG3: supplied by vcc_sys Jan 28 22:02:24 volumio kernel: vcc_sys: could not add device link regulator.11 err -2 Jan 28 22:02:24 volumio kernel: vdd_10: 1000 mV Jan 28 22:02:24 volumio kernel: rk808 1-0018: register rk8050 regulators Jan 28 22:02:24 volumio kernel: gpiochip_find_base: found new base at 510 Jan 28 22:02:24 volumio kernel: gpiochip_add_data: registered GPIOs 510 to 511 on device: rk8xx-gpio Jan 28 22:02:24 volumio kernel: rk8xx-gpio rk8xx-gpio: register rk8050 gpio successful Jan 28 22:02:24 volumio kernel: input: rk8xx_pwrkey as /devices/platform/ff160000.i2c/i2c-1/1-0018/input/input0 Jan 28 22:02:24 volumio kernel: rk808-rtc rk808-rtc: rtc core: registered rk808-rtc as rtc0 Jan 28 22:02:24 volumio kernel: rk3x-i2c ff160000.i2c: Initialized RK3xxx I2C bus at ffffff8009a76000 Jan 28 22:02:24 volumio kernel: IR NEC protocol handler initialized Jan 28 22:02:24 volumio kernel: IR RC5(x/sz) protocol handler initialized Jan 28 22:02:24 volumio kernel: IR RC6 protocol handler initialized Jan 28 22:02:24 volumio kernel: IR JVC protocol handler initialized Jan 28 22:02:24 volumio kernel: IR Sony protocol handler initialized Jan 28 22:02:24 volumio kernel: IR SANYO protocol handler initialized Jan 28 22:02:24 volumio kernel: 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: mmc_host mmc0: Bus speed (slot 0) = 200000000Hz (slot req 200000000Hz, actual 200000000HZ div = 0) Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/power-led[0]' - status (0) Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/switches/usb-switch[0]' - status (0) Jan 28 22:02:24 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 28 22:02:24 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver usbhid Jan 28 22:02:24 volumio kernel: usbhid: USB HID core driver Jan 28 22:02:24 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jan 28 22:02:24 volumio kernel: rk3328-codec ff410000.codec: spk_depop_time use default value. Jan 28 22:02:24 volumio kernel: u32 classifier Jan 28 22:02:24 volumio kernel: Netfilter messages via NETLINK v0.30. Jan 28 22:02:24 volumio kernel: ip_set: protocol 6 Jan 28 22:02:24 volumio kernel: Initializing XFRM netlink socket Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 10 Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 17 Jan 28 22:02:24 volumio kernel: NET: Registered protocol family 15 Jan 28 22:02:24 volumio kernel: bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. Jan 28 22:02:24 volumio kernel: Bridge firewalling registered Jan 28 22:02:24 volumio kernel: Bluetooth: RFCOMM socket layer initialized Jan 28 22:02:24 volumio kernel: Bluetooth: RFCOMM ver 1.11 Jan 28 22:02:24 volumio kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2 Jan 28 22:02:24 volumio kernel: Bluetooth: HIDP socket layer initialized Jan 28 22:02:24 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 28 22:02:24 volumio kernel: [WLAN_RFKILL]: Enter rfkill_wlan_init Jan 28 22:02:24 volumio kernel: [BT_RFKILL]: Enter rfkill_rk_init Jan 28 22:02:24 volumio kernel: Key type dns_resolver registered Jan 28 22:02:24 volumio kernel: Registered cp15_barrier emulation handler Jan 28 22:02:24 volumio kernel: Registered setend emulation handler Jan 28 22:02:24 volumio kernel: registered taskstats version 1 Jan 28 22:02:24 volumio kernel: Btrfs loaded, integrity-checker=on Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running btrfs free space cache tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running extent only tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running bitmap only tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running bitmap and extent tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running space stealing from bitmap to extent Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Free space cache tests finished Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running extent buffer operation tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running btrfs_split_item tests Jan 28 22:02:24 volumio kernel: BTRFS: selftest: Running find delalloc tests Jan 28 22:02:24 volumio kernel: usb 4-1: new high-speed USB device number 2 using xhci-hcd 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: 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 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: 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: Key type encrypted registered Jan 28 22:02:24 volumio kernel: pvtm list NULL Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/sound[0]' Jan 28 22:02:24 volumio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/sound[0]' Jan 28 22:02:24 volumio kernel: 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: usb 5-1: New USB device found, idVendor=05e3, idProduct=0612 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: usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 28 22:02:24 volumio kernel: usb 5-1: Product: USB3.1 Hub Jan 28 22:02:24 volumio kernel: usb 5-1: Manufacturer: GenesysLogic Jan 28 22:02:24 volumio kernel: hub 5-1:1.0: USB hub found Jan 28 22:02:24 volumio kernel: hub 5-1:1.0: 4 ports detected Jan 28 22:02:24 volumio kernel: of_cfs_init Jan 28 22:02:24 volumio kernel: of_cfs_init: OK Jan 28 22:02:24 volumio kernel: vcc_sd: disabling Jan 28 22:02:24 volumio kernel: ALSA device list: Jan 28 22:02:24 volumio kernel: #0: I2S Jan 28 22:02:24 volumio kernel: #1: 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: New USB device found, idVendor=20b1, idProduct=3086 Jan 28 22:02:24 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jan 28 22:02:24 volumio kernel: usb 1-1: Product: Singxer USB Audio 2.0 G3 Jan 28 22:02:24 volumio kernel: usb 1-1: Manufacturer: Singxer Jan 28 22:02:24 volumio kernel: hid-generic 0003:20B1:3086.0001: hiddev0,hidraw0: USB HID v1.10 Device [Singxer Singxer USB Audio 2.0 G3] on usb-ff580000.usb-1/input3 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, 61 bits of entropy available) Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 61 bits of entropy available) Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 61 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, 62 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on udev Kernel Socket. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 62 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on initctl Compatibility Named Pipe. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 62 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, 62 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on RPCbind Server Activation Socket. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 62 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Socket. Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 62 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Mounting RPC Pipe File System... Jan 28 22:02:24 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read, 62 bits of entropy available) Jan 28 22:02:24 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Jan 28 22:02:24 volumio systemd[1]: Mounting Huge Pages File System... 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]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. 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 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 28 22:02:24 volumio systemd[1]: Started dynamicswap service. Jan 28 22:02:24 volumio systemd[1]: Listening on Journal Socket (/dev/log). Jan 28 22:02:24 volumio systemd[1]: Starting Journal Service... Jan 28 22:02:24 volumio systemd[1]: Created slice User and Session Slice. Jan 28 22:02:24 volumio systemd[1]: Reached target System Time Synchronized. Jan 28 22:02:24 volumio systemd[1]: Reached target Slices. Jan 28 22:02:24 volumio systemd[1]: Starting Restore / save the current clock... 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]: Reached target Swap. Jan 28 22:02:24 volumio systemd[1]: Created slice system-serial\x2dgetty.slice. Jan 28 22:02:24 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jan 28 22:02:24 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Jan 28 22:02:24 volumio systemd[1]: Mounting Kernel Debug File System... Jan 28 22:02:24 volumio systemd[1]: Starting Load Kernel Modules... Jan 28 22:02:24 volumio systemd[1]: Mounting POSIX Message Queue File System... Jan 28 22:02:24 volumio systemd[1]: Mounted RPC Pipe File System. Jan 28 22:02:24 volumio systemd[1]: Started Remount Root and Kernel File Systems. Jan 28 22:02:24 volumio systemd[1]: Mounted Huge Pages File System. 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 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-journald[279]: Journal started Jan 28 22:02:24 volumio systemd-journald[279]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 7.5M, max 30.0M, 22.5M free. Jan 28 22:02:24 volumio fake-hwclock[285]: 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 Kernel Modules. Jan 28 22:02:24 volumio systemd[1]: Mounted POSIX Message Queue File System. Jan 28 22:02:24 volumio systemd[1]: Starting Apply Kernel Variables... 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 Create System Users... Jan 28 22:02:24 volumio systemd[1]: Starting Load/Save Random Seed... 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]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 28 22:02:24 volumio systemd[1]: Started Apply Kernel Variables. Jan 28 22:02:24 volumio systemd[1]: Mounted Kernel Configuration File System. Jan 28 22:02:24 volumio systemd[1]: Mounted FUSE Control File System. Jan 28 22:02:24 volumio systemd[1]: Started Load/Save Random Seed. Jan 28 22:02:24 volumio systemd[1]: Started Create System Users. Jan 28 22:02:24 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Jan 28 22:02:24 volumio systemd[1]: Started udev Coldplug all Devices. Jan 28 22:02:24 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 28 22:02:24 volumio systemd[1]: Started Create Static Device Nodes in /dev. Jan 28 22:02:24 volumio systemd[1]: Reached target Local File Systems (Pre). Jan 28 22:02:24 volumio systemd[1]: 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]: Mounting /var/spool/cups... Jan 28 22:02:24 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 28 22:02:24 volumio systemd[1]: Mounting /tmp... Jan 28 22:02:24 volumio systemd[1]: Starting udev Kernel Device Manager... Jan 28 22:02:24 volumio systemd[1]: Mounted /var/log. Jan 28 22:02:24 volumio systemd[1]: Mounted /var/spool/cups. Jan 28 22:02:24 volumio systemd[1]: Mounted /tmp. 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 324 (plymouthd). Jan 28 22:02:24 volumio systemd-journald[279]: Runtime journal (/run/log/journal/b15109d4193808c9249f0e6761f4677c) is 7.5M, max 30.0M, 22.5M free. 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]: 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]: Started Forward Password Requests to Plymouth Directory Watch. Jan 28 22:02:24 volumio systemd[1]: Found device /dev/ttyS2. Jan 28 22:02:24 volumio systemd-udevd[320]: Using default interface naming scheme 'v240'. Jan 28 22:02:24 volumio systemd-udevd[320]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:02:24 volumio systemd-udevd[320]: link_config: could not get ethtool features for eth0 Jan 28 22:02:24 volumio systemd-udevd[320]: Could not set offload features of eth0: Device or resource busy Jan 28 22:02:24 volumio systemd-udevd[313]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 28 22:02:24 volumio kernel: I : [File] : drivers/gpu/arm/mali400/mali/linux/mali_kernel_linux.c; [Line] : 417; [Func] : mali_module_init(); svn_rev_string_from_arm of this mali_ko is '', rk_ko_ver is '5', built at '18:16:11', on 'Jan 23 2021'. Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: mali_platform_device->num_resources = 9 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[0].start = 0x0x00000000ff300000 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[1].start = 0x0x00000000ff300000 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[2].start = 0x0x0000000000000012 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[3].start = 0x0x0000000000000013 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[4].start = 0x0x0000000000000014 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[5].start = 0x0x0000000000000015 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[6].start = 0x0x0000000000000016 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[7].start = 0x0x0000000000000017 Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: resource[8].start = 0x0x0000000000000018 Jan 28 22:02:24 volumio kernel: D : [File] : drivers/gpu/arm/mali400/mali/platform/rk/rk.c; [Line] : 623; [Func] : mali_platform_device_init(); to add platform_specific_data to platform_device_of_mali. Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Looking up mali-supply from device tree Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Failed to get leakage Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Looking up mali-supply from device tree Jan 28 22:02:24 volumio kernel: vdd_logic: could not add device link ff300000.gpu err -17 Jan 28 22:02:24 volumio kernel: vdd_logic: Failed to create debugfs directory Jan 28 22:02:24 volumio kernel: mali-utgard ff300000.gpu: Failed to get pvtm Jan 28 22:02:24 volumio kernel: 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 kernel: devfreq ff300000.gpu: Couldn't update frequency transition information. 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]: Found device /dev/disk/by-uuid/7FB8-6D8F. Jan 28 22:02:25 volumio systemd-udevd[318]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:02:25 volumio systemd-udevd[321]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. 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 Rebuild Hardware Database 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 Set Up Additional Binary Formats 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[377]: fsck.fat 4.1 (2017-01-24) Jan 28 22:02:25 volumio systemd-fsck[377]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 28 22:02:25 volumio systemd-fsck[377]: Automatically removing dirty bit. Jan 28 22:02:25 volumio systemd-fsck[377]: Performing changes. Jan 28 22:02:25 volumio systemd-fsck[377]: /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]: Starting Tell Plymouth To Write Out Runtime Data... 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]: Started ifup for eth0. Jan 28 22:02:25 volumio systemd[1]: Starting Create Volatile Files and Directories... Jan 28 22:02:25 volumio systemd[1]: Received SIGRTMIN+20 from PID 324 (plymouthd). Jan 28 22:02:25 volumio systemd[1]: plymouth-read-write.service: Succeeded. Jan 28 22:02:25 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 28 22:02:25 volumio systemd[1]: nfs-config.service: Succeeded. Jan 28 22:02:25 volumio systemd[1]: Started Preprocess NFS configuration. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 28 22:02:25 volumio systemd[1]: Reached target NFS client services. Jan 28 22:02:25 volumio sh[384]: command failed: No such device (-19) Jan 28 22:02:25 volumio systemd[1]: Started Create Volatile Files and Directories. Jan 28 22:02:25 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 28 22:02:25 volumio systemd[1]: Starting RPC bind portmap service... Jan 28 22:02:25 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 28 22:02:25 volumio systemd[1]: 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 lircd.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]: Listening on triggerhappy.socket. Jan 28 22:02:25 volumio systemd[1]: Listening on mpd.socket. Jan 28 22:02:25 volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2025-05-27 12:31:05 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 Tue 2025-05-27 12:31:05 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 Avahi mDNS/DNS-SD Stack Activation Socket. Jan 28 22:02:25 volumio systemd[1]: Reached target Sockets. Jan 28 22:02:25 volumio systemd[1]: Reached target Basic System. Jan 28 22:02:25 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 28 22:02:25 volumio sh[384]: eth0: waiting for carrier Jan 28 22:02:25 volumio sh[384]: eth0: carrier acquired Jan 28 22:02:25 volumio dhcpcd[406]: eth0: waiting for carrier Jan 28 22:02:25 volumio dhcpcd[406]: eth0: carrier acquired Jan 28 22:02:25 volumio systemd[1]: Started D-Bus System Message Bus. Jan 28 22:02:25 volumio sh[384]: DUID 00:01:00:01:29:87:24:72:ee:d8:fd:35:36:8b Jan 28 22:02:25 volumio dhcpcd[406]: DUID 00:01:00:01:29:87:24:72:ee:d8:fd:35:36:8b Jan 28 22:02:25 volumio sh[384]: eth0: IAID fd:35:36:8b Jan 28 22:02:25 volumio dhcpcd[406]: eth0: IAID fd:35:36:8b Jan 28 22:02:25 volumio sh[384]: eth0: adding address fe80::ecd8:fdff:fe35:368b Jan 28 22:02:25 volumio dhcpcd[406]: eth0: adding address fe80::ecd8:fdff:fe35:368b Jan 28 22:02:25 volumio sh[384]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio dhcpcd[406]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio thd[419]: Unable to parse trigger line: Jan 28 22:02:25 volumio thd[419]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 28 22:02:25 volumio thd[419]: Unable to parse trigger line: Jan 28 22:02:25 volumio thd[419]: Unable to parse trigger line: Jan 28 22:02:25 volumio thd[419]: Found socket passed from systemd Jan 28 22:02:25 volumio systemd[1]: Starting WPA supplicant... Jan 28 22:02:25 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jan 28 22:02:25 volumio systemd[1]: Started Volumio Iptables Module. 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 alsactl[425]: alsactl 1.1.8 daemon started Jan 28 22:02:25 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 28 22:02:25 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 28 22:02:25 volumio systemd[1]: Starting DHCP Client Daemon... Jan 28 22:02:25 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 28 22:02:25 volumio systemd[1]: Reached target Timers. Jan 28 22:02:25 volumio systemd[1]: Started volumio-remote-updater.service. Jan 28 22:02:25 volumio systemd[1]: Starting Wireless Services... Jan 28 22:02:25 volumio systemd[1]: Started UPnP Renderer front-end to MPD. 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]: Started RPC bind portmap service. Jan 28 22:02:25 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jan 28 22:02:25 volumio systemd[1]: Reached target RPC Port Mapper. Jan 28 22:02:25 volumio systemd[1]: Reached target Remote File Systems (Pre). Jan 28 22:02:25 volumio systemd[1]: Reached target Remote File Systems. Jan 28 22:02:25 volumio alsactl[425]: No state is present for card G3 Jan 28 22:02:25 volumio alsactl[426]: No state is present for card G3 Jan 28 22:02:25 volumio alsactl[425]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3086" "" "" Jan 28 22:02:25 volumio alsactl[425]: Hardware is initialized using a generic method Jan 28 22:02:25 volumio alsactl[425]: No state is present for card G3 Jan 28 22:02:25 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 28 22:02:25 volumio alsactl[426]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3086" "" "" Jan 28 22:02:25 volumio alsactl[426]: Hardware is initialized using a generic method Jan 28 22:02:25 volumio alsactl[426]: No state is present for card G3 Jan 28 22:02:25 volumio systemd[1]: Started Save/Restore Sound Card State. Jan 28 22:02:25 volumio systemd[1]: Reached target Sound Card. Jan 28 22:02:25 volumio dhcpcd[428]: DUID 00:01:00:01:29:87:24:72:ee:d8:fd:35:36:8b Jan 28 22:02:25 volumio dhcpcd[428]: eth0: IAID fd:35:36:8b Jan 28 22:02:25 volumio dhcpcd[428]: eth0: adding address fe80::ecd8:fdff:fe35:368b Jan 28 22:02:25 volumio dhcpcd[428]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio dhcpcd[428]: DUID 00:01:00:01:29:87:24:72:ee:d8:fd:35:36:8b Jan 28 22:02:25 volumio dhcpcd[428]: eth0: IAID fd:35:36:8b Jan 28 22:02:25 volumio dhcpcd[428]: eth0: adding address fe80::ecd8:fdff:fe35:368b Jan 28 22:02:25 volumio dhcpcd[428]: ipv6_addaddr1: Permission denied Jan 28 22:02:25 volumio systemd[1]: Started WPA supplicant. Jan 28 22:02:25 volumio wpa_supplicant[422]: Successfully initialized wpa_supplicant Jan 28 22:02:25 volumio dhcpcd[428]: eth0: soliciting an IPv6 router Jan 28 22:02:25 volumio dhcpcd[428]: eth0: soliciting an IPv6 router Jan 28 22:02:25 volumio avahi-daemon[427]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Jan 28 22:02:25 volumio avahi-daemon[427]: Successfully dropped root privileges. Jan 28 22:02:25 volumio avahi-daemon[427]: avahi-daemon 0.7 starting up. Jan 28 22:02:25 volumio systemd-logind[432]: New seat seat0. Jan 28 22:02:25 volumio systemd-logind[432]: Watching system buttons on /dev/input/event0 (rk8xx_pwrkey) Jan 28 22:02:25 volumio systemd[1]: Started Login Service. Jan 28 22:02:25 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 28 22:02:25 volumio avahi-daemon[427]: Successfully called chroot(). Jan 28 22:02:25 volumio avahi-daemon[427]: Successfully dropped remaining capabilities. Jan 28 22:02:25 volumio avahi-daemon[427]: No service file found in /etc/avahi/services. Jan 28 22:02:25 volumio avahi-daemon[427]: Network interface enumeration completed. Jan 28 22:02:25 volumio avahi-daemon[427]: Server startup complete. Host name is volumio.local. Local service cookie is 1398667180. Jan 28 22:02:26 volumio kernel: nf_tables: (c) 2007-2009 Patrick McHardy Jan 28 22:02:26 volumio kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Jan 28 22:02:26 volumio kernel: nf_conntrack version 0.5.0 (7879 buckets, 31516 max) Jan 28 22:02:26 volumio sh[384]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:26 volumio dhcpcd[406]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:26 volumio loadcpufreq[435]: Loading cpufreq kernel modules...done (none). Jan 28 22:02:26 volumio dhcpcd[428]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:26 volumio dhcpcd[428]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:26 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 28 22:02:26 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 28 22:02:26 volumio volumio-remote-updater[429]: Error: No active session Jan 28 22:02:26 volumio volumio-remote-updater[429]: [2022-01-28 22:02:26] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:02:26 volumio volumio-remote-updater[429]: [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[429]: [2022-01-28 22:02:26] [error] handle_connect error: Underlying Transport Error Jan 28 22:02:26 volumio volumio[431]: 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 sh[384]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio dhcpcd[406]: eth0: soliciting an IPv6 router Jan 28 22:02:26 volumio lircd-setup[423]: /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:26 volumio lircd-setup[423]: parser = configparser.SafeConfigParser() Jan 28 22:02:26 volumio systemd[1]: lircd-setup.service: Succeeded. Jan 28 22:02:26 volumio systemd[1]: Started lircd(8) initialization helper tool. Jan 28 22:02:26 volumio cpufrequtils[472]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 28 22:02:26 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. 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 kernel: usb 5-1.1: Manufacturer: Genesyslogic Jan 28 22:02:26 volumio kernel: usb 5-1.1: SerialNumber: 0000000000000014 Jan 28 22:02:26 volumio kernel: random: nonblocking pool is initialized 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: usbcore: registered new interface driver uas Jan 28 22:02:27 volumio systemd[1]: iptables.service: Succeeded. Jan 28 22:02:27 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 28 22:02:27 volumio systemd[1]: Starting Raise network interfaces... Jan 28 22:02:27 volumio ifup[503]: command failed: No such device (-19) Jan 28 22:02:27 volumio ifup[503]: command failed: No such device (-19) Jan 28 22:02:27 volumio ifup[503]: command failed: No such device (-19) Jan 28 22:02:27 volumio systemd[1]: Started Raise network interfaces. Jan 28 22:02:27 volumio systemd[1]: Reached target Network. Jan 28 22:02:27 volumio systemd[1]: Starting Permit User Sessions... Jan 28 22:02:27 volumio haveged[400]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Jan 28 22:02:27 volumio haveged[400]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Jan 28 22:02:27 volumio haveged[400]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00132 Jan 28 22:02:27 volumio haveged[400]: haveged: fills: 0, generated: 0 Jan 28 22:02:27 volumio systemd[1]: Starting Network Time Service... Jan 28 22:02:27 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:27 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 28 22:02:27 volumio systemd[1]: Starting Flexible IR remote input/output application support... Jan 28 22:02:27 volumio systemd[1]: Reached target Network is Online. Jan 28 22:02:27 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jan 28 22:02:27 volumio systemd[1]: Starting Samba NMB Daemon... Jan 28 22:02:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 22:02:27 volumio lircd-0.10.1[616]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Version: lircd 0.10.1 Jan 28 22:02:27 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jan 28 22:02:27 volumio lircd-0.10.1[616]: 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 systemd[1]: Started Permit User Sessions. Jan 28 22:02:27 volumio systemd[1]: Started /etc/rc.local Compatibility. Jan 28 22:02:27 volumio systemd[1]: Starting Hold until boot process finishes up... Jan 28 22:02:27 volumio dhcpcd[428]: eth0: carrier lost Jan 28 22:02:27 volumio sh[384]: eth0: carrier lost Jan 28 22:02:27 volumio dhcpcd[428]: eth0: carrier lost Jan 28 22:02:27 volumio dhcpcd[406]: eth0: carrier lost Jan 28 22:02:27 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 28 22:02:27 volumio ntpd[614]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:02:27 volumio ntpd[614]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:02:27 volumio systemd[1]: plymouth-start.service: Succeeded. Jan 28 22:02:27 volumio systemd[1]: Started Network Time Service. 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 ntpd[639]: proto: precision = 0.875 usec (-20) 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 324 (n/a). Jan 28 22:02:27 volumio systemd[1]: Received SIGRTMIN+21 from PID 324 (n/a). Jan 28 22:02:27 volumio ntpd[639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:02:27 volumio ntpd[639]: 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[639]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:02:27 volumio ntpd[639]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:02:27 volumio ntpd[639]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:02:27 volumio ntpd[639]: Listening on routing socket on fd #19 for interface updates Jan 28 22:02:27 volumio ntpd[639]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:27 volumio ntpd[639]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:27 volumio systemd[1]: Started Serial Getty on ttyS2. Jan 28 22:02:27 volumio sh[384]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory 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 lircd-0.10.1[616]: Info: Initial device: auto Jan 28 22:02:27 volumio lircd-0.10.1[616]: Info: Initial device: auto Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: driver: devinput Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: Notice: Using systemd fd Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: Warning: Running as root Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: output: /var/run/lirc/lircd Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: nodaemon: 1 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: logfile: syslog Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: immediate-init: 0 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: permission: 666 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: driver-options: Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: listen: 0 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: connect: (null) Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: userelease: 0 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: effective_user: (null) Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: release_suffix: _EVUP Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: allow_simulate: 0 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: repeat_max: 600 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Options: dynamic_codes: (null) Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Current driver: devinput Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Driver API version: 4 Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Driver version: 0.10.0 Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: Info: Using remote: devinput-64. Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Jan 28 22:02:27 volumio lircd-0.10.1[616]: Info: lircd: Opening log, level: Info Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: Using systemd fd Jan 28 22:02:27 volumio lircd-0.10.1[616]: Warning: Running as root Jan 28 22:02:27 volumio lircd-0.10.1[616]: Info: Using remote: devinput-64. Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: Info: Using remote: devinput-32. Jan 28 22:02:27 volumio lircd-0.10.1[616]: 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[616]: 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[616]: 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[616]: 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[616]: 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[616]: Info: Using remote: devinput-32. Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: 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[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: lircd-0.10.1[616]: 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[616]: 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[616]: 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[616]: lircd-0.10.1[616]: 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[616]: 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[616]: 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[616]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Jan 28 22:02:27 volumio ifplugd(eth0)[673]: ifplugd 0.28 initializing. Jan 28 22:02:27 volumio ifplugd(eth0)[673]: Using interface eth0/EE:D8:FD:35:36:8B with driver (version: March_2013) Jan 28 22:02:27 volumio ifplugd(eth0)[673]: Using detection mode: SIOCETHTOOL Jan 28 22:02:27 volumio ifplugd(eth0)[673]: Initialization complete, link beat not detected. Jan 28 22:02:27 volumio systemd[1]: Started Flexible IR remote input/output application support. Jan 28 22:02:27 volumio lircd[616]: lircd-0.10.1[616]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Jan 28 22:02:27 volumio lircd-0.10.1[616]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Jan 28 22:02:27 volumio sh[384]: cat: /run/dhcpcd/resolv.conf.eth0.dhcp: No such file or directory Jan 28 22:02:27 volumio ifplugd[617]: Network Interface Plugging Daemon...start eth0...done. Jan 28 22:02:27 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jan 28 22:02:28 volumio wireless.js[430]: WIRELESS DAEMON: start Jan 28 22:02:28 volumio wireless.js[430]: WIRELESS: Loaded configuration Jan 28 22:02:28 volumio wireless.js[430]: Cleaning previous... Jan 28 22:02:28 volumio nmbd[630]: [2022/01/28 22:02:28.204355, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 28 22:02:28 volumio nmbd[630]: started asyncdns process 690 Jan 28 22:02:28 volumio nmbd[630]: [2022/01/28 22:02:28.206999, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 28 22:02:28 volumio nmbd[630]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 28 22:02:28 volumio nmbd[630]: [2022/01/28 22:02:28.207168, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 28 22:02:28 volumio nmbd[630]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 28 22:02:28 volumio sudo[689]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:02:28 volumio sudo[689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:28 volumio sudo[689]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:28 volumio wireless.js[430]: Stopped aP Jan 28 22:02:28 volumio sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:02:28 volumio sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:28 volumio sudo[701]: 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[715]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:28 volumio ntpd[639]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 22:02:28 volumio hostapd[715]: Could not read interface wlan0 flags: No such device Jan 28 22:02:28 volumio hostapd[715]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:28 volumio hostapd[715]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:28 volumio hostapd[715]: Could not read interface wlan0 flags: No such device Jan 28 22:02:28 volumio hostapd[715]: nl80211 driver initialization failed. Jan 28 22:02:28 volumio hostapd[715]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:28 volumio hostapd[715]: wlan0: AP-DISABLED Jan 28 22:02:28 volumio hostapd[715]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:28 volumio hostapd[715]: 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[722]: dnsmasq: syntax check OK. Jan 28 22:02:28 volumio iw[723]: 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[742]: started, version 2.80 cachesize 150 Jan 28 22:02:28 volumio dnsmasq[742]: 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[742]: warning: interface wlan0 does not currently exist Jan 28 22:02:28 volumio dnsmasq-dhcp[742]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:28 volumio dnsmasq[742]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:28 volumio dnsmasq[742]: read /etc/hosts - 5 addresses Jan 28 22:02:28 volumio volumio-streaming-daemon[730]: ############################ Jan 28 22:02:28 volumio volumio-streaming-daemon[730]: # Volumio Streaming Daemon # Jan 28 22:02:28 volumio volumio-streaming-daemon[730]: # Running on port 7777 # Jan 28 22:02:28 volumio volumio-streaming-daemon[730]: ############################ Jan 28 22:02:28 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:28 volumio volumio-streaming-daemon[730]: Environment: production Jan 28 22:02:28 volumio systemd[1]: Reached target Host and Network Name Lookups. 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 ntpd[639]: Soliciting pool server 192.168.211.1 Jan 28 22:02:30 volumio mpd[685]: Jan 28 22:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:02:30 volumio mpd[685]: Jan 28 22:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:30 volumio mpd[685]: Jan 28 22:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 28 22:02:30 volumio systemd[1]: Started Music Player Daemon. Jan 28 22:02:30 volumio ntpd[639]: Soliciting pool server 192.168.211.1 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:30 volumio dnsmasq[742]: exiting on receipt of SIGTERM Jan 28 22:02:30 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:30 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:30 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:30 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:31 volumio hostapd[758]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:31 volumio hostapd[758]: Could not read interface wlan0 flags: No such device Jan 28 22:02:31 volumio hostapd[758]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:31 volumio hostapd[758]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:31 volumio hostapd[758]: Could not read interface wlan0 flags: No such device Jan 28 22:02:31 volumio hostapd[758]: nl80211 driver initialization failed. Jan 28 22:02:31 volumio hostapd[758]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:31 volumio hostapd[758]: wlan0: AP-DISABLED Jan 28 22:02:31 volumio hostapd[758]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:31 volumio hostapd[758]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:31 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:31 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:31 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:31 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:31 volumio dnsmasq[765]: dnsmasq: syntax check OK. Jan 28 22:02:31 volumio dnsmasq[771]: started, version 2.80 cachesize 150 Jan 28 22:02:31 volumio dnsmasq[771]: 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[771]: warning: interface wlan0 does not currently exist Jan 28 22:02:31 volumio dnsmasq-dhcp[771]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:31 volumio dnsmasq[771]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:31 volumio dnsmasq[771]: 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-remote-updater[429]: [2022-01-28 22:02:31] [connect] Successful connection Jan 28 22:02:31 volumio volumio[729]: info: ------------------------------------------- Jan 28 22:02:31 volumio volumio[729]: info: ----- Volumio3 ---- Jan 28 22:02:31 volumio volumio[729]: info: ------------------------------------------- Jan 28 22:02:31 volumio volumio[729]: info: ----- System startup ---- Jan 28 22:02:31 volumio volumio[729]: info: ------------------------------------------- Jan 28 22:02:31 volumio dhcpcd[428]: eth0: carrier acquired Jan 28 22:02:31 volumio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jan 28 22:02:31 volumio dhcpcd[428]: eth0: carrier acquired Jan 28 22:02:31 volumio sh[384]: eth0: carrier acquired Jan 28 22:02:31 volumio dhcpcd[406]: eth0: carrier acquired Jan 28 22:02:31 volumio dhcpcd[428]: eth0: IAID fd:35:36:8b Jan 28 22:02:31 volumio dhcpcd[428]: eth0: IAID fd:35:36:8b Jan 28 22:02:31 volumio sh[384]: eth0: IAID fd:35:36:8b Jan 28 22:02:31 volumio dhcpcd[406]: eth0: IAID fd:35:36:8b Jan 28 22:02:31 volumio ntpd[639]: Soliciting pool server 192.168.211.1 Jan 28 22:02:31 volumio dhcpcd[428]: eth0: soliciting an IPv6 router Jan 28 22:02:31 volumio dhcpcd[428]: eth0: soliciting an IPv6 router Jan 28 22:02:31 volumio ifplugd(eth0)[673]: Link beat detected. Jan 28 22:02:31 volumio sh[384]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:31 volumio dhcpcd[406]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:32 volumio ifplugd(eth0)[673]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 28 22:02:32 volumio ifplugd(eth0)[673]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 28 22:02:32 volumio dhcpcd[428]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:32 volumio dhcpcd[428]: eth0: rebinding lease of 192.168.3.121 Jan 28 22:02:32 volumio sh[384]: eth0: soliciting an IPv6 router Jan 28 22:02:32 volumio dhcpcd[406]: eth0: soliciting an IPv6 router Jan 28 22:02:32 volumio volumio[729]: info: MYVOLUMIO Environment detected Jan 28 22:02:32 volumio volumio[729]: info: Plugin folders cleanup Jan 28 22:02:32 volumio volumio[729]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:02:32 volumio volumio[729]: info: Scanning category audio_interface Jan 28 22:02:32 volumio volumio[729]: info: Scanning category miscellanea Jan 28 22:02:32 volumio volumio[729]: info: Scanning category music_service Jan 28 22:02:32 volumio volumio[729]: info: Scanning category plugins.json Jan 28 22:02:32 volumio volumio[729]: info: Scanning category system_controller Jan 28 22:02:32 volumio volumio[729]: info: Scanning category user_interface Jan 28 22:02:32 volumio volumio[729]: info: Scanning into folder /data/plugins/ Jan 28 22:02:32 volumio volumio[729]: info: Scanning category music_service Jan 28 22:02:32 volumio volumio[729]: info: Plugin folders cleanup completed Jan 28 22:02:32 volumio volumio[729]: info: ------------------------------------------- Jan 28 22:02:32 volumio volumio[729]: info: ----- Core plugins startup ---- Jan 28 22:02:32 volumio volumio[729]: info: ------------------------------------------- Jan 28 22:02:32 volumio volumio[729]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:02:32 volumio volumio[729]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:02:32 volumio volumio[729]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:02:32 volumio volumio[729]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:02:32 volumio volumio[729]: info: Loading plugins from folder /data/plugins/ Jan 28 22:02:32 volumio volumio[729]: info: Loading plugin "system"... Jan 28 22:02:32 volumio volumio[729]: 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[771]: 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[798]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:33 volumio hostapd[798]: Could not read interface wlan0 flags: No such device Jan 28 22:02:33 volumio hostapd[798]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:33 volumio hostapd[798]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:33 volumio hostapd[798]: Could not read interface wlan0 flags: No such device Jan 28 22:02:33 volumio hostapd[798]: nl80211 driver initialization failed. Jan 28 22:02:33 volumio hostapd[798]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:33 volumio hostapd[798]: wlan0: AP-DISABLED Jan 28 22:02:33 volumio hostapd[798]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:33 volumio hostapd[798]: 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[805]: dnsmasq: syntax check OK. Jan 28 22:02:33 volumio dnsmasq[811]: started, version 2.80 cachesize 150 Jan 28 22:02:33 volumio dnsmasq[811]: 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[811]: warning: interface wlan0 does not currently exist Jan 28 22:02:33 volumio dnsmasq-dhcp[811]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:33 volumio dnsmasq[811]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:33 volumio dnsmasq[811]: 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[729]: info: Loading plugin "network"... Jan 28 22:02:34 volumio volumio[729]: info: Refreshing Cached IP Addresses Jan 28 22:02:34 volumio sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:02:34 volumio sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio sudo[818]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "services"... Jan 28 22:02:34 volumio sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:02:34 volumio sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "alsa_controller"... Jan 28 22:02:34 volumio sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:02:34 volumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio sudo[820]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio sudo[823]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "wizard"... Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:02:34 volumio volumio[729]: info: Plugin upnp is not enabled Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "my_music"... Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "mpd"... Jan 28 22:02:34 volumio volumio[729]: info: Creating MPD Configuration file Jan 28 22:02:34 volumio volumio[729]: info: Plugin upnp_browser is not enabled Jan 28 22:02:34 volumio volumio[729]: info: Loading plugin "networkfs"... Jan 28 22:02:34 volumio sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:34 volumio sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:34 volumio sudo[833]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:34 volumio sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:34 volumio sudo[835]: 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 sh[384]: eth0: probing address 192.168.3.121/24 Jan 28 22:02:35 volumio dhcpcd[406]: eth0: probing address 192.168.3.121/24 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 volumio[729]: info: Starting Udev Watcher for removable devices Jan 28 22:02:35 volumio volumio[729]: info: Ignoring mount for partition: boot Jan 28 22:02:35 volumio volumio[729]: info: Ignoring mount for partition: volumio Jan 28 22:02:35 volumio volumio[729]: info: Ignoring mount for partition: volumio_data Jan 28 22:02:35 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:35 volumio volumio[729]: info: Loading plugin "alarm-clock"... 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[811]: 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[846]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:35 volumio hostapd[846]: Could not read interface wlan0 flags: No such device Jan 28 22:02:35 volumio hostapd[846]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:35 volumio hostapd[846]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:35 volumio hostapd[846]: Could not read interface wlan0 flags: No such device Jan 28 22:02:35 volumio hostapd[846]: nl80211 driver initialization failed. Jan 28 22:02:35 volumio hostapd[846]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:35 volumio hostapd[846]: wlan0: AP-DISABLED Jan 28 22:02:35 volumio hostapd[846]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:35 volumio hostapd[846]: 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[853]: dnsmasq: syntax check OK. Jan 28 22:02:35 volumio dnsmasq[859]: started, version 2.80 cachesize 150 Jan 28 22:02:35 volumio dnsmasq[859]: 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[859]: warning: interface wlan0 does not currently exist Jan 28 22:02:35 volumio dnsmasq-dhcp[859]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:35 volumio dnsmasq[859]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:35 volumio dnsmasq[859]: read /etc/hosts - 5 addresses Jan 28 22:02:35 volumio volumio[729]: info: Plugin airplay_emulation is not enabled Jan 28 22:02:35 volumio volumio[729]: info: Loading plugin "last_100"... Jan 28 22:02:35 volumio volumio[729]: info: Loading plugin "webradio"... Jan 28 22:02:35 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:36 volumio volumio[729]: info: Loading plugin "i2s_dacs"... Jan 28 22:02:36 volumio volumio[729]: info: I2S DAC not set, start Auto-detection Jan 28 22:02:36 volumio volumio[729]: info: Loading plugin "volumiodiscovery"... Jan 28 22:02:36 volumio volumio[729]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:36 volumio volumio[729]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio volumio[729]: *** WARNING *** For more information see Jan 28 22:02:36 volumio node[729]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:02:36 volumio volumio[729]: *** 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[729]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio volumio[729]: *** WARNING *** For more information see Jan 28 22:02:36 volumio node[729]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio node[729]: *** WARNING *** For more information see Jan 28 22:02:36 volumio node[729]: *** 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[729]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:02:36 volumio node[729]: *** WARNING *** For more information see Jan 28 22:02:36 volumio volumio[729]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:02:36 volumio volumio[729]: info: Discovery: Started advertising with name: Volumio Jan 28 22:02:36 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:02:36 volumio volumio[729]: info: Loading plugin "squeezelite_mc"... Jan 28 22:02:37 volumio mpd[841]: 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[841]: Jan 28 22:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 28 22:02:37 volumio mpd[841]: 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[835]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:37 volumio dhcpcd[428]: eth0: probing address 192.168.3.121/24 Jan 28 22:02:37 volumio dhcpcd[428]: eth0: probing address 192.168.3.121/24 Jan 28 22:02:37 volumio volumio[729]: info: Loading plugin "outputs"... Jan 28 22:02:37 volumio volumio[729]: info: Loading plugin "albumart"... Jan 28 22:02:37 volumio volumio[729]: info: Plugin example_plugin is not enabled Jan 28 22:02:37 volumio volumio[729]: info: Loading plugin "inputs"... Jan 28 22:02:37 volumio volumio[729]: info: Loading plugin "updater_comm"... Jan 28 22:02:37 volumio volumio[729]: info: Plugin mpdemulation is not enabled Jan 28 22:02:37 volumio volumio[729]: info: Loading plugin "rest_api"... Jan 28 22:02:37 volumio volumio[729]: info: Loading plugin "websocket"... Jan 28 22:02:37 volumio volumio[729]: info: Plugin RoonBridge is not enabled Jan 28 22:02:37 volumio volumio[729]: info: Loading i18n strings for locale zh Jan 28 22:02:37 volumio volumio[729]: Updating browse sources language Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::initPlayerControls Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:37 volumio volumio[729]: Express server listening on port 3000 Jan 28 22:02:37 volumio volumio[729]: [Metrics] WebUI: 6s 995.26ms Jan 28 22:02:37 volumio volumio[729]: info: CoreStateMachine::resetVolumioState Jan 28 22:02:37 volumio volumio[729]: info: CoreStateMachine::getcurrentVolume Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:37 volumio volumio[729]: info: CoreStateMachine::pushState Jan 28 22:02:37 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:37 volumio volumio[729]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 22:02:37 volumio volumio[729]: info: Completed loading Core Plugins Jan 28 22:02:37 volumio volumio[729]: info: Preparing to generate the ALSA configuration file Jan 28 22:02:37 volumio volumio[729]: Forking 3 albumart workers Jan 28 22:02:37 volumio volumio[729]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Jan 28 22:02:37 volumio volumio[729]: info: MPD Permissions set Jan 28 22:02:37 volumio volumio[729]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 22:02:37 volumio volumio[729]: wlan0 Interface doesn't support scanning. Jan 28 22:02:37 volumio volumio[729]: info: Cannot use regular scanning, forcing with ap-force Jan 28 22:02:37 volumio sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 22:02:37 volumio sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:37 volumio sudo[901]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:37 volumio volumio[729]: command failed: No such device (-19) Jan 28 22:02:37 volumio volumio[729]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 22:02:37 volumio volumio[729]: command failed: No such device (-19) Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 4. Jan 28 22:02:38 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:38 volumio volumio[729]: info: MPD running with PID841 Jan 28 22:02:38 volumio volumio[729]: ,establishing connection Jan 28 22:02:38 volumio dnsmasq[859]: exiting on receipt of SIGTERM Jan 28 22:02:38 volumio systemd[1]: dnsmasq.service: Succeeded. Jan 28 22:02:38 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:38 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:38 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 28 22:02:38 volumio hostapd[911]: Configuration file: /etc/hostapd/hostapd.conf Jan 28 22:02:38 volumio hostapd[911]: Could not read interface wlan0 flags: No such device Jan 28 22:02:38 volumio hostapd[911]: nl80211: Driver does not support authentication/association or connect commands Jan 28 22:02:38 volumio hostapd[911]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 28 22:02:38 volumio volumio[729]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:38 volumio volumio[729]: assert.ok(self.idling) Jan 28 22:02:38 volumio volumio[729]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:02:38 volumio volumio[729]: assert.ok(self.idling) Jan 28 22:02:38 volumio hostapd[911]: Could not read interface wlan0 flags: No such device Jan 28 22:02:38 volumio hostapd[911]: nl80211 driver initialization failed. Jan 28 22:02:38 volumio hostapd[911]: wlan0: interface state UNINITIALIZED->DISABLED Jan 28 22:02:38 volumio hostapd[911]: wlan0: AP-DISABLED Jan 28 22:02:38 volumio hostapd[911]: wlan0: CTRL-EVENT-TERMINATING Jan 28 22:02:38 volumio hostapd[911]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jan 28 22:02:38 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jan 28 22:02:38 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 28 22:02:38 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 22:02:38 volumio dnsmasq[919]: dnsmasq: syntax check OK. Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: Reloading queue from file Jan 28 22:02:38 volumio volumio[729]: info: Asound.conf file unchanged, so no further update is needed Jan 28 22:02:38 volumio volumio[729]: info: Output device has changed, restarting MPD Jan 28 22:02:38 volumio dnsmasq[935]: started, version 2.80 cachesize 150 Jan 28 22:02:38 volumio dnsmasq[935]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 28 22:02:38 volumio dnsmasq[935]: warning: interface wlan0 does not currently exist Jan 28 22:02:38 volumio dnsmasq-dhcp[935]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 28 22:02:38 volumio dnsmasq[935]: no servers found in /etc/resolv.conf, will retry Jan 28 22:02:38 volumio dnsmasq[935]: read /etc/hosts - 5 addresses Jan 28 22:02:38 volumio volumio[729]: info: ___________ START PLUGINS ___________ Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:38 volumio volumio[729]: info: [1643407358529] CoreMusicLibrary::Adding element Last_100 Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:02:38 volumio volumio[729]: info: [1643407358534] CoreMusicLibrary::Adding element Webradio Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:02:38 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:02:38 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:38 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:02:38 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:38 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Jan 28 22:02:38 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 22:02:38 volumio volumio[729]: info: [squeezelite_mc] Starting proxy server... Jan 28 22:02:38 volumio volumio[729]: info: Volumio Calling Home Jan 28 22:02:38 volumio systemd[1]: Stopping Music Player Daemon... Jan 28 22:02:38 volumio systemd[1]: mpd.service: Succeeded. Jan 28 22:02:38 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 22:02:38 volumio systemd[1]: Starting Music Player Daemon... Jan 28 22:02:38 volumio volumio[729]: info: [squeezelite_mc] Proxy server started on port 38685 Jan 28 22:02:38 volumio volumio[729]: Starting albumart workers Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:02:38 volumio volumio[729]: info: CoreStateMachine::pushState Jan 28 22:02:38 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Jan 28 22:02:38 volumio volumio[729]: Starting albumart workers Jan 28 22:02:38 volumio volumio[729]: info: [squeezelite_mc] Server discovery started Jan 28 22:02:38 volumio volumio[729]: info: [squeezelite_mc] Player finder started Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:02:38 volumio volumio[729]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 28 22:02:38 volumio volumio[729]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:39 volumio volumio[729]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 28 22:02:39 volumio volumio[729]: at doSend (dgram.js:714:16) Jan 28 22:02:39 volumio volumio[729]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Jan 28 22:02:39 volumio volumio[729]: at afterDns (dgram.js:660:5) Jan 28 22:02:39 volumio volumio[729]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Jan 28 22:02:39 volumio volumio[729]: errno: -101, Jan 28 22:02:39 volumio volumio[729]: code: 'ENETUNREACH', Jan 28 22:02:39 volumio volumio[729]: syscall: 'send', Jan 28 22:02:39 volumio volumio[729]: address: '255.255.255.255', Jan 28 22:02:39 volumio volumio[729]: port: 3483 Jan 28 22:02:39 volumio volumio[729]: } Jan 28 22:02:39 volumio volumio[729]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:02:39 volumio volumio[729]: Starting albumart workers Jan 28 22:02:39 volumio sh[384]: eth0: leased 192.168.3.121 for 86400 seconds Jan 28 22:02:39 volumio sh[384]: eth0: adding route to 192.168.3.0/24 Jan 28 22:02:39 volumio sh[384]: eth0: adding default route via 192.168.3.1 Jan 28 22:02:39 volumio sh[384]: eth0: dhcp_envoption 213: Operation not supported Jan 28 22:02:39 volumio dhcpcd[406]: eth0: leased 192.168.3.121 for 86400 seconds Jan 28 22:02:39 volumio dhcpcd[406]: eth0: adding route to 192.168.3.0/24 Jan 28 22:02:39 volumio dhcpcd[406]: eth0: adding default route via 192.168.3.1 Jan 28 22:02:39 volumio dhcpcd[406]: eth0: dhcp_envoption 213: Operation not supported Jan 28 22:02:39 volumio dhcpcd[406]: eth0: dhcp_envoption 213: Operation not supported Jan 28 22:02:39 volumio avahi-daemon[427]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.121. Jan 28 22:02:39 volumio avahi-daemon[427]: New relevant interface eth0.IPv4 for mDNS. Jan 28 22:02:39 volumio avahi-daemon[427]: Registering new address record for 192.168.3.121 on eth0.IPv4. Jan 28 22:02:39 volumio dnsmasq[935]: reading /etc/resolv.conf Jan 28 22:02:39 volumio dnsmasq[935]: using nameserver 192.168.3.1#53 Jan 28 22:02:39 volumio dnsmasq[935]: using nameserver 208.67.222.222#53 Jan 28 22:02:39 volumio dnsmasq[935]: using nameserver 208.67.220.220#53 Jan 28 22:02:39 volumio sh[384]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Jan 28 22:02:40 volumio ntpd[639]: ntpd exiting on signal 15 (Terminated) Jan 28 22:02:40 volumio systemd[1]: Stopping Network Time Service... Jan 28 22:02:40 volumio systemd[1]: ntp.service: Succeeded. Jan 28 22:02:40 volumio systemd[1]: Stopped Network Time Service. Jan 28 22:02:40 volumio systemd[1]: Starting Network Time Service... Jan 28 22:02:40 volumio sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-28 22:01 Jan 28 22:02:40 volumio sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:02:40 volumio ntpd[1019]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:02:40 volumio ntpd[1019]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 28 22:02:40 volumio systemd[1]: Started Network Time Service. Jan 28 22:02:40 volumio ntpd[1028]: proto: precision = 0.875 usec (-20) Jan 28 22:02:40 volumio ntpd[1028]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:02:40 volumio sh[384]: forked to background, child pid 1029 Jan 28 22:02:40 volumio dhcpcd[406]: forked to background, child pid 1029 Jan 28 22:02:40 volumio ntpd[1028]: 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:40 volumio ntpd[1028]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:02:40 volumio ntpd[1028]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:02:40 volumio ntpd[1028]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:02:40 volumio ntpd[1028]: Listen normally on 3 eth0 192.168.3.121:123 Jan 28 22:02:40 volumio ntpd[1028]: Listening on routing socket on fd #20 for interface updates Jan 28 22:02:40 volumio ntpd[1028]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:02:40 volumio ntpd[1028]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized 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"