debug eap authenticator all
debug dot1x all

Jan 23 14:32:34.271: dot1x-ev:[Gi1/0/34] Interface state changed to DOWN
Jan 23 14:32:34.271: dot1x-ev:[Gi1/0/34] Clearing all supplicant instances
Jan 23 14:32:35.261: %LINEPROTO-5-UPDOWN: Line protocol on Interface GigabitEthernet1/0/34, changed state to down
Jan 23 14:32:36.264: %LINK-3-UPDOWN: Interface GigabitEthernet1/0/34, changed state to down
Jan 23 14:32:37.186: dot1x-ev:[Gi1/0/34] Interface state changed to UP
Jan 23 14:32:39.144: %LINK-3-UPDOWN: Interface GigabitEthernet1/0/34, changed state to up
Jan 23 14:32:40.147: %LINEPROTO-5-UPDOWN: Line protocol on Interface GigabitEthernet1/0/34, changed state to up
Jan 23 14:32:43.342:     dot1x_auth Gi1/0/34: initial state auth_initialize has enter
Jan 23 14:32:43.342: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: initialising
Jan 23 14:32:43.342:     dot1x_auth Gi1/0/34: during state auth_initialize, got event 0(cfg_auto)
Jan 23 14:32:43.342: @@@ dot1x_auth Gi1/0/34: auth_initialize -> auth_disconnected
Jan 23 14:32:43.342: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: disconnected
Jan 23 14:32:43.342:     dot1x_auth Gi1/0/34: idle during state auth_disconnected
Jan 23 14:32:43.345: @@@ dot1x_auth Gi1/0/34: auth_disconnected -> auth_restart
Jan 23 14:32:43.345: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering restart
Jan 23 14:32:43.345: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending create new context event to EAP for 0xFE00044A (507b.9d62.e6e5)
Jan 23 14:32:43.345: EAP-EVENT: Received context create from LL (Dot1x-Authenticator) (0xFE00044A)
Jan 23 14:32:43.345: EAP-AUTH-EVENT: Received AAA ID 0x000006BA from LL
Jan 23 14:32:43.345: EAP-AUTH-AAA-EVENT: Assigning AAA ID 0x000006BA
Jan 23 14:32:43.345: EAP-AUTH-AAA-EVENT: CTS not enabled on interface Gi1/0/34
Jan 23 14:32:43.345: EAP-AUTH-EVENT: Received Session ID "0A07CC11000006BA0C1B90D9" from LL
Jan 23 14:32:43.345: EAP-AUTH-EVENT: Setting authentication mode: Passthrough
Jan 23 14:32:43.345:     eap_authen : initial state eap_auth_initialize has enter
Jan 23 14:32:43.345: EAP-EVENT: Allocated new EAP context (handle = 0x46000D15)
Jan 23 14:32:43.345:     dot1x_auth_bend Gi1/0/34: initial state auth_bend_initialize has enter
Jan 23 14:32:43.345: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering init state
Jan 23 14:32:43.345:     dot1x_auth_bend Gi1/0/34: initial state auth_bend_initialize has idle
Jan 23 14:32:43.345:     dot1x_auth_bend Gi1/0/34: during state auth_bend_initialize, got event 16383(idle)
Jan 23 14:32:43.345: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_initialize -> auth_bend_idle
Jan 23 14:32:43.345: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering idle state
Jan 23 14:32:43.345: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Created a client entry (0xFE00044A)
Jan 23 14:32:43.345: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Dot1x authentication started for 0xFE00044A (507b.9d62.e6e5)
Jan 23 14:32:43.345: EAP-EVENT: Received EAP event 'EAP_AUTHENTICATOR_START' on handle 0x46000D15
Jan 23 14:32:43.345:     eap_authen : during state eap_auth_initialize, got event 25(eapStartTmo)
Jan 23 14:32:43.345: @@@ eap_authen : eap_auth_initialize -> eap_auth_select_action
Jan 23 14:32:43.345:     eap_authen : during state eap_auth_select_action, got event 20(eapDecisionPropose)
Jan 23 14:32:43.345: @@@ eap_authen : eap_auth_select_action -> eap_auth_propose_method
Jan 23 14:32:43.345:     eap_authen : idle during state eap_auth_propose_method
Jan 23 14:32:43.345: @@@ eap_authen : eap_auth_propose_method -> eap_auth_method_request
Jan 23 14:32:43.345:     eap_authen : idle during state eap_auth_method_request
Jan 23 14:32:43.345: @@@ eap_authen : eap_auth_method_request -> eap_auth_tx_packet
Jan 23 14:32:43.345: EAP-AUTH-EVENT: Current method = Identity
Jan 23 14:32:43.345: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_ID_REQUEST' on handle 0x46000D15
Jan 23 14:32:43.349:     eap_authen : idle during state eap_auth_tx_packet
Jan 23 14:32:43.349: @@@ eap_authen : eap_auth_tx_packet -> eap_auth_idle
Jan 23 14:32:43.349: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x1   Length:0x0005  Type:IDENTITY
Jan 23 14:32:43.349: EAP-EVENT: Started 'Authenticator ReqId Retransmit' timer (10s) for EAP sesion handle 0x46000D15
Jan 23 14:32:43.349: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:43.349: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x46000D15
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting !EAP_RESTART on Client 0xFE00044A
Jan 23 14:32:43.349:     dot1x_auth Gi1/0/34: during state auth_restart, got event 6(no_eapRestart)
Jan 23 14:32:43.349: @@@ dot1x_auth Gi1/0/34: auth_restart -> auth_connecting
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:enter connecting state
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: restart connecting
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting RX_REQ on Client 0xFE00044A
Jan 23 14:32:43.349:     dot1x_auth Gi1/0/34: during state auth_connecting, got event 10(eapReq_no_reAuthMax)
Jan 23 14:32:43.349: @@@ dot1x_auth Gi1/0/34: auth_connecting -> auth_authenticating
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: authenticating state entered
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:connecting authenticating action
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting AUTH_START for 0xFE00044A
Jan 23 14:32:43.349:     dot1x_auth_bend Gi1/0/34: during state auth_bend_idle, got event 4(eapReq_authStart)
Jan 23 14:32:43.349: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_idle -> auth_bend_request
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:43.349: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:43.349: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:43.349: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:43.349: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:43.349: dot1x-packet: length: 0x0005
Jan 23 14:32:43.349: dot1x-packet:EAP code: 0x1  id: 0x1  length: 0x0005
Jan 23 14:32:43.349: dot1x-packet: type: 0x1
Jan 23 14:32:43.349: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:43.349: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:idle request action
Jan 23 14:32:44.576: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] queuing an EAPOL pkt on Auth Q
Jan 23 14:32:44.576: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x1
Jan 23 14:32:44.576: dot1x-packet: length: 0x0000
Jan 23 14:32:44.576: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 0,TYPE= 0,LEN= 0

Jan 23 14:32:44.576: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0101.0000
Jan 23 14:32:44.576: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Received an EAPOL-Start packet
Jan 23 14:32:44.576: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x1
Jan 23 14:32:44.576: dot1x-packet: length: 0x0000
Jan 23 14:32:44.576: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_START on Client 0xFE00044A
Jan 23 14:32:44.576:     dot1x_auth Gi1/0/34: during state auth_authenticating, got event 4(eapolStart)
Jan 23 14:32:44.576: @@@ dot1x_auth Gi1/0/34: auth_authenticating -> auth_aborting
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting authenticating state
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering aborting state
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting AUTH_ABORT for 0xFE00044A
Jan 23 14:32:44.579:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 1(authAbort)
Jan 23 14:32:44.579: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_initialize
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering init state
Jan 23 14:32:44.579:     dot1x_auth_bend Gi1/0/34: idle during state auth_bend_initialize
Jan 23 14:32:44.579: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_initialize -> auth_bend_idle
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering idle state
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting !AUTH_ABORT on Client 0xFE00044A
Jan 23 14:32:44.579:     dot1x_auth Gi1/0/34: during state auth_aborting, got event 20(no_eapolLogoff_no_authAbort)
Jan 23 14:32:44.579: @@@ dot1x_auth Gi1/0/34: auth_aborting -> auth_restart
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting aborting state
Jan 23 14:32:44.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering restart
Jan 23 14:32:44.579: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Resetting the client 0xFE00044A
Jan 23 14:32:44.579: EAP-EVENT: Received free context (0x46000D15) from LL (Dot1x-Authenticator)
Jan 23 14:32:44.579: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending create new context event to EAP for 0xFE00044A (507b.9d62.e6e5)
Jan 23 14:32:44.579: EAP-EVENT: Received context create from LL (Dot1x-Authenticator) (0xFE00044A)
Jan 23 14:32:44.579: EAP-AUTH-EVENT: Received AAA ID 0x000006BA from LL
Jan 23 14:32:44.579: EAP-AUTH-AAA-EVENT: Assigning AAA ID 0x000006BA
Jan 23 14:32:44.579: EAP-AUTH-AAA-EVENT: CTS not enabled on interface Gi1/0/34
Jan 23 14:32:44.579: EAP-AUTH-EVENT: Received Session ID "0A07CC11000006BA0C1B90D9" from LL
Jan 23 14:32:44.579: EAP-AUTH-EVENT: Setting authentication mode: Passthrough
Jan 23 14:32:44.583:     eap_authen : initial state eap_auth_initialize has enter
Jan 23 14:32:44.583: EAP-EVENT: Allocated new EAP context (handle = 0x01000D16)
Jan 23 14:32:44.583: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:restart action for aborting state
Jan 23 14:32:44.583: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting !EAP_RESTART on Client 0xFE00044A
Jan 23 14:32:44.583:     dot1x_auth Gi1/0/34: during state auth_restart, got event 6(no_eapRestart)
Jan 23 14:32:44.583: @@@ dot1x_auth Gi1/0/34: auth_restart -> auth_connecting
Jan 23 14:32:44.583: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:enter connecting state
Jan 23 14:32:44.583: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: restart connecting
Jan 23 14:32:44.583: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_DELETE' on handle 0x46000D15
Jan 23 14:32:44.583: EAP-EVENT: Stopping 'Authenticator ReqId Retransmit' timer for EAP sesion handle 0x46000D15
Jan 23 14:32:44.583: EAP-AUTH-EVENT: Freed EAP auth context
Jan 23 14:32:44.583: EAP-EVENT: Freed EAP context
Jan 23 14:32:44.583: EAP-EVENT: Received EAP event 'EAP_AUTHENTICATOR_START' on handle 0x01000D16
Jan 23 14:32:44.583:     eap_authen : during state eap_auth_initialize, got event 25(eapStartTmo)
Jan 23 14:32:44.583: @@@ eap_authen : eap_auth_initialize -> eap_auth_select_action
Jan 23 14:32:44.583:     eap_authen : during state eap_auth_select_action, got event 20(eapDecisionPropose)
Jan 23 14:32:44.583: @@@ eap_authen : eap_auth_select_action -> eap_auth_propose_method
Jan 23 14:32:44.583:     eap_authen : idle during state eap_auth_propose_method
Jan 23 14:32:44.583: @@@ eap_authen : eap_auth_propose_method -> eap_auth_method_request
Jan 23 14:32:44.583:     eap_authen : idle during state eap_auth_method_request
Jan 23 14:32:44.583: @@@ eap_authen : eap_auth_method_request -> eap_auth_tx_packet
Jan 23 14:32:44.583: EAP-AUTH-EVENT: Current method = Identity
Jan 23 14:32:44.583: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_ID_REQUEST' on handle 0x01000D16
Jan 23 14:32:44.583:     eap_authen : idle during state eap_auth_tx_packet
Jan 23 14:32:44.583: @@@ eap_authen : eap_auth_tx_packet -> eap_auth_idle
Jan 23 14:32:44.583: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x1   Length:0x0005  Type:IDENTITY
Jan 23 14:32:44.583: EAP-EVENT: Started 'Authenticator ReqId Retransmit' timer (10s) for EAP sesion handle 0x01000D16
Jan 23 14:32:44.583: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:44.583: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.583: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting RX_REQ on Client 0xFE00044A
Jan 23 14:32:44.583:     dot1x_auth Gi1/0/34: during state auth_connecting, got event 10(eapReq_no_reAuthMax)
Jan 23 14:32:44.586: @@@ dot1x_auth Gi1/0/34: auth_connecting -> auth_authenticating
Jan 23 14:32:44.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: authenticating state entered
Jan 23 14:32:44.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:connecting authenticating action
Jan 23 14:32:44.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting AUTH_START for 0xFE00044A
Jan 23 14:32:44.586:     dot1x_auth_bend Gi1/0/34: during state auth_bend_idle, got event 4(eapReq_authStart)
Jan 23 14:32:44.586: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_idle -> auth_bend_request
Jan 23 14:32:44.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:44.586: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:44.586: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:44.586: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:44.586: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:44.586: dot1x-packet: length: 0x0005
Jan 23 14:32:44.586: dot1x-packet:EAP code: 0x1  id: 0x1  length: 0x0005
Jan 23 14:32:44.586: dot1x-packet: type: 0x1
Jan 23 14:32:44.586: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:44.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:idle request action
Jan 23 14:32:44.652: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Queuing an EAPOL pkt on Authenticator Q
Jan 23 14:32:44.652: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.652: dot1x-packet: length: 0x0031
Jan 23 14:32:44.656: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 2,TYPE= 1,LEN= 49

Jan 23 14:32:44.656: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0100.0031
Jan 23 14:32:44.656: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.656: dot1x-packet: length: 0x0031
Jan 23 14:32:44.656: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_EAP for 0xFE00044A
Jan 23 14:32:44.656:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 6(eapolEap)
Jan 23 14:32:44.656: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_response
Jan 23 14:32:44.656: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering response state
Jan 23 14:32:44.656: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Response sent to the server from 0xFE00044A
Jan 23 14:32:44.656: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request response action
Jan 23 14:32:44.656: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.656: EAP-AUTH-RX-PAK: Code:RESPONSE  ID:0x1   Length:0x0031  Type:IDENTITY
Jan 23 14:32:44.656:     Payload:  686F73742F425259414E414852454E53 ...
Jan 23 14:32:44.656:     eap_authen : during state eap_auth_idle, got event 1(eapRxPacket)
Jan 23 14:32:44.656: @@@ eap_authen : eap_auth_idle -> eap_auth_received
Jan 23 14:32:44.656: EAP-AUTH-EVENT: EAP Response received by context 0x01000D16
Jan 23 14:32:44.656: EAP-AUTH-EVENT: EAP Response type = Identity
Jan 23 14:32:44.656: EAP-EVENT: Stopping 'Authenticator ReqId Retransmit' timer for EAP sesion handle 0x01000D16
Jan 23 14:32:44.656:     eap_authen : during state eap_auth_received, got event 10(eapMethodData)
Jan 23 14:32:44.656: @@@ eap_authen : eap_auth_received -> eap_auth_method_response
Jan 23 14:32:44.656: EAP-AUTH-EVENT: Received peer identity: host/BRYANAHRENSX250.andent.andersonsinc.com
Jan 23 14:32:44.656: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_IDENTITY' on handle 0x01000D16
Jan 23 14:32:44.656:     eap_authen : during state eap_auth_method_response, got event 13(eapMethodEnd)
Jan 23 14:32:44.656: @@@ eap_authen : eap_auth_method_response -> eap_auth_select_action
Jan 23 14:32:44.656:     eap_authen : during state eap_auth_select_action, got event 19(eapDecisionPass)
Jan 23 14:32:44.656: @@@ eap_authen : eap_auth_select_action -> eap_auth_passthru_init
Jan 23 14:32:44.656:     eap_authen : during state eap_auth_passthru_init, got event 22(eapPthruIdentity)
Jan 23 14:32:44.656: @@@ eap_authen : eap_auth_passthru_init -> eap_auth_aaa_req
Jan 23 14:32:44.656: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_GET_PEER_MAC_ADDRESS' on handle 0x01000D16
Jan 23 14:32:44.656: EAP-AUTH-AAA-EVENT: Adding Audit-Session-ID "0A07CC11000006BA0C1B90D9" to RADIUS Req
Jan 23 14:32:44.656: EAP-AUTH-AAA-EVENT: Added Audit-Session-ID
Jan 23 14:32:44.659: EAP-AUTH-AAA-EVENT: Adding IDB "0x07A01E10" to RADIUS Req
Jan 23 14:32:44.659: EAP-AUTH-AAA-EVENT: Added IDB
Jan 23 14:32:44.659: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_AAA_REQUEST' on handle 0x01000D16
Jan 23 14:32:44.659: EAP-AUTH-AAA-EVENT: eap_auth_aaa_authen_request_shim aaa_service 19, eap aaa_list handle 0, mlist handle 0
Jan 23 14:32:44.659: EAP-AUTH-AAA-EVENT: Request sent successfully
Jan 23 14:32:44.659:     eap_authen : during state eap_auth_aaa_req, got event 24(eapAAAReqOk)
Jan 23 14:32:44.659: @@@ eap_authen : eap_auth_aaa_req -> eap_auth_aaa_idle
Jan 23 14:32:44.719: EAP-EVENT: eap_aaa_reply
Jan 23 14:32:44.719: EAP-AUTH-AAA-EVENT: Reply received session_label 5C0000C1
Jan 23 14:32:44.719: EAP-AUTH-AAA-EVENT: Response contains EAP Message, code: 1
Jan 23 14:32:44.719: EAP-EVENT: Received AAA event 'EAP_AAA_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.719: EAP-AUTH-RX-AAA-PAK: Code:REQUEST  ID:0x58  Length:0x0006  Type:13
Jan 23 14:32:44.719:     Payload:  20
Jan 23 14:32:44.719:     eap_authen : during state eap_auth_aaa_idle, got event 5(eapAAARxPacket)
Jan 23 14:32:44.719: @@@ eap_authen : eap_auth_aaa_idle -> eap_auth_aaa_resp
Jan 23 14:32:44.719:     eap_authen : idle during state eap_auth_aaa_resp
Jan 23 14:32:44.719: @@@ eap_authen : eap_auth_aaa_resp -> eap_auth_tx_packet2
Jan 23 14:32:44.719: EAP-AUTH-EVENT: Current method = 13
Jan 23 14:32:44.719:     eap_authen : idle during state eap_auth_tx_packet2
Jan 23 14:32:44.719: @@@ eap_authen : eap_auth_tx_packet2 -> eap_auth_idle2
Jan 23 14:32:44.719: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x58  Length:0x0006  Type:13
Jan 23 14:32:44.719:     Payload:  20
Jan 23 14:32:44.719: EAP-EVENT: Started 'Authenticator Retransmit' timer (30s) for EAP sesion handle 0x01000D16
Jan 23 14:32:44.719: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:44.719: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.719: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAP_REQ for 0xFE00044A
Jan 23 14:32:44.719:     dot1x_auth_bend Gi1/0/34: during state auth_bend_response, got event 7(eapReq)
Jan 23 14:32:44.719: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_response -> auth_bend_request
Jan 23 14:32:44.719: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting response state
Jan 23 14:32:44.719: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:44.722: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:44.722: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:44.722: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:44.722: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:44.722: dot1x-packet: length: 0x0006
Jan 23 14:32:44.722: dot1x-packet:EAP code: 0x1  id: 0x58 length: 0x0006
Jan 23 14:32:44.722: dot1x-packet: type: 0xD
Jan 23 14:32:44.722: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:44.722: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:response request action
Jan 23 14:32:44.733: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Queuing an EAPOL pkt on Authenticator Q
Jan 23 14:32:44.733: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.733: dot1x-packet: length: 0x0006
Jan 23 14:32:44.733: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 2,TYPE= 3,LEN= 6

Jan 23 14:32:44.733: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0100.0006
Jan 23 14:32:44.733: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.733: dot1x-packet: length: 0x0006
Jan 23 14:32:44.733: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_EAP for 0xFE00044A
Jan 23 14:32:44.736:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 6(eapolEap)
Jan 23 14:32:44.736: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_response
Jan 23 14:32:44.736: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering response state
Jan 23 14:32:44.736: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Response sent to the server from 0xFE00044A
Jan 23 14:32:44.736: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request response action
Jan 23 14:32:44.736: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.736: EAP-AUTH-RX-PAK: Code:RESPONSE  ID:0x58  Length:0x0006  Type:NAK
Jan 23 14:32:44.736:     Payload:  19
Jan 23 14:32:44.736:     eap_authen : during state eap_auth_idle2, got event 1(eapRxPacket)
Jan 23 14:32:44.736: @@@ eap_authen : eap_auth_idle2 -> eap_auth_received2
Jan 23 14:32:44.736: EAP-AUTH-EVENT: EAP Response received by context 0x01000D16
Jan 23 14:32:44.736: EAP-AUTH-EVENT: EAP Response type = NAK
Jan 23 14:32:44.736: EAP-EVENT: Stopping 'Authenticator Retransmit' timer for EAP sesion handle 0x01000D16
Jan 23 14:32:44.736:     eap_authen : during state eap_auth_received2, got event 9(eapNak)
Jan 23 14:32:44.736: @@@ eap_authen : eap_auth_received2 -> eap_auth_aaa_req
Jan 23 14:32:44.736: EAP-AUTH-AAA-EVENT: Adding Audit-Session-ID "0A07CC11000006BA0C1B90D9" to RADIUS Req
Jan 23 14:32:44.736: EAP-AUTH-AAA-EVENT: Added Audit-Session-ID
Jan 23 14:32:44.736: EAP-AUTH-AAA-EVENT: Adding IDB "0x07A01E10" to RADIUS Req
Jan 23 14:32:44.736: EAP-AUTH-AAA-EVENT: Added IDB
Jan 23 14:32:44.736: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_AAA_REQUEST' on handle 0x01000D16
Jan 23 14:32:44.736: EAP-AUTH-AAA-EVENT: eap_auth_aaa_authen_request_shim aaa_service 19, eap aaa_list handle 0, mlist handle 0
Jan 23 14:32:44.736: EAP-AUTH-AAA-EVENT: Request sent successfully
Jan 23 14:32:44.736:     eap_authen : during state eap_auth_aaa_req, got event 24(eapAAAReqOk)
Jan 23 14:32:44.736: @@@ eap_authen : eap_auth_aaa_req -> eap_auth_aaa_idle
Jan 23 14:32:44.747: EAP-EVENT: eap_aaa_reply
Jan 23 14:32:44.747: EAP-AUTH-AAA-EVENT: Reply received session_label 5C0000C1
Jan 23 14:32:44.747: EAP-AUTH-AAA-EVENT: Response contains EAP Message, code: 1
Jan 23 14:32:44.750: EAP-EVENT: Received AAA event 'EAP_AAA_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.750: EAP-AUTH-RX-AAA-PAK: Code:REQUEST  ID:0x59  Length:0x0006  Type:25
Jan 23 14:32:44.750:     Payload:  21
Jan 23 14:32:44.750:     eap_authen : during state eap_auth_aaa_idle, got event 5(eapAAARxPacket)
Jan 23 14:32:44.750: @@@ eap_authen : eap_auth_aaa_idle -> eap_auth_aaa_resp
Jan 23 14:32:44.750:     eap_authen : idle during state eap_auth_aaa_resp
Jan 23 14:32:44.750: @@@ eap_authen : eap_auth_aaa_resp -> eap_auth_tx_packet2
Jan 23 14:32:44.750: EAP-AUTH-EVENT: Current method = 25
Jan 23 14:32:44.750:     eap_authen : idle during state eap_auth_tx_packet2
Jan 23 14:32:44.750: @@@ eap_authen : eap_auth_tx_packet2 -> eap_auth_idle2
Jan 23 14:32:44.750: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x59  Length:0x0006  Type:25
Jan 23 14:32:44.750:     Payload:  21
Jan 23 14:32:44.750: EAP-EVENT: Started 'Authenticator Retransmit' timer (30s) for EAP sesion handle 0x01000D16
Jan 23 14:32:44.750: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:44.750: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.750: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAP_REQ for 0xFE00044A
Jan 23 14:32:44.750:     dot1x_auth_bend Gi1/0/34: during state auth_bend_response, got event 7(eapReq)
Jan 23 14:32:44.750: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_response -> auth_bend_request
Jan 23 14:32:44.750: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting response state
Jan 23 14:32:44.750: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:44.750: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:44.750: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:44.750: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:44.750: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:44.750: dot1x-packet: length: 0x0006
Jan 23 14:32:44.750: dot1x-packet:EAP code: 0x1  id: 0x59 length: 0x0006
Jan 23 14:32:44.750: dot1x-packet: type: 0x19
Jan 23 14:32:44.750: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:44.754: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:response request action
Jan 23 14:32:44.785: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Queuing an EAPOL pkt on Authenticator Q
Jan 23 14:32:44.789: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.789: dot1x-packet: length: 0x0071
Jan 23 14:32:44.789: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 2,TYPE= 25,LEN= 113

Jan 23 14:32:44.789: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0100.0071
Jan 23 14:32:44.789: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.789: dot1x-packet: length: 0x0071
Jan 23 14:32:44.789: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_EAP for 0xFE00044A
Jan 23 14:32:44.789:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 6(eapolEap)
Jan 23 14:32:44.789: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_response
Jan 23 14:32:44.789: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering response state
Jan 23 14:32:44.789: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Response sent to the server from 0xFE00044A
Jan 23 14:32:44.789: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request response action
Jan 23 14:32:44.789: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.789: EAP-AUTH-RX-PAK: Code:RESPONSE  ID:0x59  Length:0x0071  Type:25
Jan 23 14:32:44.789:     Payload:  800000006716030100620100005E0301 ...
Jan 23 14:32:44.789:     eap_authen : during state eap_auth_idle2, got event 1(eapRxPacket)
Jan 23 14:32:44.789: @@@ eap_authen : eap_auth_idle2 -> eap_auth_received2
Jan 23 14:32:44.789: EAP-AUTH-EVENT: EAP Response received by context 0x01000D16
Jan 23 14:32:44.789: EAP-AUTH-EVENT: EAP Response type = Method (25)
Jan 23 14:32:44.789: EAP-EVENT: Stopping 'Authenticator Retransmit' timer for EAP sesion handle 0x01000D16
Jan 23 14:32:44.789:     eap_authen : during state eap_auth_received2, got event 10(eapMethodData)
Jan 23 14:32:44.789: @@@ eap_authen : eap_auth_received2 -> eap_auth_aaa_req
Jan 23 14:32:44.789: EAP-AUTH-AAA-EVENT: Adding Audit-Session-ID "0A07CC11000006BA0C1B90D9" to RADIUS Req
Jan 23 14:32:44.792: EAP-AUTH-AAA-EVENT: Added Audit-Session-ID
Jan 23 14:32:44.792: EAP-AUTH-AAA-EVENT: Adding IDB "0x07A01E10" to RADIUS Req
Jan 23 14:32:44.792: EAP-AUTH-AAA-EVENT: Added IDB
Jan 23 14:32:44.792: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_AAA_REQUEST' on handle 0x01000D16
Jan 23 14:32:44.792: EAP-AUTH-AAA-EVENT: eap_auth_aaa_authen_request_shim aaa_service 19, eap aaa_list handle 0, mlist handle 0
Jan 23 14:32:44.792: EAP-AUTH-AAA-EVENT: Request sent successfully
Jan 23 14:32:44.792:     eap_authen : during state eap_auth_aaa_req, got event 24(eapAAAReqOk)
Jan 23 14:32:44.792: @@@ eap_authen : eap_auth_aaa_req -> eap_auth_aaa_idle
Jan 23 14:32:44.834: EAP-EVENT: eap_aaa_reply
Jan 23 14:32:44.834: EAP-AUTH-AAA-EVENT: Reply received session_label 5C0000C1
Jan 23 14:32:44.834: EAP-AUTH-AAA-EVENT: Response contains EAP Message, code: 1
Jan 23 14:32:44.834: EAP-EVENT: Received AAA event 'EAP_AAA_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.834: EAP-AUTH-RX-AAA-PAK: Code:REQUEST  ID:0x5A  Length:0x03F4  Type:25
Jan 23 14:32:44.834:     Payload:  C0000014F51603010054020000500301 ...
Jan 23 14:32:44.834:     eap_authen : during state eap_auth_aaa_idle, got event 5(eapAAARxPacket)
Jan 23 14:32:44.834: @@@ eap_authen : eap_auth_aaa_idle -> eap_auth_aaa_resp
Jan 23 14:32:44.834:     eap_authen : idle during state eap_auth_aaa_resp
Jan 23 14:32:44.838: @@@ eap_authen : eap_auth_aaa_resp -> eap_auth_tx_packet2
Jan 23 14:32:44.838: EAP-AUTH-EVENT: Current method = 25
Jan 23 14:32:44.838:     eap_authen : idle during state eap_auth_tx_packet2
Jan 23 14:32:44.838: @@@ eap_authen : eap_auth_tx_packet2 -> eap_auth_idle2
Jan 23 14:32:44.838: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x5A  Length:0x03F4  Type:25
Jan 23 14:32:44.838:     Payload:  C0000014F51603010054020000500301 ...
Jan 23 14:32:44.838: EAP-EVENT: Started 'Authenticator Retransmit' timer (30s) for EAP sesion handle 0x01000D16
Jan 23 14:32:44.838: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:44.838: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.848: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAP_REQ for 0xFE00044A
Jan 23 14:32:44.848:     dot1x_auth_bend Gi1/0/34: during state auth_bend_response, got event 7(eapReq)
Jan 23 14:32:44.848: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_response -> auth_bend_request
Jan 23 14:32:44.848: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting response state
Jan 23 14:32:44.848: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:44.848: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:44.848: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:44.848: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:44.848: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:44.848: dot1x-packet: length: 0x03F4
Jan 23 14:32:44.848: dot1x-packet:EAP code: 0x1  id: 0x5A length: 0x03F4
Jan 23 14:32:44.848: dot1x-packet: type: 0x19
Jan 23 14:32:44.848: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:44.848: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:response request action
Jan 23 14:32:44.855: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Queuing an EAPOL pkt on Authenticator Q
Jan 23 14:32:44.855: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.855: dot1x-packet: length: 0x0006
Jan 23 14:32:44.855: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 2,TYPE= 25,LEN= 6

Jan 23 14:32:44.855: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0100.0006
Jan 23 14:32:44.855: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:44.855: dot1x-packet: length: 0x0006
Jan 23 14:32:44.855: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_EAP for 0xFE00044A
Jan 23 14:32:44.855:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 6(eapolEap)
Jan 23 14:32:44.855: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_response
Jan 23 14:32:44.855: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering response state
Jan 23 14:32:44.855: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Response sent to the server from 0xFE00044A
Jan 23 14:32:44.855: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request response action
Jan 23 14:32:44.855: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.855: EAP-AUTH-RX-PAK: Code:RESPONSE  ID:0x5A  Length:0x0006  Type:25
Jan 23 14:32:44.855:     Payload:  00
Jan 23 14:32:44.855:     eap_authen : during state eap_auth_idle2, got event 1(eapRxPacket)
Jan 23 14:32:44.855: @@@ eap_authen : eap_auth_idle2 -> eap_auth_received2
Jan 23 14:32:44.855: EAP-AUTH-EVENT: EAP Response received by context 0x01000D16
Jan 23 14:32:44.855: EAP-AUTH-EVENT: EAP Response type = Method (25)
Jan 23 14:32:44.855: EAP-EVENT: Stopping 'Authenticator Retransmit' timer for EAP sesion handle 0x01000D16
Jan 23 14:32:44.855:     eap_authen : during state eap_auth_received2, got event 10(eapMethodData)
Jan 23 14:32:44.855: @@@ eap_authen : eap_auth_received2 -> eap_auth_aaa_req
Jan 23 14:32:44.855: EAP-AUTH-AAA-EVENT: Adding Audit-Session-ID "0A07CC11000006BA0C1B90D9" to RADIUS Req
Jan 23 14:32:44.859: EAP-AUTH-AAA-EVENT: Added Audit-Session-ID
Jan 23 14:32:44.859: EAP-AUTH-AAA-EVENT: Adding IDB "0x07A01E10" to RADIUS Req
Jan 23 14:32:44.859: EAP-AUTH-AAA-EVENT: Added IDB
Jan 23 14:32:44.859: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_AAA_REQUEST' on handle 0x01000D16
Jan 23 14:32:44.859: EAP-AUTH-AAA-EVENT: eap_auth_aaa_authen_request_shim aaa_service 19, eap aaa_list handle 0, mlist handle 0
Jan 23 14:32:44.859: EAP-AUTH-AAA-EVENT: Request sent successfully
Jan 23 14:32:44.859:     eap_authen : during state eap_auth_aaa_req, got event 24(eapAAAReqOk)
Jan 23 14:32:44.859: @@@ eap_authen : eap_auth_aaa_req -> eap_auth_aaa_idle
Jan 23 14:32:44.866: EAP-EVENT: eap_aaa_reply
Jan 23 14:32:44.866: EAP-AUTH-AAA-EVENT: Reply received session_label 5C0000C1
Jan 23 14:32:44.866: EAP-AUTH-AAA-EVENT: Response contains EAP Message, code: 1
Jan 23 14:32:44.866: EAP-EVENT: Received AAA event 'EAP_AAA_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.866: EAP-AUTH-RX-AAA-PAK: Code:REQUEST  ID:0x5B  Length:0x03F0  Type:25
Jan 23 14:32:44.866:     Payload:  400603551D0E04160414DA39A3EE5E6B ...
Jan 23 14:32:44.866:     eap_authen : during state eap_auth_aaa_idle, got event 5(eapAAARxPacket)
Jan 23 14:32:44.866: @@@ eap_authen : eap_auth_aaa_idle -> eap_auth_aaa_resp
Jan 23 14:32:44.866:     eap_authen : idle during state eap_auth_aaa_resp
Jan 23 14:32:44.866: @@@ eap_authen : eap_auth_aaa_resp -> eap_auth_tx_packet2
Jan 23 14:32:44.866: EAP-AUTH-EVENT: Current method = 25
Jan 23 14:32:44.866:     eap_authen : idle during state eap_auth_tx_packet2
Jan 23 14:32:44.866: @@@ eap_authen : eap_auth_tx_packet2 -> eap_auth_idle2
Jan 23 14:32:44.866: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x5B  Length:0x03F0  Type:25
Jan 23 14:32:44.866:     Payload:  400603551D0E04160414DA39A3EE5E6B ...
Jan 23 14:32:44.866: EAP-EVENT: Started 'Authenticator Retransmit' timer (30s) for EAP sesion handle 0x01000D16
Jan 23 14:32:44.866: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:44.866: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x01000D16
Jan 23 14:32:44.866: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAP_REQ for 0xFE00044A
Jan 23 14:32:44.866:     dot1x_auth_bend Gi1/0/34: during state auth_bend_response, got event 7(eapReq)
Jan 23 14:32:44.866: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_response -> auth_bend_request
Jan 23 14:32:44.869: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting response state
Jan 23 14:32:44.869: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:44.869: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:44.869: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:44.869: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:44.869: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:44.869: dot1x-packet: length: 0x03F0
Jan 23 14:32:44.869: dot1x-packet:EAP code: 0x1  id: 0x5B length: 0x03F0
Jan 23 14:32:44.869: dot1x-packet: type: 0x19
Jan 23 14:32:44.869: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:44.869: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:response request action
Jan 23 14:32:45.537: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Queuing an EAPOL pkt on Authenticator Q
Jan 23 14:32:45.558: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:45.558: dot1x-packet: length: 0x0006
Jan 23 14:32:45.558: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 2,TYPE= 25,LEN= 6

Jan 23 14:32:45.558: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0100.0006
Jan 23 14:32:45.558: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x0
Jan 23 14:32:45.558: dot1x-packet: length: 0x0006
Jan 23 14:32:45.572: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_EAP for 0xFE00044A
Jan 23 14:32:45.572:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 6(eapolEap)
Jan 23 14:32:45.572: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_response
Jan 23 14:32:45.572: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering response state
Jan 23 14:32:45.572: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Response sent to the server from 0xFE00044A
Jan 23 14:32:45.572: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request response action
Jan 23 14:32:45.572: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_RX_PACKET' on handle 0x01000D16
Jan 23 14:32:45.572: EAP-AUTH-RX-PAK: Code:RESPONSE  ID:0x5B  Length:0x0006  Type:25
Jan 23 14:32:45.572:     Payload:  00
Jan 23 14:32:45.572:     eap_authen : during state eap_auth_idle2, got event 1(eapRxPacket)
Jan 23 14:32:45.572: @@@ eap_authen : eap_auth_idle2 -> eap_auth_received2
Jan 23 14:32:45.572: EAP-AUTH-EVENT: EAP Response received by context 0x01000D16
Jan 23 14:32:45.572: EAP-AUTH-EVENT: EAP Response type = Method (25)
Jan 23 14:32:45.572: EAP-EVENT: Stopping 'Authenticator Retransmit' timer for EAP sesion handle 0x01000D16
Jan 23 14:32:45.572:     eap_authen : during state eap_auth_received2, got event 10(eapMethodData)
Jan 23 14:32:45.572: @@@ eap_authen : eap_auth_received2 -> eap_auth_aaa_req
Jan 23 14:32:45.572: EAP-AUTH-AAA-EVENT: Adding Audit-Session-ID "0A07CC11000006BA0C1B90D9" to RADIUS Req
Jan 23 14:32:45.572: EAP-AUTH-AAA-EVENT: Added Audit-Session-ID
Jan 23 14:32:45.572: EAP-AUTH-AAA-EVENT: Adding IDB "0x07A01E10" to RADIUS Req
Jan 23 14:32:45.572: EAP-AUTH-AAA-EVENT: Added IDB
Jan 23 14:32:45.572: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_AAA_REQUEST' on handle 0x01000D16
Jan 23 14:32:45.572: EAP-AUTH-AAA-EVENT: eap_auth_aaa_authen_request_shim aaa_service 19, eap aaa_list handle 0, mlist handle 0
Jan 23 14:32:45.575: EAP-AUTH-AAA-EVENT: Request sent successfully
Jan 23 14:32:45.575:     eap_authen : during state eap_auth_aaa_req, got event 24(eapAAAReqOk)
Jan 23 14:32:45.575: @@@ eap_authen : eap_auth_aaa_req -> eap_auth_aaa_idle
Jan 23 14:32:45.579: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] queuing an EAPOL pkt on Auth Q
Jan 23 14:32:45.579: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x1
Jan 23 14:32:45.579: dot1x-packet: length: 0x0000
Jan 23 14:32:45.579: dot1x-ev:[Gi1/0/34] Dequeued pkt: Int Gi1/0/34 CODE= 0,TYPE= 0,LEN= 0

Jan 23 14:32:45.579: dot1x-ev:[Gi1/0/34] Received pkt saddr =507b.9d62.e6e5 , daddr = 0180.c200.0003, pae-ether-type = 888e.0101.0000
Jan 23 14:32:45.579: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] Received an EAPOL-Start packet
Jan 23 14:32:45.579: dot1x-packet:EAPOL pak rx - Ver: 0x1  type: 0x1
Jan 23 14:32:45.579: dot1x-packet: length: 0x0000
Jan 23 14:32:45.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAPOL_START on Client 0xFE00044A
Jan 23 14:32:45.579:     dot1x_auth Gi1/0/34: during state auth_authenticating, got event 4(eapolStart)
Jan 23 14:32:45.579: @@@ dot1x_auth Gi1/0/34: auth_authenticating -> auth_aborting
Jan 23 14:32:45.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting authenticating state
Jan 23 14:32:45.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering aborting state
Jan 23 14:32:45.579: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting AUTH_ABORT for 0xFE00044A
Jan 23 14:32:45.579:     dot1x_auth_bend Gi1/0/34: during state auth_bend_response, got event 1(authAbort)
Jan 23 14:32:45.579: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_response -> auth_bend_initialize
Jan 23 14:32:45.582: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting response state
Jan 23 14:32:45.582: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering init state
Jan 23 14:32:45.582:     dot1x_auth_bend Gi1/0/34: idle during state auth_bend_initialize
Jan 23 14:32:45.582: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_initialize -> auth_bend_idle
Jan 23 14:32:45.582: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering idle state
Jan 23 14:32:45.582: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting !AUTH_ABORT on Client 0xFE00044A
Jan 23 14:32:45.582:     dot1x_auth Gi1/0/34: during state auth_aborting, got event 20(no_eapolLogoff_no_authAbort)
Jan 23 14:32:45.582: @@@ dot1x_auth Gi1/0/34: auth_aborting -> auth_restart
Jan 23 14:32:45.582: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:exiting aborting state
Jan 23 14:32:45.582: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: entering restart
Jan 23 14:32:45.582: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Resetting the client 0xFE00044A
Jan 23 14:32:45.582: EAP-EVENT: Received free context (0x01000D16) from LL (Dot1x-Authenticator)
Jan 23 14:32:45.582: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending create new context event to EAP for 0xFE00044A (507b.9d62.e6e5)
Jan 23 14:32:45.582: EAP-EVENT: Received context create from LL (Dot1x-Authenticator) (0xFE00044A)
Jan 23 14:32:45.582: EAP-AUTH-EVENT: Received AAA ID 0x000006BA from LL
Jan 23 14:32:45.582: EAP-AUTH-AAA-EVENT: Assigning AAA ID 0x000006BA
Jan 23 14:32:45.582: EAP-AUTH-AAA-EVENT: CTS not enabled on interface Gi1/0/34
Jan 23 14:32:45.586: EAP-AUTH-EVENT: Received Session ID "0A07CC11000006BA0C1B90D9" from LL
Jan 23 14:32:45.586: EAP-AUTH-EVENT: Setting authentication mode: Passthrough
Jan 23 14:32:45.586:     eap_authen : initial state eap_auth_initialize has enter
Jan 23 14:32:45.586: EAP-EVENT: Allocated new EAP context (handle = 0x0F000D17)
Jan 23 14:32:45.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:restart action for aborting state
Jan 23 14:32:45.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting !EAP_RESTART on Client 0xFE00044A
Jan 23 14:32:45.586:     dot1x_auth Gi1/0/34: during state auth_restart, got event 6(no_eapRestart)
Jan 23 14:32:45.586: @@@ dot1x_auth Gi1/0/34: auth_restart -> auth_connecting
Jan 23 14:32:45.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:enter connecting state
Jan 23 14:32:45.586: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: restart connecting
Jan 23 14:32:45.586: EAP-EVENT: eap_aaa_reply
Jan 23 14:32:45.586: EAP-AUTH-AAA-EVENT: Reply received session_label 5C0000C1
Jan 23 14:32:45.586: EAP-AUTH-AAA-EVENT: Auth-coord CB: Reply received on stale handle (0x01000D16)
Jan 23 14:32:45.586: EAP-EVENT: Received LL (Dot1x-Authenticator) event 'EAP_DELETE' on handle 0x01000D16
Jan 23 14:32:45.586: EAP-AUTH-EVENT: Freed EAP auth context
Jan 23 14:32:45.586: EAP-EVENT: Freed EAP context
Jan 23 14:32:45.586: EAP-EVENT: Received EAP event 'EAP_AUTHENTICATOR_START' on handle 0x0F000D17
Jan 23 14:32:45.586:     eap_authen : during state eap_auth_initialize, got event 25(eapStartTmo)
Jan 23 14:32:45.586: @@@ eap_authen : eap_auth_initialize -> eap_auth_select_action
Jan 23 14:32:45.586:     eap_authen : during state eap_auth_select_action, got event 20(eapDecisionPropose)
Jan 23 14:32:45.586: @@@ eap_authen : eap_auth_select_action -> eap_auth_propose_method
Jan 23 14:32:45.586:     eap_authen : idle during state eap_auth_propose_method
Jan 23 14:32:45.589: @@@ eap_authen : eap_auth_propose_method -> eap_auth_method_request
Jan 23 14:32:45.589:     eap_authen : idle during state eap_auth_method_request
Jan 23 14:32:45.589: @@@ eap_authen : eap_auth_method_request -> eap_auth_tx_packet
Jan 23 14:32:45.589: EAP-AUTH-EVENT: Current method = Identity
Jan 23 14:32:45.589: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_CUSTOMIZE_ID_REQUEST' on handle 0x0F000D17
Jan 23 14:32:45.589:     eap_authen : idle during state eap_auth_tx_packet
Jan 23 14:32:45.589: @@@ eap_authen : eap_auth_tx_packet -> eap_auth_idle
Jan 23 14:32:45.589: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x1   Length:0x0005  Type:IDENTITY
Jan 23 14:32:45.589: EAP-EVENT: Started 'Authenticator ReqId Retransmit' timer (10s) for EAP sesion handle 0x0F000D17
Jan 23 14:32:45.589: EAP-EVENT: Started EAP tick timer
Jan 23 14:32:45.589: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x0F000D17
Jan 23 14:32:45.589: EAP-EVENT: Received event 'EAP_AAA_FAIL' on stale handle (0x01000D16)
Jan 23 14:32:45.607: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting RX_REQ on Client 0xFE00044A
Jan 23 14:32:45.607:     dot1x_auth Gi1/0/34: during state auth_connecting, got event 10(eapReq_no_reAuthMax)
Jan 23 14:32:45.607: @@@ dot1x_auth Gi1/0/34: auth_connecting -> auth_authenticating
Jan 23 14:32:45.607: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A: authenticating state entered
Jan 23 14:32:45.607: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:connecting authenticating action
Jan 23 14:32:45.607: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting AUTH_START for 0xFE00044A
Jan 23 14:32:45.607:     dot1x_auth_bend Gi1/0/34: during state auth_bend_idle, got event 4(eapReq_authStart)
Jan 23 14:32:45.607: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_idle -> auth_bend_request
Jan 23 14:32:45.607: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:45.607: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:45.607: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:45.607: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:45.607: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:45.607: dot1x-packet: length: 0x0005
Jan 23 14:32:45.607: dot1x-packet:EAP code: 0x1  id: 0x1  length: 0x0005
Jan 23 14:32:45.607: dot1x-packet: type: 0x1
Jan 23 14:32:45.607: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:32:45.607: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:idle request action
Jan 23 14:32:56.540: EAP-EVENT: 'Authenticator ReqId Retransmit' timer expired for EAP sesion handle 0xF000D17
Jan 23 14:32:56.540:     eap_authen : during state eap_auth_idle, got event 26(eapRetransmitTmo)
Jan 23 14:32:56.540: @@@ eap_authen : eap_auth_idle -> eap_auth_retransmit
Jan 23 14:32:56.540:     eap_authen : during state eap_auth_retransmit, got event 27(eapTmoRetry)
Jan 23 14:32:56.540: @@@ eap_authen : eap_auth_retransmit -> eap_auth_tx_packet
Jan 23 14:32:56.540: EAP-AUTH-EVENT: Resending last packet for context 0x0F000D17
Jan 23 14:32:56.540:     eap_authen : idle during state eap_auth_tx_packet
Jan 23 14:32:56.540: @@@ eap_authen : eap_auth_tx_packet -> eap_auth_idle
Jan 23 14:32:56.540: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x1   Length:0x0005  Type:IDENTITY
Jan 23 14:32:56.540: EAP-EVENT: Started 'Authenticator ReqId Retransmit' timer (10s) for EAP sesion handle 0x0F000D17
Jan 23 14:32:56.540: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x0F000D17
Jan 23 14:32:56.540: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAP_REQ for 0xFE00044A
Jan 23 14:32:56.540:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 7(eapReq)
Jan 23 14:32:56.540: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_request
Jan 23 14:32:56.540: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request request action
Jan 23 14:32:56.540: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:32:56.540: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:32:56.540: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:32:56.540: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:32:56.540: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:32:56.540: dot1x-packet: length: 0x0005
Jan 23 14:32:56.540: dot1x-packet:EAP code: 0x1  id: 0x1  length: 0x0005
Jan 23 14:32:56.540: dot1x-packet: type: 0x1
Jan 23 14:32:56.540: dot1x-packet:[507b.9d62.e6e5, Gi1/0/34] EAPOL packet sent to client 0xFE00044A
Jan 23 14:33:06.816: EAP-EVENT: 'Authenticator ReqId Retransmit' timer expired for EAP sesion handle 0xF000D17
Jan 23 14:33:06.816:     eap_authen : during state eap_auth_idle, got event 26(eapRetransmitTmo)
Jan 23 14:33:06.816: @@@ eap_authen : eap_auth_idle -> eap_auth_retransmit
Jan 23 14:33:06.816:     eap_authen : during state eap_auth_retransmit, got event 27(eapTmoRetry)
Jan 23 14:33:06.816: @@@ eap_authen : eap_auth_retransmit -> eap_auth_tx_packet
Jan 23 14:33:06.816: EAP-AUTH-EVENT: Resending last packet for context 0x0F000D17
Jan 23 14:33:06.816:     eap_authen : idle during state eap_auth_tx_packet
Jan 23 14:33:06.816: @@@ eap_authen : eap_auth_tx_packet -> eap_auth_idle
Jan 23 14:33:06.816: EAP-AUTH-TX-PAK: Code:REQUEST  ID:0x1   Length:0x0005  Type:IDENTITY
Jan 23 14:33:06.816: EAP-EVENT: Started 'Authenticator ReqId Retransmit' timer (10s) for EAP sesion handle 0x0F000D17
Jan 23 14:33:06.816: EAP-EVENT: Sending LL (Dot1x-Authenticator) event 'EAP_TX_PACKET' on handle 0x0F000D17
Jan 23 14:33:06.816: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] Posting EAP_REQ for 0xFE00044A
Jan 23 14:33:06.816:     dot1x_auth_bend Gi1/0/34: during state auth_bend_request, got event 7(eapReq)
Jan 23 14:33:06.816: @@@ dot1x_auth_bend Gi1/0/34: auth_bend_request -> auth_bend_request
Jan 23 14:33:06.816: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:request request action
Jan 23 14:33:06.816: dot1x-sm:[507b.9d62.e6e5, Gi1/0/34] 0xFE00044A:entering request state
Jan 23 14:33:06.816: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending EAPOL packet
Jan 23 14:33:06.816: dot1x-registry:registry:dot1x_ether_macaddr called
Jan 23 14:33:06.816: dot1x-ev:[507b.9d62.e6e5, Gi1/0/34] Sending out EAPOL packet
Jan 23 14:33:06.816: dot1x-packet:EAPOL pak Tx - Ver: 0x3  type: 0x0
Jan 23 14:33:06.816: dot1x-packet: length: 0x0005
Jan 23 14:33:06.816: dot1x-packet:EAP code: 0x1  id: 0x1  length: 0x0005
Jan 23 14:33:06.816: dot1x-packet: type: 0x1