All of lore.kernel.org
 help / color / mirror / Atom feed
* Problem with brcmfmac removing extra interface
@ 2016-03-03 22:37 Rafał Miłecki
  2016-03-22  6:36 ` Rafał Miłecki
  0 siblings, 1 reply; 9+ messages in thread
From: Rafał Miłecki @ 2016-03-03 22:37 UTC (permalink / raw)
  To: brcm80211 development
  Cc: linux-wireless, Brett Rudley, Arend van Spriel,
	Franky (Zhenhui) Lin, Hante Meuleman

Hi,

brcmfmac in general is not capable of removing interfaces. If you take
a look at brcmf_cfg80211_del_iface implementation, you'll see it
returns -EOPNOTSUPP (except for p2p interfaces).

However there is problem in handling NL80211_CMD_STOP_AP (with the
brcmf_cfg80211_stop_ap callback). Current implementation calls
brcmf_fil_cmd_int_set(ifp, BRCMF_C_DOWN, 1)
if mbss support is enabled/used.

Above call results in firmware generating BRCMF_E_LINK event. This
event is handled with the following forward-traced functions chain:
1) brcmf_notify_connect_status
2) brcmf_notify_connect_status_ap
3) brcmf_remove_interface
4) brcmf_del_if
5) brcmf_net_detach
6) unregister_netdev

So the result of NL80211_CMD_STOP_AP is interface being removed. The
problem with this behavior is that interface can't be recreated after
that:
# iw phy phy1 interface add wlan1-1 type __ap
[ 3602.929199] brcmfmac: brcmf_ap_add_vif: timeout occurred
command failed: I/O error (-5)

I hit this problem when trying to restart hostapd using BCM43602 and 2 BSSes.

Could you analyze this problem and see if you see some way of solving
this problem, please?

-- 
Rafał

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-03 22:37 Problem with brcmfmac removing extra interface Rafał Miłecki
@ 2016-03-22  6:36 ` Rafał Miłecki
  2016-03-22 20:24   ` Arend van Spriel
  0 siblings, 1 reply; 9+ messages in thread
From: Rafał Miłecki @ 2016-03-22  6:36 UTC (permalink / raw)
  To: brcm80211 development
  Cc: linux-wireless, Brett Rudley, Arend van Spriel,
	Franky (Zhenhui) Lin, Hante Meuleman

On 3 March 2016 at 23:37, Rafał Miłecki <zajec5@gmail.com> wrote:
> brcmfmac in general is not capable of removing interfaces. If you take
> a look at brcmf_cfg80211_del_iface implementation, you'll see it
> returns -EOPNOTSUPP (except for p2p interfaces).
>
> However there is problem in handling NL80211_CMD_STOP_AP (with the
> brcmf_cfg80211_stop_ap callback). Current implementation calls
> brcmf_fil_cmd_int_set(ifp, BRCMF_C_DOWN, 1)
> if mbss support is enabled/used.
>
> Above call results in firmware generating BRCMF_E_LINK event. This
> event is handled with the following forward-traced functions chain:
> 1) brcmf_notify_connect_status
> 2) brcmf_notify_connect_status_ap
> 3) brcmf_remove_interface
> 4) brcmf_del_if
> 5) brcmf_net_detach
> 6) unregister_netdev
>
> So the result of NL80211_CMD_STOP_AP is interface being removed. The
> problem with this behavior is that interface can't be recreated after
> that:
> # iw phy phy1 interface add wlan1-1 type __ap
> [ 3602.929199] brcmfmac: brcmf_ap_add_vif: timeout occurred
> command failed: I/O error (-5)
>
> I hit this problem when trying to restart hostapd using BCM43602 and 2 BSSes.
>
> Could you analyze this problem and see if you see some way of solving
> this problem, please?

Hi, any ideas / help regarding this issue?

-- 
Rafał

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-22  6:36 ` Rafał Miłecki
@ 2016-03-22 20:24   ` Arend van Spriel
  2016-03-23  8:47     ` Rafał Miłecki
  0 siblings, 1 reply; 9+ messages in thread
From: Arend van Spriel @ 2016-03-22 20:24 UTC (permalink / raw)
  To: Rafał Miłecki, brcm80211 development
  Cc: linux-wireless, Brett Rudley, Arend van Spriel,
	Franky (Zhenhui) Lin, Hante Meuleman

On 22-3-2016 7:36, Rafał Miłecki wrote:
> On 3 March 2016 at 23:37, Rafał Miłecki <zajec5@gmail.com> wrote:
>> brcmfmac in general is not capable of removing interfaces. If you take
>> a look at brcmf_cfg80211_del_iface implementation, you'll see it
>> returns -EOPNOTSUPP (except for p2p interfaces).
>>
>> However there is problem in handling NL80211_CMD_STOP_AP (with the
>> brcmf_cfg80211_stop_ap callback). Current implementation calls
>> brcmf_fil_cmd_int_set(ifp, BRCMF_C_DOWN, 1)
>> if mbss support is enabled/used.
>>
>> Above call results in firmware generating BRCMF_E_LINK event. This
>> event is handled with the following forward-traced functions chain:
>> 1) brcmf_notify_connect_status
>> 2) brcmf_notify_connect_status_ap
>> 3) brcmf_remove_interface
>> 4) brcmf_del_if
>> 5) brcmf_net_detach
>> 6) unregister_netdev
>>
>> So the result of NL80211_CMD_STOP_AP is interface being removed. The
>> problem with this behavior is that interface can't be recreated after
>> that:
>> # iw phy phy1 interface add wlan1-1 type __ap
>> [ 3602.929199] brcmfmac: brcmf_ap_add_vif: timeout occurred
>> command failed: I/O error (-5)
>>
>> I hit this problem when trying to restart hostapd using BCM43602 and 2 BSSes.
>>
>> Could you analyze this problem and see if you see some way of solving
>> this problem, please?
> 
> Hi, any ideas / help regarding this issue?

Restarting hostapd obviously is a valid scenario. My guess is we would
need to avoid interface removal in brcmf_notify_connect_status_ap. But
first I would like to know in which state the firmware is upon
brcmf_ap_add_vif. Can you provide a full log with FWCON debug level enabled?

Regards,
Arend

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-22 20:24   ` Arend van Spriel
@ 2016-03-23  8:47     ` Rafał Miłecki
  2016-03-23 10:44       ` Arend Van Spriel
  0 siblings, 1 reply; 9+ messages in thread
From: Rafał Miłecki @ 2016-03-23  8:47 UTC (permalink / raw)
  To: Arend van Spriel
  Cc: brcm80211 development, linux-wireless, Brett Rudley,
	Arend van Spriel, Franky (Zhenhui) Lin, Hante Meuleman

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

On 22 March 2016 at 21:24, Arend van Spriel
<arend.vanspriel@broadcom.com> wrote:
> On 22-3-2016 7:36, Rafał Miłecki wrote:
>> On 3 March 2016 at 23:37, Rafał Miłecki <zajec5@gmail.com> wrote:
>>> brcmfmac in general is not capable of removing interfaces. If you take
>>> a look at brcmf_cfg80211_del_iface implementation, you'll see it
>>> returns -EOPNOTSUPP (except for p2p interfaces).
>>>
>>> However there is problem in handling NL80211_CMD_STOP_AP (with the
>>> brcmf_cfg80211_stop_ap callback). Current implementation calls
>>> brcmf_fil_cmd_int_set(ifp, BRCMF_C_DOWN, 1)
>>> if mbss support is enabled/used.
>>>
>>> Above call results in firmware generating BRCMF_E_LINK event. This
>>> event is handled with the following forward-traced functions chain:
>>> 1) brcmf_notify_connect_status
>>> 2) brcmf_notify_connect_status_ap
>>> 3) brcmf_remove_interface
>>> 4) brcmf_del_if
>>> 5) brcmf_net_detach
>>> 6) unregister_netdev
>>>
>>> So the result of NL80211_CMD_STOP_AP is interface being removed. The
>>> problem with this behavior is that interface can't be recreated after
>>> that:
>>> # iw phy phy1 interface add wlan1-1 type __ap
>>> [ 3602.929199] brcmfmac: brcmf_ap_add_vif: timeout occurred
>>> command failed: I/O error (-5)
>>>
>>> I hit this problem when trying to restart hostapd using BCM43602 and 2 BSSes.
>>>
>>> Could you analyze this problem and see if you see some way of solving
>>> this problem, please?
>>
>> Hi, any ideas / help regarding this issue?
>
> Restarting hostapd obviously is a valid scenario. My guess is we would
> need to avoid interface removal in brcmf_notify_connect_status_ap. But
> first I would like to know in which state the firmware is upon
> brcmf_ap_add_vif. Can you provide a full log with FWCON debug level enabled?

Sure, hope it helps!

-- 
Rafał

[-- Attachment #2: brcmfmac-fwcon.txt --]
[-- Type: text/plain, Size: 11889 bytes --]

1) modprobe
[  155.446674] usbcore: registered new interface driver brcmfmac
[  155.452530] pci 0000:00:00.0: enabling device (0140 -> 0142)
[  155.458207] brcmfmac 0000:01:00.0: enabling device (0140 -> 0142)
[  155.822283] brcmfmac 0000:01:00.0: Direct firmware load for brcm/brcmfmac4366b-pcie.txt failed with error -2
[  155.832177] brcmfmac 0000:01:00.0: Falling back to user helper
[  155.843965] firmware brcm!brcmfmac4366b-pcie.txt: firmware_loading_store: map pages failed
[  156.129238] brcmfmac: brcmf_pcie_bus_console_init Console: base 3ffcf0, buf 3fbce8, size 16384
[  156.131866] brcmfmac: CONSOLE: 000000.000 initvars_cis_pci: Not CIS format
[  156.131898] brcmfmac: CONSOLE: 000000.000 srom rev:0
[  156.131940] brcmfmac: CONSOLE: 000000.000 initvars_srom_pci, SROM CRC Error
[  156.131979] brcmfmac: CONSOLE: 000000.000 Setting clocks to 800/400/200
[  156.132032] brcmfmac: CONSOLE: 000000.000 si_set_bb_vcofreq_frac: only work on 4360, 4350
[  156.132061] brcmfmac: CONSOLE: 000000.000 Enabling D-cache
[  156.132095] brcmfmac: CONSOLE: 026738.568 gic_dist_init max_irq 64
[  156.132143] brcmfmac: CONSOLE: 026738.569 c_init: Watchdog reset bit set, clearing
[  156.132157] brcmfmac: CONSOLE: 026738.569 
[  156.132228] brcmfmac: CONSOLE: RTE (PCIE-MSG_BUF) 10.10.69.3309 (r610991) on BCM4366 r3 @ 40.0/200.0/800.0MHz
[  156.132286] brcmfmac: CONSOLE: 026738.569 nvram_init: called again without calling nvram_exit()
[  156.132327] brcmfmac: CONSOLE: 026738.569 initvars_cis_pci: Not CIS format
[  156.132349] brcmfmac: CONSOLE: 026738.601 srom rev:0
[  156.132391] brcmfmac: CONSOLE: 026738.601 initvars_srom_pci, SROM CRC Error
[  156.132438] brcmfmac: CONSOLE: 026738.601 allocating a max of 511 rxcplid buffers
[  156.132486] brcmfmac: CONSOLE: 026738.601 pciemsgbuf0: Broadcom PCIE MSGBUF driver
[  156.132543] brcmfmac: CONSOLE: 026738.601 nvram_init: called again without calling nvram_exit()
[  156.132584] brcmfmac: CONSOLE: 026738.601 initvars_cis_pci: Not CIS format
[  156.132606] brcmfmac: CONSOLE: 026738.634 srom rev:0
[  156.132649] brcmfmac: CONSOLE: 026738.634 initvars_srom_pci, SROM CRC Error
[  156.132699] brcmfmac: CONSOLE: 026738.634 wlc_ucode_download: wl0: Loading non-MU ucode
[  156.132755] brcmfmac: CONSOLE: 026738.634 reclaim section 0: Returned 216 bytes to the heap
[  156.132795] brcmfmac: CONSOLE: 026738.634 initvars_cis_pci: Not CIS format
[  156.132819] brcmfmac: CONSOLE: 026738.667 srom rev:0
[  156.132860] brcmfmac: CONSOLE: 026738.667 initvars_srom_pci, SROM CRC Error
[  156.132909] brcmfmac: CONSOLE: 026738.667 wlc_bmac_attach, deviceid 0x43c4 nbands 1
[  156.132969] brcmfmac: CONSOLE: 026738.672 ipxotp_init: mapping otpbase at 0x18007000 to 0x18007000
[  156.133076] brcmfmac: CONSOLE: 026738.673 wl0: wlc_bmac_attach: chiprev 3 corerev 64 cccap 0x58400009 maccap 0xf0018705 band 2.4G, phy_type 11 phy_rev 32
[  156.133135] brcmfmac: CONSOLE: 026738.673 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  156.133194] brcmfmac: CONSOLE: 026738.673 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  156.133243] brcmfmac: CONSOLE: 026738.674 wl0: wlc_stf_txcore_shmem_write: No clock
[  156.133302] brcmfmac: CONSOLE: 026738.675 wl0: wlc_ampdu_tx_set: AGG Mode = MAC+AQM txmaxpkts 512
[  156.133377] brcmfmac: CONSOLE: 026738.676 wl0: Broadcom BCM4366 802.11 Wireless Controller 10.10.69.3309 (r610991)
[  156.133425] brcmfmac: CONSOLE: 026738.676 SPLITRX_MODE_2 enabled : tcmsegsize 160 
[  156.133462] brcmfmac: CONSOLE: 026738.676 TCAM: 512 used: 135 exceed:0
[  156.133520] brcmfmac: CONSOLE: 026738.676 reclaim section 1: Returned 177368 bytes to the heap
[  156.133554] brcmfmac: CONSOLE: 026738.677 ThreadX v5.6 initialized
[  156.133590] brcmfmac: CONSOLE: 
[  156.133853] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Jan  8 2016 12:54:07 version 10.10.69.3309 (r610991) FWID 01-c47a91a4
[  156.147122] brcmfmac: CONSOLE: 026738.713 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  156.152537] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code (0x30 0x30)
[  156.161409] brcmfmac: CONSOLE: 026738.727 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  156.161666] brcmfmac: CONSOLE: 026738.727 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  156.162437] pci 0001:00:00.0: enabling device (0140 -> 0142)
[  156.168160] brcmfmac 0001:01:00.0: enabling device (0140 -> 0142)
[  156.331808] brcmfmac 0001:01:00.0: Direct firmware load for brcm/brcmfmac4366b-pcie.txt failed with error -2
[  156.341714] brcmfmac 0001:01:00.0: Falling back to user helper
[  156.353306] firmware brcm!brcmfmac4366b-pcie.txt: firmware_loading_store: map pages failed
[  156.639236] brcmfmac: brcmf_pcie_bus_console_init Console: base 3ffcf0, buf 3fbce8, size 16384
[  156.641854] brcmfmac: CONSOLE: 000000.000 initvars_cis_pci: Not CIS format
[  156.641886] brcmfmac: CONSOLE: 000000.000 srom rev:0
[  156.641928] brcmfmac: CONSOLE: 000000.000 initvars_srom_pci, SROM CRC Error
[  156.641967] brcmfmac: CONSOLE: 000000.000 Setting clocks to 800/400/200
[  156.642020] brcmfmac: CONSOLE: 000000.000 si_set_bb_vcofreq_frac: only work on 4360, 4350
[  156.642049] brcmfmac: CONSOLE: 000000.000 Enabling D-cache
[  156.642083] brcmfmac: CONSOLE: 026738.568 gic_dist_init max_irq 64
[  156.642131] brcmfmac: CONSOLE: 026738.569 c_init: Watchdog reset bit set, clearing
[  156.642145] brcmfmac: CONSOLE: 026738.569 
[  156.642213] brcmfmac: CONSOLE: RTE (PCIE-MSG_BUF) 10.10.69.3309 (r610991) on BCM4366 r3 @ 40.0/200.0/800.0MHz
[  156.642271] brcmfmac: CONSOLE: 026738.569 nvram_init: called again without calling nvram_exit()
[  156.642312] brcmfmac: CONSOLE: 026738.569 initvars_cis_pci: Not CIS format
[  156.642334] brcmfmac: CONSOLE: 026738.601 srom rev:0
[  156.642376] brcmfmac: CONSOLE: 026738.601 initvars_srom_pci, SROM CRC Error
[  156.642422] brcmfmac: CONSOLE: 026738.601 allocating a max of 511 rxcplid buffers
[  156.642470] brcmfmac: CONSOLE: 026738.601 pciemsgbuf0: Broadcom PCIE MSGBUF driver
[  156.642527] brcmfmac: CONSOLE: 026738.601 nvram_init: called again without calling nvram_exit()
[  156.642568] brcmfmac: CONSOLE: 026738.601 initvars_cis_pci: Not CIS format
[  156.642590] brcmfmac: CONSOLE: 026738.633 srom rev:0
[  156.642632] brcmfmac: CONSOLE: 026738.633 initvars_srom_pci, SROM CRC Error
[  156.642683] brcmfmac: CONSOLE: 026738.634 wlc_ucode_download: wl0: Loading non-MU ucode
[  156.642738] brcmfmac: CONSOLE: 026738.634 reclaim section 0: Returned 216 bytes to the heap
[  156.642778] brcmfmac: CONSOLE: 026738.634 initvars_cis_pci: Not CIS format
[  156.642800] brcmfmac: CONSOLE: 026738.667 srom rev:0
[  156.642841] brcmfmac: CONSOLE: 026738.667 initvars_srom_pci, SROM CRC Error
[  156.642890] brcmfmac: CONSOLE: 026738.667 wlc_bmac_attach, deviceid 0x43c5 nbands 1
[  156.642949] brcmfmac: CONSOLE: 026738.675 ipxotp_init: mapping otpbase at 0x18007000 to 0x18007000
[  156.643054] brcmfmac: CONSOLE: 026738.675 wl0: wlc_bmac_attach: chiprev 3 corerev 64 cccap 0x58400009 maccap 0xf0018705 band 5G, phy_type 11 phy_rev 32
[  156.643113] brcmfmac: CONSOLE: 026738.675 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  156.643172] brcmfmac: CONSOLE: 026738.675 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  156.643220] brcmfmac: CONSOLE: 026738.676 wl0: wlc_stf_txcore_shmem_write: No clock
[  156.643280] brcmfmac: CONSOLE: 026738.677 wl0: wlc_ampdu_tx_set: AGG Mode = MAC+AQM txmaxpkts 512
[  156.643353] brcmfmac: CONSOLE: 026738.679 wl0: Broadcom BCM4366 802.11 Wireless Controller 10.10.69.3309 (r610991)
[  156.643401] brcmfmac: CONSOLE: 026738.679 SPLITRX_MODE_2 enabled : tcmsegsize 160 
[  156.643438] brcmfmac: CONSOLE: 026738.679 TCAM: 512 used: 135 exceed:0
[  156.643495] brcmfmac: CONSOLE: 026738.679 reclaim section 1: Returned 177368 bytes to the heap
[  156.643529] brcmfmac: CONSOLE: 026738.680 ThreadX v5.6 initialized
[  156.643564] brcmfmac: CONSOLE: 
[  156.643839] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Jan  8 2016 12:54:07 version 10.10.69.3309 (r610991) FWID 01-c47a91a4
[  156.657096] brcmfmac: CONSOLE: 026738.709 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  156.671363] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code (0x30 0x30)
[  156.679350] brcmfmac: CONSOLE: 026738.732 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  156.679543] brcmfmac: CONSOLE: 026738.732 wl0: wlc_enable_probe_req: state down, deferring setting of host flags

2) hostapd
[  168.539516] brcmfmac: CONSOLE: 026750.572 wl0: wl_open
[  168.539577] brcmfmac: CONSOLE: 026750.573 wlc_ucode_download: wl0: Loading non-MU ucode
[  168.539622] brcmfmac: CONSOLE: 026750.574 wl0: CORE INIT : nfifo 6 mu_tx_enab 0
[  168.539700] brcmfmac: CONSOLE: 026750.574 wl0: CORE INIT : mode 2 pktclassify 1 rxsplit 0  hdr conve 0 DMA_CT Disabled
[  168.539772] brcmfmac: CONSOLE: 026750.587 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  168.539832] brcmfmac: CONSOLE: 026750.588 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  168.541546] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[  168.548945] device wlan1 entered promiscuous mode
[  168.555693] brcmfmac: CONSOLE: 026750.604 wl0: bc/mc deauth_reason on STA BSS?
[  168.637607] brcmfmac: CONSOLE: 026750.671 wl0: wl_open
[  168.637665] brcmfmac: CONSOLE: 026750.672 wlc_ucode_download: wl0: Loading non-MU ucode
[  168.637710] brcmfmac: CONSOLE: 026750.673 wl0: CORE INIT : nfifo 6 mu_tx_enab 0
[  168.637787] brcmfmac: CONSOLE: 026750.673 wl0: CORE INIT : mode 2 pktclassify 1 rxsplit 0  hdr conve 0 DMA_CT Disabled
[  168.637858] brcmfmac: CONSOLE: 026750.686 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  168.637917] brcmfmac: CONSOLE: 026750.686 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  168.646511] brcmfmac: CONSOLE: 026750.695 wl0: link up (wl0)
[  168.646513] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[  168.646632] br-lan: port 2(wlan1) entered forwarding state
[  168.646665] br-lan: port 2(wlan1) entered forwarding state
[  168.668259] device wlan1-1 entered promiscuous mode
[  168.674240] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
[  168.680458] br-lan: port 3(wlan1-1) entered forwarding state
[  168.686216] br-lan: port 3(wlan1-1) entered forwarding state
[  168.759844] brcmfmac: CONSOLE: 026750.808 wl0: wl_open
[  168.765898] brcmfmac: CONSOLE: 026750.814 wl0: link up (wl0.2)
[  168.765961] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
[  169.929714] brcmfmac: CONSOLE: 026751.686 pciedev_send_ltr:Giving up:0x302
[  170.649221] br-lan: port 2(wlan1) entered forwarding state
[  170.689217] br-lan: port 3(wlan1-1) entered forwarding state

3) killall hostapd
[  234.424528] device wlan1-1 left promiscuous mode
[  234.429453] br-lan: port 3(wlan1-1) entered disabled state
[  234.842185] brcmfmac: CONSOLE: 026816.727 wl0: link down (wl0)
[  234.842224] brcmfmac: CONSOLE: 026816.727 wl0: link down (wl0.2)
[  235.409533] device wlan1 left promiscuous mode
[  235.414059] br-lan: port 2(wlan1) entered disabled state
[  235.846072] brcmfmac: CONSOLE: 026817.714 wl0: wl_open
[  235.846131] brcmfmac: CONSOLE: 026817.714 wlc_ucode_download: wl0: Loading non-MU ucode
[  235.846176] brcmfmac: CONSOLE: 026817.716 wl0: CORE INIT : nfifo 6 mu_tx_enab 0
[  235.846254] brcmfmac: CONSOLE: 026817.716 wl0: CORE INIT : mode 2 pktclassify 1 rxsplit 0  hdr conve 0 DMA_CT Disabled
[  235.846327] brcmfmac: CONSOLE: 026817.729 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  235.846385] brcmfmac: CONSOLE: 026817.729 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0

4) iw phy phy1 interface add wlan1-1 type __ap
[  256.819225] brcmfmac: brcmf_ap_add_vif: timeout occurred

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-23  8:47     ` Rafał Miłecki
@ 2016-03-23 10:44       ` Arend Van Spriel
  2016-03-23 19:13         ` Rafał Miłecki
  0 siblings, 1 reply; 9+ messages in thread
From: Arend Van Spriel @ 2016-03-23 10:44 UTC (permalink / raw)
  To: Rafał Miłecki
  Cc: brcm80211 development, linux-wireless, Brett Rudley,
	Arend van Spriel, Franky (Zhenhui) Lin, Hante Meuleman

On 23-3-2016 9:47, Rafał Miłecki wrote:
> On 22 March 2016 at 21:24, Arend van Spriel
> <arend.vanspriel@broadcom.com> wrote:
>> On 22-3-2016 7:36, Rafał Miłecki wrote:
>>> On 3 March 2016 at 23:37, Rafał Miłecki <zajec5@gmail.com> wrote:
>>>> brcmfmac in general is not capable of removing interfaces. If you take
>>>> a look at brcmf_cfg80211_del_iface implementation, you'll see it
>>>> returns -EOPNOTSUPP (except for p2p interfaces).
>>>>
>>>> However there is problem in handling NL80211_CMD_STOP_AP (with the
>>>> brcmf_cfg80211_stop_ap callback). Current implementation calls
>>>> brcmf_fil_cmd_int_set(ifp, BRCMF_C_DOWN, 1)
>>>> if mbss support is enabled/used.
>>>>
>>>> Above call results in firmware generating BRCMF_E_LINK event. This
>>>> event is handled with the following forward-traced functions chain:
>>>> 1) brcmf_notify_connect_status
>>>> 2) brcmf_notify_connect_status_ap
>>>> 3) brcmf_remove_interface
>>>> 4) brcmf_del_if
>>>> 5) brcmf_net_detach
>>>> 6) unregister_netdev
>>>>
>>>> So the result of NL80211_CMD_STOP_AP is interface being removed. The
>>>> problem with this behavior is that interface can't be recreated after
>>>> that:
>>>> # iw phy phy1 interface add wlan1-1 type __ap
>>>> [ 3602.929199] brcmfmac: brcmf_ap_add_vif: timeout occurred
>>>> command failed: I/O error (-5)
>>>>
>>>> I hit this problem when trying to restart hostapd using BCM43602 and 2 BSSes.
>>>>
>>>> Could you analyze this problem and see if you see some way of solving
>>>> this problem, please?
>>>
>>> Hi, any ideas / help regarding this issue?
>>
>> Restarting hostapd obviously is a valid scenario. My guess is we would
>> need to avoid interface removal in brcmf_notify_connect_status_ap. But
>> first I would like to know in which state the firmware is upon
>> brcmf_ap_add_vif. Can you provide a full log with FWCON debug level enabled?
> 
> Sure, hope it helps!

Ah. Actually would like to see some driver logging as well so
'debug=0x101410'.

> 3) killall hostapd
> [  234.424528] device wlan1-1 left promiscuous mode
> [  234.429453] br-lan: port 3(wlan1-1) entered disabled state
> [  234.842185] brcmfmac: CONSOLE: 026816.727 wl0: link down (wl0)
> [  234.842224] brcmfmac: CONSOLE: 026816.727 wl0: link down (wl0.2)

This part at least is interesting and I would like to know how brcmfmac
deals with this order of events.

Regards,
Arend

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-23 10:44       ` Arend Van Spriel
@ 2016-03-23 19:13         ` Rafał Miłecki
  2016-05-06 22:43           ` Rafał Miłecki
  2016-05-24 19:49           ` Arend Van Spriel
  0 siblings, 2 replies; 9+ messages in thread
From: Rafał Miłecki @ 2016-03-23 19:13 UTC (permalink / raw)
  To: Arend Van Spriel
  Cc: brcm80211 development, linux-wireless, Brett Rudley,
	Arend van Spriel, Franky (Zhenhui) Lin, Hante Meuleman

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

On 23 March 2016 at 11:44, Arend Van Spriel
<arend.vanspriel@broadcom.com> wrote:
> On 23-3-2016 9:47, Rafał Miłecki wrote:
>> On 22 March 2016 at 21:24, Arend van Spriel
>> <arend.vanspriel@broadcom.com> wrote:
>>> On 22-3-2016 7:36, Rafał Miłecki wrote:
>>>> Hi, any ideas / help regarding this issue?
>>>
>>> Restarting hostapd obviously is a valid scenario. My guess is we would
>>> need to avoid interface removal in brcmf_notify_connect_status_ap. But
>>> first I would like to know in which state the firmware is upon
>>> brcmf_ap_add_vif. Can you provide a full log with FWCON debug level enabled?
>>
>> Sure, hope it helps!
>
> Ah. Actually would like to see some driver logging as well so
> 'debug=0x101410'.

Sure, there you go.

-- 
Rafał

[-- Attachment #2: 2016-03-23-brcmfmac-debug.txt --]
[-- Type: text/plain, Size: 64326 bytes --]

1) modprobe
[  142.722110] usbcore: registered new interface driver brcmfmac
[  142.727945] pci 0000:00:00.0: enabling device (0140 -> 0142)
[  142.733659] brcmfmac 0000:01:00.0: enabling device (0140 -> 0142)
[  143.102239] brcmfmac 0000:01:00.0: Direct firmware load for brcm/brcmfmac4366b-pcie.txt failed with error -2
[  143.112130] brcmfmac 0000:01:00.0: Falling back to user helper
[  143.124022] firmware brcm!brcmfmac4366b-pcie.txt: firmware_loading_store: map pages failed
[  143.409220] brcmfmac: brcmf_pcie_bus_console_init Console: base 3ffcf0, buf 3fbce8, size 16384
[  143.411886] brcmfmac: CONSOLE: 000000.000 initvars_cis_pci: Not CIS format
[  143.411893] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=cur_etheraddr, len=6
[  143.411902] brcmutil: data
[  143.411909] 00000000: 90 8d 78 66 3a 54                                ..xf:T
[  143.411934] brcmfmac: CONSOLE: 000000.000 srom rev:0
[  143.411976] brcmfmac: CONSOLE: 000000.000 initvars_srom_pci, SROM CRC Error
[  143.412014] brcmfmac: CONSOLE: 000000.000 Setting clocks to 800/400/200
[  143.412067] brcmfmac: CONSOLE: 000000.000 si_set_bb_vcofreq_frac: only work on 4360, 4350
[  143.412096] brcmfmac: CONSOLE: 000000.000 Enabling D-cache
[  143.412130] brcmfmac: CONSOLE: 026738.568 gic_dist_init max_irq 64
[  143.412178] brcmfmac: CONSOLE: 026738.569 c_init: Watchdog reset bit set, clearing
[  143.412192] brcmfmac: CONSOLE: 026738.569 
[  143.412261] brcmfmac: CONSOLE: RTE (PCIE-MSG_BUF) 10.10.69.3309 (r610991) on BCM4366 r3 @ 40.0/200.0/800.0MHz
[  143.412319] brcmfmac: CONSOLE: 026738.569 nvram_init: called again without calling nvram_exit()
[  143.412359] brcmfmac: CONSOLE: 026738.569 initvars_cis_pci: Not CIS format
[  143.412381] brcmfmac: CONSOLE: 026738.601 srom rev:0
[  143.412423] brcmfmac: CONSOLE: 026738.601 initvars_srom_pci, SROM CRC Error
[  143.412469] brcmfmac: CONSOLE: 026738.601 allocating a max of 511 rxcplid buffers
[  143.412517] brcmfmac: CONSOLE: 026738.601 pciemsgbuf0: Broadcom PCIE MSGBUF driver
[  143.412573] brcmfmac: CONSOLE: 026738.601 nvram_init: called again without calling nvram_exit()
[  143.412614] brcmfmac: CONSOLE: 026738.601 initvars_cis_pci: Not CIS format
[  143.412636] brcmfmac: CONSOLE: 026738.633 srom rev:0
[  143.412678] brcmfmac: CONSOLE: 026738.633 initvars_srom_pci, SROM CRC Error
[  143.412728] brcmfmac: CONSOLE: 026738.633 wlc_ucode_download: wl0: Loading non-MU ucode
[  143.412783] brcmfmac: CONSOLE: 026738.634 reclaim section 0: Returned 216 bytes to the heap
[  143.412823] brcmfmac: CONSOLE: 026738.634 initvars_cis_pci: Not CIS format
[  143.412845] brcmfmac: CONSOLE: 026738.666 srom rev:0
[  143.412886] brcmfmac: CONSOLE: 026738.666 initvars_srom_pci, SROM CRC Error
[  143.412934] brcmfmac: CONSOLE: 026738.667 wlc_bmac_attach, deviceid 0x43c4 nbands 1
[  143.412994] brcmfmac: CONSOLE: 026738.673 ipxotp_init: mapping otpbase at 0x18007000 to 0x18007000
[  143.413099] brcmfmac: CONSOLE: 026738.673 wl0: wlc_bmac_attach: chiprev 3 corerev 64 cccap 0x58400009 maccap 0xf0018705 band 2.4G, phy_type 11 phy_rev 32
[  143.413158] brcmfmac: CONSOLE: 026738.673 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  143.413216] brcmfmac: CONSOLE: 026738.673 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  143.413264] brcmfmac: CONSOLE: 026738.674 wl0: wlc_stf_txcore_shmem_write: No clock
[  143.413323] brcmfmac: CONSOLE: 026738.675 wl0: wlc_ampdu_tx_set: AGG Mode = MAC+AQM txmaxpkts 512
[  143.413397] brcmfmac: CONSOLE: 026738.676 wl0: Broadcom BCM4366 802.11 Wireless Controller 10.10.69.3309 (r610991)
[  143.413443] brcmfmac: CONSOLE: 026738.676 SPLITRX_MODE_2 enabled : tcmsegsize 160 
[  143.413481] brcmfmac: CONSOLE: 026738.676 TCAM: 512 used: 135 exceed:0
[  143.413538] brcmfmac: CONSOLE: 026738.676 reclaim section 1: Returned 177368 bytes to the heap
[  143.413572] brcmfmac: CONSOLE: 026738.677 ThreadX v5.6 initialized
[  143.413607] brcmfmac: CONSOLE: 
[  143.413646] brcmfmac: brcmf_fil_cmd_data_get ifidx=0, cmd=98, len=68
[  143.413652] brcmutil: data
[  143.413658] 00000000: e4 14 00 00 c4 43 00 00 eb 03 21 00 03 00 00 00  .....C....!.....
[  143.413664] 00000010: 40 00 00 00 ff ff 00 00 e4 14 00 00 30 11 00 00  @...........0...
[  143.413669] 00000020: ce 45 0a 0a 00 00 00 00 00 00 00 00 66 43 00 00  .E..........fC..
[  143.413674] 00000030: 0b 00 00 00 20 00 00 00 00 00 00 00 00 00 00 00  .... ...........
[  143.413885] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=ver, len=256
[  143.413890] brcmutil: data
[  143.413895] 00000000: 77 6c 30 3a 20 4a 61 6e 20 20 38 20 32 30 31 36  wl0: Jan  8 2016
[  143.413901] 00000010: 20 31 32 3a 35 34 3a 30 37 20 76 65 72 73 69 6f   12:54:07 versio
[  143.413905] 00000020: 6e 20 31 30 2e 31 30 2e 36 39 2e 33 33 30 39 20  n 10.10.69.3309 
[  143.413910] 00000030: 28 72 36 31 30 39 39 31 29 20 46 57 49 44 20 30  (r610991) FWID 0
[  143.413917] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Jan  8 2016 12:54:07 version 10.10.69.3309 (r610991) FWID 01-c47a91a4
[  143.426421] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mpc, len=4
[  143.426430] brcmutil: data
[  143.426441] 00000000: 01 00 00 00                                      ....
[  143.426689] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=join_pref, len=8
[  143.426694] brcmutil: data
[  143.426700] 00000000: 04 02 08 01 01 02 00 00                          ........
[  143.427001] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=event_msgs, len=18
[  143.427006] brcmutil: data
[  143.427011] 00000000: 00 00 00 00 00 00 40 00 00 00 00 00 00 00 00 00  ......@.........
[  143.427016] 00000010: 00 00                                            ..
[  143.427022] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=event_msgs, len=18
[  143.427025] brcmutil: data
[  143.427030] 00000000: 00 00 00 00 00 00 40 00 00 00 00 00 00 00 00 00  ......@.........
[  143.427035] 00000010: 00 00                                            ..
[  143.427290] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=185, value=40
[  143.427328] brcmfmac: CONSOLE: 026738.713 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  143.427484] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=187, value=40
[  143.427655] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=txbf, len=4
[  143.427659] brcmutil: data
[  143.427664] 00000000: 01 00 00 00                                      ....
[  143.428142] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=cap, len=256
[  143.428146] brcmutil: data
[  143.428152] 00000000: 61 70 20 73 74 61 20 77 65 74 20 77 65 74 5f 74  ap sta wet wet_t
[  143.428157] 00000010: 75 6e 6e 65 6c 20 74 6f 65 20 6c 65 64 20 77 6d  unnel toe led wm
[  143.428162] 00000020: 65 20 38 30 32 2e 31 31 64 20 38 30 32 2e 31 31  e 802.11d 802.11
[  143.428167] 00000030: 68 20 72 6d 20 63 71 61 20 63 63 78 20 63 61 63  h rm cqa ccx cac
[  143.428408] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.428414] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=pfn, len=4
[  143.428418] brcmutil: data
[  143.428423] 00000000: 80 cc 92 c6                                      ....
[  143.428681] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.428688] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=rsdb_mode, len=4
[  143.428691] brcmutil: data
[  143.428696] 00000000: 80 cc 92 c6                                      ....
[  143.428932] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.428938] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=tdls_enable, len=4
[  143.428942] brcmutil: data
[  143.428947] 00000000: 80 cc 92 c6                                      ....
[  143.429159] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=mfp, len=4
[  143.429163] brcmutil: data
[  143.429168] 00000000: 00 00 00 00                                      ....
[  143.429387] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.429395] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=pfn_macaddr, len=8
[  143.429399] brcmutil: data
[  143.429404] 00000000: 01 00 00 00 94 bd 99 c7                          ........
[  143.429689] brcmfmac: brcmf_fil_cmd_int_get ifidx=0, cmd=1, value=2
[  143.429858] brcmfmac: brcmf_fil_cmd_data_get ifidx=0, cmd=140, len=12
[  143.429863] brcmutil: data
[  143.429869] 00000000: 01 00 00 00 02 00 00 00 78 bd 99 c7              ........x...
[  143.430036] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=vhtmode, len=4
[  143.430040] brcmutil: data
[  143.430046] 00000000: 01 00 00 00                                      ....
[  143.430210] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=nmode, len=4
[  143.430215] brcmutil: data
[  143.430220] 00000000: 01 00 00 00                                      ....
[  143.430407] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=bw_cap, len=4
[  143.430412] brcmutil: data
[  143.430417] 00000000: 01 00 00 00                                      ....
[  143.430662] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=bw_cap, len=4
[  143.430666] brcmutil: data
[  143.430671] 00000000: 00 00 00 00                                      ....
[  143.430891] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=rxchain, len=4
[  143.430896] brcmutil: data
[  143.430901] 00000000: 0f 00 00 00                                      ....
[  143.431195] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=chanspecs, len=1536
[  143.431199] brcmutil: data
[  143.431205] 00000000: 19 00 00 00 01 10 00 00 02 10 00 00 03 10 00 00  ................
[  143.431210] 00000010: 04 10 00 00 05 10 00 00 06 10 00 00 07 10 00 00  ................
[  143.431215] 00000020: 08 10 00 00 09 10 00 00 0a 10 00 00 0b 10 00 00  ................
[  143.431220] 00000030: 03 19 00 00 04 19 00 00 05 19 00 00 06 19 00 00  ................
[  143.431356] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.431360] brcmutil: data
[  143.431365] 00000000: 03 00 00 00                                      ....
[  143.431593] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.431597] brcmutil: data
[  143.431602] 00000000: 03 00 00 00                                      ....
[  143.431860] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.431864] brcmutil: data
[  143.431869] 00000000: 03 00 00 00                                      ....
[  143.432115] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.432119] brcmutil: data
[  143.432124] 00000000: 03 00 00 00                                      ....
[  143.432375] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.432379] brcmutil: data
[  143.432384] 00000000: 03 00 00 00                                      ....
[  143.432635] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.432639] brcmutil: data
[  143.432644] 00000000: 03 00 00 00                                      ....
[  143.432820] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.432824] brcmutil: data
[  143.432829] 00000000: 03 00 00 00                                      ....
[  143.433013] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.433018] brcmutil: data
[  143.433023] 00000000: 03 00 00 00                                      ....
[  143.433273] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.433277] brcmutil: data
[  143.433282] 00000000: 03 00 00 00                                      ....
[  143.433534] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.433538] brcmutil: data
[  143.433542] 00000000: 03 00 00 00                                      ....
[  143.433796] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.433800] brcmutil: data
[  143.433805] 00000000: 03 00 00 00                                      ....
[  143.434023] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=txstreams, len=4
[  143.434027] brcmutil: data
[  143.434032] 00000000: 04 00 00 00                                      ....
[  143.434235] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=txbf_bfe_cap, len=4
[  143.434239] brcmutil: data
[  143.434244] 00000000: 01 00 00 00                                      ....
[  143.434495] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=txbf_bfr_cap, len=4
[  143.434499] brcmutil: data
[  143.434504] 00000000: 02 00 00 00                                      ....
[  143.434639] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code (0x30 0x30)
[  143.442539] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=bw_cap, len=4
[  143.442548] brcmutil: data
[  143.442555] 00000000: 00 00 00 00                                      ....
[  143.442561] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=bw_cap, len=8
[  143.442565] brcmutil: data
[  143.442570] 00000000: 02 00 00 00 03 00 00 00                          ........
[  143.443087] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=chanspecs, len=1536
[  143.443093] brcmutil: data
[  143.443098] 00000000: 0e 00 00 00 03 19 00 00 04 19 00 00 05 19 00 00  ................
[  143.443104] 00000010: 06 19 00 00 07 19 00 00 08 19 00 00 09 19 00 00  ................
[  143.443109] 00000020: 03 18 00 00 04 18 00 00 05 18 00 00 06 18 00 00  ................
[  143.443114] 00000030: 07 18 00 00 08 18 00 00 09 18 00 00 00 00 00 00  ................
[  143.443124] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=obss_coex, len=4
[  143.443127] brcmutil: data
[  143.443132] 00000000: ff ff ff ff                                      ....
[  143.443319] brcmfmac: brcmf_fweh_activate_events enable event SET_SSID
[  143.443325] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH
[  143.443330] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH_IND
[  143.443335] brcmfmac: brcmf_fweh_activate_events enable event ASSOC_IND
[  143.443339] brcmfmac: brcmf_fweh_activate_events enable event REASSOC_IND
[  143.443344] brcmfmac: brcmf_fweh_activate_events enable event DISASSOC_IND
[  143.443348] brcmfmac: brcmf_fweh_activate_events enable event LINK
[  143.443352] brcmfmac: brcmf_fweh_activate_events enable event MIC_ERROR
[  143.443364] brcmfmac: brcmf_fweh_activate_events enable event ROAM
[  143.443375] brcmfmac: brcmf_fweh_activate_events enable event PFN_NET_FOUND
[  143.443385] brcmfmac: brcmf_fweh_activate_events enable event PSM_WATCHDOG
[  143.443397] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.443409] brcmfmac: brcmf_fweh_activate_events enable event P2P_DISC_LISTEN_COMPLETE
[  143.443422] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_COMPLETE
[  143.443434] brcmfmac: brcmf_fweh_activate_events enable event ESCAN_RESULT
[  143.443446] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_OFF_CHAN_COMPLETE
[  143.443456] brcmfmac: brcmf_fweh_activate_events enable event P2P_PROBEREQ_MSG
[  143.443465] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_RX
[  143.443470] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.443476] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=event_msgs, len=18
[  143.443479] brcmutil: data
[  143.443485] 00000000: f3 d7 8b cc 92 c6 c0 11 fe cf 5c bd 99 c7 1c bd  ..........\.....
[  143.443490] 00000010: 99 c7                                            ..
[  143.443710] brcmfmac: brcmf_fweh_activate_events enable event SET_SSID
[  143.443716] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH
[  143.443721] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH_IND
[  143.443725] brcmfmac: brcmf_fweh_activate_events enable event ASSOC_IND
[  143.443730] brcmfmac: brcmf_fweh_activate_events enable event REASSOC_IND
[  143.443734] brcmfmac: brcmf_fweh_activate_events enable event DISASSOC_IND
[  143.443738] brcmfmac: brcmf_fweh_activate_events enable event LINK
[  143.443743] brcmfmac: CONSOLE: 026738.729 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  143.443748] brcmfmac: brcmf_fweh_activate_events enable event MIC_ERROR
[  143.443752] brcmfmac: brcmf_fweh_activate_events enable event ROAM
[  143.443756] brcmfmac: brcmf_fweh_activate_events enable event PFN_NET_FOUND
[  143.443761] brcmfmac: brcmf_fweh_activate_events enable event PSM_WATCHDOG
[  143.443765] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.443770] brcmfmac: brcmf_fweh_activate_events enable event P2P_DISC_LISTEN_COMPLETE
[  143.443775] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_COMPLETE
[  143.443779] brcmfmac: brcmf_fweh_activate_events enable event ESCAN_RESULT
[  143.443784] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_OFF_CHAN_COMPLETE
[  143.443789] brcmfmac: brcmf_fweh_activate_events enable event P2P_PROBEREQ_MSG
[  143.443794] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_RX
[  143.443798] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.443803] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=event_msgs, len=18
[  143.443807] brcmutil: data
[  143.443812] 00000000: ff d7 5f bd 9b c7 dc bd f9 cf 00 bd 99 c7 40 49  .._...........@I
[  143.443817] 00000010: 26 bf                                            &.
[  143.444078] brcmfmac: CONSOLE: 026738.729 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  143.444741] pci 0001:00:00.0: enabling device (0140 -> 0142)
[  143.450504] brcmfmac 0001:01:00.0: enabling device (0140 -> 0142)
[  143.611738] brcmfmac 0001:01:00.0: Direct firmware load for brcm/brcmfmac4366b-pcie.txt failed with error -2
[  143.621649] brcmfmac 0001:01:00.0: Falling back to user helper
[  143.633176] firmware brcm!brcmfmac4366b-pcie.txt: firmware_loading_store: map pages failed
[  143.919219] brcmfmac: brcmf_pcie_bus_console_init Console: base 3ffcf0, buf 3fbce8, size 16384
[  143.921874] brcmfmac: CONSOLE: 000000.000 initvars_cis_pci: Not CIS format
[  143.921881] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=cur_etheraddr, len=6
[  143.921891] brcmutil: data
[  143.921898] 00000000: 90 8d 78 66 3a 56                                ..xf:V
[  143.921923] brcmfmac: CONSOLE: 000000.000 srom rev:0
[  143.921966] brcmfmac: CONSOLE: 000000.000 initvars_srom_pci, SROM CRC Error
[  143.922004] brcmfmac: CONSOLE: 000000.000 Setting clocks to 800/400/200
[  143.922058] brcmfmac: CONSOLE: 000000.000 si_set_bb_vcofreq_frac: only work on 4360, 4350
[  143.922087] brcmfmac: CONSOLE: 000000.000 Enabling D-cache
[  143.922121] brcmfmac: CONSOLE: 026738.568 gic_dist_init max_irq 64
[  143.922168] brcmfmac: CONSOLE: 026738.569 c_init: Watchdog reset bit set, clearing
[  143.922182] brcmfmac: CONSOLE: 026738.569 
[  143.922253] brcmfmac: CONSOLE: RTE (PCIE-MSG_BUF) 10.10.69.3309 (r610991) on BCM4366 r3 @ 40.0/200.0/800.0MHz
[  143.922311] brcmfmac: CONSOLE: 026738.569 nvram_init: called again without calling nvram_exit()
[  143.922352] brcmfmac: CONSOLE: 026738.569 initvars_cis_pci: Not CIS format
[  143.922374] brcmfmac: CONSOLE: 026738.602 srom rev:0
[  143.922416] brcmfmac: CONSOLE: 026738.602 initvars_srom_pci, SROM CRC Error
[  143.922462] brcmfmac: CONSOLE: 026738.602 allocating a max of 511 rxcplid buffers
[  143.922509] brcmfmac: CONSOLE: 026738.602 pciemsgbuf0: Broadcom PCIE MSGBUF driver
[  143.922567] brcmfmac: CONSOLE: 026738.602 nvram_init: called again without calling nvram_exit()
[  143.922608] brcmfmac: CONSOLE: 026738.602 initvars_cis_pci: Not CIS format
[  143.922630] brcmfmac: CONSOLE: 026738.634 srom rev:0
[  143.922672] brcmfmac: CONSOLE: 026738.634 initvars_srom_pci, SROM CRC Error
[  143.922723] brcmfmac: CONSOLE: 026738.634 wlc_ucode_download: wl0: Loading non-MU ucode
[  143.922778] brcmfmac: CONSOLE: 026738.635 reclaim section 0: Returned 216 bytes to the heap
[  143.922819] brcmfmac: CONSOLE: 026738.635 initvars_cis_pci: Not CIS format
[  143.922842] brcmfmac: CONSOLE: 026738.667 srom rev:0
[  143.922883] brcmfmac: CONSOLE: 026738.667 initvars_srom_pci, SROM CRC Error
[  143.922932] brcmfmac: CONSOLE: 026738.668 wlc_bmac_attach, deviceid 0x43c5 nbands 1
[  143.922992] brcmfmac: CONSOLE: 026738.676 ipxotp_init: mapping otpbase at 0x18007000 to 0x18007000
[  143.923097] brcmfmac: CONSOLE: 026738.676 wl0: wlc_bmac_attach: chiprev 3 corerev 64 cccap 0x58400009 maccap 0xf0018705 band 5G, phy_type 11 phy_rev 32
[  143.923156] brcmfmac: CONSOLE: 026738.676 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  143.923215] brcmfmac: CONSOLE: 026738.676 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  143.923264] brcmfmac: CONSOLE: 026738.677 wl0: wlc_stf_txcore_shmem_write: No clock
[  143.923323] brcmfmac: CONSOLE: 026738.678 wl0: wlc_ampdu_tx_set: AGG Mode = MAC+AQM txmaxpkts 512
[  143.923397] brcmfmac: CONSOLE: 026738.680 wl0: Broadcom BCM4366 802.11 Wireless Controller 10.10.69.3309 (r610991)
[  143.923445] brcmfmac: CONSOLE: 026738.680 SPLITRX_MODE_2 enabled : tcmsegsize 160 
[  143.923483] brcmfmac: CONSOLE: 026738.680 TCAM: 512 used: 135 exceed:0
[  143.923540] brcmfmac: CONSOLE: 026738.680 reclaim section 1: Returned 177368 bytes to the heap
[  143.923574] brcmfmac: CONSOLE: 026738.680 ThreadX v5.6 initialized
[  143.923611] brcmfmac: CONSOLE: 
[  143.923650] brcmfmac: brcmf_fil_cmd_data_get ifidx=0, cmd=98, len=68
[  143.923656] brcmutil: data
[  143.923662] 00000000: e4 14 00 00 c5 43 00 00 eb 03 21 00 03 00 00 00  .....C....!.....
[  143.923668] 00000010: 40 00 00 00 ff ff 00 00 e4 14 00 00 30 11 00 00  @...........0...
[  143.923673] 00000020: ce 45 0a 0a 00 00 00 00 00 00 00 00 66 43 00 00  .E..........fC..
[  143.923678] 00000030: 0b 00 00 00 20 00 00 00 00 00 00 00 00 00 00 00  .... ...........
[  143.923869] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=ver, len=256
[  143.923874] brcmutil: data
[  143.923879] 00000000: 77 6c 30 3a 20 4a 61 6e 20 20 38 20 32 30 31 36  wl0: Jan  8 2016
[  143.923884] 00000010: 20 31 32 3a 35 34 3a 30 37 20 76 65 72 73 69 6f   12:54:07 versio
[  143.923889] 00000020: 6e 20 31 30 2e 31 30 2e 36 39 2e 33 33 30 39 20  n 10.10.69.3309 
[  143.923894] 00000030: 28 72 36 31 30 39 39 31 29 20 46 57 49 44 20 30  (r610991) FWID 0
[  143.923900] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Jan  8 2016 12:54:07 version 10.10.69.3309 (r610991) FWID 01-c47a91a4
[  143.936398] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mpc, len=4
[  143.936403] brcmutil: data
[  143.936408] 00000000: 01 00 00 00                                      ....
[  143.936643] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=join_pref, len=8
[  143.936648] brcmutil: data
[  143.936655] 00000000: 04 02 08 01 01 02 00 00                          ........
[  143.936961] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=event_msgs, len=18
[  143.936966] brcmutil: data
[  143.936971] 00000000: 00 00 00 00 00 00 40 00 00 00 00 00 00 00 00 00  ......@.........
[  143.936976] 00000010: 00 00                                            ..
[  143.936982] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=event_msgs, len=18
[  143.936986] brcmutil: data
[  143.936990] 00000000: 00 00 00 00 00 00 40 00 00 00 00 00 00 00 00 00  ......@.........
[  143.936995] 00000010: 00 00                                            ..
[  143.937226] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=185, value=40
[  143.937262] brcmfmac: CONSOLE: 026738.710 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  143.937419] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=187, value=40
[  143.937678] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=txbf, len=4
[  143.937682] brcmutil: data
[  143.937687] 00000000: 01 00 00 00                                      ....
[  143.938167] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=cap, len=256
[  143.938172] brcmutil: data
[  143.938177] 00000000: 61 70 20 73 74 61 20 77 65 74 20 77 65 74 5f 74  ap sta wet wet_t
[  143.938182] 00000010: 75 6e 6e 65 6c 20 74 6f 65 20 6c 65 64 20 77 6d  unnel toe led wm
[  143.938187] 00000020: 65 20 38 30 32 2e 31 31 64 20 38 30 32 2e 31 31  e 802.11d 802.11
[  143.938192] 00000030: 68 20 72 6d 20 63 71 61 20 63 63 78 20 63 61 63  h rm cqa ccx cac
[  143.938430] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.938436] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=pfn, len=4
[  143.938440] brcmutil: data
[  143.938445] 00000000: 80 7c 0b c7                                      .|..
[  143.938694] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.938700] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=rsdb_mode, len=4
[  143.938703] brcmutil: data
[  143.938708] 00000000: 80 7c 0b c7                                      .|..
[  143.938953] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.938959] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=tdls_enable, len=4
[  143.938962] brcmutil: data
[  143.938967] 00000000: 80 7c 0b c7                                      .|..
[  143.939103] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=mfp, len=4
[  143.939107] brcmutil: data
[  143.939112] 00000000: 00 00 00 00                                      ....
[  143.939354] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  143.939362] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=pfn_macaddr, len=8
[  143.939366] brcmutil: data
[  143.939372] 00000000: 01 00 00 00 94 bd 99 c7                          ........
[  143.939713] brcmfmac: brcmf_fil_cmd_int_get ifidx=0, cmd=1, value=2
[  143.939873] brcmfmac: brcmf_fil_cmd_data_get ifidx=0, cmd=140, len=12
[  143.939878] brcmutil: data
[  143.939885] 00000000: 01 00 00 00 01 00 00 00 78 bd 99 c7              ........x...
[  143.939982] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=vhtmode, len=4
[  143.939987] brcmutil: data
[  143.939992] 00000000: 01 00 00 00                                      ....
[  143.940221] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=nmode, len=4
[  143.940226] brcmutil: data
[  143.940231] 00000000: 01 00 00 00                                      ....
[  143.940429] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=bw_cap, len=4
[  143.940433] brcmutil: data
[  143.940439] 00000000: 00 00 00 00                                      ....
[  143.940684] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=bw_cap, len=4
[  143.940688] brcmutil: data
[  143.940694] 00000000: 07 00 00 00                                      ....
[  143.940912] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=rxchain, len=4
[  143.940916] brcmutil: data
[  143.940921] 00000000: 0f 00 00 00                                      ....
[  143.941578] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=chanspecs, len=1536
[  143.941582] brcmutil: data
[  143.941588] 00000000: 49 00 00 00 24 d0 00 00 28 d0 00 00 2c d0 00 00  I...$...(...,...
[  143.941593] 00000010: 30 d0 00 00 34 d0 00 00 38 d0 00 00 3c d0 00 00  0...4...8...<...
[  143.941598] 00000020: 40 d0 00 00 64 d0 00 00 68 d0 00 00 6c d0 00 00  @...d...h...l...
[  143.941603] 00000030: 70 d0 00 00 74 d0 00 00 78 d0 00 00 7c d0 00 00  p...t...x...|...
[  143.942123] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.942128] brcmutil: data
[  143.942133] 00000000: 07 00 00 00                                      ....
[  143.942578] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.942583] brcmutil: data
[  143.942588] 00000000: 07 00 00 00                                      ....
[  143.943074] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.943078] brcmutil: data
[  143.943083] 00000000: 07 00 00 00                                      ....
[  143.943537] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.943542] brcmutil: data
[  143.943547] 00000000: 07 00 00 00                                      ....
[  143.944056] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.944061] brcmutil: data
[  143.944066] 00000000: 2f 00 00 00                                      /...
[  143.944578] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.944582] brcmutil: data
[  143.944587] 00000000: 2f 00 00 00                                      /...
[  143.945099] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.945103] brcmutil: data
[  143.945108] 00000000: 2f 00 00 00                                      /...
[  143.945620] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.945624] brcmutil: data
[  143.945629] 00000000: 2f 00 00 00                                      /...
[  143.946143] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.946147] brcmutil: data
[  143.946152] 00000000: 2f 00 00 00                                      /...
[  143.946663] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.946667] brcmutil: data
[  143.946672] 00000000: 2f 00 00 00                                      /...
[  143.947183] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.947187] brcmutil: data
[  143.947192] 00000000: 2f 00 00 00                                      /...
[  143.947710] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.947715] brcmutil: data
[  143.947719] 00000000: 2f 00 00 00                                      /...
[  143.948198] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.948202] brcmutil: data
[  143.948207] 00000000: 2f 00 00 00                                      /...
[  143.948722] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.948726] brcmutil: data
[  143.948731] 00000000: 2f 00 00 00                                      /...
[  143.949130] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.949134] brcmutil: data
[  143.949139] 00000000: 2f 00 00 00                                      /...
[  143.949588] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.949594] brcmutil: data
[  143.949599] 00000000: 2f 00 00 00                                      /...
[  143.950087] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.950091] brcmutil: data
[  143.950097] 00000000: 2f 00 00 00                                      /...
[  143.950637] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.950641] brcmutil: data
[  143.950646] 00000000: 2f 00 00 00                                      /...
[  143.951182] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.951186] brcmutil: data
[  143.951191] 00000000: 2f 00 00 00                                      /...
[  143.951628] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.951633] brcmutil: data
[  143.951638] 00000000: 2f 00 00 00                                      /...
[  143.952134] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.952138] brcmutil: data
[  143.952143] 00000000: 07 00 00 00                                      ....
[  143.952661] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.952666] brcmutil: data
[  143.952671] 00000000: 07 00 00 00                                      ....
[  143.953146] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.953150] brcmutil: data
[  143.953155] 00000000: 07 00 00 00                                      ....
[  143.953668] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.953672] brcmutil: data
[  143.953677] 00000000: 07 00 00 00                                      ....
[  143.954190] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=per_chan_info, len=4
[  143.954195] brcmutil: data
[  143.954199] 00000000: 07 00 00 00                                      ....
[  143.954409] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=txstreams, len=4
[  143.954414] brcmutil: data
[  143.954419] 00000000: 04 00 00 00                                      ....
[  143.954618] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=txbf_bfe_cap, len=4
[  143.954622] brcmutil: data
[  143.954627] 00000000: 01 00 00 00                                      ....
[  143.954876] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=txbf_bfr_cap, len=4
[  143.954880] brcmutil: data
[  143.954885] 00000000: 02 00 00 00                                      ....
[  143.955023] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code (0x30 0x30)
[  143.962662] brcmfmac: brcmf_fweh_activate_events enable event SET_SSID
[  143.962672] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH
[  143.962677] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH_IND
[  143.962681] brcmfmac: brcmf_fweh_activate_events enable event ASSOC_IND
[  143.962686] brcmfmac: brcmf_fweh_activate_events enable event REASSOC_IND
[  143.962690] brcmfmac: brcmf_fweh_activate_events enable event DISASSOC_IND
[  143.962695] brcmfmac: brcmf_fweh_activate_events enable event LINK
[  143.962699] brcmfmac: brcmf_fweh_activate_events enable event MIC_ERROR
[  143.962703] brcmfmac: brcmf_fweh_activate_events enable event ROAM
[  143.962708] brcmfmac: brcmf_fweh_activate_events enable event PFN_NET_FOUND
[  143.962713] brcmfmac: brcmf_fweh_activate_events enable event PSM_WATCHDOG
[  143.962718] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.962722] brcmfmac: brcmf_fweh_activate_events enable event P2P_DISC_LISTEN_COMPLETE
[  143.962728] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_COMPLETE
[  143.962733] brcmfmac: brcmf_fweh_activate_events enable event ESCAN_RESULT
[  143.962737] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_OFF_CHAN_COMPLETE
[  143.962742] brcmfmac: brcmf_fweh_activate_events enable event P2P_PROBEREQ_MSG
[  143.962747] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_RX
[  143.962752] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.962758] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=event_msgs, len=18
[  143.962762] brcmutil: data
[  143.962769] 00000000: 73 bf 0f 6f 13 bf c1 10 60 09 60 61 2c c7 02 00  s..o....`.`a,...
[  143.962774] 00000010: 00 00                                            ..
[  143.963069] brcmfmac: brcmf_fweh_activate_events enable event SET_SSID
[  143.963076] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH
[  143.963081] brcmfmac: brcmf_fweh_activate_events enable event DEAUTH_IND
[  143.963086] brcmfmac: brcmf_fweh_activate_events enable event ASSOC_IND
[  143.963090] brcmfmac: brcmf_fweh_activate_events enable event REASSOC_IND
[  143.963094] brcmfmac: brcmf_fweh_activate_events enable event DISASSOC_IND
[  143.963099] brcmfmac: brcmf_fweh_activate_events enable event LINK
[  143.963103] brcmfmac: brcmf_fweh_activate_events enable event MIC_ERROR
[  143.963107] brcmfmac: brcmf_fweh_activate_events enable event ROAM
[  143.963112] brcmfmac: brcmf_fweh_activate_events enable event PFN_NET_FOUND
[  143.963117] brcmfmac: brcmf_fweh_activate_events enable event PSM_WATCHDOG
[  143.963121] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.963126] brcmfmac: brcmf_fweh_activate_events enable event P2P_DISC_LISTEN_COMPLETE
[  143.963130] brcmfmac: CONSOLE: 026738.736 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  143.963135] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_COMPLETE
[  143.963140] brcmfmac: brcmf_fweh_activate_events enable event ESCAN_RESULT
[  143.963144] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_OFF_CHAN_COMPLETE
[  143.963149] brcmfmac: brcmf_fweh_activate_events enable event P2P_PROBEREQ_MSG
[  143.963154] brcmfmac: brcmf_fweh_activate_events enable event ACTION_FRAME_RX
[  143.963159] brcmfmac: brcmf_fweh_activate_events enable event IF
[  143.963164] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=event_msgs, len=18
[  143.963168] brcmutil: data
[  143.963173] 00000000: 61 15 0b 26 b7 c6 dc bd f9 cf 00 bd 99 c7 40 49  a..&..........@I
[  143.963178] 00000010: 26 bf                                            &.
[  143.963532] brcmfmac: CONSOLE: 026738.736 wl0: wlc_enable_probe_req: state down, deferring setting of host flags

2) hostapd
[  154.252601] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=cur_etheraddr, len=6
[  154.252616] brcmutil: data
[  154.252627] 00000000: 90 8d 78 66 3a 56                                ..xf:V
[  154.253363] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  154.253374] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=toe_ol, len=4
[  154.253379] brcmutil: data
[  154.253386] 00000000: f0 bd 94 c6                                      ....
[  154.253393] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=2, value=0
[  154.270050] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=185, value=40
[  154.270096] brcmfmac: CONSOLE: 026749.021 wl0: wl_open
[  154.270151] brcmfmac: CONSOLE: 026749.022 wlc_ucode_download: wl0: Loading non-MU ucode
[  154.270199] brcmfmac: CONSOLE: 026749.023 wl0: CORE INIT : nfifo 6 mu_tx_enab 0
[  154.270277] brcmfmac: CONSOLE: 026749.023 wl0: CORE INIT : mode 2 pktclassify 1 rxsplit 0  hdr conve 0 DMA_CT Disabled
[  154.270350] brcmfmac: CONSOLE: 026749.036 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  154.270410] brcmfmac: CONSOLE: 026749.037 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  154.270438] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=187, value=40
[  154.270451] brcmfmac: brcmf_fweh_event_worker event IF (54) ifidx 0 bsscfg 0 addr 90:8d:78:66:3a:56
[  154.270459] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 0
[  154.270464] brcmutil: event payload, len=5
[  154.270470] 00000000: 00 01 00 00 00                                   .....
[  154.270477] brcmfmac: brcmf_fweh_handle_if_event action: 1 ifidx: 0 bsscfgidx: 0 flags: 0 role: 0
[  154.270482] brcmfmac: brcmf_fweh_handle_if_event adding wl0 (90:8d:78:66:3a:56)
[  154.270621] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=258, value=120
[  154.270786] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=86, value=2
[  154.271035] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=bcn_timeout, len=4
[  154.271039] brcmutil: data
[  154.271045] 00000000: 02 00 00 00                                      ....
[  154.271271] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=roam_off, len=4
[  154.271275] brcmutil: data
[  154.271280] 00000000: 00 00 00 00                                      ....
[  154.271450] brcmfmac: brcmf_fil_cmd_data_set ifidx=0, cmd=55, len=8
[  154.271455] brcmutil: data
[  154.271460] 00000000: b5 ff ff ff 03 00 00 00                          ........
[  154.271661] brcmfmac: brcmf_fil_cmd_data_set ifidx=0, cmd=57, len=8
[  154.271665] brcmutil: data
[  154.271670] 00000000: 14 00 00 00 03 00 00 00                          ........
[  154.271841] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=arp_ol, len=4
[  154.271845] brcmutil: data
[  154.271850] 00000000: 09 00 00 00                                      ....
[  154.272064] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  154.272071] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=ndoe, len=4
[  154.272074] brcmutil: data
[  154.272079] 00000000: 01 00 00 00                                      ....
[  154.272433] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[  154.278462] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mcast_list, len=22
[  154.278470] brcmutil: data
[  154.278478] 00000000: 03 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  154.278483] 00000010: 01 00 5e 00 00 01                                ..^...
[  154.279948] device wlan1 entered promiscuous mode
[  154.285744] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=allmulti, len=4
[  154.285759] brcmutil: data
[  154.285769] 00000000: 00 00 00 00                                      ....
[  154.286065] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=10, value=1
[  154.286257] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mcast_list, len=22
[  154.286266] brcmutil: data
[  154.286273] 00000000: 03 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  154.286279] 00000010: 01 00 5e 00 00 01                                ..^...
[  154.286507] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=allmulti, len=4
[  154.286511] brcmutil: data
[  154.286517] 00000000: 01 00 00 00                                      ....
[  154.286763] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=10, value=1
[  154.286992] brcmfmac: brcmf_fil_cmd_data_set ifidx=0, cmd=201, len=12
[  154.286997] brcmutil: data
[  154.287002] 00000000: 02 00 00 00 ff ff ff ff ff ff b3 c6              ............
[  154.287211] brcmfmac: CONSOLE: 026749.054 wl0: bc/mc deauth_reason on STA BSS?
[  154.350908] brcmfmac: brcmf_fil_cmd_int_get ifidx=0, cmd=46, value=0
[  154.350921] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mpc, len=4
[  154.350925] brcmutil: data
[  154.350933] 00000000: 00 00 00 00                                      ....
[  154.351167] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=arp_ol, len=4
[  154.351172] brcmutil: data
[  154.351177] 00000000: 00 00 00 00                                      ....
[  154.351440] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  154.351447] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=ndoe, len=4
[  154.351450] brcmutil: data
[  154.351455] 00000000: 00 00 00 00                                      ....
[  154.351692] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=wme_bss_disable, len=4
[  154.351697] brcmutil: data
[  154.351702] 00000000: 00 00 00 00                                      ....
[  154.351957] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=auth, len=4
[  154.351961] brcmutil: data
[  154.351967] 00000000: 00 00 00 00                                      ....
[  154.352204] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=wsec, len=4
[  154.352208] brcmutil: data
[  154.352213] 00000000: 44 00 00 00                                      D...
[  154.352391] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=mfp, len=4
[  154.352395] brcmutil: data
[  154.352400] 00000000: 00 00 00 00                                      ....
[  154.352624] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=wpa_auth, len=4
[  154.352628] brcmutil: data
[  154.352633] 00000000: 80 00 00 00                                      ....
[  154.352821] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=chanspec, len=4
[  154.352825] brcmutil: data
[  154.352830] 00000000: 24 d0 00 00                                      $...
[  154.353046] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=76, value=100
[  154.353213] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=78, value=2
[  154.353385] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=3, value=1
[  154.354135] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=apsta, len=4
[  154.354139] brcmutil: data
[  154.354144] 00000000: 00 00 00 00                                      ....
[  154.354355] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=20, value=1
[  154.354605] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mbss, len=4
[  154.354609] brcmutil: data
[  154.354614] 00000000: 01 00 00 00                                      ....
[  154.354810] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=118, value=1
[  154.355014] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=2, value=1
[  154.370164] brcmfmac: brcmf_fil_cmd_data_set ifidx=0, cmd=26, len=52
[  154.370171] brcmutil: data
[  154.370177] 00000000: 08 00 00 00 4f 70 65 6e 57 72 74 35 00 00 00 00  ....OpenWrt5....
[  154.370183] 00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  154.370188] 00000020: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  154.370193] 00000030: 00 00 00 00                                      ....
[  154.370224] brcmfmac: CONSOLE: 026749.122 wl0: wl_open
[  154.370279] brcmfmac: CONSOLE: 026749.123 wlc_ucode_download: wl0: Loading non-MU ucode
[  154.370324] brcmfmac: CONSOLE: 026749.124 wl0: CORE INIT : nfifo 6 mu_tx_enab 0
[  154.370402] brcmfmac: CONSOLE: 026749.124 wl0: CORE INIT : mode 2 pktclassify 1 rxsplit 0  hdr conve 0 DMA_CT Disabled
[  154.370474] brcmfmac: CONSOLE: 026749.137 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  154.370532] brcmfmac: CONSOLE: 026749.137 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  154.370554] brcmfmac: brcmf_fweh_event_worker event IF (54) ifidx 0 bsscfg 0 addr 90:8d:78:66:3a:56
[  154.370561] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 0
[  154.370565] brcmutil: event payload, len=5
[  154.370571] 00000000: 00 01 00 00 01                                   .....
[  154.370578] brcmfmac: brcmf_fweh_handle_if_event action: 1 ifidx: 0 bsscfgidx: 0 flags: 0 role: 1
[  154.370583] brcmfmac: brcmf_fweh_handle_if_event adding wl0 (90:8d:78:66:3a:56)
[  154.567413] brcmfmac: CONSOLE: 026749.333 wl0: link up (wl0)
[  154.567416] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[  154.567540] br-lan: port 2(wlan1) entered forwarding state
[  154.567573] br-lan: port 2(wlan1) entered forwarding state
[  154.567628] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=nd_hostip_clear, len=0
[  154.567630] brcmutil: data
[  154.584900] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=nd_hostip, len=16
[  154.584909] brcmutil: data
[  154.584918] 00000000: fe 80 00 00 00 00 00 00 92 8d 78 ff fe 66 3a 56  ..........x..f:V
[  154.584974] brcmfmac: brcmf_fweh_event_worker event LINK (16) ifidx 0 bsscfg 0 addr 90:8d:78:66:3a:56
[  154.584985] brcmfmac: brcmf_fweh_event_worker   version 2 flags 1 status 0 reason 0
[  154.584990] brcmutil: event payload, len=0
[  154.585212] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mcast_list, len=28
[  154.585220] brcmutil: data
[  154.585227] 00000000: 04 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  154.585233] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 56              ..^...33.f:V
[  154.585434] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=allmulti, len=4
[  154.585439] brcmutil: data
[  154.585445] 00000000: 01 00 00 00                                      ....
[  154.585663] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=10, value=1
[  154.585889] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=wsec_key, len=164
[  154.585894] brcmutil: data
[  154.585899] 00000000: 01 00 00 00 10 00 00 00 20 00 af 16 4c 5c e5 1b  ........ ...L\..
[  154.585905] 00000010: 52 73 ab 5d cf 5a 53 b3 00 00 00 00 00 00 00 00  Rs.].ZS.........
[  154.585910] 00000020: 00 00 00 00 00 00 00 00 00 00 00 00 00 c1 47 c0  ..............G.
[  154.585915] 00000030: 00 00 00 00 2f c7 ff ff 00 3c 49 c0 0a 00 00 00  ..../....<I.....
[  154.586185] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=wsec, len=4
[  154.586190] brcmutil: data
[  154.586195] 00000000: 44 00 00 00                                      D...
[  154.586201] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=wsec, len=4
[  154.586204] brcmutil: data
[  154.586209] 00000000: 44 00 00 00                                      D...
[  154.586793] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=0, bsscfgidx=0, name=wsec, len=4
[  154.586800] brcmutil: data
[  154.586805] 00000000: 44 00 00 00                                      D...
[  154.587131] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=bsscfg:ssid, len=40
[  154.587141] brcmutil: data
[  154.587148] 00000000: 02 00 00 00 05 00 00 00 73 73 69 64 32 00 00 00  ........ssid2...
[  154.587153] 00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  154.587158] 00000020: 00 00 00 00 00 00 00 00                          ........
[  154.587656] brcmfmac: brcmf_fweh_event_worker event IF (54) ifidx 1 bsscfg 2 addr 90:8d:78:66:3a:56
[  154.587666] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 0
[  154.587671] brcmutil: event payload, len=5
[  154.587677] 00000000: 01 01 00 02 01                                   .....
[  154.587683] brcmfmac: brcmf_fweh_handle_if_event action: 1 ifidx: 1 bsscfgidx: 2 flags: 0 role: 1
[  154.587689] brcmfmac: brcmf_fweh_handle_if_event adding wl0.2 (90:8d:78:66:3a:56)
[  154.588860] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=qtxpower, len=4
[  154.588877] brcmutil: data
[  154.588887] 00000000: 7f 00 00 00                                      ....
[  154.589183] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=qtxpower, len=4
[  154.589320] brcmutil: data
[  154.589334] 00000000: 7f 00 00 00                                      ....
[  154.589643] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=cur_etheraddr, len=6
[  154.589654] brcmutil: data
[  154.589663] 00000000: 92 8d 78 66 3a 57                                ..xf:W
[  154.590192] device wlan1-1 entered promiscuous mode
[  154.595622] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  154.595635] brcmfmac: brcmf_fil_iovar_data_get ifidx=1, name=toe_ol, len=4
[  154.595639] brcmutil: data
[  154.595646] 00000000: f0 bd 94 c6                                      ....
[  154.595866] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
[  154.602023] br-lan: port 3(wlan1-1) entered forwarding state
[  154.607791] br-lan: port 3(wlan1-1) entered forwarding state
[  154.613769] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=mcast_list, len=22
[  154.613781] brcmutil: data
[  154.613791] 00000000: 03 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  154.613797] 00000010: 01 00 5e 00 00 01                                ..^...
[  154.614071] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=allmulti, len=4
[  154.614080] brcmutil: data
[  154.614088] 00000000: 01 00 00 00                                      ....
[  154.614314] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=10, value=1
[  154.616151] brcmfmac: brcmf_fil_cmd_data_set ifidx=1, cmd=201, len=12
[  154.616163] brcmutil: data
[  154.616172] 00000000: 02 00 00 00 ff ff ff ff ff ff 01 c0              ............
[  154.682028] brcmfmac: brcmf_fil_cmd_int_get ifidx=1, cmd=46, value=0
[  154.682046] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=wme_bss_disable, len=4
[  154.682051] brcmutil: data
[  154.682059] 00000000: 00 00 00 00                                      ....
[  154.682281] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=auth, len=4
[  154.682287] brcmutil: data
[  154.682292] 00000000: 00 00 00 00                                      ....
[  154.682526] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=wsec, len=4
[  154.682530] brcmutil: data
[  154.682536] 00000000: 44 00 00 00                                      D...
[  154.682787] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=mfp, len=4
[  154.682792] brcmutil: data
[  154.682797] 00000000: 00 00 00 00                                      ....
[  154.683029] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=wpa_auth, len=4
[  154.683034] brcmutil: data
[  154.683039] 00000000: 80 00 00 00                                      ....
[  154.683234] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=118, value=1
[  154.683370] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=2, value=1
[  154.683608] brcmfmac: CONSOLE: 026749.449 wl0: wl_open
[  154.683642] brcmfmac: brcmf_fweh_event_worker event IF (54) ifidx 0 bsscfg 0 addr 90:8d:78:66:3a:56
[  154.683649] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 0
[  154.683654] brcmutil: event payload, len=5
[  154.683659] 00000000: 00 01 00 00 01                                   .....
[  154.683666] brcmfmac: brcmf_fweh_handle_if_event action: 1 ifidx: 0 bsscfgidx: 0 flags: 0 role: 1
[  154.683671] brcmfmac: brcmf_fweh_handle_if_event adding wl0 (90:8d:78:66:3a:56)
[  154.683692] brcmfmac: brcmf_fil_cmd_data_set ifidx=1, cmd=26, len=52
[  154.683696] brcmutil: data
[  154.683701] 00000000: 09 00 00 00 4f 70 65 6e 57 72 74 35 78 00 00 00  ....OpenWrt5x...
[  154.683707] 00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  154.683712] 00000020: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  154.683716] 00000030: 00 00 00 00                                      ....
[  154.689678] brcmfmac: CONSOLE: 026749.456 wl0: link up (wl0.2)
[  154.689776] brcmfmac: brcmf_fweh_event_worker event LINK (16) ifidx 1 bsscfg 2 addr 92:8d:78:66:3a:57
[  154.689785] brcmfmac: brcmf_fweh_event_worker   version 2 flags 1 status 0 reason 0
[  154.689789] brcmutil: event payload, len=0
[  154.690362] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
[  154.697137] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=mcast_list, len=28
[  154.697148] brcmutil: data
[  154.697156] 00000000: 04 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  154.697162] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 57              ..^...33.f:W
[  154.697479] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=allmulti, len=4
[  154.697488] brcmutil: data
[  154.697495] 00000000: 01 00 00 00                                      ....
[  154.697626] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=10, value=1
[  154.697930] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=wsec_key, len=164
[  154.697938] brcmutil: data
[  154.697944] 00000000: 01 00 00 00 10 00 00 00 06 21 37 91 62 fc f8 10  .........!7.b...
[  154.697950] 00000010: 1a 34 3d 7f ca 00 c7 91 00 00 00 00 00 00 00 00  .4=.............
[  154.697955] 00000020: 00 00 00 00 00 00 00 00 00 30 95 c6 64 31 95 c6  .........0..d1..
[  154.697960] 00000030: 40 18 dd c6 40 1c dd c6 f8 18 dd c6 31 00 00 00  @...@.......1...
[  154.698375] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=1, bsscfgidx=2, name=wsec, len=4
[  154.698381] brcmutil: data
[  154.698386] 00000000: 44 00 00 00                                      D...
[  154.698393] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=1, bsscfgidx=2, name=wsec, len=4
[  154.698396] brcmutil: data
[  154.698401] 00000000: 44 00 00 00                                      D...
[  154.698900] brcmfmac: brcmf_fil_bsscfg_data_get ifidx=1, bsscfgidx=2, name=wsec, len=4
[  154.698906] brcmutil: data
[  154.698911] 00000000: 44 00 00 00                                      D...
[  155.579586] brcmfmac: CONSOLE: 026750.138 pciedev_send_ltr:Giving up:0x302
[  156.229372] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mcast_list, len=34
[  156.229383] brcmutil: data
[  156.229391] 00000000: 05 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  156.229397] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 56 33 33 ff 00  ..^...33.f:V33..
[  156.229402] 00000020: 00 00                                            ..
[  156.229631] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=allmulti, len=4
[  156.229638] brcmutil: data
[  156.229644] 00000000: 01 00 00 00                                      ....
[  156.229838] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=10, value=1
[  156.569201] br-lan: port 2(wlan1) entered forwarding state
[  156.579606] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=mcast_list, len=34
[  156.579614] brcmutil: data
[  156.579622] 00000000: 05 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  156.579628] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 57 33 33 ff 00  ..^...33.f:W33..
[  156.579633] 00000020: 00 00                                            ..
[  156.579821] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=allmulti, len=4
[  156.579826] brcmutil: data
[  156.579832] 00000000: 01 00 00 00                                      ....
[  156.580054] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=10, value=1
[  156.609200] br-lan: port 3(wlan1-1) entered forwarding state

3) killall hostapd
[  172.382332] brcmfmac: brcmf_fil_cmd_data_set ifidx=1, cmd=201, len=12
[  172.382349] brcmutil: data
[  172.382357] 00000000: 03 00 00 00 ff ff ff ff ff ff 00 00              ............
[  172.383143] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=qtxpower, len=4
[  172.383152] brcmutil: data
[  172.383160] 00000000: 7f 00 00 00                                      ....
[  172.383169] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=mpc, len=4
[  172.383172] brcmutil: data
[  172.383177] 00000000: 01 00 00 00                                      ....
[  172.383552] device wlan1-1 left promiscuous mode
[  172.388415] br-lan: port 3(wlan1-1) entered disabled state
[  172.394540] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=mcast_list, len=34
[  172.394548] brcmutil: data
[  172.394557] 00000000: 05 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  172.394563] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 57 33 33 ff 00  ..^...33.f:W33..
[  172.394568] 00000020: 00 00                                            ..
[  172.394872] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=allmulti, len=4
[  172.394881] brcmutil: data
[  172.394888] 00000000: 00 00 00 00                                      ....
[  172.395065] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=10, value=0
[  172.799334] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=3, value=1
[  172.802111] brcmfmac: CONSOLE: 026767.523 wl0: link down (wl0)
[  172.802147] brcmfmac: CONSOLE: 026767.523 wl0: link down (wl0.2)
[  172.802166] brcmfmac: brcmf_fweh_event_worker event LINK (16) ifidx 0 bsscfg 0 addr 90:8d:78:66:3a:56
[  172.802173] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 4
[  172.802178] brcmutil: event payload, len=0
[  172.802202] brcmfmac: brcmf_fweh_event_worker event LINK (16) ifidx 1 bsscfg 2 addr 92:8d:78:66:3a:57
[  172.802208] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 4
[  172.802212] brcmutil: event payload, len=0
[  173.309342] brcmfmac: brcmf_fil_iovar_data_set ifidx=1, name=arp_hostip_clear, len=0
[  173.309352] brcmutil: data
[  173.309561] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  173.339856] brcmfmac: brcmf_fil_cmd_data_set ifidx=0, cmd=201, len=12
[  173.339870] brcmutil: data
[  173.339880] 00000000: 03 00 00 00 ff ff ff ff ff ff 01 c0              ............
[  173.369535] device wlan1 left promiscuous mode
[  173.374056] br-lan: port 2(wlan1) entered disabled state
[  173.379711] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mcast_list, len=34
[  173.379720] brcmutil: data
[  173.379728] 00000000: 05 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  173.379734] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 56 33 33 ff 00  ..^...33.f:V33..
[  173.379739] 00000020: 00 00                                            ..
[  173.379990] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=allmulti, len=4
[  173.379998] brcmutil: data
[  173.380004] 00000000: 00 00 00 00                                      ....
[  173.380296] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=10, value=0
[  173.789341] brcmfmac: brcmf_fil_cmd_data_set ifidx=0, cmd=26, len=52
[  173.789350] brcmutil: data
[  173.789357] 00000000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  173.789363] 00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  173.789368] 00000020: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  173.789373] 00000030: 00 00 00 00                                      ....
[  173.789607] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=3, value=1
[  173.789766] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=118, value=0
[  173.790004] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=20, value=0
[  173.790141] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mbss, len=4
[  173.790146] brcmutil: data
[  173.790152] 00000000: 00 00 00 00                                      ....
[  173.790348] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=47, value=0
[  173.790485] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=2, value=1
[  173.806286] brcmfmac: CONSOLE: 026768.510 wl0: wl_open
[  173.806340] brcmfmac: CONSOLE: 026768.511 wlc_ucode_download: wl0: Loading non-MU ucode
[  173.806386] brcmfmac: CONSOLE: 026768.512 wl0: CORE INIT : nfifo 6 mu_tx_enab 0
[  173.806463] brcmfmac: CONSOLE: 026768.512 wl0: CORE INIT : mode 2 pktclassify 1 rxsplit 0  hdr conve 0 DMA_CT Disabled
[  173.806535] brcmfmac: CONSOLE: 026768.525 wl0: wlc_enable_probe_req: state down, deferring setting of host flags
[  173.806594] brcmfmac: CONSOLE: 026768.526 enable 1: q0 frmcnt 0, wrdcnt 0, q1 frmcnt 0, wrdcnt 0
[  173.806629] brcmfmac: brcmf_fweh_event_worker event IF (54) ifidx 0 bsscfg 0 addr 90:8d:78:66:3a:56
[  173.806637] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0 status 0 reason 0
[  173.806641] brcmutil: event payload, len=5
[  173.806647] 00000000: 00 01 00 00 00                                   .....
[  173.806653] brcmfmac: brcmf_fweh_handle_if_event action: 1 ifidx: 0 bsscfgidx: 0 flags: 0 role: 0
[  173.806658] brcmfmac: brcmf_fweh_handle_if_event adding wl0 (90:8d:78:66:3a:56)
[  173.806678] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mpc, len=4
[  173.806683] brcmutil: data
[  173.806688] 00000000: 01 00 00 00                                      ....
[  173.806896] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=arp_ol, len=4
[  173.806901] brcmutil: data
[  173.806907] 00000000: 09 00 00 00                                      ....
[  173.807147] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  173.807154] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=ndoe, len=4
[  173.807158] brcmutil: data
[  173.807163] 00000000: 01 00 00 00                                      ....
[  173.829637] brcmfmac: brcmf_fil_iovar_data_get ifidx=0, name=qtxpower, len=4
[  173.829645] brcmutil: data
[  173.829651] 00000000: 7f 00 00 00                                      ....
[  173.829869] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=mcast_list, len=34
[  173.829876] brcmutil: data
[  173.829883] 00000000: 05 00 00 00 33 33 00 00 00 01 33 33 00 00 00 02  ....33....33....
[  173.829889] 00000010: 01 00 5e 00 00 01 33 33 ff 66 3a 56 33 33 ff 00  ..^...33.f:V33..
[  173.829894] 00000020: 00 00                                            ..
[  173.830159] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=allmulti, len=4
[  173.830165] brcmutil: data
[  173.830170] 00000000: 00 00 00 00                                      ....
[  173.830399] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=10, value=0
[  174.339358] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=arp_hostip_clear, len=0
[  174.339367] brcmutil: data
[  174.339657] brcmfmac: brcmf_fil_cmd_data Failed: BCME_UNSUPPORTED (-23)
[  174.340793] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=nd_hostip_clear, len=0
[  174.340804] brcmutil: data
[  174.341071] brcmfmac: brcmf_fil_iovar_data_set ifidx=0, name=nd_hostip, len=16
[  174.341078] brcmutil: data
[  174.341085] 00000000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  174.341296] brcmfmac: brcmf_fil_cmd_int_set ifidx=0, cmd=20, value=1

4) iw phy phy1 interface add wlan1-1 type __ap
[  189.260050] brcmfmac: brcmf_fil_bsscfg_data_set ifidx=0, bsscfgidx=0, name=bsscfg:ssid, len=40
[  189.260062] brcmutil: data
[  189.260070] 00000000: 02 00 00 00 05 00 00 00 73 73 69 64 32 00 00 00  ........ssid2...
[  189.260076] 00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  189.260081] 00000020: 00 00 00 00 00 00 00 00                          ........
[  190.769208] brcmfmac: brcmf_ap_add_vif: timeout occurred

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-23 19:13         ` Rafał Miłecki
@ 2016-05-06 22:43           ` Rafał Miłecki
  2016-05-24 19:49           ` Arend Van Spriel
  1 sibling, 0 replies; 9+ messages in thread
From: Rafał Miłecki @ 2016-05-06 22:43 UTC (permalink / raw)
  To: Arend Van Spriel
  Cc: brcm80211 development, linux-wireless, Brett Rudley,
	Arend van Spriel, Franky (Zhenhui) Lin, Hante Meuleman

On 23 March 2016 at 20:13, Rafał Miłecki <zajec5@gmail.com> wrote:
> On 23 March 2016 at 11:44, Arend Van Spriel
> <arend.vanspriel@broadcom.com> wrote:
>> On 23-3-2016 9:47, Rafał Miłecki wrote:
>>> On 22 March 2016 at 21:24, Arend van Spriel
>>> <arend.vanspriel@broadcom.com> wrote:
>>>> On 22-3-2016 7:36, Rafał Miłecki wrote:
>>>>> Hi, any ideas / help regarding this issue?
>>>>
>>>> Restarting hostapd obviously is a valid scenario. My guess is we would
>>>> need to avoid interface removal in brcmf_notify_connect_status_ap. But
>>>> first I would like to know in which state the firmware is upon
>>>> brcmf_ap_add_vif. Can you provide a full log with FWCON debug level enabled?
>>>
>>> Sure, hope it helps!
>>
>> Ah. Actually would like to see some driver logging as well so
>> 'debug=0x101410'.
>
> Sure, there you go.

Did this allow you to understand this problem any better? Do you have
any idea for a solution?

-- 
Rafał

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-03-23 19:13         ` Rafał Miłecki
  2016-05-06 22:43           ` Rafał Miłecki
@ 2016-05-24 19:49           ` Arend Van Spriel
  2016-05-25  7:56             ` Rafał Miłecki
  1 sibling, 1 reply; 9+ messages in thread
From: Arend Van Spriel @ 2016-05-24 19:49 UTC (permalink / raw)
  To: Rafał Miłecki
  Cc: brcm80211 development, linux-wireless, Brett Rudley,
	Arend van Spriel, Franky (Zhenhui) Lin, Hante Meuleman

On 23-3-2016 20:13, Rafał Miłecki wrote:
> On 23 March 2016 at 11:44, Arend Van Spriel 
> <arend.vanspriel@broadcom.com> wrote:
>> On 23-3-2016 9:47, Rafał Miłecki wrote:
>>> On 22 March 2016 at 21:24, Arend van Spriel 
>>> <arend.vanspriel@broadcom.com> wrote:
>>>> On 22-3-2016 7:36, Rafał Miłecki wrote:
>>>>> Hi, any ideas / help regarding this issue?
>>>> 
>>>> Restarting hostapd obviously is a valid scenario. My guess is
>>>> we would need to avoid interface removal in
>>>> brcmf_notify_connect_status_ap. But first I would like to know
>>>> in which state the firmware is upon brcmf_ap_add_vif. Can you
>>>> provide a full log with FWCON debug level enabled?
>>> 
>>> Sure, hope it helps!
>> 
>> Ah. Actually would like to see some driver logging as well so 
>> 'debug=0x101410'.
> 
> Sure, there you go.

A bit overdue, but here we go. Upon killing hostapd it results in
brcmf_cfg80211_stop_ap() for 'OpenWrt5x' bss and we give a DOWN command
for the interface to firmware. However, we get a link down for the
primary interface *and* second interface.

[  172.799334] brcmfmac: brcmf_fil_cmd_int_set ifidx=1, cmd=3, value=1
[  172.802111] brcmfmac: CONSOLE: 026767.523 wl0: link down (wl0)
[  172.802147] brcmfmac: CONSOLE: 026767.523 wl0: link down (wl0.2)
[  172.802166] brcmfmac: brcmf_fweh_event_worker event LINK (16) ifidx 0
bsscfg 0 addr 90:8d:78:66:3a:56
[  172.802173] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0
status 0 reason 4
[  172.802178] brcmutil: event payload, len=0
[  172.802202] brcmfmac: brcmf_fweh_event_worker event LINK (16) ifidx 1
bsscfg 2 addr 92:8d:78:66:3a:57
[  172.802208] brcmfmac: brcmf_fweh_event_worker   version 2 flags 0
status 0 reason 4
[  172.802212] brcmutil: event payload, len=0

Looking into the firmware code it turns out that the DOWN command brings
down the entire stack iso single interface. This seems specific for some
firmware branches so we have to find a better solution. Hante and I are
looking into it.

Regards,
Arend

^ permalink raw reply	[flat|nested] 9+ messages in thread

* Re: Problem with brcmfmac removing extra interface
  2016-05-24 19:49           ` Arend Van Spriel
@ 2016-05-25  7:56             ` Rafał Miłecki
  0 siblings, 0 replies; 9+ messages in thread
From: Rafał Miłecki @ 2016-05-25  7:56 UTC (permalink / raw)
  To: Arend Van Spriel
  Cc: brcm80211 development, linux-wireless, Brett Rudley,
	Arend van Spriel, Franky (Zhenhui) Lin, Hante Meuleman

On 24 May 2016 at 21:49, Arend Van Spriel <arend.vanspriel@broadcom.com> wrote:
> Looking into the firmware code it turns out that the DOWN command brings
> down the entire stack iso single interface. This seems specific for some
> firmware branches so we have to find a better solution. Hante and I are
> looking into it.

I started working on brcmfmac few days ago and I realized that as
well. When having 4 AP interfaces and stopping hostapd nicely I get:

brcmfmac: [brcmf_fweh_handle_if_event] ifevent->action:3:BRCMF_E_IF_CHANGE
brcmfmac: action: 3 ifidx: 0 bsscfgidx: 0 flags: 0 role: 8

brcmfmac: [brcmf_fweh_handle_if_event] ifevent->action:2:BRCMF_E_IF_DEL
brcmfmac: action: 2 ifidx: 1 bsscfgidx: 2 flags: 0 role: 1
brcmfmac: brcmf_fweh_call_event_handler: no interface object

brcmfmac: [brcmf_fweh_handle_if_event] ifevent->action:2:BRCMF_E_IF_DEL
brcmfmac: action: 2 ifidx: 2 bsscfgidx: 3 flags: 0 role: 1
brcmfmac: brcmf_fweh_call_event_handler: no interface object

brcmfmac: [brcmf_fweh_handle_if_event] ifevent->action:2:BRCMF_E_IF_DEL
brcmfmac: action: 2 ifidx: 3 bsscfgidx: 4 flags: 0 role: 1
brcmfmac: brcmf_fweh_call_event_handler: no interface object

brcmfmac: [brcmf_fweh_handle_if_event] ifevent->action:1:BRCMF_E_IF_ADD
brcmfmac: action: 1 ifidx: 0 bsscfgidx: 0 flags: 0 role: 8
brcmfmac: adding wl0 (80:23:6a:a3:7d:95)

brcmfmac: [brcmf_fweh_handle_if_event] ifevent->action:3:BRCMF_E_IF_CHANGE
brcmfmac: action: 3 ifidx: 0 bsscfgidx: 0 flags: 0 role: 0

It means that all interfaces/BSS-es were dropped by firmware
internally and thanks to that we can create them again (start AP
again).

It doesn't happen if:
1) I simply add & remove interfaces manually with "iw"
2) I use BCM4366 and hostapd
In both above cases firmware keeps its internal interfaces/BSS-es and
I can't recreate interfaces.

-- 
Rafał

^ permalink raw reply	[flat|nested] 9+ messages in thread

end of thread, other threads:[~2016-05-25  7:56 UTC | newest]

Thread overview: 9+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2016-03-03 22:37 Problem with brcmfmac removing extra interface Rafał Miłecki
2016-03-22  6:36 ` Rafał Miłecki
2016-03-22 20:24   ` Arend van Spriel
2016-03-23  8:47     ` Rafał Miłecki
2016-03-23 10:44       ` Arend Van Spriel
2016-03-23 19:13         ` Rafał Miłecki
2016-05-06 22:43           ` Rafał Miłecki
2016-05-24 19:49           ` Arend Van Spriel
2016-05-25  7:56             ` Rafał Miłecki

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.