Client Association Failure Null

Joshua Engels · ‎02-22-2013

Hey guys,

I am running WLC 5508 and WCS version 7.0.98. We are noticing with some of our handheld devices that have Sychip Wireless cards that they constantly have issues communicating. The error I see on the WCS side is shown below: Is this a common issue and any advice would be great. Does this reason code mean anything?

Client '00:0b:6c:2f:d0:32 (0.0.0.0)' failed to associate with interface '802.11b/g' of AP 'HO-BRSales'. The reason code is '0(null)'.

Thanks,

Josh

Saravanan Lakshmanan · ‎02-24-2013

get debug client while reproducing the issue

Joshua Engels · ‎02-25-2013

Thanks guys, Here is the debug output:

(Cisco Controller)

User: admin

Password:********

(Cisco Controller) >show wlan 4

WLAN Identifier.................................. 4

Profile Name..................................... dolphin

Network Name (SSID).............................. dolphin

Status........................................... Enabled

MAC Filtering.................................... Disabled

Broadcast SSID................................... Enabled

AAA Policy Override.............................. Disabled

Network Admission Control

NAC-State...................................... Disabled

Quarantine VLAN................................ 0

Number of Active Clients......................... 3

Exclusionlist.................................... Disabled

Session Timeout.................................. 1800 seconds

CHD per WLAN..................................... Enabled

Webauth DHCP exclusion........................... Disabled

Interface........................................ dolphin

WLAN ACL......................................... unconfigured

DHCP Server...................................... Default

DHCP Address Assignment Required................. Enabled

--More-- or (q)uit

Quality of Service............................... Silver (best effort)

Scan Defer Priority.............................. 4,5,6

Scan Defer Time.................................. 100 milliseconds

WMM.............................................. Allowed

Media Stream Multicast-direct.................... Disabled

CCX - AironetIe Support.......................... Enabled

CCX - Gratuitous ProbeResponse (GPR)............. Disabled

CCX - Diagnostics Channel Capability............. Disabled

Dot11-Phone Mode (7920).......................... Disabled

Wired Protocol................................... None

IPv6 Support..................................... Disabled

Passive Client Feature........................... Disabled

Peer-to-Peer Blocking Action..................... Disabled

Radio Policy..................................... All

DTIM period for 802.11a radio.................... 1

DTIM period for 802.11b radio.................... 1

Radius Servers

Authentication................................ Global Servers

Accounting.................................... Global Servers

Dynamic Interface............................. Disabled

Local EAP Authentication......................... Disabled

Security

--More-- or (q)uit

802.11 Authentication:........................ Open System

Static WEP Keys............................... Disabled

802.1X........................................ Disabled

Wi-Fi Protected Access (WPA/WPA2)............. Enabled

WPA (SSN IE)............................... Enabled

TKIP Cipher............................. Enabled

AES Cipher.............................. Enabled

WPA2 (RSN IE).............................. Enabled

TKIP Cipher............................. Enabled

AES Cipher.............................. Enabled

Auth Key Management

802.1x.................................. Disabled

PSK..................................... Enabled

CCKM.................................... Disabled

FT(802.11r)............................. Disabled

FT-PSK(802.11r)......................... Disabled

FT Reassociation Timeout......................... 20

FT Over-The-Air mode............................. Enabled

FT Over-The-Ds mode.............................. Enabled

CKIP ......................................... Disabled

Web Based Authentication...................... Disabled

Web-Passthrough............................... Disabled

Conditional Web Redirect...................... Disabled

--More-- or (q)uit

Splash-Page Web Redirect...................... Disabled

Auto Anchor................................... Disabled

H-REAP Local Switching........................ Enabled

H-REAP Learn IP Address....................... Enabled

Client MFP.................................... Disabled

Tkip MIC Countermeasure Hold-down Timer....... 60

Call Snooping.................................... Disabled

Roamed Call Re-Anchor Policy..................... Disabled

Band Select...................................... Disabled

Load Balancing................................... Disabled

Mobility Anchor List

WLAN ID IP Address Status

------- --------------- ------

(Cisco Controller) >debug client 00:0b:6c:35:b7:5d

(Cisco Controller) >debug ap enable HO-IS_CubicalArea

(Cisco Controller) >debug ap command "debug dot11 mgmt msg" HO-IS_CubicalArea

(Cisco Controller) >debug ap command "debug dot11 mgmt state-machine" HO-IS_CubicalArea

(Cisco Controller) >debug ap command "debug dot11 station connection failure" HO-IS_CubicalArea

(Cisco Controller) >debug ap command "debug dot11 mgmt station detail " HO-IS_CubicalArea

(Cisco Controller) >debug ap command "debug dot11 dot11radio{0 | 1} trace print xmt" HO-IS_CubicalArea

(Cisco Controller) >*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:3a:99:85:d2:20

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d Clearing Address 10.0.89.43 on mobile

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Skipping TMP rule add

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d apfMsRunStateDec

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Change state to DHCP_REQD (7) last state RUN (20)

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Complete to Mobility-Incomplete

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*pemReceiveTask: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 0.0.0.0 Removed NPU entry.

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'rojo_to_PCI' (ACL ID 0) --- (caller apf_policy.c:1465)

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 1, site 'HomeOffice', interface 'home office'

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'rojo_to_PCI' (ACL ID 0) ===> 'rojo_to_PCI' (ACL ID 0) --- (caller apf_policy.c:1465)

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 21, interface id 11, interface 'home office'

*apfMsConnTask_4: Feb 25 09:52:42.504: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 1, site 'HomeOffice', interface 'home office'

*apfMsConnTask_4: Feb 25 09:52:42.505: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'rojo_to_PCI' (ACL ID 0) ===> 'rojo_to_PCI' (ACL ID 0) --- (caller apf_policy.c:1465)

*apfMsConnTask_4: Feb 25 09:52:42.505: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_4: Feb 25 09:52:42.505: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:3a:99:85:d2:20 (status 1) ApVapId 1 Slot 0

*apfMsConnTask_4: Feb 25 09:52:42.519: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:3a:99:85:d2:20

*apfMsConnTask_4: Feb 25 09:52:42.519: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_4: Feb 25 09:52:42.519: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_4: Feb 25 09:52:42.519: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_4: Feb 25 09:52:42.519: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'rojo_to_PCI' (ACL ID 0) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.519: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_4: Feb 25 09:52:42.520: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.520: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 87, interface id 13, interface 'ho-wlan'

*apfMsConnTask_4: Feb 25 09:52:42.520: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_4: Feb 25 09:52:42.520: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.520: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_4: Feb 25 09:52:42.520: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:3a:99:85:d2:20 (status 1) ApVapId 2 Slot 0

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:3a:99:85:d2:20

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 89, interface id 15, interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:42.538: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:42.539: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.539: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_4: Feb 25 09:52:42.539: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:3a:99:85:d2:20 (status 1) ApVapId 3 Slot 0

*apfMsConnTask_6: Feb 25 09:52:42.553: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:23:33:a2:b4:f0

*apfMsConnTask_6: Feb 25 09:52:42.553: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_6: Feb 25 09:52:42.553: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_6: Feb 25 09:52:42.553: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_6: Feb 25 09:52:42.553: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 89, interface id 15, interface 'dolphin'

*apfMsConnTask_6: Feb 25 09:52:42.553: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_6: Feb 25 09:52:42.554: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_6: Feb 25 09:52:42.554: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_6: Feb 25 09:52:42.554: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:23:33:a2:b4:f0 (status 1) ApVapId 3 Slot 0

*apfMsConnTask_6: Feb 25 09:52:42.569: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:23:33:a2:b4:f0

*apfMsConnTask_6: Feb 25 09:52:42.569: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_6: Feb 25 09:52:42.569: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_6: Feb 25 09:52:42.569: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'rojo_to_PCI' (ACL ID 0) --- (caller apf_policy.c:1465)

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 1, site 'HomeOffice', interface 'home office'

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'rojo_to_PCI' (ACL ID 0) ===> 'rojo_to_PCI' (ACL ID 0) --- (caller apf_policy.c:1465)

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 21, interface id 11, interface 'home office'

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 1, site 'HomeOffice', interface 'home office'

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'rojo_to_PCI' (ACL ID 0) ===> 'rojo_to_PCI' (ACL ID 0) --- (caller apf_policy.c:1465)

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_6: Feb 25 09:52:42.570: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:23:33:a2:b4:f0 (status 1) ApVapId 1 Slot 0

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:23:33:a2:b4:f0

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'rojo_to_PCI' (ACL ID 0) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 87, interface id 13, interface 'ho-wlan'

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_6: Feb 25 09:52:42.585: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_6: Feb 25 09:52:42.586: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:23:33:a2:b4:f0 (status 1) ApVapId 2 Slot 0

*apfMsConnTask_4: Feb 25 09:52:42.600: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:21:55:ab:c3:90

*apfMsConnTask_4: Feb 25 09:52:42.600: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_4: Feb 25 09:52:42.600: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_4: Feb 25 09:52:42.600: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_4: Feb 25 09:52:42.600: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.600: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:42.601: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.601: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 89, interface id 15, interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:42.601: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:42.601: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.601: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_4: Feb 25 09:52:42.601: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:21:55:ab:c3:90 (status 1) ApVapId 3 Slot 0

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:21:55:ab:c3:90

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 87, interface id 13, interface 'ho-wlan'

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_4: Feb 25 09:52:42.620: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:21:55:ab:c3:90 (status 1) ApVapId 2 Slot 0

*apfMsConnTask_3: Feb 25 09:52:42.748: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:21:55:ab:b4:00

*apfMsConnTask_3: Feb 25 09:52:42.748: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_3: Feb 25 09:52:42.748: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_3: Feb 25 09:52:42.749: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_3: Feb 25 09:52:42.749: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 87, interface id 13, interface 'ho-wlan'

*apfMsConnTask_3: Feb 25 09:52:42.749: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_3: Feb 25 09:52:42.749: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_3: Feb 25 09:52:42.749: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_3: Feb 25 09:52:42.749: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:21:55:ab:b4:00 (status 1) ApVapId 2 Slot 0

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:3a:98:8a:e7:80

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 87, interface id 13, interface 'ho-wlan'

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 2, site 'HomeOffice', interface 'ho-wlan'

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_7: Feb 25 09:52:42.877: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:3a:98:8a:e7:80 (status 1) ApVapId 2 Slot 0

*apfMsConnTask_3: Feb 25 09:52:42.895: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:21:55:ab:b4:00

*apfMsConnTask_3: Feb 25 09:52:42.895: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 4809

*apfMsConnTask_3: Feb 25 09:52:42.895: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 9) in 10 seconds

*apfMsConnTask_3: Feb 25 09:52:42.895: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:3a:99:85:d2:20]

*apfMsConnTask_3: Feb 25 09:52:42.895: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_3: Feb 25 09:52:42.895: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_3: Feb 25 09:52:42.896: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_3: Feb 25 09:52:42.896: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 89, interface id 15, interface 'dolphin'

*apfMsConnTask_3: Feb 25 09:52:42.896: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_3: Feb 25 09:52:42.896: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_3: Feb 25 09:52:42.896: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_3: Feb 25 09:52:42.896: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:21:55:ab:b4:00 (status 1) ApVapId 3 Slot 0

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d Association received from mobile on AP 00:3a:99:85:d2:20

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d Applying site-specific IPv6 override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d Applying IPv6 Interface Policy for station 00:0b:6c:35:b7:5d - vlan 89, interface id 15, interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d Applying site-specific override for station 00:0b:6c:35:b7:5d - vapId 4, site 'HomeOffice', interface 'dolphin'

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1393)

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d STA - rates (4): 130 132 11 22 0 0 0 0 0 0 0 0 0 0 0 0

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d Processing WPA IE type 221, length 22 for mobile 00:0b:6c:35:b7:5d

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d apfMs1xStateDec

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:3a:99:85:d2:20 vapId 4 apVapId 3

*apfMsConnTask_4: Feb 25 09:52:44.755: 00:0b:6c:35:b7:5d apfPemAddUser2 (apf_policy.c:222) Changing state for mobile 00:0b:6c:35:b7:5d on AP 00:3a:99:85:d2:20 from Associated to Associated

*apfMsConnTask_4: Feb 25 09:52:44.756: 00:0b:6c:35:b7:5d Scheduling deletion of Mobile Station: (callerId: 49) in 1800 seconds

*apfMsConnTask_4: Feb 25 09:52:44.756: 00:0b:6c:35:b7:5d Sending Assoc Response to station on BSSID 00:3a:99:85:d2:20 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_4: Feb 25 09:52:44.756: 00:0b:6c:35:b7:5d apfProcessAssocReq (apf_80211.c:4606) Changing state for mobile 00:0b:6c:35:b7:5d on AP 00:3a:99:85:d2:20 from Associated to Associated

*dot1xMsgTask: Feb 25 09:52:44.757: 00:0b:6c:35:b7:5d Creating a PKC PMKID Cache entry for station 00:0b:6c:35:b7:5d (RSN 0)

*dot1xMsgTask: Feb 25 09:52:44.757: 00:0b:6c:35:b7:5d Initiating WPA PSK to mobile 00:0b:6c:35:b7:5d

*dot1xMsgTask: Feb 25 09:52:44.757: 00:0b:6c:35:b7:5d dot1x - moving mobile 00:0b:6c:35:b7:5d into Force Auth state

*dot1xMsgTask: Feb 25 09:52:44.757: 00:0b:6c:35:b7:5d Skipping EAP-Success to mobile 00:0b:6c:35:b7:5d

*dot1xMsgTask: Feb 25 09:52:44.757: 00:0b:6c:35:b7:5d Starting key exchange to mobile 00:0b:6c:35:b7:5d, data packets will be dropped

*dot1xMsgTask: Feb 25 09:52:44.758: 00:0b:6c:35:b7:5d Sending EAPOL-Key Message to mobile 00:0b:6c:35:b7:5d

state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.816: 00:0b:6c:35:b7:5d Received EAPOL-Key from mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.816: 00:0b:6c:35:b7:5d Received EAPOL-key in PTK_START state (message 2) from mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.816: 00:0b:6c:35:b7:5d Stopping retransmission timer for mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.816: 00:0b:6c:35:b7:5d Sending EAPOL-Key Message to mobile 00:0b:6c:35:b7:5d

state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d Received EAPOL-Key from mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d apfMs1xStateInc

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state DHCP_REQD (7)

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:3a:99:85:d2:20 vapId 4 apVapId 3

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4501, Adding TMP rule

*Dot1x_NW_MsgTask_5: Feb 25 00:12:28.834: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule

type = Airespace AP - Learn IP address

on AP 00:3a:99:85:d2:20, slot 0, interface = 13, QOS = 0

ACL Id = 255, Jumbo F

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 89, IPv6 intf id = 15

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d Stopping retransmission timer for mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d Key exchange done, data packets from mobile 00:0b:6c:35:b7:5d should be forwarded shortly

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d Sending EAPOL-Key Message to mobile 00:0b:6c:35:b7:5d

state PTKINITDONE (message 5 - group), replay counter 00.00.00.00.00.00.00.02

*apfReceiveTask: Feb 25 09:52:44.834: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED

*apfReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4182, Adding TMP rule

*apfReceiveTask: Feb 25 09:52:44.048: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule

type = Airespace AP - Learn IP address

on AP 00:3a:99:85:d2:20, slot 0, interface = 13, QOS = 0

ACL Id = 255, Jumb

*apfReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 89, IPv6 intf id = 15

*apfReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

*pemReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*pemReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d Sent an XID frame

*pemReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*pemReceiveTask: Feb 25 09:52:44.835: 00:0b:6c:35:b7:5d Sent an XID frame

*spamApTask3: Feb 25 09:52:44.836: 00:0b:6c:35:b7:5d Sent EAPOL-Key M5 for mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.848: 00:0b:6c:35:b7:5d Received EAPOL-Key from mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.848: 00:0b:6c:35:b7:5d Received EAPOL-key in REKEYNEGOTIATING state (message 6) from mobile 00:0b:6c:35:b7:5d

*Dot1x_NW_MsgTask_5: Feb 25 09:52:44.848: 00:0b:6c:35:b7:5d Stopping retransmission timer for mobile 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 0.0.0.0 VLAN: 0

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979b (817141659), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:44.930: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:52:44.931: 00:0b:6c:35:b7:5d DHCP ARPing for 10.0.89.3 (SPA 10.0.89.4, vlanId 89)

*DHCP Socket Task: Feb 25 09:52:44.931: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:44.931: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979b (817141659), secs: 768, flags: 0

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:48.740: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:52:48.741: 00:0b:6c:35:b7:5d DHCP received op BOOTREPLY (2) (len 308,vlan 89, port 13, encap 0xec00)

*DHCP Socket Task: Feb 25 09:52:48.741: 00:0b:6c:35:b7:5d DHCP setting server from OFFER (server 10.0.89.3, yiaddr 10.0.89.43)

*DHCP Socket Task: Feb 25 09:52:48.741: 00:0b:6c:35:b7:5d DHCP sending REPLY to STA (len 418, port 13, vlan 4)

*DHCP Socket Task: Feb 25 09:52:48.741: 00:0b:6c:35:b7:5d DHCP transmitting DHCP OFFER (2)

*DHCP Socket Task: Feb 25 09:52:48.742: 00:0b:6c:35:b7:5d DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

*DHCP Socket Task: Feb 25 09:52:48.742: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979b (817141659), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:48.742: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:48.742: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 10.0.89.43

*DHCP Socket Task: Feb 25 09:52:48.742: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:48.742: 00:0b:6c:35:b7:5d DHCP server id: 1.1.1.1 rcvd server id: 10.0.89.3

*DHCP Socket Task: Feb 25 09:52:48.747: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP transmitting DHCP REQUEST (3)

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979b (817141659), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP requested ip: 10.0.89.43

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP server id: 10.0.89.3 rcvd server id: 1.1.1.1

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:48.748: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:52:48.773: 00:0b:6c:35:b7:5d DHCP received op BOOTREPLY (2) (len 308,vlan 89, port 13, encap 0xec00)

*DHCP Socket Task: Feb 25 09:52:48.773: 00:0b:6c:35:b7:5d apfMsRunStateInc

*DHCP Socket Task: Feb 25 09:52:48.773: 00:0b:6c:35:b7:5d 10.0.89.43 DHCP_REQD (7) Change state to RUN (20) last state RUN (20)

*DHCP Socket Task: Feb 25 09:52:48.774: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Reached PLUMBFASTPATH: from line 4999

*DHCP Socket Task: Feb 25 09:52:48.774: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Replacing Fast Path rule

type = Airespace AP Client

on AP 00:3a:99:85:d2:20, slot 0, interface = 13, QOS = 0

ACL Id = 255, Jumbo Frames = NO

*DHCP Socket Task: Feb 25 09:52:48.774: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 89, IPv6 intf id = 15

*DHCP Socket Task: Feb 25 09:52:48.774: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Successfully plumbed mobile rule (ACL ID 255)

*DHCP Socket Task: Feb 25 09:52:48.774: 00:0b:6c:35:b7:5d Assigning Address 10.0.89.43 to mobile

*DHCP Socket Task: Feb 25 09:52:48.774: 00:0b:6c:35:b7:5d DHCP sending REPLY to STA (len 418, port 13, vlan 4)

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP transmitting DHCP ACK (5)

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979b (817141659), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 10.0.89.43

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:48.775: 00:0b:6c:35:b7:5d DHCP server id: 1.1.1.1 rcvd server id: 10.0.89.3

*pemReceiveTask: Feb 25 09:52:48.776: 00:0b:6c:35:b7:5d 10.0.89.43 Added NPU entry of type 1, dtlFlags 0x0

*pemReceiveTask: Feb 25 09:52:48.776: 00:0b:6c:35:b7:5d Sending a gratuitous ARP for 10.0.89.43, VLAN Id 89

*DHCP Socket Task: Feb 25 09:52:53.841: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:52:53.841: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP transmitting DHCP RELEASE (7)

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979c (817141660), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP ciaddr: 10.0.89.43, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP server id: 10.0.89.3 rcvd server id: 1.1.1.1

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d Clearing Address 10.0.89.43 on mobile

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Skipping TMP rule add

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d apfMsRunStateDec

*DHCP Socket Task: Feb 25 09:52:53.842: 00:0b:6c:35:b7:5d 10.0.89.43 RUN (20) Change state to DHCP_REQD (7) last state RUN (20)

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 0.0.0.0 VLAN: 0

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979d (817141661), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:54.669: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:54.670: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:54.670: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:52:54.670: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:52:54.670: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:54.670: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:52:56.667: 00:0b:6c:35:b7:5d DHCP received op BOOTREPLY (2) (len 308,vlan 89, port 13, encap 0xec00)

*DHCP Socket Task: Feb 25 09:52:56.667: 00:0b:6c:35:b7:5d DHCP setting server from OFFER (server 10.0.89.3, yiaddr 10.0.89.45)

*DHCP Socket Task: Feb 25 09:52:56.667: 00:0b:6c:35:b7:5d DHCP sending REPLY to STA (len 418, port 13, vlan 4)

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP transmitting DHCP OFFER (2)

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979d (817141661), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 10.0.89.45

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:56.668: 00:0b:6c:35:b7:5d DHCP server id: 1.1.1.1 rcvd server id: 10.0.89.3

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP transmitting DHCP REQUEST (3)

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979d (817141661), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP requested ip: 10.0.89.45

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP server id: 10.0.89.3 rcvd server id: 1.1.1.1

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 0.0.0.0,

dhcpGateway: 0.0.0.0, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:52:56.729: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:52:56.731: 00:0b:6c:35:b7:5d DHCP received op BOOTREPLY (2) (len 308,vlan 89, port 13, encap 0xec00)

*DHCP Socket Task: Feb 25 09:52:56.731: 00:0b:6c:35:b7:5d apfMsRunStateInc

*DHCP Socket Task: Feb 25 09:52:56.731: 00:0b:6c:35:b7:5d 10.0.89.45 DHCP_REQD (7) Change state to RUN (20) last state RUN (20)

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d 10.0.89.45 RUN (20) Reached PLUMBFASTPATH: from line 4999

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d 10.0.89.45 RUN (20) Replacing Fast Path rule

type = Airespace AP Client

on AP 00:3a:99:85:d2:20, slot 0, interface = 13, QOS = 0

ACL Id = 255, Jumbo Frames = NO

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d 10.0.89.45 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 89, IPv6 intf id = 15

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d 10.0.89.45 RUN (20) Successfully plumbed mobile rule (ACL ID 255)

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d Assigning Address 10.0.89.45 to mobile

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP sending REPLY to STA (len 418, port 13, vlan 4)

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP transmitting DHCP ACK (5)

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979d (817141661), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP ciaddr: 0.0.0.0, yiaddr: 10.0.89.45

*DHCP Socket Task: Feb 25 09:52:56.732: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:52:56.733: 00:0b:6c:35:b7:5d DHCP server id: 1.1.1.1 rcvd server id: 10.0.89.3

*pemReceiveTask: Feb 25 09:52:56.733: 00:0b:6c:35:b7:5d 10.0.89.45 Added NPU entry of type 1, dtlFlags 0x0

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 255.255.255.0,

dhcpGateway: 10.0.89.1, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP transmitting DHCP REQUEST (3)

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979e (817141662), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP ciaddr: 10.0.89.45, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 255.255.255.0,

dhcpGateway: 10.0.89.1, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:53:02.433: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:53:02.434: 00:0b:6c:35:b7:5d DHCP received op BOOTREPLY (2) (len 308,vlan 89, port 13, encap 0xec00)

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP sending REPLY to STA (len 418, port 13, vlan 4)

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP transmitting DHCP ACK (5)

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979e (817141662), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP ciaddr: 10.0.89.45, yiaddr: 10.0.89.45

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:53:02.435: 00:0b:6c:35:b7:5d DHCP server id: 1.1.1.1 rcvd server id: 10.0.89.3

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP received op BOOTREQUEST (1) (len 308,vlan 4, port 13, encap 0xec03)

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP selecting relay 1 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 255.255.255.0,

dhcpGateway: 10.0.89.1, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP selected relay 1 - 10.0.89.3 (local address 10.0.89.4, gateway 10.0.89.3, VLAN 89, port 13)

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP transmitting DHCP REQUEST (3)

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979f (817141663), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP ciaddr: 10.0.89.45, yiaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 10.0.89.4

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP sending REQUEST to 10.0.89.3 (len 350, port 13, vlan 89)

*DHCP Socket Task: Feb 25 09:53:04.869: 00:0b:6c:35:b7:5d DHCP selecting relay 2 - control block settings:

dhcpServer: 10.0.89.3, dhcpNetmask: 255.255.255.0,

dhcpGateway: 10.0.89.1, dhcpRelay: 10.0.89.4 VLAN: 89

*DHCP Socket Task: Feb 25 09:53:04.870: 00:0b:6c:35:b7:5d DHCP selected relay 2 - NONE

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP received op BOOTREPLY (2) (len 308,vlan 89, port 13, encap 0xec00)

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP sending REPLY to STA (len 418, port 13, vlan 4)

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP transmitting DHCP ACK (5)

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP xid: 0x30b4979f (817141663), secs: 0, flags: 0

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP chaddr: 00:0b:6c:35:b7:5d

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP ciaddr: 10.0.89.45, yiaddr: 10.0.89.45

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0

*DHCP Socket Task: Feb 25 09:53:04.875: 00:0b:6c:35:b7:5d DHCP server id: 1.1.1.1 rcvd server id: 10.0.89.3

Scott Fella · ‎02-25-2013

Here is your problem...

Wi-Fi Protected Access (WPA/WPA2)............. Enabled

WPA (SSN IE)............................... Enabled

TKIP Cipher............................. Enabled

AES Cipher.............................. Enabled

WPA2 (RSN IE).............................. Enabled

TKIP Cipher............................. Enabled

AES Cipher.............................. Enabled

Now what encryption method are you using, because its best to only choose one. You either have WPA/TKIP or WPA2/AES, not both and not a mix like what you have. So try WPA2/AES only as long as the devices support WPA2 and see if that helps.

You might want to change that on your other SSID's too if thats how you have it.

Thanks,

Scott

Help out other by using the rating system and marking answered questions as "Answered"

-Scott
*** Please rate helpful posts ***

Joshua Engels · ‎02-25-2013

Thanks for the reply Scott. In our situation, we have two types of devices that use this SSID. One of the devices is about 6 years old and only supports WPA with TKIP where as the newer device utilizes WPA2 with AES. I was under the impression that if all options were selected that it just negotiated the most secure protocol that the device could use. Is that incorrect?

Thanks again!

Scott Fella · ‎02-25-2013

That is incorrect. Devices will get confused because this info is sent in the beacons. Your best bet is to configure this WLAN profile for WPA/TKIP and then create a new WLAN with the same SSID but a different WLAN profile name. Now set that to WPA2/AES. This will eliminate the issue you are having.

Sent from Cisco Technical Support iPhone App

-Scott
*** Please rate helpful posts ***

Joshua Engels · ‎02-25-2013

Great Scott, I will make the changes and test tomorrow. Thanks for the followup! I'll let you know how it goes.

Scott Fella · ‎02-25-2013

Keep us posted

Sent from Cisco Technical Support iPhone App

-Scott
*** Please rate helpful posts ***

Joshua Engels · ‎02-25-2013

Hey Scott, while sitting at the house I did some more digging and found why I thought that I could have it configured this way. I wanted to get your thoughts on this paragraph from the Cisco 7.0 WLC configuration guide from June 2010 (page 413 in pdf)

"On a single WLAN, you can allow WPA1, WPA2, and 802.1X/PSK/CCKM/802.1X+CCKM clients to join. All of the access points on such a WLAN advertise WPA1, WPA2, and 802.1X/PSK/CCKM/

802.1X+CCKM information elements in their beacons and probe responses. When you enable WPA1 and/or WPA2, you can also enable one or two ciphers, or cryptographic algorithms, designed to protect data traffic. Specifically, you can enable AES and/or TKIP data encryption for WPA1 and/or WPA2. TKIP is the default value for WPA1, and AES is the default value for WPA2."

I definitley am willing to try testing what you are suggesting but wanted to get your thoughts on this documentation. Am I misunderstanding what I am reading?

Thanks,

Josh

Scott Fella · ‎02-25-2013

Sure you can do that... that is what you did. The question is, what does the client want. The standard for WPA is WPA with TKIP, the standard for WPA2 is WPA2 with AES. there is no standard for mixing encryption. When you configure a client for WPA-PSK ro WPA-Enterprise, it uses WPA/TKIP. When you configure a client for WPA2-PSK or WPA2-Enterprise, it uses WPA2/AES. So when you do something that isn't standard, you can expect that devices will or may not work. If you had any Apple devices, forget it... they will not connect with how you have your SSID set.

Thanks,

Scott

Help out other by using the rating system and marking answered questions as "Answered"

-Scott
*** Please rate helpful posts ***

maldehne · ‎02-26-2013

I can see that the client has been associating to multiple APs , it seems like jumping between them.

Can you please try to shut the radio of the surronding APs and try to associate to single radio?

How it goes?

If that made it , then you have to revisit the power settings on your APs.

-------------------------------------------------------------------------

Please Don't forget to rate correct answers

maldehne · ‎02-24-2013

Please have the following output:

show wlan

on the controller, have the following debugs enabled before trying to connect with that particular client:

debug client < client mac addr >

debug ap enable < name of AP as reported by controller @ which the client is trying to connect >

debug ap command "debug dot11 mgmt msg"

debug ap command "debug dot11 mgmt state-machine"

debug ap command "debug dot11 station connection failure"

debug ap command "debug dot11 mgmt station detail "

debug ap command "debug dot11 dot11radio{0 | 1} trace print xmt"

Cheers