Subject: Re: bin/32536: wpa_supplicant(8) unreliable, dropping connections with iwi(4)
To: None <gnats-admin@netbsd.org, netbsd-bugs@netbsd.org, salo@Xtrmntr.org>
From: Lubomir Sedlacik <salo@Xtrmntr.org>
List: netbsd-bugs
Date: 01/16/2006 00:45:02
The following reply was made to PR bin/32536; it has been noted by GNATS.

From: Lubomir Sedlacik <salo@Xtrmntr.org>
To: gnats-bugs@netbsd.org
Cc: 
Subject: Re: bin/32536: wpa_supplicant(8) unreliable, dropping connections with iwi(4)
Date: Mon, 16 Jan 2006 01:44:30 +0100

 the same situation happens when using up-to-date kernel:
 
 NetBSD 3.99.15 Mon Jan 16 00:02:07 CET 2006
 
 output from iwi(4) IWI_DEBUG:
 
 wpa_supplicant started:
 
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting MAC address to 00:0e:35:f1:2b:9a
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=0 type=11 len=6
 Jan 16 01:10:22 aurora-surrealis /netbsd: Configuring adapter
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=1 type=6 len=20
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting power mode to 0
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=2 type=17 len=4
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting RTS threshold to 2346
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=3 type=15 len=4
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting fragmentation threshold to 2346
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=4 type=16 len=4
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting .11bg supported rates (12)
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=5 type=22 len=16
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting .11a supported rates (0)
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=6 type=22 len=16
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting desired ESSID to "klozet"
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=7 type=8 len=6
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting initialization vector to 786142910
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=8 type=34 len=4
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting wep key index 0 len 0
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=9 type=18 len=20
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting wep key index 1 len 0
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=10 type=18 len=20
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting wep key index 2 len 0
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=11 type=18 len=20
 Jan 16 01:10:22 aurora-surrealis /netbsd: Setting wep key index 3 len 0
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=12 type=18 len=20
 Jan 16 01:10:22 aurora-surrealis /netbsd: Enabling adapter
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=13 type=2 len=0
 Jan 16 01:10:22 aurora-surrealis /netbsd: iwi_scan: Setting adapter desired ESSID to klozet
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=14 type=8 len=6
 Jan 16 01:10:22 aurora-surrealis /netbsd: Start scanning
 Jan 16 01:10:22 aurora-surrealis /netbsd: sending command idx=15 type=26 len=96
 Jan 16 01:10:22 aurora-surrealis /netbsd: Finished scanning channel (1)
 Jan 16 01:10:22 aurora-surrealis /netbsd: Finished scanning channel (2)
 Jan 16 01:10:22 aurora-surrealis /netbsd: Finished scanning channel (3)
 Jan 16 01:10:22 aurora-surrealis /netbsd: Finished scanning channel (4)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (5)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (6)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (7)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (8)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (9)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (10)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (11)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (12)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (13)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Finished scanning channel (14)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Scan completed (14, 1)
 Jan 16 01:10:23 aurora-surrealis /netbsd: Configuring adapter
 Jan 16 01:10:23 aurora-surrealis /netbsd: sending command idx=0 type=6 len=20
 Jan 16 01:10:23 aurora-surrealis /netbsd: Setting ESSID to "klozet"
 Jan 16 01:10:23 aurora-surrealis /netbsd: sending command idx=1 type=8 len=6
 Jan 16 01:10:23 aurora-surrealis /netbsd: Setting negotiated rates (12)
 Jan 16 01:10:23 aurora-surrealis /netbsd: sending command idx=2 type=22 len=16
 Jan 16 01:10:23 aurora-surrealis /netbsd: Setting optional IE (len=24)
 Jan 16 01:10:23 aurora-surrealis /netbsd: sending command idx=3 type=31 len=24
 Jan 16 01:10:23 aurora-surrealis /netbsd: Setting sensitivity to 59
 Jan 16 01:10:23 aurora-surrealis /netbsd: sending command idx=4 type=42 len=4
 Jan 16 01:10:23 aurora-surrealis /netbsd: Trying to associate to 00:12:17:dd:27:6e channel 11 auth 0
 Jan 16 01:10:23 aurora-surrealis /netbsd: sending command idx=5 type=21 len=40
 Jan 16 01:10:24 aurora-surrealis /netbsd: Authentication (9)
 Jan 16 01:10:24 aurora-surrealis /netbsd: Association (12, 110)
 Jan 16 01:10:24 aurora-surrealis /netbsd: iwi0: link state changed to UP
 
 burst of ssh packets and wpa_supplicant stuck:
 
 Jan 16 01:13:00 aurora-surrealis /netbsd: Authentication (0)
 Jan 16 01:13:00 aurora-surrealis /netbsd: Association (0, 11249)
 Jan 16 01:13:00 aurora-surrealis /netbsd: iwi_scan: Setting adapter desired ESSID to klozet
 Jan 16 01:13:00 aurora-surrealis /netbsd: sending command idx=6 type=8 len=6
 Jan 16 01:13:00 aurora-surrealis /netbsd: Start scanning
 Jan 16 01:13:00 aurora-surrealis /netbsd: sending command idx=7 type=26 len=96
 Jan 16 01:13:00 aurora-surrealis /netbsd: Finished scanning channel (1)
 Jan 16 01:13:00 aurora-surrealis /netbsd: Finished scanning channel (2)
 Jan 16 01:13:00 aurora-surrealis /netbsd: Finished scanning channel (3)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (4)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (5)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (6)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (7)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (8)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (9)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (10)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (11)
 Jan 16 01:13:01 aurora-surrealis /netbsd: Finished scanning channel (12)
 Jan 16 01:13:02 aurora-surrealis /netbsd: Finished scanning channel (13)
 Jan 16 01:13:02 aurora-surrealis /netbsd: Finished scanning channel (14)
 Jan 16 01:13:02 aurora-surrealis /netbsd: Scan completed (14, 1)
 
 also one interesting datapoint, when this happens another WPA client on
 the network, a Windows XP machine, gets disconnected from the network as
 well.  i can't tell whether this happens because the firmware of the AP
 is buggy and what i see on the NetBSD client is just a result of it or
 whether it is caused by the NetBSD client.  unfortunatelly, i can't test
 against a different AP now.   there are newer firmwares available for
 the router (WRT54G v2.2), the current one running is v3.03.1 and v4.00.7
 mentions: "Resolves issue with enabling TKIP after enabling WEP" which
 is rather vague and can apply to my situation.  the AP is not
 technically mine but i'll try to upgrade it tomorrow and report back.
 
 regards,
 
 -- 
 -- Lubomir Sedlacik <salo@{NetBSD,Xtrmntr,silcnet}.org>   --