Hi again Quaker

On Feb 11, 2008 9:45 AM, Quaker Fang <Quaker.Fang at sun.com> wrote:
> Hi Johan,
>
>
> Johan wrote:
> > Hi Quaker
> >
> > Do you, or anybody else for that matter, have any news on this?  It
still does not appear to work for me in Build 80.
> >
> wpi driver of b80 or b79b should support WPA/PSK already, could you
> please tell the steps you connect to the AP and the configuration of
> your AP?
>
> --
> Quaker
>


Results of re-testing.

Accesspoint config:
Mode: *802.11b+g*
ESSID: *hartz-3*
ESSID Hide: *Disabled*
Channel: *1*
Regulatory Domain: *N. America*

Security Mode: *WPA2 Pre-shared Key*
WPA2 Algoritm: *TKIP*
SharedKey: *secret*
Group Key Renewal: *3600 sec*

There may be other settings somewhere, let me know if there is something
interesting to report.


bash-3.2# dladm scan-wifi
LINK       ESSID               BSSID/IBSSID      SEC    STRENGTH   MODE
SPEED
wpi0       hartz-3             0:13:d3:73:9e:9a  wpa    excellent  g
 54Mb

bash-3.2# dladm connect-wifi -s wpa -k newkey -e hartz-3
dladm: cannot connect link 'wpi0': operation timed out
------------------
bash-3.2# echo "wpa_debug_level, 1/W 1" | mdb -p `pgrep wpad`
wpad`wpa_debug_level:           0x4             =       0x1
------------------
Messages Seen while connect-wifi is running:
Feb 12 21:30:26 solwarg mac: [ID 744254 kern.info] NOTICE: wpi0 link up
Feb 12 21:30:26 solwarg mac: [ID 486395 kern.info] NOTICE: wpi0 link down
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice] Signal 15
received - terminating
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice] <= eloop_run()
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_disassociate
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=1
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=2
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=3
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_wpa: enable=0
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_wpa_ie
Feb 12 21:30:35 solwarg wpad[1174]: [ID 702911 daemon.notice]
wpa_supplicant_door_destroy(/var/run/wpa_door_wpi0)
Feb 12 21:30:36 solwarg mac: [ID 744254 kern.info] NOTICE: wpi0 link up
Feb 12 21:30:37 solwarg mac: [ID 486395 kern.info] NOTICE: wpi0 link down

And just to be clear about that, the above text appears each time, including
a wpi0 link up / link down immediately after running the command and again
when the command finally gives up.

I thought it was boring - it looked the same each time.  So I decided I
could do better, using a script like this:

bash-3.2# cat /tmp/doboth
dladm connect-wifi -s wpa -k newkey -e hartz-3 &
while ! echo "wpa_debug_level, 1/W 1" | mdb -p `pgrep wpad`
do echo ".\n"
done

. /tmp/doboth

And this time I saw much more in the log file, like this:
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_supplicant_door_setup(/var/run/wpa_door_wpi0)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_wpa: enable=1
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=1
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=2
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Setting scan
request: 0 sec 100000 usec
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] => eloop_run
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Starting AP
scan (specific SSID)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Scan SSID:
hartz-3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_scan
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_disassociate
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_scan: return
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_get_scan_results: interface name =wpi0 max size=64
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Scan results:
1
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Selecting BSS
from scan results (1)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] 0:
00:13:d3:73:9e:9a ssid='hartz-3' wpa_ie_len=22
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]    selected
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Trying to
associate with 00:13:d3:73:9e:9a (SSID='hartz-3' freq=2412 MHz)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Cancelling
scan request
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] RSN: using
IEEE 802.11i/D9.0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: Own WPA
IE - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 02 01 00 00 0f
ac 02 00 00
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=1
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=2
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_associate : 00:13:ffffffd3:73:ffffff9e:ffffff9a
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_wpa_ie
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Setting
authentication timeout: 15 sec 0 usec
Feb 12 21:46:37 solwarg mac: [ID 744254 kern.info] NOTICE: wpi0 link up
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
Feb 12 21:46:37 solwarg Association event - clear replay counter
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_get_bssid: 00:13:d3:73:9e:9a
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Associated to
a new BSS: BSSID=00:13:d3:73:9e:9a
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=1
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=2
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Setting
authentication timeout: 10 sec 0 usec
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] RX EAPOL from
00:13:d3:73:9e:9a
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Setting
authentication timeout: 10 sec 0 usec
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: EAPOL
frame len 99
Feb 12 21:46:37 solwarg
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] hdr_len=4,
key_len=95
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] IEEE
802.1XRX: version=1 type=3 length=95
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]   EAPOL-Key
type=2
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: RX
message 1 of 4-Way Handshake from 00:13:d3:73:9e:9a (ver=1)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_get_ssid: ssid=hartz-3 len=7
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_get_bssid: 00:13:d3:73:9e:9a
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] entry len=7
ssid=hartz-3, driver len=7 ssid=hartz-3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] RSN: msg 1/4
key data - hexdump(len=0):
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: WPA IE
for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 02 01
00 00 0f ac 02 00 00
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: Renewed
SNonce - hexdump(len=32): 42 8f 5f bf 54 1c 03 d6 2d 6d 1e e5 6c b6 ae 2d 4c
65 a8 f2 13 ad ad 5b 7b 11 74 88 77 c8 d0 9d
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: PMK -
hexdump(len=32): dc 08 15 1f 07 13 2c 36 d6 14 67 d0 40 f8 57 8d 3e 02 60 15
73 10 fe e0 e8 16 8c 84 44 4c fc a9
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: PTK -
hexdump(len=64): bc 72 8f 60 63 5a eb 20 23 83 61 24 a5 15 79 49 ff 2f 4e 98
ed 78 77 7b a5 db d4 ca b2 2d 46 2b 0c 8f a7 83 60 97 0c 35 72 47 1b 67 c7
2d 30 b1 4f 6b 28 3b 68 32 19 e0 3f 1c 4a ee 55 fa 50 4d
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: EAPOL-Key
MIC - hexdump(len=16): 2c 23 bb 4d 5b ee 08 d8 01 d2 48 a5 e1 b2 8b bc
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: Sending
EAPOL-Key 2/4
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] RX EAPOL from
00:13:d3:73:9e:9a
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: EAPOL
frame len 167
Feb 12 21:46:37 solwarg
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] hdr_len=4,
key_len=95
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] IEEE
802.1XRX: version=1 type=3 length=163
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]   EAPOL-Key
type=2
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] RSN: encrypted
key data - hexdump(len=68): e5 49 77 bc ae 42 ba 70 a7 27 3c ae 3f 43 03 95
7b 50 96 4b ca 66 e0 37 0d 21 1b 90 53 a9 d2 d0 c5 5e 64 b0 f8 c8 9b 8a 00
59 51 2b d4 1f 12 79 c4 bd 50 78 7e 88 6c 1f 48 c6 87 95 fe 0c 41 49 99 8d
74 81
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: decrypted
EAPOL-Key key data - hexdump(len=68): de 76 df 83 7d 17 58 36 80 c0 2d fa fb
10 c9 d0 a9 8e d9 0b 42 01 82 1e d5 93 1e 57 72 7b eb 92 a4 b5 da 2e bf 06
cb ba 41 4c bb 73 b6 3e a8 56 e2 9b 27 f5 c5 42 79 de b0 bf 56 e0 f9 f3 e3
3e 8b 47 e5 20
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: RX
message 3 of 4-Way Handshake from 00:13:d3:73:9e:9a (ver=1)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] RSN: key data
underflow (ie=222 len=118)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: WPA IE in
3/4 msg does not match with WPA IE in Beacon/ProbeResp
(src=00:13:d3:73:9e:9a)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: WPA IE in
Beacon/ProbeResp - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac
02 01 00 00 0f ac 02 00 00
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] WPA: WPA IE in
3/4 msg - hexdump(len=0):
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_disassociate
Feb 12 21:46:37 solwarg mac: [ID 486395 kern.info] NOTICE: wpi0 link down
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=1
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=2
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Setting scan
request: 0 sec 0 usec
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Starting AP
scan (specific SSID)
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice] Scan SSID:
hartz-3
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_scan
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_disassociate
Feb 12 21:46:37 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_scan: return
Feb 12 21:46:38 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_get_scan_results: interface name =wpi0 max size=64
Feb 12 21:46:38 solwarg wpad[1291]: [ID 702911 daemon.notice] Scan results:
1
Feb 12 21:46:38 solwarg wpad[1291]: [ID 702911 daemon.notice] Selecting BSS
from scan results (1)
Feb 12 21:46:38 solwarg wpad[1291]: [ID 702911 daemon.notice] 0:
00:13:d3:73:9e:9a ssid='hartz-3' wpa_ie_len=22
Feb 12 21:46:38 solwarg wpad[1291]: [ID 702911 daemon.notice]    selected
Feb 12 21:46:38 solwarg wpad[1291]: [ID 702911 daemon.notice] Already
associated with the selected AP.
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice] Signal 15
received - terminating
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice] <= eloop_run()
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_disassociate
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=1
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=2
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=3
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_key
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_del_key: id=0
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_wpa: enable=0
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_driver_wifi_set_wpa_ie
Feb 12 21:46:46 solwarg wpad[1291]: [ID 702911 daemon.notice]
wpa_supplicant_door_destroy(/var/run/wpa_door_wpi0)
Feb 12 21:46:54 solwarg mac: [ID 744254 kern.info] NOTICE: wpi0 link up
Feb 12 21:46:55 solwarg mac: [ID 486395 kern.info] NOTICE: wpi0 link down


Quite a bit more interesting, but does not give me any clues as to the cause
of my problem.

Thank you,
  _Johan
-------------- next part --------------
An HTML attachment was scrubbed...
URL: 
<http://mail.opensolaris.org/pipermail/laptop-discuss/attachments/20080212/693bfa37/attachment.html>

Reply via email to