rsi_91x: Failed to read status register on failed authentication

From: Alexey Brodkin
Date: Tue Dec 05 2017 - 11:12:04 EST


Hi Amit,

I'm seeing quite a strange behavior of RedPine module.
It connects perfectly fine to one of access points but fails
to connect to another.

Moreover after that failure RSI driver starts to flood me with
messages saying:
--------------------->8--------------------
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
--------------------->8--------------------

Below you may find details of my 2 experiments.
Note I use vanilla Linux kernel v4.14.4

Any ideas what could be wrong?

-Alexey


1. Working scenario
======================================================
# cat /etc/wpa_supplicant.confÂ
ctrl_interface=/var/run/wpa_supplicant
ap_scan=1

network={
 ssid="YYY"
 psk="zzz"
}

# iw wlan0 scan
BSS 80:19:34:1f:5e:50(on wlan0) -- associated
ÂÂÂÂÂÂÂÂTSF: 248634601 usec (0d, 00:04:08)
ÂÂÂÂÂÂÂÂfreq: 2412
ÂÂÂÂÂÂÂÂbeacon interval: 100 TUs
ÂÂÂÂÂÂÂÂcapability: ESS Privacy ShortSlotTime (0x0411)
ÂÂÂÂÂÂÂÂsignal: -31.00 dBm
ÂÂÂÂÂÂÂÂlast seen: 0 ms ago
ÂÂÂÂÂÂÂÂInformation elements from Probe Response frame:
ÂÂÂÂÂÂÂÂSSID: YYY
ÂÂÂÂÂÂÂÂSupported rates: 1.0* 2.0* 5.5* 11.0* 6.0 9.0 12.0 18.0Â
ÂÂÂÂÂÂÂÂDS Parameter set: channel 1
ÂÂÂÂÂÂÂÂERP: Barker_Preamble_Mode
ÂÂÂÂÂÂÂÂExtended supported rates: 24.0 36.0 48.0 54.0Â
ÂÂÂÂÂÂÂÂRSN:ÂÂÂÂÂ* Version: 1
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Group cipher: CCMP
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Pairwise ciphers: CCMP
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Authentication suites: PSK
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Capabilities: 16-PTKSA-RC 1-GTKSA-RC (0x000c)
ÂÂÂÂÂÂÂÂHT capabilities:
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂCapabilities: 0x1ec
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂHT20
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂSM Power Save disabled
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂRX HT20 SGI
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂRX HT40 SGI
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂTX STBC
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂRX STBC 1-stream
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂMax AMSDU length: 3839 bytes
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂNo DSSS/CCK HT40
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂMaximum RX AMPDU length 65535 bytes (exponent: 0x003)
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂMinimum RX AMPDU time spacing: 4 usec (0x05)
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂHT Max RX data rate: 300 Mbps
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂHT TX/RX MCS rate indexes supported: 0-15
ÂÂÂÂÂÂÂÂHT operation:
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* primary channel: 1
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* secondary channel offset: no secondary
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* STA channel width: 20 MHz
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* RIFS: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* HT protection: no
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* non-GF present: 1
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* OBSS non-GF present: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* dual beacon: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* dual CTS protection: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* STBC beacon: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* L-SIG TXOP Prot: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* PCO active: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* PCO phase: 0
ÂÂÂÂÂÂÂÂExtended capabilities: Extended Channel Switching, 6
ÂÂÂÂÂÂÂÂWMM:ÂÂÂÂÂ* Parameter version 1
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* BE: CW 15-1023, AIFSN 3
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* BK: CW 15-1023, AIFSN 7
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* VI: CW 7-15, AIFSN 2, TXOP 2976 usec
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* VO: CW 3-7, AIFSN 2, TXOP 1472 usec
ÂÂÂÂÂÂÂÂWPS:ÂÂÂÂÂ* Version: 1.0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Wi-Fi Protected Setup State: 2 (Configured)
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Response Type: 3 (AP)
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* UUID: 97b29264-d9a5-5551-88f9-62fbdacb7162
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Manufacturer:ÂÂ
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Model:ÂÂ
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Model Number:ÂÂ
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Serial Number:ÂÂ
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Primary Device Type: 0-00000000-0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Device name:ÂÂ
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Config methods: Display, Keypad
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Unknown TLV (0x1049, 6 bytes): 00 37 2a 00 01 20

# ifconfig wlan0 up
rsi_91x: ===> Interface UP <===
rsi_91x: rsi_disable_ps: Cannot accept disable PS in PS_NONE state
IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready

# wpa_supplicant -B -i wlan0 -c /etc/wpa_supplicant.conf
Successfully initialized wpa_supplicant
rfkill: Cannot open RFKILL control device
# wlan0: authenticate with 80:19:34:1f:5e:50
wlan0: send auth to 80:19:34:1f:5e:50 (try 1/3)
wlan0: authenticated
wlan0: associate with 80:19:34:1f:5e:50 (try 1/3)
wlan0: RX AssocResp from 80:19:34:1f:5e:50 (capab=0x411 status=0 aid=1)
IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
wlan0: associated
rsi_91x: rsi_hal_key_config: Cipher 0xfac04 key_type: 1 key_len: 16
rsi_91x: rsi_mac80211_set_key: RSI set_key
rsi_91x: rsi_hal_key_config: Cipher 0xfac04 key_type: 2 key_len: 16
rsi_91x: rsi_mac80211_set_key: RSI set_key

# udhcpc -i wlan0
udhcpc: started, v1.27.2
udhcpc: sending discover
udhcpc: sending select for 10.42.1.137
udhcpc: lease of 10.42.1.137 obtained, lease time 3600
deleting routers
adding dns 10.42.1.1
# ifconfigÂ
loÂÂÂÂÂÂÂÂLink encap:Local LoopbackÂÂ
ÂÂÂÂÂÂÂÂÂÂinet addr:127.0.0.1ÂÂMask:255.0.0.0
ÂÂÂÂÂÂÂÂÂÂinet6 addr: ::1/128 Scope:Host
ÂÂÂÂÂÂÂÂÂÂUP LOOPBACK RUNNINGÂÂMTU:65536ÂÂMetric:1
ÂÂÂÂÂÂÂÂÂÂRX packets:0 errors:0 dropped:0 overruns:0 frame:0
ÂÂÂÂÂÂÂÂÂÂTX packets:0 errors:0 dropped:0 overruns:0 carrier:0
ÂÂÂÂÂÂÂÂÂÂcollisions:0 txqueuelen:1000Â
ÂÂÂÂÂÂÂÂÂÂRX bytes:0 (0.0 B)ÂÂTX bytes:0 (0.0 B)

wlan0ÂÂÂÂÂLink encap:EthernetÂÂHWaddr 00:23:A7:65:9B:94ÂÂ
ÂÂÂÂÂÂÂÂÂÂinet addr:10.42.1.137ÂÂBcast:10.42.1.255ÂÂMask:255.255.255.0
ÂÂÂÂÂÂÂÂÂÂinet6 addr: fe80::223:a7ff:fe65:9b94/64 Scope:Link
ÂÂÂÂÂÂÂÂÂÂUP BROADCAST RUNNING MULTICASTÂÂMTU:1500ÂÂMetric:1
ÂÂÂÂÂÂÂÂÂÂRX packets:5 errors:0 dropped:0 overruns:0 frame:0
ÂÂÂÂÂÂÂÂÂÂTX packets:11 errors:0 dropped:0 overruns:0 carrier:0
ÂÂÂÂÂÂÂÂÂÂcollisions:0 txqueuelen:1000Â
ÂÂÂÂÂÂÂÂÂÂRX bytes:1028 (1.0 KiB)ÂÂTX bytes:1738 (1.6 KiB)
======================================================

2. Failing scenario:
======================================================
# cat /etc/wpa_supplicant.confÂ
ctrl_interface=/var/run/wpa_supplicant
ap_scan=1

network={
 ssid="XXX"
 psk="zzz"
}

# iw wlan0 scan
BSS 6c:3b:6b:7d:a1:46(on wlan0)
ÂÂÂÂÂÂÂÂTSF: 251623213323 usec (2d, 21:53:43)
ÂÂÂÂÂÂÂÂfreq: 2427
ÂÂÂÂÂÂÂÂbeacon interval: 100 TUs
ÂÂÂÂÂÂÂÂcapability: ESS Privacy ShortSlotTime (0x0411)
ÂÂÂÂÂÂÂÂsignal: -65.00 dBm
ÂÂÂÂÂÂÂÂlast seen: 0 ms ago
ÂÂÂÂÂÂÂÂInformation elements from Probe Response frame:
ÂÂÂÂÂÂÂÂSSID: XXX
ÂÂÂÂÂÂÂÂSupported rates: 1.0* 2.0* 5.5* 11.0* 6.0 9.0 12.0 18.0Â
ÂÂÂÂÂÂÂÂDS Parameter set: channel 4
ÂÂÂÂÂÂÂÂERP: <no flags>
ÂÂÂÂÂÂÂÂHT capabilities:
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂCapabilities: 0x106e
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂHT20/HT40
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂSM Power Save disabled
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂRX HT20 SGI
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂRX HT40 SGI
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂNo RX STBC
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂMax AMSDU length: 3839 bytes
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂDSSS/CCK HT40
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂMaximum RX AMPDU length 65535 bytes (exponent: 0x003)
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂMinimum RX AMPDU time spacing: No restriction (0x00)
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂHT RX MCS rate indexes supported: 0-15
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂHT TX MCS rate indexes are undefined
ÂÂÂÂÂÂÂÂRSN:ÂÂÂÂÂ* Version: 1
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Group cipher: CCMP
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Pairwise ciphers: CCMP TKIP
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Authentication suites: PSK
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* Capabilities: 1-PTKSA-RC 1-GTKSA-RC (0x0000)
ÂÂÂÂÂÂÂÂExtended supported rates: 24.0 36.0 48.0 54.0Â
ÂÂÂÂÂÂÂÂHT operation:
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* primary channel: 4
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* secondary channel offset: above
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* STA channel width: any
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* RIFS: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* HT protection: no
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* non-GF present: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* OBSS non-GF present: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* dual beacon: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* dual CTS protection: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* STBC beacon: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* L-SIG TXOP Prot: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* PCO active: 0
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* PCO phase: 0
ÂÂÂÂÂÂÂÂWMM:ÂÂÂÂÂ* Parameter version 1
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* u-APSD
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* BE: CW 15-1023, AIFSN 3
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* BK: CW 15-1023, AIFSN 7
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* VI: CW 7-15, AIFSN 2, TXOP 3008 usec
ÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂÂ* VO: CW 3-7, AIFSN 2, TXOP 1504 usec

# ifconfig wlan0 up
rsi_91x: ===> Interface UP <===
rsi_91x: rsi_disable_ps: Cannot accept disable PS in PS_NONE state
IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready

# wpa_supplicant -B -i wlan0 -c /etc/wpa_supplicant.conf
Successfully initialized wpa_supplicant
rfkill: Cannot open RFKILL control device
# wlan0: authenticate with 6c:3b:6b:7d:a1:46
wlan0: send auth to 6c:3b:6b:7d:a1:46 (try 1/3)
wlan0: send auth to 6c:3b:6b:7d:a1:46 (try 2/3)
wlan0: send auth to 6c:3b:6b:7d:a1:46 (try 3/3)
rsi_91x: rsi_sdio_write_register_multiple: Synch Cmd53 write failed -16
rsi_91x: rsi_sdio_host_intf_write_pkt: Unable to write onto the card: -16
rsi_91x: rsi_send_mgmt_pkt: Failed to write the packet
wlan0: authentication with 6c:3b:6b:7d:a1:46 timed out
wlan0: authenticate with 6c:3b:6b:76:e0:ab
wlan0: send auth to 6c:3b:6b:76:e0:ab (try 1/3)
wlan0: send auth to 6c:3b:6b:76:e0:ab (try 2/3)
wlan0: send auth to 6c:3b:6b:76:e0:ab (try 3/3)
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
wlan0: authentication with 6c:3b:6b:76:e0:ab timed out
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
rsi_91x: rsi_sdio_check_buffer_status: Failed to read status register
======================================================