mwifiex: CMD_SET_BSS_MODE timeout on 8897 pcie card

mwifiex: CMD_SET_BSS_MODE timeout on 8897 pcie card

[Ivan Delalande]

[-- Attachment #1: Type: text/plain, Size: 4983 bytes --]

Hi,

I've been trying to create an access point with the 8897 pcie card
shipped with the Espressobin, but the driver seems unable to switch the
card to AP mode if I'm interpreting the driver logs correctly. Hostapd
on its side prints:

	nl80211: Set mode ifindex 8 iftype 3 (AP)
	nl80211: Failed to set interface 8 to mode 3: -1 (Operation not permitted)

I'm using hostapd-2.4 on Debian in the US regdom, with the very last
version of regulatory.db and Marvell firmwares from their git
repositories. I've tried this on kernel 4.14, 4.17, 4.18-rc7 and
4.18-rc8 without success, the dmesg and device coredump attached were
taken on 4.18-rc8.

Is this setup supposed to work? If so, do you have any idea how to fix
or debug this issue? I can provide more logs and traces if needed.

Thanks a lot,

[  204.698353] mwifiex_pcie: try set_consistent_dma_mask(32)
[  204.701212] mwifiex_pcie: PCI memory map Virt0: 00000000dd182ff4 PCI memory map Virt2: 00000000705e17b7
[  206.144550] mwifiex_pcie 0000:00:00.0: info: FW download over, size 843828 bytes
[  206.904901] mwifiex_pcie 0000:00:00.0: WLAN FW is active
[  206.972600] mwifiex_pcie 0000:00:00.0: Unknown api_id: 4
[  207.005215] mwifiex_pcie 0000:00:00.0: info: MWIFIEX VERSION: mwifiex 1.0 (15.68.7.p154)
[  207.010868] mwifiex_pcie 0000:00:00.0: driver_version = mwifiex 1.0 (15.68.7.p154)
[  262.451406] mwifiex_pcie 0000:00:00.0: mwifiex_cmd_timeout_func: Timeout cmd id = 0xf7, act = 0x2
[  262.457711] mwifiex_pcie 0000:00:00.0: num_data_h2c_failure = 0
[  262.463797] mwifiex_pcie 0000:00:00.0: num_cmd_h2c_failure = 0
[  262.469798] mwifiex_pcie 0000:00:00.0: is_cmd_timedout = 1
[  262.475443] mwifiex_pcie 0000:00:00.0: num_tx_timeout = 0
[  262.480998] mwifiex_pcie 0000:00:00.0: last_cmd_index = 3
[  262.486555] mwifiex_pcie 0000:00:00.0: last_cmd_id: 1e 00 0c 01 4d 00 f7 00 20 00
[  262.494259] mwifiex_pcie 0000:00:00.0: last_cmd_act: 00 00 01 00 01 00 02 00 08 00
[  262.502054] mwifiex_pcie 0000:00:00.0: last_cmd_resp_index = 2
[  262.508058] mwifiex_pcie 0000:00:00.0: last_cmd_resp_id: 1e 80 0c 81 4d 80 1e 80 20 80
[  262.516211] mwifiex_pcie 0000:00:00.0: last_event_index = 1
[  262.521946] mwifiex_pcie 0000:00:00.0: last_event: 0b 00 0a 00 0b 00 0a 00 0a 00
[  262.529561] mwifiex_pcie 0000:00:00.0: data_sent=0 cmd_sent=1
[  262.535470] mwifiex_pcie 0000:00:00.0: ps_mode=1 ps_state=0
[  262.541415] mwifiex_pcie 0000:00:00.0: PREP_CMD: FW is in bad state
[  262.542956] mwifiex_pcie 0000:00:00.0: ===mwifiex driverinfo dump start===
[  262.548222] mwifiex_pcie 0000:00:00.0: PREP_CMD: FW is in bad state
[  262.554761] mwifiex_pcie 0000:00:00.0: info: MWIFIEX VERSION: mwifiex 1.0 (15.68.7.p154)
[  262.561407] mwifiex_pcie 0000:00:00.0: PREP_CMD: FW is in bad state
[  262.573535] mwifiex_pcie 0000:00:00.0: PCIE register dump start
[  262.576494] mwifiex_pcie 0000:00:00.0: PREP_CMD: FW is in bad state
[  262.582349] mwifiex_pcie 0000:00:00.0: pcie scratch register:
[  262.588828] mwifiex_pcie 0000:00:00.0: PREP_CMD: FW is in bad state
[  262.594652] mwifiex_pcie 0000:00:00.0: reg:0xcf0, value=0xfedcba00
               reg:0xcf8, value=0x170000d
               reg:0xcfc, value=0x2e8e907
[  262.616810] mwifiex_pcie 0000:00:00.0: PCIE register dump end
[  262.629556] mwifiex_pcie 0000:00:00.0: ===mwifiex driverinfo dump end===
[  262.635556] IPv6: ADDRCONF(NETDEV_UP): mlan0: link is not ready
[  262.642020] mwifiex_pcie 0000:00:00.0: == mwifiex firmware dump start ==
[  262.674710] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.687472] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.699835] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.712388] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.768298] netlink: 'hostapd': attribute type 213 has an invalid length.
[  262.791883] mwifiex_pcie 0000:00:00.0: 11D: failed to enable 11D
[  262.804448] mwifiex_pcie 0000:00:00.0: Failed to set AP configuration
[  262.811067] mwifiex_pcie 0000:00:00.0: Failed to start AP
[  262.823290] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.835746] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.848294] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.860838] mwifiex_pcie 0000:00:00.0: deleting the crypto keys
[  262.936068] mwifiex_pcie 0000:00:00.0: mlan0: changing to 2 not supported
[  262.954076] mwifiex_pcie 0000:00:00.0: mlan0: changing to 2 not supported
[  262.986099] IPv6: ADDRCONF(NETDEV_UP): mlan0: link is not ready
[  295.275216] mwifiex_pcie 0000:00:00.0: Firmware dump Finished!
[  295.278377] mwifiex_pcie 0000:00:00.0: == mwifiex firmware dump end ==
[  295.286802] mwifiex_pcie 0000:00:00.0: == mwifiex dump information to /sys/class/devcoredump start
[  295.294446] mwifiex_pcie 0000:00:00.0: == mwifiex dump information to /sys/class/devcoredump end

(I've suppressed lots of "PREP_CMD: FW is in bad state" messages after
the first few for clarity, there were 32 in total.)

-- 
Ivan Delalande

[-- Attachment #2: devcoredump.gz --]
[-- Type: application/gzip, Size: 420504 bytes --]