cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1381
Views
0
Helpful
2
Replies

Problems with some iPads connecting to BYOD

stephendrkw
Level 3
Level 3

I've setup some new Wireless DMZ Controllers which have mobility Anchors to our Internal Controllers for a new BYOD ssid. BYOD is working fine DHCP works and Internet connection is good. However, there are some users with Ipads (no specific versions) who connect automatically straight away without any problems but some who have problems connecting.

Problem users - user does not connect automatically they click BYOD ssid and their Apple IPad device will not connect they then click on another ssid GUEST then click back on BYOD and BYOD works!

Below is a user example with problems (iPad 3):

debug client 74:e1:b6:e5:68:5f

(-wcon) >

(-wcon) >Tue Jul 10 13:42:44 2012: 74:e1:b6:e5:68:5f Adding mobile on LWAPP AP 00:15:62:17:57:10(1)

Tue Jul 10 13:42:44 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 23) in 5 seconds

Tue Jul 10 13:42:44 2012: 74:e1:b6:e5:68:5f apfProcessProbeReq (apf_80211.c:4142) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:17:57:10 from Idle to Probe

Tue Jul 10 13:42:44 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:45 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:45 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:45 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:45 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:46 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:46 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:46 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:46 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:47 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:47 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:47 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:47 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:47 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 24) in 5 seconds

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Association received from mobile on AP 00:15:62:26:cc:40

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f STA - rates (8): 130 132 139 150 36 48 72 108 0 0 0 0 0 0 0 0

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Processing RSN IE type 48, length 20 for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Deleted mobile LWAPP rule on AP [00:15:62:17:57:10]

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Updated location for station old AP 00:00:00:00:00:00-0, new AP 00:15:62:26:cc:40-0

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f apfProcessAssocReq (apf_80211.c:3765) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Probe to AAA Pending

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 20) in 10 seconds

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Initializing policy

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state AUTHCHECK (2)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state 8021X_REQD (3)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f apfPemAddUser2 (apf_policy.c:212) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from AAA Pending to Associated

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Stopping deletion of Mobile Station: (callerId: 48)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 0)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f apfProcessRadiusAssocResp (apf_80211.c:1783) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Associated

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Creating a PKC PMKID Cache entry for station 74:e1:b6:e5:68:5f (RSN 2)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Adding BSSID 00:15:62:26:cc:44 to PMKID cache for station 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: New PMKID: (16)

Tue Jul 10 13:42:48 2012:      [0000] 20 19 98 0d cc ea b8 04 a7 ba 83 bc 32 0a 7d d6

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Initiating RSN PSK to mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f dot1x - moving mobile 74:e1:b6:e5:68:5f into Force Auth state

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Skipping EAP-Success to mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: Including PMKID in M1  (16)

Tue Jul 10 13:42:48 2012:      [0000] 20 19 98 0d cc ea b8 04 a7 ba 83 bc 32 0a 7d d6

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Sending EAPOL-Key Message to mobile 74:e1:b6:e5:68:5f

                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-Key from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-key in PKT_START state (message 2) from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Stopping retransmission timer for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Sending EAPOL-Key Message to mobile 74:e1:b6:e5:68:5f

                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-Key from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state L2AUTHCOMPLETE (4)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4272, Adding TMP rule

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:15:62:26:cc:40, slot 0, interface = 29, QOS = 0

  ACL Id = 255, Jumbo Frames = NO, 802.1P = 0, DSCP = 0, TokenID = 5006

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Stopping retransmission timer for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Added NPU entry of type 9

Tue Jul 10 13:42:48 2012: 74:e1:b6:e5:68:5f Sent an XID frame

Tue Jul 10 13:42:49 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:42:49 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmQueryRequested'

Tue Jul 10 13:42:52 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:42:52 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:42:56 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:42:56 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:42:58 2012: 74:e1:b6:e5:68:5f Copy AP LOCP - mode:0 slotId:0, apMac 0x0:15:62:26:cc:40

Tue Jul 10 13:42:58 2012: 74:e1:b6:e5:68:5f Copy WLAN LOCP EssIndex:5 aid:2 ssid:   WLKPV

Tue Jul 10 13:42:58 2012: 74:e1:b6:e5:68:5f Copy Security LOCP ecypher:0x0 ptype:0x2, p:0x1, eaptype:0x6 w:0x1 aalg:0x0, PMState:  DHCP_REQD

Tue Jul 10 13:42:58 2012: 74:e1:b6:e5:68:5f Copy 802.11 LOCP a:0x0 b:0x0 c:0x0 d:0x0 e:0x1 protocol2:0x3 statuscode 0, reasoncode 99, status 3

Tue Jul 10 13:42:58 2012: 74:e1:b6:e5:68:5f Copy MobilityData LOCP status:0, anchorip:0xa42db48

Tue Jul 10 13:43:04 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:04 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:04 2012: 74:e1:b6:e5:68:5f Orphan Packet from 0.0.0.0 on mobile

Tue Jul 10 13:43:05 2012: 74:e1:b6:e5:68:5f Orphan Packet from 0.0.0.0 on mobile

Tue Jul 10 13:43:05 2012: 74:e1:b6:e5:68:5f Orphan Packet from 0.0.0.0 on mobile

Tue Jul 10 13:43:06 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.43.79 on mobile

Tue Jul 10 13:43:06 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.43.79 on mobile

Tue Jul 10 13:43:06 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.43.79 on mobile

Tue Jul 10 13:43:09 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.43.79 on mobile

Tue Jul 10 13:43:13 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:13 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:21 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:21 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:30 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:30 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:38 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:38 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:47 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:47 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Association received from mobile on AP 00:15:62:26:cc:40

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Processing RSN IE type 48, length 20 for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f apfProcessAssocReq (apf_80211.c:3765) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to AAA Pending

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 20) in 10 seconds

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Initializing policy

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f apfPemAddUser2 (apf_policy.c:212) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from AAA Pending to Associated

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Stopping deletion of Mobile Station: (callerId: 48)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 0)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f apfProcessRadiusAssocResp (apf_80211.c:1783) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Associated

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Removed NPU entry.

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Creating a PKC PMKID Cache entry for station 74:e1:b6:e5:68:5f (RSN 2)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Adding BSSID 00:15:62:26:cc:44 to PMKID cache for station 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: New PMKID: (16)

Tue Jul 10 13:43:48 2012:      [0000] 20 19 98 0d cc ea b8 04 a7 ba 83 bc 32 0a 7d d6

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Initiating RSN PSK to mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f dot1x - moving mobile 74:e1:b6:e5:68:5f into Force Auth state

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Skipping EAP-Success to mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: Including PMKID in M1  (16)

Tue Jul 10 13:43:48 2012:      [0000] 20 19 98 0d cc ea b8 04 a7 ba 83 bc 32 0a 7d d6

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Sending EAPOL-Key Message to mobile 74:e1:b6:e5:68:5f

                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-Key from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-key in PKT_START state (message 2) from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Stopping retransmission timer for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Sending EAPOL-Key Message to mobile 74:e1:b6:e5:68:5f

                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-Key from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state DHCP_REQD (7)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4272, Adding TMP rule

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:15:62:26:cc:40, slot 0, interface = 29, QOS = 0

  ACL Id = 255, Jumbo Frames = NO, 802.1P = 0, DSCP = 0, TokenID = 5006

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f Stopping retransmission timer for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:43:48 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Added NPU entry of type 9

Tue Jul 10 13:43:50 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:50 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:53 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:53 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:57 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:43:57 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:43:58 2012: 74:e1:b6:e5:68:5f Copy AP LOCP - mode:0 slotId:0, apMac 0x0:15:62:26:cc:40

Tue Jul 10 13:43:58 2012: 74:e1:b6:e5:68:5f Copy WLAN LOCP EssIndex:5 aid:2 ssid:   WLKPV

Tue Jul 10 13:43:58 2012: 74:e1:b6:e5:68:5f Copy Security LOCP ecypher:0x0 ptype:0x2, p:0x1, eaptype:0x6 w:0x1 aalg:0x0, PMState:  DHCP_REQD

Tue Jul 10 13:43:58 2012: 74:e1:b6:e5:68:5f Copy 802.11 LOCP a:0x0 b:0x0 c:0x0 d:0x0 e:0x1 protocol2:0x3 statuscode 0, reasoncode 99, status 3

Tue Jul 10 13:43:58 2012: 74:e1:b6:e5:68:5f Copy MobilityData LOCP status:0, anchorip:0xa42db48

Tue Jul 10 13:44:05 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:44:05 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:44:05 2012: 74:e1:b6:e5:68:5f Orphan Packet from 0.0.0.0 on mobile

Tue Jul 10 13:44:06 2012: 74:e1:b6:e5:68:5f Orphan Packet from 0.0.0.0 on mobile

Tue Jul 10 13:44:06 2012: 74:e1:b6:e5:68:5f Orphan Packet from 0.0.0.0 on mobile

Tue Jul 10 13:44:06 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.85.244 on mobile

Tue Jul 10 13:44:07 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.85.244 on mobile

Tue Jul 10 13:44:07 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.85.244 on mobile

Tue Jul 10 13:44:10 2012: 74:e1:b6:e5:68:5f Orphan Packet from 169.254.85.244 on mobile

Tue Jul 10 13:44:14 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:44:14 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:44:23 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:44:23 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:44:31 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:44:31 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmAnchorExportRequested'

Tue Jul 10 13:44:32 2012: 74:e1:b6:e5:68:5f Association received from mobile on AP 00:15:62:26:cc:40

Tue Jul 10 13:44:32 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 50) in 1 seconds

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Ignoring 802.11 assoc request from mobile pending deletion

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 12)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Ignoring 802.11 assoc request from mobile pending deletion

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 12)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f apfMsExpireCallback (apf_ms.c:433) Expiring Mobile!

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f apfMsExpireMobileStation (apf_ms.c:4229) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Disassociated

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f apfMsExpireMobileStation (apf_ms.c:4335) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Disassociated to Idle

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:15:62:26:cc:40]

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Deleting mobile on AP 00:15:62:26:cc:40(0)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Removed NPU entry.

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Adding mobile on LWAPP AP 00:15:62:26:cc:40(0)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Association received from mobile on AP 00:15:62:26:cc:40

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f STA - rates (8): 130 132 139 150 36 48 72 108 0 0 0 0 0 0 0 0

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Initializing policy

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state AUTHCHECK (2)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state L2AUTHCOMPLETE (4)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f apfPemAddUser2 (apf_policy.c:212) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Idle to Associated

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Stopping deletion of Mobile Station: (callerId: 48)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 0)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f apfProcessAssocReq (apf_80211.c:3888) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Associated

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmQueryRequested'

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREQUEST (1) (len 308, port 29, encap 0xec03)

Tue Jul 10 13:44:33 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0,  mobility state = 'apfMsMmQueryRequested'

Tue Jul 10 13:44:34 2012: 74:e1:b6:e5:68:5f Copy AP LOCP - mode:0 slotId:0, apMac 0x0:15:62:26:cc:40

Tue Jul 10 13:44:34 2012: 74:e1:b6:e5:68:5f Copy WLAN LOCP EssIndex:3 aid:2 ssid:guestwifi

Tue Jul 10 13:44:34 2012: 74:e1:b6:e5:68:5f Copy Security LOCP ecypher:0x3 ptype:0x4, p:0x1, eaptype:0x6 w:0x0 aalg:0x0, PMState:  DHCP_REQD

Tue Jul 10 13:44:34 2012: 74:e1:b6:e5:68:5f Copy 802.11 LOCP a:0x0 b:0x0 c:0x0 d:0x0 e:0x1 protocol2:0x3 statuscode 0, reasoncode 99, status 3

Tue Jul 10 13:44:34 2012: 74:e1:b6:e5:68:5f Copy MobilityData LOCP status:0, anchorip:0x0

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=ExpForeign

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Change state to RUN (20) last state RUN (20)

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Reached PLUMBFASTPATH: from line 4032

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Adding Fast Path rule

  type = Airespace AP Client

  on AP 00:15:62:26:cc:40, slot 0, interface = 29, QOS = 3

  ACL Id = 255, Jumbo Frames = NO, 802.1P = 0, DSCP = 0, TokenID = 5006

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Successfully plumbed mobile rule (ACL ID 255)

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f Set bi-dir guest tunnel for 74:e1:b6:e5:68:5f as in Export Foreign role

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Added NPU entry of type 1

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREPLY (2) (len 308, port 29, encap 0xec00)

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP processing DHCP OFFER (2)

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP   op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP   xid: 0x611981a2 (1629061538), secs: 2, flags: 0

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP   chaddr: 74:e1:b6:e5:68:5f

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP   ciaddr: 0.0.0.0,  yiaddr: 192.168.250.206

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP   siaddr: 192.168.250.1,  giaddr: 0.0.0.0

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP   server id: 192.168.250.1  rcvd server id: 192.168.250.1

Tue Jul 10 13:44:35 2012: 74:e1:b6:e5:68:5f DHCP successfully bridged packet to STA

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP received op BOOTREPLY (2) (len 308, port 29, encap 0xec00)

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP processing DHCP ACK (5)

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP   op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP   xid: 0x611981a2 (1629061538), secs: 3, flags: 0

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP   chaddr: 74:e1:b6:e5:68:5f

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP   ciaddr: 0.0.0.0,  yiaddr: 192.168.250.206

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP   siaddr: 192.168.250.1,  giaddr: 0.0.0.0

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP   server id: 192.168.250.1  rcvd server id: 192.168.250.1

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f 192.168.250.206 RUN (20) DHCP Address Re-established

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f Assigning Address 192.168.250.206 to mobile

Tue Jul 10 13:44:36 2012: 74:e1:b6:e5:68:5f DHCP successfully bridged packet to STA

Tue Jul 10 13:44:46 2012: 74:e1:b6:e5:68:5f Copy AP LOCP - mode:0 slotId:0, apMac 0x0:15:62:26:cc:40

Tue Jul 10 13:44:46 2012: 74:e1:b6:e5:68:5f Copy WLAN LOCP EssIndex:3 aid:2 ssid:guestwifi

Tue Jul 10 13:44:46 2012: 74:e1:b6:e5:68:5f Copy Security LOCP ecypher:0x3 ptype:0x4, p:0x0, eaptype:0x6 w:0x0 aalg:0x0, PMState:        RUN

Tue Jul 10 13:44:46 2012: 74:e1:b6:e5:68:5f Copy 802.11 LOCP a:0x0 b:0x0 c:0x0 d:0x0 e:0x1 protocol2:0x3 statuscode 0, reasoncode 99, status 3

Tue Jul 10 13:44:46 2012: 74:e1:b6:e5:68:5f Copy IP LOCP: 0xc0a8face

Tue Jul 10 13:44:46 2012: 74:e1:b6:e5:68:5f Copy MobilityData LOCP status:5, anchorip:0xa88071f

Tue Jul 10 13:46:01 2012: 74:e1:b6:e5:68:5f Association received from mobile on AP 00:15:62:26:cc:40

Tue Jul 10 13:46:01 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 50) in 1 seconds

Tue Jul 10 13:46:01 2012: 74:e1:b6:e5:68:5f Ignoring 802.11 assoc request from mobile pending deletion

Tue Jul 10 13:46:01 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 12)

Tue Jul 10 13:46:01 2012: 74:e1:b6:e5:68:5f Ignoring 802.11 assoc request from mobile pending deletion

Tue Jul 10 13:46:01 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 12)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f apfMsExpireCallback (apf_ms.c:433) Expiring Mobile!

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f apfMsExpireMobileStation (apf_ms.c:4229) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Disassociated

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Sent Deauthenticate to mobile on BSSID 00:15:62:26:cc:40 slot 0(caller apf_ms.c:4299)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f apfMsExpireMobileStation (apf_ms.c:4335) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Disassociated to Idle

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 RUN (20) Deleted mobile LWAPP rule on AP [00:15:62:26:cc:40]

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Clearing Address 192.168.250.206 on mobile

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 RUN (20) Change state to DHCP_REQD (7) last state RUN (20)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 DHCP_REQD (7) pemAdvanceState2 3866, Adding TMP rule

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 DHCP_REQD (7) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:15:62:26:cc:40, slot 0, interface = 29, QOS = 3

  ACL Id = 255, Jumbo Frames = NO, 802.1P = 0, DSCP = 0, TokenID = 5006

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Deleting mobile on AP 00:15:62:26:cc:40(0)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 Removed NPU entry.

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Set bi-dir guest tunnel for 74:e1:b6:e5:68:5f as in Export Foreign role

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 192.168.250.206 Added NPU entry of type 9

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Adding mobile on LWAPP AP 00:15:62:26:cc:40(0)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Association received from mobile on AP 00:15:62:26:cc:40

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f STA - rates (8): 130 132 139 150 36 48 72 108 0 0 0 0 0 0 0 0

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Processing RSN IE type 48, length 20 for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f apfProcessAssocReq (apf_80211.c:3765) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Idle to AAA Pending

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Scheduling deletion of Mobile Station:  (callerId: 20) in 10 seconds

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Initializing policy

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state AUTHCHECK (2)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state 8021X_REQD (3)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f apfPemAddUser2 (apf_policy.c:212) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from AAA Pending to Associated

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Stopping deletion of Mobile Station: (callerId: 48)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Sending Assoc Response to station on BSSID 00:15:62:26:cc:40 (status 0)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f apfProcessRadiusAssocResp (apf_80211.c:1783) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Associated

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Removed NPU entry.

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Creating a PKC PMKID Cache entry for station 74:e1:b6:e5:68:5f (RSN 2)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Adding BSSID 00:15:62:26:cc:44 to PMKID cache for station 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: New PMKID: (16)

Tue Jul 10 13:46:02 2012:      [0000] 20 19 98 0d cc ea b8 04 a7 ba 83 bc 32 0a 7d d6

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Initiating RSN PSK to mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f dot1x - moving mobile 74:e1:b6:e5:68:5f into Force Auth state

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Skipping EAP-Success to mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: Including PMKID in M1  (16)

Tue Jul 10 13:46:02 2012:      [0000] 20 19 98 0d cc ea b8 04 a7 ba 83 bc 32 0a 7d d6

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Sending EAPOL-Key Message to mobile 74:e1:b6:e5:68:5f

                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Received EAPOL-Key from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Received EAPOL-key in PKT_START state (message 2) from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Stopping retransmission timer for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Sending EAPOL-Key Message to mobile 74:e1:b6:e5:68:5f

                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Received EAPOL-Key from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state L2AUTHCOMPLETE (4)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:15:62:26:cc:40

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4272, Adding TMP rule

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:15:62:26:cc:40, slot 0, interface = 29, QOS = 0

  ACL Id = 255, Jumbo Frames = NO, 802.1P = 0, DSCP = 0, TokenID = 5006

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Stopping retransmission timer for mobile 74:e1:b6:e5:68:5f

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Added NPU entry of type 9

Tue Jul 10 13:46:02 2012: 74:e1:b6:e5:68:5f Sent an XID frame

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=ExpForeign

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f 0.0.0.0 DHCP_REQD (7) Change state to RUN (20) last state RUN (20)

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Reached PLUMBFASTPATH: from line 4032

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Replacing Fast Path rule

  type = Airespace AP Client

  on AP 00:15:62:26:cc:40, slot 0, interface = 29, QOS = 0

  ACL Id = 255, Jumbo Frames = NO, 802.1P = 0, DSCP = 0, TokenID = 5006

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Successfully plumbed mobile rule (ACL ID 255)

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f Set bi-dir guest tunnel for 74:e1:b6:e5:68:5f as in Export Foreign role

Tue Jul 10 13:46:04 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Added NPU entry of type 1

Tue Jul 10 13:46:11 2012: 74:e1:b6:e5:68:5f Copy AP LOCP - mode:0 slotId:0, apMac 0x0:15:62:26:cc:40

Tue Jul 10 13:46:11 2012: 74:e1:b6:e5:68:5f Copy WLAN LOCP EssIndex:5 aid:2 ssid:   WLKPV

Tue Jul 10 13:46:11 2012: 74:e1:b6:e5:68:5f Copy Security LOCP ecypher:0x0 ptype:0x2, p:0x0, eaptype:0x6 w:0x1 aalg:0x0, PMState:        RUN

Tue Jul 10 13:46:11 2012: 74:e1:b6:e5:68:5f Copy 802.11 LOCP a:0x0 b:0x0 c:0x0 d:0x0 e:0x1 protocol2:0x3 statuscode 0, reasoncode 99, status 3

Tue Jul 10 13:46:11 2012: 74:e1:b6:e5:68:5f Copy MobilityData LOCP status:5, anchorip:0xa42db49

Tue Jul 10 13:50:14 2012: 74:e1:b6:e5:68:5f apfMmProcessCloseResponse (apf_mm.c:427) Expiring Mobile!

Tue Jul 10 13:50:14 2012: 74:e1:b6:e5:68:5f apfMsExpireMobileStation (apf_ms.c:4229) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Associated to Disassociated

Tue Jul 10 13:50:14 2012: 74:e1:b6:e5:68:5f apfMsExpireMobileStation (apf_ms.c:4335) Changing state for mobile 74:e1:b6:e5:68:5f on AP 00:15:62:26:cc:40 from Disassociated to Idle

Tue Jul 10 13:50:14 2012: 74:e1:b6:e5:68:5f 0.0.0.0 RUN (20) Deleted mobile LWAPP rule on AP [00:15:62:26:cc:40]

Tue Jul 10 13:50:14 2012: 74:e1:b6:e5:68:5f Deleting mobile on AP 00:15:62:26:cc:40(0)

Tue Jul 10 13:50:14 2012: 74:e1:b6:e5:68:5f 0.0.0.0 Removed NPU entry.

2 Replies 2

Amjad Abdullah
VIP Alumni
VIP Alumni

Well, what i see that the ipad drops dhcp packets due ongoing mobility exchange. The mobility exchange seems stuck and disconnecting and connecting again seem to refresh it.

I would say this is ipad issue. I've seem too many issues with apple ad cisco all with apple problems.

If you can provide wired sniff capture hat may reveal which device stopped responding to the other and caused the process to get stuck.

Sent from Cisco Technical Support iPad App

Rating useful replies is more useful than saying "Thank you"

This line is showing that your Auto-Anchor (mobility) is not completing:

Tue Jul 10 13:43:21 2012: 74:e1:b6:e5:68:5f DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmAnchorExportRequested'

This would imply that for some reason your mobility handoff is either being rejected by your anchor or isnt making it through the network. My suggestion would be to run the same debug on both the foreign and any potential anchors without changing ssids to see what it shows..

In addition to the client debug, a "debug mobility handoff enable"  might show a reason of the handoff being rejected. But I'd wait to see the basic client debug on all wLCs first.

Are you anchoring to multiple WLCs?

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: