Public bug reported:
Binary package hint: network-manager
Hello!
I've been to a conference this week where they had a very flaky WiFi
access point. I think it crashed or at least overloaded because of the
many people trying to connect at the same time.
Anyway, I noticed a bug: whenever the network manager failed to connect
it would ask me for the network key, even though the failure was not
related to authentication. I've noticed this a couple times before, but
during the conference it happened all the time.
I've saved a snippet of /var/log/daemon.log (below) which shows this
happening. As you can see, around the bottom it asks for new secrets,
despite the only error being a timeout.
There's also a "<WARN> nm_device_wifi_set_enabled(): not in expected
unavailable state!" line that kept appearing often, should I file a bug
for that, too?
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): bringing up device.
Oct 23 12:11:35 arioch acpid: client connected from 5073[0:0]
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): preparing device.
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): deactivating device
(reason: 2).
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): device state change: 2
-> 3
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): supplicant interface
state change: 1 -> 2.
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) starting
connection 'agora/bda2008'
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): device state change: 3
-> 4
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) scheduled...
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) started...
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) scheduled...
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) complete.
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) starting...
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): device state change: 4
-> 5
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1/wireless):
connection 'agora/bda2008' has security, and secrets exist. No new secrets
needed.
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'ssid' value
'agora'
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'scan_ssid' value
'1'
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'key_mgmt' value
'WPA-PSK'
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'psk' value
'<omitted>'
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'proto' value 'WPA
RSN'
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'pairwise' value
'TKIP CCMP'
Oct 23 12:11:35 arioch NetworkManager: <info> Config: added 'group' value
'WEP40 WEP104 TKIP CCMP'
Oct 23 12:11:35 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) complete.
Oct 23 12:11:35 arioch NetworkManager: <info> Config: set interface ap_scan to
1
Oct 23 12:11:35 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 1 -> 2
Oct 23 12:12:00 arioch NetworkManager: <info> Activation (wlan1/wireless):
association took too long.
Oct 23 12:12:00 arioch NetworkManager: <info> (wlan1): device state change: 5
-> 6
Oct 23 12:12:00 arioch NetworkManager: <info> Activation (wlan1/wireless):
asking for new secrets
Oct 23 12:12:00 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 2 -> 0
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) scheduled...
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) started...
Oct 23 12:12:07 arioch NetworkManager: <info> (wlan1): device state change: 6
-> 4
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) scheduled...
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) complete.
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) starting...
Oct 23 12:12:07 arioch NetworkManager: <info> (wlan1): device state change: 4
-> 5
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1/wireless):
connection 'agora/bda2008' has security, and secrets exist. No new secrets
needed.
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'ssid' value
'agora'
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'scan_ssid' value
'1'
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'key_mgmt' value
'WPA-PSK'
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'psk' value
'<omitted>'
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'proto' value 'WPA
RSN'
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'pairwise' value
'TKIP CCMP'
Oct 23 12:12:07 arioch NetworkManager: <info> Config: added 'group' value
'WEP40 WEP104 TKIP CCMP'
Oct 23 12:12:07 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) complete.
Oct 23 12:12:07 arioch NetworkManager: <info> Config: set interface ap_scan to
1
Oct 23 12:12:07 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 0 -> 2
Oct 23 12:12:20 arioch dnsmasq[4683]: query[A] talk.google.com from 127.0.0.1
Oct 23 12:12:20 arioch dnsmasq[4683]: query[A] talk.google.com from 127.0.0.1
Oct 23 12:12:20 arioch dnsmasq[4683]: query[A] talk.google.com.WAG54GS from
127.0.0.1
Oct 23 12:12:20 arioch dnsmasq[4683]: query[A] talk.google.com.WAG54GS from
127.0.0.1
Oct 23 12:12:32 arioch NetworkManager: <info> Activation (wlan1/wireless):
association took too long.
Oct 23 12:12:32 arioch NetworkManager: <info> (wlan1): device state change: 5
-> 6
Oct 23 12:12:32 arioch NetworkManager: <info> Activation (wlan1/wireless):
asking for new secrets
Oct 23 12:12:32 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 2 -> 0
Oct 23 12:12:47 arioch NetworkManager: <info> wlan1: link timed out.
Oct 23 12:13:15 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 0 -> 2
Oct 23 12:13:50 arioch ntpd[23599]: Deleting interface #2 wlan1,
192.168.1.107#123, interface stats: received=0, sent=0, dropped=0,
active_time=601 secs
Oct 23 12:13:50 arioch dnsmasq[4683]: query[A] talk.google.com from 127.0.0.1
Oct 23 12:13:50 arioch dnsmasq[4683]: query[A] talk.google.com from 127.0.0.1
Oct 23 12:13:50 arioch dnsmasq[4683]: query[A] talk.google.com.WAG54GS from
127.0.0.1
Oct 23 12:13:50 arioch dnsmasq[4683]: query[A] talk.google.com.WAG54GS from
127.0.0.1
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) scheduled...
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) started...
Oct 23 12:13:53 arioch NetworkManager: <info> (wlan1): device state change: 6
-> 4
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) scheduled...
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1) Stage 1 of 5
(Device Prepare) complete.
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) starting...
Oct 23 12:13:53 arioch NetworkManager: <info> (wlan1): device state change: 4
-> 5
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1/wireless):
connection 'agora/bda2008' has security, and secrets exist. No new secrets
needed.
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'ssid' value
'agora'
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'scan_ssid' value
'1'
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'key_mgmt' value
'WPA-PSK'
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'psk' value
'<omitted>'
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'proto' value 'WPA
RSN'
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'pairwise' value
'TKIP CCMP'
Oct 23 12:13:53 arioch NetworkManager: <info> Config: added 'group' value
'WEP40 WEP104 TKIP CCMP'
Oct 23 12:13:53 arioch NetworkManager: <info> Activation (wlan1) Stage 2 of 5
(Device Configure) complete.
Oct 23 12:13:53 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 2 -> 0
Oct 23 12:13:53 arioch NetworkManager: <info> Config: set interface ap_scan to
1
Oct 23 12:13:53 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 0 -> 2
Oct 23 12:14:08 arioch NetworkManager: <info> wlan1: link timed out.
Oct 23 12:14:18 arioch NetworkManager: <info> Activation (wlan1/wireless):
association took too long.
Oct 23 12:14:18 arioch NetworkManager: <info> (wlan1): device state change: 5
-> 6
Oct 23 12:14:18 arioch NetworkManager: <info> Activation (wlan1/wireless):
asking for new secrets
Oct 23 12:14:18 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 2 -> 0
Oct 23 12:14:25 arioch NetworkManager: <info> (wlan1): supplicant connection
state change: 0 -> 2
Oct 23 12:14:33 arioch NetworkManager: <info> wlan1: link timed out.
Oct 23 12:15:53 arioch NetworkManager: <info> (wlan1): device state change: 6
-> 2
Oct 23 12:15:53 arioch NetworkManager: <info> (wlan1): deactivating device
(reason: 0).
Oct 23 12:15:53 arioch NetworkManager: <info> (wlan1): taking down device.
Oct 23 12:16:18 arioch NetworkManager: <WARN> nm_device_wifi_set_enabled():
not in expected unavailable state!
Oct 23 12:16:18 arioch NetworkManager: <info> (wlan1): bringing up device.
Oct 23 12:16:18 arioch NetworkManager: <info> (wlan1): device state change: 2
-> 3
Oct 23 12:16:18 arioch NetworkManager: <info> (wlan1): supplicant interface
state change: 1 -> 2.
** Affects: network-manager (Ubuntu)
Importance: Undecided
Status: New
--
NetworkManager asks for password after unrelated connection errors
https://bugs.launchpad.net/bugs/289108
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
--
ubuntu-bugs mailing list
ubuntu-bugs@lists.ubuntu.com
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
