Ubuntu
network-manager package

NetworkManager falsely reports WPA network "out of range"

Bug #1099976 reported by jerrylamos
10
This bug affects 2 people
Affects Status Importance Assigned to Milestone
network-manager (Ubuntu)
Confirmed
Undecided
Unassigned

Bug Description

On boot with amd64 Broadcom BCM43225, Raring NetworkManager correctly detects the network name, then NM falsely reports "out of range". What NM is trying to do is to connect without using the WPA encryption key which it already has.

This is similar to bug 1097002 except in 1097002 NM doesn't even see the hidden network name even though it already has the encryption key for that network.

In syslog below, says "has security but secrets are required". That's right, has security, but NM already has the secret encryption key; NM is supposed to use the key instead of disconnecting.

Obviously NM doesn't even look to see it already has the key which it does.

The branch in the NM script that sees "secrets are required" should go right ahead, see that it already has the "secrets", and use them. NetworkManager either doesn't have this path or is refusing to use it.

I go on to connect manually with settings > network using the encryption key network manager already had and should have used.

In any case, when NetworkManager finally does ask the kernel to connect, the kernel connects just fine. Not a kernel problem.

Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Auto-activating connection 'LAUREL'.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) starting connection 'LAUREL'
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0/wireless): access point 'LAUREL' has security, but secrets are required.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0/wireless): connection 'LAUREL' has security, and secrets exist. No new secrets needed.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Config: added 'ssid' value 'LAUREL'
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Config: added 'scan_ssid' value '1'
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Config: added 'psk' value '<omitted>'
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> Config: set interface ap_scan to 1
Jan 15 14:14:10 Aspire-5253 NetworkManager[785]: <info> (wlan0): supplicant interface state: inactive -> scanning
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.465674] wlan0: authenticate with 00:0f:b3:b0:d6:3c
Jan 15 14:14:28 Aspire-5253 wpa_supplicant[970]: wlan0: SME: Trying to authenticate with 00:0f:b3:b0:d6:3c (SSID='LAUREL' freq=2452 MHz)
Jan 15 14:14:28 Aspire-5253 wpa_supplicant[970]: wlan0: Trying to associate with 00:0f:b3:b0:d6:3c (SSID='LAUREL' freq=2452 MHz)
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.468603] wlan0: send auth to 00:0f:b3:b0:d6:3c (try 1/3)
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.470369] wlan0: authenticated
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.472057] brcmsmac bcma0:0 wlan0: disabling HT/VHT due to WEP/TKIP use
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.472081] brcmsmac bcma0:0 wlan0: disabling HT as WMM/QoS is not supported by the AP
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.472090] brcmsmac bcma0:0 wlan0: disabling VHT as WMM/QoS is not supported by the AP
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): supplicant interface state: authenticating -> associating
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.476235] wlan0: associate with 00:0f:b3:b0:d6:3c (try 1/3)
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.479529] wlan0: RX AssocResp from 00:0f:b3:b0:d6:3c (capab=0x431 status=0 aid=1)
Jan 15 14:14:28 Aspire-5253 wpa_supplicant[970]: wlan0: Associated with 00:0f:b3:b0:d6:3c
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.480578] brcmsmac bcma0:0: brcmsmac: brcms_ops_bss_info_changed: associated
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.480593] brcmsmac bcma0:0: brcms_ops_bss_info_changed: arp filtering: enabled true, count 0 (implement)
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.480602] brcmsmac bcma0:0: brcms_ops_bss_info_changed: qos enabled: false (implement)
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.480637] wlan0: associated
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.480663] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Jan 15 14:14:28 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Key negotiation completed with 00:0f:b3:b0:d6:3c [PTK=TKIP GTK=TKIP]
Jan 15 14:14:28 Aspire-5253 wpa_supplicant[970]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:0f:b3:b0:d6:3c completed (auth) [id=0 id_str=]
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): supplicant interface state: group handshake -> completed
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'LAUREL'.
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> dhclient started with pid 3009
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Beginning IP6 addrconf.
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Jan 15 14:14:28 Aspire-5253 dhclient: Internet Systems Consortium DHCP Client 4.2.4
Jan 15 14:14:28 Aspire-5253 dhclient: Copyright 2004-2012 Internet Systems Consortium.
Jan 15 14:14:28 Aspire-5253 dhclient: All rights reserved.
Jan 15 14:14:28 Aspire-5253 dhclient: For info, please visit https://www.isc.org/software/dhcp/
Jan 15 14:14:28 Aspire-5253 dhclient:
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Jan 15 14:14:28 Aspire-5253 dhclient: Listening on LPF/wlan0/88:9f:fa:39:01:f3
Jan 15 14:14:28 Aspire-5253 dhclient: Sending on LPF/wlan0/88:9f:fa:39:01:f3
Jan 15 14:14:28 Aspire-5253 dhclient: Sending on Socket/fallback
Jan 15 14:14:28 Aspire-5253 dhclient: DHCPREQUEST of 192.168.0.4 on wlan0 to 255.255.255.255 port 67
Jan 15 14:14:28 Aspire-5253 dhclient: DHCPACK of 192.168.0.4 from 192.168.0.1
Jan 15 14:14:28 Aspire-5253 dhclient: bound to 192.168.0.4 -- renewal in 35105 seconds.
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> address 192.168.0.4
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> prefix 24 (255.255.255.0)
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> gateway 192.168.0.1
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> hostname 'Aspire-5253'
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> nameserver '192.168.0.1'
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> nameserver '216.165.129.158'
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> domain name 'domain_not_set.invalid'
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Jan 15 14:14:28 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Jan 15 14:14:28 Aspire-5253 avahi-daemon[480]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.4.
Jan 15 14:14:28 Aspire-5253 avahi-daemon[480]: New relevant interface wlan0.IPv4 for mDNS.
Jan 15 14:14:28 Aspire-5253 avahi-daemon[480]: Registering new address record for 192.168.0.4 on wlan0.IPv4.
Jan 15 14:14:28 Aspire-5253 kernel: [ 252.717131] brcmsmac bcma0:0: brcms_ops_bss_info_changed: arp filtering: enabled true, count 1 (implement)
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <info> (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0]
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <info> Policy set 'LAUREL' (wlan0) as default for IPv4 routing and DNS.
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <info> DNS: starting dnsmasq...
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <error> [1358277269.879119] [nm-dns-dnsmasq.c:399] update(): dnsmasq not available on the bus, can't update servers.
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <error> [1358277269.879260] [nm-dns-dnsmasq.c:401] update(): dnsmasq owner not found on bus: Could not get owner of name 'org.freedesktop.NetworkManager.dnsmasq': no such name
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <warn> DNS: plugin dnsmasq update failed
Jan 15 14:14:29 Aspire-5253 NetworkManager[785]: <info> ((null)): writing resolv.conf to /sbin/resolvconf
Jan 15 14:14:29 Aspire-5253 dnsmasq[3013]: started, version 2.65 cache disabled
Jan 15 14:14:29 Aspire-5253 dnsmasq[3013]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack
Jan 15 14:14:29 Aspire-5253 dnsmasq[3013]: DBus support enabled: connected to system bus
Jan 15 14:14:29 Aspire-5253 dnsmasq[3013]: warning: no upstream servers configured
Jan 15 14:14:30 Aspire-5253 avahi-daemon[480]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::8a9f:faff:fe39:1f3.
Jan 15 14:14:30 Aspire-5253 avahi-daemon[480]: New relevant interface wlan0.IPv6 for mDNS.
Jan 15 14:14:30 Aspire-5253 avahi-daemon[480]: Registering new address record for fe80::8a9f:faff:fe39:1f3 on wlan0.*.
Jan 15 14:14:32 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) successful, device activated.
Jan 15 14:14:32 Aspire-5253 dbus[424]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Jan 15 14:14:32 Aspire-5253 NetworkManager[785]: <warn> dnsmasq appeared on DBus: :1.58
Jan 15 14:14:32 Aspire-5253 NetworkManager[785]: <info> ((null)): writing resolv.conf to /sbin/resolvconf
Jan 15 14:14:32 Aspire-5253 dnsmasq[3013]: setting upstream servers from DBus
Jan 15 14:14:32 Aspire-5253 dnsmasq[3013]: using nameserver 216.165.129.158#53
Jan 15 14:14:32 Aspire-5253 dnsmasq[3013]: using nameserver 192.168.0.1#53
Jan 15 14:14:32 Aspire-5253 dbus[424]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 15 14:14:40 Aspire-5253 ntpdate[3141]: adjust time server 91.189.94.4 offset 0.036555 sec
Jan 15 14:14:49 Aspire-5253 NetworkManager[785]: <info> (wlan0): IP6 addrconf timed out or failed.
Jan 15 14:14:49 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Jan 15 14:14:49 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Jan 15 14:14:49 Aspire-5253 NetworkManager[785]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Jan 15 14:14:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:15:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:16:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:17:01 Aspire-5253 CRON[3256]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Jan 15 14:17:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:18:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:19:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:20:56 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]
Jan 15 14:21:57 Aspire-5253 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 00:0f:b3:b0:d6:3c [GTK=TKIP]

ProblemType: Bug
DistroRelease: Ubuntu 13.04
Package: network-manager 0.9.6.0+git201301021750.e78c3e8-0ubuntu1
ProcVersionSignature: Ubuntu 3.8.0-0.3-generic 3.8.0-rc3
Uname: Linux 3.8.0-0-generic x86_64
ApportVersion: 2.8-0ubuntu1
Architecture: amd64
Date: Tue Jan 15 14:11:20 2013
IfupdownConfig:
 # interfaces(5) file used by ifup(8) and ifdown(8)
 auto lo
 iface lo inet loopback
InstallationDate: Installed on 2012-12-14 (32 days ago)
InstallationMedia: Ubuntu 13.04 "Raring Ringtail" - Alpha amd64 (20121213)
IpRoute:

MarkForUpload: True
NetworkManager.state:
 [main]
 NetworkingEnabled=true
 WirelessEnabled=true
 WWANEnabled=true
 WimaxEnabled=true
ProcEnviron:
 TERM=xterm
 PATH=(custom, no user)
 XDG_RUNTIME_DIR=<set>
 LANG=en_US.UTF-8
 SHELL=/bin/bash
SourcePackage: network-manager
UpgradeStatus: No upgrade log present (probably fresh install)
nmcli-con:
 NAME UUID TYPE TIMESTAMP TIMESTAMP-REAL AUTOCONNECT READONLY DBUS-PATH
 Wired connection 1 484ee6a2-2102-4943-8efc-8ffabbe739e5 802-3-ethernet 1358277021 Tue 15 Jan 2013 02:10:21 PM EST yes no /org/freedesktop/NetworkManager/Settings/1
 LAUREL 87813286-cf7b-4afc-983e-55e506fdaee2 802-11-wireless 1358276995 Tue 15 Jan 2013 02:09:55 PM EST yes no /org/freedesktop/NetworkManager/Settings/0
nmcli-dev:
 DEVICE TYPE STATE DBUS-PATH
 wlan0 802-11-wireless disconnected /org/freedesktop/NetworkManager/Devices/1
 eth0 802-3-ethernet unavailable /org/freedesktop/NetworkManager/Devices/0
nmcli-nm:
 RUNNING VERSION STATE NET-ENABLED WIFI-HARDWARE WIFI WWAN-HARDWARE WWAN
 running 0.9.7.0 disconnected enabled enabled enabled enabled disabled

See original description
Revision history for this message
jerrylamos (jerrylamos) wrote :
Thomas Hood (jdthood)
summary: - Raring network manager msg false out of range WPA network
+ NetworkManager falsely reports WPA network "out of range"
description: updated
Revision history for this message
Launchpad Janitor (janitor) wrote :

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in network-manager (Ubuntu):
status: New → Confirmed
Revision history for this message
jerrylamos (jerrylamos) wrote :

Raring updated as of 31 January still gets false "out of range" on hidden WPA protected network on both Acer Aspire One netbook with integrated wireless and on Acer Aspire 5253 notebook with Broadcom wireless. Precise no problem. Is there any way to put Precise level Network Manager on Raring for testing purposes?

Revision history for this message
Ralph (rpeters52) wrote :

As of today, I see this problem with an old Dell Inspiron 1525. I am running linux mint 15 with network-manager-gnome Version: 0.9.8.0-1ubuntu2

I did not have this problem with linux mint 10 through linux mint 13.

To post a comment you must log in.
This report contains Public information  
Everyone can see this information.
Subscribing...

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.