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@xxxxxxxxxxxxxxx
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@xxxxxxxxxxxxxxx
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@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
