Traces - allgemein:

Die LANCOM Router bieten zur Fehlerdiagnose die Möglichkeit, interne Abläufe mitzuprotokollieren.
Diese Trace-Funktion erreichen sie über die Telnet-Konsole oder über das Trace-Modul im LANmonitor.

Nachfolgend finden sie einen PPP-Trace nach Bedeutung jeder Meldung aufgeschlüsselt.



Anwendung:

Der PPP-Trace wird verwendet, um das ordnungsgemäße Zustandekommen einer PPP-basierten Verbindung zu überwachen.
PPP-Basierte Verbindungen sind z.B. ISDN-Verbindungen, DSL-Verbindungen (PPPoE) oder PPTP-Verbindungen (VPN, mit LANCOM nur unverschlüsselt).

Geben sie hierzu in der Telnet-Konsole den Befehl "trace + ppp" ein, oder wählen Sie im Trace-Modul des LANmonitors die Option "PPP" aus.



Der PPP-Trace im Detail (am Beispiel einer Internet-Verbindung):


10:17:58,480

Zeitstempel - wird jeder einzelnen Message vorangestellt

Change phase to ESTABLISH

Wechsel in die ESTABLISH (Aufbau) Phase

Lower-Layer-Up event for LCP

Das LCP (Link-Control-Protocol) wird gestartet

Initializing LCP restart timer to 3000 milliseconds

Einrichtung des LCP-Timers (3000 Millisekunden)

Waiting up to 200ms for connection

Einrichtung einer Wartezeit für langsame Anschlüsse

Starting LCP restart timer with 200 milliseconds

Start des LCP-Timers (200 Millisekunden)



10:17:58,680
Positive Restart-Timeout event for LCP

LCP-Timer ist abgelaufen

Stop waiting for connection

Es wird nicht mehr auf eine Verbindung gewartet

Initializing LCP restart timer to 3000 milliseconds

Aufruf des LCP-Timers (3000 Millisekunden)

Generating LCP configure-request for peer INTERNET

Erstellung einer LCP Konfigurations-Anfrage für die Gegenstelle INTERNET

Inserting local MRU 1504

Einsetzen der lokalen MRU auf 1504 Byte

Inserting local magic number 1f2cdd4c

Einsetzen der Magic Number zur Schleifenerkennung

Inserting local callback via callback controll protocoll

Einsetzen der Rückruf-Option für das CBCP

Inserting local option protocol field compression

Einsetzen der Protokollfeld-Kompression

Inserting local option address- and controlfield compression

Einsetzen der Adress- und Kontrollfeld-Kompression

Sending LCP configure-request with ID 00 and length 21 to peer INTERNET

LCP-Request mit ID 00 an die Gegenstelle INTERNET senden

Starting LCP restart timer with 3000 milliseconds

Start des LCP-Timers (3000 Millisekunden)



10:17:59,210


Received LCP frame from peer INTERNET (channel 1)

Ein LCP-Paket wird von der Gegenstelle INTERNET auf Kanal 1 empfangen

Evaluate configure-request with ID 01 and size 14

Prüfung der empfangenen Konfigurationsanfrage

Peer magic number 77092162 accepted

Keine Schleife, da Magic-Number verschieden

Peer requests authentication protocol PAP, accepted

Die Gegenstelle wünscht PAP-Protokoll, wir akzeptieren

Positive Configure-Request-Received event for LCP

Die LCP-Konfiguration der Gegenseite ist akzeptabel

Sending LCP configure-ack with ID 01 and length 14 to peer INTERNET

Eine Bestätigung der LCP-Konfiguration wird an die Gegenstelle Internet gesendet.



10:17:59,220


Received LCP frame from peer INTERNET (channel 1)


Evaluate configure-reject with ID 00 and size 7

Überprüfung der Konfigurationsablehnung

Peer rejects callback, discard local option

Die Gegenstelle lehnt Rückruf ab, wir passen uns an

Configure-Nak/Rej-Received event for LCP

Die Konfigurationsablehnung wird verwaltet

Initializing LCP restart timer to 3000 milliseconds

Eine neue Konfigurationsanfrage für LCP wird erstellt

Generating LCP configure-request for peer INTERNET
Inserting local MRU 1504
Inserting local magic number 1f2cdd4c
Inserting local option protocol field compression
Inserting local option address- and controlfield compression

diesmal OHNE Rückrufoption



Sending LCP configure-request with ID 02 and length 18 to peer INTERNET

Senden der LCP Konfigurationsanfrage an die Gegenstelle INTERNET

Starting LCP restart timer with 3000 milliseconds




10:17:59,240
Received LCP frame from peer Internet (channel 1)


Evaluate configure-ack with ID 02 and size 18

Überprüfung der Konfigurationsbestätigung

Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds

Die Konfigurationsbestätigung wird verarbeitet

This-Layer-Up action for LCP

Das Link-Control-Protocol ist aktiviert

Change phase to AUTHENTICATE

Die Authentifizierungsphase des PPP wird gestartet

Generating PAP-request for peer INTERNET

Erstellung einer PAP-Anfrage an die Gegenstelle INTERNET

Resolved peer as INTERNET in PPP table

Auflösen der Gegenstelle INTERNET in der PPP-Tabelle

Sending PAP-request with ID 22, size 24 and peer-id LANCOM

Sendung eines PAP-Requests an die Gegenstelle INTERNET unter der eigenen ID LANCOM

Stopping LCP restart timer

LCP-Timer wird gestoppt, da das LCP aktiviert ist



10:17:59,270
Received PAP frame from peer INTERNET (channel 1)


Evaluate PAP ack with ID 22 and size 20

Die PAP-Zustimmung wir überprüft

Embedded message: Login Succeeded

Ggf. wird eine eingebettete ASCII-Nachricht angezeigt

This-Layer-Up action for LCP

Aufgrund der Definition des PPP wird die Aktivierung des LCP noch einmal durchgeführt

Change phase to CALLBACK

Wechsel zur CALLBACK-Phase

This-Layer-Up action for LCP

Ein Rückruf wurde nicht ausgehandelt, daher noch einmal Aktivierung des LCP

Change phase to NETWORK

Wechsel zur NETWORK-Phase

Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer INTERNET

Das IPCP wird gestartet, da untergeordnete Layer bereit, d.h. notwendige Timer werden eingerichtet und eine Konfigurationsanfrage an die Gegenstelle gesendet

Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0
Inserting primary NBNS address 0.0.0.0
Inserting secondary NBNS address 0.0.0.0

Alle notwendigen IP-Adressen werden von der Gegenstelle erfragt, d.h. es handelt sich um einen typischen Internet-Zugang!

Sending IPCP configure-request with ID 00 and length 34 to peer INTERNET

Die Konfigurationsanfrage wird an die Gegenstelle gesendet

Starting IPCP restart timer with 3000 milliseconds




10:17:59,270


Received IPCP frame from peer INTERNET (channel 1)

Ein IPCP-Frame der Gegenstelle auf Kanal 1

Evaluate configure-request with ID 01 and size 16

Die Konfigurationsanfrage wird ausgewertet

Peer requests IP header-compression, rejected

Die Gegenstelle wünscht IP Header-Compression, wir lehnen dies ab

Peer requests IP address 212.202.63.16, accepted

Wir akzeptieren die vorgeschlagene IP-Adresse

Negative Configure-Request-Received event for IPCP

Insgesamt ablehnen, wg. Header-Compression

Sending IPCP configure-reject with ID 01 and length 10 to peer
INTERNET

Eine Konfigurationsablehnung wird gesendet



10:17:59,300
Received IPCP frame from peer INTERNET (channel 1)


Evaluate configure-reject with ID 00 and size 16

Überprüfung der Konfigurationsablehnung

Peer rejects primary NBNS address 0.0.0.0, discard local option

Die Gegenstelle lehnt primären NBNS ab, wir akzeptieren

Peer rejects secondary NBNS address 0.0.0.0, discard local option

Die Gegenstelle lehnt sekundären NBNS ab, wir akzeptieren.

Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer INTERNET

Die Konfigurationsablehnung wird verarbeitet, d.h. notwendige Timer werden gestartet und eine neue, angepasste Konfigurationsanfrage wird gesendet

Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0

Diesmal OHNE NBNS-Adressen!

Sending IPCP configure-request with ID 02 and length 22
to peer INTERNET

Starting IPCP restart timer with 3000 milliseconds




10:17:59,300
Received IPCP frame from peer INTERNET (channel 1)


Evaluate configure-request with ID 02 and size 14
Peer requests IP header-compression, rejected
Peer requests IP address 212.202.63.16, accepted
Negative Configure-Request-Received event for IPCP
Sending IPCP configure-reject with ID 02 and length 8 to peer INTERNET

Die Gegenstelle versucht erneut IP Header-Compression durchzusetzen, was wir erneut ablehnen.



10:17:59,330
Received IPCP frame from peer INTERNET (channel 1)


Evaluate configure-nak with ID 02 and size 22

Überprüfung der Konfigurationsänderung

Peer NAKs IP address 212.202.72.74, accepted

Die Gegenstelle liefert unsere IP-Adresse, wir nehmen an

Peer NAKs primary DNS address 195.158.160.2, accepted

Die Gegenstelle liefert primären DNS, wir nehmen an

Peer NAKs secondary DNS address 195.158.162.1, accepted

Die Gegenstelle liefert sekundären DNS, wir nehmen an

Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer INTERNET

Die Konfigurationsablehnung wird verarbeitet, d.h. notwendige Timer werden gestartet und eine neue, angepasste Konfigurationsanfrage wird gesendet

Inserting IP address 212.202.72.74
Inserting primary DNS address 195.158.160.2
Inserting secondary DNS address 195.158.162.1

Diesmal MIT den zugewiesenen Adressen (aber weiterhin OHNE NBNS)

Sending IPCP configure-request with ID 04 and length 22 to peer INTERNET
Starting IPCP restart timer with 3000 milliseconds




10:17:59,330


Received IPCP frame from peer INTERNET (channel 1)
Evaluate configure-request with ID 03 and size 10
Peer requests IP address 212.202.63.16, accepted
Positive Configure-Request-Received event for IPCP
Sending IPCP configure-ack with ID 03 and length 10 to peer INTERNET

Diesmal verzichtet die Gegenstelle auf IP Header-Compression. Wir akzeptieren diese Konfigurationsanfrage und senden eine Bestätigung.



10:17:59,350
Received IPCP frame from peer INTERNET (channel 1)


Evaluate configure-ack with ID 04 and size 22
Configure-Ack-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
This-Layer-Up action for IPCP

Die Gegenstelle sendet uns ebenfalls eine Bestätigung unserer Konfigurationsanfrage, d.h. das IPCP ist erfolgreich verhandelt. Notwendige Timer werden angehalten und das PPP signalisiert dem restlichen Protokoll-Stack im LANCOM, daß IP-Pakete nun zur Gegenstelle übertragen werden können.

Stopping IPCP restart timer



56 Byte Packet from 141.1.1.1 seq.no=0 time=3664 ms
56 Byte Packet from 141.1.1.1 seq.no=1 time=2693 ms
56 Byte Packet from 141.1.1.1 seq.no=2 time=1696 ms
56 Byte Packet from 141.1.1.1 seq.no=3 time=700 ms
56 Byte Packet from 141.1.1.1 seq.no=4 time=53 ms
56 Byte Packet from 141.1.1.1 seq.no=5 time=52 ms
56 Byte Packet from 141.1.1.1 seq.no=6 time=55 ms

---141.1.1.1 ping statistic---
56 Bytes Data, 7 packets transmitted, 7 packets received, 0% loss

Als Resultat laufen nun Ping-Anfragen, die Verbindung besteht!



10:18:06,400
Administrativ-Close event for LCP

Die Verbindung wird vom LANCOM Router aktiv abgebaut

This-Layer-Down action for LCP

Das LCP wird deaktiviert

Lower-Layer-Down event for BACP
Stopping BACP restart timer

Lower-Layer-Down event for CCP
Stopping CCP restart timer

Lower-Layer-Down event for IPCP
This-Layer-Down action for IPCP
Stopping IPCP restart timer

Lower-Layer-Down event for IPXCP
Stopping IPXCP restart timer

Alle übergeordneten Protokolle des PPP werden ebenfalls geschlossen

Change phase to TERMINATE

PPP wechselt in die TERMINATE-Phase

Sending LCP terminate-request with ID 03 and length 4 to peer INTERNET
Starting LCP restart timer with 3000 milliseconds

Das LCP wird über Terminate-Pakete sauber beendet



10:18:06,420
Received LCP frame from peer INTERNET (channel 1)


Terminate-Ack-Received event for LCP
Stopping LCP restart timer

Die Gegenstelle hat die Terminierung bestätigt

This-Layer-Finish action for LCP

Das LCP wird angehalten

Disconnecting because LCP was finished

Die Leitung kann nun ebenfalls abgebaut werden

Change phase to DEAD

PPP ist beendet

Stopping LCP restart timer
Stopping IPXCP restart timer
Stopping IPCP restart timer
Stopping CCP restart timer
Stopping BACP restart timer

Ggf. noch aktive Timer werden ebenfalls angehalten



Häufige Fehlermeldungen und Ihre Bedeutung im PPP:

Fehlermeldung: PAP-NAK Received:


Received PAP frame from peer T-DSLBIZ (channel 1)

Ein PAP-Frame wird von der Gegenseite empfangen

Evaluate PAP nak with ID 03 and size 55
Embedded message: 1057 STPR2AU 0000000000 UserAuthIP -
unknown user

Es handelt sich um eine PAP nak - Meldung.
Diese wird hier von der Gegenseite noch spezifiziert: unknown User

Administrativ-Close event for LCP

Das LCP wird angehalten

This-Layer-Down action for LCP
Lower-Layer-Down event for BACP
Lower-Layer-Down event for CCP
Lower-Layer-Down event for IPCP
Lower-Layer-Down event for IPXCP

Höherliegende Protokolle werden abgebaut

Change phase to TERMINATE
Sending LCP terminate-request with ID 02 and length 4 to peer T-DSLBIZ

PPP wechselt in die TERMINATE-Phase
Das LCP wird über Terminate-Pakete sauber beendet

Bedeutung:
Die Nutzerdaten werden nicht zugelassen.

Mögliche Ursachen:
- Die Nutzerdaten sind nicht korrekt. Benutzername und/oder Passwort sind nicht bekannt.
- Die Nutzerdaten sind beim Provider gesperrt
- Die Nutzerdaten sind bereits an einem anderen Anschluss in Verwendung.