-- Logs begin at Sun 2024-04-21 11:44:28 UTC, end at Sun 2024-04-21 11:46:51 UTC. -- Apr 21 11:45:01 volumio kernel: failed to power up DHD generic adapter, 3 retry left Apr 21 11:45:01 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:01 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:01 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:01 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:01 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:01 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:01 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:01 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:01 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:02 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:02 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:02 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:02 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:02 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:02 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:02 volumio kernel: [sdio_reinit] finish Apr 21 11:45:04 volumio kernel: failed to power up DHD generic adapter, 2 retry left Apr 21 11:45:04 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:04 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:04 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:04 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:04 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:05 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:05 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:05 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:05 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:05 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:05 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:05 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:05 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:05 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:05 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:05 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:05 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:06 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:06 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:06 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:06 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:06 volumio kernel: [sdio_reinit] finish Apr 21 11:45:08 volumio kernel: failed to power up DHD generic adapter, 1 retry left Apr 21 11:45:08 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:08 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:08 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:08 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:08 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:08 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:08 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:08 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:09 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:09 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:09 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:09 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:09 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:09 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:09 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:09 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:09 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:09 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:09 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:09 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:09 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:09 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:09 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:09 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:09 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:09 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:10 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:10 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:10 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:10 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:10 volumio kernel: [sdio_reinit] finish Apr 21 11:45:12 volumio kernel: failed to power up DHD generic adapter, 0 retry left Apr 21 11:45:12 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:12 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:12 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:12 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:12 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:12 volumio kernel: failed to power up DHD generic adapter, max retry reached** Apr 21 11:45:12 volumio kernel: unregister wifi platform drivers Apr 21 11:45:12 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:12 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:12 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:12 volumio kernel: ======== dhd_wlan_deinit_plat_data ======== Apr 21 11:45:12 volumio kernel: dhd_module_init: Failed to load the driver, try cnt 1 Apr 21 11:45:12 volumio kernel: ======== dhd_wlan_init_plat_data ======== Apr 21 11:45:12 volumio kernel: dhd_wlan_init_gpio: GPIO(WL_REG_ON) = -1 Apr 21 11:45:12 volumio kernel: dhd_wlan_init_gpio: GPIO(WL_HOST_WAKE) = -1 Apr 21 11:45:12 volumio kernel: dhd_wlan_init_gpio: host_oob_irq: 100 Apr 21 11:45:12 volumio kernel: dhd_wlan_init_gpio: host_oob_irq_flags=0x414 Apr 21 11:45:12 volumio kernel: dhd_wifi_platform_load: Enter Apr 21 11:45:12 volumio kernel: Power-up adapter 'DHD generic adapter' Apr 21 11:45:12 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:12 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:12 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:12 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:13 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:13 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:13 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:13 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:13 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:13 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:13 volumio kernel: [sdio_reinit] finish Apr 21 11:45:15 volumio kernel: failed to power up DHD generic adapter, 3 retry left Apr 21 11:45:15 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:15 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:15 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:15 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:15 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:16 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:16 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:16 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:16 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:16 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:16 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:16 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:16 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:16 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:16 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:16 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:17 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:17 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:17 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:17 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:17 volumio kernel: [sdio_reinit] finish Apr 21 11:45:19 volumio kernel: failed to power up DHD generic adapter, 2 retry left Apr 21 11:45:19 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:19 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:19 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:19 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:19 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:19 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:19 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:19 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:19 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:20 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:20 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:20 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:20 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:20 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:20 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:20 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:20 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:20 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:20 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:20 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:20 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:20 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:20 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:20 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:20 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:20 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:21 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:21 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:21 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:21 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:21 volumio kernel: [sdio_reinit] finish Apr 21 11:45:23 volumio kernel: failed to power up DHD generic adapter, 1 retry left Apr 21 11:45:23 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:23 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:23 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:23 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:23 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:23 volumio kernel: wifi_platform_set_power = 1 Apr 21 11:45:23 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 21 11:45:23 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:23 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 21 11:45:24 volumio kernel: wifi_platform_bus_enumerate device present 1 Apr 21 11:45:24 volumio kernel: ======== Card detection to detect SDIO card! ======== Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:5 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:55 Apr 21 11:45:24 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Apr 21 11:45:24 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Apr 21 11:45:24 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:1 Apr 21 11:45:24 volumio kernel: sdio: error -110 whilst initialising MMC card Apr 21 11:45:24 volumio kernel: [sdio_reinit] finish Apr 21 11:45:26 volumio kernel: failed to power up DHD generic adapter, 0 retry left Apr 21 11:45:26 volumio kernel: wifi_platform_set_power = 0 Apr 21 11:45:26 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 21 11:45:26 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:26 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:26 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:26 volumio kernel: failed to power up DHD generic adapter, max retry reached** Apr 21 11:45:26 volumio kernel: unregister wifi platform drivers Apr 21 11:45:27 volumio kernel: wifi_platform_bus_enumerate device present 0 Apr 21 11:45:27 volumio kernel: ======== Card detection to remove SDIO card! ======== Apr 21 11:45:27 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 21 11:45:27 volumio kernel: ======== dhd_wlan_deinit_plat_data ======== Apr 21 11:45:27 volumio kernel: dhd_module_init: Failed to load the driver, try cnt 0 Apr 21 11:45:27 volumio kernel: dhd_module_init: Failed to load driver max retry reached** Apr 21 11:45:27 volumio kernel: dhd_module_init: Exit err=-19 Apr 21 11:45:27 volumio systemd-modules-load[2897]: Failed to insert 'dhd': No such device Apr 21 11:45:27 volumio systemd-modules-load[2897]: Failed to find module 'snd_soc_pcm5102' Apr 21 11:45:27 volumio systemd-modules-load[2897]: Failed to find module 'snd_soc_odroid_dac' Apr 21 11:45:27 volumio systemd[1]: systemd-modules-load.service: main process exited, code=exited, status=1/FAILURE Apr 21 11:45:27 volumio systemd[1]: Failed to start Load Kernel Modules. Apr 21 11:45:27 volumio systemd[1]: Unit systemd-modules-load.service entered failed state. Apr 21 11:45:27 volumio systemd[1]: Mounting Configuration File System... Apr 21 11:45:27 volumio systemd[1]: Starting Apply Kernel Variables... Apr 21 11:45:27 volumio systemd[1]: Mounted Configuration File System. Apr 21 11:45:27 volumio systemd[1]: Started Apply Kernel Variables. Apr 21 11:45:27 volumio systemd[1]: Starting LSB: Raise network interfaces.... Apr 21 11:45:27 volumio kernel: libphy: set driving length c Apr 21 11:45:27 volumio kernel: libphy: set PLL minimum jitter Apr 21 11:45:27 volumio kernel: libphy: set driving length c Apr 21 11:45:27 volumio kernel: libphy: set PLL minimum jitter Apr 21 11:45:29 volumio kernel: libphy: stmmac-0:08 - Link is Up - 100/Full Apr 21 11:45:29 volumio kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Apr 21 11:45:29 volumio networking[4398]: Configuring network interfaces...done. Apr 21 11:45:29 volumio systemd[1]: Started LSB: Raise network interfaces.. Apr 21 11:45:29 volumio systemd[1]: Starting ifup for wlan0... Apr 21 11:45:29 volumio systemd[1]: Started ifup for wlan0. Apr 21 11:45:29 volumio systemd[1]: Starting ifup for eth0... Apr 21 11:45:29 volumio systemd[1]: Started ifup for eth0. Apr 21 11:45:29 volumio systemd[1]: Starting Network. Apr 21 11:45:29 volumio systemd[1]: Reached target Network. Apr 21 11:45:29 volumio systemd[1]: Starting Network is Online. Apr 21 11:45:29 volumio systemd[1]: Reached target Network is Online. Apr 21 11:45:29 volumio systemd[1]: Starting LSB: RPC portmapper replacement... Apr 21 11:45:29 volumio ifup[4483]: dhcpcd[4505]: version 6.0.5 starting Apr 21 11:45:29 volumio dhcpcd[4505]: version 6.0.5 starting Apr 21 11:45:29 volumio dhcpcd[4505]: eth0: soliciting a DHCP lease Apr 21 11:45:29 volumio ifup[4483]: dhcpcd[4505]: eth0: soliciting a DHCP lease Apr 21 11:45:29 volumio rpcbind[4500]: Starting rpcbind daemon.... Apr 21 11:45:29 volumio systemd[1]: Started LSB: RPC portmapper replacement. Apr 21 11:45:29 volumio systemd[1]: Starting RPC Port Mapper. Apr 21 11:45:29 volumio systemd[1]: Reached target RPC Port Mapper. Apr 21 11:45:29 volumio systemd[1]: Starting LSB: NFS support files common to client and server... Apr 21 11:45:30 volumio rpc.statd[4583]: Version 1.2.8 starting Apr 21 11:45:30 volumio sm-notify[4584]: Version 1.2.8 starting Apr 21 11:45:30 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Apr 21 11:45:30 volumio nfs-common[4577]: Starting NFS common utilities: statd idmapd. Apr 21 11:45:30 volumio systemd[1]: Started LSB: NFS support files common to client and server. Apr 21 11:45:30 volumio systemd[1]: Starting System Initialization. Apr 21 11:45:30 volumio systemd[1]: Reached target System Initialization. Apr 21 11:45:30 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Apr 21 11:45:30 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 21 11:45:30 volumio systemd[1]: Starting mpd.socket. Apr 21 11:45:30 volumio systemd[1]: Listening on mpd.socket. Apr 21 11:45:30 volumio systemd[1]: Starting D-Bus System Message Bus Socket. Apr 21 11:45:30 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 21 11:45:30 volumio systemd[1]: Starting Sockets. Apr 21 11:45:30 volumio systemd[1]: Reached target Sockets. Apr 21 11:45:30 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. Apr 21 11:45:30 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 21 11:45:30 volumio systemd[1]: Starting Timers. Apr 21 11:45:30 volumio systemd[1]: Reached target Timers. Apr 21 11:45:30 volumio systemd[1]: Started Manage Sound Card State (restore and store). Apr 21 11:45:30 volumio systemd[1]: Starting Restore Sound Card State... Apr 21 11:45:30 volumio systemd[1]: Starting Basic System. Apr 21 11:45:30 volumio systemd[1]: Reached target Basic System. Apr 21 11:45:30 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Apr 21 11:45:30 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 21 11:45:30 volumio systemd[1]: Starting cd.service... Apr 21 11:45:30 volumio alsactl[4600]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:DAC Digital Playback Volume:0' : No such file or directory Apr 21 11:45:30 volumio systemd[1]: Started cd.service. Apr 21 11:45:30 volumio kernel: aml_audio_i2s_mute_flag: flag=0 Apr 21 11:45:30 volumio kernel: aml_audio_set_spdif_mute: flag=0 Apr 21 11:45:30 volumio kernel: aml_snd_card: aml_m8_set_spk: aml_m8_spk_enabled=0 Apr 21 11:45:30 volumio systemd[1]: Starting Volumio Iptables Module... Apr 21 11:45:30 volumio volumio[4602]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 21 11:45:30 volumio systemd[1]: Started Volumio Iptables Module. Apr 21 11:45:30 volumio systemd[1]: Starting mount disks automatically with standby... Apr 21 11:45:30 volumio systemd[1]: Started mount disks automatically with standby. Apr 21 11:45:30 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 21 11:45:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 21 11:45:30 volumio systemd[1]: Starting volumio-remote-updater.service... Apr 21 11:45:30 volumio systemd[1]: Started volumio-remote-updater.service. Apr 21 11:45:30 volumio systemd[1]: Starting Volumio Log Rotation Service... Apr 21 11:45:30 volumio systemd[1]: Started Volumio Log Rotation Service. Apr 21 11:45:30 volumio systemd[1]: Starting Volumio SSH enabler... Apr 21 11:45:30 volumio systemd[1]: Started Volumio SSH enabler. Apr 21 11:45:30 volumio systemd[1]: Starting Wireless Services... Apr 21 11:45:30 volumio systemd[1]: Starting /etc/rc.local Compatibility... Apr 21 11:45:30 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Apr 21 11:45:30 volumio systemd[1]: Starting Login Service... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: start Winbind daemon... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: triggerhappy hotkey daemon... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: Start NTP daemon... Apr 21 11:45:30 volumio rc.local[4619]: kernel 3.14.29 Apr 21 11:45:30 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: Starts LIRC daemon.... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio triggerhappy[4626]: Unable to parse trigger line: Apr 21 11:45:30 volumio lirc[4651]: No valid /etc/lirc/lircd.conf has been found.. Apr 21 11:45:30 volumio lirc[4651]: Remote control support has been disabled.. Apr 21 11:45:30 volumio lirc[4651]: Reconfigure LIRC or manually replace /etc/lirc/lircd.conf to enable.. Apr 21 11:45:30 volumio kernel: fb: osd[0] canvas.idx =0x40 Apr 21 11:45:30 volumio kernel: fb: osd[0] canvas.addr=0x7d100000 Apr 21 11:45:30 volumio kernel: fb: osd[0] canvas.width=3840 Apr 21 11:45:30 volumio kernel: fb: osd[0] canvas.height=1440 Apr 21 11:45:30 volumio kernel: fb: osd[0] frame.width=1280 Apr 21 11:45:30 volumio kernel: fb: osd[0] frame.height=720 Apr 21 11:45:30 volumio kernel: fb: Frame buffer memory assigned at Apr 21 11:45:30 volumio kernel: fb: 1, phy: 0x000000007e900000, vir:0xffffff8003800000, size=1024K Apr 21 11:45:30 volumio kernel: fb: fb1 open, logo index:1 Apr 21 11:45:30 volumio kernel: fb: osd[1] canvas.idx =0x43 Apr 21 11:45:30 volumio kernel: fb: osd[1] canvas.addr=0x7e900000 Apr 21 11:45:30 volumio kernel: fb: osd[1] canvas.width=128 Apr 21 11:45:30 volumio kernel: fb: osd[1] canvas.height=32 Apr 21 11:45:30 volumio kernel: fb: osd[1] frame.width=32 Apr 21 11:45:30 volumio kernel: fb: osd[1] frame.height=32 Apr 21 11:45:30 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Apr 21 11:45:30 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Apr 21 11:45:30 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 21 11:45:30 volumio kernel: vout_serve: vmode set to 720p60hz Apr 21 11:45:30 volumio kernel: fb: current vmode=1080p60hz, cmd: 0x50000 Apr 21 11:45:30 volumio kernel: tv_vout: tv_set_current_vmode[919]fps_target_mode=10 Apr 21 11:45:30 volumio kernel: tv_vout: mode is 10,sync_duration_den=1,sync_duration_num=60 Apr 21 11:45:30 volumio kernel: tv_vout: TV mode 720p60hz selected. Apr 21 11:45:30 volumio kernel: vdac_enable: on:0,module_sel:8 Apr 21 11:45:30 volumio kernel: vdac_enable: on:0,module_sel:8 Apr 21 11:45:30 volumio kernel: tv_vout: new mode =720p60hz set ok Apr 21 11:45:30 volumio kernel: vout_serve: vinfo mode is: 720p60hz Apr 21 11:45:30 volumio kernel: vout_serve: new mode 720p60hz set ok Apr 21 11:45:30 volumio kernel: hdmitx: video: get current mode: 720p60hz Apr 21 11:45:30 volumio kernel: hdmitx: update rx hdr info 4 Apr 21 11:45:30 volumio kernel: hdmitx: update physcial size: 1440 810 Apr 21 11:45:30 volumio kernel: hdmitx: system: already init VIC = 0 Now VIC = 4 Apr 21 11:45:30 volumio kernel: hdmitx: rx has SCDC present indicator Apr 21 11:45:30 volumio kernel: hdmirx version is 2.0 Apr 21 11:45:30 volumio kernel: hdmitx div40: 0 Apr 21 11:45:30 volumio loadcpufreq[4646]: Loading cpufreq kernel modules...done (none). Apr 21 11:45:30 volumio dhcpcd[4703]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Apr 21 11:45:30 volumio dhcpcd[4703]: /var/run/dhcpcd-eth0.pid ... failed! Apr 21 11:45:31 volumio systemd[1]: Starting Music Player Daemon... Apr 21 11:45:31 volumio kernel: hdmitx: system: set mode VIC 4 (cd0,cs0,pm1,vd0,1) Apr 21 11:45:31 volumio kernel: hdmitx: system: set pll Apr 21 11:45:31 volumio kernel: hdmitx: system: param->VIC:4 Apr 21 11:45:31 volumio kernel: hdmitx: set clk: VIC = 4 cd = 4 frac_rate = 0 Apr 21 11:45:31 volumio kernel: config HPLL = 2970000 Apr 21 11:45:31 volumio kernel: HPLL: 0xc000027b Apr 21 11:45:31 volumio kernel: config HPLL done Apr 21 11:45:31 volumio kernel: set_hpll_od3_clk_div[437] div = 6 Apr 21 11:45:31 volumio kernel: j = 2 vid_clk_div = 1 Apr 21 11:45:31 volumio kernel: hdmitx: set enc for VIC: 4 Apr 21 11:45:31 volumio kernel: hdmitx_set_hw[4947] set VIC = 4 Apr 21 11:45:31 volumio systemd[1]: Starting D-Bus System Message Bus... Apr 21 11:45:31 volumio sudo[4618]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Apr 21 11:45:31 volumio sudo[4618]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:31 volumio kernel: hdmitx: system: PHY Setting Done Apr 21 11:45:31 volumio kernel: hdmitx: system: irq 80000001 Apr 21 11:45:31 volumio kernel: Start = 0x10000100 End = 0x100001ff Apr 21 11:45:31 volumio kernel: [0x10000104]: 0x00000001 Apr 21 11:45:31 volumio kernel: [0x10000180]: 0x000000ff Apr 21 11:45:31 volumio kernel: [0x10000181]: 0x000000ff Apr 21 11:45:31 volumio kernel: [0x10000182]: 0x00000003 Apr 21 11:45:31 volumio kernel: [0x10000183]: 0x00000007 Apr 21 11:45:31 volumio kernel: [0x10000184]: 0x0000003f Apr 21 11:45:31 volumio kernel: [0x10000185]: 0x00000002 Apr 21 11:45:31 volumio kernel: [0x10000187]: 0x000000ff Apr 21 11:45:31 volumio kernel: [0x10000188]: 0x00000003 Apr 21 11:45:31 volumio kernel: Sink is HDMI device Apr 21 11:45:31 volumio kernel: hdmitx: system: packet: can't get vendor data Apr 21 11:45:31 volumio kernel: 720p60hz 60 1 Apr 21 11:45:31 volumio kernel: 720p60hz 60 1 Apr 21 11:45:31 volumio kernel: hdmtix: set audio Apr 21 11:45:31 volumio kernel: hdmitx tx_aud_src = 0 Apr 21 11:45:31 volumio kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 74250 Apr 21 11:45:31 volumio kernel: hdmitx aud_n_para = 6144 Apr 21 11:45:31 volumio kernel: hdmitx set channel status Apr 21 11:45:31 volumio kernel: hdmitx: audio: Audio Type: PCM Apr 21 11:45:31 volumio kernel: fb: current vmode=720p60hz, cmd: 0x10000 Apr 21 11:45:31 volumio sudo[4618]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: ifplugd 0.28 initializing. Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: Using interface eth0/00:AC:2F:78:C0:FF with driver (version: March_2013) Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: Using detection mode: SIOCETHTOOL Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: Initialization complete, link beat detected. Apr 21 11:45:31 volumio kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Apr 21 11:45:31 volumio kernel: fb: osd[0] enable: 1 (hdmi.sh) Apr 21 11:45:31 volumio kernel: fb: osd[1] enable: 0 (hdmi.sh) Apr 21 11:45:31 volumio systemd[1]: Started D-Bus System Message Bus. Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: client: /sbin/ifup: interface eth0 already configured Apr 21 11:45:31 volumio ifplugd(eth0)[4729]: Program executed successfully. Apr 21 11:45:31 volumio ifplugd[4698]: Network Interface Plugging Daemon...start eth0...done. Apr 21 11:45:31 volumio avahi-daemon[4719]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Apr 21 11:45:31 volumio avahi-daemon[4719]: Successfully dropped root privileges. Apr 21 11:45:31 volumio avahi-daemon[4719]: avahi-daemon 0.6.31 starting up. Apr 21 11:45:31 volumio avahi-daemon[4719]: Successfully called chroot(). Apr 21 11:45:31 volumio avahi-daemon[4719]: Successfully dropped remaining capabilities. Apr 21 11:45:31 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 21 11:45:31 volumio avahi-daemon[4719]: No service file found in /etc/avahi/services. Apr 21 11:45:31 volumio avahi-daemon[4719]: Network interface enumeration completed. Apr 21 11:45:31 volumio avahi-daemon[4719]: Registering HINFO record with values 'AARCH64'/'LINUX'. Apr 21 11:45:31 volumio avahi-daemon[4719]: Server startup complete. Host name is volumio.local. Local service cookie is 2413002322. Apr 21 11:45:31 volumio kernel: RTL871X: nolinked power save enter Apr 21 11:45:31 volumio systemd[1]: Starting Permit User Sessions... Apr 21 11:45:31 volumio systemd[1]: Started Restore Sound Card State. Apr 21 11:45:31 volumio ntpd[4687]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:59:22 UTC 2016 (1) Apr 21 11:45:31 volumio ntp[4634]: Starting NTP server: ntpd. Apr 21 11:45:31 volumio ntpd[4739]: proto: precision = 0.458 usec Apr 21 11:45:31 volumio ntpd[4739]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Apr 21 11:45:31 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 21 11:45:31 volumio systemd[1]: Unit cd.service entered failed state. Apr 21 11:45:31 volumio ntpd[4739]: Listen and drop on 1 v6wildcard :: UDP 123 Apr 21 11:45:31 volumio ntpd[4739]: Listen normally on 2 lo 127.0.0.1 UDP 123 Apr 21 11:45:31 volumio ntpd[4739]: peers refreshed Apr 21 11:45:31 volumio ntpd[4739]: Listening on routing socket on fd #19 for interface updates Apr 21 11:45:31 volumio systemd[1]: Started /etc/rc.local Compatibility. Apr 21 11:45:31 volumio systemd[1]: Started LSB: triggerhappy hotkey daemon. Apr 21 11:45:31 volumio dbus[4730]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Apr 21 11:45:31 volumio systemd[1]: Started LSB: Start NTP daemon. Apr 21 11:45:31 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 21 11:45:31 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Apr 21 11:45:31 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 21 11:45:31 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Apr 21 11:45:31 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Apr 21 11:45:31 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Apr 21 11:45:31 volumio systemd[1]: Unit dhcpcd.service entered failed state. Apr 21 11:45:31 volumio systemd[1]: Started Permit User Sessions. Apr 21 11:45:31 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 21 11:45:31 volumio systemd-logind[4622]: New seat seat0. Apr 21 11:45:31 volumio systemd[1]: Started Login Service. Apr 21 11:45:31 volumio systemd[1]: Starting Disk Manager (legacy version)... Apr 21 11:45:31 volumio systemd[1]: Stopping cd.service... Apr 21 11:45:31 volumio systemd[1]: Starting cd.service... Apr 21 11:45:31 volumio systemd[1]: Started cd.service. Apr 21 11:45:31 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 21 11:45:31 volumio systemd[1]: Starting Wait for Plymouth Boot Screen to Quit... Apr 21 11:45:31 volumio volumio[4753]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 21 11:45:31 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 21 11:45:31 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 21 11:45:31 volumio systemd[1]: Unit cd.service entered failed state. Apr 21 11:45:31 volumio systemd[1]: Started Wait for Plymouth Boot Screen to Quit. Apr 21 11:45:31 volumio haveged[4601]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Apr 21 11:45:31 volumio haveged[4601]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 9/40; sz: 15780/68380 Apr 21 11:45:31 volumio haveged[4601]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99771 Apr 21 11:45:31 volumio haveged[4601]: haveged: fills: 0, generated: 0 Apr 21 11:45:31 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 21 11:45:31 volumio systemd[1]: Starting Getty on tty1... Apr 21 11:45:31 volumio systemd[1]: Started Getty on tty1. Apr 21 11:45:31 volumio systemd[1]: Starting Serial Getty on ttyS0... Apr 21 11:45:31 volumio systemd[1]: Started Serial Getty on ttyS0. Apr 21 11:45:31 volumio systemd[1]: Starting Login Prompts. Apr 21 11:45:31 volumio dbus[4730]: [system] Successfully activated service 'org.freedesktop.UDisks' Apr 21 11:45:31 volumio systemd[1]: Reached target Login Prompts. Apr 21 11:45:31 volumio cpufrequtils[4754]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 21 11:45:31 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 21 11:45:32 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 21 11:45:32 volumio systemd[1]: Started Disk Manager (legacy version). Apr 21 11:45:32 volumio systemd[1]: Stopping cd.service... Apr 21 11:45:32 volumio systemd[1]: Starting cd.service... Apr 21 11:45:32 volumio systemd[1]: Started cd.service. Apr 21 11:45:32 volumio volumio[4774]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 21 11:45:32 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 21 11:45:32 volumio systemd[1]: Unit cd.service entered failed state. Apr 21 11:45:32 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 21 11:45:32 volumio systemd[1]: Stopping cd.service... Apr 21 11:45:32 volumio systemd[1]: Starting cd.service... Apr 21 11:45:32 volumio systemd[1]: Started cd.service. Apr 21 11:45:32 volumio volumio[4776]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 21 11:45:32 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 21 11:45:32 volumio systemd[1]: Unit cd.service entered failed state. Apr 21 11:45:32 volumio dbus[4730]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Apr 21 11:45:32 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Apr 21 11:45:32 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 21 11:45:32 volumio systemd[1]: Stopping cd.service... Apr 21 11:45:32 volumio systemd[1]: Starting cd.service... Apr 21 11:45:32 volumio systemd[1]: Started cd.service. Apr 21 11:45:32 volumio volumio[4782]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 21 11:45:32 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 21 11:45:32 volumio systemd[1]: Unit cd.service entered failed state. Apr 21 11:45:32 volumio polkitd[4779]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 21 11:45:32 volumio dbus[4730]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 21 11:45:32 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Apr 21 11:45:32 volumio volumio-remote-updater[4608]: Error: No active session Apr 21 11:45:32 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:32] [info] asio async_connect error: system:111 (Connection refused) Apr 21 11:45:32 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 21 11:45:32 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:32] [error] handle_connect error: Underlying Transport Error Apr 21 11:45:32 volumio udisks-glue[4606]: Device file /dev/sda3 inserted Apr 21 11:45:32 volumio udisks-glue[4606]: Device file /dev/sda inserted Apr 21 11:45:32 volumio udisks-glue[4606]: Device file /dev/misc inserted Apr 21 11:45:32 volumio volumio[4607]: Could not open config: /tmp/upmpdcli.conf Apr 21 11:45:32 volumio udisks-glue[4606]: Device file /dev/mmcblk0rpmb inserted Apr 21 11:45:32 volumio udisks-glue[4606]: Device file /dev/bootloader inserted Apr 21 11:45:32 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 21 11:45:32 volumio systemd[1]: Stopping cd.service... Apr 21 11:45:32 volumio udisks-glue[4606]: Device file /dev/tee inserted Apr 21 11:45:32 volumio udisks-glue[4606]: Trying to automount /dev/tee... Apr 21 11:45:32 volumio systemd[1]: Starting cd.service... Apr 21 11:45:32 volumio systemd[1]: cd.service start request repeated too quickly, refusing to start. Apr 21 11:45:32 volumio systemd[1]: Failed to start cd.service. Apr 21 11:45:32 volumio systemd[1]: Unit cd.service entered failed state. Apr 21 11:45:32 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Apr 21 11:45:32 volumio systemd[1]: Unit upmpdcli.service entered failed state. Apr 21 11:45:33 volumio kernel: EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null) Apr 21 11:45:33 volumio udisks-glue[4606]: Successfully automounted /dev/tee at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/logo inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/env inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/boot inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/mmcblk0boot0 inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/mmcblk0boot1 inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/recovery inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/system inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Trying to automount /dev/system... Apr 21 11:45:33 volumio kernel: EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: (null) Apr 21 11:45:33 volumio udisks-glue[4606]: Successfully automounted /dev/system at /media/system Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/rsv inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/cache inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Trying to automount /dev/cache... Apr 21 11:45:33 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Apr 21 11:45:33 volumio udisks-glue[4606]: Successfully automounted /dev/cache at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b_ Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/reserved inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/crypt inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/mmcblk0 inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/data inserted Apr 21 11:45:33 volumio udisks-glue[4606]: Trying to automount /dev/data... Apr 21 11:45:33 volumio dhcpcd[4505]: eth0: offered 192.168.31.193 from 192.168.31.1 Apr 21 11:45:33 volumio ifup[4483]: dhcpcd[4505]: eth0: offered 192.168.31.193 from 192.168.31.1 Apr 21 11:45:33 volumio kernel: EXT4-fs (mmcblk0p13): mounted filesystem with ordered data mode. Opts: (null) Apr 21 11:45:33 volumio udisks-glue[4606]: Successfully automounted /dev/data at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b__ Apr 21 11:45:33 volumio dhcpcd[4505]: eth0: leased 192.168.31.193 for 43200 seconds Apr 21 11:45:33 volumio dhcpcd[4505]: eth0: adding host route to 192.168.31.193 via 127.0.0.1 Apr 21 11:45:33 volumio dhcpcd[4505]: eth0: adding route to 192.168.31.0/24 Apr 21 11:45:33 volumio dhcpcd[4505]: eth0: adding default route via 192.168.31.1 Apr 21 11:45:33 volumio avahi-daemon[4719]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.193. Apr 21 11:45:33 volumio avahi-daemon[4719]: New relevant interface eth0.IPv4 for mDNS. Apr 21 11:45:33 volumio avahi-daemon[4719]: Registering new address record for 192.168.31.193 on eth0.IPv4. Apr 21 11:45:33 volumio ifup[4483]: dhcpcd[4505]: eth0: leased 192.168.31.193 for 43200 seconds Apr 21 11:45:33 volumio ifup[4483]: dhcpcd[4505]: eth0: adding host route to 192.168.31.193 via 127.0.0.1 Apr 21 11:45:33 volumio ifup[4483]: dhcpcd[4505]: eth0: adding route to 192.168.31.0/24 Apr 21 11:45:33 volumio ifup[4483]: dhcpcd[4505]: eth0: adding default route via 192.168.31.1 Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/tee mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/system mounted at /media/system Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/cache mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b_ Apr 21 11:45:33 volumio udisks-glue[4606]: Device file /dev/data mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b__ Apr 21 11:45:33 volumio dhcpcd[4505]: forked to background, child pid 4831 Apr 21 11:45:33 volumio ifup[4483]: dhcpcd[4505]: forked to background, child pid 4831 Apr 21 11:45:33 volumio ntpd[4739]: Deferring DNS for 0.debian.pool.ntp.org 1 Apr 21 11:45:33 volumio ntpd[4851]: signal_no_reset: signal 17 had flags 4000000 Apr 21 11:45:35 volumio ntpd[4739]: Listen normally on 3 eth0 192.168.31.193 UDP 123 Apr 21 11:45:35 volumio ntpd[4739]: peers refreshed Apr 21 11:45:36 volumio ntpd_intres[4851]: DNS 0.debian.pool.ntp.org -> 162.159.200.1 Apr 21 11:45:36 volumio wireless.js[4741]: Cleaning previous... Apr 21 11:45:36 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Apr 21 11:45:36 volumio systemd[1]: Stopped hotspot.service. Apr 21 11:45:36 volumio sudo[4911]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 21 11:45:36 volumio sudo[4911]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:36 volumio sudo[4911]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:36 volumio sudo[4915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 21 11:45:36 volumio sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:36 volumio sudo[4915]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:36 volumio winbind[4625]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Apr 21 11:45:36 volumio winbind[4625]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 21 11:45:36 volumio winbind[4625]: Unable to setup corepath for winbindd: No such file or directory Apr 21 11:45:36 volumio winbind[4625]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 21 11:45:36 volumio winbind[4625]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 21 11:45:36 volumio winbind[4625]: Unable to setup corepath for winbindd: No such file or directory Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:36 volumio winbind[4625]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:36 volumio winbind[4625]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../source3/winbindd/winbindd.c:1549(main) Apr 21 11:45:36 volumio winbind[4625]: winbindd version 4.2.14-Debian started. Apr 21 11:45:36 volumio winbind[4625]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../lib/util/util.c:220(directory_create_or_exist) Apr 21 11:45:36 volumio winbind[4625]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Apr 21 11:45:36 volumio winbind[4625]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Apr 21 11:45:36 volumio winbind[4625]: Unable to setup corepath for winbindd: No such file or directory Apr 21 11:45:36 volumio wireless.js[4741]: Stopped aP Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../source3/lib/util.c:788(smb_panic_s3) Apr 21 11:45:36 volumio winbind[4625]: PANIC (pid 4909): Could not init winbindd's messaging context. Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../source3/lib/util.c:899(log_stack_trace) Apr 21 11:45:36 volumio winbind[4625]: BACKTRACE: 0 stack frames: Apr 21 11:45:36 volumio winbind[4625]: [2024/04/21 11:45:36, 0] ../source3/lib/dumpcore.c:304(dump_core) Apr 21 11:45:36 volumio winbind[4625]: Can not dump core: corepath not set up Apr 21 11:45:36 volumio winbind[4625]: failed! Apr 21 11:45:36 volumio systemd[1]: winbind.service: control process exited, code=exited status=1 Apr 21 11:45:36 volumio systemd[1]: Failed to start LSB: start Winbind daemon. Apr 21 11:45:36 volumio systemd[1]: Unit winbind.service entered failed state. Apr 21 11:45:36 volumio nmbd[4638]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Apr 21 11:45:36 volumio nmbd[4638]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 21 11:45:36 volumio nmbd[4638]: Unable to setup corepath for nmbd: No such file or directory Apr 21 11:45:36 volumio nmbd[4914]: [2024/04/21 11:45:36.502143, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:36 volumio nmbd[4914]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 21 11:45:36 volumio nmbd[4638]: [2024/04/21 11:45:36, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:36 volumio nmbd[4638]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 21 11:45:36 volumio nmbd[4638]: [2024/04/21 11:45:36, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:36 volumio nmbd[4638]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 21 11:45:36 volumio nmbd[4638]: [2024/04/21 11:45:36, 0] ../source3/nmbd/nmbd.c:908(main) Apr 21 11:45:36 volumio nmbd[4638]: nmbd version 4.2.14-Debian started. Apr 21 11:45:36 volumio nmbd[4638]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 21 11:45:36 volumio nmbd[4638]: [2024/04/21 11:45:36.502143, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:36 volumio nmbd[4638]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 21 11:45:36 volumio systemd[1]: nmbd.service: control process exited, code=exited status=1 Apr 21 11:45:36 volumio nmbd[4638]: failed! Apr 21 11:45:36 volumio systemd[1]: Failed to start LSB: start Samba NetBIOS nameserver (nmbd). Apr 21 11:45:36 volumio systemd[1]: Unit nmbd.service entered failed state. Apr 21 11:45:36 volumio systemd[1]: Stopped hotspot.service. Apr 21 11:45:36 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Apr 21 11:45:36 volumio sudo[4934]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 21 11:45:36 volumio sudo[4934]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:36 volumio sudo[4934]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:36 volumio sudo[4936]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 21 11:45:36 volumio sudo[4936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:36 volumio sudo[4936]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:36 volumio avahi-daemon[4719]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 21 11:45:36 volumio kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Apr 21 11:45:36 volumio avahi-daemon[4719]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 11:45:36 volumio avahi-daemon[4719]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 21 11:45:36 volumio systemd[1]: Starting hotspot.service... Apr 21 11:45:36 volumio systemd[1]: Started hotspot.service. Apr 21 11:45:36 volumio systemd[1]: Starting dhcpd.service... Apr 21 11:45:36 volumio systemd[1]: Starting dnsmasq.service... Apr 21 11:45:36 volumio systemd[1]: Started Wireless Services. Apr 21 11:45:36 volumio systemd[1]: Starting Volumio Backend Module... Apr 21 11:45:36 volumio systemd[1]: Started Volumio Backend Module. Apr 21 11:45:36 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 21 11:45:36 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 21 11:45:36 volumio dnsmasq[4958]: started, version 2.72 cachesize 150 Apr 21 11:45:36 volumio dnsmasq[4958]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect Apr 21 11:45:36 volumio dnsmasq[4958]: reading /etc/resolv.conf Apr 21 11:45:36 volumio dnsmasq[4958]: using nameserver 192.168.31.1#53 Apr 21 11:45:36 volumio dnsmasq[4958]: using nameserver 208.67.222.222#53 Apr 21 11:45:36 volumio dnsmasq[4958]: using nameserver 208.67.220.220#53 Apr 21 11:45:36 volumio dnsmasq[4958]: read /etc/hosts - 4 addresses Apr 21 11:45:36 volumio systemd[1]: Started dnsmasq.service. Apr 21 11:45:36 volumio systemd[1]: Starting Host and Network Name Lookups. Apr 21 11:45:36 volumio systemd[1]: Reached target Host and Network Name Lookups. Apr 21 11:45:36 volumio systemd[1]: Started Music Player Daemon. Apr 21 11:45:36 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 21 11:45:36 volumio dhcpd[4947]: Internet Systems Consortium DHCP Server 4.3.1 Apr 21 11:45:36 volumio dhcpd[4947]: Copyright 2004-2014 Internet Systems Consortium. Apr 21 11:45:36 volumio dhcpd[4947]: All rights reserved. Apr 21 11:45:36 volumio dhcpd[4947]: For info, please visit https://www.isc.org/software/dhcp/ Apr 21 11:45:36 volumio dhcpd[4947]: Config file: /etc/dhcp/dhcpd.conf Apr 21 11:45:36 volumio dhcpd[4947]: Database file: /var/lib/dhcp/dhcpd.leases Apr 21 11:45:36 volumio dhcpd[4947]: PID file: /var/run/dhcpd.pid Apr 21 11:45:37 volumio hotspot[4947]: Internet Systems Consortium DHCP Server 4.3.1 Apr 21 11:45:37 volumio hotspot[4947]: Copyright 2004-2014 Internet Systems Consortium. Apr 21 11:45:37 volumio hotspot[4947]: All rights reserved. Apr 21 11:45:37 volumio hotspot[4947]: For info, please visit https://www.isc.org/software/dhcp/ Apr 21 11:45:37 volumio hotspot[4947]: Config file: /etc/dhcp/dhcpd.conf Apr 21 11:45:37 volumio hotspot[4947]: Database file: /var/lib/dhcp/dhcpd.leases Apr 21 11:45:37 volumio hotspot[4947]: PID file: /var/run/dhcpd.pid Apr 21 11:45:37 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 21 11:45:37 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 21 11:45:37 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 21 11:45:37 volumio dhcpd[4947]: Internet Systems Consortium DHCP Server 4.3.1 Apr 21 11:45:37 volumio dhcpd[4947]: Copyright 2004-2014 Internet Systems Consortium. Apr 21 11:45:37 volumio dhcpd[4947]: All rights reserved. Apr 21 11:45:37 volumio dhcpd[4947]: For info, please visit https://www.isc.org/software/dhcp/ Apr 21 11:45:37 volumio dhcpd[4947]: Wrote 0 leases to leases file. Apr 21 11:45:37 volumio hotspot[4947]: Wrote 0 leases to leases file. Apr 21 11:45:37 volumio hotspot[4945]: Launching Ordinary Hostapd Apr 21 11:45:37 volumio dhcpd[4947]: Listening on LPF/wlan0/00:2d:f4:5b:0e:77/192.168.211.0/24 Apr 21 11:45:37 volumio dhcpd[4947]: Sending on LPF/wlan0/00:2d:f4:5b:0e:77/192.168.211.0/24 Apr 21 11:45:37 volumio dhcpd[4947]: Apr 21 11:45:37 volumio dhcpd[4947]: No subnet declaration for eth0 (192.168.31.193). Apr 21 11:45:37 volumio dhcpd[4947]: ** Ignoring requests on eth0. If this is not what Apr 21 11:45:37 volumio dhcpd[4947]: you want, please write a subnet declaration Apr 21 11:45:37 volumio dhcpd[4947]: in your dhcpd.conf file for the network segment Apr 21 11:45:37 volumio dhcpd[4947]: to which interface eth0 is attached. ** Apr 21 11:45:37 volumio dhcpd[4947]: Apr 21 11:45:37 volumio dhcpd[4947]: Sending on Socket/fallback/fallback-net Apr 21 11:45:37 volumio systemd[1]: Started dhcpd.service. Apr 21 11:45:37 volumio hotspot[4947]: Listening on LPF/wlan0/00:2d:f4:5b:0e:77/192.168.211.0/24 Apr 21 11:45:37 volumio hotspot[4947]: Sending on LPF/wlan0/00:2d:f4:5b:0e:77/192.168.211.0/24 Apr 21 11:45:37 volumio hotspot[4947]: No subnet declaration for eth0 (192.168.31.193). Apr 21 11:45:37 volumio hotspot[4947]: ** Ignoring requests on eth0. If this is not what Apr 21 11:45:37 volumio hotspot[4947]: you want, please write a subnet declaration Apr 21 11:45:37 volumio hotspot[4947]: in your dhcpd.conf file for the network segment Apr 21 11:45:37 volumio hotspot[4947]: to which interface eth0 is attached. ** Apr 21 11:45:37 volumio hotspot[4947]: Sending on Socket/fallback/fallback-net Apr 21 11:45:37 volumio udisks-glue[4606]: Updating DB (#1) ... Apr 21 11:45:37 volumio udisks-glue[4606]: volume: n/a repeat: off random: off single: off consume: off Apr 21 11:45:37 volumio udisks-glue[4606]: Updating DB (#1) ... Apr 21 11:45:37 volumio udisks-glue[4606]: volume: n/a repeat: off random: off single: off consume: off Apr 21 11:45:37 volumio udisks-glue[4606]: Updating DB (#1) ... Apr 21 11:45:37 volumio udisks-glue[4606]: volume: n/a repeat: off random: off single: off consume: off Apr 21 11:45:37 volumio udisks-glue[4606]: Updating DB (#1) ... Apr 21 11:45:37 volumio udisks-glue[4606]: volume: n/a repeat: off random: off single: off consume: off Apr 21 11:45:37 volumio dhcpd[4981]: Server starting service. Apr 21 11:45:37 volumio hotspot[4945]: Configuration file: /etc/hostapd/hostapd.conf Apr 21 11:45:37 volumio hotspot[4945]: Interface name not specified in /etc/hostapd/hostapd.conf Apr 21 11:45:37 volumio hotspot[4945]: hostapd_free_hapd_data: Interface wasn't started Apr 21 11:45:37 volumio hotspot[4945]: Failed to initialize interface Apr 21 11:45:37 volumio systemd[1]: hotspot.service: main process exited, code=exited, status=1/FAILURE Apr 21 11:45:37 volumio systemd[1]: Stopping dhcpd.service... Apr 21 11:45:37 volumio volumio-streaming-daemon[4951]: ############################ Apr 21 11:45:37 volumio volumio-streaming-daemon[4951]: # Volumio Streaming Daemon # Apr 21 11:45:37 volumio volumio-streaming-daemon[4951]: # Running on port 7777 # Apr 21 11:45:37 volumio volumio-streaming-daemon[4951]: ############################ Apr 21 11:45:37 volumio volumio-streaming-daemon[4951]: Environment: production Apr 21 11:45:37 volumio hotspot[4984]: Killing Hostapd Apr 21 11:45:37 volumio systemd[1]: Stopped dhcpd.service. Apr 21 11:45:37 volumio sudo[4993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Apr 21 11:45:37 volumio sudo[4993]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:37 volumio hotspot[4984]: hostapd: no process found Apr 21 11:45:37 volumio sudo[4993]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:37 volumio hotspot[4984]: Killing Dhcpd Apr 21 11:45:37 volumio sudo[4996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Apr 21 11:45:37 volumio sudo[4996]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:37 volumio hotspot[4984]: dhcpd: no process found Apr 21 11:45:37 volumio sudo[4996]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:37 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1 Apr 21 11:45:37 volumio systemd[1]: Unit hotspot.service entered failed state. Apr 21 11:45:37 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:37] [info] asio async_connect error: system:111 (Connection refused) Apr 21 11:45:37 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 21 11:45:37 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:37] [error] handle_connect error: Underlying Transport Error Apr 21 11:45:38 volumio smbd[4927]: Starting SMB/CIFS daemon: smbd[2024/04/21 11:45:38, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:38 volumio smbd[4927]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 21 11:45:38 volumio smbd[4927]: [2024/04/21 11:45:38, 0] ../lib/util/util.c:220(directory_create_or_exist) Apr 21 11:45:38 volumio smbd[4927]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 21 11:45:38 volumio smbd[4927]: [2024/04/21 11:45:38, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Apr 21 11:45:38 volumio smbd[4927]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 21 11:45:38 volumio smbd[4927]: [2024/04/21 11:45:38, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Apr 21 11:45:38 volumio smbd[4927]: Unable to setup corepath for smbd: No such file or directory Apr 21 11:45:38 volumio smbd[4927]: [2024/04/21 11:45:38, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 21 11:45:38 volumio smbd[4927]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 21 11:45:38 volumio smbd[4927]: [2024/04/21 11:45:38, 0] ../source3/smbd/server.c:1241(main) Apr 21 11:45:38 volumio smbd[4927]: smbd version 4.2.14-Debian started. Apr 21 11:45:38 volumio smbd[4927]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 21 11:45:38 volumio smbd[4927]: failed! Apr 21 11:45:38 volumio systemd[1]: smbd.service: control process exited, code=exited status=1 Apr 21 11:45:38 volumio systemd[1]: Failed to start LSB: start Samba SMB/CIFS daemon (smbd). Apr 21 11:45:38 volumio systemd[1]: Unit smbd.service entered failed state. Apr 21 11:45:38 volumio systemd[1]: Starting Multi-User System. Apr 21 11:45:38 volumio systemd[1]: Reached target Multi-User System. Apr 21 11:45:38 volumio systemd[1]: Starting Graphical Interface. Apr 21 11:45:38 volumio systemd[1]: Reached target Graphical Interface. Apr 21 11:45:38 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 21 11:45:38 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 21 11:45:38 volumio systemd[1]: Startup finished in 16.528s (kernel) + 1min 11.387s (userspace) = 1min 27.915s. Apr 21 11:45:38 volumio ntpd[4739]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 Apr 21 11:45:38 volumio ntpd[4739]: peers refreshed Apr 21 11:45:40 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:40 volumio volumio[4950]: info: ----- Volumio2 ---- Apr 21 11:45:40 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:40 volumio volumio[4950]: info: ----- System startup ---- Apr 21 11:45:40 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:41 volumio volumio[4950]: info: MYVOLUMIO Environment detected Apr 21 11:45:42 volumio volumio[4950]: info: Plugin folders cleanup Apr 21 11:45:42 volumio volumio[4950]: info: Scanning into folder /volumio/app/plugins/ Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category audio_interface Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category miscellanea Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category music_service Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category plugins.json Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category system_controller Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category user_interface Apr 21 11:45:42 volumio volumio[4950]: info: Scanning into folder /data/plugins/ Apr 21 11:45:42 volumio volumio[4950]: info: Scanning category miscellanea Apr 21 11:45:42 volumio volumio[4950]: info: Plugin folders cleanup completed Apr 21 11:45:42 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:42 volumio volumio[4950]: info: ----- Core plugins startup ---- Apr 21 11:45:42 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:42 volumio volumio[4950]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 11:45:42 volumio volumio[4950]: info: Adding plugin upnp to MyMusic Plugins Apr 21 11:45:42 volumio volumio[4950]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 11:45:42 volumio volumio[4950]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 11:45:42 volumio volumio[4950]: info: Loading plugins from folder /data/plugins/ Apr 21 11:45:42 volumio volumio[4950]: info: Loading plugin "system"... Apr 21 11:45:42 volumio volumio[4950]: info: Loading plugin "appearance"... Apr 21 11:45:43 volumio volumio[4950]: info: Loading plugin "network"... Apr 21 11:45:44 volumio volumio[4950]: info: Refreshing Cached IP Addresses Apr 21 11:45:44 volumio sudo[5024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:45:44 volumio sudo[5024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:44 volumio volumio[4950]: info: Loading plugin "touch_display"... Apr 21 11:45:44 volumio sudo[5024]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:44 volumio sudo[5026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:45:44 volumio sudo[5026]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:44 volumio sudo[5026]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:44 volumio volumio[4950]: info: Applying required configuration parameters for plugin touch_display Apr 21 11:45:45 volumio volumio-remote-updater[4608]: [2024-04-21 11:45:45] [connect] Successful connection Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "services"... Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "alsa_controller"... Apr 21 11:45:45 volumio volumio[4950]: Invalid card number. Apr 21 11:45:45 volumio volumio[4950]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Apr 21 11:45:45 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:45 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:45 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:45 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 11:45:45 volumio volumio[4950]: Invalid card number. Apr 21 11:45:45 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:45 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:45 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:45 volumio volumio[4950]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "wizard"... Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "volumio_command_line_client"... Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "upnp"... Apr 21 11:45:45 volumio volumio[4950]: info: [1713699945668] Starting Upmpd Daemon Apr 21 11:45:45 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "my_music"... Apr 21 11:45:45 volumio volumio[4950]: info: Loading plugin "mpd"... Apr 21 11:45:46 volumio volumio[4950]: info: Loading plugin "upnp_browser"... Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "networkfs"... Apr 21 11:45:47 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "alarm-clock"... Apr 21 11:45:47 volumio sudo[5050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=BG4BWN,password=ZHANGjun19790130,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.210/音乐 /mnt/NAS/BG4BWN-OMV-NAS Apr 21 11:45:47 volumio sudo[5050]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "airplay_emulation"... Apr 21 11:45:47 volumio volumio[4950]: info: Starting Shairport Sync Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "last_100"... Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "webradio"... Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "i2s_dacs"... Apr 21 11:45:47 volumio volumio[4950]: info: I2S DAC not set, start Auto-detection Apr 21 11:45:47 volumio volumio[4950]: info: Loading plugin "volumiodiscovery"... Apr 21 11:45:48 volumio volumio[4950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 11:45:48 volumio node[4950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 11:45:48 volumio volumio[4950]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 11:45:48 volumio node[4950]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 11:45:48 volumio volumio[4950]: *** WARNING *** For more information see Apr 21 11:45:48 volumio node[4950]: *** WARNING *** For more information see Apr 21 11:45:48 volumio volumio[4950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 11:45:48 volumio node[4950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 11:45:48 volumio node[4950]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 11:45:48 volumio node[4950]: *** WARNING *** For more information see Apr 21 11:45:48 volumio volumio[4950]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 11:45:48 volumio volumio[4950]: *** WARNING *** For more information see Apr 21 11:45:48 volumio volumio[4950]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 11:45:48 volumio volumio[4950]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/volumiodiscovery/config.json' Apr 21 11:45:48 volumio volumio[4950]: Discovery: StartAdv! undefined Apr 21 11:45:48 volumio volumio[4950]: Discovery: Started advertising... Volumio - undefined Apr 21 11:45:48 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "outputs"... Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "albumart"... Apr 21 11:45:48 volumio volumio[4950]: info: Plugin example_plugin is not enabled Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "inputs"... Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "updater_comm"... Apr 21 11:45:48 volumio volumio[4950]: info: Plugin mpdemulation is not enabled Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "rest_api"... Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "websocket"... Apr 21 11:45:48 volumio volumio[4950]: info: Loading plugin "minidlna"... Apr 21 11:45:48 volumio volumio[4950]: Forking 3 albumart workers Apr 21 11:45:48 volumio volumio[4950]: info: Applying required configuration parameters for plugin minidlna Apr 21 11:45:49 volumio volumio[4950]: info: ___________ START PLUGINS ___________ Apr 21 11:45:49 volumio volumio[4950]: info: Loading i18n strings for locale zh Apr 21 11:45:49 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 11:45:49 volumio volumio[4950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 11:45:49 volumio volumio[4950]: info: [1713699949478] CoreMusicLibrary::Adding element Media Servers Apr 21 11:45:49 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 11:45:49 volumio sudo[5087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 21 11:45:49 volumio sudo[5087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:49 volumio systemd[1]: Reloading. Apr 21 11:45:49 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:49 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:49 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:49 volumio volumio[4950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 11:45:49 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:49 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:49 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:49 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Apr 21 11:45:49 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Apr 21 11:45:49 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Apr 21 11:45:49 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Apr 21 11:45:50 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:50 volumio volumio[4950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 11:45:50 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:50 volumio volumio[4950]: Starting albumart workers Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 11:45:50 volumio volumio[4950]: info: [1713699950015] CoreMusicLibrary::Adding element Last_100 Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 11:45:50 volumio volumio[4950]: info: [1713699950023] CoreMusicLibrary::Adding element Webradio Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 11:45:50 volumio volumio[4950]: Starting albumart workers Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 11:45:50 volumio volumio[4950]: info: Loading i18n strings for locale zh Apr 21 11:45:50 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:50 volumio volumio[4950]: info: ----- MyVolumio plugins startup ---- Apr 21 11:45:50 volumio volumio[4950]: Starting albumart workers Apr 21 11:45:50 volumio volumio[4950]: info: ------------------------------------------- Apr 21 11:45:50 volumio volumio[4950]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 21 11:45:50 volumio volumio[4950]: info: Loading i18n strings for locale zh Apr 21 11:45:50 volumio volumio[4950]: Updating browse sources language Apr 21 11:45:50 volumio volumio[4950]: Cannot find translation for sourceMedia Servers Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::initPlayerControls Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 21 11:45:50 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 11:45:50 volumio volumio[4950]: info: BOOT COMPLETED Apr 21 11:45:50 volumio volumio[4950]: [Metrics] CommandRouter: 10s 127.41ms Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 11:45:50 volumio sudo[5087]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:50 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:50 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:50 volumio volumio[4950]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Apr 21 11:45:50 volumio volumio[4950]: aplay: main:722: audio open error: No such file or directory Apr 21 11:45:50 volumio volumio[4950]: Cannot play startup sound Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::Close All Modals sent Apr 21 11:45:50 volumio volumio[4950]: info: CoreCommandRouter::Close All Modals sent Apr 21 11:45:50 volumio volumio[4950]: Express server listening on port 3000 Apr 21 11:45:50 volumio volumio[4950]: [Metrics] WebUI: 11s 311.54ms Apr 21 11:45:50 volumio volumio[4950]: Volumio Calling Home Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::resetVolumioState Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::getcurrentVolume Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 11:45:51 volumio volumio[4950]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/audio_interface/alsa_controller/config.json' Apr 21 11:45:51 volumio volumio[4950]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::getcurrentVolume Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 11:45:51 volumio volumio[4950]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 21 11:45:51 volumio volumio[4950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 21 11:45:51 volumio volumio[4950]: info: Setting Device type: Amlogic Apr 21 11:45:51 volumio volumio[4950]: error: minidlna: Failed to query miniDLNA version: Error: Command failed: /usr/bin/minidlnad -V Apr 21 11:45:51 volumio volumio[4950]: /bin/sh: 1: /usr/bin/minidlnad: not found Apr 21 11:45:51 volumio volumio[4950]: info: touch_display: systemctl daemon-reload succeeded. Apr 21 11:45:51 volumio volumio[4950]: info: MPD running with PID4720 Apr 21 11:45:51 volumio volumio[4950]: ,establishing connection Apr 21 11:45:51 volumio sudo[5151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 21 11:45:51 volumio sudo[5151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:51 volumio sudo[5153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 21 11:45:51 volumio sudo[5153]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:51 volumio sudo[5151]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:51 volumio sudo[5153]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:51 volumio volumio[4950]: info: VolumeController:: Volume=undefined Mute =false Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::pushState Apr 21 11:45:51 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioPushState Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::updateTrackBlock Apr 21 11:45:51 volumio volumio[4950]: info: CorePlayQueue::getTrackBlock Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 11:45:51 volumio volumio[4950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 21 11:45:51 volumio volumio[4950]: info: VolumeController:: Volume=undefined Mute =false Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::pushState Apr 21 11:45:51 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioPushState Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 11:45:51 volumio volumio[4950]: info: CoreStateMachine::updateTrackBlock Apr 21 11:45:51 volumio volumio[4950]: info: CorePlayQueue::getTrackBlock Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 11:45:51 volumio volumio[4950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 21 11:45:51 volumio volumio[4950]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Apr 21 11:45:51 volumio volumio[4950]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Apr 21 11:45:51 volumio volumio[4950]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Apr 21 11:45:51 volumio volumio[4950]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service failed to load: No such file or directory. Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 11:45:51 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 11:45:52 volumio volumio[4950]: Home directory not accessible: Permission denied Apr 21 11:45:52 volumio volumio[4950]: W: [pulseaudio] core-util.c: Failed to open configuration file '/home/volumio/.config/pulse//daemon.conf': Permission denied Apr 21 11:45:52 volumio volumio[4950]: W: [pulseaudio] daemon-conf.c: Failed to open configuration file: Permission denied Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 11:45:52 volumio volumio[4950]: info: Reloading queue from file Apr 21 11:45:52 volumio volumio[4950]: error: updateQueue error: null Apr 21 11:45:52 volumio volumio[4950]: info: VolumeController:: Volume=undefined Mute =false Apr 21 11:45:52 volumio volumio[4950]: info: CoreStateMachine::pushState Apr 21 11:45:52 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::volumioPushState Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 11:45:52 volumio volumio[4950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 21 11:45:52 volumio volumio[4950]: info: CoreStateMachine::setRepeat null single undefined Apr 21 11:45:52 volumio volumio[4950]: info: CoreStateMachine::pushState Apr 21 11:45:52 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::volumioPushState Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 11:45:52 volumio volumio[4950]: info: CoreStateMachine::setRandom null Apr 21 11:45:52 volumio volumio[4950]: info: CoreStateMachine::pushState Apr 21 11:45:52 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::volumioPushState Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 11:45:52 volumio sudo[5166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:45:52 volumio sudo[5166]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:52 volumio sudo[5166]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:52 volumio sudo[5168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:45:52 volumio sudo[5168]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:52 volumio sudo[5168]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:52 volumio volumio[4950]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/system/config.json' Apr 21 11:45:52 volumio volumio[4950]: error: minidlna: Error writing /data/minidlna.conf: Error: EACCES: permission denied, open '/data/minidlna.conf' Apr 21 11:45:52 volumio volumio[4950]: info: VolumeController:: Volume=undefined Mute =false Apr 21 11:45:52 volumio volumio[4950]: info: CoreStateMachine::pushState Apr 21 11:45:52 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::volumioPushState Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 11:45:52 volumio volumio[4950]: info: Starting Shairport Sync Apr 21 11:45:52 volumio volumio[4950]: info: Starting Shairport Sync Apr 21 11:45:52 volumio sudo[5175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 11:45:52 volumio sudo[5175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:52 volumio volumio[4950]: Discovery: adding e647343a-d4ec-4305-8c08-c18e038c2138 Apr 21 11:45:52 volumio volumio[4950]: info: mDNS: Found device Volumio Apr 21 11:45:52 volumio volumio[4950]: info: CoreCommandRouter::volumioGetState Apr 21 11:45:52 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:53 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 21 11:45:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 11:45:53 volumio sudo[5177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 11:45:53 volumio sudo[5177]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:53 volumio sudo[5175]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 21 11:45:53 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 21 11:45:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 11:45:53 volumio sudo[5177]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:53 volumio volumio[4950]: { Error: EACCES: permission denied, unlink '/data/backgrounds/aurora.jpg' Apr 21 11:45:53 volumio volumio[4950]: errno: -13, Apr 21 11:45:53 volumio volumio[4950]: code: 'EACCES', Apr 21 11:45:53 volumio volumio[4950]: syscall: 'unlink', Apr 21 11:45:53 volumio volumio[4950]: path: '/data/backgrounds/aurora.jpg' } Apr 21 11:45:53 volumio sudo[5050]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:54 volumio volumio[4950]: info: Shairport-Sync Started Apr 21 11:45:54 volumio volumio[4950]: Error adding Membership: Error: addMembership EINVAL Apr 21 11:45:54 volumio volumio[4950]: info: Shairport-Sync Started Apr 21 11:45:54 volumio volumio[4950]: Discovery: this is already registered, e647343a-d4ec-4305-8c08-c18e038c2138 Apr 21 11:45:54 volumio volumio[4950]: info: mDNS: Found device Volumio Apr 21 11:45:54 volumio volumio[4950]: info: CoreCommandRouter::volumioGetState Apr 21 11:45:54 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:55 volumio volumio[4950]: info: Cannot mount NAS BG4BWN-OMV-NAS at system boot, trial number 1 ,retrying in 5 seconds Apr 21 11:45:56 volumio sudo[5205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:45:56 volumio sudo[5205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:56 volumio sudo[5205]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:56 volumio sudo[5208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:45:56 volumio sudo[5208]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:56 volumio sudo[5208]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:56 volumio sudo[5211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 21 11:45:56 volumio sudo[5211]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:45:56 volumio volumio[4950]: info: CoreCommandRouter::volumioGetState Apr 21 11:45:56 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:56 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 21 11:45:56 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 21 11:45:56 volumio sudo[5211]: pam_unix(sudo:session): session closed for user root Apr 21 11:45:56 volumio volumio[4950]: info: Upmpdcli Daemon Started Apr 21 11:45:56 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 11:45:56 volumio volumio[4950]: info: CoreCommandRouter::volumioGetState Apr 21 11:45:56 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:45:58 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 21 11:45:59 volumio volumio[4950]: info: Adding plugin bluetooth to MyMusic Plugins Apr 21 11:45:59 volumio volumio[4950]: info: Adding plugin cd_controller to MyMusic Plugins Apr 21 11:45:59 volumio volumio[4950]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 21 11:45:59 volumio volumio[4950]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 21 11:46:00 volumio volumio-remote-updater[4608]: [2024-04-21 11:46:00] [connect] Successful connection Apr 21 11:46:01 volumio volumio[4950]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 21 11:46:03 volumio volumio[4950]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 21 11:46:03 volumio volumio[4950]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 21 11:46:03 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 11:46:03 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 11:46:03 volumio volumio[4950]: info: Starting MyVolumio Remote Streaming Endpoints Apr 21 11:46:03 volumio volumio[4950]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 21 11:46:03 volumio volumio[4950]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 21 11:46:03 volumio volumio[4950]: info: Streaming services startup Apr 21 11:46:03 volumio volumio[4950]: info: Starting Streaming Daemon Apr 21 11:46:03 volumio sudo[5239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 21 11:46:03 volumio sudo[5239]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:03 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 21 11:46:03 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 21 11:46:03 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 21 11:46:03 volumio sudo[5239]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:03 volumio volumio-streaming-daemon[5246]: ############################ Apr 21 11:46:03 volumio volumio-streaming-daemon[5246]: # Volumio Streaming Daemon # Apr 21 11:46:03 volumio volumio-streaming-daemon[5246]: # Running on port 7777 # Apr 21 11:46:03 volumio volumio-streaming-daemon[5246]: ############################ Apr 21 11:46:03 volumio volumio-streaming-daemon[5246]: Environment: production Apr 21 11:46:03 volumio volumio[4950]: info: MyVolumio not started Apr 21 11:46:03 volumio volumio[4950]: info: Initializing device activation check Apr 21 11:46:03 volumio volumio[4950]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 21 11:46:03 volumio sudo[5254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=BG4BWN,password=ZHANGjun19790130,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.210/音乐 /mnt/NAS/BG4BWN-OMV-NAS Apr 21 11:46:03 volumio sudo[5254]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:04 volumio volumio-remote-updater[4608]: [2024-04-21 11:46:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1713699960 101 Apr 21 11:46:04 volumio sudo[5263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:46:04 volumio sudo[5263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:04 volumio sudo[5263]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:04 volumio sudo[5266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:46:04 volumio sudo[5266]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:04 volumio sudo[5266]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:04 volumio volumio[4950]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Apr 21 11:46:06 volumio volumio[4950]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=1ac0973e8a28ba1398b2ce116654ae7c" http://updates.volumio.org:7070/downloader-v1/track-device Apr 21 11:46:06 volumio volumio[4950]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 21 11:46:06 volumio volumio[4950]: Dload Upload Total Spent Left Speed Apr 21 11:46:06 volumio volumio[4950]: [472B blob data] Apr 21 11:46:06 volumio volumio[4950]: retrying in 5 seconds, trial 0 Apr 21 11:46:06 volumio volumio[4950]: Volumio Calling Home Apr 21 11:46:06 volumio volumio-streaming-daemon[5246]: Environment: production Apr 21 11:46:06 volumio volumio[4950]: error: Failed initialization of streaming services: Error: An error occurred while configuring services daemon. Apr 21 11:46:09 volumio sudo[5254]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:09 volumio volumio[4950]: info: Cannot mount NAS BG4BWN-OMV-NAS at system boot, trial number 2 ,retrying in 5 seconds Apr 21 11:46:15 volumio sudo[5304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=BG4BWN,password=ZHANGjun19790130,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.210/音乐 /mnt/NAS/BG4BWN-OMV-NAS Apr 21 11:46:15 volumio sudo[5304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:16 volumio volumio[4950]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=1ac0973e8a28ba1398b2ce116654ae7c" http://updates.volumio.org:7070/downloader-v1/track-device Apr 21 11:46:16 volumio volumio[4950]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 21 11:46:16 volumio volumio[4950]: Dload Upload Total Spent Left Speed Apr 21 11:46:16 volumio volumio[4950]: [235B blob data] Apr 21 11:46:16 volumio volumio[4950]: retrying in 5 seconds, trial 1 Apr 21 11:46:16 volumio volumio[4950]: Volumio Calling Home Apr 21 11:46:20 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 11:46:20 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 11:46:21 volumio sudo[5304]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:21 volumio volumio[4950]: info: Cannot mount NAS BG4BWN-OMV-NAS at system boot, trial number 3 ,retrying in 5 seconds Apr 21 11:46:26 volumio sudo[5350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=BG4BWN,password=ZHANGjun19790130,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.210/音乐 /mnt/NAS/BG4BWN-OMV-NAS Apr 21 11:46:26 volumio sudo[5350]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:26 volumio volumio[4950]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=1ac0973e8a28ba1398b2ce116654ae7c" http://updates.volumio.org:7070/downloader-v1/track-device Apr 21 11:46:26 volumio volumio[4950]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 21 11:46:26 volumio volumio[4950]: Dload Upload Total Spent Left Speed Apr 21 11:46:26 volumio volumio[4950]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 21 11:46:26 volumio volumio[4950]: retrying in 5 seconds, trial 2 Apr 21 11:46:26 volumio volumio[4950]: Volumio Calling Home Apr 21 11:46:28 volumio sudo[5369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:46:28 volumio sudo[5369]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:28 volumio sudo[5369]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:28 volumio sudo[5372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:46:28 volumio sudo[5372]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:28 volumio sudo[5372]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:29 volumio sudo[5376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:46:29 volumio sudo[5376]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:29 volumio sudo[5376]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:29 volumio sudo[5379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:46:29 volumio sudo[5379]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:29 volumio sudo[5379]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:29 volumio volumio[4950]: info: CoreCommandRouter::volumioGetState Apr 21 11:46:29 volumio volumio[4950]: info: CorePlayQueue::getTrack 0 Apr 21 11:46:29 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 11:46:29 volumio volumio[4950]: info: Listing playlists Apr 21 11:46:29 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 21 11:46:29 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 21 11:46:29 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 21 11:46:29 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 11:46:32 volumio volumio[4950]: info: CoreCommandRouter::volumioGetQueue Apr 21 11:46:32 volumio volumio[4950]: info: CoreStateMachine::getQueue Apr 21 11:46:32 volumio volumio[4950]: info: CorePlayQueue::getQueue Apr 21 11:46:32 volumio sudo[5350]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:32 volumio volumio[4950]: info: Cannot mount NAS BG4BWN-OMV-NAS at system boot, trial number 4 ,retrying in 5 seconds Apr 21 11:46:34 volumio volumio[4950]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 11:46:34 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 21 11:46:34 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 21 11:46:34 volumio sudo[5418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 21 11:46:34 volumio sudo[5418]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:34 volumio sudo[5418]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:34 volumio sudo[5424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 21 11:46:34 volumio sudo[5424]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:34 volumio sudo[5424]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:34 volumio sudo[5432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 21 11:46:34 volumio sudo[5432]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:34 volumio sudo[5432]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:34 volumio sudo[5437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 21 11:46:34 volumio sudo[5437]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:34 volumio sudo[5437]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:34 volumio sudo[5443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 11:46:34 volumio sudo[5443]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:34 volumio sudo[5443]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:34 volumio sudo[5447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 11:46:34 volumio sudo[5447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:34 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 21 11:46:34 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 21 11:46:34 volumio sudo[5447]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:34 volumio sudo[5450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 11:46:34 volumio sudo[5450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:35 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 11:46:35 volumio kernel: RTL871X: nolinked power save leave Apr 21 11:46:37 volumio sudo[5462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=BG4BWN,password=ZHANGjun19790130,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.210/音乐 /mnt/NAS/BG4BWN-OMV-NAS Apr 21 11:46:37 volumio sudo[5462]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 11:46:37 volumio kernel: RTL871X: nolinked power save enter Apr 21 11:46:37 volumio sudo[5450]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:43 volumio sudo[5462]: pam_unix(sudo:session): session closed for user root Apr 21 11:46:43 volumio volumio[4950]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 21 11:46:50 volumio volumio[4950]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Apr 21 11:46:50 volumio volumio[4950]: info: Saving new wireless network Apr 21 11:46:50 volumio volumio[4950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 11:46:50 volumio volumio[4950]: ReferenceError: error is not defined Apr 21 11:46:50 volumio volumio[4950]: at /volumio/app/plugins/system_controller/network/index.js:513:57 Apr 21 11:46:50 volumio volumio[4950]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Apr 21 11:46:50 volumio volumio[4950]: at fs.js:1279:7 Apr 21 11:46:50 volumio volumio[4950]: at FSReqWrap.oncomplete (fs.js:135:15) Apr 21 11:46:50 volumio volumio[4950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 11:46:51 volumio sudo[5517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 11:45 Apr 21 11:46:51 volumio sudo[5517]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"