cci@sw100-EX2200-2# show protocols dot1x traceoptions { file dot1x; flag state; flag dot1x-debug; flag eapol; } authenticator { authentication-profile-name WIRED; interface { ge-0/0/2.0 { supplicant multiple; retries 2; quiet-period 15; transmit-period 7; mac-radius; no-reauthentication; supplicant-timeout 4; server-timeout 5; guest-vlan ROUTED_Registration; server-reject-vlan ROUTED_Registration; server-fail vlan-name ROUTED_Registration; } } } ge-0/0/2 { unit 0 { family ethernet-switching; } DEBUG: May 18 15:13:34.723396 Enabling PNAC on interface 71... May 18 15:13:34.723564 CD Machine called for Port: 71 with Event: PORTENABLED, State: FORCEBOTH May 18 15:13:34.728262 Successfully exiting CD Machine... May 18 15:13:34.728423 Enabled PNAC on interface 71... May 18 15:13:35.957228 Trying to create a session for MAC: -262253-3df1- thro' Port: 71 ... May 18 15:13:35.958134 queried vid:7 May 18 15:13:35.958246 Allocated Session Node : 23d554 May 18 15:13:35.958459 PnacAuthSessStatsInit PnacAuthSessStatsInit May 18 15:13:35.960850 Generated Unique SessId :9322734803873603715 May 18 15:13:35.960994 BSM Called with Event: INITIALIZE, and State: Initialise May 18 15:13:35.961143 for Port: 71, MAC: 262253-3df1 May 18 15:13:35.961278 Id: 0, SessionNode: 23d554 May 18 15:13:35.961413 BSM moved to state: INITIALIZE !! May 18 15:13:35.961555 BSM moved to state: IDLE !! May 18 15:13:35.962463 ASM Called with Event: INITIALIZE, and State: Initialize May 18 15:13:35.962658 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:35.963551 Id: 0, SessionNode: 23d554 May 18 15:13:35.964547 ASM: Inside PnacAuthAsmInitInitialize May 18 15:13:35.964851 ASM moved to state: INITIALIZE !! May 18 15:13:35.965009 ASM moved to state: DISCONNECTED !! May 18 15:13:35.965166 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 0 Reauth Count 0 May 18 15:13:35.965303 ASM moved to state: CONNECTING !! May 18 15:13:35.965463 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:13:35.965967 TMR: Timer is started May 18 15:13:35.966317 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:13:35.966528 EAP Frame Sent with code: 1 !!! May 18 15:13:35.966849 EAP Req Id Frame Sent !!! May 18 15:13:35.967014 Session Created for MAC: -262253-3df1- through Port: 71 ... May 18 15:13:35.967514 Task transmit function invoked May 18 15:13:35.971021 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:13:42.957658 TMR: In txWhen_expiry() May 18 15:13:42.958255 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:13:42.958418 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:42.960157 Id: 0, SessionNode: 23d554 May 18 15:13:42.960310 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 1 Max Req 2 May 18 15:13:42.961332 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 1 Reauth Count 0 May 18 15:13:42.962273 ASM moved to state: CONNECTING !! May 18 15:13:42.962852 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:13:42.963038 TMR: Timer is started May 18 15:13:42.963372 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:13:42.963708 EAP Frame Sent with code: 1 !!! May 18 15:13:42.963883 EAP Req Id Frame Sent !!! May 18 15:13:42.964209 Task transmit function invoked May 18 15:13:42.964913 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:13:49.959498 TMR: In txWhen_expiry() May 18 15:13:49.959989 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:13:49.960149 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:49.961163 Id: 0, SessionNode: 23d554 May 18 15:13:49.961667 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 2 Max Req 2 May 18 15:13:49.961821 PnacAuthAsmInitMacRadius Intf ge-0/0/2.0: Launch MACAUTH. May 18 15:13:49.962024 ASM Called with Event: RXRESPID, and State: Connecting May 18 15:13:49.962183 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:49.962777 Id: 0, SessionNode: 23d554 May 18 15:13:49.962912 ASM: Inside PnacAuthAsmRxrespConnecting May 18 15:13:49.963058 ASM moved to state: AUTHENTICATING !! May 18 15:13:49.963208 BSM Called with Event: AUTHSTART, and State: Idle May 18 15:13:49.963520 for Port: 71, MAC: 262253-3df1 May 18 15:13:49.963668 Id: 0, SessionNode: 23d554 May 18 15:13:49.963853 TMR: Timer is started May 18 15:13:49.964002 BSM moved to state: RESPONSE !! May 18 15:13:49.964159 ASIF: Transferring Server-data to Auth Server for the user, 002622533df1. May 18 15:13:49.964940 SessId: 8O2.1x816101e8000e9fd9 strlen: 22 May 18 15:13:49.966858 Queuing message to auth client to validate mac address 0:26:22:53:3d:f1, user 002622533df1 on interface ge-0/0/2.0 May 18 15:13:49.968684 ASIF: Radius REQUEST_ID: d7 May 18 15:13:49.968803 ASIF: Tx of Server-data to Auth Server succeeded May 18 15:13:49.969089 Sending message to authentication client May 18 15:13:49.987892 Received message from authentication client May 18 15:13:49.988024 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:28 reply_len:28 May 18 15:13:49.988153 hdr_bytes_read 0 May 18 15:13:49.988866 len read : 28 reply_len: 2944 May 18 15:13:49.989020 bytes_remnant 2916 tot_bytes_read 28 May 18 15:13:49.989188 bytes_read 2916 May 18 15:13:49.989437 Creating background job to process reply from authentication client May 18 15:13:49.989696 Entering background job to process message from authentication client May 18 15:13:49.989815 process_auth_reply len:2944 May 18 15:13:49.989944 No VLAN attributes configured or Captive-Portal enabled May 18 15:13:49.990051 Received Access-Reject authentication message May 18 15:13:49.990155 Server reject_retry: (1) May 18 15:13:49.990873 Invoking state machine for authentication response for mac 00:26:22:53:3d:f1 on intf ge-0/0/2.0 May 18 15:13:49.991105 pnac_process_auth_reply_result: server reject vlan configured May 18 15:13:49.991222 ASIF: Handing over Server frame to Authenticator May 18 15:13:49.991510 AUTH: Handling Server Frame May 18 15:13:49.991669 SessNode got from SessIdtbl for Id 0 is : 23d554, Port: 71 May 18 15:13:49.991812 Code = 4, Id = 0, Len = 1024 May 18 15:13:49.991955 BSM Called with Event: AFAIL_RCVD, and State: Response May 18 15:13:49.992094 for Port: 71, MAC: 262253-3df1 May 18 15:13:49.992227 Id: 0, SessionNode: 23d554 May 18 15:13:49.992810 TMR: Timer is deleted May 18 15:13:49.992950 BSM moved to state: FAIL !! May 18 15:13:49.993115 BSM moved to state: IDLE !! May 18 15:13:49.993612 ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating May 18 15:13:49.993922 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:49.994068 Id: 0, SessionNode: 23d554 May 18 15:13:49.994211 Auth Fail Count 1: Moved to state CONNECTING !! May 18 15:13:49.996667 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 2 Reauth Count 0 May 18 15:13:49.996805 ASM moved to state: CONNECTING !! May 18 15:13:49.996959 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:13:49.997128 TMR: Timer is started May 18 15:13:49.998115 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:13:49.998430 EAP Frame Sent with code: 1 !!! May 18 15:13:49.998600 EAP Req Id Frame Sent !!! May 18 15:13:49.998761 ASIF: Handing over Server frame to Authenticator 71. May 18 15:13:49.998912 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 18 15:13:49.999013 Rad Attr Class Len = 0 May 18 15:13:49.999108 ASIF: Received No Key from Server May 18 15:13:49.999240 Accounting order not configured, do not send acctng req May 18 15:13:49.999940 Processing authentication response complete May 18 15:13:50.000113 Deleted background job to process replies from authentication client May 18 15:13:50.001756 Task transmit function invoked May 18 15:13:50.002998 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:13:56.990320 TMR: In txWhen_expiry() May 18 15:13:56.990800 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:13:56.990961 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:56.993530 Id: 1, SessionNode: 23d554 May 18 15:13:56.993705 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 3 Max Req 2 May 18 15:13:56.993851 PnacAuthAsmInitMacRadius Intf ge-0/0/2.0: Launch MACAUTH. May 18 15:13:56.994053 ASM Called with Event: RXRESPID, and State: Connecting May 18 15:13:56.996325 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:56.996488 Id: 1, SessionNode: 23d554 May 18 15:13:56.996618 ASM: Inside PnacAuthAsmRxrespConnecting May 18 15:13:56.996759 ASM moved to state: AUTHENTICATING !! May 18 15:13:56.996912 BSM Called with Event: AUTHSTART, and State: Idle May 18 15:13:56.997329 for Port: 71, MAC: 262253-3df1 May 18 15:13:56.997480 Id: 1, SessionNode: 23d554 May 18 15:13:56.997659 TMR: Timer is started May 18 15:13:56.997804 BSM moved to state: RESPONSE !! May 18 15:13:56.997957 ASIF: Transferring Server-data to Auth Server for the user, 002622533df1. May 18 15:13:56.998258 SessId: 8O2.1x816101e8000e9fd9 strlen: 22 May 18 15:13:56.998559 Queuing message to auth client to validate mac address 0:26:22:53:3d:f1, user 002622533df1 on interface ge-0/0/2.0 May 18 15:13:56.998810 ASIF: Radius REQUEST_ID: d8 May 18 15:13:56.998926 ASIF: Tx of Server-data to Auth Server succeeded May 18 15:13:56.999966 Sending message to authentication client May 18 15:13:57.018103 Received message from authentication client May 18 15:13:57.019036 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:2944 reply_len:2944 May 18 15:13:57.019953 hdr_bytes_read 0 May 18 15:13:57.020955 len read : 28 reply_len: 2944 May 18 15:13:57.021239 bytes_remnant 2916 tot_bytes_read 28 May 18 15:13:57.021423 bytes_read 2916 May 18 15:13:57.021535 Creating background job to process reply from authentication client May 18 15:13:57.021781 Entering background job to process message from authentication client May 18 15:13:57.021905 process_auth_reply len:2944 May 18 15:13:57.022033 No VLAN attributes configured or Captive-Portal enabled May 18 15:13:57.022563 Received Access-Reject authentication message May 18 15:13:57.022676 pnac_apply_access_reject_vlan portnum:71 May 18 15:13:57.022785 Server Reject VLAN ROUTED_Registration May 18 15:13:57.023315 Invoking state machine for authentication response for mac 00:26:22:53:3d:f1 on intf ge-0/0/2.0 May 18 15:13:57.023494 vlan data is NULL for idx 71 May 18 15:13:57.023732 Client moved to dynamic VLAN ROUTED_Registration May 18 15:13:57.027999 pnac_process_auth_reply_result: server reject vlan configured May 18 15:13:57.028764 ASIF: Handing over Server frame to Authenticator May 18 15:13:57.028924 AUTH: Handling Server Frame May 18 15:13:57.030585 SessNode got from SessIdtbl for Id 0 is : 23d554, Port: 71 May 18 15:13:57.030731 Code = 3, Id = 0, Len = 1024 May 18 15:13:57.030885 BSM Called with Event: ASUCCESS_RCVD, and State: Response May 18 15:13:57.033235 for Port: 71, MAC: 262253-3df1 May 18 15:13:57.033410 Id: 1, SessionNode: 23d554 May 18 15:13:57.033563 TMR: Timer is deleted May 18 15:13:57.033700 BSM moved to state: SUCCESS !! May 18 15:13:57.033852 ASM Called with Event: BKEND_AUTHSUCCESS, and State: Authenticating May 18 15:13:57.033997 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:57.035805 Id: 0, SessionNode: 23d554 May 18 15:13:57.035967 ASM moved to state: AUTHENTICATED from Authenticating!! May 18 15:13:57.036490 ASM moved to state: AUTHENTICATED !! May 18 15:13:57.036653 BSM moved to state: IDLE !! May 18 15:13:57.036801 ASIF: Handing over Server frame to Authenticator 71. May 18 15:13:57.036969 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 18 15:13:57.037205 Rad Attr Class Len = 0 May 18 15:13:57.037329 ASIF: Received No Key from Server May 18 15:13:57.037470 Accounting order not configured, do not send acctng req May 18 15:13:57.037571 Processing authentication response complete May 18 15:13:57.037765 Deleted background job to process replies from authentication client May 18 15:13:57.303866 ASM Called with Event: EAPLOGOFF_RCVD, and State: Authenticated comment: WHY SWITCH RESTARTs AUTH... I NEED GUEST VLAN FOR THIS PORT: "VLAN ROUTED_Registration" May 18 15:13:57.304049 for Port: 71, MAC: 262253 - 3df1 May 18 15:13:57.304752 Id: 1, SessionNode: 23d554 May 18 15:13:57.304882 ASM: Inside PnacAuthAsmEapLogoffAuthenticated May 18 15:13:57.306877 pnac_client_delete_session: MAC: 00:26:22:53:3d:f1 authenticatedStateCause: 10 AuthenticatorState: 5 SessionID: 9322734803873603715 May 18 15:13:57.307008 Radius Accounting not configured May 18 15:13:57.308596 Queuing session cleanup message message to auth client May 18 15:13:57.310010 Deleting Dynamic filter dot1x_ge-0/0/2_DOT1X_dotmac_002622533df1 May 18 15:13:57.311330 remove_filter_from_list:1197 is_wl= 0 is_sm= 0 May 18 15:13:57.312895 PnacAuthSessStatsInit PnacAuthSessStatsInit May 18 15:13:57.314554 ASM moved to state: DISCONNECTED !! May 18 15:13:57.314726 Trying to delete the session for MAC: -262253-3df1- on Port: 71 ... May 18 15:13:57.314861 Trying to delete the Session Node : 23d554 ... May 18 15:13:57.316526 Session Deleted for MAC: -262253-3df1- on Port: 71 ... May 18 15:13:57.316694 Deleted Session Node : 23d554 ... May 18 15:13:57.320026 Sending message to authentication client May 18 15:13:57.323395 Received message from authentication client May 18 15:13:57.323528 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:2944 reply_len:2944 May 18 15:13:57.323666 hdr_bytes_read 0 May 18 15:13:57.323768 len read : 28 reply_len: 28 May 18 15:13:57.323878 bytes_remnant 0 tot_bytes_read 28 May 18 15:13:57.323975 Creating background job to process reply from authentication client May 18 15:13:57.325563 Entering background job to process message from authentication client May 18 15:13:57.325696 process_auth_reply len:28 May 18 15:13:57.325820 Error retrieving session node. May 18 15:13:57.325925 Error response from authentication client. Message not processed further May 18 15:13:57.326025 Accounting order not configured, do not send acctng req May 18 15:13:57.326558 ASIF: Handing over Server frame to Authenticator May 18 15:13:57.326720 AUTH: Handling Server Frame May 18 15:13:57.326860 Port Info is NULL for portnum:0 May 18 15:13:57.326955 ASIF: Authenticator handling of Server frame failed May 18 15:13:57.327250 Deleted background job to process replies from authentication client May 18 15:13:57.340029 pnac_cleanup_white_list_entries_on_vmember_del:392:CP_DEBUG: May 18 15:13:59.166337 dot1xd transaction accepted by dfwd May 18 15:13:59.166795 dot1xd transaction accepted by dfwd May 18 15:13:59.166962 dot1xd transaction accepted by dfwd May 18 15:13:59.167539 dot1xd transaction accepted by dfwd May 18 15:14:03.877647 Trying to create a session for MAC: -262253-3df1- thro' Port: 71 ... May 18 15:14:03.878491 queried vid:7 May 18 15:14:03.878680 Allocated Session Node : 23d554 May 18 15:14:03.879327 PnacAuthSessStatsInit PnacAuthSessStatsInit May 18 15:14:03.879570 Generated Unique SessId :9322734816758426484 May 18 15:14:03.879688 BSM Called with Event: INITIALIZE, and State: Initialise May 18 15:14:03.879833 for Port: 71, MAC: 262253-3df1 May 18 15:14:03.883582 Id: 0, SessionNode: 23d554 May 18 15:14:03.883718 BSM moved to state: INITIALIZE !! May 18 15:14:03.883859 BSM moved to state: IDLE !! May 18 15:14:03.884166 ASM Called with Event: INITIALIZE, and State: Initialize May 18 15:14:03.884317 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:03.884455 Id: 0, SessionNode: 23d554 May 18 15:14:03.884583 ASM: Inside PnacAuthAsmInitInitialize May 18 15:14:03.884724 ASM moved to state: INITIALIZE !! May 18 15:14:03.884872 ASM moved to state: DISCONNECTED !! May 18 15:14:03.885461 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 0 Reauth Count 0 May 18 15:14:03.885598 ASM moved to state: CONNECTING !! May 18 15:14:03.885754 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:14:03.886176 TMR: Timer is started May 18 15:14:03.886534 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:14:03.886743 EAP Frame Sent with code: 1 !!! May 18 15:14:03.887321 EAP Req Id Frame Sent !!! May 18 15:14:03.887497 Session Created for MAC: -262253-3df1- through Port: 71 ... May 18 15:14:03.888128 Task transmit function invoked May 18 15:14:03.888424 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:14:10.877902 TMR: In txWhen_expiry() May 18 15:14:10.878391 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:14:10.878548 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:10.878687 Id: 0, SessionNode: 23d554 May 18 15:14:10.879398 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 1 Max Req 2 May 18 15:14:10.879553 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 1 Reauth Count 0 May 18 15:14:10.880469 ASM moved to state: CONNECTING !! May 18 15:14:10.880631 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:14:10.881248 TMR: Timer is started May 18 15:14:10.881584 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:14:10.882094 EAP Frame Sent with code: 1 !!! May 18 15:14:10.882263 EAP Req Id Frame Sent !!! May 18 15:14:10.882585 Task transmit function invoked May 18 15:14:10.884152 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:14:14.405852 snmp_epi_register: called May 18 15:14:17.878753 TMR: In txWhen_expiry() May 18 15:14:17.879235 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:14:17.879396 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:17.881117 Id: 0, SessionNode: 23d554 May 18 15:14:17.881295 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 2 Max Req 2 May 18 15:14:17.881441 PnacAuthAsmInitMacRadius Intf ge-0/0/2.0: Launch MACAUTH. May 18 15:14:17.881813 ASM Called with Event: RXRESPID, and State: Connecting May 18 15:14:17.881982 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:17.882125 Id: 0, SessionNode: 23d554 May 18 15:14:17.882256 ASM: Inside PnacAuthAsmRxrespConnecting May 18 15:14:17.882401 ASM moved to state: AUTHENTICATING !! May 18 15:14:17.882959 BSM Called with Event: AUTHSTART, and State: Idle May 18 15:14:17.883128 for Port: 71, MAC: 262253-3df1 May 18 15:14:17.883264 Id: 0, SessionNode: 23d554 May 18 15:14:17.883437 TMR: Timer is started May 18 15:14:17.883957 BSM moved to state: RESPONSE !! May 18 15:14:17.884119 ASIF: Transferring Server-data to Auth Server for the user, 002622533df1. May 18 15:14:17.884287 SessId: 8O2.1x816101eb000d691c strlen: 22 May 18 15:14:17.884981 Queuing message to auth client to validate mac address 0:26:22:53:3d:f1, user 002622533df1 on interface ge-0/0/2.0 May 18 15:14:17.885235 ASIF: Radius REQUEST_ID: d9 May 18 15:14:17.885353 ASIF: Tx of Server-data to Auth Server succeeded May 18 15:14:17.885787 Sending message to authentication client May 18 15:14:17.905203 Received message from authentication client May 18 15:14:17.905337 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:28 reply_len:28 May 18 15:14:17.905470 hdr_bytes_read 0 May 18 15:14:17.906187 len read : 28 reply_len: 2944 May 18 15:14:17.906341 bytes_remnant 2916 tot_bytes_read 28 May 18 15:14:17.906510 bytes_read 2916 May 18 15:14:17.906779 Creating background job to process reply from authentication client May 18 15:14:17.907022 Entering background job to process message from authentication client May 18 15:14:17.907145 process_auth_reply len:2944 May 18 15:14:17.907277 No VLAN attributes configured or Captive-Portal enabled May 18 15:14:17.907389 Received Access-Reject authentication message May 18 15:14:17.907498 Server reject_retry: (1) May 18 15:14:17.908493 Invoking state machine for authentication response for mac 00:26:22:53:3d:f1 on intf ge-0/0/2.0 May 18 15:14:17.908771 pnac_async_response_to_eswd_internal auth_vid:7, queried_vid:7 May 18 15:14:17.908939 pnac_process_auth_reply_result: server reject vlan configured May 18 15:14:17.909061 ASIF: Handing over Server frame to Authenticator May 18 15:14:17.909204 AUTH: Handling Server Frame May 18 15:14:17.909357 SessNode got from SessIdtbl for Id 0 is : 23d554, Port: 71 May 18 15:14:17.909504 Code = 4, Id = 0, Len = 1024 May 18 15:14:17.910826 BSM Called with Event: AFAIL_RCVD, and State: Response May 18 15:14:17.910981 for Port: 71, MAC: 262253-3df1 May 18 15:14:17.911119 Id: 0, SessionNode: 23d554 May 18 15:14:17.911276 TMR: Timer is deleted May 18 15:14:17.911418 BSM moved to state: FAIL !! May 18 15:14:17.912002 BSM moved to state: IDLE !! May 18 15:14:17.912189 ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating May 18 15:14:17.912339 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:17.912478 Id: 0, SessionNode: 23d554 May 18 15:14:17.912798 Auth Fail Count 1: Moved to state CONNECTING !! May 18 15:14:17.912965 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 2 Reauth Count 0 May 18 15:14:17.913474 ASM moved to state: CONNECTING !! May 18 15:14:17.914202 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:14:17.914374 TMR: Timer is started May 18 15:14:17.914836 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:14:17.915052 EAP Frame Sent with code: 1 !!! May 18 15:14:17.915218 EAP Req Id Frame Sent !!! May 18 15:14:17.915383 ASIF: Handing over Server frame to Authenticator 71. May 18 15:14:17.915963 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 18 15:14:17.916088 Rad Attr Class Len = 0 May 18 15:14:17.916184 ASIF: Received No Key from Server May 18 15:14:17.916324 Accounting order not configured, do not send acctng req May 18 15:14:17.916421 Processing authentication response complete May 18 15:14:17.916731 Deleted background job to process replies from authentication client May 18 15:14:17.917028 Task transmit function invoked May 18 15:14:17.917324 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:14:24.907554 TMR: In txWhen_expiry() May 18 15:14:24.908032 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:14:24.908191 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:24.908331 Id: 1, SessionNode: 23d554 May 18 15:14:24.909043 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 3 Max Req 2 May 18 15:14:24.909188 PnacAuthAsmInitMacRadius Intf ge-0/0/2.0: Launch MACAUTH. May 18 15:14:24.909533 ASM Called with Event: RXRESPID, and State: Connecting May 18 15:14:24.909707 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:24.909849 Id: 1, SessionNode: 23d554 May 18 15:14:24.909980 ASM: Inside PnacAuthAsmRxrespConnecting May 18 15:14:24.910122 ASM moved to state: AUTHENTICATING !! May 18 15:14:24.910274 BSM Called with Event: AUTHSTART, and State: Idle May 18 15:14:24.911055 for Port: 71, MAC: 262253-3df1 May 18 15:14:24.911192 Id: 1, SessionNode: 23d554 May 18 15:14:24.913963 TMR: Timer is started May 18 15:14:24.914126 BSM moved to state: RESPONSE !! May 18 15:14:24.915578 ASIF: Transferring Server-data to Auth Server for the user, 002622533df1. May 18 15:14:24.915763 SessId: 8O2.1x816101eb000d691c strlen: 22 May 18 15:14:24.916047 Queuing message to auth client to validate mac address 0:26:22:53:3d:f1, user 002622533df1 on interface ge-0/0/2.0 May 18 15:14:24.916294 ASIF: Radius REQUEST_ID: da May 18 15:14:24.916540 ASIF: Tx of Server-data to Auth Server succeeded May 18 15:14:24.916855 Sending message to authentication client May 18 15:14:24.930934 Received message from authentication client May 18 15:14:24.931065 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:2944 reply_len:2944 May 18 15:14:24.931196 hdr_bytes_read 0 May 18 15:14:24.931300 len read : 28 reply_len: 2944 May 18 15:14:24.932209 bytes_remnant 2916 tot_bytes_read 28 May 18 15:14:24.932535 bytes_read 2916 May 18 15:14:24.932656 Creating background job to process reply from authentication client May 18 15:14:24.932901 Entering background job to process message from authentication client May 18 15:14:24.933024 process_auth_reply len:2944 May 18 15:14:24.933155 No VLAN attributes configured or Captive-Portal enabled May 18 15:14:24.933268 Received Access-Reject authentication message May 18 15:14:24.935331 pnac_apply_access_reject_vlan portnum:71 May 18 15:14:24.935870 Server Reject VLAN ROUTED_Registration May 18 15:14:24.936728 Invoking state machine for authentication response for mac 00:26:22:53:3d:f1 on intf ge-0/0/2.0 May 18 15:14:24.936907 vlan data is NULL for idx 71 May 18 15:14:24.937144 Client moved to dynamic VLAN ROUTED_Registration May 18 15:14:24.940251 pnac_process_auth_reply_result: server reject vlan configured May 18 15:14:24.945114 ASIF: Handing over Server frame to Authenticator May 18 15:14:24.945275 AUTH: Handling Server Frame May 18 15:14:24.946197 SessNode got from SessIdtbl for Id 0 is : 23d554, Port: 71 May 18 15:14:24.946477 Code = 3, Id = 0, Len = 1024 May 18 15:14:24.946662 BSM Called with Event: ASUCCESS_RCVD, and State: Response May 18 15:14:24.946820 for Port: 71, MAC: 262253-3df1 May 18 15:14:24.947087 Id: 1, SessionNode: 23d554 May 18 15:14:24.947255 TMR: Timer is deleted May 18 15:14:24.947839 BSM moved to state: SUCCESS !! May 18 15:14:24.947994 ASM Called with Event: BKEND_AUTHSUCCESS, and State: Authenticating May 18 15:14:24.948145 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:24.948278 Id: 0, SessionNode: 23d554 May 18 15:14:24.948795 ASM moved to state: AUTHENTICATED from Authenticating!! May 18 15:14:24.948937 ASM moved to state: AUTHENTICATED !! May 18 15:14:24.949072 BSM moved to state: IDLE !! May 18 15:14:24.949220 ASIF: Handing over Server frame to Authenticator 71. May 18 15:14:24.949802 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 18 15:14:24.949928 Rad Attr Class Len = 0 May 18 15:14:24.950026 ASIF: Received No Key from Server May 18 15:14:24.950165 Accounting order not configured, do not send acctng req May 18 15:14:24.950265 Processing authentication response complete May 18 15:14:24.950564 Deleted background job to process replies from authentication client May 18 15:14:25.253133 ASM Called with Event: EAPLOGOFF_RCVD, and State: Authenticated comment: WHY SWITCH RESTARTs AUTH... I NEED GUEST VLAN FOR THIS PORT: "VLAN ROUTED_Registration" May 18 15:14:25.253902 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:25.254042 Id: 1, SessionNode: 23d554 May 18 15:14:25.254179 ASM: Inside PnacAuthAsmEapLogoffAuthenticated May 18 15:14:25.255981 pnac_client_delete_session: MAC: 00:26:22:53:3d:f1 authenticatedStateCause: 10 AuthenticatorState: 5 SessionID: 9322734816758426484 May 18 15:14:25.256112 Radius Accounting not configured May 18 15:14:25.256247 Queuing session cleanup message message to auth client May 18 15:14:25.259186 Deleting Dynamic filter dot1x_ge-0/0/2_DOT1X_dotmac_002622533df1 May 18 15:14:25.259317 remove_filter_from_list:1197 is_wl= 0 is_sm= 0 May 18 15:14:25.263899 PnacAuthSessStatsInit PnacAuthSessStatsInit May 18 15:14:25.264072 ASM moved to state: DISCONNECTED !! May 18 15:14:25.264222 Trying to delete the session for MAC: -262253-3df1- on Port: 71 ... May 18 15:14:25.266008 Trying to delete the Session Node : 23d554 ... May 18 15:14:25.268706 Session Deleted for MAC: -262253-3df1- on Port: 71 ... May 18 15:14:25.268872 Deleted Session Node : 23d554 ... May 18 15:14:25.271758 Sending message to authentication client May 18 15:14:25.273307 Received message from authentication client May 18 15:14:25.276145 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:2944 reply_len:2944 May 18 15:14:25.276282 hdr_bytes_read 0 May 18 15:14:25.277133 len read : 28 reply_len: 28 May 18 15:14:25.277268 bytes_remnant 0 tot_bytes_read 28 May 18 15:14:25.277496 Creating background job to process reply from authentication client May 18 15:14:25.277753 Entering background job to process message from authentication client May 18 15:14:25.277875 process_auth_reply len:28 May 18 15:14:25.277997 Error retrieving session node. May 18 15:14:25.278105 Error response from authentication client. Message not processed further May 18 15:14:25.278204 Accounting order not configured, do not send acctng req May 18 15:14:25.278309 ASIF: Handing over Server frame to Authenticator May 18 15:14:25.278762 AUTH: Handling Server Frame May 18 15:14:25.278911 Port Info is NULL for portnum:0 May 18 15:14:25.279009 ASIF: Authenticator handling of Server frame failed May 18 15:14:25.279170 Deleted background job to process replies from authentication client May 18 15:14:25.292704 pnac_cleanup_white_list_entries_on_vmember_del:392:CP_DEBUG: May 18 15:14:29.172587 dot1xd transaction accepted by dfwd May 18 15:14:29.173050 dot1xd transaction accepted by dfwd May 18 15:14:29.173221 dot1xd transaction accepted by dfwd May 18 15:14:29.173546 dot1xd transaction accepted by dfwd May 18 15:14:37.473770 Trying to create a session for MAC: -262253-3df1- thro' Port: 71 ... May 18 15:14:37.474477 queried vid:7 May 18 15:14:37.474590 Allocated Session Node : 23d554 May 18 15:14:37.474802 PnacAuthSessStatsInit PnacAuthSessStatsInit May 18 15:14:37.475163 Generated Unique SessId :9322734829642923832 May 18 15:14:37.475304 BSM Called with Event: INITIALIZE, and State: Initialise May 18 15:14:37.475582 for Port: 71, MAC: 262253-3df1 May 18 15:14:37.475735 Id: 0, SessionNode: 23d554 May 18 15:14:37.475873 BSM moved to state: INITIALIZE !! May 18 15:14:37.476139 BSM moved to state: IDLE !! May 18 15:14:37.476328 ASM Called with Event: INITIALIZE, and State: Initialize May 18 15:14:37.476480 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:37.476619 Id: 0, SessionNode: 23d554 May 18 15:14:37.476750 ASM: Inside PnacAuthAsmInitInitialize May 18 15:14:37.476890 ASM moved to state: INITIALIZE !! May 18 15:14:37.477162 ASM moved to state: DISCONNECTED !! May 18 15:14:37.477345 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 0 Reauth Count 0 May 18 15:14:37.477491 ASM moved to state: CONNECTING !! May 18 15:14:37.477652 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:14:37.477820 TMR: Timer is started May 18 15:14:37.478256 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:14:37.478474 EAP Frame Sent with code: 1 !!! May 18 15:14:37.478646 EAP Req Id Frame Sent !!! May 18 15:14:37.478804 Session Created for MAC: -262253-3df1- through Port: 71 ... May 18 15:14:37.479440 Task transmit function invoked May 18 15:14:37.479739 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:14:44.474061 TMR: In txWhen_expiry() May 18 15:14:44.474549 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:14:44.474711 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:44.475417 Id: 0, SessionNode: 23d554 May 18 15:14:44.475594 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 1 Max Req 2 May 18 15:14:44.475752 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 1 Reauth Count 0 May 18 15:14:44.476059 ASM moved to state: CONNECTING !! May 18 15:14:44.476233 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:14:44.476403 TMR: Timer is started May 18 15:14:44.476747 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:14:44.477466 EAP Frame Sent with code: 1 !!! May 18 15:14:44.477644 EAP Req Id Frame Sent !!! May 18 15:14:44.479499 Task transmit function invoked May 18 15:14:44.481554 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:14:51.474851 TMR: In txWhen_expiry() May 18 15:14:51.475336 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:14:51.475500 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:51.475642 Id: 0, SessionNode: 23d554 May 18 15:14:51.476914 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 2 Max Req 2 May 18 15:14:51.477078 PnacAuthAsmInitMacRadius Intf ge-0/0/2.0: Launch MACAUTH. May 18 15:14:51.477291 ASM Called with Event: RXRESPID, and State: Connecting May 18 15:14:51.477456 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:51.477599 Id: 0, SessionNode: 23d554 May 18 15:14:51.478192 ASM: Inside PnacAuthAsmRxrespConnecting May 18 15:14:51.478344 ASM moved to state: AUTHENTICATING !! May 18 15:14:51.478500 BSM Called with Event: AUTHSTART, and State: Idle May 18 15:14:51.478691 for Port: 71, MAC: 262253-3df1 May 18 15:14:51.479450 Id: 0, SessionNode: 23d554 May 18 15:14:51.479627 TMR: Timer is started May 18 15:14:51.481437 BSM moved to state: RESPONSE !! May 18 15:14:51.481628 ASIF: Transferring Server-data to Auth Server for the user, 002622533df1. May 18 15:14:51.482315 SessId: 8O2.1x816101ee00073e8d strlen: 22 May 18 15:14:51.482595 Queuing message to auth client to validate mac address 0:26:22:53:3d:f1, user 002622533df1 on interface ge-0/0/2.0 May 18 15:14:51.482976 ASIF: Radius REQUEST_ID: db May 18 15:14:51.483099 ASIF: Tx of Server-data to Auth Server succeeded May 18 15:14:51.483397 Sending message to authentication client May 18 15:14:51.495360 Received message from authentication client May 18 15:14:51.495494 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:28 reply_len:28 May 18 15:14:51.495628 hdr_bytes_read 0 May 18 15:14:51.496468 len read : 28 reply_len: 2944 May 18 15:14:51.496600 bytes_remnant 2916 tot_bytes_read 28 May 18 15:14:51.497033 bytes_read 2916 May 18 15:14:51.497155 Creating background job to process reply from authentication client May 18 15:14:51.497404 Entering background job to process message from authentication client May 18 15:14:51.497529 process_auth_reply len:2944 May 18 15:14:51.498076 No VLAN attributes configured or Captive-Portal enabled May 18 15:14:51.498202 Received Access-Reject authentication message May 18 15:14:51.498311 Server reject_retry: (1) May 18 15:14:51.498422 Invoking state machine for authentication response for mac 00:26:22:53:3d:f1 on intf ge-0/0/2.0 May 18 15:14:51.498545 pnac_async_response_to_eswd_internal auth_vid:7, queried_vid:7 May 18 15:14:51.498840 pnac_process_auth_reply_result: server reject vlan configured May 18 15:14:51.498974 ASIF: Handing over Server frame to Authenticator May 18 15:14:51.499122 AUTH: Handling Server Frame May 18 15:14:51.499286 SessNode got from SessIdtbl for Id 0 is : 23d554, Port: 71 May 18 15:14:51.499433 Code = 4, Id = 0, Len = 1024 May 18 15:14:51.499581 BSM Called with Event: AFAIL_RCVD, and State: Response May 18 15:14:51.500186 for Port: 71, MAC: 262253-3df1 May 18 15:14:51.500327 Id: 0, SessionNode: 23d554 May 18 15:14:51.500487 TMR: Timer is deleted May 18 15:14:51.500632 BSM moved to state: FAIL !! May 18 15:14:51.502265 BSM moved to state: IDLE !! May 18 15:14:51.502430 ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating May 18 15:14:51.502583 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:51.503005 Id: 0, SessionNode: 23d554 May 18 15:14:51.503166 Auth Fail Count 1: Moved to state CONNECTING !! May 18 15:14:51.503325 ASM CONNECTING : Intf ge-0/0/2.0: ReqId Count 2 Reauth Count 0 May 18 15:14:51.503469 ASM moved to state: CONNECTING !! May 18 15:14:51.503629 TMR: Transmit Timer Started for port:71, Duration: 7 !! May 18 15:14:51.504250 TMR: Timer is started May 18 15:14:51.504883 Queuing EAPOL frame to be transmitted out on interface ge-0/0/2 May 18 15:14:51.505311 EAP Frame Sent with code: 1 !!! May 18 15:14:51.505596 EAP Req Id Frame Sent !!! May 18 15:14:51.506219 ASIF: Handing over Server frame to Authenticator 71. May 18 15:14:51.506373 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 18 15:14:51.506474 Rad Attr Class Len = 0 May 18 15:14:51.506571 ASIF: Received No Key from Server May 18 15:14:51.506881 Accounting order not configured, do not send acctng req May 18 15:14:51.506992 Processing authentication response complete May 18 15:14:51.507175 Deleted background job to process replies from authentication client May 18 15:14:51.507458 Task transmit function invoked May 18 15:14:51.508216 EAPOL frame transmitted out on interface (ge-0/0/2.0) May 18 15:14:58.498702 TMR: In txWhen_expiry() May 18 15:14:58.499187 ASM Called with Event: TXWHEN_EXPIRED, and State: Connecting May 18 15:14:58.499348 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:58.500067 Id: 1, SessionNode: 23d554 May 18 15:14:58.500245 ASM TxWhenTimer CONN: If ge-0/0/2.0: TxReqId Count 3 Max Req 2 May 18 15:14:58.500396 PnacAuthAsmInitMacRadius Intf ge-0/0/2.0: Launch MACAUTH. May 18 15:14:58.501239 ASM Called with Event: RXRESPID, and State: Connecting May 18 15:14:58.501402 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:58.502000 Id: 1, SessionNode: 23d554 May 18 15:14:58.502141 ASM: Inside PnacAuthAsmRxrespConnecting May 18 15:14:58.502288 ASM moved to state: AUTHENTICATING !! May 18 15:14:58.502439 BSM Called with Event: AUTHSTART, and State: Idle May 18 15:14:58.503319 for Port: 71, MAC: 262253-3df1 May 18 15:14:58.503504 Id: 1, SessionNode: 23d554 May 18 15:14:58.504082 TMR: Timer is started May 18 15:14:58.504231 BSM moved to state: RESPONSE !! May 18 15:14:58.504391 ASIF: Transferring Server-data to Auth Server for the user, 002622533df1. May 18 15:14:58.504728 SessId: 8O2.1x816101ee00073e8d strlen: 22 May 18 15:14:58.505017 Queuing message to auth client to validate mac address 0:26:22:53:3d:f1, user 002622533df1 on interface ge-0/0/2.0 May 18 15:14:58.506787 ASIF: Radius REQUEST_ID: dc May 18 15:14:58.506911 ASIF: Tx of Server-data to Auth Server succeeded May 18 15:14:58.507210 Sending message to authentication client May 18 15:14:58.520143 Received message from authentication client May 18 15:14:58.520275 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:2944 reply_len:2944 May 18 15:14:58.520405 hdr_bytes_read 0 May 18 15:14:58.521437 len read : 28 reply_len: 2944 May 18 15:14:58.521997 bytes_remnant 2916 tot_bytes_read 28 May 18 15:14:58.522167 bytes_read 2916 May 18 15:14:58.522272 Creating background job to process reply from authentication client May 18 15:14:58.522637 Entering background job to process message from authentication client May 18 15:14:58.522769 process_auth_reply len:2944 May 18 15:14:58.522902 No VLAN attributes configured or Captive-Portal enabled May 18 15:14:58.523008 Received Access-Reject authentication message May 18 15:14:58.523119 pnac_apply_access_reject_vlan portnum:71 May 18 15:14:58.523228 Server Reject VLAN ROUTED_Registration May 18 15:14:58.524761 Invoking state machine for authentication response for mac 00:26:22:53:3d:f1 on intf ge-0/0/2.0 May 18 15:14:58.524940 vlan data is NULL for idx 71 May 18 15:14:58.525193 Client moved to dynamic VLAN ROUTED_Registration May 18 15:14:58.528368 pnac_process_auth_reply_result: server reject vlan configured May 18 15:14:58.531266 ASIF: Handing over Server frame to Authenticator May 18 15:14:58.531427 AUTH: Handling Server Frame May 18 15:14:58.532245 SessNode got from SessIdtbl for Id 0 is : 23d554, Port: 71 May 18 15:14:58.532389 Code = 3, Id = 0, Len = 1024 May 18 15:14:58.533322 BSM Called with Event: ASUCCESS_RCVD, and State: Response May 18 15:14:58.534033 for Port: 71, MAC: 262253-3df1 May 18 15:14:58.534193 Id: 1, SessionNode: 23d554 May 18 15:14:58.534344 TMR: Timer is deleted May 18 15:14:58.534613 BSM moved to state: SUCCESS !! May 18 15:14:58.534787 ASM Called with Event: BKEND_AUTHSUCCESS, and State: Authenticating May 18 15:14:58.534936 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:58.535224 Id: 0, SessionNode: 23d554 May 18 15:14:58.535394 ASM moved to state: AUTHENTICATED from Authenticating!! May 18 15:14:58.536884 ASM moved to state: AUTHENTICATED !! May 18 15:14:58.537022 BSM moved to state: IDLE !! May 18 15:14:58.537172 ASIF: Handing over Server frame to Authenticator 71. May 18 15:14:58.537342 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 18 15:14:58.537445 Rad Attr Class Len = 0 May 18 15:14:58.537993 ASIF: Received No Key from Server May 18 15:14:58.538133 Accounting order not configured, do not send acctng req May 18 15:14:58.538227 Processing authentication response complete May 18 15:14:58.538393 Deleted background job to process replies from authentication client May 18 15:14:58.867239 ASM Called with Event: EAPLOGOFF_RCVD, and State: Authenticated comment: AND so on.... May 18 15:14:58.867419 for Port: 71, MAC: 262253 - 3df1 May 18 15:14:58.868172 Id: 1, SessionNode: 23d554 May 18 15:14:58.868304 ASM: Inside PnacAuthAsmEapLogoffAuthenticated May 18 15:14:58.870360 pnac_client_delete_session: MAC: 00:26:22:53:3d:f1 authenticatedStateCause: 10 AuthenticatorState: 5 SessionID: 9322734829642923832 May 18 15:14:58.872024 Radius Accounting not configured May 18 15:14:58.872180 Queuing session cleanup message message to auth client May 18 15:14:58.872922 Deleting Dynamic filter dot1x_ge-0/0/2_DOT1X_dotmac_002622533df1 May 18 15:14:58.873052 remove_filter_from_list:1197 is_wl= 0 is_sm= 0 May 18 15:14:58.874390 PnacAuthSessStatsInit PnacAuthSessStatsInit May 18 15:14:58.876725 ASM moved to state: DISCONNECTED !! May 18 15:14:58.876872 Trying to delete the session for MAC: -262253-3df1- on Port: 71 ... May 18 15:14:58.877008 Trying to delete the Session Node : 23d554 ... May 18 15:14:58.877311 Session Deleted for MAC: -262253-3df1- on Port: 71 ... May 18 15:14:58.878886 Deleted Session Node : 23d554 ... May 18 15:14:58.879426 Sending message to authentication client May 18 15:14:58.882428 Received message from authentication client May 18 15:14:58.886428 reply: 1ca03c rply_hdr: 1cf000 bytes_remnant :0 len:2944 reply_len:2944 May 18 15:14:58.886713 hdr_bytes_read 0 May 18 15:14:58.886824 len read : 28 reply_len: 28 May 18 15:14:58.886934 bytes_remnant 0 tot_bytes_read 28 May 18 15:14:58.887033 Creating background job to process reply from authentication client May 18 15:14:58.887269 Entering background job to process message from authentication client May 18 15:14:58.887389 process_auth_reply len:28 May 18 15:14:58.887639 Error retrieving session node. May 18 15:14:58.887761 Error response from authentication client. Message not processed further May 18 15:14:58.887864 Accounting order not configured, do not send acctng req May 18 15:14:58.888038 ASIF: Handing over Server frame to Authenticator May 18 15:14:58.888194 AUTH: Handling Server Frame May 18 15:14:58.888338 Port Info is NULL for portnum:0 May 18 15:14:58.888434 ASIF: Authenticator handling of Server frame failed May 18 15:14:58.888757 Deleted background job to process replies from authentication client May 18 15:14:58.903306 pnac_cleanup_white_list_entries_on_vmember_del:392:CP_DEBUG: May 18 15:14:59.180829 dot1xd transaction accepted by dfwd May 18 15:14:59.181279 dot1xd transaction accepted by dfwd May 18 15:14:59.181430 dot1xd transaction accepted by dfwd May 18 15:14:59.183396 dot1xd transaction accepted by dfwd