[pkg-wpa-devel] Bug#681155: wpasupplicant: fails to connect to WPA2 Enterprise network

Andreas Kloeckner inform at tiker.net
Tue Jul 10 23:52:45 UTC 2012


Package: wpasupplicant
Version: 0.7.3-6
Severity: important

Dear Maintainer,

wpasupplicant 1.0-2 fails for me where 0.7.3-6 (from
snapshot.debian.net) succeeds. I'm trying to access NYU's WPA2
Enterprise network.

Here's the successful log from 0.7.3-6:
-------------------------------------------------------------------------------
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 1 of 5 (Device Prepare) scheduled...
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 1 of 5 (Device Prepare) started...
Jul 10 19:30:12 ding NetworkManager[1600]: <info> (wlan0): device state
change: need-auth -> prepare (reason 'none') [60 40 0]
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 2 of 5 (Device Configure) scheduled...
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 1 of 5 (Device Prepare) complete.
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 2 of 5 (Device Configure) starting...
Jul 10 19:30:12 ding NetworkManager[1600]: <info> (wlan0): device state
change: prepare -> config (reason 'none') [40 50 0]
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation
(wlan0/wireless): connection 'nyu' has security, and secrets exist.  No
new secrets needed.
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added 'ssid'
value 'nyu'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added
'scan_ssid' value '1'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added
'key_mgmt' value 'WPA-EAP'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added
'password' value '<omitted>'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added 'eap'
value 'PEAP'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added
'fragment_size' value '1300'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added 'phase2'
value 'auth=MSCHAPV2'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added
'identity' value 'ak177'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: added 'bgscan'
value 'simple:30:-45:300'
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 2 of 5 (Device Configure) complete.
Jul 10 19:30:12 ding NetworkManager[1600]: <info> Config: set interface
ap_scan to 1
Jul 10 19:30:12 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: disconnected -> scanning
Jul 10 19:30:15 ding wpa_supplicant[4683]: Trying to authenticate with
9c:4e:20:c9:03:01 (SSID='nyu' freq=2462 MHz)
Jul 10 19:30:15 ding kernel: [ 1167.664776] wlan0: authenticate with
9c:4e:20:c9:03:01
Jul 10 19:30:15 ding kernel: [ 1167.686181] wlan0: send auth to
9c:4e:20:c9:03:01 (try 1/3)
Jul 10 19:30:15 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: scanning -> authenticating
Jul 10 19:30:15 ding wpa_supplicant[4683]: Trying to associate with
9c:4e:20:c9:03:01 (SSID='nyu' freq=2462 MHz)
Jul 10 19:30:15 ding kernel: [ 1167.688286] wlan0: authenticated
Jul 10 19:30:15 ding kernel: [ 1167.690647] wlan0: associate with
9c:4e:20:c9:03:01 (try 1/3)
Jul 10 19:30:15 ding kernel: [ 1167.693097] wlan0: RX AssocResp from
9c:4e:20:c9:03:01 (capab=0x431 status=0 aid=1)
Jul 10 19:30:15 ding kernel: [ 1167.693107] wlan0: associated
Jul 10 19:30:15 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: authenticating -> associating
Jul 10 19:30:15 ding kernel: [ 1167.698128] ADDRCONF(NETDEV_CHANGE):
wlan0: link becomes ready
Jul 10 19:30:15 ding wpa_supplicant[4683]: Associated with
9c:4e:20:c9:03:01
Jul 10 19:30:15 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-STARTED EAP
authentication started
Jul 10 19:30:15 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: associating -> associated
Jul 10 19:30:15 ding wpa_supplicant[4683]:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=25
Jul 10 19:30:15 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-METHOD EAP
vendor 0 method 25 (PEAP) selected
Jul 10 19:30:15 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-PEER-CERT
depth=2 subject='/C=US/O=VeriSign, Inc./OU=VeriSign Trust Network/OU=(c)
2006 VeriSign, Inc. - For authorized use only/CN=VeriSign Class 3 Public
Primary Certification Authority - G5'
Jul 10 19:30:15 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-PEER-CERT
depth=2 subject='/C=US/O=VeriSign, Inc./OU=VeriSign Trust Network/OU=(c)
2006 VeriSign, Inc. - For authorized use only/CN=VeriSign Class 3 Public
Primary Certification Authority - G5'
Jul 10 19:30:15 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-PEER-CERT
depth=1 subject='/C=US/O=VeriSign, Inc./OU=VeriSign Trust
Network/OU=Terms of use at https://www.verisign.com/rpa
(c)10/CN=VeriSign Class 3 Secure Server CA - G3'
Jul 10 19:30:15 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-PEER-CERT
depth=0 subject='/C=US/ST=New York/L=New York/O=New York
University/OU=Information Technology Services/CN=rad.nyu.edu'
Jul 10 19:30:16 ding wpa_supplicant[4683]: EAP-MSCHAPV2: Authentication
succeeded
Jul 10 19:30:16 ding wpa_supplicant[4683]: EAP-TLV: TLV Result - Success
- EAP-TLV/Phase2 Completed
Jul 10 19:30:16 ding wpa_supplicant[4683]: CTRL-EVENT-EAP-SUCCESS EAP
authentication completed successfully
Jul 10 19:30:16 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: associated -> 4-way handshake
Jul 10 19:30:16 ding wpa_supplicant[4683]: WPA: Key negotiation
completed with 9c:4e:20:c9:03:01 [PTK=CCMP GTK=TKIP]
Jul 10 19:30:16 ding wpa_supplicant[4683]: CTRL-EVENT-CONNECTED -
Connection to 9c:4e:20:c9:03:01 completed (auth) [id=0 id_str=]
Jul 10 19:30:16 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: 4-way handshake -> completed
Jul 10 19:30:16 ding NetworkManager[1600]: <info> Activation
(wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected
to wireless network 'nyu'.
Jul 10 19:30:16 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 3 of 5 (IP Configure Start) scheduled.
Jul 10 19:30:16 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 3 of 5 (IP Configure Start) started...
Jul 10 19:30:16 ding NetworkManager[1600]: <info> (wlan0): device state
change: config -> ip-config (reason 'none') [50 70 0]
Jul 10 19:30:16 ding NetworkManager[1600]: <info> Activation (wlan0)
Beginning DHCPv4 transaction (timeout in 45 seconds)
Jul 10 19:30:16 ding NetworkManager[1600]: <info> dhclient started with
pid 4790
Jul 10 19:30:16 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 3 of 5 (IP Configure Start) complete.
Jul 10 19:30:16 ding dhclient: Internet Systems Consortium DHCP Client
4.2.2
Jul 10 19:30:16 ding dhclient: Copyright 2004-2011 Internet Systems
Consortium.
Jul 10 19:30:16 ding dhclient: All rights reserved.
Jul 10 19:30:16 ding dhclient: For info, please visit
https://www.isc.org/software/dhcp/
Jul 10 19:30:16 ding dhclient: 
Jul 10 19:30:16 ding NetworkManager[1600]: <info> (wlan0): DHCPv4 state
changed nbi -> preinit
Jul 10 19:30:16 ding dhclient: Listening on LPF/wlan0/a0:88:b4:8a:87:50
Jul 10 19:30:16 ding dhclient: Sending on   LPF/wlan0/a0:88:b4:8a:87:50
Jul 10 19:30:16 ding dhclient: Sending on   Socket/fallback
Jul 10 19:30:16 ding dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
port 67 interval 7
Jul 10 19:30:17 ding avahi-daemon[1334]: Joining mDNS multicast group on
interface wlan0.IPv6 with address fe80::a288:b4ff:fe8a:8750.
Jul 10 19:30:17 ding avahi-daemon[1334]: New relevant interface
wlan0.IPv6 for mDNS.
Jul 10 19:30:17 ding avahi-daemon[1334]: Registering new address record
for fe80::a288:b4ff:fe8a:8750 on wlan0.*.
Jul 10 19:30:23 ding dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
port 67 interval 14
Jul 10 19:30:23 ding dhclient: DHCPREQUEST on wlan0 to 255.255.255.255
port 67
Jul 10 19:30:23 ding dhclient: DHCPOFFER from 172.26.60.2
Jul 10 19:30:23 ding dhclient: DHCPACK from 172.26.60.2
Jul 10 19:30:23 ding dhclient: bound to 172.26.61.115 -- renewal in 255
seconds.
Jul 10 19:30:23 ding NetworkManager[1600]: <info> (wlan0): DHCPv4 state
changed preinit -> bound
Jul 10 19:30:23 ding NetworkManager[1600]: <info>   address
172.26.61.115
Jul 10 19:30:23 ding NetworkManager[1600]: <info>   prefix 22
(255.255.252.0)
Jul 10 19:30:23 ding NetworkManager[1600]: <info>   gateway 172.26.60.1
Jul 10 19:30:23 ding NetworkManager[1600]: <info>   nameserver
'128.122.253.103'
Jul 10 19:30:23 ding NetworkManager[1600]: <info>   nameserver
'128.122.253.79'
Jul 10 19:30:23 ding NetworkManager[1600]: <info>   domain name
'nyu.edu'
Jul 10 19:30:23 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Jul 10 19:30:23 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 5 of 5 (IPv4 Commit) started...
Jul 10 19:30:23 ding avahi-daemon[1334]: Joining mDNS multicast group on
interface wlan0.IPv4 with address 172.26.61.115.
Jul 10 19:30:23 ding avahi-daemon[1334]: New relevant interface
wlan0.IPv4 for mDNS.
Jul 10 19:30:23 ding avahi-daemon[1334]: Registering new address record
for 172.26.61.115 on wlan0.IPv4.
Jul 10 19:30:24 ding NetworkManager[1600]: <info> (wlan0): device state
change: ip-config -> activated (reason 'none') [70 100 0]
Jul 10 19:30:24 ding NetworkManager[1600]: <info> (wlan0): roamed from
BSSID 1C:17:D3:CB:DB:F0 (nyu) to 9C:4E:20:C9:03:01 (nyu)
Jul 10 19:30:24 ding NetworkManager[1600]: <info> Policy set 'nyu'
(wlan0) as default for IPv4 routing and DNS.
Jul 10 19:30:24 ding NetworkManager[1600]: <info> Activation (wlan0)
successful, device activated.
Jul 10 19:30:24 ding NetworkManager[1600]: <info> Activation (wlan0)
Stage 5 of 5 (IPv4 Commit) complete.
Jul 10 19:30:24 ding dbus[1557]: [system] Activating service
name='org.freedesktop.nm_dispatcher' (using servicehelper)
Jul 10 19:30:24 ding dbus-daemon[1557]: dbus[1557]: [system] Activating
service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Jul 10 19:30:24 ding dbus-daemon[1557]: dbus[1557]: [system]
Successfully activated service 'org.freedesktop.nm_dispatcher'
Jul 10 19:30:24 ding dbus[1557]: [system] Successfully activated service
'org.freedesktop.nm_dispatcher'
Jul 10 19:30:26 ding kernel: [ 1178.141158] wlan0: no IPv6 routers
present
-------------------------------------------------------------------------------

Here's the failing log from 1.0-2:
-------------------------------------------------------------------------------
ul 10 19:22:45 ding wpa_supplicant[1771]: wlan0: SME: Trying to
authenticate with 1c:17:d3:cb:9a:e0 (SSID='nyu' freq=2437 MHz)
Jul 10 19:22:45 ding kernel: [  718.128381] wlan0: authenticate with
1c:17:d3:cb:9a:e0
Jul 10 19:22:45 ding kernel: [  718.149338] wlan0: direct probe to
1c:17:d3:cb:9a:e0 (try 1/3)
Jul 10 19:22:45 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: scanning -> authenticating
Jul 10 19:22:45 ding kernel: [  718.351121] wlan0: direct probe to
1c:17:d3:cb:9a:e0 (try 2/3)
Jul 10 19:22:46 ding kernel: [  718.554902] wlan0: direct probe to
1c:17:d3:cb:9a:e0 (try 3/3)
Jul 10 19:22:46 ding kernel: [  718.758641] wlan0: authentication with
1c:17:d3:cb:9a:e0 timed out
Jul 10 19:22:46 ding kernel: [  718.759662] iwlwifi 0000:03:00.0:
ACTIVATE a non DRIVER active station id 0 addr 1c:17:d3:cb:9a:e0
Jul 10 19:22:46 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: authenticating -> disconnected
Jul 10 19:22:46 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: disconnected -> scanning
Jul 10 19:22:46 ding wpa_supplicant[1771]: wlan0: SME: Trying to
authenticate with b4:a4:e3:1e:d7:10 (SSID='nyu' freq=2462 MHz)
Jul 10 19:22:46 ding kernel: [  718.980892] wlan0: authenticate with
b4:a4:e3:1e:d7:10
Jul 10 19:22:46 ding kernel: [  719.001778] wlan0: direct probe to
b4:a4:e3:1e:d7:10 (try 1/3)
Jul 10 19:22:46 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: scanning -> authenticating
Jul 10 19:22:46 ding kernel: [  719.202029] wlan0: direct probe to
b4:a4:e3:1e:d7:10 (try 2/3)
Jul 10 19:22:47 ding kernel: [  719.405804] wlan0: direct probe to
b4:a4:e3:1e:d7:10 (try 3/3)
Jul 10 19:22:47 ding kernel: [  719.609534] wlan0: authentication with
b4:a4:e3:1e:d7:10 timed out
Jul 10 19:22:47 ding kernel: [  719.610028] iwlwifi 0000:03:00.0:
ACTIVATE a non DRIVER active station id 0 addr b4:a4:e3:1e:d7:10
Jul 10 19:22:47 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: authenticating -> disconnected
Jul 10 19:22:47 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: disconnected -> scanning
Jul 10 19:22:47 ding wpa_supplicant[1771]: wlan0: SME: Trying to
authenticate with 9c:4e:20:c9:03:01 (SSID='nyu' freq=2462 MHz)
Jul 10 19:22:47 ding kernel: [  719.864494] wlan0: authenticate with
9c:4e:20:c9:03:01
Jul 10 19:22:47 ding kernel: [  719.866531] wlan0: send auth to
9c:4e:20:c9:03:01 (try 1/3)
Jul 10 19:22:47 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: scanning -> authenticating
Jul 10 19:22:47 ding kernel: [  720.068941] wlan0: send auth to
9c:4e:20:c9:03:01 (try 2/3)
Jul 10 19:22:47 ding kernel: [  720.072776] wlan0: authenticated
Jul 10 19:22:47 ding wpa_supplicant[1771]: wlan0: Trying to associate
with 9c:4e:20:c9:03:01 (SSID='nyu' freq=2462 MHz)
Jul 10 19:22:47 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: authenticating -> associating
Jul 10 19:22:47 ding kernel: [  720.076881] wlan0: associate with
9c:4e:20:c9:03:01 (try 1/3)
Jul 10 19:22:47 ding kernel: [  720.280670] wlan0: associate with
9c:4e:20:c9:03:01 (try 2/3)
Jul 10 19:22:47 ding kernel: [  720.286258] wlan0: RX AssocResp from
9c:4e:20:c9:03:01 (capab=0x431 status=0 aid=3)
Jul 10 19:22:47 ding kernel: [  720.286268] wlan0: associated
Jul 10 19:22:47 ding wpa_supplicant[1771]: wlan0: Associated with
9c:4e:20:c9:03:01
Jul 10 19:22:47 ding NetworkManager[1600]: <info> (wlan0): supplicant
interface state: associating -> associated
Jul 10 19:22:50 ding kernel: [  722.680071] wlan0: deauthenticated from
9c:4e:20:c9:03:01 (Reason: 2)
Jul 10 19:22:50 ding wpa_supplicant[1771]: wlan0:
CTRL-EVENT-DISCONNECTED bssid=9c:4e:20:c9:03:01 reason=2
-------------------------------------------------------------------------------

Thanks,
Andreas

-- System Information:
Debian Release: wheezy/sid
  APT prefers testing
  APT policy: (990, 'testing'), (500, 'unstable'), (500, 'stable'), (1, 'experimental')
Architecture: amd64 (x86_64)
Foreign Architectures: i386

Kernel: Linux 3.4-trunk-amd64 (SMP w/4 CPU cores)
Locale: LANG=de_DE.utf8, LC_CTYPE=de_DE.utf8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages wpasupplicant depends on:
ii  adduser           3.113+nmu3
ii  initscripts       2.88dsf-22.1
ii  libc6             2.13-33
ii  libdbus-1-3       1.6.0-1
ii  libnl-3-200       3.2.7-4
ii  libnl-genl-3-200  3.2.7-4
ii  libpcsclite1      1.8.4-1
ii  libreadline6      6.2-8
ii  libssl1.0.0       1.0.1c-3
ii  lsb-base          4.1+Debian7

wpasupplicant recommends no packages.

Versions of packages wpasupplicant suggests:
pn  libengine-pkcs11-openssl  <none>
pn  wpagui                    <none>

-- no debconf information





More information about the Pkg-wpa-devel mailing list