Contenido

Introducción

prerrequisitos

Requisitos

Componentes Utilizados

Registro del permiso NAM

Captura de paquetes de la configuración NAM

Colección del registro

Lectura de los registros NAM

Registre el resumen de una conexión de red sin la autenticación habilitada 802.1x

Registre el resumen de una conexión de red usando el 802.1x y el PEAP sobre la red alámbrica

Introducción

Este documento describe cómo habilitar el registro del administrador del acceso a la red de AnyConnect (NAM) así como recoger e interpretar los registros. Los ejemplos incluidos en el documento describen diversos escenarios de la autenticación y los registros que reflejen los pasos tomados por el administrador del acceso a la red para autenticar al cliente.

Prerequisites

Requisitos

No hay requisitos específicos para este documento.

Componentes Utilizados

Este documento no tiene restricciones específicas en cuanto a versiones de software y de hardware.

La información que contiene este documento se creó a partir de los dispositivos en un ambiente de laboratorio específico. Todos los dispositivos que se utilizan en este documento se pusieron en funcionamiento con una configuración verificada (predeterminada). Si la red está funcionando, asegúrese de haber comprendido el impacto que puede tener cualquier comando.

Habilite el registro NAM

Si se identifica un problema que se puede relacionar con el módulo NAM, el primer paso es habilitar la característica de registro extendida. Esto se debe hacer en el punto final del cliente mientras que el módulo NAM se está ejecutando.

Paso 1. Abra la ventana de AnyConnect y aseegurese la está en el foco.

Paso 2. Presione esta combinación de claves, mayús izquierdo + se fue Alt + L. No hay respuesta.

Paso 3. Click derecho en el icono de AnyConnect en la bandeja del Sistema Windows. Un menú surge.

Paso 4. Seleccione la registración extendida así que hace una marca de tilde visualizar. El NAM ahora registra los mensajes detallados del debug.

Captura de paquetes de la configuración NAM

Cuando se habilita el registro extendido, el NAM también guarda ir del buffer de la captura de paquetes. El buffer por abandono se limita alrededor a 1MB. Si la captura de paquetes es necesaria, puede ser beneficioso aumentar el tamaño de almacén intermedio así que captura más actividades. Para ampliar el buffer, un archivo de la configuración XML debe ser modificado manualmente.

Paso 1. En el PC de Windows, hojee a:

Cliente de la movilidad de C:\ProgramData\Cisco\Cisco AnyConnect \ administrador \ sistema seguros del acceso a la red \

Paso 2. Abrir archivo internalConfiguration.xml.

Paso 3. Localice la etiqueta <packetCaptureFileSize>1</packetCaptureFileSize> XML y ajuste el valor a 10 para que haya un tamaño de almacén intermedio 10MB, y así sucesivamente.

Paso 4. Reinicie PC del cliente para que el cambio tome el efecto.

Registre la colección

La colección del registro NAM se hace vía el diagnóstico y la herramienta de informe (DARDO), que es un módulo de la habitación de AnyConnect. En el instalador, seleccione un módulo y utilice la instalación completa ISO de AnnyConnect para instalar. El instalador de la interfaz de los servicios de medios de Cisco (MSI) se puede también encontrar dentro del ISO.

Después de que usted habilite el registro extendido y realice una prueba, ejecute simplemente el DARDO y pase con el diálogo, el conjunto del registro está situado por abandono en el escritorio de Windows.

Además del conjunto del DARDO, el registro de mensajes NAM es también útil localizar los datos pertinentes en el registro NAM. Para encontrar el registro de mensajes NAM, navegue al historial de la ventana de configuración de AnyConnect > del administrador > del mensaje del acceso a la red. El registro de mensajes contiene el grupo fecha/hora de cada evento de la conexión de red, que se puede utilizar para encontrar los registros relevantes al evento.

Lectura de los registros NAM

Los registros NAM, especialmente después de que usted habilita el registro extendido, contienen una gran cantidad de datos, más cuyo sea inútil y puede ser ignorado. Esta sección enumera hacia fuera las líneas del debug para demostrar cada paso NAM toma para establecer una conexión de red. Cuando usted trabaja a través de un registro, estas frases claves pueden ser útiles establecer a la parte del registro relevante al problema.

Registre el resumen de una conexión de red sin la autenticación habilitada 802.1x

2016 17:20:37.974 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: AccessStateMachine current state = ACCESS_STOPPED, received userEvent = START

Explicación: Esto indica que el usuario ha seleccionado una red del módulo NAM, y el NAM ha recibido un userEvent del COMIENZO.

538: TESTPC: May 16 2016 17:20:37.974 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: AccessStateMachine new state = ACCESS_STARTED

539: TESTPC: May 16 2016 17:20:37.974 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: NetworkStateMachine current state USER_T_DISCONNECTED, received access event ACCESS_STARTED

Explicación: Se han encendido la máquina de estado del acceso y la máquina de estado de la red.

545: TESTPC: May 16 2016 17:20:37.974 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Ipv4 {EFDAF0F0-CF25-4D88-B125-E748CD539DFF}: received Cancel event [state: COMPLETE]

Explicación: El caso del IPv4 conseguido canceló para reajustar los estados.

547: TESTPC: May 16 2016 17:20:37.974 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: starting makeMatches...

549: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-6-INFO_MSG: %[tid=1412]: matching adapter {484E4FEF-392C-436F-97F0-CD7206CD7D48} and network test123 ...

Explicación: El adaptador con ID 484E4FEF-392C-436F-97F0-CD7206CD7D48 fue seleccionado para conectar con la red test123, que es el nombre de la conexión de red configurada en el NAM.

551: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: AccessStateMachine new state = ACCESS_ATTACHED

557: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: AccessStateMachine current state = ACCESS_ATTACHED, received userEvent = CONNECT

Explicación: El NAM ha dedicado con éxito el adaptador para esta red. Ahora el NAM intenta asociarse (conectar) a esta red (que suceda ser inalámbrica):

561: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM current: state(STATE_DISCONNECTED_LINK_DOWN), event(EVENT_CONNECT)

562: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM state change: STATE_DISCONNECTED_LINK_DOWN -> STATE_ASSOCIATING

567: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-6-INFO_MSG: %[tid=1412]: Starting wifi connection, trying ssid test123 ...

568: TESTPC: May 16 2016 17:20:37.989 +0600: %NAM-6-INFO_MSG: %[tid=1412]: Connection Association Started(openNoEncryption)

Explicación: el openNoEncryption indica que la red está configurada como abierta. En el regulador del Wireless LAN utiliza puente de la autenticación de MAC (MAB) para autenticar.

234: TESTPC: May 16 2016 17:20:38.020 +0600: %NAMSSO-7-DEBUG_MSG: %[tid=1912]: waiting for cs...

Explicación: el cs se puede ver mucho en los registros NAM. Éstos son registros inútiles y deben ser ignorados.

575: TESTPC: May 16 2016 17:20:38.020 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: NetworkStateMachine new state USER_T_DISCONNECTED 236: TESTPC: May 16 2016 17:20:38.020 +0600: %NAMSSO-7-DEBUG_MSG: %[tid=1912]: Tx CP Msg: <?xml version="1.0" encoding="UTF-8"?><SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" xmlns:ssc="http://www.cisco.com/ssc" encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"> <SOAP-ENV:Body> <networkStateEvent> <sequenceNumber>16</sequenceNumber> <groupName>Local networks</groupName> <networkName>test123</networkName> <networkState>Associating</networkState> <adapterName>Intel(R) Centrino(R) Ultimate-N 6300 AGN</adapterName> <serverVerifiedName></serverVerifiedName> </networkStateEvent> </SOAP-ENV:Body></SOAP-ENV:Envelope>

Explicación: Éstos son mensajes simples del protocolo de acceso a objetos (JABÓN) usados para decir AnyConnect GUI visualizar el mensaje del estado de la conexión tal como asociación en este caso. Cualquier mensaje de error visualizado en la ventana NAM se puede encontrar en uno de los mensajes SOAP en el registro que se puede utilizar para localizar el problema fácilmente.

582: TESTPC: May 16 2016 17:20:38.020 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: {484E4FEF-392C-436F-97F0-CD7206CD7D48} - Received STATE_AUTHENTICATED

583: TESTPC: May 16 2016 17:20:38.020 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM current: state(STATE_ASSOCIATING), event(EVENT_AUTH_SUCCESS)

Explicación: El NAM recibe un evento AUTH_SUCCESS, que se engaña porque no hay autenticación que sucedió actualmente. Usted es consigue este evento simplemente porque usted conecta con una red abierta, tan por abandono autenticación es acertado.

595: TESTPC: May 16 2016 17:20:38.738 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network test123: AccessStateMachine current state = ACCESS_ASSOCIATING, received adapterState = associated

Explicación: La asociación al Service Set Identifier (SSID) es acertada, mide el tiempo para manejar la autenticación.

603: TESTPC: May 16 2016 17:20:38.754 +0600: %NAM-6-INFO_MSG: %[tid=1412][mac=1,6,3c:a9:f4:33:ab:50]: Authentication not required.

604: TESTPC: May 16 2016 17:20:38.754 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM current: state(STATE_ASSOCIATED), event(EVENT_AUTH_SUCCESS)

605: TESTPC: May 16 2016 17:20:38.754 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM state change: STATE_ASSOCIATED -> STATE_AUTHENTICATED

Explicación: Puesto que esto es una red abierta, por abandono se autentica. En este momento, el NAM está conectado con la red y ahora comienza el proceso DHCP:

610: TESTPC: May 16 2016 17:20:38.754 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: {484E4FEF-392C-436F-97F0-CD7206CD7D48} creating a new DHCP work

612: TESTPC: May 16 2016 17:20:38.754 +0600: %NAM-6-INFO_MSG: %[tid=1412][mac=1,6,3c:a9:f4:33:ab:50]: {484E4FEF-392C-436F-97F0-CD7206CD7D48}: DHCP: Sending DHCP request

613: TESTPC: May 16 2016 17:20:38.754 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: queueing DHCP work

642: TESTPC: May 16 2016 17:20:40.830 +0600: %NAM-7-DEBUG_MSG: %[tid=1448]: Ipv4 {484E4FEF-392C-436F-97F0-CD7206CD7D48}: connectivity test[03]: IP:10.201.230.196(255.255.255.224) GW:10.201.230.193 [Success]

643: TESTPC: May 16 2016 17:20:40.830 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Ipv4 {484E4FEF-392C-436F-97F0-CD7206CD7D48}: received Success event [state: WAIT_FOR_CONNECTIVITY]

645: TESTPC: May 16 2016 17:20:40.845 +0600: %NAM-6-INFO_MSG: %[tid=1412][mac=1,6,3c:a9:f4:33:ab:50]: {484E4FEF-392C-436F-97F0-CD7206CD7D48}: IP Address Received: 10.201.230.196

646: TESTPC: May 16 2016 17:20:40.845 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Ipv4 Connectivity Result: SUCCESS

Explicación: El NAM adquiere con éxito una dirección IP.

648: TESTPC: May 16 2016 17:20:40.845 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM current: state(STATE_AUTHENTICATED), event(EVENT_IP_CONNECTIVITY)

649: TESTPC: May 16 2016 17:20:40.845 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: ACE: adapter SM state change: STATE_AUTHENTICATED -> STATE_CONNECTED

Explicación: Una vez que se recibe una dirección IP el NAM enviará la petición ARP (protocolo Protocolo de resolución de la dirección (ARP)) al gateway (GET-Conectividad). Una vez que se recibe la respuesta ARP el cliente está conectado.

Registre el resumen de una conexión de red usando el 802.1x y el PEAP sobre la red alámbrica

1286: TESTPC: May 16 2016 17:55:17.138 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network WiredPEAP: AccessStateMachine new state = ACCESS_STARTED

Explicación: El NAM comenzó a conectar con la red WiredPEAP.

1300: TESTPC: May 16 2016 17:55:17.138 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Binding adapter Intel(R) 82579LM Gigabit Network Connection and user auth for network WiredPEAP

1303: TESTPC: May 16 2016 17:55:17.138 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network WiredPEAP: AccessStateMachine new state = ACCESS_ATTACHED

Explicación: El NAM correspondió con un adaptador a esta red.

1309: TESTPC: May 16 2016 17:55:17.138 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Network WiredPEAP: AccessStateMachine current state = ACCESS_ATTACHED, received userEvent = CONNECT

1342: TESTPC: May 16 2016 17:55:17.154 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: STATE (4) S_enterStateAux called with state = CONNECTING (dot1x_sm.c 142)

Explicación: Conexión comenzada NAM con esta red alámbrica.

1351: TESTPC: May 16 2016 17:55:17.154 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: 8021X (4) Sent start frame (dot1x_sm.c 117)

Explicación: El cliente envía EAPOL_START.

1388: TESTPC: May 16 2016 17:55:17.154 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: PORT (3) net: RECV (status: UP, AUTO) (portMsg.c 658)

1389: TESTPC: May 16 2016 17:55:17.154 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: 8021X (4) recvd EAP IDENTITY frame (dot1x_util.c 264)

1397: TESTPC: May 16 2016 17:55:17.154 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) EAP State: EAP_STATE_IDENTITY (eap_auth_client.c 940)

Explicación: El cliente recibe la petición de la identidad del Switch, él ahora busca los credenciales para enviar detrás.

1406: TESTPC: May 16 2016 17:55:17.154 +0600: %NAM-7-DEBUG_MSG: %[tid=1464]: EAP-CB: credential requested: sync=8, session-id=1, handle=00AE1FFC, type=AC_CRED_SESSION_START

1426: TESTPC: May 16 2016 17:55:17.169 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: EAP: processing credential request: sync=8, session-id=1, eap-handle=00AE1FFC, eap-level=0, auth-level=0, protected=0, type=CRED_REQ_SESSION_START

1458: TESTPC: May 16 2016 17:55:17.169 +0600: %NAM-6-INFO_MSG: %[tid=1412]: Trying fast reauthentication for unprotected identity anonymous

1464: TESTPC: May 16 2016 17:55:17.169 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: EAP: credential request completed, response sent: sync=9

Explicación: Por abandono, Anyconnect envía anónimo como identidad desprotegida (identidad externa), tan aquí él intenta anónimo y ve si el servidor es ACEPTABLE con él. El hecho de que la identidad sea anónima en comparación con el host/anónima indica que es una autenticación de usuario, bastante que la autenticación de la máquina.

1492: TESTPC: May 16 2016 17:55:17.185 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: 8021X (4) recvd EAP TLS frame (dot1x_util.c 293)

Explicación: El servidor de RADIUS envía una trama de la Seguridad de la capa del Protocolo-transporte de la autenticación ampliable (EAP-TLS) sin ningún contenido. Su propósito es negociar el protocolo del EAP-TLS con el cliente.

1516: TESTPC: May 16 2016 17:55:17.185 +0600: %NAM-6-INFO_MSG: %[tid=1412]: EAP: EAP suggested by server: eapTls

1517: TESTPC: May 16 2016 17:55:17.185 +0600: %NAM-6-INFO_MSG: %[tid=1412]: EAP: EAP requested by client: eapPeap

1518: TESTPC: May 16 2016 17:55:17.185 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: EAP: EAP methods sent: sync=10

1519: TESTPC: May 16 2016 17:55:17.185 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: EAP: credential request 10: state transition: PENDING -> RESPONDED

Explicación: El NAM reconoce la petición del servidor de utilizar el EAP-TLS pero configuran al cliente para utilizar el protocolo extensible authentication protegido (PEAP). Ésta es la razón que el NAM devuelve una contrapropuesta para el PEAP.

1520: TESTPC: May 16 2016 17:55:17.185 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Auth[WiredPEAP:user-auth]: Authentication state transition: AUTH_STATE_UNPROTECTED_IDENTITY_SENT_FOR_FAST_REAUTHENTICATION -> AUTH_STATE_UNPROTECTED_IDENTITY_ACCEPTED

Explicación: El servidor de RADIUS valida la identidad externa/desprotegida.

1551: TESTPC: May 16 2016 17:55:17.200 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: 8021X (4) recvd EAP PEAP frame (dot1x_util.c 305)

1563: TESTPC: May 16 2016 17:55:17.200 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) EAP-PEAP: SSL handshake start (eap_auth_tls_p.c 409)

Explicación: La porción protegida de comienzo PEAP (establecer un túnel seguro para intercambiar las credenciales internas), después de que el cliente reciba una confirmación del servidor de RADIUS para continuar el uso del PEAP.

1565: TESTPC: May 16 2016 17:55:17.200 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) SSL STATE: SSLv3 write client hello A (eap_auth_tls_p.c 394)

1566: TESTPC: May 16 2016 17:55:17.200 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) SSL STATE: SSLv3 read server hello A (eap_auth_tls_p.c 394)

Explicación: El NAM envía un saludo del cliente encapsulado en el mensaje EAP y espera los saludos del servidor para venir. El servidor hola contiene el certificado ISE, así que tarda un cierto tiempo para acabar de transferir.

1622: TESTPC: May 16 2016 17:55:17.216 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: 8021X (4) recvd EAP PEAP frame (dot1x_util.c 305)

1632: TESTPC: May 16 2016 17:55:17.216 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) SSL STATE: SSLv3 read server hello A (eap_auth_tls_p.c 394)

1633: TESTPC: May 16 2016 17:55:17.216 +0600: %NAM-6-INFO_MSG: %[tid=1468][comp=SAE]: CERT (0) looking up: "/CN=ISE20-1.kurmai.com" (lookup.c 100)

1634: TESTPC: May 16 2016 17:55:17.232 +0600: %NAM-6-INFO_MSG: %[tid=1468][comp=SAE]: CERT (0) Certificate not found: "/CN=ISE20-1.kurmai.com" (lookup.c 133)

1646: TESTPC: May 16 2016 17:55:17.232 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) SSL_ERROR_WANT_X509_LOOKUP (eap_auth_tls_p.c 193)

Explicación: El NAM extrajo el asunto del servidor ISE del certificado de servidor. Puesto que no tiene certificado de servidor instalado en el almacén de la confianza, usted no lo encuentra allí.

1649: TESTPC: May 16 2016 17:55:17.232 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (5) EAP_EVENT_CRED_REQUEST queued (eapCredProcess.c 496)

1650: TESTPC: May 16 2016 17:55:17.232 +0600: %NAM-7-DEBUG_MSG: %[tid=1464][comp=SAE]: EAP (5) EAP: CRED_REQUEST (eapMessage.c 355)

1662: TESTPC: May 16 2016 17:55:17.232 +0600: %NAM-6-INFO_MSG: %[tid=1412]: Getting credentials from logon.

1685: TESTPC: May 16 2016 17:55:17.232 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Auth[WiredPEAP:user-auth]: ...resumed

Explicación: El NAM busca la identidad interna/protegida que se enviará al servidor de RADIUS después de que se establezca el túnel. En este caso, “utilice automáticamente mi nombre de inicio de Windows y la opción de la contraseña” se ha habilitado en el adaptador atado con alambre, así que el NAM utiliza los credenciales de inicio de sesión de las ventanas en vez de pedir al usuario él.

1700: TESTPC: May 16 2016 17:55:17.247 +0600: %NAM-7-DEBUG_MSG: %[tid=1464][comp=SAE]: EAP (0) SSL STATE: SSLv3 write client key exchange A (eap_auth_tls_p.c 394)

1701: TESTPC: May 16 2016 17:55:17.247 +0600: %NAM-7-DEBUG_MSG: %[tid=1464][comp=SAE]: EAP (0) SSL STATE: SSLv3 write change cipher spec A (eap_auth_tls_p.c 394)

1750: TESTPC: May 16 2016 17:55:17.278 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) SSL STATE: SSL negotiation finished successfully (eap_auth_tls_p.c 394)

1751: TESTPC: May 16 2016 17:55:17.278 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) EAP-PEAP: SSL handshake done (eap_auth_tls_p.c 425)

1752: TESTPC: May 16 2016 17:55:17.278 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) EAP-PEAP: New session. (eap_auth_tls_p.c 433)

1753: TESTPC: May 16 2016 17:55:17.278 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: EAP (0) EAP-PEAP: session cipher AES256-SHA. (eap_auth_tls_p.c 441)

Explicación: El NAM envió la clave del cliente y espec. de la cifra al servidor y recibió la confirmación. La negociación SSL es acertada y se establece un túnel.

1810: TESTPC: May 16 2016 17:55:17.294 +0600: %NAM-6-INFO_MSG: %[tid=1412]: Protected identity/(Username) sent.

1814: TESTPC: May 16 2016 17:55:17.294 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Auth[WiredPEAP:user-auth]: Authentication state transition: AUTH_STATE_UNPROTECTED_IDENTITY_ACCEPTED -> AUTH_STATE_PROTECTED_IDENTITY_SENT

1883: TESTPC: May 16 2016 17:55:17.310 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Auth[WiredPEAP:user-auth]: Authentication state transition: AUTH_STATE_PROTECTED_IDENTITY_SENT -> AUTH_STATE_PROTECTED_IDENTITY_ACCEPTED

Explicación: La identidad protegida se envía al servidor, que valida la identidad. Ahora el servidor pide la contraseña.

1902: TESTPC: May 16 2016 17:55:17.310 +0600: %NAM-7-DEBUG_MSG: %[tid=1464][comp=SAE]: EAP (5) deferred password request (eapRequest.c 147)

1918: TESTPC: May 16 2016 17:55:17.310 +0600: %NAM-6-INFO_MSG: %[tid=1412]: Protected password sent.

1921: TESTPC: May 16 2016 17:55:17.325 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Auth[WiredPEAP:user-auth]: Authentication state transition: AUTH_STATE_PROTECTED_IDENTITY_ACCEPTED -> AUTH_STATE_CREDENTIAL_SENT

Explicación: El NAM recibe la petición de la contraseña y envía la contraseña al servidor.

2076: TESTPC: May 16 2016 17:55:17.856 +0600: %NAM-7-DEBUG_MSG: %[tid=1412]: Auth[WiredPEAP:user-auth]: Authentication state transition: AUTH_STATE_CREDENTIAL_SENT -> AUTH_STATE_SUCCESS

2077: TESTPC: May 16 2016 17:55:17.856 +0600: %NAM-7-DEBUG_MSG: %[tid=1468][comp=SAE]: STATE (4) S_enterStateAux called with state = AUTHENTICATED (dot1x_sm.c 142)

Explicación: El servidor recibe la contraseña, la verifica y envía el EAP-éxito. La autenticación es acertada en este momento, y el cliente procede mientras que consigue la dirección IP del DHCP.