02-22-2013 06:43 AM - edited 07-03-2021 11:36 PM
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
02-24-2013 08:34 PM
get debug client while reproducing the issue
02-25-2013 07:42 AM
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
02-25-2013 08:53 AM
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"
02-25-2013 04:47 PM
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!
02-25-2013 04:52 PM
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
02-25-2013 04:54 PM
Great Scott, I will make the changes and test tomorrow. Thanks for the followup! I'll let you know how it goes.
02-25-2013 04:58 PM
Keep us posted
Sent from Cisco Technical Support iPhone App
02-25-2013 05:29 PM
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
02-25-2013 05:34 PM
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"
02-26-2013 02:40 AM
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
02-24-2013 11:02 PM
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
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide