"scan stuck" with if_iwi(4)

Thu Jul 20 23:01:24 UTC 2006

Henrik Brix Andersen wrote:
> Hi,
> 
> I recently upgraded my IBM ThinkPad X31 from 6.1-RELEASE to 6.1-STABLE
> after which the if_iwi(4) driver started dropping the connection at
> regular intervals when connected to my hostapd-based AP using WPA2 PSK
> CCMP.
> 
> The dmesg output with debug.iwi=4 is listed below - it fails regularly
> (every 3 mins or so) with the message "scan stuck" after which the
> adapter is reinitialized and the connection lost for some seconds.
> 
> The connection drop seems to be caused by the adapter suddently
> entering the SCAN state.
> 
> I have been unable to reproduce this problem with 6.1-RELEASE.

You will not be able to reproduce the problem because the code in 6.1R
ignored beacon misses (and a lot of other things).

The stuck scan is not fatal; the driver just resets the card.  It's
caused by a firwmare problem that I never got around to dealing with
('cuz it wouldn't change the overall operation of the driver).

The basic problem is your card is losing sync w/ the ap.  I don't know
what the local conditions are but I've seen this a lot w/ iwi; there's
nothing we can do in the driver if you want to be able to roam.

	Sam

> 
> $ uname -a
> FreeBSD fangorn.brixandersen.dk 6.1-STABLE FreeBSD 6.1-STABLE #2: Tue Jul 18 11:16:02 CEST 2006     root at fangorn.brixandersen.dk:/usr/obj/usr/src/sys/GENERIC  i386
> 
> 
> Jul 20 23:38:04 fangorn kernel: iwi0: <Intel(R) PRO/Wireless 2915ABG> mem 0xc0200000-0xc0200fff irq 11 at device 2.0 on pci2
> Jul 20 23:38:04 fangorn kernel: iwi0: Ethernet address: 00:0e:35:fd:81:94
> ...
> Jul 21 00:19:26 fangorn kernel: Beacon miss: 7 >= 7
> Jul 21 00:20:24 fangorn kernel: Beacon miss: 7 >= 7
> Jul 21 00:20:24 fangorn kernel: iwi_newstate: RUN -> SCAN flags 0x11
> Jul 21 00:20:24 fangorn kernel: iwi0: link state changed to DOWN
> Jul 21 00:20:24 fangorn kernel: iwi_newstate: INIT -> SCAN flags 0x11
> Jul 21 00:20:24 fangorn kernel: iwi_newstate: SCAN -> SCAN flags 0x13
> Jul 21 00:20:24 fangorn kernel: Start scanning
> Jul 21 00:20:24 fangorn kernel: sending command idx=5 type=26 len=96
> Jul 21 00:20:24 fangorn kernel: Beacon miss: 8 >= 7
> Jul 21 00:20:24 fangorn kernel: Beacon miss: 9 >= 7
> Jul 21 00:20:30 fangorn kernel: iwi0: scan stuck
> Jul 21 00:20:30 fangorn kernel: iwi_newstate: SCAN -> INIT flags 0x0
> Jul 21 00:20:31 fangorn kernel: Setting MAC address to 00:0e:35:fd:81:94
> Jul 21 00:20:31 fangorn kernel: sending command idx=0 type=11 len=6
> Jul 21 00:20:31 fangorn kernel: Configuring adapter
> Jul 21 00:20:31 fangorn kernel: sending command idx=1 type=6 len=20
> Jul 21 00:20:31 fangorn kernel: Setting power mode to 0
> Jul 21 00:20:31 fangorn kernel: sending command idx=2 type=17 len=4
> Jul 21 00:20:31 fangorn kernel: Setting RTS threshold to 2346
> Jul 21 00:20:31 fangorn kernel: sending command idx=3 type=15 len=4
> Jul 21 00:20:31 fangorn kernel: Setting fragmentation threshold to 2346
> Jul 21 00:20:31 fangorn kernel: sending command idx=4 type=16 len=4
> Jul 21 00:20:31 fangorn kernel: Setting .11bg supported rates (12)
> Jul 21 00:20:31 fangorn kernel: sending command idx=5 type=22 len=16
> Jul 21 00:20:31 fangorn kernel: Setting .11a supported rates (8)
> Jul 21 00:20:31 fangorn kernel: sending command idx=6 type=22 len=16
> Jul 21 00:20:31 fangorn kernel: Setting initialization vector to 1069254139
> Jul 21 00:20:31 fangorn kernel: sending command idx=7 type=34 len=4
> Jul 21 00:20:31 fangorn kernel: Setting wep key index 0 len 0
> Jul 21 00:20:31 fangorn kernel: sending command idx=8 type=18 len=20
> Jul 21 00:20:31 fangorn kernel: Setting wep key index 1 len 0
> Jul 21 00:20:31 fangorn kernel: sending command idx=9 type=18 len=20
> Jul 21 00:20:31 fangorn kernel: Setting wep key index 2 len 0
> Jul 21 00:20:31 fangorn kernel: sending command idx=10 type=18 len=20
> Jul 21 00:20:31 fangorn kernel: Setting wep key index 3 len 0
> Jul 21 00:20:31 fangorn kernel: sending command idx=11 type=18 len=20
> Jul 21 00:20:31 fangorn kernel: Enabling adapter
> Jul 21 00:20:31 fangorn kernel: sending command idx=12 type=2 len=0
> Jul 21 00:20:31 fangorn kernel: iwi_newstate: INIT -> SCAN flags 0x5
> Jul 21 00:20:31 fangorn kernel: iwi_newstate: SCAN -> SCAN flags 0x3
> Jul 21 00:20:31 fangorn kernel: Start scanning
> Jul 21 00:20:31 fangorn kernel: sending command idx=13 type=26 len=96
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5180 complete (36)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5200 complete (40)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5220 complete (44)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5240 complete (48)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5260 complete (52)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5280 complete (56)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5300 complete (60)
> Jul 21 00:20:31 fangorn kernel: Scan of channel 5320 complete (64)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 5745 complete (149)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 5765 complete (153)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 5785 complete (157)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 5805 complete (161)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 5825 complete (165)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 2412 complete (1)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 2417 complete (2)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 2422 complete (3)
> Jul 21 00:20:32 fangorn kernel: Scan of channel 2427 complete (4)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2432 complete (5)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2437 complete (6)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2442 complete (7)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2447 complete (8)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2452 complete (9)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2457 complete (10)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2462 complete (11)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2467 complete (12)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2472 complete (13)
> Jul 21 00:20:33 fangorn kernel: Scan of channel 2484 complete (14)
> Jul 21 00:20:33 fangorn kernel: Scan completed (27, 1)
> Jul 21 00:20:38 fangorn kernel: iwi_newstate: INIT -> SCAN flags 0x1
> Jul 21 00:20:38 fangorn kernel: iwi_newstate: SCAN -> SCAN flags 0x3
> Jul 21 00:20:38 fangorn kernel: Start scanning
> Jul 21 00:20:38 fangorn kernel: sending command idx=14 type=26 len=96
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5180 complete (36)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5200 complete (40)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5220 complete (44)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5240 complete (48)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5260 complete (52)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5280 complete (56)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5300 complete (60)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5320 complete (64)
> Jul 21 00:20:39 fangorn kernel: Scan of channel 5745 complete (149)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 5765 complete (153)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 5785 complete (157)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 5805 complete (161)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 5825 complete (165)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 2412 complete (1)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 2417 complete (2)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 2422 complete (3)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 2427 complete (4)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 2432 complete (5)
> Jul 21 00:20:40 fangorn kernel: Scan of channel 2437 complete (6)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2442 complete (7)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2447 complete (8)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2452 complete (9)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2457 complete (10)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2462 complete (11)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2467 complete (12)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2472 complete (13)
> Jul 21 00:20:41 fangorn kernel: Scan of channel 2484 complete (14)
> Jul 21 00:20:41 fangorn kernel: Scan completed (27, 1)
> Jul 21 00:20:41 fangorn kernel: iwi_newstate: SCAN -> AUTH flags 0x1
> Jul 21 00:20:41 fangorn kernel: Configuring adapter
> Jul 21 00:20:41 fangorn kernel: sending command idx=15 type=6 len=20
> Jul 21 00:20:41 fangorn kernel: Setting ESSID to "brix"
> Jul 21 00:20:41 fangorn kernel: sending command idx=0 type=8 len=4
> Jul 21 00:20:41 fangorn kernel: Setting negotiated rates (4)
> Jul 21 00:20:41 fangorn kernel: sending command idx=1 type=22 len=16
> Jul 21 00:20:41 fangorn kernel: Setting optional IE (len=24)
> Jul 21 00:20:41 fangorn kernel: sending command idx=2 type=31 len=24
> Jul 21 00:20:41 fangorn kernel: Setting sensitivity to 39
> Jul 21 00:20:41 fangorn kernel: sending command idx=3 type=42 len=4
> Jul 21 00:20:41 fangorn kernel: Join bssid 00:02:6f:37:fc:68 dst 00:02:6f:37:fc:68 channel 1 policy 0x2 auth 0 capinfo 0x31 li
> ntval 100 bintval 100
> Jul 21 00:20:41 fangorn kernel: sending command idx=4 type=21 len=40
> Jul 21 00:20:41 fangorn kernel: Authentication succeeeded
> Jul 21 00:20:41 fangorn kernel: iwi_newstate: AUTH -> ASSOC flags 0x1
> Jul 21 00:20:41 fangorn kernel: Association succeeded
> Jul 21 00:20:41 fangorn kernel: iwi_newstate: ASSOC -> RUN flags 0x11
> Jul 21 00:20:41 fangorn kernel: iwi0: link state changed to UP
> Jul 21 00:20:49 fangorn dhclient: New IP Address (iwi0): 10.0.0.56
> Jul 21 00:20:49 fangorn dhclient: New Subnet Mask (iwi0): 255.255.255.0
> Jul 21 00:20:49 fangorn dhclient: New Broadcast Address (iwi0): 10.0.0.255
> Jul 21 00:20:49 fangorn dhclient: New Routers (iwi0): 10.0.0.1
> 
> Regards,
> Brix