Date: Tue, 7 Jan 2014 22:30:11 -0800 From: Adrian Chadd <adrian@freebsd.org> To: =?ISO-8859-1?Q?Peter_Ankerst=E5l?= <peter@pean.org> Cc: "stable@freebsd.org" <stable@freebsd.org>, "freebsd-wireless@freebsd.org" <freebsd-wireless@freebsd.org> Subject: Re: wpa_supplicant problem. Message-ID: <CAJ-VmokeEPBq1Tz=abJ6qmWzzn9vWH0Pno4sJ1GLHUh5iq8Qcw@mail.gmail.com> In-Reply-To: <8CFBC6B5-B683-4EC3-9561-EFFA6CE55755@pean.org> References: <79AEC286-90EB-41D0-8CA8-18A78D53C3C6@pean.org> <CAJ-Vmonr%2Bx%2BaQ1r=Sedyz3eq8sdoidxJvKtY5Gp_8WXsbzTCeA@mail.gmail.com> <8CFBC6B5-B683-4EC3-9561-EFFA6CE55755@pean.org>
next in thread | previous in thread | raw e-mail | index | archive | help
Hm, sounds to me like something I fixed in -HEAD. Try -HEAD.
The TL;DR - the EAPOL frames are going out at a much higher transmit
rate than the initial association attempts, which means it associates
fine but it can't authenticate successfully.
Try running -HEAD. I bet it'll work.
-a
On 7 January 2014 22:03, Peter Ankerst=E5l <peter@pean.org> wrote:
> Hi!
>
> Now I get on the router:
> wlan0: STA f0:de:f1:c9:14:4d WPA: EAPOL-Key timeout
> WPA: f0:de:f1:c9:14:4d WPA_PTK entering state PTKSTART
> wlan0: STA f0:de:f1:c9:14:4d WPA: sending 1/4 msg of 4-Way Handshake
>
>
> and on the client:
> wlan0: Selecting BSS from priority group 0
> wlan0: 0: 04:f0:21:01:3d:87 ssid=3D'web' wpa_ie_len=3D0 rsn_ie_len=3D20 c=
aps=3D0x31 level=3D-65
> wlan0: selected based on RSN IE
> wlan0: selected BSS 04:f0:21:01:3d:87 ssid=3D'web'
> wlan0: Request association: reassociate: 0 selected: 04:f0:21:01:3d:87 =
bssid: 00:00:00:00:00:00 pending: 00:00:00:00:00:00 wpa_state: SCANNING
> wlan0: Trying to associate with 04:f0:21:01:3d:87 (SSID=3D'web' freq=3D24=
12 MHz)
> FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=3D0)=
:
> wlan0: Cancelling scan request
> wlan0: WPA: clearing own WPA/RSN IE
> wlan0: Automatic auth_alg selection: 0x1
> RSN: PMKSA cache search - network_ctx=3D0x0 try_opportunistic=3D0
> RSN: Search for BSSID 04:f0:21:01:3d:87
> RSN: No PMKSA cache entry found
> wlan0: RSN: using IEEE 802.11i/D9.0
> wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto=
2
> wlan0: WPA: clearing AP WPA IE
> WPA: set AP RSN IE - hexdump(len=3D22): 30 14 01 00 00 0f ac 04 01 00 00 =
0f ac 04 01 00 00 0f ac 02 00 00
> wlan0: WPA: using GTK CCMP
> wlan0: WPA: using PTK CCMP
> wlan0: WPA: using KEY_MGMT WPA-PSK
> WPA: Set own WPA IE default - hexdump(len=3D22): 30 14 01 00 00 0f ac 04 =
01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
> wlan0: No keys have been configured - skip key clearing
> wlan0: State: SCANNING -> ASSOCIATING
> Limit connection to BSSID 04:f0:21:01:3d:87 freq=3D2412 MHz based on scan=
results (bssid_set=3D0)
> wpa_driver_bsd_associate: ssid 'web' wpa ie len 22 pairwise 3 group 3 key=
mgmt 1
> wpa_driver_bsd_set_drop_unencrypted: enabled=3D1
> bsd_set_opt_ie: set WPA+RSN ie (len 22)
> wpa_driver_bsd_associate: set PRIVACY 1
> wlan0: Setting authentication timeout: 10 sec 0 usec
> EAPOL: External notification - EAP success=3D0
> EAPOL: Supplicant port status: Unauthorized
> EAPOL: External notification - EAP fail=3D0
> EAPOL: Supplicant port status: Unauthorized
> EAPOL: External notification - portControl=3DAuto
> EAPOL: Supplicant port status: Unauthorized
> RSN: Ignored PMKID candidate without preauth flag
> wlan0: Event ASSOC (0) received
> wlan0: State: ASSOCIATING -> ASSOCIATED
>
> On 08 Jan 2014, at 00:00, Adrian Chadd <adrian@freebsd.org> wrote:
>
>> hi,
>>
>> please retry with the only network block entries being:
>>
>> ssid=3D
>> psk=3D
>>
>> .. and let wpa_supplicant figure it out.
>>
>>
>> -a
>>
>>
>> On 7 January 2014 13:07, Peter Ankerst=E5l <peter@pean.org> wrote:
>>> Hi!
>>>
>>> Im trying to connect from a Lenovo X220 to my FreeBSD router running ho=
stapd. The router is supporting
>>> a lot of other clients (OS X and iOS) without any problems. And my lap=
top can connect to my phone when
>>> I set it up as a hotspot.
>>>
>>> Lenovo X220:
>>> FreeBSD lenny 10.0-RC2 FreeBSD 10.0-RC2 #0 r259404: Sun Dec 15 08:18:20=
UTC 2013 root@snap.freebsd.org:/usr/obj/usr/src/sys/GENERIC amd64
>>>
>>> iwn0@pci0:3:0:0: class=3D0x028000 card=3D0x13118086 chip=3D0x008=
58086 rev=3D0x34 hdr=3D0x00
>>> vendor =3D 'Intel Corporation'
>>> device =3D 'Centrino Advanced-N 6205 [Taylor Peak]'
>>> class =3D network
>>>
>>> # cat /etc/wpa_supplicant.conf
>>> ctrl_interface=3D/var/run/wpa_supplicant
>>> eapol_version=3D2
>>> ap_scan=3D1
>>> fast_reauth=3D1
>>>
>>> network=3D{
>>> priority=3D100
>>> proto=3DRSN WPA
>>> ssid=3D"web"
>>> key_mgmt=3DWPA-PSK
>>> pairwise=3DCCMP TKIP
>>> group=3DCCMP TKIP
>>> psk=3D=93secret=94
>>> }
>>>
>>> Router:
>>> FreeBSD gw 10.0-RC1 FreeBSD 10.0-RC1 #3 r259258: Thu Dec 12 16:21:37 CE=
T 2013 root@gw:/usr/obj/usr/src/sys/SOEKRIS i386
>>>
>>> ath0@pci0:7:0:0: class=3D0x028000 card=3D0x3114168c chip=3D0x003=
0168c rev=3D0x01 hdr=3D0x00
>>> vendor =3D 'Atheros Communications Inc.'
>>> device =3D 'AR9300 Wireless LAN adaptor'
>>> class =3D network
>>>
>>> # cat /etc/hostapd.conf
>>> interface=3Dwlan0
>>> driver=3Dbsd
>>> debug=3D0
>>> ctrl_interface=3D/var/run/hostapd
>>> ctrl_interface_group=3Dwheel
>>> ssid=3Dweb
>>> wpa=3D2
>>> wpa_passphrase=3Dsecret
>>> wpa_key_mgmt=3DWPA-PSK
>>> wpa_pairwise=3DCCMP TKIP
>>> rsn_pairwise=3DCCMP
>>>
>>>
>>> debug output from wpa_supplicant.
>>> # wpa_supplicant -D bsd -i wlan0 -d -c /etc/wpa_supplicant.conf
>>> wpa_supplicant v2.0
>>> Successfully initialized wpa_supplicant
>>> Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver '=
bsd' ctrl_interface 'N/A' bridge 'N/A'
>>> Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.c=
onf'
>>> Reading configuration file '/etc/wpa_supplicant.conf'
>>> ctrl_interface=3D'/var/run/wpa_supplicant'
>>> eapol_version=3D2
>>> ap_scan=3D1
>>> fast_reauth=3D1
>>> Priority group 100
>>> id=3D0 ssid=3D'web'
>>> wpa_driver_bsd_capa: drivercaps=3D0x2581e201,cryptocaps=3D0x00000000
>>> wlan0: Own MAC address: f0:de:f1:c9:14:4d
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D0 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D0
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D1 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D1
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D2 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D2
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D3 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D3
>>> wpa_driver_bsd_set_countermeasures: enabled=3D0
>>> wlan0: RSN: flushing PMKID list in the driver
>>> wlan0: Setting scan request: 0 sec 100000 usec
>>> WPS: Set UUID for interface wlan0
>>> WPS: UUID based on MAC address - hexdump(len=3D16): f4 2a e7 3f ed 75 5=
2 b7 99 15 e0 56 b1 07 04 4f
>>> EAPOL: SUPP_PAE entering state DISCONNECTED
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: KEY_RX entering state NO_KEY_RECEIVE
>>> EAPOL: SUPP_BE entering state INITIALIZE
>>> EAP: EAP entering state DISABLED
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: Supplicant port status: Unauthorized
>>> wlan0: Added interface wlan0
>>> wlan0: State: DISCONNECTED -> DISCONNECTED
>>> wlan0: State: DISCONNECTED -> SCANNING
>>> wlan0: Starting AP scan for wildcard SSID
>>> wpa_driver_bsd_set_wpa: enabled=3D1
>>> wpa_driver_bsd_set_wpa_internal: wpa=3D3 privacy=3D1
>>> EAPOL: disable timer tick
>>> EAPOL: Supplicant port status: Unauthorized
>>> wlan0: Event SCAN_RESULTS (3) received
>>> Received 2508 bytes of scan results (9 BSSes)
>>> wlan0: BSS: Start scan result update 1
>>> wlan0: BSS: Add new id 0 BSSID 04:f0:21:01:3d:87 SSID 'web'
>>> wlan0: BSS: Add new id 1 BSSID 8a:08:93:36:a1:10 SSID 'Mavar'
>>> wlan0: BSS: Add new id 2 BSSID 88:1f:a1:36:93:08 SSID 'Gotland'
>>> wlan0: BSS: Add new id 3 BSSID 8a:09:93:36:a1:10 SSID 'Mavar'
>>> wlan0: BSS: Add new id 4 BSSID 88:1f:a1:36:93:09 SSID 'Gotland++'
>>> wlan0: BSS: Add new id 5 BSSID 58:98:35:ba:2c:17 SSID 'Katyusha'
>>> wlan0: BSS: Add new id 6 BSSID 58:98:35:be:ef:9f SSID 'TeliaGateway58-9=
8-35-BE-EF-9F'
>>> wlan0: BSS: Add new id 7 BSSID cc:b2:55:94:23:e8 SSID 'P-sons'
>>> wlan0: BSS: Add new id 8 BSSID a0:21:b7:b4:55:d8 SSID 'Bonnier'
>>> BSS: last_scan_res_used=3D9/32 last_scan_full=3D0
>>> wlan0: New scan results available
>>> WPS: AP cc:b2:55:94:23:e8 type 0 added
>>> WPS: AP a0:21:b7:b4:55:d8 type 0 added
>>> WPS: AP[0] cc:b2:55:94:23:e8 type=3D0 tries=3D0 last_attempt=3D-1 sec a=
go blacklist=3D0
>>> WPS: AP[1] a0:21:b7:b4:55:d8 type=3D0 tries=3D0 last_attempt=3D-1 sec a=
go blacklist=3D0
>>> wlan0: Selecting BSS from priority group 100
>>> wlan0: 0: 04:f0:21:01:3d:87 ssid=3D'web' wpa_ie_len=3D0 rsn_ie_len=3D20=
caps=3D0x11 level=3D-67
>>> wlan0: selected based on RSN IE
>>> wlan0: selected BSS 04:f0:21:01:3d:87 ssid=3D'web'
>>> wlan0: Request association: reassociate: 0 selected: 04:f0:21:01:3d:87=
bssid: 00:00:00:00:00:00 pending: 00:00:00:00:00:00 wpa_
>>> state: SCANNING
>>> wlan0: Trying to associate with 04:f0:21:01:3d:87 (SSID=3D'web' freq=3D=
5280 MHz)
>>> FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=3D=
0):
>>> wlan0: Cancelling scan request
>>> wlan0: WPA: clearing own WPA/RSN IE
>>> wlan0: Automatic auth_alg selection: 0x1
>>> RSN: PMKSA cache search - network_ctx=3D0x0 try_opportunistic=3D0
>>> RSN: Search for BSSID 04:f0:21:01:3d:87
>>> RSN: No PMKSA cache entry found
>>> wlan0: RSN: using IEEE 802.11i/D9.0
>>> wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 pro=
to 2
>>> wlan0: WPA: clearing AP WPA IE
>>> WPA: set AP RSN IE - hexdump(len=3D22): 30 14 01 00 00 0f ac 04 01 00 0=
0 0f ac 04 01 00 00 0f ac 02 0c 00
>>> wlan0: WPA: using GTK CCMP
>>> wlan0: WPA: using PTK CCMP
>>> wlan0: WPA: using KEY_MGMT WPA-PSK
>>> WPA: Set own WPA IE default - hexdump(len=3D22): 30 14 01 00 00 0f ac 0=
4 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
>>> wlan0: No keys have been configured - skip key clearing
>>> wlan0: State: SCANNING -> ASSOCIATING
>>> Limit connection to BSSID 04:f0:21:01:3d:87 freq=3D5280 MHz based on sc=
an results (bssid_set=3D0)
>>> wpa_driver_bsd_associate: ssid 'web' wpa ie len 22 pairwise 3 group 3 k=
ey mgmt 1
>>> wpa_driver_bsd_set_drop_unencrypted: enabled=3D1
>>> bsd_set_opt_ie: set WPA+RSN ie (len 22)
>>> wpa_driver_bsd_associate: set PRIVACY 1
>>> wlan0: Setting authentication timeout: 10 sec 0 usec
>>> EAPOL: External notification - EAP success=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - EAP fail=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - portControl=3DAuto
>>> EAPOL: Supplicant port status: Unauthorized
>>> RSN: Ignored PMKID candidate without preauth flag
>>> wlan0: Event ASSOC (0) received
>>> wlan0: State: ASSOCIATING -> ASSOCIATED
>>> wlan0: Associated to a new BSS: BSSID=3D04:f0:21:01:3d:87
>>> wlan0: No keys have been configured - skip key clearing
>>> wlan0: Associated with 04:f0:21:01:3d:87
>>> wlan0: WPA: Association event - clear replay counter
>>> wlan0: WPA: Clear old PTK
>>> EAPOL: External notification - portEnabled=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - portValid=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - EAP success=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - portEnabled=3D1
>>> EAPOL: SUPP_PAE entering state CONNECTING
>>> EAPOL: enable timer tick
>>> EAPOL: SUPP_BE entering state IDLE
>>> wlan0: Setting authentication timeout: 10 sec 0 usec
>>> wlan0: Cancelling scan request
>>> EAPOL: startWhen --> 0
>>> EAPOL: disable timer tick
>>> EAPOL: SUPP_PAE entering state CONNECTING
>>> EAPOL: enable timer tick
>>> EAPOL: txStart
>>> WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=3D1 len=3D0)
>>> wlan0: Event DISASSOC (1) received
>>> wlan0: Disassociation notification
>>> wlan0: CTRL-EVENT-DISCONNECTED bssid=3D04:f0:21:01:3d:87 reason=3D0
>>> wlan0: Auto connect enabled: try to reconnect (wps=3D0 wpa_state=3D6)
>>> wlan0: Setting scan request: 0 sec 100000 usec
>>> Added BSSID 04:f0:21:01:3d:87 into blacklist
>>> wlan0: Blacklist count 1 --> request scan in 100 ms
>>> wlan0: Setting scan request: 0 sec 100000 usec
>>> wlan0: Disconnect event - remove keys
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D0 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D0
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D1 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D1
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D2 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D2
>>> bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D3 set_tx=3D0 seq_len=3D0 key_=
len=3D0
>>> bsd_del_key: key_idx=3D3
>>> bsd_set_key: alg=3D0 addr=3D0x801c2f4c8 key_idx=3D0 set_tx=3D0 seq_len=
=3D0 key_len=3D0
>>> bsd_del_key: addr=3D04:f0:21:01:3d:87
>>> wlan0: State: ASSOCIATED -> DISCONNECTED
>>> EAPOL: External notification - portEnabled=3D0
>>> EAPOL: SUPP_PAE entering state DISCONNECTED
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: SUPP_BE entering state INITIALIZE
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - portValid=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> EAPOL: External notification - EAP success=3D0
>>> EAPOL: Supplicant port status: Unauthorized
>>> wlan0: State: DISCONNECTED -> SCANNING
>>>
>>>
>>> _______________________________________________
>>> freebsd-stable@freebsd.org mailing list
>>> http://lists.freebsd.org/mailman/listinfo/freebsd-stable
>>> To unsubscribe, send any mail to "freebsd-stable-unsubscribe@freebsd.or=
g"
>>
>
>
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?CAJ-VmokeEPBq1Tz=abJ6qmWzzn9vWH0Pno4sJ1GLHUh5iq8Qcw>