=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2014.05.28 00:55:27 =~=~=~=~=~=~=~=~=~=~=~=
May 28 00:47:42.426562  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:47:42.426808  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:47:42.426933  Id: 1, SessionNode: aa4aa8 

May 28 00:47:42.427059  Trying to delete the session for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:47:42.427175  Trying to delete the Session Node : aa4aa8 ... 

May 28 00:47:42.427308  Session Deleted for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:47:42.427449  Deleted Session Node : aa4aa8 ... 

May 28 00:47:42.427574 QuietWhile Timer Expired and Session deleted !!!
 
May 28 00:47:43.584588  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:47:43.584822  for Port: 73, MAC: 80027df - fe56 

May 28 00:47:43.584950  Id: 1, SessionNode: aad000 

May 28 00:47:43.585076  Trying to delete the session for MAC: -80027df-fe56- on Port: 73 ... 

May 28 00:47:43.585194  Trying to delete the Session Node : aad000 ... 

May 28 00:47:43.585457  Session Deleted for MAC: -80027df-fe56- on Port: 73 ... 

May 28 00:47:43.585589  Deleted Session Node : aad000 ... 

May 28 00:47:43.585771 QuietWhile Timer Expired and Session deleted !!!
 
May 28 00:47:45.899516  Trying to create a session for MAC: -80027df-fe56- thro' Port: 73 ... 

May 28 00:47:45.899751 queried vid:4
May 28 00:47:45.899845  Allocated Session Node : aa4aa8 

May 28 00:47:45.900010  PnacAuthSessStatsInit PnacAuthSessStatsInit

May 28 00:47:45.900199 Generated Unique SessId :9299371457388592164
May 28 00:47:45.901085  BSM Called with Event: INITIALIZE, and State: Initialise

May 28 00:47:45.901267  for Port: 73, MAC: 80027df-fe56 

May 28 00:47:45.901408  Id: 0, SessionNode: aa4aa8 

May 28 00:47:45.901524  BSM moved to state: INITIALIZE !!

May 28 00:47:45.901642  BSM moved to state: IDLE !!

May 28 00:47:45.901767  ASM Called with Event: INITIALIZE, and State: Initialize

May 28 00:47:45.901895  for Port: 73, MAC: 80027df - fe56 

May 28 00:47:45.902012  Id: 0, SessionNode: aa4aa8 

May 28 00:47:45.902121 ASM: Inside PnacAuthAsmInitInitialize

May 28 00:47:45.902233  ASM moved to state: INITIALIZE !!

May 28 00:47:45.902459  ASM moved to state: DISCONNECTED !!

May 28 00:47:45.902617 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:47:45.902735  ASM moved to state: CONNECTING !!

May 28 00:47:45.902857 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:47:45.902976 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:47:45.903142  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:47:45.903278  for Port: 73, MAC: 80027df - fe56 

May 28 00:47:45.903417  Id: 0, SessionNode: aa4aa8 

May 28 00:47:45.903536 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:47:45.903651  ASM moved to state: AUTHENTICATING !!

May 28 00:47:45.903770  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:47:45.903893  for Port: 73, MAC: 80027df-fe56 

May 28 00:47:45.904007  Id: 0, SessionNode: aa4aa8 

May 28 00:47:45.904279  TMR: Timer is started

May 28 00:47:45.904463  BSM moved to state: RESPONSE !!

May 28 00:47:45.904592  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:47:45.904732 SessId: 8O2.1x810e0111000dbb95 strlen: 22
May 28 00:47:45.904996 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:47:45.905406  ASIF: Radius REQUEST_ID: ef
May 28 00:47:45.905575  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:47:45.905710  Session Created for MAC: -80027df-fe56- through Port: 73 ... 

May 28 00:47:45.906140 Sending message to authentication client
May 28 00:47:56.398677  Trying to create a session for MAC: -1c750832-72c- thro' Port: 73 ... 

May 28 00:47:56.398957 queried vid:4
May 28 00:47:56.399045  Allocated Session Node : aad000 

May 28 00:47:56.399238  PnacAuthSessStatsInit PnacAuthSessStatsInit

May 28 00:47:56.399418 Generated Unique SessId :9299371465978025992
May 28 00:47:56.399520  BSM Called with Event: INITIALIZE, and State: Initialise

May 28 00:47:56.399644  for Port: 73, MAC: 1c750832-72c 

May 28 00:47:56.399762  Id: 0, SessionNode: aad000 

May 28 00:47:56.399982  BSM moved to state: INITIALIZE !!

May 28 00:47:56.400138  BSM moved to state: IDLE !!

May 28 00:47:56.400264  ASM Called with Event: INITIALIZE, and State: Initialize

May 28 00:47:56.400416  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:47:56.400553  Id: 0, SessionNode: aad000 

May 28 00:47:56.400664 ASM: Inside PnacAuthAsmInitInitialize

May 28 00:47:56.400780  ASM moved to state: INITIALIZE !!

May 28 00:47:56.400920  ASM moved to state: DISCONNECTED !!

May 28 00:47:56.401059 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:47:56.401179  ASM moved to state: CONNECTING !!

May 28 00:47:56.401294 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:47:56.401413 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:47:56.401604  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:47:56.401841  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:47:56.401983  Id: 0, SessionNode: aad000 

May 28 00:47:56.402097 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:47:56.402213  ASM moved to state: AUTHENTICATING !!

May 28 00:47:56.402331  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:47:56.402433  for Port: 73, MAC: 1c750832-72c 

May 28 00:47:56.402555  Id: 0, SessionNode: aad000 

May 28 00:47:56.402728  TMR: Timer is started

May 28 00:47:56.402856  BSM moved to state: RESPONSE !!

May 28 00:47:56.403240  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:47:56.403453 SessId: 8O2.1x810e01130006176c strlen: 22
May 28 00:47:56.403726 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:47:56.404101  ASIF: Radius REQUEST_ID: f0
May 28 00:47:56.404234  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:47:56.404367  Session Created for MAC: -1c750832-72c- through Port: 73 ... 

May 28 00:47:56.404801 Sending message to authentication client
May 28 00:48:03.940189 Received message from authentication client
May 28 00:48:03.940394 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:48:03.940550 hdr_bytes_read 0
May 28 00:48:03.940652 len read : 28 reply_len: 28
May 28 00:48:03.940751 bytes_remnant 0 tot_bytes_read 28
May 28 00:48:03.940837 Creating background job to process reply from authentication client
May 28 00:48:03.941037 Entering background job to process message from authentication client
May 28 00:48:03.941158 process_auth_reply len:28
May 28 00:48:03.941253 Authentication client could not contact RADIUS servers
May 28 00:48:03.941356 pnac_apply_server_fail_action portnum:73
May 28 00:48:03.941475 Server Fail 0
May 28 00:48:03.941552 Server Fail Deny/Default
May 28 00:48:03.941643 (pnac_construct_eap_pkt) code:4
May 28 00:48:03.941737 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:48:03.942105 trace_rotate: Rotating trace files

*** 'dot1x' has been deleted ***

*** 'dot1x' has been created ***

*** dot1x ***
May 28 00:48:05 trace_on: Tracing to "/var/log/dot1x" started
May 28 00:48:03.942105  on intf fe-0/0/2.0

May 28 00:48:05.100371 authenticatedStateCause :1
May 28 00:48:05.100497  ASIF: Handing over Server frame to Authenticator

May 28 00:48:05.102786  AUTH: Handling Server Frame

May 28 00:48:05.103027  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:48:05.103202 Code = 4, Id = 0, Len = 4

May 28 00:48:05.103329  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:48:05.103504  for Port: 73, MAC: 80027df-fe56 

May 28 00:48:05.103647  Id: 0, SessionNode: aa4aa8 

May 28 00:48:05.104488  TMR: Timer is deleted

May 28 00:48:05.104657  BSM moved to state: FAIL !!

May 28 00:48:05.104827  BSM moved to state: IDLE !!

May 28 00:48:05.105012  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:48:05.105167  for Port: 73, MAC: 80027df - fe56 

May 28 00:48:05.105304  Id: 0, SessionNode: aa4aa8 

May 28 00:48:05.105464 Auth Fail Count 1: Moved to state CONNECTING !!

May 28 00:48:05.105594 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:48:05.107415  ASM moved to state: CONNECTING !!

May 28 00:48:05.107594 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:48:05.107776 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:48:05.108019  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:48:05.108214  for Port: 73, MAC: 80027df - fe56 

May 28 00:48:05.108334  Id: 1, SessionNode: aa4aa8 

May 28 00:48:05.108503 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:48:05.108655  ASM moved to state: AUTHENTICATING !!

May 28 00:48:05.108797  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:48:05.108992  for Port: 73, MAC: 80027df-fe56 

May 28 00:48:05.109094  Id: 1, SessionNode: aa4aa8 

May 28 00:48:05.109306  TMR: Timer is started

May 28 00:48:05.109501  BSM moved to state: RESPONSE !!

May 28 00:48:05.109667  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:48:05.109963 SessId: 8O2.1x810e0111000dbb95 strlen: 22
May 28 00:48:05.110299 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:48:05.110802  ASIF: Radius REQUEST_ID: f1
May 28 00:48:05.111454  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:48:05.111714  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:48:05.111900 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:48:05.112185 Rad Attr Class Len = 0
May 28 00:48:05.112322  ASIF: Received No Key from Server

May 28 00:48:05.112498 Processing authentication response complete
May 28 00:48:05.112651 Deleted background job to process replies from authentication client
May 28 00:48:05.112912 Sending message to authentication client
May 28 00:48:05.221331 Received message from authentication client
May 28 00:48:05.221498 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:48:05.221636 hdr_bytes_read 0
May 28 00:48:05.221728 len read : 28 reply_len: 28
May 28 00:48:05.221813 bytes_remnant 0 tot_bytes_read 28
May 28 00:48:05.221900 Creating background job to process reply from authentication client
May 28 00:48:05.222080 Entering background job to process message from authentication client
May 28 00:48:05.222237 process_auth_reply len:28
May 28 00:48:05.222321 Authentication client could not contact RADIUS servers
May 28 00:48:05.222430 pnac_apply_server_fail_action portnum:73
May 28 00:48:05.222527 Server Fail 0
May 28 00:48:05.222585 Server Fail Deny/Default
May 28 00:48:05.222682 (pnac_construct_eap_pkt) code:4
May 28 00:48:05.222777 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:48:05.222865  on intf fe-0/0/2.0

May 28 00:48:05.222998 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:48:05.223111 authenticatedStateCause :1
May 28 00:48:05.225504  ASIF: Handing over Server frame to Authenticator

May 28 00:48:05.225678  AUTH: Handling Server Frame

May 28 00:48:05.225817  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:48:05.226167 Code = 4, Id = 0, Len = 4

May 28 00:48:05.226331  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:48:05.226457  for Port: 73, MAC: 80027df-fe56 

May 28 00:48:05.226572  Id: 1, SessionNode: aa4aa8 

May 28 00:48:05.226697  TMR: Timer is deleted

May 28 00:48:05.226821  BSM moved to state: FAIL !!

May 28 00:48:05.226953  BSM moved to state: IDLE !!

May 28 00:48:05.227077  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:48:05.227198  for Port: 73, MAC: 80027df - fe56 

May 28 00:48:05.233666  Id: 0, SessionNode: aa4aa8 

May 28 00:48:05.233808 Auth Fail Count 2: Moved to state CONNECTING !!

May 28 00:48:05.233957 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:48:05.234077  ASM moved to state: CONNECTING !!

May 28 00:48:05.234192 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:48:05.234311 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:48:05.234517  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:48:05.234666  for Port: 73, MAC: 80027df - fe56 

May 28 00:48:05.234784  Id: 1, SessionNode: aa4aa8 

May 28 00:48:05.234893 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:48:05.235009  ASM moved to state: AUTHENTICATING !!

May 28 00:48:05.235133  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:48:05.235248  for Port: 73, MAC: 80027df-fe56 

May 28 00:48:05.235359  Id: 1, SessionNode: aa4aa8 

May 28 00:48:05.237392  TMR: Timer is started

May 28 00:48:05.241801  BSM moved to state: RESPONSE !!

May 28 00:48:05.242159  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:48:05.242383 SessId: 8O2.1x810e0111000dbb95 strlen: 22
May 28 00:48:05.242693 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:48:05.244044  ASIF: Radius REQUEST_ID: f2
May 28 00:48:05.244232  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:48:05.244519  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:48:05.244702 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:48:05.244946 Rad Attr Class Len = 0
May 28 00:48:05.245069  ASIF: Received No Key from Server

May 28 00:48:05.245243 Processing authentication response complete
May 28 00:48:05.245393 Deleted background job to process replies from authentication client
May 28 00:48:05.245660 Sending message to authentication client
May 28 00:48:05.251745 Received message from authentication client
May 28 00:48:05.251929 reply: a69b2c rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:48:05.252065 hdr_bytes_read 0
May 28 00:48:05.253316 len read : 28 reply_len: 28
May 28 00:48:05.253518 bytes_remnant 0 tot_bytes_read 28
May 28 00:48:05.253648 Creating background job to process reply from authentication client
May 28 00:48:05.253913 Entering background job to process message from authentication client
May 28 00:48:05.254043 process_auth_reply len:28
May 28 00:48:05.254174 Authentication client could not contact RADIUS servers
May 28 00:48:05.254316 pnac_apply_server_fail_action portnum:73
May 28 00:48:05.254477 Server Fail 0
May 28 00:48:05.254552 Server Fail Deny/Default
May 28 00:48:05.254666 (pnac_construct_eap_pkt) code:4
May 28 00:48:05.254777 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:48:05.254899  on intf fe-0/0/2.0

May 28 00:48:05.255051 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:48:05.256437 authenticatedStateCause :1
May 28 00:48:05.256581  ASIF: Handing over Server frame to Authenticator

May 28 00:48:05.256699  AUTH: Handling Server Frame

May 28 00:48:05.256853  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:48:05.256974 Code = 4, Id = 0, Len = 4

May 28 00:48:05.257077  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:48:05.257196  for Port: 73, MAC: 80027df-fe56 

May 28 00:48:05.257365  Id: 1, SessionNode: aa4aa8 

May 28 00:48:05.259194  TMR: Timer is deleted

May 28 00:48:05.259327  BSM moved to state: FAIL !!

May 28 00:48:05.259508  BSM moved to state: IDLE !!

May 28 00:48:05.259641  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:48:05.259767  for Port: 73, MAC: 80027df - fe56 

May 28 00:48:05.259883  Id: 0, SessionNode: aa4aa8 

May 28 00:48:05.260035  TMR: Quiet While Timer Started for port:73, Duration: 60 !!

May 28 00:48:05.260193  TMR: Timer is started

May 28 00:48:05.260318  ASM moved to state: HELD !!

May 28 00:48:05.260478  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:48:05.260611 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:48:05.260685 Rad Attr Class Len = 0
May 28 00:48:05.260770  ASIF: Received No Key from Server

May 28 00:48:05.260905 Processing authentication response complete
May 28 00:48:05.261038 Deleted background job to process replies from authentication client
May 28 00:48:14.434357 Received message from authentication client
May 28 00:48:14.434544 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:48:14.434676 hdr_bytes_read 0
May 28 00:48:14.434784 len read : 28 reply_len: 28
May 28 00:48:14.434885 bytes_remnant 0 tot_bytes_read 28
May 28 00:48:14.434971 Creating background job to process reply from authentication client
May 28 00:48:14.435163 Entering background job to process message from authentication client
May 28 00:48:14.435769 process_auth_reply len:28
May 28 00:48:14.435925 Authentication client could not contact RADIUS servers
May 28 00:48:14.436038 pnac_apply_server_fail_action portnum:73
May 28 00:48:14.436135 Server Fail 0
May 28 00:48:14.436200 Server Fail Deny/Default
May 28 00:48:14.436291 (pnac_construct_eap_pkt) code:4
May 28 00:48:14.436505 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:48:14.436619  on intf fe-0/0/2.0

May 28 00:48:14.436866 authenticatedStateCause :1
May 28 00:48:14.436990  ASIF: Handing over Server frame to Authenticator

May 28 00:48:14.437189  AUTH: Handling Server Frame

May 28 00:48:14.437328  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:48:14.438183 Code = 4, Id = 0, Len = 4

May 28 00:48:14.438313  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:48:14.438696  for Port: 73, MAC: 1c750832-72c 

May 28 00:48:14.438873  Id: 0, SessionNode: aad000 

May 28 00:48:14.439026  TMR: Timer is deleted

May 28 00:48:14.439156  BSM moved to state: FAIL !!

May 28 00:48:14.439285  BSM moved to state: IDLE !!

May 28 00:48:14.439414  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:48:14.439725  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:48:14.439828  Id: 0, SessionNode: aad000 

May 28 00:48:14.439953 Auth Fail Count 1: Moved to state CONNECTING !!

May 28 00:48:14.440123 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:48:14.440263  ASM moved to state: CONNECTING !!

May 28 00:48:14.440386 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:48:14.440617 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:48:14.440818  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:48:14.440973  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:48:14.441102  Id: 1, SessionNode: aad000 

May 28 00:48:14.441195 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:48:14.441308  ASM moved to state: AUTHENTICATING !!

May 28 00:48:14.441428  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:48:14.441569  for Port: 73, MAC: 1c750832-72c 

May 28 00:48:14.441688  Id: 1, SessionNode: aad000 

May 28 00:48:14.441866  TMR: Timer is started

May 28 00:48:14.441997  BSM moved to state: RESPONSE !!

May 28 00:48:14.442133  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:48:14.442274 SessId: 8O2.1x810e01130006176c strlen: 22
May 28 00:48:14.442529 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:48:14.443040  ASIF: Radius REQUEST_ID: f3
May 28 00:48:14.443181  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:48:14.443320  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:48:14.443449 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:48:14.443539 Rad Attr Class Len = 0
May 28 00:48:14.443728  ASIF: Received No Key from Server

May 28 00:48:14.443874 Processing authentication response complete
May 28 00:48:14.443986 Deleted background job to process replies from authentication client
May 28 00:48:14.444196 Sending message to authentication client
May 28 00:48:14.449531 Received message from authentication client
May 28 00:48:14.449682 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:48:14.449809 hdr_bytes_read 0
May 28 00:48:14.450427 len read : 28 reply_len: 28
May 28 00:48:14.450532 bytes_remnant 0 tot_bytes_read 28
May 28 00:48:14.450620 Creating background job to process reply from authentication client
May 28 00:48:14.450809 Entering background job to process message from authentication client
May 28 00:48:14.450946 process_auth_reply len:28
May 28 00:48:14.451050 Authentication client could not contact RADIUS servers
May 28 00:48:14.451158 pnac_apply_server_fail_action portnum:73
May 28 00:48:14.451257 Server Fail 0
May 28 00:48:14.451322 Server Fail Deny/Default
May 28 00:48:14.451422 (pnac_construct_eap_pkt) code:4
May 28 00:48:14.451517 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:48:14.451605  on intf fe-0/0/2.0

May 28 00:48:14.451741 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:48:14.452008 authenticatedStateCause :1
May 28 00:48:14.452142  ASIF: Handing over Server frame to Authenticator

May 28 00:48:14.452269  AUTH: Handling Server Frame

May 28 00:48:14.452397  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:48:14.452535 Code = 4, Id = 0, Len = 4

May 28 00:48:14.452656  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:48:14.452867  for Port: 73, MAC: 1c750832-72c 

May 28 00:48:14.453021  Id: 1, SessionNode: aad000 

May 28 00:48:14.453162  TMR: Timer is deleted

May 28 00:48:14.453287  BSM moved to state: FAIL !!

May 28 00:48:14.453421  BSM moved to state: IDLE !!

May 28 00:48:14.453560  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:48:14.453682  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:48:14.453799  Id: 0, SessionNode: aad000 

May 28 00:48:14.453921 Auth Fail Count 2: Moved to state CONNECTING !!

May 28 00:48:14.454075 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:48:14.454193  ASM moved to state: CONNECTING !!

May 28 00:48:14.454308 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:48:14.454427 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:48:14.454620  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:48:14.454756  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:48:14.454872  Id: 1, SessionNode: aad000 

May 28 00:48:14.454997 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:48:14.455127  ASM moved to state: AUTHENTICATING !!

May 28 00:48:14.455253  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:48:14.455372  for Port: 73, MAC: 1c750832-72c 

May 28 00:48:14.455485  Id: 1, SessionNode: aad000 

May 28 00:48:14.455650  TMR: Timer is started

May 28 00:48:14.455786  BSM moved to state: RESPONSE !!

May 28 00:48:14.455914  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:48:14.456112 SessId: 8O2.1x810e01130006176c strlen: 22
May 28 00:48:14.456377 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:48:14.457569  ASIF: Radius REQUEST_ID: f4
May 28 00:48:14.457725  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:48:14.457868  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:48:14.457999 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:48:14.458236 Rad Attr Class Len = 0
May 28 00:48:14.458322  ASIF: Received No Key from Server

May 28 00:48:14.458434 Processing authentication response complete
May 28 00:48:14.458542 Deleted background job to process replies from authentication client
May 28 00:48:14.458763 Sending message to authentication client
May 28 00:48:14.463002 Received message from authentication client
May 28 00:48:14.463148 reply: a69b2c rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:48:14.463680 hdr_bytes_read 0
May 28 00:48:14.463851 len read : 28 reply_len: 28
May 28 00:48:14.463936 bytes_remnant 0 tot_bytes_read 28
May 28 00:48:14.464024 Creating background job to process reply from authentication client
May 28 00:48:14.464200 Entering background job to process message from authentication client
May 28 00:48:14.464350 process_auth_reply len:28
May 28 00:48:14.464433 Authentication client could not contact RADIUS servers
May 28 00:48:14.464539 pnac_apply_server_fail_action portnum:73
May 28 00:48:14.464633 Server Fail 0
May 28 00:48:14.464692 Server Fail Deny/Default
May 28 00:48:14.464772 (pnac_construct_eap_pkt) code:4
May 28 00:48:14.464865 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:48:14.464935  on intf fe-0/0/2.0

May 28 00:48:14.465062 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:48:14.465228 authenticatedStateCause :1
May 28 00:48:14.465316  ASIF: Handing over Server frame to Authenticator

May 28 00:48:14.466220  AUTH: Handling Server Frame

May 28 00:48:14.466361  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:48:14.466508 Code = 4, Id = 0, Len = 4

May 28 00:48:14.466620  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:48:14.466735  for Port: 73, MAC: 1c750832-72c 

May 28 00:48:14.466825  Id: 1, SessionNode: aad000 

May 28 00:48:14.466982  TMR: Timer is deleted

May 28 00:48:14.467099  BSM moved to state: FAIL !!

May 28 00:48:14.467229  BSM moved to state: IDLE !!

May 28 00:48:14.467526  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:48:14.467655  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:48:14.467767  Id: 0, SessionNode: aad000 

May 28 00:48:14.467907  TMR: Quiet While Timer Started for port:73, Duration: 60 !!

May 28 00:48:14.468057  TMR: Timer is started

May 28 00:48:14.468176  ASM moved to state: HELD !!

May 28 00:48:14.468296  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:48:14.468416 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:48:14.468523 Rad Attr Class Len = 0
May 28 00:48:14.468603  ASIF: Received No Key from Server

May 28 00:48:14.468697 Processing authentication response complete
May 28 00:48:14.468801 Deleted background job to process replies from authentication client
May 28 00:49:05.321338  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:49:05.321572  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:05.321689  Id: 1, SessionNode: aa4aa8 

May 28 00:49:05.321816  Trying to delete the session for MAC: -80027df-fe56- on Port: 73 ... 

May 28 00:49:05.321927  Trying to delete the Session Node : aa4aa8 ... 

May 28 00:49:05.322062  Session Deleted for MAC: -80027df-fe56- on Port: 73 ... 

May 28 00:49:05.322175  Deleted Session Node : aa4aa8 ... 

May 28 00:49:05.322321 QuietWhile Timer Expired and Session deleted !!!
 
May 28 00:49:14.489319  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:49:14.489550  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:14.489652  Id: 1, SessionNode: aad000 

May 28 00:49:14.489775  Trying to delete the session for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:49:14.489888  Trying to delete the Session Node : aad000 ... 

May 28 00:49:14.490015  Session Deleted for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:49:14.490130  Deleted Session Node : aad000 ... 

May 28 00:49:14.490501 QuietWhile Timer Expired and Session deleted !!!
 
May 28 00:49:19.343059  Trying to create a session for MAC: -1c750832-72c- thro' Port: 73 ... 

May 28 00:49:19.343372 queried vid:4
May 28 00:49:19.343467  Allocated Session Node : aa4aa8 

May 28 00:49:19.343676  PnacAuthSessStatsInit PnacAuthSessStatsInit

May 28 00:49:19.343857 Generated Unique SessId :9299371474567905022
May 28 00:49:19.343957  BSM Called with Event: INITIALIZE, and State: Initialise

May 28 00:49:19.344079  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:19.344202  Id: 0, SessionNode: aa4aa8 

May 28 00:49:19.344310  BSM moved to state: INITIALIZE !!

May 28 00:49:19.344420  BSM moved to state: IDLE !!

May 28 00:49:19.344539  ASM Called with Event: INITIALIZE, and State: Initialize

May 28 00:49:19.344656  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:19.344791  Id: 0, SessionNode: aa4aa8 

May 28 00:49:19.344899 ASM: Inside PnacAuthAsmInitInitialize

May 28 00:49:19.344996  ASM moved to state: INITIALIZE !!

May 28 00:49:19.345110  ASM moved to state: DISCONNECTED !!

May 28 00:49:19.345241 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:49:19.345355  ASM moved to state: CONNECTING !!

May 28 00:49:19.345468 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:49:19.345585 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:49:19.345781  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:49:19.345923  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:19.346038  Id: 0, SessionNode: aa4aa8 

May 28 00:49:19.346127 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:49:19.346247  ASM moved to state: AUTHENTICATING !!

May 28 00:49:19.346365  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:49:19.346478  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:19.346589  Id: 0, SessionNode: aa4aa8 

May 28 00:49:19.346780  TMR: Timer is started

May 28 00:49:19.346914  BSM moved to state: RESPONSE !!

May 28 00:49:19.347041  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:49:19.347177 SessId: 8O2.1x810e011500053e3f strlen: 22
May 28 00:49:19.347438 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:49:19.349109  ASIF: Radius REQUEST_ID: f5
May 28 00:49:19.349245  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:49:19.349402  Session Created for MAC: -1c750832-72c- through Port: 73 ... 

May 28 00:49:19.349919 Sending message to authentication client
May 28 00:49:31.547167  Trying to create a session for MAC: -80027df-fe56- thro' Port: 73 ... 

May 28 00:49:31.547402 queried vid:4
May 28 00:49:31.547488  Allocated Session Node : aad000 

May 28 00:49:31.547694  PnacAuthSessStatsInit PnacAuthSessStatsInit

May 28 00:49:31.547876 Generated Unique SessId :9299371483158043632
May 28 00:49:31.547976  BSM Called with Event: INITIALIZE, and State: Initialise

May 28 00:49:31.548095  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:31.548216  Id: 0, SessionNode: aad000 

May 28 00:49:31.548328  BSM moved to state: INITIALIZE !!

May 28 00:49:31.548441  BSM moved to state: IDLE !!

May 28 00:49:31.548560  ASM Called with Event: INITIALIZE, and State: Initialize

May 28 00:49:31.548677  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:31.548818  Id: 0, SessionNode: aad000 

May 28 00:49:31.548925 ASM: Inside PnacAuthAsmInitInitialize

May 28 00:49:31.549036  ASM moved to state: INITIALIZE !!

May 28 00:49:31.549151  ASM moved to state: DISCONNECTED !!

May 28 00:49:31.549283 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:49:31.549378  ASM moved to state: CONNECTING !!

May 28 00:49:31.549489 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:49:31.549603 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:49:31.549800  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:49:31.549940  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:31.550052  Id: 0, SessionNode: aad000 

May 28 00:49:31.550156 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:49:31.550276  ASM moved to state: AUTHENTICATING !!

May 28 00:49:31.550395  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:49:31.550607  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:31.550724  Id: 0, SessionNode: aad000 

May 28 00:49:31.550921  TMR: Timer is started

May 28 00:49:31.551050  BSM moved to state: RESPONSE !!

May 28 00:49:31.551174  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:49:31.551324 SessId: 8O2.1x810e011700085b3e strlen: 22
May 28 00:49:31.551582 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:49:31.552684  ASIF: Radius REQUEST_ID: f6
May 28 00:49:31.552845  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:49:31.552986  Session Created for MAC: -80027df-fe56- through Port: 73 ... 

May 28 00:49:31.553428 Sending message to authentication client
May 28 00:49:37.382486 Received message from authentication client
May 28 00:49:37.382706 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:49:37.382832 hdr_bytes_read 0
May 28 00:49:37.382929 len read : 28 reply_len: 28
May 28 00:49:37.383019 bytes_remnant 0 tot_bytes_read 28
May 28 00:49:37.383109 Creating background job to process reply from authentication client
May 28 00:49:37.383298 Entering background job to process message from authentication client
May 28 00:49:37.383419 process_auth_reply len:28
May 28 00:49:37.383500 Authentication client could not contact RADIUS servers
May 28 00:49:37.384096 pnac_apply_server_fail_action portnum:73
May 28 00:49:37.384210 Server Fail 0
May 28 00:49:37.384296 Server Fail Deny/Default
May 28 00:49:37.384370 (pnac_construct_eap_pkt) code:4
May 28 00:49:37.384462 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:49:37.384551  on intf fe-0/0/2.0

May 28 00:49:37.385742 authenticatedStateCause :1
May 28 00:49:37.385880  ASIF: Handing over Server frame to Authenticator

May 28 00:49:37.386000  AUTH: Handling Server Frame

May 28 00:49:37.386138  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:49:37.386351 Code = 4, Id = 0, Len = 4

May 28 00:49:37.386475  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:49:37.386597  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:37.386737  Id: 0, SessionNode: aa4aa8 

May 28 00:49:37.386870  TMR: Timer is deleted

May 28 00:49:37.386990  BSM moved to state: FAIL !!

May 28 00:49:37.387116  BSM moved to state: IDLE !!

May 28 00:49:37.387249  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:49:37.387370  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:37.387482  Id: 0, SessionNode: aa4aa8 

May 28 00:49:37.387601 Auth Fail Count 1: Moved to state CONNECTING !!

May 28 00:49:37.387750 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:49:37.387867  ASM moved to state: CONNECTING !!

May 28 00:49:37.387980 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:49:37.388097 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:49:37.388266  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:49:37.388421  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:37.388519  Id: 1, SessionNode: aa4aa8 

May 28 00:49:37.388622 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:49:37.388763  ASM moved to state: AUTHENTICATING !!

May 28 00:49:37.388886  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:49:37.389005  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:37.389116  Id: 1, SessionNode: aa4aa8 

May 28 00:49:37.389271  TMR: Timer is started

May 28 00:49:37.389399  BSM moved to state: RESPONSE !!

May 28 00:49:37.389542  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:49:37.389680 SessId: 8O2.1x810e011500053e3f strlen: 22
May 28 00:49:37.389960 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:49:37.390373  ASIF: Radius REQUEST_ID: f7
May 28 00:49:37.390506  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:49:37.390645  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:49:37.391014 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:49:37.391113 Rad Attr Class Len = 0
May 28 00:49:37.391198  ASIF: Received No Key from Server

May 28 00:49:37.391332 Processing authentication response complete
May 28 00:49:37.391444 Deleted background job to process replies from authentication client
May 28 00:49:37.391644 Sending message to authentication client
May 28 00:49:37.396929 Received message from authentication client
May 28 00:49:37.397217 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:49:37.397371 hdr_bytes_read 0
May 28 00:49:37.397471 len read : 28 reply_len: 28
May 28 00:49:37.397577 bytes_remnant 0 tot_bytes_read 28
May 28 00:49:37.397648 Creating background job to process reply from authentication client
May 28 00:49:37.397855 Entering background job to process message from authentication client
May 28 00:49:37.397974 process_auth_reply len:28
May 28 00:49:37.398221 Authentication client could not contact RADIUS servers
May 28 00:49:37.398364 pnac_apply_server_fail_action portnum:73
May 28 00:49:37.398466 Server Fail 0
May 28 00:49:37.398557 Server Fail Deny/Default
May 28 00:49:37.398632 (pnac_construct_eap_pkt) code:4
May 28 00:49:37.398727 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:49:37.398813  on intf fe-0/0/2.0

May 28 00:49:37.398946 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:49:37.399132 authenticatedStateCause :1
May 28 00:49:37.399248  ASIF: Handing over Server frame to Authenticator

May 28 00:49:37.399369  AUTH: Handling Server Frame

May 28 00:49:37.399496  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:49:37.399622 Code = 4, Id = 0, Len = 4

May 28 00:49:37.399742  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:49:37.399864  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:37.399980  Id: 1, SessionNode: aa4aa8 

May 28 00:49:37.400126  TMR: Timer is deleted

May 28 00:49:37.400345  BSM moved to state: FAIL !!

May 28 00:49:37.400481  BSM moved to state: IDLE !!

May 28 00:49:37.400619  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:49:37.400742  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:37.400859  Id: 0, SessionNode: aa4aa8 

May 28 00:49:37.400978 Auth Fail Count 2: Moved to state CONNECTING !!

May 28 00:49:37.401102 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:49:37.402053  ASM moved to state: CONNECTING !!

May 28 00:49:37.402199 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:49:37.402330 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:49:37.402498  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:49:37.402637  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:37.402758  Id: 1, SessionNode: aa4aa8 

May 28 00:49:37.402868 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:49:37.402984  ASM moved to state: AUTHENTICATING !!

May 28 00:49:37.403107  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:49:37.403366  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:37.403491  Id: 1, SessionNode: aa4aa8 

May 28 00:49:37.403643  TMR: Timer is started

May 28 00:49:37.403797  BSM moved to state: RESPONSE !!

May 28 00:49:37.403942  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:49:37.404109 SessId: 8O2.1x810e011500053e3f strlen: 22
May 28 00:49:37.404399 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:49:37.404836  ASIF: Radius REQUEST_ID: f8
May 28 00:49:37.404972  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:49:37.405114  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:49:37.405379 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:49:37.405510 Rad Attr Class Len = 0
May 28 00:49:37.405600  ASIF: Received No Key from Server

May 28 00:49:37.405716 Processing authentication response complete
May 28 00:49:37.406145 Deleted background job to process replies from authentication client
May 28 00:49:37.406491 Sending message to authentication client
May 28 00:49:37.410934 Received message from authentication client
May 28 00:49:37.411086 reply: a69b2c rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:49:37.411208 hdr_bytes_read 0
May 28 00:49:37.411298 len read : 28 reply_len: 28
May 28 00:49:37.411382 bytes_remnant 0 tot_bytes_read 28
May 28 00:49:37.411468 Creating background job to process reply from authentication client
May 28 00:49:37.411691 Entering background job to process message from authentication client
May 28 00:49:37.411807 process_auth_reply len:28
May 28 00:49:37.411884 Authentication client could not contact RADIUS servers
May 28 00:49:37.411987 pnac_apply_server_fail_action portnum:73
May 28 00:49:37.412091 Server Fail 0
May 28 00:49:37.412165 Server Fail Deny/Default
May 28 00:49:37.412236 (pnac_construct_eap_pkt) code:4
May 28 00:49:37.412328 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:49:37.412394  on intf fe-0/0/2.0

May 28 00:49:37.412561 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:49:37.412718 authenticatedStateCause :1
May 28 00:49:37.412826  ASIF: Handing over Server frame to Authenticator

May 28 00:49:37.412939  AUTH: Handling Server Frame

May 28 00:49:37.413066  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:49:37.413179 Code = 4, Id = 0, Len = 4

May 28 00:49:37.413292  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:49:37.413390  for Port: 73, MAC: 1c750832-72c 

May 28 00:49:37.413498  Id: 1, SessionNode: aa4aa8 

May 28 00:49:37.413645  TMR: Timer is deleted

May 28 00:49:37.413761  BSM moved to state: FAIL !!

May 28 00:49:37.413885  BSM moved to state: IDLE !!

May 28 00:49:37.414006  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:49:37.414129  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:49:37.414380  Id: 0, SessionNode: aa4aa8 

May 28 00:49:37.414546  TMR: Quiet While Timer Started for port:73, Duration: 60 !!

May 28 00:49:37.415199  TMR: Timer is started

May 28 00:49:37.415334  ASM moved to state: HELD !!

May 28 00:49:37.415455  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:49:37.415578 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:49:37.416800 Rad Attr Class Len = 0
May 28 00:49:37.416939  ASIF: Received No Key from Server

May 28 00:49:37.417058 Processing authentication response complete
May 28 00:49:37.417174 Deleted background job to process replies from authentication client
May 28 00:49:49.573237 Received message from authentication client
May 28 00:49:49.573439 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:49:49.573591 hdr_bytes_read 0
May 28 00:49:49.573690 len read : 28 reply_len: 28
May 28 00:49:49.573770 bytes_remnant 0 tot_bytes_read 28
May 28 00:49:49.573857 Creating background job to process reply from authentication client
May 28 00:49:49.574056 Entering background job to process message from authentication client
May 28 00:49:49.574175 process_auth_reply len:28
May 28 00:49:49.574253 Authentication client could not contact RADIUS servers
May 28 00:49:49.574356 pnac_apply_server_fail_action portnum:73
May 28 00:49:49.574449 Server Fail 0
May 28 00:49:49.574764 Server Fail Deny/Default
May 28 00:49:49.574898 (pnac_construct_eap_pkt) code:4
May 28 00:49:49.575000 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:49:49.575099  on intf fe-0/0/2.0

May 28 00:49:49.575338 authenticatedStateCause :1
May 28 00:49:49.575459  ASIF: Handing over Server frame to Authenticator

May 28 00:49:49.575607  AUTH: Handling Server Frame

May 28 00:49:49.575736  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:49:49.575852 Code = 4, Id = 0, Len = 4

May 28 00:49:49.575967  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:49:49.576090  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:49.576314  Id: 0, SessionNode: aad000 

May 28 00:49:49.576455  TMR: Timer is deleted

May 28 00:49:49.576597  BSM moved to state: FAIL !!

May 28 00:49:49.576734  BSM moved to state: IDLE !!

May 28 00:49:49.576860  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:49:49.576977  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:49.577097  Id: 0, SessionNode: aad000 

May 28 00:49:49.577216 Auth Fail Count 1: Moved to state CONNECTING !!

May 28 00:49:49.577337 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:49:49.577447  ASM moved to state: CONNECTING !!

May 28 00:49:49.577556 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:49:49.577695 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:49:49.577857  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:49:49.577992  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:49.578126  Id: 1, SessionNode: aad000 

May 28 00:49:49.578240 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:49:49.578358  ASM moved to state: AUTHENTICATING !!

May 28 00:49:49.578477  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:49:49.578596  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:49.578979  Id: 1, SessionNode: aad000 

May 28 00:49:49.579187  TMR: Timer is started

May 28 00:49:49.579344  BSM moved to state: RESPONSE !!

May 28 00:49:49.579478  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:49:49.579618 SessId: 8O2.1x810e011700085b3e strlen: 22
May 28 00:49:49.579921 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:49:49.580844  ASIF: Radius REQUEST_ID: f9
May 28 00:49:49.580981  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:49:49.581121  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:49:49.581255 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:49:49.581343 Rad Attr Class Len = 0
May 28 00:49:49.581484  ASIF: Received No Key from Server

May 28 00:49:49.581612 Processing authentication response complete
May 28 00:49:49.581715 Deleted background job to process replies from authentication client
May 28 00:49:49.582238 Sending message to authentication client
May 28 00:49:49.586993 Received message from authentication client
May 28 00:49:49.587140 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:49:49.587268 hdr_bytes_read 0
May 28 00:49:49.587366 len read : 28 reply_len: 28
May 28 00:49:49.587466 bytes_remnant 0 tot_bytes_read 28
May 28 00:49:49.587553 Creating background job to process reply from authentication client
May 28 00:49:49.587740 Entering background job to process message from authentication client
May 28 00:49:49.587862 process_auth_reply len:28
May 28 00:49:49.588827 Authentication client could not contact RADIUS servers
May 28 00:49:49.589014 pnac_apply_server_fail_action portnum:73
May 28 00:49:49.589119 Server Fail 0
May 28 00:49:49.589205 Server Fail Deny/Default
May 28 00:49:49.589274 (pnac_construct_eap_pkt) code:4
May 28 00:49:49.589372 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:49:49.589459  on intf fe-0/0/2.0

May 28 00:49:49.589599 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:49:49.589751 authenticatedStateCause :1
May 28 00:49:49.589860  ASIF: Handing over Server frame to Authenticator

May 28 00:49:49.589982  AUTH: Handling Server Frame

May 28 00:49:49.590131  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:49:49.590250 Code = 4, Id = 0, Len = 4

May 28 00:49:49.590367  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:49:49.590482  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:49.590603  Id: 1, SessionNode: aad000 

May 28 00:49:49.590736  TMR: Timer is deleted

May 28 00:49:49.590860  BSM moved to state: FAIL !!

May 28 00:49:49.590987  BSM moved to state: IDLE !!

May 28 00:49:49.591137  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:49:49.591354  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:49.591473  Id: 0, SessionNode: aad000 

May 28 00:49:49.591604 Auth Fail Count 2: Moved to state CONNECTING !!

May 28 00:49:49.591731 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:49:49.591842  ASM moved to state: CONNECTING !!

May 28 00:49:49.591957 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:49:49.592101 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:49:49.592719  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:49:49.592862  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:49.592979  Id: 1, SessionNode: aad000 

May 28 00:49:49.593088 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:49:49.593233  ASM moved to state: AUTHENTICATING !!

May 28 00:49:49.593353  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:49:49.593471  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:49.593581  Id: 1, SessionNode: aad000 

May 28 00:49:49.593763  TMR: Timer is started

May 28 00:49:49.593899  BSM moved to state: RESPONSE !!

May 28 00:49:49.594042  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:49:49.594299 SessId: 8O2.1x810e011700085b3e strlen: 22
May 28 00:49:49.594583 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:49:49.595556  ASIF: Radius REQUEST_ID: fa
May 28 00:49:49.595693  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:49:49.595841  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:49:49.595973 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:49:49.596057 Rad Attr Class Len = 0
May 28 00:49:49.596126  ASIF: Received No Key from Server

May 28 00:49:49.596262 Processing authentication response complete
May 28 00:49:49.596375 Deleted background job to process replies from authentication client
May 28 00:49:49.596596 Sending message to authentication client
May 28 00:49:49.600664 Received message from authentication client
May 28 00:49:49.600875 reply: a69b2c rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:49:49.601023 hdr_bytes_read 0
May 28 00:49:49.601124 len read : 28 reply_len: 28
May 28 00:49:49.601220 bytes_remnant 0 tot_bytes_read 28
May 28 00:49:49.601306 Creating background job to process reply from authentication client
May 28 00:49:49.601736 Entering background job to process message from authentication client
May 28 00:49:49.601878 process_auth_reply len:28
May 28 00:49:49.601990 Authentication client could not contact RADIUS servers
May 28 00:49:49.602098 pnac_apply_server_fail_action portnum:73
May 28 00:49:49.602195 Server Fail 0
May 28 00:49:49.602260 Server Fail Deny/Default
May 28 00:49:49.602351 (pnac_construct_eap_pkt) code:4
May 28 00:49:49.603175 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:49:49.603329  on intf fe-0/0/2.0

May 28 00:49:49.603503 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:49:49.603762 authenticatedStateCause :1
May 28 00:49:49.603870  ASIF: Handing over Server frame to Authenticator

May 28 00:49:49.603991  AUTH: Handling Server Frame

May 28 00:49:49.604127  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:49:49.604245 Code = 4, Id = 0, Len = 4

May 28 00:49:49.604365  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:49:49.604483  for Port: 73, MAC: 80027df-fe56 

May 28 00:49:49.604626  Id: 1, SessionNode: aad000 

May 28 00:49:49.604762  TMR: Timer is deleted

May 28 00:49:49.604881  BSM moved to state: FAIL !!

May 28 00:49:49.605010  BSM moved to state: IDLE !!

May 28 00:49:49.605146  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:49:49.605268  for Port: 73, MAC: 80027df - fe56 

May 28 00:49:49.605380  Id: 0, SessionNode: aad000 

May 28 00:49:49.605520  TMR: Quiet While Timer Started for port:73, Duration: 60 !!

May 28 00:49:49.605704  TMR: Timer is started

May 28 00:49:49.605928  ASM moved to state: HELD !!

May 28 00:49:49.606060  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:49:49.606193 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:49:49.606261 Rad Attr Class Len = 0
May 28 00:49:49.606345  ASIF: Received No Key from Server

May 28 00:49:49.606462 Processing authentication response complete
May 28 00:49:49.606566 Deleted background job to process replies from authentication client
May 28 00:50:37.420516  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:50:37.420759  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:37.420883  Id: 1, SessionNode: aa4aa8 

May 28 00:50:37.421010  Trying to delete the session for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:50:37.421125  Trying to delete the Session Node : aa4aa8 ... 

May 28 00:50:37.421263  Session Deleted for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:50:37.421493  Deleted Session Node : aa4aa8 ... 

May 28 00:50:37.421650 QuietWhile Timer Expired and Session deleted !!!
 
May 28 00:50:39.321110  Trying to create a session for MAC: -1c750832-72c- thro' Port: 73 ... 

May 28 00:50:39.321318 queried vid:4
May 28 00:50:39.321414  Allocated Session Node : aa4aa8 

May 28 00:50:39.321631  PnacAuthSessStatsInit PnacAuthSessStatsInit

May 28 00:50:39.321817 Generated Unique SessId :9299371491747752166
May 28 00:50:39.321924  BSM Called with Event: INITIALIZE, and State: Initialise

May 28 00:50:39.322047  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:39.322170  Id: 0, SessionNode: aa4aa8 

May 28 00:50:39.322280  BSM moved to state: INITIALIZE !!

May 28 00:50:39.322397  BSM moved to state: IDLE !!

May 28 00:50:39.322521  ASM Called with Event: INITIALIZE, and State: Initialize

May 28 00:50:39.322663  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:39.322782  Id: 0, SessionNode: aa4aa8 

May 28 00:50:39.322891 ASM: Inside PnacAuthAsmInitInitialize

May 28 00:50:39.323005  ASM moved to state: INITIALIZE !!

May 28 00:50:39.323129  ASM moved to state: DISCONNECTED !!

May 28 00:50:39.323368 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:50:39.323491  ASM moved to state: CONNECTING !!

May 28 00:50:39.323605 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:50:39.323752 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:50:39.323923  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:50:39.324061  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:39.324186  Id: 0, SessionNode: aa4aa8 

May 28 00:50:39.324293 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:50:39.324410  ASM moved to state: AUTHENTICATING !!

May 28 00:50:39.324530  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:50:39.324670  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:39.324792  Id: 0, SessionNode: aa4aa8 

May 28 00:50:39.324969  TMR: Timer is started

May 28 00:50:39.325103  BSM moved to state: RESPONSE !!

May 28 00:50:39.325240  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:50:39.325375 SessId: 8O2.1x810e01190004e845 strlen: 22
May 28 00:50:39.325630 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:50:39.326937  ASIF: Radius REQUEST_ID: fb
May 28 00:50:39.327092  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:50:39.327234  Session Created for MAC: -1c750832-72c- through Port: 73 ... 

May 28 00:50:39.327656 Sending message to authentication client
May 28 00:50:49.607912  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:50:49.608142  for Port: 73, MAC: 80027df - fe56 

May 28 00:50:49.608269  Id: 1, SessionNode: aad000 

May 28 00:50:49.608393  Trying to delete the session for MAC: -80027df-fe56- on Port: 73 ... 

May 28 00:50:49.608510  Trying to delete the Session Node : aad000 ... 

May 28 00:50:49.608645  Session Deleted for MAC: -80027df-fe56- on Port: 73 ... 

May 28 00:50:49.608786  Deleted Session Node : aad000 ... 

May 28 00:50:49.608955 QuietWhile Timer Expired and Session deleted !!!
 
May 28 00:50:57.361773 Received message from authentication client
May 28 00:50:57.361963 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:50:57.362121 hdr_bytes_read 0
May 28 00:50:57.362199 len read : 28 reply_len: 28
May 28 00:50:57.362298 bytes_remnant 0 tot_bytes_read 28
May 28 00:50:57.362380 Creating background job to process reply from authentication client
May 28 00:50:57.362564 Entering background job to process message from authentication client
May 28 00:50:57.363154 process_auth_reply len:28
May 28 00:50:57.363300 Authentication client could not contact RADIUS servers
May 28 00:50:57.363407 pnac_apply_server_fail_action portnum:73
May 28 00:50:57.363499 Server Fail 0
May 28 00:50:57.363564 Server Fail Deny/Default
May 28 00:50:57.363642 (pnac_construct_eap_pkt) code:4
May 28 00:50:57.363760 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:50:57.363832  on intf fe-0/0/2.0

May 28 00:50:57.363960 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:50:57.364134 authenticatedStateCause :1
May 28 00:50:57.364251  ASIF: Handing over Server frame to Authenticator

May 28 00:50:57.364366  AUTH: Handling Server Frame

May 28 00:50:57.364491  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:50:57.364587 Code = 4, Id = 0, Len = 4

May 28 00:50:57.364824  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:50:57.364984  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:57.365083  Id: 0, SessionNode: aa4aa8 

May 28 00:50:57.365238  TMR: Timer is deleted

May 28 00:50:57.365339  BSM moved to state: FAIL !!

May 28 00:50:57.365464  BSM moved to state: IDLE !!

May 28 00:50:57.365588  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:50:57.365702  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:57.365834  Id: 0, SessionNode: aa4aa8 

May 28 00:50:57.365950 Auth Fail Count 1: Moved to state CONNECTING !!

May 28 00:50:57.366068 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:50:57.366326  ASM moved to state: CONNECTING !!

May 28 00:50:57.366445 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:50:57.366562 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:50:57.366762  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:50:57.366906  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:57.367020  Id: 1, SessionNode: aa4aa8 

May 28 00:50:57.367123 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:50:57.367234  ASM moved to state: AUTHENTICATING !!

May 28 00:50:57.367363  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:50:57.367464  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:57.367574  Id: 1, SessionNode: aa4aa8 

May 28 00:50:57.367739  TMR: Timer is started

May 28 00:50:57.367907  BSM moved to state: RESPONSE !!

May 28 00:50:57.368033  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:50:57.368171 SessId: 8O2.1x810e01190004e845 strlen: 22
May 28 00:50:57.368440 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:50:57.369905  ASIF: Radius REQUEST_ID: fc
May 28 00:50:57.370042  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:50:57.370179  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:50:57.370306 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:50:57.370397 Rad Attr Class Len = 0
May 28 00:50:57.370460  ASIF: Received No Key from Server

May 28 00:50:57.370574 Processing authentication response complete
May 28 00:50:57.370681 Deleted background job to process replies from authentication client
May 28 00:50:57.371004 Sending message to authentication client
May 28 00:50:57.376013 Received message from authentication client
May 28 00:50:57.376178 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:50:57.376308 hdr_bytes_read 0
May 28 00:50:57.376405 len read : 28 reply_len: 28
May 28 00:50:57.376570 bytes_remnant 0 tot_bytes_read 28
May 28 00:50:57.376680 Creating background job to process reply from authentication client
May 28 00:50:57.376882 Entering background job to process message from authentication client
May 28 00:50:57.377002 process_auth_reply len:28
May 28 00:50:57.377130 Authentication client could not contact RADIUS servers
May 28 00:50:57.377995 pnac_apply_server_fail_action portnum:73
May 28 00:50:57.378080 Server Fail 0
May 28 00:50:57.378189 Server Fail Deny/Default
May 28 00:50:57.378281 (pnac_construct_eap_pkt) code:4
May 28 00:50:57.378373 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:50:57.378446  on intf fe-0/0/2.0

May 28 00:50:57.378577 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:50:57.378759 authenticatedStateCause :1
May 28 00:50:57.378870  ASIF: Handing over Server frame to Authenticator

May 28 00:50:57.378989  AUTH: Handling Server Frame

May 28 00:50:57.379117  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:50:57.379263 Code = 4, Id = 0, Len = 4

May 28 00:50:57.379381  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:50:57.379500  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:57.379612  Id: 1, SessionNode: aa4aa8 

May 28 00:50:57.379755  TMR: Timer is deleted

May 28 00:50:57.379874  BSM moved to state: FAIL !!

May 28 00:50:57.380002  BSM moved to state: IDLE !!

May 28 00:50:57.380125  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:50:57.380274  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:57.380389  Id: 0, SessionNode: aa4aa8 

May 28 00:50:57.380504 Auth Fail Count 2: Moved to state CONNECTING !!

May 28 00:50:57.380625 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:50:57.380741  ASM moved to state: CONNECTING !!

May 28 00:50:57.380835 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:50:57.380953 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:50:57.381423  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:50:57.381626  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:57.381748  Id: 1, SessionNode: aa4aa8 

May 28 00:50:57.381859 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:50:57.381960  ASM moved to state: AUTHENTICATING !!

May 28 00:50:57.382082  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:50:57.382198  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:57.382335  Id: 1, SessionNode: aa4aa8 

May 28 00:50:57.382510  TMR: Timer is started

May 28 00:50:57.382641  BSM moved to state: RESPONSE !!

May 28 00:50:57.382769  ASIF: Transferring Server-data to Auth Server for the user, 1c750832072c.

May 28 00:50:57.382910 SessId: 8O2.1x810e01190004e845 strlen: 22
May 28 00:50:57.383165 Queuing message to auth client to validate mac address 1c:75:8:32:7:2c, user 1c750832072c on interface fe-0/0/2.0
May 28 00:50:57.384501  ASIF: Radius REQUEST_ID: fd
May 28 00:50:57.384639  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:50:57.384769  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:50:57.384908 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:50:57.384990 Rad Attr Class Len = 0
May 28 00:50:57.385059  ASIF: Received No Key from Server

May 28 00:50:57.385174 Processing authentication response complete
May 28 00:50:57.385277 Deleted background job to process replies from authentication client
May 28 00:50:57.385516 Sending message to authentication client
May 28 00:50:57.389260 Received message from authentication client
May 28 00:50:57.389407 reply: a69b2c rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:50:57.389947 hdr_bytes_read 0
May 28 00:50:57.390087 len read : 28 reply_len: 28
May 28 00:50:57.390191 bytes_remnant 0 tot_bytes_read 28
May 28 00:50:57.390278 Creating background job to process reply from authentication client
May 28 00:50:57.390458 Entering background job to process message from authentication client
May 28 00:50:57.390615 process_auth_reply len:28
May 28 00:50:57.390782 Authentication client could not contact RADIUS servers
May 28 00:50:57.390902 pnac_apply_server_fail_action portnum:73
May 28 00:50:57.391000 Server Fail 0
May 28 00:50:57.391110 Server Fail Deny/Default
May 28 00:50:57.391185 (pnac_construct_eap_pkt) code:4
May 28 00:50:57.391280 Invoking state machine for authentication response for mac address 1C:75:08:32:07:2C 
May 28 00:50:57.391369  on intf fe-0/0/2.0

May 28 00:50:57.391506 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:50:57.392197 authenticatedStateCause :1
May 28 00:50:57.392336  ASIF: Handing over Server frame to Authenticator

May 28 00:50:57.392459  AUTH: Handling Server Frame

May 28 00:50:57.392625  SessNode got from SessIdtbl for Id 0 is : aa4aa8, Port: 73  

May 28 00:50:57.392751 Code = 4, Id = 0, Len = 4

May 28 00:50:57.392872  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:50:57.392991  for Port: 73, MAC: 1c750832-72c 

May 28 00:50:57.393086  Id: 1, SessionNode: aa4aa8 

May 28 00:50:57.393244  TMR: Timer is deleted

May 28 00:50:57.393362  BSM moved to state: FAIL !!

May 28 00:50:57.393493  BSM moved to state: IDLE !!

May 28 00:50:57.393867  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:50:57.394053  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:50:57.394180  Id: 0, SessionNode: aa4aa8 

May 28 00:50:57.394330  TMR: Quiet While Timer Started for port:73, Duration: 60 !!

May 28 00:50:57.394500  TMR: Timer is started

May 28 00:50:57.394622  ASM moved to state: HELD !!

May 28 00:50:57.394774  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:50:57.394899 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:50:57.394984 Rad Attr Class Len = 0
May 28 00:50:57.395055  ASIF: Received No Key from Server

May 28 00:50:57.395175 Processing authentication response complete
May 28 00:50:57.395281 Deleted background job to process replies from authentication client
May 28 00:51:40.726240  Trying to create a session for MAC: -80027df-fe56- thro' Port: 73 ... 

May 28 00:51:40.726569 queried vid:4
May 28 00:51:40.726657  Allocated Session Node : aad000 

May 28 00:51:40.726882  PnacAuthSessStatsInit PnacAuthSessStatsInit

May 28 00:51:40.727076 Generated Unique SessId :9299371500338091999
May 28 00:51:40.727177  BSM Called with Event: INITIALIZE, and State: Initialise

May 28 00:51:40.727306  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.727407  Id: 0, SessionNode: aad000 

May 28 00:51:40.727518  BSM moved to state: INITIALIZE !!

May 28 00:51:40.727632  BSM moved to state: IDLE !!

May 28 00:51:40.727754  ASM Called with Event: INITIALIZE, and State: Initialize

May 28 00:51:40.727896  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.728015  Id: 0, SessionNode: aad000 

May 28 00:51:40.728123 ASM: Inside PnacAuthAsmInitInitialize

May 28 00:51:40.728237  ASM moved to state: INITIALIZE !!

May 28 00:51:40.728364  ASM moved to state: DISCONNECTED !!

May 28 00:51:40.728490 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:51:40.728602  ASM moved to state: CONNECTING !!

May 28 00:51:40.728714 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:51:40.728856 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:51:40.730003  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:51:40.730149  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.730263  Id: 0, SessionNode: aad000 

May 28 00:51:40.730372 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:51:40.730497  ASM moved to state: AUTHENTICATING !!

May 28 00:51:40.730617  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:51:40.730734  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.730845  Id: 0, SessionNode: aad000 

May 28 00:51:40.731140  TMR: Timer is started

May 28 00:51:40.731299  BSM moved to state: RESPONSE !!

May 28 00:51:40.731438  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:51:40.731583 SessId: 8O2.1x810e011b000b174a strlen: 22
May 28 00:51:40.731840 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:51:40.732345  ASIF: Radius REQUEST_ID: fe
May 28 00:51:40.732489  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:51:40.732626  Session Created for MAC: -80027df-fe56- through Port: 73 ... 

May 28 00:51:40.733206 Sending message to authentication client
May 28 00:51:40.737021 Received message from authentication client
May 28 00:51:40.737888 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:51:40.738086 hdr_bytes_read 0
May 28 00:51:40.738209 len read : 28 reply_len: 28
May 28 00:51:40.738314 bytes_remnant 0 tot_bytes_read 28
May 28 00:51:40.738387 Creating background job to process reply from authentication client
May 28 00:51:40.738591 Entering background job to process message from authentication client
May 28 00:51:40.738720 process_auth_reply len:28
May 28 00:51:40.738816 Authentication client could not contact RADIUS servers
May 28 00:51:40.738904 pnac_apply_server_fail_action portnum:73
May 28 00:51:40.739007 Server Fail 0
May 28 00:51:40.739090 Server Fail Deny/Default
May 28 00:51:40.739174 (pnac_construct_eap_pkt) code:4
May 28 00:51:40.739277 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:51:40.739370  on intf fe-0/0/2.0

May 28 00:51:40.739603 authenticatedStateCause :1
May 28 00:51:40.739732  ASIF: Handing over Server frame to Authenticator

May 28 00:51:40.739854  AUTH: Handling Server Frame

May 28 00:51:40.739983  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:51:40.740103 Code = 4, Id = 0, Len = 4

May 28 00:51:40.740239  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:51:40.740370  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.740489  Id: 0, SessionNode: aad000 

May 28 00:51:40.740620  TMR: Timer is deleted

May 28 00:51:40.740750  BSM moved to state: FAIL !!

May 28 00:51:40.740876  BSM moved to state: IDLE !!

May 28 00:51:40.741002  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:51:40.741213  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.741359  Id: 0, SessionNode: aad000 

May 28 00:51:40.741483 Auth Fail Count 1: Moved to state CONNECTING !!

May 28 00:51:40.741612 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:51:40.741724  ASM moved to state: CONNECTING !!

May 28 00:51:40.741847 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:51:40.741963 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:51:40.742126  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:51:40.742309  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.742437  Id: 1, SessionNode: aad000 

May 28 00:51:40.742532 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:51:40.742650  ASM moved to state: AUTHENTICATING !!

May 28 00:51:40.742772  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:51:40.742897  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.743008  Id: 1, SessionNode: aad000 

May 28 00:51:40.743179  TMR: Timer is started

May 28 00:51:40.743569  BSM moved to state: RESPONSE !!

May 28 00:51:40.743761  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:51:40.743930 SessId: 8O2.1x810e011b000b174a strlen: 22
May 28 00:51:40.744193 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:51:40.745271  ASIF: Radius REQUEST_ID: ff
May 28 00:51:40.745514  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:51:40.745694  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:51:40.745825 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:51:40.745905 Rad Attr Class Len = 0
May 28 00:51:40.745995  ASIF: Received No Key from Server

May 28 00:51:40.746111 Processing authentication response complete
May 28 00:51:40.746216 Deleted background job to process replies from authentication client
May 28 00:51:40.746447 Sending message to authentication client
May 28 00:51:40.751264 Received message from authentication client
May 28 00:51:40.751422 reply: a69b04 rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:51:40.751550 hdr_bytes_read 0
May 28 00:51:40.751652 len read : 28 reply_len: 28
May 28 00:51:40.751751 bytes_remnant 0 tot_bytes_read 28
May 28 00:51:40.751834 Creating background job to process reply from authentication client
May 28 00:51:40.752011 Entering background job to process message from authentication client
May 28 00:51:40.752135 process_auth_reply len:28
May 28 00:51:40.752231 Authentication client could not contact RADIUS servers
May 28 00:51:40.752330 pnac_apply_server_fail_action portnum:73
May 28 00:51:40.752424 Server Fail 0
May 28 00:51:40.752494 Server Fail Deny/Default
May 28 00:51:40.752571 (pnac_construct_eap_pkt) code:4
May 28 00:51:40.753560 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:51:40.753957  on intf fe-0/0/2.0

May 28 00:51:40.754141 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:51:40.754319 authenticatedStateCause :1
May 28 00:51:40.754423  ASIF: Handing over Server frame to Authenticator

May 28 00:51:40.754523  AUTH: Handling Server Frame

May 28 00:51:40.754647  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:51:40.754805 Code = 4, Id = 0, Len = 4

May 28 00:51:40.754922  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:51:40.755020  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.755128  Id: 1, SessionNode: aad000 

May 28 00:51:40.755267  TMR: Timer is deleted

May 28 00:51:40.755387  BSM moved to state: FAIL !!

May 28 00:51:40.755513  BSM moved to state: IDLE !!

May 28 00:51:40.755635  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:51:40.755895  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.756008  Id: 0, SessionNode: aad000 

May 28 00:51:40.756127 Auth Fail Count 2: Moved to state CONNECTING !!

May 28 00:51:40.756259 ASM CONNECTING : Intf fe-0/0/2.0: ReqId Count 0 Reauth Count 0 

May 28 00:51:40.756507  ASM moved to state: CONNECTING !!

May 28 00:51:40.756627 ASM CONNECTING: ONLY-MAC-MODE init on intf fe-0/0/2.0

May 28 00:51:40.756741 PnacAuthAsmInitMacRadius Intf fe-0/0/2.0: Launch MACAUTH.

May 28 00:51:40.756949  ASM Called with Event: RXRESPID, and State: Connecting

May 28 00:51:40.757081  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.757193  Id: 1, SessionNode: aad000 

May 28 00:51:40.757304 ASM: Inside PnacAuthAsmRxrespConnecting

May 28 00:51:40.757400  ASM moved to state: AUTHENTICATING !!

May 28 00:51:40.757516  BSM Called with Event: AUTHSTART, and State: Idle

May 28 00:51:40.757629  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.757740  Id: 1, SessionNode: aad000 

May 28 00:51:40.757954  TMR: Timer is started

May 28 00:51:40.758083  BSM moved to state: RESPONSE !!

May 28 00:51:40.758205  ASIF: Transferring Server-data to Auth Server for the user, 080027dffe56.

May 28 00:51:40.758343 SessId: 8O2.1x810e011b000b174a strlen: 22
May 28 00:51:40.758600 Queuing message to auth client to validate mac address 8:0:27:df:fe:56, user 080027dffe56 on interface fe-0/0/2.0
May 28 00:51:40.759067  ASIF: Radius REQUEST_ID:  1
May 28 00:51:40.759196  ASIF: Tx of Server-data to Auth Server succeeded

May 28 00:51:40.759321  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:51:40.759458 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:51:40.759526 Rad Attr Class Len = 0
May 28 00:51:40.759610  ASIF: Received No Key from Server

May 28 00:51:40.759724 Processing authentication response complete
May 28 00:51:40.759824 Deleted background job to process replies from authentication client
May 28 00:51:40.760062 Sending message to authentication client
May 28 00:51:40.764871 Received message from authentication client
May 28 00:51:40.765055 reply: a69b2c rply_hdr: a6b000 bytes_remnant :0 len:28 reply_len:28
May 28 00:51:40.765189 hdr_bytes_read 0
May 28 00:51:40.765266 len read : 28 reply_len: 28
May 28 00:51:40.765355 bytes_remnant 0 tot_bytes_read 28
May 28 00:51:40.765527 Creating background job to process reply from authentication client
May 28 00:51:40.765757 Entering background job to process message from authentication client
May 28 00:51:40.765876 process_auth_reply len:28
May 28 00:51:40.765970 Authentication client could not contact RADIUS servers
May 28 00:51:40.766181 pnac_apply_server_fail_action portnum:73
May 28 00:51:40.766307 Server Fail 0
May 28 00:51:40.766378 Server Fail Deny/Default
May 28 00:51:40.766458 (pnac_construct_eap_pkt) code:4
May 28 00:51:40.766529 Invoking state machine for authentication response for mac address 08:00:27:DF:FE:56 
May 28 00:51:40.766629  on intf fe-0/0/2.0

May 28 00:51:40.766762 pnac_async_response_to_eswd_internal auth_vid:4, queried_vid:4
May 28 00:51:40.766934 authenticatedStateCause :1
May 28 00:51:40.767023  ASIF: Handing over Server frame to Authenticator

May 28 00:51:40.767180  AUTH: Handling Server Frame

May 28 00:51:40.767311  SessNode got from SessIdtbl for Id 0 is : aad000, Port: 73  

May 28 00:51:40.767429 Code = 4, Id = 0, Len = 4

May 28 00:51:40.767527  BSM Called with Event: AFAIL_RCVD, and State: Response

May 28 00:51:40.767654  for Port: 73, MAC: 80027df-fe56 

May 28 00:51:40.767767  Id: 1, SessionNode: aad000 

May 28 00:51:40.767898  TMR: Timer is deleted

May 28 00:51:40.768017  BSM moved to state: FAIL !!

May 28 00:51:40.768187  BSM moved to state: IDLE !!

May 28 00:51:40.768314  ASM Called with Event: BKEND_AUTHFAIL, and State: Authenticating

May 28 00:51:40.768433  for Port: 73, MAC: 80027df - fe56 

May 28 00:51:40.768529  Id: 0, SessionNode: aad000 

May 28 00:51:40.768695  TMR: Quiet While Timer Started for port:73, Duration: 60 !!

May 28 00:51:40.768830  TMR: Timer is started

May 28 00:51:40.768950  ASM moved to state: HELD !!

May 28 00:51:40.769069  ASIF: Handing over Server frame to Authenticator 73.

May 28 00:51:40.769220 PnacAsIfRecvFromServer : Rad Attr Statelen = 0
May 28 00:51:40.769305 Rad Attr Class Len = 0
May 28 00:51:40.769373  ASIF: Received No Key from Server

May 28 00:51:40.769560 Processing authentication response complete
May 28 00:51:40.769678 Deleted background job to process replies from authentication client
May 28 00:51:57.413992  ASM Called with Event: QUIETWHILE_EXPIRED, and State: Held

May 28 00:51:57.414279  for Port: 73, MAC: 1c750832 - 72c 

May 28 00:51:57.414404  Id: 1, SessionNode: aa4aa8 

May 28 00:51:57.414592  Trying to delete the session for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:51:57.415647  Trying to delete the Session Node : aa4aa8 ... 

May 28 00:51:57.415946  Session Deleted for MAC: -1c750832-72c- on Port: 73 ... 

May 28 00:51:57.416116  Deleted Session Node : aa4aa8 ... 

May 28 00:51:57.416306 QuietWhile Timer Expired and Session deleted !!!