Is wpasupplicant 0.7.3-6 compatible with 5Ghz band steering?

Sat Mar 31 08:10:57 PDT 2012

Howdy,

My company recently turned on band steering to redirect 5Ghz capable clients
from 2.4Ghz to 5Ghz.

This is causing no end of trouble for my 5300AGN linux client.
I'm running 3.2.8 kernel with iwlwifi-5000-5.ucode and wpasupplicant 0.7.3-6 

The essid is the same for 2.4Ghz and 5Ghz, and I haven't found a way to
force my client to ignore the 2.4Ghz radios.
When I connect to the essid, it consistently tries to connect to 2.4Ghz,
gets and auth denied from the AP, and then eventually goes to 5Ghz.

The problem is that every minute or so, either the driver or wpa_supplicant 
does a rescan of nearby APs, and my client says 'oh, there are 2.4Ghz APs',
so it gives up its perfect 70/70 5Ghz signal and tries to go to 2.4Ghz.
It gets denied, times out, and eventually goes back to 5Ghz up to 2mn later,
cutting my current connection in the meantime.

When we turned off 5Ghz steering on the AP, my client stayed on 2.4Ghz and
everything worked.
When we made a essid-A with only 5Ghz APs, I connected to it right away and
stayed on it just fine.

So, why is my client ignoring client steering and trying to get back to
2.4Ghz when it's already connected to 5Ghz?

Below are the relevant syslog and wpi_cli logs.

It seems that it may be wpa_supplicant that is telling the driver to drop
its perfectly good connection and try to roam to a 2.4Ghz AP it wasn't even
able to get a connection with.
Note that those are open networks with no encryption.

First, you see my client connecting to a 5Ghz AP, and below you'll see it disconnect on its own:

> 20:00:35 wpa_supplicant[12458]: Trying to authenticate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> 20:00:35 wpa_supplicant[12458]: Trying to associate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> 20:00:35 kernel: [ 8740.269596] wlan0: authenticate with 00:24:6c:63:56:31 (try 1)
> 20:00:35 kernel: [ 8740.270660] wlan0: authenticated
> 20:00:35 kernel: [ 8740.272472] wlan0: associate with 00:24:6c:63:56:31 (try 1)
> 20:00:35 kernel: [ 8740.275981] wlan0: RX AssocResp from 00:24:6c:63:56:31 (capab=0x501 status=0 aid=1)
> 20:00:35 kernel: [ 8740.275984] wlan0: associated
> 20:00:35 kernel: [ 8740.279472] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
> 20:00:35 kernel: [ 8740.279528] cfg80211: Calling CRDA for country: US
> 20:00:35 wpa_supplicant[12458]: Associated with 00:24:6c:63:56:31
> 20:00:35 wpa_supplicant[12458]: CTRL-EVENT-CONNECTED - Connection to 00:24:6c:63:56:31 completed (auth) [id=0 id_str=]
> 20:00:35 kernel: [ 8740.284109] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
> 20:00:35 kernel: [ 8740.284113] cfg80211: 2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
> 20:00:35 kernel: [ 8740.284115] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
(...)
> 20:00:35 kernel: [ 8740.284201] cfg80211: 5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:00:35 kernel: [ 8740.284203] cfg80211: Updating information on frequency 5320 MHz for a 20 MHz width channel with regulatory rule:
> 20:00:35 kernel: [ 8740.284206] cfg80211: 5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:00:35 NetworkManager[12447]: <info> (wlan0): supplicant interface state: scanning -> authenticating
> 20:00:35 kernel: [ 8740.284208] cfg80211: Updating information on frequency 5500 MHz for a 20 MHz width channel with regulatory rule:
(...)
> 20:00:35 kernel: [ 8740.284275] cfg80211: 5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
> 20:00:35 kernel: [ 8740.284279] cfg80211: Regulatory domain changed to country: US
> 20:00:35 kernel: [ 8740.284281] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
> 20:00:35 kernel: [ 8740.284284] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
> 20:00:35 kernel: [ 8740.284286] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
> 20:00:35 kernel: [ 8740.284289] cfg80211:     (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:00:35 kernel: [ 8740.284291] cfg80211:     (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:00:35 kernel: [ 8740.284294] cfg80211:     (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:00:35 kernel: [ 8740.284296] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
> 20:00:35 NetworkManager[12447]: <info> (wlan0): supplicant interface state: authenticating -> associating
> 20:00:35 NetworkManager[12447]: <info> (wlan0): supplicant interface state: associating -> completed
> 20:00:35 NetworkManager[12447]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'GoogleGuest'.
> 20:00:35 NetworkManager[12447]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
> 20:00:35 NetworkManager[12447]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
> 20:00:35 NetworkManager[12447]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
> 20:00:35 NetworkManager[12447]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
> 20:00:35 NetworkManager[12447]: <info> dhclient started with pid 17364
> 20:00:35 NetworkManager[12447]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
> 20:00:35 dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
> 20:00:35 dhclient: Copyright 2004-2010 Internet Systems Consortium.
> 20:00:35 dhclient: All rights reserved.
> 20:00:35 dhclient: For info, please visit https://www.isc.org/software/dhcp/
> 20:00:35 dhclient: 
> 20:00:36 dhclient: Listening on LPF/wlan0/00:21:6a:56:16:02
> 20:00:36 dhclient: Sending on   LPF/wlan0/00:21:6a:56:16:02
> 20:00:36 dhclient: Sending on   Socket/fallback
> 20:00:36 dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
> 20:00:36 dhclient: DHCPACK from 192.168.188.253
> 20:00:36 kernel: [ 8740.377880] cfg80211: Found new beacon on frequency: 5200 MHz (Ch 40) on phy0
> 20:00:36 dhclient: bound to 192.168.188.50 -- renewal in 863 seconds.
> 20:00:36 NetworkManager[12447]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
> 20:00:36 NetworkManager[12447]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
> 20:00:36 NetworkManager[12447]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
> 20:00:36 NetworkManager[12447]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
> 20:00:36 NetworkManager[12447]: <info>   address 192.168.188.50
> 20:00:36 NetworkManager[12447]: <info>   prefix 24 (255.255.255.0)
> 20:00:36 NetworkManager[12447]: <info>   gateway 192.168.188.254
> 20:00:36 NetworkManager[12447]: <info>   nameserver '8.8.8.8'
> 20:00:36 NetworkManager[12447]: <info>   nameserver '8.8.4.4'
> 20:00:36 NetworkManager[12447]: <info>   domain search 'google.com.'
> 20:00:36 NetworkManager[12447]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
> 20:00:36 NetworkManager[12447]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
> 20:00:36 NetworkManager[12447]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
> 20:00:36 avahi-daemon[3378]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.188.50.
> 20:00:36 avahi-daemon[3378]: New relevant interface wlan0.IPv4 for mDNS.
> 20:00:36 avahi-daemon[3378]: Registering new address record for 192.168.188.50 on wlan0.IPv4.
> 20:00:37 NetworkManager[12447]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
> 20:00:37 NetworkManager[12447]: <info> (wlan0): roamed from BSSID 00:24:6C:67:05:80 (GoogleGuest) to 00:24:6C:63:56:31 (GoogleGuest)
> 20:00:37 NetworkManager[12447]: <info> Policy set 'Auto GoogleGuest' (wlan0) as default for IPv4 routing and DNS.
> 20:00:37 NetworkManager[12447]: <info> Activation (wlan0) successful, device activated.
> 20:00:37 dbus[2792]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
> 20:00:37 NetworkManager[12447]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
> 20:00:37 dbus[2792]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
> 20:00:37 avahi-daemon[3378]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::221:6aff:fe56:1602.
> 20:00:37 avahi-daemon[3378]: New relevant interface wlan0.IPv6 for mDNS.
> 20:00:37 avahi-daemon[3378]: Registering new address record for fe80::221:6aff:fe56:1602 on wlan0.*.
> 20:00:39 avahi-daemon[3378]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::221:6aff:fe56:1602.
> 20:00:39 avahi-daemon[3378]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2620:0:1000:fd2b:221:6aff:fe56:1602.
> 20:00:39 avahi-daemon[3378]: Registering new address record for 2620:0:1000:fd2b:221:6aff:fe56:1602 on wlan0.*.
> 20:00:39 avahi-daemon[3378]: Withdrawing address record for fe80::221:6aff:fe56:1602 on wlan0.

Ok, now this is the rescan that happens on its own, and causes my client to try and switch back to 2.4Ghz:
> 20:00:56 kernel: [ 8761.195198] cfg80211: Found new beacon on frequency: 5180 MHz (Ch 36) on phy0
> 20:00:57 kernel: [ 8761.616126] cfg80211: Found new beacon on frequency: 5220 MHz (Ch 44) on phy0
> 20:00:57 kernel: [ 8761.811932] cfg80211: Found new beacon on frequency: 5240 MHz (Ch 48) on phy0
> 20:01:00 kernel: [ 8764.486423] cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0
> 20:01:00 kernel: [ 8764.736835] cfg80211: Found new beacon on frequency: 5765 MHz (Ch 153) on phy0
> 20:01:00 kernel: [ 8764.897829] cfg80211: Found new beacon on frequency: 5785 MHz (Ch 157) on phy0
> 20:01:00 kernel: [ 8765.107876] cfg80211: Found new beacon on frequency: 5805 MHz (Ch 161) on phy0
> 20:01:01 wpa_supplicant[12458]: Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
> 20:01:01 kernel: [ 8765.376215] wlan0: direct probe to 00:24:6c:63:56:20 (try 1/3)
> 20:01:01 NetworkManager[12447]: <info> (wlan0): supplicant interface state: completed -> authenticating
> 20:01:01 kernel: [ 8765.576037] wlan0: direct probe to 00:24:6c:63:56:20 (try 2/3)
> 20:01:01 kernel: [ 8765.776072] wlan0: direct probe to 00:24:6c:63:56:20 (try 3/3)
> 20:01:01 kernel: [ 8765.976059] wlan0: direct probe to 00:24:6c:63:56:20 timed out
> 20:01:11 kernel: [ 8776.116832] wlan0: deauthenticating from 00:24:6c:63:56:31 by local choice (reason=2)

Note, this is bad:
1) it didn't get allowed on 00:24:6c:63:56:20/2.4Ghz due to band steering, not that it had any reason to look anyway
2) after failing to switch, it disconnects from its good AP: 00:24:6c:63:56:31 

After that, I'm disconnected and I'll have to wait for another round:

> 20:01:11 kernel: [ 8776.133580] cfg80211: All devices are disconnected, going to restore regulatory settings
> 20:01:11 kernel: [ 8776.133592] cfg80211: Restoring regulatory settings
> 20:01:11 kernel: [ 8776.133643] cfg80211: Calling CRDA to update world regulatory domain
> 20:01:11 kernel: [ 8776.152396] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
> 20:01:11 kernel: [ 8776.152400] cfg80211: World regulatory domain updated:
> 20:01:11 kernel: [ 8776.152401] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
> 20:01:11 kernel: [ 8776.152404] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.152407] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.152409] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.152412] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.152414] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 wpa_supplicant[12458]: Trying to authenticate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> 20:01:11 kernel: [ 8776.160135] wlan0: authenticate with 00:24:6c:63:56:31 (try 1)
> 20:01:11 kernel: [ 8776.161078] wlan0: authenticated
> 20:01:11 wpa_supplicant[12458]: CTRL-EVENT-DISCONNECTED bssid=00:24:6c:63:56:31 reason=2
> 20:01:11 wpa_supplicant[12458]: Trying to associate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> 20:01:11 kernel: [ 8776.211358] wlan0: associate with 00:24:6c:63:56:31 (try 1)
> 20:01:11 kernel: [ 8776.212807] wlan0: RX ReassocResp from 00:24:6c:63:56:31 (capab=0x501 status=0 aid=1)
> 20:01:11 kernel: [ 8776.212810] wlan0: associated
> 20:01:11 kernel: [ 8776.217899] cfg80211: Calling CRDA for country: US
> 20:01:11 kernel: [ 8776.222332] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
> 20:01:11 kernel: [ 8776.222335] cfg80211: 2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
> 20:01:11 kernel: [ 8776.222338] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
> 20:01:11 kernel: [ 8776.222341] cfg80211: 2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
> 20:01:11 kernel: [ 8776.222343] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
(...)
> 20:01:11 kernel: [ 8776.222501] cfHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
> 20:01:11 kernel: [ 8776.222599] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
> 20:01:11 kernel: [ 8776.222602] cfg80211:     (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.222604] cfg80211:     (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.222607] cfg80211:     (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:11 kernel: [ 8776.222609] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
> 20:01:11 wpa_supplicant[12458]: Associated with 00:24:6c:63:56:31
> 20:01:11 wpa_supplicant[12458]: CTRL-EVENT-CONNECTED - Connection to 00:24:6c:63:56:31 completed (reauth) [id=0 id_str=]
> 20:01:11 NetworkManager[12447]: <info> (wlan0): supplicant interface state: authenticating -> associating
> 20:01:12 NetworkManager[12447]: <info> (wlan0): supplicant interface state: associating -> completed
> 20:01:34 kernel: [ 8799.053492] cfg80211: Found new beacon on frequency: 5200 MHz (Ch 40) on phy0
> 20:01:36 kernel: [ 8801.233860] cfg80211: Found new beacon on frequency: 5180 MHz (Ch 36) on phy0
> 20:01:37 kernel: [ 8801.654826] cfg80211: Found new beacon on frequency: 5220 MHz (Ch 44) on phy0
> 20:01:37 kernel: [ 8801.850625] cfg80211: Found new beacon on frequency: 5240 MHz (Ch 48) on phy0
> 20:01:40 kernel: [ 8804.525219] cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0
> 20:01:40 kernel: [ 8804.775611] cfg80211: Found new beacon on frequency: 5765 MHz (Ch 153) on phy0
> 20:01:40 kernel: [ 8804.936514] cfg80211: Found new beacon on frequency: 5785 MHz (Ch 157) on phy0
> 20:01:40 kernel: [ 8805.146599] cfg80211: Found new beacon on frequency: 5805 MHz (Ch 161) on phy0
> 20:01:41 wpa_supplicant[12458]: Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
> 20:01:41 kernel: [ 8805.404420] wlan0: direct probe to 00:24:6c:63:56:20 (try 1/3)
> 20:01:41 NetworkManager[12447]: <info> (wlan0): supplicant interface state: completed -> authenticating
> 20:01:41 kernel: [ 8805.604067] wlan0: direct probe to 00:24:6c:63:56:20 (try 2/3)
> 20:01:41 kernel: [ 8805.804088] wlan0: direct probe to 00:24:6c:63:56:20 (try 3/3)
> 20:01:41 kernel: [ 8806.004079] wlan0: direct probe to 00:24:6c:63:56:20 timed out
> 20:01:51 kernel: [ 8816.151777] wlan0: deauthenticating from 00:24:6c:63:56:31 by local choice (reason=2)
> 20:01:51 wpa_supplicant[12458]: Trying to authenticate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> 20:01:51 kernel: [ 8816.171949] cfg80211: All devices are disconnected, going to restore regulatory settings
> 20:01:51 kernel: [ 8816.171956] cfg80211: Restoring regulatory settings
> 20:01:51 kernel: [ 8816.171970] cfg80211: Calling CRDA to update world regulatory domain
> 20:01:51 wpa_supplicant[12458]: CTRL-EVENT-DISCONNECTED bssid=00:24:6c:63:56:31 reason=2
> 20:01:51 kernel: [ 8816.179437] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
> 20:01:51 kernel: [ 8816.179441] cfg80211: World regulatory domain updated:
> 20:01:51 kernel: [ 8816.179443] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
> 20:01:51 kernel: [ 8816.179446] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.179448] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.179451] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.179453] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.179456] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.180894] wlan0: authenticate with 00:24:6c:63:56:31 (try 1)
> 20:01:51 kernel: [ 8816.184097] wlan0: authenticated
> 20:01:51 wpa_supplicant[12458]: Trying to associate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> 20:01:51 kerne KHz @ 40000 KHz), (300 mBi, 2700 mBm)
> 20:01:51 kernel: [ 8816.216310] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
> 20:01:51 kernel: [ 8816.216312] cfg80211: 2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
(...)
> 20:01:51 kernel: [ 8816.216466] cfg80211:     (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.216468] cfg80211:     (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> 20:01:51 kernel: [ 8816.216471] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
> 20:01:51 NetworkManager[12447]: <info> (wlan0): supplicant interface state: authenticating -> associating
> 20:01:51 wpa_supplicant[12458]: Associated with 00:24:6c:63:56:31
> 20:01:51 wpa_supplicant[12458]: CTRL-EVENT-CONNECTED - Connection to 00:24:6c:63:56:31 completed (reauth) [id=0 id_str=]
> 20:01:51 kernel: [ 8816.256803] cfg80211: Found new beacon on frequency: 5200 MHz (Ch 40) on phy0
> 20:01:51 NetworkManager[12447]: <info> (wlan0): supplicant interface state: associating -> completed

And there you go, connected back to the same AP I was on to start with, and the cycle repeats...

This is what wpa_cli says during the 5Ghz -> 2Ghz -> 5Ghz transition:
<2>CTRL-EVENT-BSS-ADDED 0 00:24:6c:63:56:22
> <2>CTRL-EVENT-BSS-ADDED 1 00:24:6c:63:56:21
> <2>CTRL-EVENT-BSS-ADDED 2 00:24:6c:63:56:32
> <2>CTRL-EVENT-BSS-ADDED 3 00:24:6c:63:56:30
> <2>CTRL-EVENT-BSS-ADDED 4 00:24:6c:67:f4:22
> <2>CTRL-EVENT-BSS-ADDED 5 00:24:6c:67:09:82
> <2>CTRL-EVENT-BSS-ADDED 6 00:24:6c:67:06:a1
(...)
> <2>CTRL-EVENT-BSS-ADDED 128 00:24:6c:67:06:00
> <2>CTRL-EVENT-BSS-ADDED 129 00:24:6c:66:ff:a0
> <2>CTRL-EVENT-BSS-ADDED 130 00:1a:1e:5e:14:c0
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>CTRL-EVENT-BSS-ADDED 131 00:24:6c:67:05:81
> <2>CTRL-EVENT-BSS-ADDED 132 00:24:6c:66:fc:e1
> <2>CTRL-EVENT-BSS-ADDED 133 00:24:6c:66:ff:10
> <2>CTRL-EVENT-BSS-ADDED 134 00:07:7d:d2:d6:10
> <2>CTRL-EVENT-BSS-ADDED 135 00:24:6c:63:56:40
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
<2>CTRL-EVENT-BSS-ADDED 136 00:24:6c:63:56:41
> <2>CTRL-EVENT-BSS-ADDED 137 00:1a:1e:5e:11:82
> <2>CTRL-EVENT-BSS-ADDED 138 00:24:6c:66:fc:e0
> <2>CTRL-EVENT-BSS-ADDED 139 00:1a:1e:5e:11:80
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
<2>CTRL-EVENT-BSS-ADDED 140 00:24:6c:66:fe:82
> <2>CTRL-EVENT-BSS-ADDED 141 00:24:6c:66:fa:90
> <2>CTRL-EVENT-BSS-ADDED 142 00:24:6c:66:fa:92
> <2>CTRL-EVENT-BSS-ADDED 143 00:24:6c:67:09:20
> <2>CTRL-EVENT-BSS-ADDED 144 00:24:6c:66:fa:91
> <2>CTRL-EVENT-BSS-REMOVED 70 00:24:6c:67:06:12
> <2>CTRL-EVENT-BSS-REMOVED 113 00:24:6c:66:fd:60
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
<2>CTRL-EVENT-BSS-ADDED 145 00:24:6c:67:05:82
> <2>CTRL-EVENT-BSS-ADDED 146 00:24:6c:67:05:80
> <2>CTRL-EVENT-BSS-REMOVED 5 00:24:6c:67:09:82
> <2>CTRL-EVENT-BSS-REMOVED 4 00:24:6c:67:f4:22
> <2>CTRL-EVENT-BSS-REMOVED 9 00:24:6c:67:09:92
> <2>CTRL-EVENT-BSS-REMOVED 33 00:24:6c:67:00:22
(...)
> <2>CTRL-EVENT-BSS-REMOVED 120 00:24:6c:67:00:40
> <2>CTRL-EVENT-BSS-REMOVED 135 00:24:6c:63:56:40
> <2>CTRL-EVENT-BSS-REMOVED 139 00:1a:1e:5e:11:80
> <2>CTRL-EVENT-BSS-REMOVED 130 00:1a:1e:5e:14:c0
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
<2>CTRL-EVENT-BSS-ADDED 147 00:24:6c:67:f4:22
> <2>CTRL-EVENT-BSS-ADDED 148 00:24:6c:67:09:92
> <2>CTRL-EVENT-BSS-ADDED 149 00:24:6c:67:00:22
(...)
> <2>CTRL-EVENT-BSS-ADDED 163 00:24:6c:67:05:91
> <2>CTRL-EVENT-BSS-ADDED 164 00:24:6c:67:00:e0
> <2>CTRL-EVENT-BSS-ADDED 165 02:2a:7a:f6:cb:f5
> <2>CTRL-EVENT-BSS-REMOVED 140 00:24:6c:66:fe:82
> <2>CTRL-EVENT-BSS-REMOVED 82 00:1a:1e:5e:10:42
> <2>CTRL-EVENT-BSS-REMOVED 41 00:24:6c:66:ff:02
> <2>CTRL-EVENT-BSS-REMOVED 67 00:24:6c:66:ff:c1
> <2>CTRL-EVENT-BSS-REMOVED 133 00:24:6c:66:ff:10
> <2>CTRL-EVENT-BSS-REMOVED 69 00:24:6c:66:ff:a2
> <2>CTRL-EVENT-BSS-REMOVED 76 00:24:6c:66:ff:d2
> <2>CTRL-EVENT-BSS-REMOVED 83 00:1a:1e:5e:13:82
> <2>CTRL-EVENT-BSS-REMOVED 134 00:07:7d:d2:d6:10
> <2>CTRL-EVENT-BSS-REMOVED 109 00:24:6c:67:09:51
> <2>CTRL-EVENT-BSS-REMOVED 123 00:24:6c:66:ff:d1
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> <2>Trying to associate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> <2>Associated with 00:24:6c:63:56:31
> <2>CTRL-EVENT-CONNECTED - Connection to 00:24:6c:63:56:31 completed (auth) [id=0 id_str=]
<2>CTRL-EVENT-BSS-ADDED 166 00:24:6c:66:fc:e2
> <2>CTRL-EVENT-BSS-ADDED 167 00:24:6c:67:09:50
> <2>CTRL-EVENT-BSS-ADDED 168 00:24:6c:67:09:52
(...)
> <2>CTRL-EVENT-BSS-ADDED 187 00:24:6c:66:ff:d1
> <2>CTRL-EVENT-BSS-ADDED 188 00:1a:1e:5e:11:80
> <2>CTRL-EVENT-BSS-REMOVED 13 00:24:6c:63:56:02
> <2>CTRL-EVENT-BSS-REMOVED 18 00:24:6c:63:56:01
> <2>CTRL-EVENT-BSS-REMOVED 48 00:24:6c:66:ff:01
> <2>CTRL-EVENT-BSS-REMOVED 75 00:24:6c:66:ff:d0
> <2>CTRL-EVENT-BSS-REMOVED 95 00:24:6c:63:56:00
> <2>CTRL-EVENT-BSS-REMOVED 111 00:24:6c:66:fd:00
> <2>CTRL-EVENT-BSS-REMOVED 108 00:24:6c:66:ff:00
> <2>CTRL-EVENT-BSS-REMOVED 138 00:24:6c:66:fc:e0
> <2>CTRL-EVENT-BSS-REMOVED 126 00:1a:1e:5e:12:c0
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:20 (SSID='GoogleGuest' freq=2437 MHz)
<2>CTRL-EVENT-BSS-ADDED 189 00:24:6c:63:56:01
> <2>CTRL-EVENT-BSS-ADDED 190 00:24:6c:66:fd:61
> <2>CTRL-EVENT-BSS-ADDED 191 00:24:6c:66:fe:82
> <2>CTRL-EVENT-BSS-ADDED 192 00:24:6c:66:fc:f0
> <2>CTRL-EVENT-BSS-ADDED 193 00:1a:1e:5e:11:c1
> <2>CTRL-EVENT-BSS-ADDED 194 00:24:6c:66:ff:00
> <2>CTRL-EVENT-BSS-ADDED 195 00:24:6c:66:fc:e0
> <2>CTRL-EVENT-BSS-ADDED 196 00:24:6c:66:fd:00
> <2>CTRL-EVENT-BSS-ADDED 197 00:24:6c:67:00:40
> <2>CTRL-EVENT-BSS-ADDED 198 00:24:6c:66:fc:f1
> <2>CTRL-EVENT-BSS-ADDED 199 00:24:6c:66:fe:40
> <2>CTRL-EVENT-BSS-REMOVED 2 00:24:6c:63:56:32
> <2>CTRL-EVENT-BSS-REMOVED 150 00:24:6c:63:56:41
> <2>CTRL-EVENT-BSS-REMOVED 153 00:24:6c:66:ff:22
> <2>CTRL-EVENT-BSS-REMOVED 54 00:24:6c:66:ff:21
> <2>CTRL-EVENT-BSS-REMOVED 161 00:1a:1e:5e:14:c2
> <2>CTRL-EVENT-BSS-REMOVED 144 00:24:6c:66:fa:91
> <2>CTRL-EVENT-SCAN-RESULTS
> <2>Trying to authenticate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
> <2>CTRL-EVENT-DISCONNECTED bssid=00:24:6c:63:56:31 reason=2
> <2>Trying to associate with 00:24:6c:63:56:31 (SSID='GoogleGuest' freq=5200 MHz)
<2>Associated with 00:24:6c:63:56:31
> <2>CTRL-EVENT-CONNECTED - Connection to 00:24:6c:63:56:31 completed (reauth) [id=0 id_str=]

-- 
"A mouse is a device used to point at the xterm you want to type in" - A.S.R.
Microsoft is to operating systems ....
                                      .... what McDonalds is to gourmet cooking
Home page: http://marc.merlins.org/  