Open HDC67 opened 5 years ago
This look like the same issue fixed in 6b33f29. I tried the following sequence on the head of dev branch, and it succeeded 10/10 times. `modprobe wilc_spi wpa_supplicant -Dnl80211 -iwlan0 -c/etc/wilc_wpa_supplicant.conf &
killall wpa_supplicant rmmod wilc_spi`
That works OK but is not what I listed in the reproduction method. You're also not using an SDIO connected device.
Please try with what I posted, not something else.
modprobe wilc-sdio; \
ifconfig wlan0 up; \
/usr/sbin/wpa_supplicant -Dnl80211 -iwlan0 -c /etc/wpa_supplicant.conf -B; \
rmmod wilc-sdio
Which driver commit is the latest wilc_demo up to? There is no version numbering to be able to tell.
The latest demo maps to release 15.01 release as per the commit message. This release is tagged on the driver, firmware and linux-at91 repos, and it doesn't have the fix i'm pointing to.
I'm not expecting much difference between spi and sdio since the warning is triggered from the cfg layer while closing the net interface, so the bus shouldn't differ much.
Using -dev "updated demo binaries for release 15.2 RC1" is the commit message so no it doesn’t give the driver version. The master driver is much too old/broken to use.
Have upgraded the firmware binaries to 15.2 RC3 (build 10924) firmware.
Seems to crash every time on the second loop when modprobing module:
# cat wifitest.sh
#!/bin/sh
while true; do
modprobe wilc-sdio
sleep 3
ifconfig wlan0 up
sleep 1
/usr/sbin/wpa_supplicant -Dnl80211 -iwlan0 -c /etc/wpa_supplicant.conf -B
sleep 20
rmmod wilc-sdio
sleep 1
killall wpa_supplicant
sleep 5
done
Full log
AT91Bootstrap 3.8.8 (Thu May 31 12:57:18 MST 2018)
EEPROM: Loading AT24xx information ...
EEPROM: BoardName | [Revid] | VendorName
HW Info: The total size: 0xff isn't correct
EEPROM: Using default information
EEPROM: Board sn: 0x1012420 revision: 0x680820
HDMI SiI9022: Enter D3 Cold mode
NAND: ONFI flash detected
NAND: Manufacturer ID: 0x2c Chip ID: 0x34
NAND: Page Bytes: 4096, Spare Bytes: 224
NAND: ECC Correctability Bits: 8, ECC Sector Bytes: 512
NAND: Disable On-Die ECC
NAND: Initialize PMECC params, cap: 8, sector: 512
NAND: Image: Copy 0xa0000 bytes from 0x40000 to 0x26f00000
NAND: Done to load image
<debug_uart>
U-Boot 2017.03-linux4sam_5.6 (May 31 2018 - 12:57:41 -0700)
CPU: SAMA5D44
Crystal frequency: 12 MHz
CPU clock : 600 MHz
Master clock : 200 MHz
DRAM: 512 MiB
NAND: 512 MiB
MMC: Atmel mci: 0
In: serial
Out: serial
Err: serial
Net: eth0: ethernet@f8020000
Hit any key to stop autoboot: 0
NAND read: device 0 offset 0x180000, size 0x817c
33148 bytes read: OK
NAND read: device 0 offset 0x200000, size 0x3df7c8
4061128 bytes read: OK
## Flattened Device Tree blob at 21000000
Booting using the fdt blob at 0x21000000
Loading Device Tree to 3f951000, end 3f95c17b ... OK
Starting kernel ...
Booting Linux on physical CPU 0x0
Linux version 4.9.36 (adham@adham-Latitude-E7450) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #1 Thu May 31 13:04:12 MST 2018
CPU: ARMv7 Processor [410fc051] revision 1 (ARMv7), cr=10c53c7d
CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
OF: fdt:Machine model: Atmel SAMA5D4 Xplained
cma: Reserved 64 MiB at 0x38000000
Memory policy: Data cache writeback
CPU: All CPU(s) started in SVC mode.
Built 1 zonelists in Zone order, mobility grouping on. Total pages: 130048
Kernel command line: console=ttyS0,115200 mtdparts=atmel_nand:256k(bootstrap)ro,512k(uboot)ro,256k(env),256k(env_redundant),256k(spare),512k(dtb),6M(kernel)ro,-(rootfs) rootfstype=ubifs ubi.mtd=7 root=ubi0:rootfs rw
PID hash table entries: 2048 (order: 1, 8192 bytes)
Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Memory: 444308K/524288K available (6144K kernel code, 196K rwdata, 1492K rodata, 1024K init, 193K bss, 14444K reserved, 65536K cma-reserved)
Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xe0800000 - 0xff800000 ( 496 MB)
lowmem : 0xc0000000 - 0xe0000000 ( 512 MB)
modules : 0xbf000000 - 0xc0000000 ( 16 MB)
.text : 0xc0008000 - 0xc0700000 (7136 kB)
.init : 0xc0900000 - 0xc0a00000 (1024 kB)
.data : 0xc0a00000 - 0xc0a31220 ( 197 kB)
.bss : 0xc0a31220 - 0xc0a61950 ( 194 kB)
NR_IRQS:16 nr_irqs:16 16
L2C-310 ID prefetch enabled, offset 2 lines
L2C-310 dynamic clock gating enabled, standby mode enabled
L2C-310 cache controller enabled, 8 ways, 128 kB
L2C-310: CACHE_ID 0x410000c9, AUX_CTRL 0x36020000
clocksource: pit: mask: 0x7ffffff max_cycles: 0x7ffffff, max_idle_ns: 9556302164 ns
sched_clock: 32 bits at 100 Hz, resolution 10000000ns, wraps every 21474836475000000ns
Console: colour dummy device 80x30
Calibrating delay loop... 398.13 BogoMIPS (lpj=1990656)
pid_max: default: 32768 minimum: 301
Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
CPU: Testing write buffer coherency: ok
Setting up static identity map for 0x20100000 - 0x20100058
devtmpfs: initialized
VFP support v0.3: implementor 41 architecture 2 part 30 variant 5 rev 1
clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
futex hash table entries: 256 (order: -1, 3072 bytes)
pinctrl core: initialized pinctrl subsystem
NET: Registered protocol family 16
DMA: preallocated 256 KiB pool for atomic coherent allocations
cpuidle: using governor ladder
AT91: Detected SoC family: sama5d4
AT91: Detected SoC: sama5d44, revision 0
gpio-at91 fc06a000.gpio: at address e0818000
gpio-at91 fc06b000.gpio: at address e081a000
gpio-at91 fc06c000.gpio: at address e081c000
gpio-at91 fc068000.gpio: at address e081e000
gpio-at91 fc06d000.gpio: at address e0831000
pinctrl-at91 ahb:apb:pinctrl@fc06a000: initialized AT91 pinctrl driver
clocksource: tcb_clksrc: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 152900835740 ns
at_xdmac f0004000.dma-controller: 16 channels, mapped at 0xe0837000
at_xdmac f0014000.dma-controller: 16 channels, mapped at 0xe0839000
VDD MCI1: supplied by VCC 3V3
SCSI subsystem initialized
usbcore: registered new interface driver usbfs
usbcore: registered new interface driver hub
usbcore: registered new device driver usb
at91_i2c f8014000.i2c: using dma0chan0 (tx) and dma0chan1 (rx) for DMA transfers
at91_i2c f8014000.i2c: AT91 i2c bus driver (hw version: 0x502).
media: Linux media interface: v0.10
Linux video capture interface: v2.00
Advanced Linux Sound Architecture Driver Initialized.
Bluetooth: Core ver 2.22
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: L2CAP socket layer initialized
Bluetooth: SCO socket layer initialized
clocksource: Switched to clocksource tcb_clksrc
NET: Registered protocol family 2
TCP established hash table entries: 4096 (order: 2, 16384 bytes)
TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
TCP: Hash tables configured (established 4096 bind 4096)
UDP hash table entries: 256 (order: 0, 4096 bytes)
UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
NET: Registered protocol family 1
RPC: Registered named UNIX socket transport module.
RPC: Registered udp transport module.
RPC: Registered tcp transport module.
RPC: Registered tcp NFSv4.1 backchannel transport module.
workingset: timestamp_bits=30 max_order=17 bucket_order=0
io scheduler noop registered (default)
f8004000.serial: ttyS5 at MMIO 0xf8004000 (irq = 25, base_baud = 6250000) is a ATMEL_SERIAL
fc00c000.serial: ttyS0 at MMIO 0xfc00c000 (irq = 31, base_baud = 6250000) is a ATMEL_SERIAL
console [ttyS0] enabled
fc010000.serial: ttyS1 at MMIO 0xfc010000 (irq = 32, base_baud = 6250000) is a ATMEL_SERIAL
[drm] Initialized
brd: module loaded
loop: module loaded
atmel_nand_nfc 90000000.nfc: NFC is probed.
atmel_nand 80000000.nand: Using dma0chan2 for DMA transfers.
nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xdc
nand: Micron MT29F4G08ABAEAWP
nand: 512 MiB, SLC, erase size: 256 KiB, page size: 4096, OOB size: 224
atmel_nand 80000000.nand: Use On Flash BBT
atmel_nand 80000000.nand: Cannot get PMECC lookup table offset, will build a lookup table in runtime.
atmel_nand 80000000.nand: minimum ECC: 8 bits in 512 bytes
atmel_nand 80000000.nand: Initialize PMECC params, cap: 8, sector: 512
atmel_nand 80000000.nand: Using NFC Sram read and write
Bad block table found at page 131008, version 0x01
Bad block table found at page 130944, version 0x01
8 cmdlinepart partitions found on MTD device atmel_nand
Creating 8 MTD partitions on "atmel_nand":
0x000000000000-0x000000040000 : "bootstrap"
0x000000040000-0x0000000c0000 : "uboot"
0x0000000c0000-0x000000100000 : "env"
0x000000100000-0x000000140000 : "env_redundant"
0x000000140000-0x000000180000 : "spare"
0x000000180000-0x000000200000 : "dtb"
0x000000200000-0x000000800000 : "kernel"
0x000000800000-0x000020000000 : "rootfs"
atmel_spi f8010000.spi: version: 0x221
atmel_spi f8010000.spi: Using dma0chan3 (tx) and dma0chan4 (rx) for DMA transfers
m25p80 spi32766.0: unrecognized JEDEC id bytes: 00, 00, 00
atmel_spi f8010000.spi: Atmel SPI Controller at 0xf8010000 (irq 26)
atmel_spi fc018000.spi: version: 0x221
atmel_spi fc018000.spi: Using dma0chan5 (tx) and dma0chan6 (rx) for DMA transfers
atmel_spi fc018000.spi: Atmel SPI Controller at 0xfc018000 (irq 33)
libphy: Fixed MDIO Bus: probed
CAN device driver interface
libphy: MACB_mii_bus: probed
Micrel KSZ8081 or KSZ8091 f8020000.etherne:01: attached PHY driver [Micrel KSZ8081 or KSZ8091] (mii_bus:phy_addr=f8020000.etherne:01, irq=175)
macb f8020000.ethernet eth0: Cadence GEM rev 0x00020120 at 0xf8020000 irq 29 (fc:c2:3d:0d:ac:e3)
ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
ehci-atmel: EHCI Atmel driver
atmel-ehci 600000.ehci: EHCI Host Controller
atmel-ehci 600000.ehci: new USB bus registered, assigned bus number 1
atmel-ehci 600000.ehci: irq 19, io mem 0x00600000
atmel-ehci 600000.ehci: USB 2.0 started, EHCI 1.00
usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
usb usb1: Product: EHCI Host Controller
usb usb1: Manufacturer: Linux 4.9.36 ehci_hcd
usb usb1: SerialNumber: 600000.ehci
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 3 ports detected
ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
ohci-atmel: OHCI Atmel driver
at91_ohci 500000.ohci: failed to find sfr node
at91_ohci 500000.ohci: USB Host Controller
at91_ohci 500000.ohci: new USB bus registered, assigned bus number 2
at91_ohci 500000.ohci: irq 19, io mem 0x00500000
usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
usb usb2: Product: USB Host Controller
usb usb2: Manufacturer: Linux 4.9.36 ohci_hcd
usb usb2: SerialNumber: at91
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 3 ports detected
usbcore: registered new interface driver cdc_acm
cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
usbcore: registered new interface driver usb-storage
usbcore: registered new interface driver usbserial
usbcore: registered new interface driver usbserial_generic
usbserial: USB Serial support registered for generic
usbcore: registered new interface driver ftdi_sio
usbserial: USB Serial support registered for FTDI USB Serial Device
usbcore: registered new interface driver pl2303
usbserial: USB Serial support registered for pl2303
rtc rtc0: alarm rollover not handled
rtc rtc0: invalid alarm value: 1900-1-1 0:0:0
at91_rtc fc0686b0.rtc: rtc core: registered fc0686b0.rtc as rtc0
at91_rtc fc0686b0.rtc: AT91 Real Time Clock driver.
i2c /dev entries driver
AT91: Starting after user reset
sama5d4_wdt fc068640.watchdog: initialized (timeout = 16 sec, nowayout = 0)
Bluetooth: HCI UART driver ver 2.3
Bluetooth: HCI UART protocol H4 registered
Bluetooth: HCI UART protocol BCSP registered
Bluetooth: HCI UART protocol LL registered
Bluetooth: HCI UART protocol ATH3K registered
Bluetooth: HCI UART protocol Three-wire (H5) registered
sdhci: Secure Digital Host Controller Interface driver
sdhci: Copyright(c) Pierre Ossman
atmel_mci fc000000.mmc: reg property is missing for /ahb/apb/mmc@fc000000/wilc_sdio@0
atmel_mci fc000000.mmc: version: 0x600
atmel_mci fc000000.mmc: using dma0chan7 for DMA transfers
atmel_mci fc000000.mmc: Atmel MCI controller at 0xfc000000 irq 30, 1 slots
sdhci-pltfm: SDHCI platform and OF driver helper
ledtrig-cpu: registered to indicate activity on CPUs
atmel_aes fc044000.aes: version: 0x201
atmel_aes fc044000.aes: Atmel AES - Using dma1chan0, dma1chan1 for DMA transfers
atmel_sha fc050000.sha: version: 0x420
atmel_sha fc050000.sha: using dma1chan2 for DMA transfers
atmel_sha fc050000.sha: Atmel SHA1/SHA256/SHA224/SHA384/SHA512
atmel_tdes fc04c000.tdes: version: 0x702
atmel_tdes fc04c000.tdes: using dma1chan3, dma1chan4 for DMA transfers
atmel_tdes fc04c000.tdes: Atmel DES/TDES
usbcore: registered new interface driver usbhid
usbhid: USB HID core driver
iio iio:device0: Resolution used: 10 bits
iio iio:device0: ADC Touch screen is disabled.
NET: Registered protocol family 10
sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
NET: Registered protocol family 17
can: controller area network core (rev 20120528 abi 9)
NET: Registered protocol family 29
can: raw protocol (rev 20120528)
can: broadcast manager protocol (rev 20161123 t)
can: netlink gateway (rev 20130117) max_hops=1
ubi0: default fastmap pool size: 100
ubi0: default fastmap WL pool size: 50
ubi0: attaching mtd7
random: fast init done
mmc0: new high speed SDIO card at address 0001
ubi0: scanning is finished
ubi0: attached mtd7 (name "rootfs", size 504 MiB)
ubi0: PEB size: 262144 bytes (256 KiB), LEB size: 253952 bytes
ubi0: min./max. I/O unit sizes: 4096/4096, sub-page size 4096
ubi0: VID header offset: 4096 (aligned 4096), data offset: 8192
ubi0: good PEBs: 2012, bad PEBs: 4, corrupted PEBs: 0
ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
ubi0: max/mean erase counter: 2/0, WL threshold: 4096, image sequence number: 1986554931
ubi0: available PEBs: 0, total reserved PEBs: 2012, PEBs reserved for bad PEB handling: 36
input: gpio_keys as /devices/soc0/gpio_keys/input/input0
ubi0: background thread "ubi_bgt0d" started, PID 101
at91_rtc fc0686b0.rtc: setting system clock to 2012-01-01 00:21:36 UTC (1325377296)
ALSA device list:
No soundcards found.
atmel_usart fc00c000.serial: using dma0chan8 for rx DMA transfers
atmel_usart fc00c000.serial: using dma0chan9 for tx DMA transfers
UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 102
UBIFS (ubi0:0): recovery needed
random: crng init done
UBIFS (ubi0:0): recovery completed
UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs"
UBIFS (ubi0:0): LEB size: 253952 bytes (248 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
UBIFS (ubi0:0): FS size: 497745920 bytes (474 MiB, 1960 LEBs), journal size 9404416 bytes (8 MiB, 38 LEBs)
UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID 42AB1827-036A-4DA8-BC22-AA80F1632338, small LPT model
VFS: Mounted root (ubifs filesystem) on device 0:12.
devtmpfs: mounted
Freeing unused kernel memory: 1024K (c0900000 - c0a00000)
Starting logging: OK
Populating /dev using udev: /etc/init.d/S10udev: line 47: can't create /proc/sys/kernel/hotplug: nonexistent directory
udevd[118]: starting version 3.2.2
udevd[119]: starting eudev-3.2.2
atmel_usba_udc 400000.gadget: MMIO registers at 0xfc02c000 mapped at e0ae4000
atmel_usba_udc 400000.gadget: FIFO at 0x00400000 mapped at e9100000
done
Initializing random number generator... done.
Starting system message bus: Unknown username "pulse" in message bus configuration file
done
Starting network: Internet Systems Consortium DHCP Client 4.3.5
Copyright 2004-2016 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/
IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Listening on LPF/eth0/fc:c2:3d:0d:ac:e3
Sending on LPF/eth0/fc:c2:3d:0d:ac:e3
Sending on Socket/fallback
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
macb f8020000.ethernet eth0: link up (100/Full)
IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 9
DHCPREQUEST on eth0 to 255.255.255.255 port 67
DHCPOFFER from 10.1.2.1
DHCPACK from 10.1.2.1
bound to 10.1.2.223 -- renewal in 7002 seconds.
OK
Starting radvd: done
[Jan 01 00:21:44] radvd (197): wlan0 not found: No such device
Starting sshd: OK
Starting DHCP server: FAIL
Welcome to Buildroot
buildroot login: root
# cat wifitest.sh
#!/bin/sh
while true; do
modprobe wilc-sdio
sleep 3
ifconfig wlan0 up
sleep 1
/usr/sbin/wpa_supplicant -Dnl80211 -iwlan0 -c /etc/wpa_supplicant.conf -B
sleep 20
rmmod wilc-sdio
sleep 1
killall wpa_supplicant
sleep 5
done
# ./wifitest.sh
wilc_sdio: module is from the staging directory, the quality is unknown, you have been warned.
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Registering wifi device
(unnamed net_device) (uninitialized): INFO [wilc_wfi_cfg_alloc]Allocating wireless device
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Successful Registering
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Registering wifi device
(unnamed net_device) (uninitialized): INFO [wilc_wfi_cfg_alloc]Allocating wireless device
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Successful Registering
wilc_sdio mmc0:0001:1: WILC got 60 for gpio_reset
wilc_sdio mmc0:0001:1: WILC got 94 for gpio_chip_en
wilc_sdio mmc0:0001:1: WILC got 91 for gpio_irq
wifi_pm : 0
wifi_pm : 1
wilc_sdio mmc0:0001:1: Driver Initializing success
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mac_open]MAC OPEN[d45ee800] wlan0
WILC POWER UP
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_init_host_int]Host[d45ee800][d4636c00]
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mac_open]*** re-init ***
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_init_locks]Initializing Locks ...
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_init]Initializing WILC_Wlan
wilc_sdio mmc0:0001:1: SDIO speed: 50000000
wilc_sdio mmc0:0001:1: chipid 001003a0
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Initialization done
wilc_sdio mmc0:0001:1 wlan0: INFO [init_irq]IRQ request succeeded IRQ-NUM= 137 on GPIO: 91
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_initialize_threads]Initializing Threads ...
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for transmission
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_initialize_threads]Creating kthread for Debugging
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_get_firmware]Detect chip WILC1000
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_get_firmware]loading firmware mchp/wilc1000_wifi_firmware.bin
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_get_firmware]WLAN firmware: mchp/wilc1000_wifi_firmware.bin
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_firmware_download]Downloading Firmware ...
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_firmware_download]Downloading firmware size = 135020
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_firmware_download]Offset = 119704
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_firmware_download]Offset = 135020
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_firmware_download]Download Succeeded
wilc_sdio mmc0:0001:1 wlan0: INFO [linux_wlan_start_firmware]Starting Firmware ...
wilc_sdio mmc0:0001:1: wilc_sdio_cmd52..failed, err(-84)
wilc_sdio mmc0:0001:1: Failed cmd 52, read reg (000000fc) ...
wilc_sdio mmc0:0001:1 wlan0: INFO [linux_wlan_start_firmware]Waiting for FW to get ready ...
wilc_sdio mmc0:0001:1 wlan0: INFO [linux_wlan_start_firmware]Firmware successfully started
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_initialize]WILC Firmware Ver = WILC_WIFI_FW_REL_15_2_RC3 Build: 10924
wilc_sdio mmc0:0001:1 wlan0: INFO [linux_wlan_init_test_config]Start configuring Firmware
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mac_open]Mac address: f8:f0:05:f1:48:66
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 2.
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 33:33:0:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:0:0:0:2
wilc_sdio mmc0:0001:1 wlan0: INFO [set_power_mgmt] Power save Enabled= 0 , TimeOut = -1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 3.
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 33:33:0:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:0:0:0:2
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[2]: 1:0:5e:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 4.
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 33:33:0:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:0:0:0:2
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[2]: 1:0:5e:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[3]: 33:33:ff:f1:48:66
Successfully initialized wpa_supplicant
wilc_sdio mmc0:0001:1 wlan0: INFO [get_tx_power]Got tx power 0
wilc_sdio mmc0:0001:1 wlan0: INFO [get_tx_power]Got tx power 0
wilc_sdio mmc0:0001:1 wlan0: INFO [get_tx_power]Got tx power 0
wilc_sdio mmc0:0001:1 wlan0: INFO [get_tx_power]Got tx power 0
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 1
wilc_sdio mmc0:0001:1 wlan0: INFO [get_tx_power]Got tx power 0
wilc_sdio mmc0:0001:1 wlan0: INFO [flush_pmksa]Flushing PMKID key values
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Setting mcast List with count = 5.
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[0]: 33:33:0:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[1]: 33:33:0:0:0:2
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[2]: 1:0:5e:0:0:1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[3]: 33:33:ff:f1:48:66
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_set_multicast_list]Entry[4]: 33:33:ff:0:0:0
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Requested num of scan channel 14
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Scan Request IE len = 126
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Number of SSIDs 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wfi_cfg_alloc_fill_ssid]Received one NULL SSID
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Trigger Scan Request
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network SM01AP2 found
wilc_sdio mmc0:0001:1 wlan0: INFO [is_network_in_shadow]Starting Aging timer
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Update RSSI of SM01AP2
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network FreedomShared@Galleries found
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_scan_complete_received]Scan notification received
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network DIRECT-41-HP M477 LaserJet found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network DIRECT-3F-HP OfficeJet Pro 8740 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network arqus found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Scan Done[d45ee800]
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Refreshing Scan ...
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]5 Network(s) found
wilc_sdio mmc0:0001:1 wlan0: INFO [debug_thread]*** Debug Thread Running ***
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Requested num of scan channel 14
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Scan Request IE len = 126
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Number of SSIDs 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wfi_cfg_alloc_fill_ssid]Received one NULL SSID
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Trigger Scan Request
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network SM01AP2 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Update RSSI of SM01AP2
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network Telstra447F2B found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network .U308@Galleries-2G found
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_scan_complete_received]Scan notification received
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network FreedomShared@Galleries found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network DIRECT-3F-HP OfficeJet Pro 8740 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Scan Done[d45ee800]
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Refreshing Scan ...
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]5 Network(s) found
wilc_sdio mmc0:0001:1 wlan0: INFO [remove_network_from_shadow]Number of cached networks: 7
wilc_sdio mmc0:0001:1 wlan0: INFO [debug_thread]*** Debug Thread Running ***
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Requested num of scan channel 14
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Scan Request IE len = 126
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Number of SSIDs 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wfi_cfg_alloc_fill_ssid]Received one NULL SSID
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Trigger Scan Request
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network SM01AP2 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Update RSSI of SM01AP2
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Update RSSI of SM01AP2
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network DIRECT-3F-HP OfficeJet Pro 8740 found
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_scan_complete_received]Scan notification received
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network HUAWEI-hK78 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network TelstraE82DE9 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Scan Done[d45ee800]
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Refreshing Scan ...
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]4 Network(s) found
wilc_sdio mmc0:0001:1 wlan0: INFO [debug_thread]*** Debug Thread Running ***
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Requested num of scan channel 14
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Scan Request IE len = 126
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Number of SSIDs 1
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wfi_cfg_alloc_fill_ssid]Received one NULL SSID
wilc_sdio mmc0:0001:1 wlan0: INFO [scan]Trigger Scan Request
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network SM01AP2 found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Update RSSI of SM01AP2
wilc_sdio mmc0:0001:1 wlan0: INFO [remove_network_from_shadow]Number of cached networks: 9
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network .U308@Galleries-2G found
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_scan_complete_received]Scan notification received
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network FreedomShared@Galleries found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Network DIRECT-41-HP M477 LaserJet found
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Scan Done[d45ee800]
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]Refreshing Scan ...
wilc_sdio mmc0:0001:1 wlan0: INFO [cfg_scan_result]4 Network(s) found
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mac_close]Mac close
wilc_sdio mmc0:0001:1 wlan0: ERR [host_if_work: 3000] [Host Interface] undefined
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mac_close]Deinitializing wilc
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing wilc ...
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Disabling IRQ
wilc_sdio mmc0:0001:1: wilc_sdio_disable_interrupt
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing Threads
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinit debug Thread
wilc_sdio mmc0:0001:1 wlan0: INFO [debug_thread]Exit debug thread
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_deinitialize_threads]Deinitializing Threads
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing IRQ
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_stop]Read RESET Reg fffffbff : Retry10
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_stop]Bit 10 reset after : Retry 10
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_stop]Read RESET Reg fffffbff : Retry10
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing WILC Wlan
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]Deinitializing Locks
wilc_sdio mmc0:0001:1 wlan0: INFO [wlan_deinit_locks]De-Initializing Locks
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_wlan_deinitialize]wilc deinitialization Done
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_netdev_cleanup]Unregistering netdev d45ee800
wilc_sdio mmc0:0001:1 wlan0: INFO [wilc_mac_close]Mac close
wilc_sdio mmc0:0001:1 wlan0: ERR [wilc_mac_close: 1534] MAC close called with no opened interfaces
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Frame registering Frame Type: d0: Boolean: 0
wilc_sdio mmc0:0001:1 wlan0 (unregistering): INFO [wilc_mgmt_frame_register]Return since mac is closed
wilc_sdio mmc0:0001:1 wlan0 (unregistered): INFO [wilc_netdev_cleanup]Freeing Wiphy...
wilc_sdio mmc0:0001:1 wlan0 (unregistered): INFO [wilc_free_wiphy]Unregistering wiphy
wilc_sdio mmc0:0001:1 wlan0 (unregistered): INFO [wilc_free_wiphy]Freeing wiphy
wilc_sdio mmc0:0001:1 wlan0 (unregistered): INFO [wilc_netdev_cleanup]Freeing netdev...
wilc_sdio mmc0:0001:1 p2p0: INFO [wilc_netdev_cleanup]Unregistering netdev d4617800
wilc_sdio mmc0:0001:1 p2p0 (unregistered): INFO [wilc_netdev_cleanup]Freeing Wiphy...
wilc_sdio mmc0:0001:1 p2p0 (unregistered): INFO [wilc_free_wiphy]Unregistering wiphy
wilc_sdio mmc0:0001:1 p2p0 (unregistered): INFO [wilc_free_wiphy]Freeing wiphy
wilc_sdio mmc0:0001:1 p2p0 (unregistered): INFO [wilc_netdev_cleanup]Freeing netdev...
Module_exit Done.
at_pwr_dev: deinit
at_pwr_dev: unregistered
[Jan 01 00:22:56] radvd (197): wlan0 not found: No such device
wilc_sdio: module is from the staging directory, the quality is unknown, you have been warned.
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Registering wifi device
(unnamed net_device) (uninitialized): INFO [wilc_wfi_cfg_alloc]Allocating wireless device
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Successful Registering
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Registering wifi device
(unnamed net_device) (uninitialized): INFO [wilc_wfi_cfg_alloc]Allocating wireless device
(unnamed net_device) (uninitialized): INFO [wilc_create_wiphy]Successful Registering
wilc_sdio mmc0:0001:1: WILC got 60 for gpio_reset
wilc_sdio mmc0:0001:1: WILC got 94 for gpio_chip_en
wilc_sdio mmc0:0001:1: WILC got 91 for gpio_irq
wifi_pm : 0
wifi_pm : 1
wilc_sdio mmc0:0001:1: Driver Initializing success
Unable to handle kernel NULL pointer dereference at virtual address 00000000
pgd = c0004000
[00000000] *pgd=00000000
Internal error: Oops: 80000007 [#1] ARM
Modules linked in: wilc_sdio(C) atmel_usba_udc [last unloaded: wilc_sdio]
CPU: 0 PID: 0 Comm: swapper Tainted: G C 4.9.36 #1
Hardware name: Atmel SAMA5
task: c0a054c0 task.stack: c0a00000
PC is at 0x0
LR is at call_timer_fn.constprop.6+0x28/0x98
pc : [<00000000>] lr : [<c0149614>] psr: 40000113
sp : c0a01e78 ip : 000000c0 fp : 00000000
r10: c0a01e98 r9 : 00000000 r8 : 00000000
r7 : 00000200 r6 : 00000000 r5 : 00000100 r4 : ffffe000
r3 : c0a00000 r2 : 00000000 r1 : 00000000 r0 : 00000000
Flags: nZcv IRQs on FIQs on Mode SVC_32 ISA ARM Segment none
Control: 10c53c7d Table: 34710059 DAC: 00000051
Process swapper (pid: 0, stack limit = 0xc0a00208)
Stack: (0xc0a01e78 to 0xc0a02000)
1e60: c0a01ef4 0000001c
1e80: c0a0c3c0 c0a0c3d4 c0a0c414 c01497d8 00000000 c0a0cc40 00000000 d40c1600
1ea0: 00000000 c0a01ef4 0000001c c04af66c d411b440 c01410b0 c0a01f40 00000000
1ec0: 00000001 c0a00000 c0a31a44 c0a31a40 40000001 00000100 c0a31a40 c0119234
1ee0: d4002200 c0a00000 00000000 c0a31a00 0000000a ffffadc1 c0a0cc40 00200100
1f00: d40c1600 c0a1329c 0000001c 00000000 00000000 d4002200 c0a00000 00000000
1f20: 00000000 c0140a58 c0487450 80000013 ffffffff c0a01f74 d2718180 c010b2cc
1f40: 00000000 00000014 d2718180 c0a098f0 d2718180 00000014 c0a24230 00000001
1f60: d2718180 00000014 00000000 00000000 98968000 c0a01f90 c048744c c0487450
1f80: 80000013 ffffffff 00000051 00000000 c0a24230 c0a24230 c0a00000 c0a0286c
1fa0: c0a24228 c0a02874 c0a0de07 c0a2462c 00000000 c01392fc ffffffff c0900c38
1fc0: ffffffff ffffffff 00000000 c0900684 00000000 c092ca28 c0a31414 c0a02818
1fe0: c092ca24 c0a065f4 20004059 410fc051 00000000 20008078 00000000 00000000
[<c0149614>] (call_timer_fn.constprop.6) from [<c01497d8>] (run_timer_softirq+0x154/0x170)
[<c01497d8>] (run_timer_softirq) from [<c0119234>] (__do_softirq+0xac/0x21c)
[<c0119234>] (__do_softirq) from [<c0140a58>] (__handle_domain_irq+0x58/0xa8)
[<c0140a58>] (__handle_domain_irq) from [<c010b2cc>] (__irq_svc+0x6c/0x90)
[<c010b2cc>] (__irq_svc) from [<c0487450>] (cpuidle_enter_state+0x17c/0x29c)
[<c0487450>] (cpuidle_enter_state) from [<c01392fc>] (cpu_startup_entry+0xb8/0x144)
[<c01392fc>] (cpu_startup_entry) from [<c0900c38>] (start_kernel+0x35c/0x368)
Code: bad PC value
---[ end trace 76663530cd67116c ]---
Kernel panic - not syncing: Fatal exception in interrupt
---[ end Kernel panic - not syncing: Fatal exception in interrupt
I didn't get your comment clearly
Using -dev "updated demo binaries for release 15.2 RC1" is the commit message so no it doesn’t give the driver version
the driver version used for this commit is 15.2 RC1 from the dev branch.
Did you update the driver to the head of t he dev branch as well, or only the FW? please note that the fix I was referring to in my comments is in the driver.
I had your script running for ~30 mins, where it did 70 cycles, and I couldn't see the failure. wifi_init_logs.txt
@ShonkyCH , are you still seeing this problem?
I have also been testing for this issue yesterday and again today. Yes, there is still an issue with this sequence. More in a few hours.... Summary:
Yes we are still seeing this. It seems more of a problem when the module is receiving traffic e.g. flood ping it or do a constant UDP iperf from another machine to the module so it's always receiving packets.
I had the similar experience with trying to integrate ATWILC1000 with a Raspberry Pi CM3+. I could see the kernel oops saying null kernel reference. When I dig deeper I could see this oops was happening when CMD52 read error occurs. I had wired the two dev kits with wires and I thought this could be a signal integrity issue as my sdio clock was running at 50MHz, So I reduced the sdio clock to 25MHz just to test and it solved the problem. I have tested the driver with iperf, flood pings, wget large files and it seems to hold.
this is the bus error [ 345.520541] wilc_sdio mmc1:0001:1 wlan0: INFO [wilc_mac_xmit]DHCP Message transmitted, type:35 1 1 [ 345.870812] wilc_sdio mmc1:0001:1: wilc_sdio_cmd52..failed, err(-84) [ 345.870826] wilc_sdio mmc1:0001:1: Failed cmd 52, read reg (000000f1) ... [ 345.870835] Bus error (5).0 0 [ 346.390762] wilc_sdio mmc1:0001:1: wilc_sdio_cmd52..failed, err(-84) [ 346.390775] wilc_sdio mmc1:0001:1: Failed cmd 52, read reg (000000f1) ... [ 346.390784] Bus error (5).0 0 [ 346.393224] Unable to handle kernel NULL pointer dereference at virtual address 0000001a [ 346.405472] pgd = c5a7bbf3 [ 346.410225] [0000001a] *pgd=00000000
the kernel oops is [ 89.163586] Unable to handle kernel NULL pointer dereference at virtual address 00000004 [ 89.176083] pgd = 53ee692f [ 89.180981] [00000004] *pgd=00000000 [ 89.186715] Internal error: Oops: 17 [#1] SMP ARM [ 89.193532] Modules linked in: wilc_sdio sha256_generic cfg80211 rfkill snd_bcm2835(C) bcm2835_codec(C) bcm2835_v4l2(C) v4l2_mem2mem v4l2_common bcm2835_mmal_vchiq(C) snd_pcm videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 snd_timer videobuf2_common snd raspberrypi_hwmon hwmon videodev media vc_sm_cma(C) fixed uio_pdrv_genirq uio ip_tables x_tables ipv6 [ 89.238347] CPU: 2 PID: 623 Comm: K_TXQ_TASK Tainted: G C 4.19.30-v7+ #6 [ 89.251273] Hardware name: BCM2835 [ 89.257259] PC is at wilc_wlan_txq_remove_from_head+0x60/0xa8 [wilc_sdio]
I think the driver is not gracefully handling a CMD52 read failure. This was failing (in my case) in kernel thread "K_TXQ_TASK " and executing wilc_wlan_txq_remove_from_head () function.
Cheers, Kaushalyas
Yes we are still seeing this. It seems more of a problem when the module is receiving traffic e.g. flood ping it or do a constant UDP iperf from another machine to the module so it's always receiving packets.
@ShonkyCH I have the same problem. Did you solve it?
Yes we are still seeing this. It seems more of a problem when the module is receiving traffic e.g. flood ping it or do a constant UDP iperf from another machine to the module so it's always receiving packets.
@ShonkyCH I have the same problem. Did you solve it?
We never had a 100% reliable way to reproduce it - just it seemed that pulling the module whilst receiving packets caused it. I would say it's better in newer versions but can't 100% guarantee it's fixed. Definitely try the latest release if you haven't already.
We have spent way too much time chasing issues with driver and firmware on this thing, we're just not in a position to burn more time on it whilst it's largely working.
Hardware: SAMA5D4Xplained dev board + ATWILC1000-SD (rev 4) dev board
Software: wilc_demo 15.2RC1 from: https://github.com/linux4wilc/wilc_demo/tree/009b36048d18ffb64b2140eeecd0194cc21cbfd4
There is no "AP" access point in the vicinity i.e. the unit has nothing to connect to. There are quite a few other APs around though as you can see in the log.
Steps to reproduce:
Repeatable every time. Yes this is a somewhat harsh test, but the driver shouldn't be taking down the whole system.
I should note that the wpa_supplicant step is required. Without that step it doesn't crash.
Full log from boot:
Second stack trace comes a few seconds after the initial one.