Vastlegging AnyConnect Network Access Manager begrijpen

Inleiding

In dit document wordt beschreven hoe u AnyConnect Network Access Manager (NAM)-logboekregistratie kunt inschakelen en hoe u de logbestanden kunt verzamelen en interpreteren. De voorbeelden in het document beschrijven verschillende verificatiescenario's en de logbestanden die de stappen weerspiegelen die door Network Access Manager zijn genomen om de client te verifiëren.

Voorwaarden

Vereisten

Er zijn geen specifieke vereisten van toepassing op dit document.

Gebruikte componenten

Dit document is niet beperkt tot specifieke software- en hardware-versies.

De informatie in dit document is gebaseerd op de apparaten in een specifieke laboratoriumomgeving. Alle apparaten die in dit document worden beschreven, hadden een opgeschoonde (standaard)configuratie. Als uw netwerk live is, moet u de potentiële impact van elke opdracht begrijpen.

NAM-vastlegging inschakelen

Als er een probleem wordt geïdentificeerd dat mogelijk verband houdt met de NAM-module, is de eerste stap om de functie Uitgebreid vastleggen in te schakelen. Dit moet worden gedaan op het client-endpoint terwijl de NAM-module actief is.

Stap 1. Open het AnyConnect-venster en controleer of de focus goed is.

Stap 2. Druk op deze toetsencombinatie, Links Shift + Links Alt + L. Er is geen reactie.

Stap 3. Klik met de rechtermuisknop op het pictogram AnyConnect in het systeemvak van Windows. Er verschijnt een menu.

Stap 4. Selecteer Extended Logging zodat er een selectieteken wordt weergegeven. NAM nu logboeken gedetailleerde debug berichten.

NAM Packet Capture configureren

Wanneer Extended Logging is ingeschakeld, houdt NAM ook een pakketopnamebuffer actief. De buffer is standaard beperkt tot ongeveer 1 MB. Als pakketopname nodig is, kan het nuttig zijn om de buffergrootte te vergroten zodat meer activiteiten worden opgenomen. Om de buffer uit te breiden, moet een XML-instellingsbestand handmatig worden gewijzigd.

Stap 1. Blader op de Windows-pc naar:
C:\ProgramData\Cisco\Cisco AnyConnect Secure Mobility Client\Network Access Manager\systeem\

Stap 2. Open bestand internalConfiguration.xml.

Stap 3. Zoek de XML-tag <packetCaptureFileSize>1</packetCaptureFileSize> en stel de waarde in op 10 voor een buffergrootte van 10 MB, enzovoort.

Stap 4. Start de client-pc opnieuw op zodat de wijziging van kracht wordt.

Logbestanden verzamelen

NAM log collectie wordt gedaan via Diagnostic and Reporting Tool (DART), een module van AnyConnect suite. Selecteer in het installatieprogramma een module en gebruik AnnyConnect volledige installatie ISO om te installeren. Het installatieprogramma voor Cisco Media Services Interface (MSI) is ook te vinden binnen de ISO.

Nadat u Extended Logging hebt ingeschakeld en een test hebt uitgevoerd, voert u eenvoudig DART uit en gaat u door het dialoogvenster, de logbundel bevindt zich standaard op het Windows-bureaublad.

Naast de DART-bundel is het NAM-berichtenlog ook handig om de relevante gegevens in het NAM-logboek te vinden. Om het NAM-berichtenlog te vinden, navigeer je naar het AnyConnect-instellingenvenster > Network Access Manager > Berichtgeschiedenis. Het berichtenlog bevat tijdstempel van elke netwerkverbindingsgebeurtenis, die kan worden gebruikt om de logbestanden te vinden die relevant zijn voor de gebeurtenis.

Lezen van NAM-logbestanden

NAM-logbestanden, vooral nadat u Extended Logging hebt ingeschakeld, bevatten een grote hoeveelheid gegevens, waarvan de meeste irrelevant zijn en kunnen worden genegeerd. Deze sectie beschrijft de debug-regels om aan te tonen dat elke stap van NAM is om een netwerkverbinding tot stand te brengen. Wanneer u door een logboek werkt, kunnen deze sleutelzinnen helpen om een deel van het logboek te vinden dat relevant is voor het probleem. 

Log overzicht van een netwerkverbinding zonder 802.1x enabled-verificatie

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

Uitleg: Dit geeft aan dat de gebruiker een netwerk heeft geselecteerd van de NAM-module en dat NAM een userEvent van START heeft ontvangen.

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

Uitleg: Zowel de machine van de toegangsstaat als de Machine van de netwerkstaat zijn begonnen.

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]

Uitleg: De IPv4-instantie is geannuleerd om de toestanden te resetten.

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 ...

Uitleg: De adapter met ID 484E4FEF-392C-436F-97F0-CD7206CD7D48 is geselecteerd voor verbinding met netwerk test123, wat de naam is van de netwerkverbinding die in NAM is geconfigureerd.

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

Uitleg: de NAM heeft met succes de adapter voor dit netwerk ingeschakeld. NAM probeert nu verbinding te maken met dit netwerk (dat draadloos is):

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)

Uitleg: openNoEncryption geeft aan dat het netwerk is geconfigureerd als open. Op de draadloze LAN-controller maakt het gebruik van MAC-verificatie-omzeiling (MAB) om te verifiëren.

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

Uitleg: cs kan veel worden gezien in NAM-logs. Dit zijn irrelevante logs en moeten worden genegeerd.

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>
 

Uitleg: Dit zijn SOAP-berichten (Simple Object Access Protocol) die worden gebruikt om AnyConnect GUI te vertellen dat het bericht over de verbindingsstatus moet worden weergegeven, zoals Associating in dit geval. Alle foutmeldingen die in het NAM-venster worden weergegeven, kunnen worden gevonden in een van de SOAP-berichten in het logboek die kan worden gebruikt om het probleem gemakkelijk te vinden.

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)

Uitleg: NAM ontvangt een AUTH_SUCCESS gebeurtenis, die misleidt omdat er geen authenticatie is die momenteel is gebeurd. U krijgt deze gebeurtenis simpelweg omdat u verbinding maakt met een open netwerk, dus standaard verificatie is succesvol.

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

Uitleg: Associatie naar Service Set Identifier (SSID) is succesvol, tijd om verificatie te verwerken.

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

Uitleg: Aangezien dit een open netwerk is, wordt het standaard geverifieerd. Op dit punt is NAM verbonden met het netwerk en start het DHCP-proces:

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

Uitleg: NAM verwerft met succes een IP-adres.

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

Uitleg: Zodra een IP-adres is ontvangen, zal de NAM ARP (Address Resolution Protocol)-verzoek verzenden naar de gateway (Get-Connectivity). Zodra de ARP reactie wordt ontvangen wordt de client verbonden.

Log overzicht van een netwerkverbinding met 802.1x en PEAP over Wired Network

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

Uitleg: NAM begon verbinding te maken met het netwerk van 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
 

Uitleg: NAM heeft een adapter gekoppeld aan dit netwerk.

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)
 

Uitleg: NAM begon verbinding te maken met dit bekabelde netwerk.

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)
 

Uitleg: De client stuurt 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)
 

Uitleg: Klant ontvangt identiteitsverzoek van de switch, het zoekt nu naar een bewijs om terug te sturen.

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
 

Uitleg: Standaard verstuurt AnyConnect anoniem als onbeschermde identiteit (buitenste identiteit), dus hier probeert het anoniem en ziet u of de server OK is. Het feit dat de identiteit anoniem is in plaats van host/anoniem geeft aan dat het om een gebruikersverificatie gaat, in plaats van om machine-verificatie.

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)
 

Uitleg: RADIUS-server stuurt een EAP-TLS-frame (Extensible Verification Protocol-Transport Layer Security) zonder inhoud. Het doel ervan is om met de client te onderhandelen over een EAP-TLS-protocol.

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
 

Uitleg: NAM erkent het verzoek van de server om EAP-TLS te gebruiken, maar de client is geconfigureerd voor het gebruik van Protected Extensible Verification Protocol (PEAP). Dat is de reden dat de NAM een tegenbod voor PEAP doet.

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
 

Uitleg: RADIUS-server accepteert de buitenste/onbeschermde identiteit.

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)
 

Uitleg: Het beschermde deel van PEAP (om een beveiligde tunnel te creëren om innerlijke referenties uit te wisselen) start, nadat de client een bevestiging van RADIUS-server ontvangt om het gebruik van PEAP voort te zetten.

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)
 

Uitleg: NAM stuurt een client hello ingesloten in EAP bericht en wacht tot server hello komt. De hello van de server bevat ISE-certificaat, dus het duurt enige tijd om de overdracht af te ronden.

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)
 

Uitleg: NAM haalde de onderwerpnaam van de ISE-server uit het servercertificaat. Aangezien er geen servercertificaat geïnstalleerd is in de trust store, vind je het daar niet.

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
 

Uitleg: NAM zoekt de binnen/beschermde identiteit die naar de server van RADIUS moet worden verzonden nadat de tunnel wordt gevestigd. In dit geval is de optie "Automatisch mijn Windows-gebruikersnaam en -wachtwoord gebruiken" ingeschakeld op de bekabelde adapter. NAM gebruikt dus aanmeldingsgegevens voor Windows in plaats van de gebruiker ernaar te vragen.

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)
 

Uitleg: NAM stuurde client-sleutel en algoritme-specificaties naar server en ontving bevestiging. SSL-onderhandeling is succesvol en er wordt een tunnel tot stand gebracht.

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
 

Uitleg: Beschermde identiteit wordt verzonden naar de server, die de identiteit accepteert. De server vraagt nu om een wachtwoord.

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
 

Uitleg: NAM ontvangt wachtwoordverzoek en stuurt wachtwoord naar server.

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)
 

Uitleg: De server ontvangt het wachtwoord, verifieert het en verstuurt EAP-Success. De verificatie is op dit punt succesvol en de client gaat verder als het IP-adres van DHCP wordt ontvangen.