linux4wilc / driver

DEPRECATED: Updated Linux drivers for the ATWILC1000/ATWILC3000 products are located at https://github.com/linux4microchip/linux/tree/master/drivers/net/wireless/microchip/wilc1000. To simplify development, the legacy Linux4WILC was merged into the Linux4Microchip repo where driver development continues (Please refer latest ATWILC1000/ATWILC 3000 Wi-Fi Link Controller Linux User Guide) Driver code for Microchip ATWILC Wireless Devices (ATWILC1000 & ATWILC3000)
https://www.microchip.com/wwwproducts/en/ATWILC1000
32 stars 20 forks source link

ATWILC 1000 SDIO Failed to start firmware #100

Open Andrflor opened 4 years ago

Andrflor commented 4 years ago

Hello, I'm using 15.3.1 ATWILC1000 sdio on a cm3 with a V4.19.80 kernel

I have a pretty random comportment, most of the time wifi does not work, but some times it does work for some reason It seems that it restarts multiple times I have hardcoded gpio pins in the driver header and configured sdio overlay.

I don't understand what is happening, if you have some hint :smile_cat:


[    4.777717] wilc_sdio mmc1:0001:1: succesfully got gpio_reset
[    4.777738] wilc_sdio mmc1:0001:1: succesfully got gpio_chip_en
[    4.777771] wilc_sdio mmc1:0001:1: succesfully got gpio_reset
[    4.777790] wilc_sdio mmc1:0001:1: succesfully got gpio_chip_en
[    4.794740] wilc_sdio mmc1:0001:1: Driver Initializing success
[    9.944819] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[11e0aa06] wlan0
[    9.944833] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[11e0aa06][8d002279]
[    9.944853] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[    9.944862] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
[    9.944873] wilc_sdio mmc1:0001:1: SDIO speed: 25000000
[    9.945582] wilc_sdio mmc1:0001:1: chipid 001003a0
[    9.946359] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Initialization done
[    9.946373] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Initializing Threads ...
[    9.946380] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for transmission
[    9.946568] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for Debugging
[    9.946713] wilc_sdio mmc1:0001:1: failed to get IRQ GPIO, load default
[    9.946777] wilc_sdio mmc1:0001:1 wlan0: INFO [init_irq]IRQ request succeeded IRQ-NUM= 176
[    9.946857] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]Detect chip WILC1000
[    9.946867] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]loading firmware mchp/wilc1000_wifi_firmware.bin
[    9.946880] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]WLAN firmware: mchp/wilc1000_wifi_firmware.bin
[    9.947192] wilc_handle_isr,>> UNKNOWN_INTERRUPT - 0x00000000
[    9.956349] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Downloading Firmware ...
[    9.956362] wilc_wlan_firmware_downloadDownloading firmware size = 135832
[    9.972352] wilc_wlan_firmware_download Offset = 120380
[    9.974466] wilc_wlan_firmware_download Offset = 135832
[    9.974479] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Download Succeeded
[    9.974516] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Starting Firmware ...
[    9.975170] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Waiting for FW to get ready ...
[   10.088056] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Firmware successfully started
[   10.097503] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Firmware Ver = WILC_WIFI_FW_REL_15_3_1 Build: 11736
[   10.097532] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_fw_config]Start configuring Firmware
[   10.217862] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]Mac address: f8:f0:05:ae:bb:13
[   10.217917] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 0.
[   10.217929] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Enable mcast filter retrive directed pkts only
[   10.218163] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 1.
[   10.218177] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   10.218217] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 2.
[   10.218230] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   10.218242] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:00:00:00:01
[   10.218375] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt]dev [wlan0]
[   10.218388] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt] Power save Enabled= 1 , TimeOut = -1
[   10.245698] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   10.246493] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 2.
[   10.246511] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   10.246523] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:00:00:00:01
[   10.246729] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Mac close
[   10.302643] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Deinitializing wilc
[   10.302677] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing wilc  ...
[   10.302699] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Disabling IRQ
[   10.302819] wilc_sdio mmc1:0001:1: wilc_sdio_disable_interrupt
[   10.302913] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing Threads
[   10.302925] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[   10.302936] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
[   11.031824] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[   11.031854] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing IRQ
[   11.036809] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing WILC Wlan
[   11.036830] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]wilc deinitialization Done
[   11.037924] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   11.037941] wilc_sdio mmc1:0001:1 wlan0: Failed to get mac address
[   11.037950] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   11.037956] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_mac_address:1780] Failed to set mac address
[   11.038517] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[11e0aa06] wlan0
[   11.038538] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[11e0aa06][8d002279]
[   11.038552] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[   11.038559] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
[   11.041840] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Initialization done
[   11.041852] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Initializing Threads ...
[   11.041861] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for transmission
[   11.042324] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for Debugging
[   11.042487] wilc_sdio mmc1:0001:1: got gpio_irq successfully
[   11.042533] wilc_sdio mmc1:0001:1 wlan0: INFO [init_irq]IRQ request succeeded IRQ-NUM= 176
[   11.042629] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]Detect chip WILC1000
[   11.042637] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]loading firmware mchp/wilc1000_wifi_firmware.bin
[   11.042643] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]WLAN firmware: mchp/wilc1000_wifi_firmware.bin
[   11.042868] wilc_handle_isr,>> UNKNOWN_INTERRUPT - 0x0000000e
[   11.043056] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Downloading Firmware ...
[   11.043063] wilc_wlan_firmware_downloadDownloading firmware size = 135832
[   11.063225] wilc_wlan_firmware_download Offset = 120380
[   11.067813] wilc_wlan_firmware_download Offset = 135832
[   11.067829] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Download Succeeded
[   11.067871] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Starting Firmware ...
[   11.070514] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Waiting for FW to get ready ...
[   11.571785] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Firmware start timed out
[   11.571797] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:945] Failed to start firmware
[   11.571807] wilc_sdio mmc1:0001:1: wilc_sdio_disable_interrupt
[   11.571902] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[   11.571907] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
[   12.061854] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[   12.061915] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:985] WLAN initialization FAILED
[   12.061921] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_mac_open:1029] Failed to initialize wilc
[   12.126511] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   12.126527] wilc_sdio mmc1:0001:1 wlan0: ERR [get_tx_power:2254] Failed to get tx power
[   12.126533] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   12.126585] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   12.126591] wilc_sdio mmc1:0001:1 wlan0: ERR [get_tx_power:2254] Failed to get tx power
[   12.126596] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   12.126925] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   12.126931] wilc_sdio mmc1:0001:1 wlan0: ERR [get_tx_power:2254] Failed to get tx power
[   12.126937] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   12.126985] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   12.126991] wilc_sdio mmc1:0001:1 wlan0: ERR [get_tx_power:2254] Failed to get tx power
[   12.126996] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   12.127176] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127183] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127223] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127229] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127267] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127273] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127310] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127316] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127353] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127358] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127396] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127402] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127439] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127445] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127482] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127488] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127524] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127529] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127570] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127576] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127615] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127621] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127661] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127666] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127703] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127709] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127746] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127752] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127789] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127794] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127832] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127838] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127874] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127881] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127918] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127923] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.127961] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.127967] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.128003] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.128009] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.128045] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.128051] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.128087] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.128092] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.128641] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   12.128648] wilc_sdio mmc1:0001:1 wlan0: ERR [get_tx_power:2254] Failed to get tx power
[   12.128654] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   12.128740] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[11e0aa06] wlan0
[   12.128756] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[11e0aa06][8d002279]
[   12.128766] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[   12.128772] wilc_sdio mmc1:0001:1 wlan0: WRN [wilc_wlan_initialize: 987]wilc already initialized
[   12.128780] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.128786] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_operation_mode:1858] Failed to set driver handler
[   12.128793] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[   12.128798] wilc_sdio mmc1:0001:1 wlan0: Failed to get mac address
[   12.128805] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]Mac address: f8:f0:05:ae:bb:13
[   12.128812] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.128817] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[   12.128824] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 0.
[   12.128830] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Enable mcast filter retrive directed pkts only
[   12.128883] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.128892] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[   12.128922] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 1.
[   12.128929] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   12.128951] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 2.
[   12.128957] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   12.128963] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:00:00:00:01
[   12.129010] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.129016] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[   12.129026] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.129031] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[   12.129066] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt]dev [wlan0]
[   12.129072] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt] Power save Enabled= 1 , TimeOut = -1
[   12.129079] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.129085] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_power_mgmt:2486] Failed to send power management
[   12.172303] wilc_sdio mmc1:0001:1 wlan0: INFO [flush_pmksa]Flushing  PMKID key values
[   12.310935] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 1.
[   12.310955] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   12.311001] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.311008] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[   12.320334] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[   12.320346] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[   12.320351] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[   12.320360] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[   12.320377] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   12.320384] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[   12.320391] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[   16.005525] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[   16.005535] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[   16.005541] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[   16.005546] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[   16.005563] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   16.005570] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[   16.005576] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[   39.015721] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[   39.015742] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[   39.015753] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[   39.015763] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[   39.015791] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   39.015804] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[   39.015816] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[   72.029043] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[   72.029061] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[   72.029072] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[   72.029082] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[   72.029115] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[   72.029127] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[   72.029139] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  115.044724] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  115.044741] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  115.044752] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  115.044763] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  115.044791] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  115.044804] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  115.044816] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  168.044531] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  168.044549] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  168.044560] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  168.044570] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  168.044596] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  168.044608] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  168.044620] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  231.034949] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  231.034968] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  231.034979] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  231.034989] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  231.035014] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  231.035026] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  231.035038] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  294.069873] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  294.069893] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  294.069904] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  294.069914] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  294.069941] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  294.069953] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  294.069967] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  357.053143] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 1.
[  357.053155] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[  357.053193] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  357.053201] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[  357.053406] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Mac close
[  357.053424] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Deinitializing wilc
[  357.053430] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing wilc  ...
[  357.053435] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Disabling IRQ
[  357.053441] wilc_sdio mmc1:0001:1: wilc_sdio_disable_interrupt
[  357.053533] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing Threads
[  357.053538] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  357.053543] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
[  357.053549] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  357.053554] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing IRQ
[  357.059888] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing WILC Wlan
[  357.059909] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]wilc deinitialization Done
[  357.061434] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[  357.061451] wilc_sdio mmc1:0001:1 wlan0: Failed to get mac address
[  357.061467] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  357.061478] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_mac_address:1780] Failed to set mac address
[  357.062159] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[11e0aa06] wlan0
[  357.062192] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[11e0aa06][8d002279]
[  357.062211] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[  357.062223] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
[  357.067627] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Initialization done
[  357.067644] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Initializing Threads ...
[  357.067655] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for transmission
[  357.069391] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for Debugging
[  357.069593] wilc_sdio mmc1:0001:1: got gpio_irq successfully
[  357.069647] wilc_sdio mmc1:0001:1 wlan0: INFO [init_irq]IRQ request succeeded IRQ-NUM= 176
[  357.069770] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]Detect chip WILC1000
[  357.069783] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]loading firmware mchp/wilc1000_wifi_firmware.bin
[  357.069794] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]WLAN firmware: mchp/wilc1000_wifi_firmware.bin
[  357.070179] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Downloading Firmware ...
[  357.070192] wilc_wlan_firmware_downloadDownloading firmware size = 135832
[  357.095001] wilc_wlan_firmware_download Offset = 120380
[  357.101124] wilc_wlan_firmware_download Offset = 135832
[  357.101144] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Download Succeeded
[  357.101204] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Starting Firmware ...
[  357.106480] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Waiting for FW to get ready ...
[  357.106498] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Firmware successfully started
[  357.121394] wilc_sdio mmc1:0001:1: wilc_sdio_cmd53..failed, err(-84)
[  357.121410] wilc_sdio mmc1:0001:1: Failed cmd53, read reg (00001078)...
[  357.121465] wilc_sdio mmc1:0001:1: wilc_sdio_cmd52..failed, err(-84)
[  357.121475] wilc_sdio mmc1:0001:1: Failed cmd 52, read reg (000000fc) ...
[  360.154386] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_fw_config]Start configuring Firmware
[  363.194405] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:964] Failed to configure firmware
[  363.197484] wilc_sdio mmc1:0001:1: wilc_sdio_disable_interrupt
[  363.197567] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  363.197572] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
[  363.197599] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  363.197646] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:985] WLAN initialization FAILED
[  363.197652] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_mac_open:1029] Failed to initialize wilc
[  678.071148] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[  678.071166] wilc_sdio mmc1:0001:1 wlan0: Failed to get mac address
[  678.071182] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.071193] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_mac_address:1780] Failed to set mac address
[  678.071860] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[11e0aa06] wlan0
[  678.071893] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[11e0aa06][8d002279]
[  678.071912] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[  678.071926] wilc_sdio mmc1:0001:1 wlan0: WRN [wilc_wlan_initialize: 987]wilc already initialized
[  678.071941] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.071952] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_operation_mode:1858] Failed to set driver handler
[  678.071965] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[  678.071975] wilc_sdio mmc1:0001:1 wlan0: Failed to get mac address
[  678.071989] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]Mac address: f8:f0:05:ae:bb:13
[  678.072003] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.072014] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_frame_register:2271] Failed to frame register
[  678.072042] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 0.
[  678.072055] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Enable mcast filter retrive directed pkts only
[  678.072111] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.072125] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[  678.072182] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 1.
[  678.072195] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[  678.072237] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt]dev [wlan0]
[  678.072248] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt] Power save Enabled= 1 , TimeOut = -1
[  678.072262] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.072273] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_power_mgmt:2486] Failed to send power management
[  678.072624] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.072638] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[  678.159219] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  678.159237] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  678.159249] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  678.159259] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  678.159287] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.159299] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  678.159311] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  678.164887] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  678.164903] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  678.164914] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  678.164924] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  678.164952] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  678.164966] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  678.164977] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  741.072531] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  741.072542] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  741.072548] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  741.072553] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  741.072570] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  741.072579] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  741.072585] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  804.073545] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  804.073564] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  804.073574] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  804.073584] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  804.073611] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  804.073623] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  804.073635] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  867.074123] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  867.074142] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  867.074153] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  867.074163] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  867.074189] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  867.074202] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  867.074214] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  930.033038] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Requested num of channel 14
[  930.033055] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Scan Request IE len =  0
[  930.033066] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Number of SSIDs 1
[  930.033076] wilc_sdio mmc1:0001:1 wlan0: INFO [scan]Trigger Scan Request
[  930.033104] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  930.033116] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_scan:440] Failed to send scan parameters
[  930.033128] wilc_sdio mmc1:0001:1 wlan0: WRN [scan: 341]Device is busy: Error(-110)
[  993.073795] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 1.
[  993.073815] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[  993.073879] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  993.073893] wilc_sdio mmc1:0001:1 wlan0: ERR [handle_set_mcast_filter:1362] Failed to send setup multicast
[  993.074013] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Mac close
[  993.074050] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Deinitializing wilc
[  993.074061] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing wilc  ...
[  993.074071] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Disabling IRQ
[  993.074094] wilc_sdio mmc1:0001:1: wilc_sdio_disable_interrupt
[  993.074234] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing Threads
[  993.074248] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  993.074258] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
[  993.074269] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  993.074279] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing IRQ
[  993.079689] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing WILC Wlan
[  993.079708] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_deinitialize]wilc deinitialization Done
[  993.081744] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1799] Get Timed out
[  993.081761] wilc_sdio mmc1:0001:1 wlan0: Failed to get mac address
[  993.081777] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1816] Set Timed out
[  993.081789] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_mac_address:1780] Failed to set mac address
[  993.082449] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[11e0aa06] wlan0
[  993.082481] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[11e0aa06][8d002279]
[  993.082499] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[  993.082511] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
[  993.089924] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Initialization done
[  993.089944] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Initializing Threads ...
[  993.089954] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for transmission
[  993.090219] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for Debugging
[  993.090410] wilc_sdio mmc1:0001:1: got gpio_irq successfully
[  993.090463] wilc_sdio mmc1:0001:1 wlan0: INFO [init_irq]IRQ request succeeded IRQ-NUM= 176
[  993.090567] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]Detect chip WILC1000
[  993.090613] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]loading firmware mchp/wilc1000_wifi_firmware.bin
[  993.090624] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_get_firmware]WLAN firmware: mchp/wilc1000_wifi_firmware.bin
[  993.091009] wilc_handle_isr,>> UNKNOWN_INTERRUPT - 0x00000010
[  993.091701] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Downloading Firmware ...
[  993.091741] wilc_wlan_firmware_downloadDownloading firmware size = 135832
[  993.115154] wilc_wlan_firmware_download Offset = 120380
[  993.121309] wilc_wlan_firmware_download Offset = 135832
[  993.121333] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_firmware_download]Download Succeeded
[  993.121392] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Starting Firmware ...
[  993.126084] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Waiting for FW to get ready ...
[  993.629338] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_start_firmware]Firmware start timed out
[  993.629352] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:945] Failed to start firmware
[  993.629358] wilc_sdio mmc1:0001:1: wilc_sdio_disable_interrupt
[  993.629503] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  993.629508] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
[  994.109484] wilc_sdio mmc1:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
[  994.109595] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:985] WLAN initialization FAILED
[  994.109607] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_mac_open:1029] Failed to initialize wilc```
ajaykathat commented 4 years ago
[   10.218375] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt]dev [wlan0]
[   10.218388] wilc_sdio mmc1:0001:1 wlan0: INFO [set_power_mgmt] Power save Enabled= 1 , TimeOut = -1
[   10.245698] wilc_sdio mmc1:0001:1 wlan0: INFO [get_tx_power]Got tx power 18
[   10.246493] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 2.
[   10.246511] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 01:00:5e:00:00:01
[   10.246523] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:00:00:00:01
[   10.246729] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_close]Mac close

From the above logs, it appears the mac_close is triggered by HOST during the initialization of the driver. The initialization part looks fine but this mac_close is triggered in between. Please check the causes of mac_close() trigger in the CM3 board.

Please give a try by disabling the PowerSave mode. To disable PS mode, either compile kernel by disabling default PS (CONFIG_CFG80211_DEFAULT_PS) or modify 'wilc_set_power_mgmt' function to always set 'power_mode' variable with WILC_FW_NO_POWERSAVE value.

    if (enabled)
        power_mode = WILC_FW_MIN_FAST_PS;
    else
        power_mode = WILC_FW_NO_POWERSAVE;

to

    power_mode = WILC_FW_NO_POWERSAVE;
Andrflor commented 4 years ago

I tried both options but it didin't end well... I'm wondering if it could be because of reset and chip_en pin state?


[  434.691437] power up request for already powered up source Wifi
[  434.691441] Device already up. request source is Wifi
[  434.691448] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[7a429e69][0014ce1f]
[  434.691459] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[  434.691465] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
[  434.691495] wilc_sdio mmc1:0001:1: SDIO speed: 25000000
[  434.691894] wilc_sdio mmc1:0001:1: wilc_sdio_cmd52..failed, err(-110)
[  434.691904] wilc_sdio mmc1:0001:1: Fail cmd 52, enable csa...
[  434.691912] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:905] Initializing WILC_Wlan FAILED
[  434.691917] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_mac_open:1029] Failed to initialize wilc
[  434.692888] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[7a429e69] wlan0
[  434.692894] power up request for already powered up source Wifi
[  434.692897] Device already up. request source is Wifi
[  434.692905] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_init_host_int]Host[7a429e69][0014ce1f]
[  434.692913] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
[  434.692919] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
[  434.692925] wilc_sdio mmc1:0001:1: SDIO speed: 25000000
[  434.693039] wilc_sdio mmc1:0001:1: wilc_sdio_cmd52..failed, err(-110)
[  434.693045] wilc_sdio mmc1:0001:1: Fail cmd 52, enable csa...
[  434.693052] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_wlan_initialize:905] Initializing WILC_Wlan FAILED
[  434.693057] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_mac_open:1029] Failed to initialize wilc
[  434.693082] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693088] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693093] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693099] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693105] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693110] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693116] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693121] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693127] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693132] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693138] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693143] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693148] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693154] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693160] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693165] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693170] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693176] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693182] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693187] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693193] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  434.693198] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Return since mac is closed
[  435.594678] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_send_config_pkt:1806] Set Timed out
[  435.594695] wilc_sdio mmc1:0001:1 wlan0: ERR [wilc_set_operation_mode:1858] Failed to set driver handler
[  435.594712] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_netdev_cleanup]Unregistering netdev 7a429e69
[  435.684908] De-Initializing Locks
[  435.734648] Freeing wiphy
[  435.734669] Module_exit Done.
[  435.734675] at_pwr_dev: deinit
[  435.734920] at_pwr_dev: unregistered
[  435.735050] mmc1: card 0001 removed