=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2014.05.25 02:54:49 =~=~=~=~=~=~=~=~=~=~=~= May 25 02:47:49.137564 Disabling fallback on port 74 May 25 02:47:49.137820 Enabling PNAC on interface 74... May 25 02:47:49.137966 CD Machine called for Port: 74 with Event: PORTENABLED, State: FORCEBOTH May 25 02:47:49.138189 Successfully exiting CD Machine... May 25 02:47:49.138327 Enabled PNAC on interface 74... May 25 02:47:49.318738 Trying to create a session for MAC: -1c750832-72c- thro' Port: 74 ... May 25 02:47:49.318916 queried vid:5 May 25 02:47:49.319004 Allocated Session Node : aa8aa8 May 25 02:47:49.319911 PnacAuthSessStatsInit PnacAuthSessStatsInit May 25 02:47:49.320137 Generated Unique SessId :9363828634349265476 May 25 02:47:49.320264 BSM Called with Event: INITIALIZE, and State: Initialise May 25 02:47:49.320399 for Port: 74, MAC: 1c750832-72c May 25 02:47:49.320517 Id: 0, SessionNode: aa8aa8 May 25 02:47:49.320626 BSM moved to state: INITIALIZE !! May 25 02:47:49.320751 BSM moved to state: IDLE !! May 25 02:47:49.320878 ASM Called with Event: INITIALIZE, and State: Initialize May 25 02:47:49.320999 for Port: 74, MAC: 1c750832 - 72c May 25 02:47:49.321110 Id: 0, SessionNode: aa8aa8 May 25 02:47:49.321202 ASM: Inside PnacAuthAsmInitInitialize May 25 02:47:49.321456 ASM moved to state: INITIALIZE !! May 25 02:47:49.321607 ASM moved to state: DISCONNECTED !! May 25 02:47:49.321740 ASM CONNECTING : Intf fe-0/0/4.0: ReqId Count 0 Reauth Count 0 May 25 02:47:49.321866 ASM moved to state: CONNECTING !! May 25 02:47:49.321961 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/4.0 May 25 02:47:49.322078 PnacAuthAsmInitMacRadius Intf fe-0/0/4.0: Launch MACAUTH. May 25 02:47:49.322259 ASM Called with Event: RXRESPID, and State: Connecting May 25 02:47:49.322422 for Port: 74, MAC: 1c750832 - 72c May 25 02:47:49.322564 Id: 0, SessionNode: aa8aa8 May 25 02:47:49.322693 ASM: Inside PnacAuthAsmRxrespConnecting May 25 02:47:49.322822 ASM moved to state: AUTHENTICATING !! May 25 02:47:49.322949 BSM Called with Event: AUTHSTART, and State: Idle May 25 02:47:49.323069 for Port: 74, MAC: 1c750832-72c May 25 02:47:49.323184 Id: 0, SessionNode: aa8aa8 May 25 02:47:49.323376 TMR: Timer is started May 25 02:47:49.323518 BSM moved to state: RESPONSE !! May 25 02:47:49.323752 ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c. May 25 02:47:49.323914 SessId: 8O2.1x81f300870004ded4 strlen: 22 May 25 02:47:49.324181 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/4.0 May 25 02:47:49.324624 ASIF: Radius REQUEST_ID: b9 May 25 02:47:49.324758 ASIF: Tx of Server-data to Auth Server succeeded May 25 02:47:49.324898 Session Created for MAC: -1c750832-72c- through Port: 74 ... May 25 02:47:49.325324 Sending message to authentication client May 25 02:48:04.634623 snmp_epi_register: called May 25 02:48:07.362314 Received message from authentication client May 25 02:48:07.362541 reply: a69028 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28 May 25 02:48:07.362674 hdr_bytes_read 0 May 25 02:48:07.362772 len read : 28 reply_len: 28 May 25 02:48:07.362853 bytes_remnant 0 tot_bytes_read 28 May 25 02:48:07.362966 Creating background job to process reply from authentication client May 25 02:48:07.363184 Entering background job to process message from authentication client May 25 02:48:07.363307 process_auth_reply len:28 May 25 02:48:07.363404 Authentication client could not contact RADIUS servers May 25 02:48:07.363521 pnac_apply_server_fail_action portnum:74 May 25 02:48:07.363602 Server Fail 0 May 25 02:48:07.363691 Server Fail Deny/Default May 25 02:48:07.363775 (pnac_construct_eap_pkt) code:4 May 25 02:48:07.363848 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C May 25 02:48:07.363938 on intf fe-0/0/4.0 May 25 02:48:07.364205 authenticatedStateCause :1 May 25 02:48:07.364325 ASIF: Handing over Server frame to Authenticator May 25 02:48:07.364446 AUTH: Handling Server Frame May 25 02:48:07.364583 SessNode got from SessIdtbl for Id 0 is : aa8aa8, Port: 74 May 25 02:48:07.364704 Code = 4, Id = 0, Len = 4 May 25 02:48:07.364822 BSM Called with Event: AFAIL_RCVD, and State: Response May 25 02:48:07.364941 for Port: 74, MAC: 1c750832-72c May 25 02:48:07.365177 Id: 0, SessionNode: aa8aa8 May 25 02:48:07.365321 TMR: Timer is deleted May 25 02:48:07.365447 BSM moved to state: FAIL !! May 25 02:48:07.365586 BSM moved to state: IDLE !! May 25 02:48:07.365718 ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating May 25 02:48:07.365840 for Port: 74, MAC: 1c750832 - 72c May 25 02:48:07.365955 Id: 0, SessionNode: aa8aa8 May 25 02:48:07.366121 Auth Fail Count 1: Moved to state CONNECTING !! May 25 02:48:07.366260 ASM CONNECTING : Intf fe-0/0/4.0: ReqId Count 0 Reauth Count 0 May 25 02:48:07.366376 ASM moved to state: CONNECTING !! May 25 02:48:07.366473 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/4.0 May 25 02:48:07.366602 PnacAuthAsmInitMacRadius Intf fe-0/0/4.0: Launch MACAUTH. May 25 02:48:07.366792 ASM Called with Event: RXRESPID, and State: Connecting May 25 02:48:07.366932 for Port: 74, MAC: 1c750832 - 72c May 25 02:48:07.367072 Id: 1, SessionNode: aa8aa8 May 25 02:48:07.367195 ASM: Inside PnacAuthAsmRxrespConnecting May 25 02:48:07.367315 ASM moved to state: AUTHENTICATING !! May 25 02:48:07.367437 BSM Called with Event: AUTHSTART, and State: Idle May 25 02:48:07.367561 for Port: 74, MAC: 1c750832-72c May 25 02:48:07.367687 Id: 1, SessionNode: aa8aa8 May 25 02:48:07.367843 TMR: Timer is started May 25 02:48:07.367977 BSM moved to state: RESPONSE !! May 25 02:48:07.368166 ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c. May 25 02:48:07.368324 SessId: 8O2.1x81f300870004ded4 strlen: 22 May 25 02:48:07.368591 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/4.0 May 25 02:48:07.370281 ASIF: Radius REQUEST_ID: ba May 25 02:48:07.370422 ASIF: Tx of Server-data to Auth Server succeeded May 25 02:48:07.370568 ASIF: Handing over Server frame to Authenticator 74. May 25 02:48:07.370706 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 25 02:48:07.370797 Rad Attr Class Len = 0 May 25 02:48:07.370954 ASIF: Received No Key from Server May 25 02:48:07.371086 Processing authentication response complete May 25 02:48:07.371246 Deleted background job to process replies from authentication client May 25 02:48:07.371848 Sending message to authentication client May 25 02:48:07.376540 Received message from authentication client May 25 02:48:07.376693 reply: a69028 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28 May 25 02:48:07.376826 hdr_bytes_read 0 May 25 02:48:07.376926 len read : 28 reply_len: 28 May 25 02:48:07.377036 bytes_remnant 0 tot_bytes_read 28 May 25 02:48:07.377129 Creating background job to process reply from authentication client May 25 02:48:07.377324 Entering background job to process message from authentication client May 25 02:48:07.377444 process_auth_reply len:28 May 25 02:48:07.378883 Authentication client could not contact RADIUS servers May 25 02:48:07.379004 pnac_apply_server_fail_action portnum:74 May 25 02:48:07.379099 Server Fail 0 May 25 02:48:07.379191 Server Fail Deny/Default May 25 02:48:07.379278 (pnac_construct_eap_pkt) code:4 May 25 02:48:07.379371 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C May 25 02:48:07.379448 on intf fe-0/0/4.0 May 25 02:48:07.379899 pnac_async_response_to_eswd_internal auth_vid:5, queried_vid:5 May 25 02:48:07.380074 authenticatedStateCause :1 May 25 02:48:07.380185 ASIF: Handing over Server frame to Authenticator May 25 02:48:07.380309 AUTH: Handling Server Frame May 25 02:48:07.380439 SessNode got from SessIdtbl for Id 0 is : aa8aa8, Port: 74 May 25 02:48:07.380718 Code = 4, Id = 0, Len = 4 May 25 02:48:07.380902 BSM Called with Event: AFAIL_RCVD, and State: Response May 25 02:48:07.381029 for Port: 74, MAC: 1c750832-72c May 25 02:48:07.381161 Id: 1, SessionNode: aa8aa8 May 25 02:48:07.381298 TMR: Timer is deleted May 25 02:48:07.381424 BSM moved to state: FAIL !! May 25 02:48:07.381559 BSM moved to state: IDLE !! May 25 02:48:07.381900 ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating May 25 02:48:07.382135 for Port: 74, MAC: 1c750832 - 72c May 25 02:48:07.382259 Id: 0, SessionNode: aa8aa8 May 25 02:48:07.382386 Auth Fail Count 2: Moved to state CONNECTING !! May 25 02:48:07.382518 ASM CONNECTING : Intf fe-0/0/4.0: ReqId Count 0 Reauth Count 0 May 25 02:48:07.382651 ASM moved to state: CONNECTING !! May 25 02:48:07.382779 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/4.0 May 25 02:48:07.382899 PnacAuthAsmInitMacRadius Intf fe-0/0/4.0: Launch MACAUTH. May 25 02:48:07.383065 ASM Called with Event: RXRESPID, and State: Connecting May 25 02:48:07.383213 for Port: 74, MAC: 1c750832 - 72c May 25 02:48:07.383334 Id: 1, SessionNode: aa8aa8 May 25 02:48:07.383447 ASM: Inside PnacAuthAsmRxrespConnecting May 25 02:48:07.383566 ASM moved to state: AUTHENTICATING !! May 25 02:48:07.383940 BSM Called with Event: AUTHSTART, and State: Idle May 25 02:48:07.384129 for Port: 74, MAC: 1c750832-72c May 25 02:48:07.384261 Id: 1, SessionNode: aa8aa8 May 25 02:48:07.384424 TMR: Timer is started May 25 02:48:07.384565 BSM moved to state: RESPONSE !! May 25 02:48:07.384698 ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c. May 25 02:48:07.384891 SessId: 8O2.1x81f300870004ded4 strlen: 22 May 25 02:48:07.385154 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/4.0 May 25 02:48:07.385881 ASIF: Radius REQUEST_ID: bb May 25 02:48:07.386022 ASIF: Tx of Server-data to Auth Server succeeded May 25 02:48:07.386162 ASIF: Handing over Server frame to Authenticator 74. May 25 02:48:07.386302 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 25 02:48:07.386394 Rad Attr Class Len = 0 May 25 02:48:07.386463 ASIF: Received No Key from Server May 25 02:48:07.386577 Processing authentication response complete May 25 02:48:07.386686 Deleted background job to process replies from authentication client May 25 02:48:07.386935 Sending message to authentication client May 25 02:48:07.391545 Received message from authentication client May 25 02:48:07.391691 reply: a69028 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28 May 25 02:48:07.391819 hdr_bytes_read 0 May 25 02:48:07.391917 len read : 28 reply_len: 28 May 25 02:48:07.392372 bytes_remnant 0 tot_bytes_read 28 May 25 02:48:07.392514 Creating background job to process reply from authentication client May 25 02:48:07.392709 Entering background job to process message from authentication client May 25 02:48:07.392824 process_auth_reply len:28 May 25 02:48:07.392919 Authentication client could not contact RADIUS servers May 25 02:48:07.393156 pnac_apply_server_fail_action portnum:74 May 25 02:48:07.393290 Server Fail 0 May 25 02:48:07.393374 Server Fail Deny/Default May 25 02:48:07.393446 (pnac_construct_eap_pkt) code:4 May 25 02:48:07.393543 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C May 25 02:48:07.393634 on intf fe-0/0/4.0 May 25 02:48:07.393768 pnac_async_response_to_eswd_internal auth_vid:5, queried_vid:5 May 25 02:48:07.393921 authenticatedStateCause :1 May 25 02:48:07.394030 ASIF: Handing over Server frame to Authenticator May 25 02:48:07.394455 AUTH: Handling Server Frame May 25 02:48:07.394597 SessNode got from SessIdtbl for Id 0 is : aa8aa8, Port: 74 May 25 02:48:07.394718 Code = 4, Id = 0, Len = 4 May 25 02:48:07.394837 BSM Called with Event: AFAIL_RCVD, and State: Response May 25 02:48:07.394953 for Port: 74, MAC: 1c750832-72c May 25 02:48:07.395065 Id: 1, SessionNode: aa8aa8 May 25 02:48:07.395220 TMR: Timer is deleted May 25 02:48:07.395343 BSM moved to state: FAIL !! May 25 02:48:07.395472 BSM moved to state: IDLE !! May 25 02:48:07.395621 ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating May 25 02:48:07.395724 for Port: 74, MAC: 1c750832 - 72c May 25 02:48:07.395837 Id: 0, SessionNode: aa8aa8 May 25 02:48:07.395975 TMR: Quiet While Timer Started for port:74, Duration: 60 !! May 25 02:48:07.396898 TMR: Timer is started May 25 02:48:07.397224 ASM moved to state: HELD !! May 25 02:48:07.397384 ASIF: Handing over Server frame to Authenticator 74. May 25 02:48:07.397510 PnacAsIfRecvFromServer : Rad Attr Statelen = 0 May 25 02:48:07.397579 Rad Attr Class Len = 0 May 25 02:48:07.397667 ASIF: Received No Key from Server May 25 02:48:07.397782 Processing authentication response complete May 25 02:48:07.397889 Deleted background job to process replies from authentication client [edit security policies] marlon@Jlab-R2# exit [edit] marlon@Jlab-R2# u quit Exiting configuration mode marlon@Jlab-R2> monitor do ^ syntax error, expecting . marlon@Jlab-R2> monitor do   ? Possible completions: interface Show interface traffic label-switched-path Show label-switched-path traffic list Show status of monitored files start Start showing log file in real time static-lsp Show static label-switched-path traffic stop Stop showing log file in real time traffic Show real-time network traffic information marlon@Jlab-R2> monitor  May 25 02:49:07.403027 ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held May 25 02:49:07.403269 for Port: 74, MAC: 1c750832 - 72c May 25 02:49:07.403397 Id: 1, SessionNode: aa8aa8 May 25 02:49:07.403526 Trying to delete the session for MAC: -1c750832-72c- on Port: 74 ... May 25 02:49:07.403643 Trying to delete the Session Node : aa8aa8 ... May 25 02:49:07.403778 Session Deleted for MAC: -1c750832-72c- on Port: 74 ... May 25 02:49:07.403947 Deleted Session Node : aa8aa8 ... May 25 02:49:07.404067 QuietWhile Timer Expired and Session deleted !!! marlon@Jlab-R2> show dot1x interface 802.1X Information: Interface Role State MAC address User fe-0/0/3.0 Authenticator Initialize fe-0/0/4.0 Authenticator Authenticating 1C:75:08:32:07:2C 1c750832072c marlon@Jlab-R2> May 25 02:49:11.876545 Trying to create a session for MAC: -1c750832-72c- thro' Port: 74 ... May 25 02:49:11.876777 queried vid:5 May 25 02:49:11.876879 Allocated Session Node : aa8aa8 May 25 02:49:11.877077 PnacAuthSessStatsInit PnacAuthSessStatsInit May 25 02:49:11.877245 Generated Unique SessId :9363828642939757191 May 25 02:49:11.877347 BSM Called with Event: INITIALIZE, and State: Initialise May 25 02:49:11.877506 for Port: 74, MAC: 1c750832-72c May 25 02:49:11.877624 Id: 0, SessionNode: aa8aa8 May 25 02:49:11.877734 BSM moved to state: INITIALIZE !! May 25 02:49:11.877848 BSM moved to state: IDLE !! May 25 02:49:11.877982 ASM Called with Event: INITIALIZE, and State: Initialize May 25 02:49:11.878102 for Port: 74, MAC: 1c750832 - 72c May 25 02:49:11.878210 Id: 0, SessionNode: aa8aa8 May 25 02:49:11.878318 ASM: Inside PnacAuthAsmInitInitialize May 25 02:49:11.878461 ASM moved to state: INITIALIZE !! May 25 02:49:11.878590 ASM moved to state: DISCONNECTED !! May 25 02:49:11.878836 ASM CONNECTING : Intf fe-0/0/4.0: ReqId Count 0 Reauth Count 0 May 25 02:49:11.878966 ASM moved to state: CONNECTING !! May 25 02:49:11.879076 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/4.0 May 25 02:49:11.879192 PnacAuthAsmInitMacRadius Intf fe-0/0/4.0: Launch MACAUTH. May 25 02:49:11.879360 ASM Called with Event: RXRESPID, and State: Connecting May 25 02:49:11.879540 for Port: 74, MAC: 1c750832 - 72c May 25 02:49:11.879656 Id: 0, SessionNode: aa8aa8 May 25 02:49:11.879769 ASM: Inside PnacAuthAsmRxrespConnecting May 25 02:49:11.879887 ASM moved to state: AUTHENTICATING !! May 25 02:49:11.880019 BSM Called with Event: AUTHSTART, and State: Idle May 25 02:49:11.880143 for Port: 74, MAC: 1c750832-72c May 25 02:49:11.880257 Id: 0, SessionNode: aa8aa8 May 25 02:49:11.880412 TMR: Timer is started May 25 02:49:11.880833 BSM moved to state: RESPONSE !! May 25 02:49:11.880974 ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c. May 25 02:49:11.881140 SessId: 8O2.1x81f30089000d61e9 strlen: 22 May 25 02:49:11.881409 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/4.0 May 25 02:49:11.882098 ASIF: Radius REQUEST_ID: bc May 25 02:49:11.882237 ASIF: Tx of Server-data to Auth Server succeeded May 25 02:49:11.882370 Session Created for MAC: -1c750832-72c- through Port: 74 ... May 25 02:49:11.882908 Sending message to authentication client May 25 02:49:15.229959 mgmt_listen_accept: accepting connection from local May 25 02:49:15.237235 tasknb_recv: local: received unexpected EOF May 25 02:49:15.237378 mgmtp_close: closing peer local, event 3