wifi connection dropping during wpa2 key rotation
We are using iPhones and iPads in our workplace that consists of Cisco 3502 Access Points LWAPP connected to Cisco 5508 WLC. The iPhones (and iPads) running iOS v5.0.1 connect fine, but when it comes time for key rotation between the AP controller and the device the iPhone is not responding and then deauthenticated, dropped. In order to use the wireless connection again, the user must re-authenticate many times during the day. I have an open ticket open with Cisco TAC, support requested I check with Apple.
Anyone else seeing the same problem, have a solution?
<pre>
debug client 8C:58:77:35:AE:03
(Cisco Controller) >*pemReceiveTask: Feb 20 08:53:39.709: 8c:58:77:35:ae:03 10.3
.10.8 RUN (20) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (ca
ller apf_policy.c:1626)
*pemReceiveTask: Feb 20 08:53:39.709: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Repla
cing Fast Path rule
type = Airespace AP Client
on AP 64:a0:e7:dc:60:10, slot 0, interface = 13, QOS = 0
ACL Id = 255, Jumbo Frames = NO
*pemReceiveTask: Feb 20 08:53:39.709: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Fast
Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 10, IPv6
intf id = 11
*pemReceiveTask: Feb 20 08:53:39.709: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Succe
ssfully plumbed mobile rule (ACL ID 255)
*pemReceiveTask: Feb 20 08:53:39.709: 8c:58:77:35:ae:03 10.3.10.8 Added NPU entr
y of type 1, dtlFlags 0x0
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 Reassociation received
from mobile on AP 24:b6:57:f8:c7:80
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Chan
ging ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1
626)
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 Applying site-specific
IPv6 override for station 8c:58:77:35:ae:03 - vapId 1, site 'default-group', int
erface 'nysenatenet'
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 Applying IPv6 Interface
Policy for station 8c:58:77:35:ae:03 - vlan 10, interface id 11, interface 'nys
enatenet'
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 STA - rates (8): 130 13
2 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 STA - rates (12): 130 1
32 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 Processing RSN IE type
48, length 20 for mobile 8c:58:77:35:ae:03
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Dele
ted mobile LWAPP rule on AP [64:a0:e7:dc:60:10]
*pemReceiveTask: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 10.3.10.8 Removed NPU en
try.
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 Updated location for st
ation old AP 64:a0:e7:dc:60:10-0, new AP 24:b6:57:f8:c7:80-0
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 apfMsRunStateDec
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 apfMs1xStateDec
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Chan
ge state to START (0) last state RUN (20)
*apfMsConnTask_2: Feb 20 08:54:34.302: 8c:58:77:35:ae:03 10.3.10.8 START (0) Ini
tializing policy
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 10.3.10.8 START (0) Cha
nge state to AUTHCHECK (2) last state RUN (20)
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 10.3.10.8 AUTHCHECK (2)
Change state to 8021X_REQD (3) last state RUN (20)
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 10.3.10.8 8021X_REQD (3
) DHCP required on AP 24:b6:57:f8:c7:80 vapId 1 apVapId 1for this client
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 Not Using WMM Complianc
e code qosCap 00
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 10.3.10.8 8021X_REQD (3
) Plumbed mobile LWAPP rule on AP 24:b6:57:f8:c7:80 vapId 1 apVapId 1
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 apfPemAddUser2 (apf_pol
icy.c:223) Changing state for mobile 8c:58:77:35:ae:03 on AP 24:b6:57:f8:c7:80 f
rom Associated to Associated
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 Scheduling deletion of
Mobile Station: (callerId: 49) in 43200 seconds
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 Sending Assoc Response
to station on BSSID 24:b6:57:f8:c7:80 (status 0) ApVapId 1 Slot 0
*apfMsConnTask_2: Feb 20 08:54:34.303: 8c:58:77:35:ae:03 apfProcessAssocReq (apf
_80211.c:5237) Changing state for mobile 8c:58:77:35:ae:03 on AP 24:b6:57:f8:c7:
80 from Associated to Associated
*dot1xMsgTask: Feb 20 08:54:34.305: 8c:58:77:35:ae:03 Creating a PKC PMKID Cache
entry for station 8c:58:77:35:ae:03 (RSN 2)
*dot1xMsgTask: Feb 20 08:54:34.305: 8c:58:77:35:ae:03 Adding BSSID 24:b6:57:f8:c
7:80 to PMKID cache for station 8c:58:77:35:ae:03
*dot1xMsgTask: Feb 20 08:54:34.305: New PMKID: (16)
*dot1xMsgTask: Feb 20 08:54:34.305: [0000] fa fe bc ca 02 b7 e3 20 ee 73 f6
d3 53 3b ea 17
*dot1xMsgTask: Feb 20 08:54:34.305: 8c:58:77:35:ae:03 Initiating RSN PSK to mobi
le 8c:58:77:35:ae:03
*dot1xMsgTask: Feb 20 08:54:34.306: 8c:58:77:35:ae:03 dot1x - moving mobile 8c:5
8:77:35:ae:03 into Force Auth state
*dot1xMsgTask: Feb 20 08:54:34.306: 8c:58:77:35:ae:03 Skipping EAP-Success to mo
bile 8c:58:77:35:ae:03
*dot1xMsgTask: Feb 20 08:54:34.306: Including PMKID in M1 (16)
*dot1xMsgTask: Feb 20 08:54:34.306: [0000] fa fe bc ca 02 b7 e3 20 ee 73 f6
d3 53 3b ea 17
*dot1xMsgTask: Feb 20 08:54:34.306: 8c:58:77:35:ae:03 Starting key exchange to m
obile 8c:58:77:35:ae:03, data packets will be dropped
*dot1xMsgTask: Feb 20 08:54:34.306: 8c:58:77:35:ae:03 Sending EAPOL-Key Message
to mobile 8c:58:77:35:ae:03
state INITPMK (message 1), replay counter 00.00.00
.00.00.00.00.00
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.312: 8c:58:77:35:ae:03 Received EAPOL-Key f
rom mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.313: 8c:58:77:35:ae:03 Received EAPOL-key i
n PTK_START state (message 2) from mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.313: 8c:58:77:35:ae:03 Stopping retransmiss
ion timer for mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.313: 8c:58:77:35:ae:03 Sending EAPOL-Key Me
ssage to mobile 8c:58:77:35:ae:03
state PTKINITNEGOTIATING (message 3), replay
counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.318: 8c:58:77:35:ae:03 Received EAPOL-Key f
rom mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.318: 8c:58:77:35:ae:03 Received EAPOL-key i
n PTKINITNEGOTIATING state (message 4) from mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.318: 8c:58:77:35:ae:03 apfMs1xStateInc
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.318: 8c:58:77:35:ae:03 10.3.10.8 8021X_REQD
(3) Change state to L2AUTHCOMPLETE (4) last state RUN (20)
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.319: 8c:58:77:35:ae:03 10.3.10.8 L2AUTHCOMP
LETE (4) DHCP required on AP 24:b6:57:f8:c7:80 vapId 1 apVapId 1for this client
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.319: 8c:58:77:35:ae:03 Not Using WMM Compli
ance code qosCap 00
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.319: 8c:58:77:35:ae:03 10.3.10.8 L2AUTHCOMP
LETE (4) Plumbed mobile LWAPP rule on AP 24:b6:57:f8:c7:80 vapId 1 apVapId 1
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.319: 8c:58:77:35:ae:03 apfMsRunStateInc
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.319: 8c:58:77:35:ae:03 10.3.10.8 L2AUTHCOMP
LETE (4) Change state to RUN (20) last state RUN (20)
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.320: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) R
eached PLUMBFASTPATH: from line 4918
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.320: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) A
dding Fast Path rule
type = Airespace AP Client
on AP 24:b6:57:f8:c7:80, slot 0, interface = 13, QOS = 0
ACL Id = 255, Jumbo Frames = NO
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.320: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) F
ast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 10, I
Pv6 intf id = 11
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.320: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) S
uccessfully plumbed mobile rule (ACL ID 255)
*Dot1x_NW_MsgTask_3: Feb 20 08:54:34.320: 8c:58:77:35:ae:03 Stopping retransmiss
ion timer for mobile 8c:58:77:35:ae:03
*pemReceiveTask: Feb 20 08:54:34.320: 8c:58:77:35:ae:03 10.3.10.8 Added NPU entr
y of type 1, dtlFlags 0x0
*apfMsConnTask_7: Feb 20 08:54:48.531: 8c:58:77:35:ae:03 Reassociation received
from mobile on AP 64:a0:e7:dc:60:10
*apfMsConnTask_7: Feb 20 08:54:48.531: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Chan
ging ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1
626)
*apfMsConnTask_7: Feb 20 08:54:48.531: 8c:58:77:35:ae:03 Applying site-specific
IPv6 override for station 8c:58:77:35:ae:03 - vapId 1, site 'default-group', int
erface 'nysenatenet'
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 Applying IPv6 Interface
Policy for station 8c:58:77:35:ae:03 - vlan 10, interface id 11, interface 'nys
enatenet'
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 STA - rates (8): 130 13
2 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 STA - rates (12): 130 1
32 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 Processing RSN IE type
48, length 20 for mobile 8c:58:77:35:ae:03
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Dele
ted mobile LWAPP rule on AP [24:b6:57:f8:c7:80]
*pemReceiveTask: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 Removed NPU en
try.
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 Updated location for st
ation old AP 24:b6:57:f8:c7:80-0, new AP 64:a0:e7:dc:60:10-0
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 apfMsRunStateDec
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 apfMs1xStateDec
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Chan
ge state to START (0) last state RUN (20)
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 START (0) Ini
tializing policy
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 START (0) Cha
nge state to AUTHCHECK (2) last state RUN (20)
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 AUTHCHECK (2)
Change state to 8021X_REQD (3) last state RUN (20)
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 8021X_REQD (3
) DHCP required on AP 64:a0:e7:dc:60:10 vapId 1 apVapId 1for this client
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 Not Using WMM Complianc
e code qosCap 00
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 10.3.10.8 8021X_REQD (3
) Plumbed mobile LWAPP rule on AP 64:a0:e7:dc:60:10 vapId 1 apVapId 1
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 apfPemAddUser2 (apf_pol
icy.c:223) Changing state for mobile 8c:58:77:35:ae:03 on AP 64:a0:e7:dc:60:10 f
rom Associated to Associated
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 Scheduling deletion of
Mobile Station: (callerId: 49) in 43200 seconds
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 Sending Assoc Response
to station on BSSID 64:a0:e7:dc:60:10 (status 0) ApVapId 1 Slot 0
*apfMsConnTask_7: Feb 20 08:54:48.532: 8c:58:77:35:ae:03 apfProcessAssocReq (apf
_80211.c:5237) Changing state for mobile 8c:58:77:35:ae:03 on AP 64:a0:e7:dc:60:
10 from Associated to Associated
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 Creating a PKC PMKID Cache
entry for station 8c:58:77:35:ae:03 (RSN 2)
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 Adding BSSID 64:a0:e7:dc:6
0:10 to PMKID cache for station 8c:58:77:35:ae:03
*dot1xMsgTask: Feb 20 08:54:48.535: New PMKID: (16)
*dot1xMsgTask: Feb 20 08:54:48.535: [0000] 8e b3 87 c9 e6 cf 22 5c d6 86 91
fe b6 a5 fb fc
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 Initiating RSN PSK to mobi
le 8c:58:77:35:ae:03
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 dot1x - moving mobile 8c:5
8:77:35:ae:03 into Force Auth state
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 Skipping EAP-Success to mo
bile 8c:58:77:35:ae:03
*dot1xMsgTask: Feb 20 08:54:48.535: Including PMKID in M1 (16)
*dot1xMsgTask: Feb 20 08:54:48.535: [0000] 8e b3 87 c9 e6 cf 22 5c d6 86 91
fe b6 a5 fb fc
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 Starting key exchange to m
obile 8c:58:77:35:ae:03, data packets will be dropped
*dot1xMsgTask: Feb 20 08:54:48.535: 8c:58:77:35:ae:03 Sending EAPOL-Key Message
to mobile 8c:58:77:35:ae:03
state INITPMK (message 1), replay counter 00.00.00
.00.00.00.00.00
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.542: 8c:58:77:35:ae:03 Received EAPOL-Key f
rom mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.542: 8c:58:77:35:ae:03 Received EAPOL-key i
n PTK_START state (message 2) from mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.542: 8c:58:77:35:ae:03 Stopping retransmiss
ion timer for mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.542: 8c:58:77:35:ae:03 Sending EAPOL-Key Me
ssage to mobile 8c:58:77:35:ae:03
state PTKINITNEGOTIATING (message 3), replay
counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 Received EAPOL-Key f
rom mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 Received EAPOL-key i
n PTKINITNEGOTIATING state (message 4) from mobile 8c:58:77:35:ae:03
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 apfMs1xStateInc
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 10.3.10.8 8021X_REQD
(3) Change state to L2AUTHCOMPLETE (4) last state RUN (20)
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 10.3.10.8 L2AUTHCOMP
LETE (4) DHCP required on AP 64:a0:e7:dc:60:10 vapId 1 apVapId 1for this client
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 Not Using WMM Compli
ance code qosCap 00
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 10.3.10.8 L2AUTHCOMP
LETE (4) Plumbed mobile LWAPP rule on AP 64:a0:e7:dc:60:10 vapId 1 apVapId 1
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 apfMsRunStateInc
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.549: 8c:58:77:35:ae:03 10.3.10.8 L2AUTHCOMP
LETE (4) Change state to RUN (20) last state RUN (20)
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.550: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) R
eached PLUMBFASTPATH: from line 4918
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.551: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) A
dding Fast Path rule
type = Airespace AP Client
on AP 64:a0:e7:dc:60:10, slot 0, interface = 13, QOS = 0
ACL Id = 255, Jumbo Frames = NO
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.551: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) F
ast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 7006 IPv6 Vlan = 10, I
Pv6 intf id = 11
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.551: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) S
uccessfully plumbed mobile rule (ACL ID 255)
*Dot1x_NW_MsgTask_3: Feb 20 08:54:48.551: 8c:58:77:35:ae:03 Stopping retransmiss
ion timer for mobile 8c:58:77:35:ae:03
*pemReceiveTask: Feb 20 08:54:48.551: 8c:58:77:35:ae:03 10.3.10.8 Added NPU entr
y of type 1, dtlFlags 0x0
*osapiBsnTimer: Feb 20 09:24:48.414: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 09:54:48.202: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 10:24:48.214: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 10:54:47.993: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 11:24:47.997: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 11:54:47.761: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 12:24:47.777: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 12:54:47.551: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 13:24:47.559: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 13:54:47.334: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*osapiBsnTimer: Feb 20 14:24:47.350: 8c:58:77:35:ae:03 802.1x 'reAuthWhen' Timer
expired for station 8c:58:77:35:ae:03 and for message = M4
*dot1xMsgTask: Feb 20 14:46:49.523: 8c:58:77:35:ae:03 Key exchange done, data pa
ckets from mobile 8c:58:77:35:ae:03 should be forwarded shortly
*dot1xMsgTask: Feb 20 14:46:49.523: 8c:58:77:35:ae:03 Sending EAPOL-Key Message
to mobile 8c:58:77:35:ae:03
state PTKINITDONE (message 5 - group), replay coun
ter 00.00.00.00.00.00.00.02
*dot1xMsgTask: Feb 20 14:46:49.523: 8c:58:77:35:ae:03 Updated broadcast key sent
to mobile 8C:58:77:35:AE:03
*osapiBsnTimer: Feb 20 14:46:50.722: 8c:58:77:35:ae:03 802.1x 'timeoutEvt' Timer
expired for station 8c:58:77:35:ae:03 and for message = M5
*dot1xMsgTask: Feb 20 14:46:50.723: 8c:58:77:35:ae:03 Retransmit 1 of EAPOL-Key
M5 (length 147) for mobile 8c:58:77:35:ae:03
*osapiBsnTimer: Feb 20 14:46:51.722: 8c:58:77:35:ae:03 802.1x 'timeoutEvt' Timer
expired for station 8c:58:77:35:ae:03 and for message = M5
*dot1xMsgTask: Feb 20 14:46:51.723: 8c:58:77:35:ae:03 Retransmit 2 of EAPOL-Key
M5 (length 147) for mobile 8c:58:77:35:ae:03
*osapiBsnTimer: Feb 20 14:46:52.722: 8c:58:77:35:ae:03 802.1x 'timeoutEvt' Timer
expired for station 8c:58:77:35:ae:03 and for message = M5
*dot1xMsgTask: Feb 20 14:46:52.723: 8c:58:77:35:ae:03 Retransmit failure for EAP
OL-Key M5 to mobile 8c:58:77:35:ae:03, retransmit count 3, mscb deauth count 0
*dot1xMsgTask: Feb 20 14:46:52.724: 8c:58:77:35:ae:03 Sent Deauthenticate to mob
ile on BSSID 64:a0:e7:dc:60:10 slot 0(caller 1x_ptsm.c:534)
*dot1xMsgTask: Feb 20 14:46:52.724: 8c:58:77:35:ae:03 Scheduling deletion of Mob
ile Station: (callerId: 57) in 10 seconds
*osapiBsnTimer: Feb 20 14:47:02.722: 8c:58:77:35:ae:03 apfMsExpireCallback (apf_
ms.c:608) Expiring Mobile!
*apfReceiveTask: Feb 20 14:47:02.723: 8c:58:77:35:ae:03 apfMsExpireMobileStation
(apf_ms.c:5009) Changing state for mobile 8c:58:77:35:ae:03 on AP 64:a0:e7:dc:6
0:10 from Associated to Disassociated
*apfReceiveTask: Feb 20 14:47:02.723: 8c:58:77:35:ae:03 Scheduling deletion of M
obile Station: (callerId: 45) in 10 seconds
*osapiBsnTimer: Feb 20 14:47:12.722: 8c:58:77:35:ae:03 apfMsExpireCallback (apf_
ms.c:608) Expiring Mobile!
*apfReceiveTask: Feb 20 14:47:12.724: 8c:58:77:35:ae:03 Sent Deauthenticate to m
obile on BSSID 64:a0:e7:dc:60:10 slot 0(caller apf_ms.c:5101)
*apfReceiveTask: Feb 20 14:47:12.724: 8c:58:77:35:ae:03 apfMsAssoStateDec
*apfReceiveTask: Feb 20 14:47:12.724: 8c:58:77:35:ae:03 apfMsExpireMobileStation
(apf_ms.c:5139) Changing state for mobile 8c:58:77:35:ae:03 on AP 64:a0:e7:dc:6
0:10 from Disassociated to Idle
*apfReceiveTask: Feb 20 14:47:12.724: 8c:58:77:35:ae:03 Scheduling deletion of M
obile Station: (callerId: 47) in 10 seconds
*osapiBsnTimer: Feb 20 14:47:22.722: 8c:58:77:35:ae:03 apfMsExpireCallback (apf_
ms.c:608) Expiring Mobile!
*apfReceiveTask: Feb 20 14:47:22.724: 8c:58:77:35:ae:03 10.3.10.8 RUN (20) Delet
ed mobile LWAPP rule on AP [64:a0:e7:dc:60:10]
*apfReceiveTask: Feb 20 14:47:22.724: 8c:58:77:35:ae:03 apfMsRunStateDec
*apfReceiveTask: Feb 20 14:47:22.724: 8c:58:77:35:ae:03 apfMs1xStateDec
*apfReceiveTask: Feb 20 14:47:22.724: 8c:58:77:35:ae:03 Deleting mobile on AP 64
:a0:e7:dc:60:10(0)
</pre>
iPhone 4, iOS 5.0.1, seeing this also with iPad2