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

** Changed in: network-manager (Ubuntu)
       Status: New => Confirmed

-- 
You received this bug notification because you are a member of Desktop
Packages, which is subscribed to network-manager in Ubuntu.
https://bugs.launchpad.net/bugs/1099976

Title:
  NetworkManager falsely reports WPA network "out of range"

Status in “network-manager” package in Ubuntu:
  Confirmed

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

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/network-manager/+bug/1099976/+subscriptions

-- 
Mailing list: https://launchpad.net/~desktop-packages
Post to     : desktop-packages@lists.launchpad.net
Unsubscribe : https://launchpad.net/~desktop-packages
More help   : https://help.launchpad.net/ListHelp

Reply via email to