All of lore.kernel.org
 help / color / mirror / Atom feed
From: Arend van Spriel <arend@broadcom.com>
To: Dan Williams <dcbw@redhat.com>
Cc: "Jürgen Bausa" <Juergen.Bausa@online.de>, linux-wireless@vger.kernel.org
Subject: Re: Broadcom 43340
Date: Wed, 18 Mar 2015 22:40:05 +0100	[thread overview]
Message-ID: <5509F0B5.7030601@broadcom.com> (raw)
In-Reply-To: <1426714179.26901.37.camel@redhat.com>

On 03/18/15 22:29, Dan Williams wrote:
> On Wed, 2015-03-18 at 21:24 +0000, Jürgen Bausa wrote:
>> Dan Williams<dcbw@...>  writes:
>>
>>>
>>>>
>>>> Thanks, that worked. Now I have firmware and nvram-file and the driver
>> seems
>>>> to load ok. At least I have an interface wlan0.
>>>>
>>>> However, I am not able to connect.Network-manager just says "interface is
>>>> being set up ..." forever. I have no idea howto debug this. In the other
>>>> post you said that the nl80211 driver should be used instead of wext. I
>>>> found that nl80211 is the default for network-manager. So, whats going
>> wrong
>>>> here?
>>>
>>> Could you provide more NetworkManager logs?  They should look like this;
>>> where do your logs stop?
>>>
>>
>>
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) starting
>> connection 'tuxgang'
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) scheduled...
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) started...
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: disconnected ->  prepare (reason 'none') [30 40 0]
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  NetworkManager state is now
>> CONNECTING
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) scheduled...
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) complete.
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) starting...
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: prepare ->  config (reason 'none') [40 50 0]
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0/wireless):
>> connection 'tuxgang' has security, and secrets exist.  No new secrets needed.
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Config: added 'ssid' value
>> 'tuxgang'
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Config: added 'scan_ssid'
>> value '1'
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Config: added 'key_mgmt'
>> value 'WPA-PSK'
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Config: added 'auth_alg'
>> value 'OPEN'
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Config: added 'psk' value
>> '<omitted>'
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) complete.
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  Config: set interface
>> ap_scan to 1
>> Mar 18 22:16:40 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: inactive ->  scanning
>> Mar 18 22:16:50 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: scanning ->  associating
>> Mar 18 22:17:00 lina NetworkManager[532]:<warn>  Connection disconnected
>> (reason -3)
>
> Something is still wrong with the driver/device.  Here, even though a
> scan has already been done and the SSID found (otherwise NM wouldn't
> have started a connection in the first place) the supplicant is doing
> another scan (10s) and then attempting to associate, which takes 10s
> before NM kills the attempt.
>
> So ignoring the scan issues (eg, why is another 10s scan requires when
> the SSID is already in the supplicant's network list) it still has
> association problems.
>
> NetworkManager allows up to 20s for an auth/assoc since that really,
> really should be enough.  If it's not enough, then something is
> seriously wrong with the hardware or the wifi network.
>
> Next steps:
>
> What does 'dmesg' say here?  Anything about association tries?
>
> After that we try getting supplicant debug logs and figuring out what's
> going on from its point of view.

Hi Dan,

Thanks for checking the network manager log.

Jurgen,

Do you have brcmfmac driver built with CONFIG_BRCMDBG. If so you can 
enable debug output by:

sudo sh -c 'echo 0xd416 > /sys/module/brcmfmac/parameters/debug'

Regards,
Arend

> Dan
>
>> Mar 18 22:17:00 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: associating ->  disconnected
>> Mar 18 22:17:00 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: disconnected ->  scanning
>> Mar 18 22:17:05 lina NetworkManager[532]:<warn>  Activation (wlan0/wireless):
>> association took too long.
>> Mar 18 22:17:05 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: config ->  need-auth (reason 'none') [50 60 0]
>> Mar 18 22:17:05 lina NetworkManager[532]:<warn>  Activation (wlan0/wireless):
>> asking for new secrets
>> Mar 18 22:17:05 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:17:05 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:17:29 lina NetworkManager[532]: (NetworkManager:532): libnm-util-
>> CRITICAL **: get_secret_flags: assertion 'is_secret_prop (setting,
>> secret_name, error)' failed
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) scheduled...
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) started...
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: need-auth ->  prepare (reason 'none') [60 40 0]
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) scheduled...
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) complete.
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) starting...
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: prepare ->  config (reason 'none') [40 50 0]
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0/wireless):
>> connection 'tuxgang' has security, and secrets exist.  No new secrets needed.
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Config: added 'ssid' value
>> 'tuxgang'
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Config: added 'scan_ssid'
>> value '1'
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Config: added 'key_mgmt'
>> value 'WPA-PSK'
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Config: added 'auth_alg'
>> value 'OPEN'
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Config: added 'psk' value
>> '<omitted>'
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) complete.
>> Mar 18 22:17:29 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  Config: set interface
>> ap_scan to 1
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: scanning ->  associated
>> Mar 18 22:17:29 lina NetworkManager[532]:<warn>  Connection disconnected
>> (reason -3)
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: associated ->  disconnected
>> Mar 18 22:17:29 lina NetworkManager[532]:<warn>  Connection disconnected
>> (reason 6)
>> Mar 18 22:17:29 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: disconnected ->  scanning
>> Mar 18 22:17:39 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: scanning ->  associating
>> Mar 18 22:17:49 lina NetworkManager[532]:<warn>  Connection disconnected
>> (reason -3)
>> Mar 18 22:17:49 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: associating ->  disconnected
>> Mar 18 22:17:49 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: disconnected ->  scanning
>> Mar 18 22:17:54 lina NetworkManager[532]:<warn>  Activation (wlan0/wireless):
>> association took too long.
>> Mar 18 22:17:54 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: config ->  need-auth (reason 'none') [50 60 0]
>> Mar 18 22:17:54 lina NetworkManager[532]:<warn>  Activation (wlan0/wireless):
>> asking for new secrets
>> Mar 18 22:17:54 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:17:54 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:18:00 lina NetworkManager[532]: (NetworkManager:532): libnm-util-
>> CRITICAL **: get_secret_flags: assertion 'is_secret_prop (setting,
>> secret_name, error)' failed
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) scheduled...
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) started...
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: need-auth ->  prepare (reason 'none') [60 40 0]
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) scheduled...
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) complete.
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) starting...
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: prepare ->  config (reason 'none') [40 50 0]
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0/wireless):
>> connection 'tuxgang' has security, and secrets exist.  No new secrets needed.
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Config: added 'ssid' value
>> 'tuxgang'
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Config: added 'scan_ssid'
>> value '1'
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Config: added 'key_mgmt'
>> value 'WPA-PSK'
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Config: added 'auth_alg'
>> value 'OPEN'
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Config: added 'psk' value
>> '<omitted>'
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) complete.
>> Mar 18 22:18:00 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Config: set interface
>> ap_scan to 1
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: scanning ->  4-way handshake
>> Mar 18 22:18:00 lina NetworkManager[532]:<warn>  Connection disconnected
>> (reason 6)
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: 4-way handshake ->  disconnected
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  Activation (wlan0/wireless):
>> disconnected during association, asking for new key.
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: config ->  need-auth (reason 'supplicant-disconnect') [50 60 8]
>> Mar 18 22:18:00 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: disconnected ->  inactive
>> Mar 18 22:18:07 lina NetworkManager[532]: (NetworkManager:532): libnm-util-
>> CRITICAL **: get_secret_flags: assertion 'is_secret_prop (setting,
>> secret_name, error)' failed
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) scheduled...
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) started...
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: need-auth ->  prepare (reason 'none') [60 40 0]
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) scheduled...
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 1
>> of 5 (Device Prepare) complete.
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) starting...
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: prepare ->  config (reason 'none') [40 50 0]
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0/wireless):
>> connection 'tuxgang' has security, and secrets exist.  No new secrets needed.
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Config: added 'ssid' value
>> 'tuxgang'
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Config: added 'scan_ssid'
>> value '1'
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Config: added 'key_mgmt'
>> value 'WPA-PSK'
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Config: added 'auth_alg'
>> value 'OPEN'
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Config: added 'psk' value
>> '<omitted>'
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Activation (wlan0) Stage 2
>> of 5 (Device Configure) complete.
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  Config: set interface
>> ap_scan to 1
>> Mar 18 22:18:07 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: inactive ->  associating
>> Mar 18 22:18:17 lina NetworkManager[532]:<warn>  Connection disconnected
>> (reason -3)
>> Mar 18 22:18:17 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: associating ->  disconnected
>> Mar 18 22:18:17 lina NetworkManager[532]:<info>  (wlan0): supplicant
>> interface state: disconnected ->  scanning
>> Mar 18 22:18:32 lina NetworkManager[532]:<warn>  Activation (wlan0/wireless):
>> association took too long.
>> Mar 18 22:18:32 lina NetworkManager[532]:<info>  (wlan0): device state
>> change: config ->  need-auth (reason 'none') [50 60 0]
>> Mar 18 22:18:32 lina NetworkManager[532]:<warn>  Activation (wlan0/wireless):
>> asking for new secrets
>> Mar 18 22:18:32 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>> Mar 18 22:18:32 lina NetworkManager[532]:<warn>  Couldn't disconnect
>> supplicant interface: This interface is not connected.
>>
>>
>>
>>
>> --
>> To unsubscribe from this list: send the line "unsubscribe linux-wireless" in
>> the body of a message to majordomo@vger.kernel.org
>> More majordomo info at  http://vger.kernel.org/majordomo-info.html
>
>
> --
> To unsubscribe from this list: send the line "unsubscribe linux-wireless" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html


  reply	other threads:[~2015-03-18 21:40 UTC|newest]

Thread overview: 25+ messages / expand[flat|nested]  mbox.gz  Atom feed  top
     [not found] <550482A2.3080803@gmail.com>
     [not found] ` <55048833.90408@broadcom.com>
     [not found]   ` <55048BB1.9080906@gmail.com>
2015-03-14 19:54     ` Broadcom 43340 Arend van Spriel
2015-03-14 20:07       ` Ochal Christophe
2015-03-14 20:39         ` Stefan Lippers-Hollmann
2015-03-14 22:13           ` Ochal Christophe
2015-03-15 11:37             ` Arend van Spriel
2015-03-15 20:38               ` Jürgen Bausa
2015-03-15 21:14                 ` Arend van Spriel
2015-03-17 21:01                   ` Jürgen Bausa
2015-03-17 22:20                     ` Arend van Spriel
2015-03-18 21:12                       ` Jürgen Bausa
2015-03-17 23:20                     ` Dan Williams
2015-03-18 21:24                       ` Jürgen Bausa
2015-03-18 21:29                         ` Dan Williams
2015-03-18 21:40                           ` Arend van Spriel [this message]
2015-03-19 21:00                             ` Jürgen Bausa
2015-03-19 21:40                               ` Arend van Spriel
2015-03-21 20:38                                 ` Jürgen Bausa
2015-03-22 13:11                                   ` Arend van Spriel
2015-03-22 20:14                                     ` Jürgen Bausa
2015-03-23  9:52                                       ` Arend van Spriel
2015-03-23 20:09                                         ` Jürgen Bausa
2015-03-23 20:50                                           ` Arend van Spriel
2015-03-15 21:20                 ` Stefan Lippers-Hollmann
2015-03-15  2:03       ` Koen Bulcke
2015-03-15 11:39         ` Arend van Spriel

Reply instructions:

You may reply publicly to this message via plain-text email
using any one of the following methods:

* Save the following mbox file, import it into your mail client,
  and reply-to-all from there: mbox

  Avoid top-posting and favor interleaved quoting:
  https://en.wikipedia.org/wiki/Posting_style#Interleaved_style

* Reply using the --to, --cc, and --in-reply-to
  switches of git-send-email(1):

  git send-email \
    --in-reply-to=5509F0B5.7030601@broadcom.com \
    --to=arend@broadcom.com \
    --cc=Juergen.Bausa@online.de \
    --cc=dcbw@redhat.com \
    --cc=linux-wireless@vger.kernel.org \
    /path/to/YOUR_REPLY

  https://kernel.org/pub/software/scm/git/docs/git-send-email.html

* If your mail client supports setting the In-Reply-To header
  via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line before the message body.
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.