IP Header Compression

KK · Beitrag von KK » 19 Jul 2008, 18:29

Hallo Forum,

ich schreibe wegen einer völlig trivialen Konfiguration, die sich aber absolut nicht realisieren lassen will:

Ein 1823 soll sich als PPP-Client mit einem Windows-XP RAS DFÜ-Server über ISDN verbinden. Die Verbindung wird aufgebaut, aber sofort und ohne Fehlermeldung wieder abgebaut. Den Trace habe ich heute zwar nicht zur Hand, war aber recht eindeutig: Der RAS Server bricht in der Phase Network ab, da er auf IP-Header Compression besteht. Die will oder kann der 1823 aber nicht bieten.

Lösung wäre also Aktivierung der IP-Header Compression auf dem 1823, oder Deaktivierung der IP-Header Compression auf dem WIN XP RAS DFÜ-Server.

Frage: Ist in den Routern generell keine IP-Header Compression implementiert, oder gibt's im 1823 eine Einstellung, die ich übersehen habe???

Die WIN-Foren habe ich natürlich durchgequält. Im Gegensatz zu anderen Windows Versionen lassen sich sich unter XP und 2000 nur für ausgehende Verbindungen die LCP-Erweiterungen und die IP-Header Compression abschalten. Als RAS-Server ist unter XP und 2000 die IP-Header Compression nicht abschaltbar. (Gerne lasse ich mich eines Besseren belehren).

Die o.g. Konstellation ist doch nun alles andere als exotisch... Bin ich der Einzige mit diesem Problem? Es gab hierzu schon früher ein Posting, das aber nicht auf den Punkt kam: http://www.lancom-forum.de/ptopic,3038, ... .html#3038

Hat irgend ein Experte eine Idee?

backslash · Beitrag von **backslash** » 20 Jul 2008, 21:21

Hi KK

Der RAS Server bricht in der Phase Network ab, da er auf IP-Header Compression besteht.

das wage ich zu bezweifeln, weil Headerkompression rein optional ist, d.h. wenn eine Seite sie nicht beherrscht, dann wird es auch nicht gemacht.

Die will oder kann der 1823 aber nicht bieten.

kann!
Die Header-Kompression spart gerade mal 8 Bytes. Das ist aber nur für einen reinen Client überhaupt sinnvoll (weil unter den gesparten Bytes auch id IP-Adresse des Clients ist). Da das LANCOM ein Router ist, verweigert es auch die Headerkomression...

Zudem bringt sie eh nur etwas bei extrem langsamen Verbindungen (V.110 mit 9,6 KBit/s).

Lösung wäre also Aktivierung der IP-Header Compression auf dem 1823, oder Deaktivierung der IP-Header Compression auf dem WIN XP RAS DFÜ-Server.

mach lieber mal einen PPP-Trace auf dem LANCOM und schau nach, weshalb die Verbindung scheitert. Mit der Headerkompression hat das definitiv nichts zu tun

Als RAS-Server ist unter XP und 2000 die IP-Header Compression nicht abschaltbar. (Gerne lasse ich mich eines Besseren belehren).

sie braucht auch nicht schaltbar zu sein, weil es ausgehandelt wird.

Die o.g. Konstellation ist doch nun alles andere als exotisch...

korrekt, und funktioniert auch problemlos... Hier eine Einwahl in ein Windows 200 ohne, daß irgendetwas konfiguriert wurde (bis auf den Adreßpool)

Code: Alles auswählen

[PPP] 2008/07/20 21:10:13,510
Change phase to ESTABLISH
Lower-Layer-Up event for LCP
Initializing LCP restart timer to 3000 milliseconds
Waiting up to 200ms for connection
Starting LCP restart timer with 200 milliseconds


[PPP] 2008/07/20 21:10:13,710
Positive Restart-Timeout event for LCP
Stop waiting for connection
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer RASTEST
Inserting local MRU 1504
Inserting local magic number dc217f36
Inserting local option protocol field compression
Inserting local option address- and controlfield compression
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 00 and length 21 to peer RASTEST (channel 1)
Starting LCP restart timer with 3000 milliseconds


[PPP] 2008/07/20 21:10:16,710
Positive Restart-Timeout event for LCP
Generating LCP configure-request for peer RASTEST
Inserting local MRU 1504
Inserting local magic number dc217f36
Inserting local option protocol field compression
Inserting local option address- and controlfield compression
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 01 and length 21 to peer RASTEST (channel 1)
Starting LCP restart timer with 3000 milliseconds


[PPP] 2008/07/20 21:10:19,710
Positive Restart-Timeout event for LCP
Generating LCP configure-request for peer RASTEST
Inserting local MRU 1504
Inserting local magic number dc217f36
Inserting local option protocol field compression
Inserting local option address- and controlfield compression
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 02 and length 21 to peer RASTEST (channel 1)
Starting LCP restart timer with 3000 milliseconds


[PPP] 2008/07/20 21:10:19,740

Received LCP frame from peer RASTEST (channel 1)
Evaluate configure-request with ID 00 and size 49
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 671141f2 accepted
Peer requests callback via callback control protocol, accepted
Peer Link-Discriminator 0002 rejected
Negative Configure-Request-Received event for LCP
Sending LCP configure-reject with ID 00 and length 35 to peer RASTEST (channel 1)


[PPP] 2008/07/20 21:10:19,750

Received LCP frame from peer RASTEST (channel 1)
Evaluate configure-reject with ID 02 and size 8
Peer does not support protocol field compression - accepted
Peer does not support address- and controlfield compression - accepted
Configure-Nak/Rej-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer RASTEST
Inserting local MRU 1504
Inserting local magic number dc217f36
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 04 and length 17 to peer RASTEST (channel 1)
Starting LCP restart timer with 3000 milliseconds


[PPP] 2008/07/20 21:10:19,790

Received LCP frame from peer RASTEST (channel 1)
Evaluate configure-request with ID 01 and size 18
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 671141f2 accepted
Peer requests callback via callback control protocol, accepted
Negative Configure-Request-Received event for LCP
Sending LCP configure-nak with ID 01 and length 9 to peer RASTEST (channel 1)


[PPP] 2008/07/20 21:10:19,800

Received LCP frame from peer RASTEST (channel 1)
Evaluate configure-ack with ID 04 and size 17
Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds


[PPP] 2008/07/20 21:10:19,810

Received LCP frame from peer RASTEST (channel 1)
Evaluate configure-request with ID 02 and size 18
Peer requests authentication protocol CHAP with DES encryption, accepted
Peer magic number 671141f2 accepted
Peer requests callback via callback control protocol, accepted
Positive Configure-Request-Received event for LCP
Sending LCP configure-ack with ID 02 and length 18 to peer RASTEST (channel 1)
Stopping LCP restart timer
This-Layer-Up action for LCP
Change phase to AUTHENTICATE


[PPP] 2008/07/20 21:10:19,830

Received CHAP frame from peer RASTEST (channel 1)
Got CHAP-Challenge from peer RASTEST
Challenge = 62 0e 39 f5 9e a8 65 94
Found peer-id RASTEST in PPP table
Sending CHAP-response to peer RASTEST (channel 1), length = 49


[PPP] 2008/07/20 21:10:19,980

Received CHAP frame from peer RASTEST (channel 1)
Got CHAP-Success from peer RASTEST
This-Layer-Up action for LCP
Change phase to CALLBACK


[PPP] 2008/07/20 21:10:19,990

Received CBCP frame from peer RASTEST (channel 1)
Evaluate CBCP-request with ID 01 and size 6
Peer offers callback via no callback
Sending CBCP-response to peer RASTEST (channel 1): ok, don't call me back


[PPP] 2008/07/20 21:10:20,020

Received CBCP frame from peer RASTEST (channel 1)
Evaluate CBCP-ack with ID 01 and size 6

This-Layer-Up action for LCP
Change phase to NETWORK
Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer RASTEST
Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0
Sending IPCP configure-request with ID 00 and length 22 to peer RASTEST (channel 1)
Starting IPCP restart timer with 3000 milliseconds
This-Layer-Up action for LCP


[PPP] 2008/07/20 21:10:20,220

Received CCP frame from peer RASTEST (channel 1)
Sending LCP protocol-reject for protocol 80fd with ID 07 and length 16 to peer RASTEST (channel 1)


[PPP] 2008/07/20 21:10:20,230

Received IPCP frame from peer RASTEST (channel 1)
Evaluate configure-request with ID 05 and size 16
Peer requests IP header-compression, rejected
Peer requests IP address 192.168.1.100, accepted
Negative Configure-Request-Received event for IPCP
Sending IPCP configure-reject with ID 05 and length 10 to peer RASTEST (channel 1)


[PPP] 2008/07/20 21:10:20,240

Received IPCP frame from peer RASTEST (channel 1)
Evaluate configure-reject with ID 00 and size 10
Peer rejects secondary DNS address 0.0.0.0, discard local option
Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer RASTEST
Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Sending IPCP configure-request with ID 02 and length 16 to peer RASTEST (channel 1)
Starting IPCP restart timer with 3000 milliseconds


[PPP] 2008/07/20 21:10:20,500

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


[PPP] 2008/07/20 21:10:20,500

Received IPCP frame from peer RASTEST (channel 1)
Evaluate configure-nak with ID 02 and size 16
Peer NAKs IP address 192.168.1.101, accepted
Peer NAKs primary DNS address 10.1.1.1, accepted
Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer RASTEST
Inserting IP address 192.168.1.101
Inserting primary DNS address 10.1.1.1
Sending IPCP configure-request with ID 04 and length 16 to peer RASTEST (channel 1)
Starting IPCP restart timer with 3000 milliseconds


[PPP] 2008/07/20 21:10:20,750

Received IPCP frame from peer RASTEST (channel 1)
Evaluate configure-ack with ID 04 and size 16
Configure-Ack-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
This-Layer-Up action for IPCP
Stopping IPCP restart timer

Auch hier fordert Windows die Headerkompression, welche vom LANCOM abgelehnt wird. Danach macht das Windows einfach ohne die Kompression weiter...

Gruß
Backslash

KK · Beitrag von KK » 21 Jul 2008, 15:03

Hi Backslash

hier der komplette Trace. Komme allein leider nicht weiter und freue mich auf eine fachmännische Analye

Gruß
KK

[TraceStarted] 2008/07/21 14:33:44,000
Used config:
trace + PPP
[Bootlog] 2008/07/21 14:33:44,000
Boot log (189 Bytes):

****

01/01/1900 00:00:02 System boot after power on

DEVICE: LANCOM 1823 VoIP (Annex B)
HW-RELEASE: B
VERSION: 7.52.0058 / 24.04.2008 / 6.26/e74.02.41.2

[Sysinfo] 2008/07/21 14:33:44,000

DEVICE: LANCOM 1823 VoIP (Annex B)
HW-RELEASE: B
SERIAL-NUMBER: 067091800017
MAC-ADDRESS: 00a0571239ba
IP-ADDRESS: 192.168.1.1
IP-NETMASK: 255.255.255.0
INTRANET-ADDRESS: 0.0.0.0
INTRANETMASK: 0.0.0.0
VERSION: 7.52.0058 / 24.04.2008 / 6.26/e74.02.41.2
NAME:
CONFIG-STATUS: 1056;0
FIRMWARE-STATUS: 1;1.5;1.1;7.27.22112007.4;7.52.24042008.5
LANCAPI-PORT: 75
HW-MASK: 00001100000000000000000001100011
FEATUREWORD: 01000000001000000100000100011101
REGISTERED-WORD: 01000000001000000100000100011101
FEATURE-LIST: 00/F/00000000
FEATURE-LIST: 02/F/00000000
FEATURE-LIST: 03/F/00000000
FEATURE-LIST: 04/F/00000000
FEATURE-LIST: 08/F/00000000
FEATURE-LIST: 0e/F/00000000
FEATURE-LIST: 15/F/00000000
FEATURE-LIST: 1e/F/00000000
TIME: 14325721072008
HTTP-PORT: 80
HTTPS-PORT: 443
[PPP] 2008/07/21 14:33:05,600
Change phase to ESTABLISH
Lower-Layer-Up event for LCP
Initializing LCP restart timer to 3000 milliseconds
Waiting up to 200ms for connection
Starting LCP restart timer with 200 milliseconds

[PPP] 2008/07/21 14:33:05,800
Positive Restart-Timeout event for LCP
Stop waiting for connection
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer W2
Inserting local MRU 1504
Inserting local magic number 2cb6690e
Inserting local MRRU 1504
Inserting local endpoint discriminator = 03 00 a0 57 12 39 ba
Inserting local Link-Discriminator 0016
Inserting local option protocol field compression
Inserting local option address- and controlfield compression
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 00 and length 38 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds

[PPP] 2008/07/21 14:33:06,020

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 00 and size 53
Peer MRU 1492 accepted
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 1af633a2 accepted
Peer requests callback via callback control protocol, accepted
Peer MRRU 1614 accepted
Peer endpoint discriminator = 01 f9 0d 16 d4 94 5e 42 29 82 02 a6 2c fd ac 3e 10 00 00 00 00 accepted
Peer Link-Discriminator 0019 accepted
Negative Configure-Request-Received event for LCP
Sending LCP configure-nak with ID 00 and length 9 to peer W2 (channel 9)

[PPP] 2008/07/21 14:33:06,020

Received LCP frame from peer W2 (channel 9)
Evaluate configure-reject with ID 00 and size 8
Peer does not support protocol field compression - accepted
Peer does not support address- and controlfield compression - accepted
Configure-Nak/Rej-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer W2
Inserting local MRU 1504
Inserting local magic number 2cb6690e
Inserting local MRRU 1504
Inserting local endpoint discriminator = 03 00 a0 57 12 39 ba
Inserting local Link-Discriminator 0016
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 02 and length 34 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds

[PPP] 2008/07/21 14:33:06,080

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 01 and size 53
Peer MRU 1492 accepted
Peer requests authentication protocol CHAP with DES encryption, accepted
Peer magic number 1af633a2 accepted
Peer requests callback via callback control protocol, accepted
Peer MRRU 1614 accepted
Peer endpoint discriminator = 01 f9 0d 16 d4 94 5e 42 29 82 02 a6 2c fd ac 3e 10 00 00 00 00 accepted
Peer Link-Discriminator 0019 accepted
Positive Configure-Request-Received event for LCP
Sending LCP configure-ack with ID 01 and length 53 to peer W2 (channel 9)

[PPP] 2008/07/21 14:33:06,100

Received LCP frame from peer W2 (channel 9)
Evaluate configure-ack with ID 02 and size 34
Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
This-Layer-Up action for LCP
Change phase to AUTHENTICATE
Stopping LCP restart timer

[PPP] 2008/07/21 14:33:06,290

Received CHAP frame from peer W2 (channel 9)
Got CHAP-Challenge from peer W2
Challenge = d0 3c ec 56 31 4a 2a 92
Found peer-id W2 in PPP table
Sending CHAP-response to peer W2 (channel 9), length = 49

[PPP] 2008/07/21 14:33:06,800

Received CHAP frame from peer W2 (channel 9)
Got CHAP-Success from peer W2
This-Layer-Up action for LCP
Change phase to CALLBACK

[PPP] 2008/07/21 14:33:06,870

Received CBCP frame from peer W2 (channel 9)
Evaluate CBCP-request with ID 01 and size 6
Peer offers callback via no callback
Sending CBCP-response to peer W2 (channel 9): ok, don't call me back

[PPP] 2008/07/21 14:33:06,900

Received CBCP frame from peer W2 (channel 9)
Evaluate CBCP-ack with ID 01 and size 6

This-Layer-Up action for LCP
Change phase to NETWORK
Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer W2
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
Sending IPCP configure-request with ID 00 and length 34 to peer W2 (channel 9)
Starting IPCP restart timer with 3000 milliseconds
Lower-Layer-Up event for CCP
Initializing CCP restart timer to 3000 milliseconds
Generating CCP configure-request for peer W2
Inserting STAC with 1 historie(s) and checkmode Sequence Number
Sending CCP configure-request with ID 00 and length 9 to peer W2 (channel 9)
Starting CCP restart timer with 3000 milliseconds
Lower-Layer-Up event for BACP
Initializing BACP restart timer to 3000 milliseconds
Generating BACP configure-request for peer W2
Inserting favored peer 00000001
Sending BACP configure-request with ID 00 and length 10 to peer W2 (channel 9)
Starting BACP restart timer with 3000 milliseconds
This-Layer-Up action for LCP

[PPP] 2008/07/21 14:33:07,140

Received CCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 03 and size 10
Rejecting unknown Option 12 06 00 00 00 41
Negative Configure-Request-Received event for CCP
Sending CCP configure-reject with ID 03 and length 10 to peer W2 (channel 9)

[PPP] 2008/07/21 14:33:07,150

Received BACP frame from peer W2 (channel 9)
Evaluate configure-request with ID 04 and size 10
Peer requests favored peer option 0000294f accepted
Positive Configure-Request-Received event for BACP
Sending BACP configure-ack with ID 04 and length 10 to peer W2 (channel 9)

[PPP] 2008/07/21 14:33:07,150

Received IPCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 05 and size 16
Peer requests IP header-compression, rejected
Peer requests IP address 192.168.2.1, accepted
Negative Configure-Request-Received event for IPCP
Sending IPCP configure-reject with ID 05 and length 10 to peer W2 (channel 9)

[PPP] 2008/07/21 14:33:07,180

Received LCP frame from peer W2 (channel 9)
Terminate-Request-Received event for LCP

[PPP] 2008/07/21 14:33:07,180
This-Layer-Down action for LCP
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
Stopping IPCP restart timer
Lower-Layer-Down event for IPXCP
Stopping IPXCP restart timer
Resetting LCP restart timer with 3000 milliseconds
Change phase to TERMINATE
Sending LCP terminate-request with ID 04 and length 4 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds
Sending LCP terminate-ack with ID 80 and length 4 to peer W2 (channel 9)

[PPP] 2008/07/21 14:33:07,180
Change phase to DEAD
Stopping LCP restart timer
Stopping IPXCP restart timer
Stopping IPCP restart timer
Stopping CCP restart timer
Stopping BACP restart timer

[TraceStopped] 2008/07/21 14:33:56,000
Used config:
trace + PPP

backslash · Beitrag von **backslash** » 22 Jul 2008, 15:11

Hi KK,

das erste, was mir auffällt, ist der Punkt, daß du im LANCOM die Kompression eingeschaltet hast. Das wird nicht funktionieren, weil das LANCOM hierfür die STAC-Kompression einsetzt, während Windows nur MPPC (Microsoft PPP Compression) versteht - ggf. liegt hier ein Grund...

Als zweites: Es gibt zwar einen IPCP Configure-Request, mit dem das Windows seine IP-Adresse mitteilt (und auch dei Headercompression), aber auf den Configure-Request des LANCOMs kommt keine Antwort. Hast du ggf. den Adreßpool nicht konfiguriert? Unter einkommende Verbindungen -> Eigenschaften -> Netzwerk -> Internetprotokoll (TCP/IP) -> Eigenschaften muß der Punkt TCP/IP-Adressen angeben angehakt und dann unter von und bis der zuzuweisende Pool eingetragen werden. Die Einstellung TCP/IP-Adressen automatisch durch DHCP zuweisen funktioniert nur, wenn auf dem Windows auch ein DHCP-Server läuft.

Hast beim Anlegen der einkommenden Verbindungen auf VPN zulassen geklickt? Wenn ja, dann will Windows vermutlich verschlüsseln und bricht ab, sobald die Kompression abgelehnt wird... (das kannst du auch nachträglich unter einkommende Verbindungen -> Eigenschaften -> Allgemein -> Virtuelles privates Netzwerk wieder abschalten).

Wenn alles nicht hilft, dann mußt du unter Windows das PPP-Logging einschalten: http://www.modemsite.com/56K/ppp.asp.

Gruß
Backslash

KK · Beitrag von KK » 23 Jul 2008, 14:27

Hi Backslash,

Danke für die ausführliche Anleitung. Komprimierung im Layer des Lancom habe ich abgestellt, leider ohne eine Auswirkung.

Der Adresspool auf dem RAS Server war bereits korrekt eingerichtet, DHCP nicht aktiviert. Den Adresspool habe ich testweise geändert, jedoch ebenfalls ohne Auswirkung auf das Problem.

VPN war bereits deaktiviert.

Auch wenn ich hier lästig falle, ich habe nachfolgend den PPP Trace vom Lancom und WIN XP RAS Server eingefügt. Die beiden Traces sind gerade eben simultan aufgenommen, stammen also von der gleichen Verbindung. Leider langt's bei mir nicht für eine Analyse...

Verzweifelter Gruß von KK

---------------

[TraceStarted] 2008/07/23 14:01:54,000
Used config:
trace + PPP
[Bootlog] 2008/07/23 14:01:54,000
Boot log (189 Bytes):

****

01/01/1900 00:00:02 System boot after power on

DEVICE: LANCOM 1823 VoIP (Annex B)
HW-RELEASE: B
VERSION: 7.52.0058 / 24.04.2008 / 6.26/e74.02.41.2

[Sysinfo] 2008/07/23 14:01:54,000

DEVICE: LANCOM 1823 VoIP (Annex B)
HW-RELEASE: B
SERIAL-NUMBER: 067091800017
MAC-ADDRESS: 00a0571239ba
IP-ADDRESS: 192.168.1.1
IP-NETMASK: 255.255.255.0
INTRANET-ADDRESS: 0.0.0.0
INTRANETMASK: 0.0.0.0
VERSION: 7.52.0058 / 24.04.2008 / 6.26/e74.02.41.2
NAME:
CONFIG-STATUS: 1056;0
FIRMWARE-STATUS: 1;1.5;1.1;7.27.22112007.4;7.52.24042008.5
LANCAPI-PORT: 75
HW-MASK: 00001100000000000000000001100011
FEATUREWORD: 01000000001000000100000100011101
REGISTERED-WORD: 01000000001000000100000100011101
FEATURE-LIST: 00/F/00000000
FEATURE-LIST: 02/F/00000000
FEATURE-LIST: 03/F/00000000
FEATURE-LIST: 04/F/00000000
FEATURE-LIST: 08/F/00000000
FEATURE-LIST: 0e/F/00000000
FEATURE-LIST: 15/F/00000000
FEATURE-LIST: 1e/F/00000000
TIME: 14005523072008
HTTP-PORT: 80
HTTPS-PORT: 443
[PPP] 2008/07/23 14:01:01,500
Change phase to ESTABLISH
Lower-Layer-Up event for LCP
Initializing LCP restart timer to 3000 milliseconds
Waiting up to 200ms for connection
Starting LCP restart timer with 200 milliseconds

[PPP] 2008/07/23 14:01:01,700
Positive Restart-Timeout event for LCP
Stop waiting for connection
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer W2
Inserting local MRU 1504
Inserting local magic number c6b7dbfa
Inserting local option protocol field compression
Inserting local option address- and controlfield compression
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 00 and length 21 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds

[PPP] 2008/07/23 14:01:01,820

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 00 and size 53
Peer MRU 1492 accepted
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 200a5919 accepted
Peer requests callback via callback control protocol, accepted
Peer Link-Discriminator 0025 rejected
Negative Configure-Request-Received event for LCP
Sending LCP configure-reject with ID 00 and length 35 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:01,820

Received LCP frame from peer W2 (channel 9)
Evaluate configure-reject with ID 00 and size 8
Peer does not support protocol field compression - accepted
Peer does not support address- and controlfield compression - accepted
Configure-Nak/Rej-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer W2
Inserting local MRU 1504
Inserting local magic number c6b7dbfa
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 02 and length 17 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds

[PPP] 2008/07/23 14:01:01,860

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 01 and size 22
Peer MRU 1492 accepted
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 200a5919 accepted
Peer requests callback via callback control protocol, accepted
Negative Configure-Request-Received event for LCP
Sending LCP configure-nak with ID 01 and length 9 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:01,860

Received LCP frame from peer W2 (channel 9)
Evaluate configure-ack with ID 02 and size 17
Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds

[PPP] 2008/07/23 14:01:01,950

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 02 and size 22
Peer MRU 1492 accepted
Peer requests authentication protocol CHAP with DES encryption, accepted
Peer magic number 200a5919 accepted
Peer requests callback via callback control protocol, accepted
Positive Configure-Request-Received event for LCP
Sending LCP configure-ack with ID 02 and length 22 to peer W2 (channel 9)
Stopping LCP restart timer
This-Layer-Up action for LCP
Change phase to AUTHENTICATE

[PPP] 2008/07/23 14:01:01,990

Received CHAP frame from peer W2 (channel 9)
Got CHAP-Challenge from peer W2
Challenge = 91 ac bb 3b cc 65 df f2
Found peer-id W2 in PPP table
Sending CHAP-response to peer W2 (channel 9), length = 49

[PPP] 2008/07/23 14:01:02,080

Received CHAP frame from peer W2 (channel 9)
Got CHAP-Success from peer W2
This-Layer-Up action for LCP
Change phase to CALLBACK

[PPP] 2008/07/23 14:01:02,080

Received CBCP frame from peer W2 (channel 9)
Evaluate CBCP-request with ID 01 and size 6
Peer offers callback via no callback
Sending CBCP-response to peer W2 (channel 9): ok, don't call me back

[PPP] 2008/07/23 14:01:02,110

Received CBCP frame from peer W2 (channel 9)
Evaluate CBCP-ack with ID 01 and size 6

This-Layer-Up action for LCP
Change phase to NETWORK
Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer W2
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
Sending IPCP configure-request with ID 00 and length 34 to peer W2 (channel 9)
Starting IPCP restart timer with 3000 milliseconds
This-Layer-Up action for LCP

[PPP] 2008/07/23 14:01:02,290

Received CCP frame from peer W2 (channel 9)
Sending LCP protocol-reject for protocol 80fd with ID 05 and length 16 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:02,290

Received IPCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 05 and size 16
Peer requests IP header-compression, rejected
Peer requests IP address 192.168.2.123, accepted
Negative Configure-Request-Received event for IPCP
Sending IPCP configure-reject with ID 05 and length 10 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:02,300

Received IPCP frame from peer W2 (channel 9)
Evaluate configure-reject with ID 00 and size 16
Peer rejects primary NBNS address 0.0.0.0, discard local option
Peer rejects secondary NBNS address 0.0.0.0, discard local option
Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer W2
Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0
Sending IPCP configure-request with ID 02 and length 22 to peer W2 (channel 9)
Starting IPCP restart timer with 3000 milliseconds

[PPP] 2008/07/23 14:01:02,340

Received LCP frame from peer W2 (channel 9)
Terminate-Request-Received event for LCP

[PPP] 2008/07/23 14:01:02,350
This-Layer-Down action for LCP
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
Stopping IPCP restart timer
Lower-Layer-Down event for IPXCP
Stopping IPXCP restart timer
Resetting LCP restart timer with 3000 milliseconds
Change phase to TERMINATE
Sending LCP terminate-request with ID 06 and length 4 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds
Sending LCP terminate-ack with ID 06 and length 4 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:02,350
Change phase to DEAD
Stopping LCP restart timer
Stopping IPXCP restart timer
Stopping IPCP restart timer
Stopping CCP restart timer
Stopping BACP restart timer

[PPP] 2008/07/23 14:01:06,810
Change phase to ESTABLISH
Lower-Layer-Up event for LCP
Initializing LCP restart timer to 3000 milliseconds
Waiting up to 200ms for connection
Starting LCP restart timer with 200 milliseconds

[PPP] 2008/07/23 14:01:07,010
Positive Restart-Timeout event for LCP
Stop waiting for connection
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer W2
Inserting local MRU 1504
Inserting local magic number bb5d59b2
Inserting local option protocol field compression
Inserting local option address- and controlfield compression
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 00 and length 21 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds

[PPP] 2008/07/23 14:01:07,050

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 00 and size 53
Peer MRU 1492 accepted
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 629d4dd6 accepted
Peer requests callback via callback control protocol, accepted
Peer Link-Discriminator 0026 rejected
Negative Configure-Request-Received event for LCP
Sending LCP configure-reject with ID 00 and length 35 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:07,050

Received LCP frame from peer W2 (channel 9)
Evaluate configure-reject with ID 00 and size 8
Peer does not support protocol field compression - accepted
Peer does not support address- and controlfield compression - accepted
Configure-Nak/Rej-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer W2
Inserting local MRU 1504
Inserting local magic number bb5d59b2
Inserting local callback via callback control protocol
Sending LCP configure-request with ID 02 and length 17 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds

[PPP] 2008/07/23 14:01:07,090

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 01 and size 22
Peer MRU 1492 accepted
Peer requests authentication protocol c223, NAK with CHAP MD5
Peer magic number 629d4dd6 accepted
Peer requests callback via callback control protocol, accepted
Negative Configure-Request-Received event for LCP
Sending LCP configure-nak with ID 01 and length 9 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:07,090

Received LCP frame from peer W2 (channel 9)
Evaluate configure-ack with ID 02 and size 17
Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds

[PPP] 2008/07/23 14:01:07,130

Received LCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 02 and size 22
Peer MRU 1492 accepted
Peer requests authentication protocol CHAP with DES encryption, accepted
Peer magic number 629d4dd6 accepted
Peer requests callback via callback control protocol, accepted
Positive Configure-Request-Received event for LCP
Sending LCP configure-ack with ID 02 and length 22 to peer W2 (channel 9)
Stopping LCP restart timer
This-Layer-Up action for LCP
Change phase to AUTHENTICATE

[PPP] 2008/07/23 14:01:07,160

Received CHAP frame from peer W2 (channel 9)
Got CHAP-Challenge from peer W2
Challenge = f3 7c 7f 0e d4 53 56 0c
Found peer-id W2 in PPP table
Sending CHAP-response to peer W2 (channel 9), length = 49

[PPP] 2008/07/23 14:01:07,210

Received CHAP frame from peer W2 (channel 9)
Got CHAP-Success from peer W2
This-Layer-Up action for LCP
Change phase to CALLBACK

[PPP] 2008/07/23 14:01:07,210

Received CBCP frame from peer W2 (channel 9)
Evaluate CBCP-request with ID 01 and size 6
Peer offers callback via no callback
Sending CBCP-response to peer W2 (channel 9): ok, don't call me back

[PPP] 2008/07/23 14:01:07,240

Received CBCP frame from peer W2 (channel 9)
Evaluate CBCP-ack with ID 01 and size 6

This-Layer-Up action for LCP
Change phase to NETWORK
Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer W2
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
Sending IPCP configure-request with ID 00 and length 34 to peer W2 (channel 9)
Starting IPCP restart timer with 3000 milliseconds
This-Layer-Up action for LCP

[PPP] 2008/07/23 14:01:07,250

Received CCP frame from peer W2 (channel 9)
Sending LCP protocol-reject for protocol 80fd with ID 05 and length 16 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:07,250

Received IPCP frame from peer W2 (channel 9)
Evaluate configure-request with ID 05 and size 16
Peer requests IP header-compression, rejected
Peer requests IP address 192.168.2.123, accepted
Negative Configure-Request-Received event for IPCP
Sending IPCP configure-reject with ID 05 and length 10 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:07,280

Received IPCP frame from peer W2 (channel 9)
Evaluate configure-reject with ID 00 and size 16
Peer rejects primary NBNS address 0.0.0.0, discard local option
Peer rejects secondary NBNS address 0.0.0.0, discard local option
Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer W2
Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0
Sending IPCP configure-request with ID 02 and length 22 to peer W2 (channel 9)
Starting IPCP restart timer with 3000 milliseconds

[PPP] 2008/07/23 14:01:07,290

Received LCP frame from peer W2 (channel 9)
Terminate-Request-Received event for LCP

[PPP] 2008/07/23 14:01:07,290
This-Layer-Down action for LCP
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
Stopping IPCP restart timer
Lower-Layer-Down event for IPXCP
Stopping IPXCP restart timer
Resetting LCP restart timer with 3000 milliseconds
Change phase to TERMINATE
Sending LCP terminate-request with ID 06 and length 4 to peer W2 (channel 9)
Starting LCP restart timer with 3000 milliseconds
Sending LCP terminate-ack with ID 05 and length 4 to peer W2 (channel 9)

[PPP] 2008/07/23 14:01:07,290
Change phase to DEAD
Stopping LCP restart timer
Stopping IPXCP restart timer
Stopping IPCP restart timer
Stopping CCP restart timer
Stopping BACP restart timer

[TraceStopped] 2008/07/23 14:02:10,000
Used config:
trace + PPP

---------------

[1268] 14:02:02:803: Line up event occurred on port 3
[1268] 14:02:02:803: PortName: ISDN15-1
[1268] 14:02:02:897: Starting PPP on link with IfType=0x0,IPIf=0x0,IPXIf=0x0
[1268] 14:02:02:897: RasGetBuffer returned 979a758 for SendBuf
[1268] 14:02:02:897: FsmInit called for protocol = c021, port = 3
[1268] 14:02:02:897: ConfigInfo = 80270a
[1268] 14:02:02:897: APs available = 6
[1268] 14:02:02:897: FsmReset called for protocol = c021, port = 3
[1268] 14:02:02:897: Inserting port in bucket # 3
[1268] 14:02:02:897: Inserting bundle in bucket # 4
[1268] 14:02:02:897: FsmOpen event received for protocol c021 on port 3
[1268] 14:02:02:897: FsmThisLayerStarted called for protocol = c021, port = 3
[1268] 14:02:02:897: FsmUp event received for protocol c021 on port 3
[1268] 14:02:02:897: <PPP packet sent at 07/23/2008 12:02:02:897
[1268] 14:02:02:897: <Protocol = LCP, Type = Configure-Req, Length = 0x37, Id = 0x0, Port = 3
[1268] 14:02:02:897: <C0 21 01 00 00 35 01 04 05 D4 03 05 C2 23 81 05 |.!...5.......#..|
[1268] 14:02:02:897: <06 20 0A 59 19 0D 03 06 11 04 06 4E 13 17 01 F9 |. .Y.......N....|
[1268] 14:02:02:897: <0D 16 D4 94 5E 42 29 82 02 A6 2C FD AC 3E 10 00 |....^B)...,..>..|
[1268] 14:02:02:897: <00 00 00 17 04 00 25 00 00 00 00 00 00 00 00 00 |......%.........|
[1268] 14:02:02:897:
[1268] 14:02:02:897: InsertInTimerQ called portid=162,Id=0,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:02:897: InsertInTimerQ called portid=162,Id=0,Protocol=0,EventType=3,fAuth=0
[1268] 14:02:02:897: >PPP packet received at 07/23/2008 12:02:02:897
[1268] 14:02:02:897: >Protocol = LCP, Type = Configure-Req, Length = 0x17, Id = 0x0, Port = 3
[1268] 14:02:02:897: >C0 21 01 00 00 15 01 04 05 E0 05 06 C6 B7 DB FA |.!..............|
[1268] 14:02:02:897: >07 02 08 02 0D 03 06 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:02:897:
[1268] 14:02:02:897: <PPP packet sent at 07/23/2008 12:02:02:897
[1268] 14:02:02:897: <Protocol = LCP, Type = Configure-Reject, Length = 0xa, Id = 0x0, Port = 3
[1268] 14:02:02:897: <C0 21 04 00 00 08 07 02 08 02 00 00 00 00 00 00 |.!..............|
[1268] 14:02:02:897:
[376] 14:02:02:928: Packet received (37 bytes) for hPort 3
[1268] 14:02:02:928: >PPP packet received at 07/23/2008 12:02:02:928
[1268] 14:02:02:928: >Protocol = LCP, Type = Configure-Reject, Length = 0x25, Id = 0x0, Port = 3
[1268] 14:02:02:928: >C0 21 04 00 00 23 11 04 06 4E 13 17 01 F9 0D 16 |.!...#...N......|
[1268] 14:02:02:928: >D4 94 5E 42 29 82 02 A6 2C FD AC 3E 10 00 00 00 |..^B)...,..>....|
[1268] 14:02:02:928: >00 17 04 00 25 00 00 00 00 00 00 00 00 00 00 00 |....%...........|
[1268] 14:02:02:928:
[1268] 14:02:02:928: RemoveFromTimerQ called portid=162,Id=0,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:02:928: <PPP packet sent at 07/23/2008 12:02:02:928
[1268] 14:02:02:928: <Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x1, Port = 3
[1268] 14:02:02:928: <C0 21 01 01 00 16 01 04 05 D4 03 05 C2 23 81 05 |.!...........#..|
[1268] 14:02:02:928: <06 20 0A 59 19 0D 03 06 00 00 00 00 00 00 00 00 |. .Y............|
[1268] 14:02:02:928:
[1268] 14:02:02:928: InsertInTimerQ called portid=162,Id=1,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:02:928: Packet received (19 bytes) for hPort 3
[1268] 14:02:02:928: >PPP packet received at 07/23/2008 12:02:02:928
[1268] 14:02:02:928: >Protocol = LCP, Type = Configure-Req, Length = 0x13, Id = 0x2, Port = 3
[1268] 14:02:02:928: >C0 21 01 02 00 11 01 04 05 E0 05 06 C6 B7 DB FA |.!..............|
[1268] 14:02:02:928: >0D 03 06 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:02:928:
[1268] 14:02:02:928: <PPP packet sent at 07/23/2008 12:02:02:928
[1268] 14:02:02:928: <Protocol = LCP, Type = Configure-Ack, Length = 0x13, Id = 0x2, Port = 3
[1268] 14:02:02:928: <C0 21 02 02 00 11 01 04 05 E0 05 06 C6 B7 DB FA |.!..............|
[1268] 14:02:02:928: <0D 03 06 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:02:928:
[376] 14:02:02:975: Packet received (11 bytes) for hPort 3
[1268] 14:02:03:038: >PPP packet received at 07/23/2008 12:02:03:038
[1268] 14:02:03:038: >Protocol = LCP, Type = Configure-Nak, Length = 0xb, Id = 0x1, Port = 3
[1268] 14:02:03:038: >C0 21 03 01 00 09 03 05 C2 23 05 00 00 00 00 00 |.!.......#......|
[1268] 14:02:03:038:
[1268] 14:02:03:038: RemoveFromTimerQ called portid=162,Id=1,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:03:038: <PPP packet sent at 07/23/2008 12:02:03:038
[1268] 14:02:03:038: <Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:03:038: <C0 21 01 02 00 16 01 04 05 D4 03 05 C2 23 80 05 |.!...........#..|
[1268] 14:02:03:038: <06 20 0A 59 19 0D 03 06 00 00 00 00 00 00 00 00 |. .Y............|
[1268] 14:02:03:038:
[1268] 14:02:03:038: InsertInTimerQ called portid=162,Id=2,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:03:069: Packet received (24 bytes) for hPort 3
[1268] 14:02:03:069: >PPP packet received at 07/23/2008 12:02:03:069
[1268] 14:02:03:069: >Protocol = LCP, Type = Configure-Ack, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:03:069: >C0 21 02 02 00 16 01 04 05 D4 03 05 C2 23 80 05 |.!...........#..|
[1268] 14:02:03:069: >06 20 0A 59 19 0D 03 06 00 00 00 00 00 00 00 00 |. .Y............|
[1268] 14:02:03:069:
[1268] 14:02:03:069: RemoveFromTimerQ called portid=162,Id=2,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:03:069: FsmThisLayerUp called for protocol = c021, port = 3
[1268] 14:02:03:069: LCP Local Options-------------
[1268] 14:02:03:069: MRU=1492,ACCM=-1,Auth=c223,MagicNumber=537549081,PFC=OFF,ACFC=OFF
[1268] 14:02:03:069: Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=25,BAP=OFF
[1268] 14:02:03:069: LCP Remote Options-------------
[1268] 14:02:03:069: MRU=1504,ACCM=-1,Auth=0,MagicNumber=-961029126,PFC=OFF,ACFC=OFF
[1268] 14:02:03:069: Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[1268] 14:02:03:069: LCP Configured successfully
[1268] 14:02:03:069: Authenticating phase started
[1268] 14:02:03:069: Calling APWork in APStart
[1268] 14:02:03:069: <PPP packet sent at 07/23/2008 12:02:03:069
[1268] 14:02:03:069: <Protocol = CHAP, Type = Protocol specific, Length = 0x18, Id = 0x0, Port = 3
[1268] 14:02:03:069: <C2 23 01 00 00 16 08 91 AC BB 3B CC 65 DF F2 4E |.#........;.e..N|
[1268] 14:02:03:069: <4F 54 45 42 4F 4F 4B 31 00 00 00 00 00 00 00 00 |OTEBOOK1........|
[1268] 14:02:03:069:
[1268] 14:02:03:069: InsertInTimerQ called portid=162,Id=0,Protocol=c223,EventType=0,fAuth=1
[376] 14:02:03:116: Packet received (64 bytes) for hPort 3
[1268] 14:02:03:116: >PPP packet received at 07/23/2008 12:02:03:116
[1268] 14:02:03:116: >Protocol = CHAP, Type = Protocol specific, Length = 0x40, Id = 0x0, Port = 3
[1268] 14:02:03:116: >C2 23 02 00 00 3E 31 DE B4 F4 FE 1F 4E A0 09 34 |.#...>1.....N..4|
[1268] 14:02:03:116: >D8 25 0E D8 11 01 B0 60 01 F7 F2 73 A9 59 9E 95 |.%.....`...s.Y..|
[1268] 14:02:03:116: >08 EE 5A 20 C2 BF 44 47 20 C5 62 D2 49 B0 60 29 |..Z ..DG .b.I.`)|
[1268] 14:02:03:116: >8E BD 21 23 EE 65 13 01 48 61 74 7A 66 65 6C 64 |..!#.e..Hatzfeld|
[1268] 14:02:03:116:
[1268] 14:02:03:116: RemoveFromTimerQ called portid=162,Id=0,Protocol=c223,EventType=0,fAuth=1
[1268] 14:02:03:163: <PPP packet sent at 07/23/2008 12:02:03:163
[1268] 14:02:03:163: <Protocol = CHAP, Type = Protocol specific, Length = 0x6, Id = 0x0, Port = 3
[1268] 14:02:03:163: <C2 23 03 00 00 04 00 00 00 00 00 00 00 00 00 00 |.#..............|
[1268] 14:02:03:163:
[1268] 14:02:03:163: Encryption
[1268] 14:02:03:163: Strong encryption
[1268] 14:02:03:163: MS-CHAP-MPPE-Keys set
[1268] 14:02:03:163: Auth Attribute Domain = NOTEBOOK1
[1268] 14:02:03:163: Auth Attribute Idle Timeout Seconds = 0
[1268] 14:02:03:163: AuthAttribute MaxChannelsAllowed = -1
[1268] 14:02:03:163: FsmThisLayerUp called for protocol = c223, port = 3
[1268] 14:02:03:163: NotifyCaller(hPort=3, dwMsgId=17)
[1268] 14:02:03:163: Callback phase started
[1268] 14:02:03:163: CallbackPriv in CB = 1
[1268] 14:02:03:163: <PPP packet sent at 07/23/2008 12:02:03:163
[1268] 14:02:03:163: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:03:163: <C0 29 01 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:03:163:
[1268] 14:02:03:163: InsertInTimerQ called portid=162,Id=1,Protocol=c029,EventType=0,fAuth=0
[376] 14:02:03:194: Packet received (8 bytes) for hPort 3
[1268] 14:02:03:194: >PPP packet received at 07/23/2008 12:02:03:194
[1268] 14:02:03:194: >Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:03:194: >C0 29 02 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:03:194:
[1268] 14:02:03:194: RemoveFromTimerQ called portid=162,Id=1,Protocol=c029,EventType=0,fAuth=0
[1268] 14:02:03:194: <PPP packet sent at 07/23/2008 12:02:03:194
[1268] 14:02:03:194: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:03:194: <C0 29 03 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:03:194:
[1268] 14:02:03:194: FsmThisLayerUp called for protocol = c029, port = 3
[1268] 14:02:03:194: NotifyCaller(hPort=3, dwMsgId=20)
[748] 14:02:03:194: PPPEMSG_DdmInterfaceInfo recvd,IPXif=0,IPif=2e,Type=0

[1268] 14:02:03:225: Will not initialize CP 8029
[1268] 14:02:03:225: Will not initialize CP 802b
[1268] 14:02:03:225: FsmInit called for protocol = 80fd, port = 3
[1268] 14:02:03:225: FsmReset called for protocol = 80fd, port = 3
[1268] 14:02:03:225: FsmInit called for protocol = 8021, port = 3
[376] 14:02:03:225: Packet received (36 bytes) for hPort 3
[1268] 14:02:03:366: FsmReset called for protocol = 8021, port = 3
[1268] 14:02:03:366: FsmOpen event received for protocol 80fd on port 3
[1268] 14:02:03:366: FsmThisLayerStarted called for protocol = 80fd, port = 3
[1268] 14:02:03:366: FsmUp event received for protocol 80fd on port 3
[1268] 14:02:03:366: <PPP packet sent at 07/23/2008 12:02:03:366
[1268] 14:02:03:366: <Protocol = CCP, Type = Configure-Req, Length = 0xc, Id = 0x4, Port = 3
[1268] 14:02:03:366: <80 FD 01 04 00 0A 12 06 00 00 00 41 00 00 00 00 |...........A....|
[1268] 14:02:03:366:
[1268] 14:02:03:366: InsertInTimerQ called portid=162,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:03:366: FsmOpen event received for protocol 8021 on port 3
[1268] 14:02:03:366: FsmThisLayerStarted called for protocol = 8021, port = 3
[1268] 14:02:03:366: FsmUp event received for protocol 8021 on port 3
[1268] 14:02:03:366: <PPP packet sent at 07/23/2008 12:02:03:366
[1268] 14:02:03:366: <Protocol = IPCP, Type = Configure-Req, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:03:366: <80 21 01 05 00 10 02 06 00 2D 0F 01 03 06 C0 A8 |.!.......-......|
[1268] 14:02:03:366: <02 7B 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.{..............|
[1268] 14:02:03:366:
[1268] 14:02:03:366: InsertInTimerQ called portid=162,Id=5,Protocol=8021,EventType=0,fAuth=0
[1268] 14:02:03:366: >PPP packet received at 07/23/2008 12:02:03:366
[1268] 14:02:03:366: >Protocol = IPCP, Type = Configure-Req, Length = 0x24, Id = 0x0, Port = 3
[1268] 14:02:03:366: >80 21 01 00 00 22 03 06 00 00 00 00 81 06 00 00 |.!..."..........|
[1268] 14:02:03:366: >00 00 83 06 00 00 00 00 82 06 00 00 00 00 84 06 |................|
[1268] 14:02:03:366: >00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:03:366:
[1268] 14:02:03:366: <PPP packet sent at 07/23/2008 12:02:03:366
[1268] 14:02:03:366: <Protocol = IPCP, Type = Configure-Reject, Length = 0x12, Id = 0x0, Port = 3
[1268] 14:02:03:366: <80 21 04 00 00 10 82 06 00 00 00 00 84 06 00 00 |.!..............|
[1268] 14:02:03:366: <00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:03:366:
[376] 14:02:03:397: Packet received (18 bytes) for hPort 3
[1268] 14:02:03:397: >PPP packet received at 07/23/2008 12:02:03:397
[1268] 14:02:03:397: >Protocol = LCP, Type = Protocol-Reject, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:03:397: >C0 21 08 05 00 10 80 FD 01 04 00 0A 12 06 00 00 |.!..............|
[1268] 14:02:03:397: >00 41 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.A..............|
[1268] 14:02:03:397:
[1268] 14:02:03:397: PPP Protocol Reject, Protocol = 80fd
[1268] 14:02:03:397: RemoveFromTimerQ called portid=162,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:03:397: FsmThisLayerFinished called for protocol = 80fd, port = 3
[1268] 14:02:03:397: NotifyCaller(hPort=3, dwMsgId=14)
[376] 14:02:03:413: Packet received (12 bytes) for hPort 3
[1268] 14:02:03:413: >PPP packet received at 07/23/2008 12:02:03:413
[1268] 14:02:03:413: >Protocol = IPCP, Type = Configure-Reject, Length = 0xc, Id = 0x5, Port = 3
[1268] 14:02:03:413: >80 21 04 05 00 0A 02 06 00 2D 0F 01 00 00 00 00 |.!.......-......|
[1268] 14:02:03:413:
[1268] 14:02:03:413: Non-LCP packet received when LCP is not opened
[1268] 14:02:03:413: Packet being silently discarded
[376] 14:02:03:413: Packet received (24 bytes) for hPort 3
[1268] 14:02:03:413: >PPP packet received at 07/23/2008 12:02:03:413
[1268] 14:02:03:413: >Protocol = IPCP, Type = Configure-Req, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:03:413: >80 21 01 02 00 16 03 06 00 00 00 00 81 06 00 00 |.!..............|
[1268] 14:02:03:413: >00 00 83 06 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:03:413:
[1268] 14:02:03:413: Non-LCP packet received when LCP is not opened
[1268] 14:02:03:413: Packet being silently discarded
[748] 14:02:03:428: PppDdmStop

[748] 14:02:03:428: PPPEMSG_Stop recvd

[1268] 14:02:03:428: FsmClose event received for protocol c021 on port 3
[1268] 14:02:03:428: RemoveFromTimerQ called portid=162,Id=2,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:03:428: FsmThisLayerDown called for protocol = c021, port = 3
[1268] 14:02:03:428: FsmDown event received for protocol 80fd on port 3
[1268] 14:02:03:428: RemoveFromTimerQ called portid=162,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:03:428: FsmReset called for protocol = 80fd, port = 3
[1268] 14:02:03:428: FsmDown event received for protocol 8021 on port 3
[1268] 14:02:03:428: RemoveFromTimerQ called portid=162,Id=5,Protocol=8021,EventType=0,fAuth=0
[1268] 14:02:03:428: FsmReset called for protocol = 8021, port = 3
[1268] 14:02:03:428: <PPP packet sent at 07/23/2008 12:02:03:428
[1268] 14:02:03:428: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0x6, Port = 3
[1268] 14:02:03:428: <C0 21 05 06 00 10 20 0A 59 19 00 3C CD 74 00 00 |.!.... .Y..<.t..|
[1268] 14:02:03:428: <02 E6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:03:428:
[1268] 14:02:03:428: InsertInTimerQ called portid=162,Id=6,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:03:459: Packet received (6 bytes) for hPort 3
[376] 14:02:03:459: Packet received (6 bytes) for hPort 3
[1268] 14:02:03:491: >PPP packet received at 07/23/2008 12:02:03:491
[1268] 14:02:03:491: >Protocol = LCP, Type = Terminate-Req, Length = 0x6, Id = 0x6, Port = 3
[1268] 14:02:03:491: >C0 21 05 06 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1268] 14:02:03:491:
[1268] 14:02:03:491: RemoveFromTimerQ called portid=162,Id=6,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:03:491: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=2,fAuth=0
[1268] 14:02:03:491: <PPP packet sent at 07/23/2008 12:02:03:491
[1268] 14:02:03:491: <Protocol = LCP, Type = Terminate-Ack, Length = 0x6, Id = 0x6, Port = 3
[1268] 14:02:03:491: <C0 21 06 06 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1268] 14:02:03:491:
[1268] 14:02:03:491: InsertInTimerQ called portid=162,Id=0,Protocol=0,EventType=2,fAuth=0
[1268] 14:02:03:491: >PPP packet received at 07/23/2008 12:02:03:491
[1268] 14:02:03:491: >Protocol = LCP, Type = Terminate-Ack, Length = 0x6, Id = 0x6, Port = 3
[1268] 14:02:03:491: >C0 21 06 06 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1268] 14:02:03:491:
[1268] 14:02:03:491: RemoveFromTimerQ called portid=162,Id=6,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:03:491: FsmThisLayerFinished called for protocol = c021, port = 3
[1268] 14:02:03:491: FsmThisLayerFinished called for protocol = 80fd, port = 3: 0
[1268] 14:02:03:491: FsmThisLayerFinished called for protocol = 8021, port = 3: 0
[1268] 14:02:03:491: NotifyCaller(hPort=3, dwMsgId=18)
[376] 14:02:03:584: PPPEMSG_LineDown recvd, hPort=3

[1268] 14:02:03:584: Line down event occurred on port 3
[1268] 14:02:03:584: FsmDown event received for protocol c021 on port 3
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=6,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:03:584: FsmReset called for protocol = c021, port = 3
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=3,fAuth=0
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=7,fAuth=0
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=2,fAuth=0
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=1,fAuth=0
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=4,fAuth=0
[1268] 14:02:03:584: RemoveFromTimerQ called portid=162,Id=0,Protocol=0,EventType=6,fAuth=0
[1268] 14:02:03:584: LcpEnd
[1464] 14:02:03:616: Stopping Accounting for port 3
[1268] 14:02:05:616: Post line down event occurred on port 3
[1268] 14:02:05:616: NotifyCaller(hPort=3, dwMsgId=23)
[1268] 14:02:08:116: Line up event occurred on port 3
[1268] 14:02:08:116: PortName: ISDN15-1
[1268] 14:02:08:116: Starting PPP on link with IfType=0x0,IPIf=0x0,IPXIf=0x0
[1268] 14:02:08:116: RasGetBuffer returned 979a758 for SendBuf
[1268] 14:02:08:116: FsmInit called for protocol = c021, port = 3
[1268] 14:02:08:116: ConfigInfo = 80270a
[1268] 14:02:08:116: APs available = 6
[1268] 14:02:08:116: FsmReset called for protocol = c021, port = 3
[1268] 14:02:08:116: Inserting port in bucket # 3
[1268] 14:02:08:116: Inserting bundle in bucket # 5
[1268] 14:02:08:116: FsmOpen event received for protocol c021 on port 3
[1268] 14:02:08:116: FsmThisLayerStarted called for protocol = c021, port = 3
[1268] 14:02:08:116: FsmUp event received for protocol c021 on port 3
[1268] 14:02:08:116: <PPP packet sent at 07/23/2008 12:02:08:116
[1268] 14:02:08:116: <Protocol = LCP, Type = Configure-Req, Length = 0x37, Id = 0x0, Port = 3
[1268] 14:02:08:116: <C0 21 01 00 00 35 01 04 05 D4 03 05 C2 23 81 05 |.!...5.......#..|
[1268] 14:02:08:116: <06 62 9D 4D D6 0D 03 06 11 04 06 4E 13 17 01 F9 |.b.M.......N....|
[1268] 14:02:08:116: <0D 16 D4 94 5E 42 29 82 02 A6 2C FD AC 3E 10 00 |....^B)...,..>..|
[1268] 14:02:08:116: <00 00 00 17 04 00 26 00 00 00 00 00 00 00 00 00 |......&.........|
[1268] 14:02:08:116:
[1268] 14:02:08:116: InsertInTimerQ called portid=164,Id=0,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:08:116: InsertInTimerQ called portid=164,Id=0,Protocol=0,EventType=3,fAuth=0
[1268] 14:02:08:116: >PPP packet received at 07/23/2008 12:02:08:116
[1268] 14:02:08:116: >Protocol = LCP, Type = Configure-Req, Length = 0x17, Id = 0x0, Port = 3
[1268] 14:02:08:116: >C0 21 01 00 00 15 01 04 05 E0 05 06 BB 5D 59 B2 |.!...........]Y.|
[1268] 14:02:08:116: >07 02 08 02 0D 03 06 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:116:
[1268] 14:02:08:116: <PPP packet sent at 07/23/2008 12:02:08:116
[1268] 14:02:08:116: <Protocol = LCP, Type = Configure-Reject, Length = 0xa, Id = 0x0, Port = 3
[1268] 14:02:08:116: <C0 21 04 00 00 08 07 02 08 02 00 00 00 00 00 00 |.!..............|
[1268] 14:02:08:116:
[376] 14:02:08:163: Packet received (37 bytes) for hPort 3
[1268] 14:02:08:163: >PPP packet received at 07/23/2008 12:02:08:163
[1268] 14:02:08:163: >Protocol = LCP, Type = Configure-Reject, Length = 0x25, Id = 0x0, Port = 3
[1268] 14:02:08:163: >C0 21 04 00 00 23 11 04 06 4E 13 17 01 F9 0D 16 |.!...#...N......|
[1268] 14:02:08:163: >D4 94 5E 42 29 82 02 A6 2C FD AC 3E 10 00 00 00 |..^B)...,..>....|
[1268] 14:02:08:163: >00 17 04 00 26 00 00 00 00 00 00 00 00 00 00 00 |....&...........|
[1268] 14:02:08:163:
[1268] 14:02:08:163: RemoveFromTimerQ called portid=164,Id=0,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:08:163: <PPP packet sent at 07/23/2008 12:02:08:163
[1268] 14:02:08:163: <Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x1, Port = 3
[1268] 14:02:08:163: <C0 21 01 01 00 16 01 04 05 D4 03 05 C2 23 81 05 |.!...........#..|
[1268] 14:02:08:163: <06 62 9D 4D D6 0D 03 06 00 00 00 00 00 00 00 00 |.b.M............|
[1268] 14:02:08:163:
[1268] 14:02:08:163: InsertInTimerQ called portid=164,Id=1,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:08:163: Packet received (19 bytes) for hPort 3
[1268] 14:02:08:163: >PPP packet received at 07/23/2008 12:02:08:163
[1268] 14:02:08:163: >Protocol = LCP, Type = Configure-Req, Length = 0x13, Id = 0x2, Port = 3
[1268] 14:02:08:163: >C0 21 01 02 00 11 01 04 05 E0 05 06 BB 5D 59 B2 |.!...........]Y.|
[1268] 14:02:08:163: >0D 03 06 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:163:
[1268] 14:02:08:163: <PPP packet sent at 07/23/2008 12:02:08:163
[1268] 14:02:08:163: <Protocol = LCP, Type = Configure-Ack, Length = 0x13, Id = 0x2, Port = 3
[1268] 14:02:08:163: <C0 21 02 02 00 11 01 04 05 E0 05 06 BB 5D 59 B2 |.!...........]Y.|
[1268] 14:02:08:163: <0D 03 06 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:163:
[376] 14:02:08:194: Packet received (11 bytes) for hPort 3
[1268] 14:02:08:194: >PPP packet received at 07/23/2008 12:02:08:194
[1268] 14:02:08:194: >Protocol = LCP, Type = Configure-Nak, Length = 0xb, Id = 0x1, Port = 3
[1268] 14:02:08:194: >C0 21 03 01 00 09 03 05 C2 23 05 00 00 00 00 00 |.!.......#......|
[1268] 14:02:08:194:
[1268] 14:02:08:194: RemoveFromTimerQ called portid=164,Id=1,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:08:194: <PPP packet sent at 07/23/2008 12:02:08:194
[1268] 14:02:08:194: <Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:08:194: <C0 21 01 02 00 16 01 04 05 D4 03 05 C2 23 80 05 |.!...........#..|
[1268] 14:02:08:194: <06 62 9D 4D D6 0D 03 06 00 00 00 00 00 00 00 00 |.b.M............|
[1268] 14:02:08:194:
[1268] 14:02:08:194: InsertInTimerQ called portid=164,Id=2,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:08:241: Packet received (24 bytes) for hPort 3
[1268] 14:02:08:241: >PPP packet received at 07/23/2008 12:02:08:241
[1268] 14:02:08:241: >Protocol = LCP, Type = Configure-Ack, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:08:241: >C0 21 02 02 00 16 01 04 05 D4 03 05 C2 23 80 05 |.!...........#..|
[1268] 14:02:08:241: >06 62 9D 4D D6 0D 03 06 00 00 00 00 00 00 00 00 |.b.M............|
[1268] 14:02:08:241:
[1268] 14:02:08:241: RemoveFromTimerQ called portid=164,Id=2,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:08:241: FsmThisLayerUp called for protocol = c021, port = 3
[1268] 14:02:08:241: LCP Local Options-------------
[1268] 14:02:08:241: MRU=1492,ACCM=-1,Auth=c223,MagicNumber=1654476246,PFC=OFF,ACFC=OFF
[1268] 14:02:08:241: Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=26,BAP=OFF
[1268] 14:02:08:241: LCP Remote Options-------------
[1268] 14:02:08:241: MRU=1504,ACCM=-1,Auth=0,MagicNumber=-1151510094,PFC=OFF,ACFC=OFF
[1268] 14:02:08:241: Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[1268] 14:02:08:241: LCP Configured successfully
[1268] 14:02:08:241: Authenticating phase started
[1268] 14:02:08:241: Calling APWork in APStart
[1268] 14:02:08:241: <PPP packet sent at 07/23/2008 12:02:08:241
[1268] 14:02:08:241: <Protocol = CHAP, Type = Protocol specific, Length = 0x18, Id = 0x0, Port = 3
[1268] 14:02:08:241: <C2 23 01 00 00 16 08 F3 7C 7F 0E D4 53 56 0C 4E |.#......|..SV.N|
[1268] 14:02:08:241: <4F 54 45 42 4F 4F 4B 31 00 00 00 00 00 00 00 00 |OTEBOOK1........|
[1268] 14:02:08:241:
[1268] 14:02:08:241: InsertInTimerQ called portid=164,Id=0,Protocol=c223,EventType=0,fAuth=1
[376] 14:02:08:288: Packet received (64 bytes) for hPort 3
[1268] 14:02:08:288: >PPP packet received at 07/23/2008 12:02:08:288
[1268] 14:02:08:288: >Protocol = CHAP, Type = Protocol specific, Length = 0x40, Id = 0x0, Port = 3
[1268] 14:02:08:288: >C2 23 02 00 00 3E 31 22 4A DB 4C 05 37 1F C6 10 |.#...>1"J.L.7...|
[1268] 14:02:08:288: >7F F9 9F 82 A1 2A 22 81 1A B9 A4 52 C7 48 0E 52 |....*"....R.H.R|
[1268] 14:02:08:288: >00 90 3E 24 51 E4 74 AE D0 68 D6 93 6A CD A2 6D |..>$Q.t..h..j..m|
[1268] 14:02:08:288: >9C A5 58 C3 B2 A2 20 01 48 61 74 7A 66 65 6C 64 |..X... .Hatzfeld|
[1268] 14:02:08:288:
[1268] 14:02:08:288: RemoveFromTimerQ called portid=164,Id=0,Protocol=c223,EventType=0,fAuth=1
[1268] 14:02:08:288: <PPP packet sent at 07/23/2008 12:02:08:288
[1268] 14:02:08:288: <Protocol = CHAP, Type = Protocol specific, Length = 0x6, Id = 0x0, Port = 3
[1268] 14:02:08:288: <C2 23 03 00 00 04 00 00 00 00 00 00 00 00 00 00 |.#..............|
[1268] 14:02:08:288:
[1268] 14:02:08:288: Encryption
[1268] 14:02:08:288: Strong encryption
[1268] 14:02:08:288: MS-CHAP-MPPE-Keys set
[1268] 14:02:08:288: Auth Attribute Domain = NOTEBOOK1
[1268] 14:02:08:288: Auth Attribute Idle Timeout Seconds = 0
[1268] 14:02:08:288: AuthAttribute MaxChannelsAllowed = -1
[1268] 14:02:08:288: FsmThisLayerUp called for protocol = c223, port = 3
[1268] 14:02:08:288: NotifyCaller(hPort=3, dwMsgId=17)
[1268] 14:02:08:288: Callback phase started
[1268] 14:02:08:288: CallbackPriv in CB = 1
[1268] 14:02:08:288: <PPP packet sent at 07/23/2008 12:02:08:288
[1268] 14:02:08:288: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:08:288: <C0 29 01 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:08:288:
[1268] 14:02:08:288: InsertInTimerQ called portid=164,Id=1,Protocol=c029,EventType=0,fAuth=0
[376] 14:02:08:319: Packet received (8 bytes) for hPort 3
[1268] 14:02:08:319: >PPP packet received at 07/23/2008 12:02:08:319
[1268] 14:02:08:319: >Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:08:319: >C0 29 02 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:08:319:
[1268] 14:02:08:319: RemoveFromTimerQ called portid=164,Id=1,Protocol=c029,EventType=0,fAuth=0
[1268] 14:02:08:319: <PPP packet sent at 07/23/2008 12:02:08:319
[1268] 14:02:08:319: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:08:319: <C0 29 03 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:08:319:
[1268] 14:02:08:319: FsmThisLayerUp called for protocol = c029, port = 3
[1268] 14:02:08:319: NotifyCaller(hPort=3, dwMsgId=20)
[748] 14:02:08:319: PPPEMSG_DdmInterfaceInfo recvd,IPXif=0,IPif=2f,Type=0

[1268] 14:02:08:319: Will not initialize CP 8029
[1268] 14:02:08:319: Will not initialize CP 802b
[1268] 14:02:08:319: FsmInit called for protocol = 80fd, port = 3
[1268] 14:02:08:319: FsmReset called for protocol = 80fd, port = 3
[1268] 14:02:08:319: FsmInit called for protocol = 8021, port = 3
[1268] 14:02:08:319: FsmReset called for protocol = 8021, port = 3
[1268] 14:02:08:319: FsmOpen event received for protocol 80fd on port 3
[1268] 14:02:08:319: FsmThisLayerStarted called for protocol = 80fd, port = 3
[1268] 14:02:08:319: FsmUp event received for protocol 80fd on port 3
[1268] 14:02:08:319: <PPP packet sent at 07/23/2008 12:02:08:319
[1268] 14:02:08:319: <Protocol = CCP, Type = Configure-Req, Length = 0xc, Id = 0x4, Port = 3
[1268] 14:02:08:319: <80 FD 01 04 00 0A 12 06 00 00 00 41 00 00 00 00 |...........A....|
[1268] 14:02:08:319:
[1268] 14:02:08:319: InsertInTimerQ called portid=164,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:08:319: FsmOpen event received for protocol 8021 on port 3
[1268] 14:02:08:319: FsmThisLayerStarted called for protocol = 8021, port = 3
[1268] 14:02:08:319: FsmUp event received for protocol 8021 on port 3
[1268] 14:02:08:319: <PPP packet sent at 07/23/2008 12:02:08:319
[1268] 14:02:08:319: <Protocol = IPCP, Type = Configure-Req, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:08:319: <80 21 01 05 00 10 02 06 00 2D 0F 01 03 06 C0 A8 |.!.......-......|
[1268] 14:02:08:319: <02 7B 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.{..............|
[1268] 14:02:08:319:
[1268] 14:02:08:319: InsertInTimerQ called portid=164,Id=5,Protocol=8021,EventType=0,fAuth=0
[376] 14:02:08:366: Packet received (36 bytes) for hPort 3
[1268] 14:02:08:366: >PPP packet received at 07/23/2008 12:02:08:366
[1268] 14:02:08:366: >Protocol = IPCP, Type = Configure-Req, Length = 0x24, Id = 0x0, Port = 3
[1268] 14:02:08:366: >80 21 01 00 00 22 03 06 00 00 00 00 81 06 00 00 |.!..."..........|
[1268] 14:02:08:366: >00 00 83 06 00 00 00 00 82 06 00 00 00 00 84 06 |................|
[1268] 14:02:08:366: >00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:366:
[1268] 14:02:08:366: <PPP packet sent at 07/23/2008 12:02:08:366
[1268] 14:02:08:366: <Protocol = IPCP, Type = Configure-Reject, Length = 0x12, Id = 0x0, Port = 3
[1268] 14:02:08:366: <80 21 04 00 00 10 82 06 00 00 00 00 84 06 00 00 |.!..............|
[1268] 14:02:08:366: <00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:366:
[376] 14:02:08:366: Packet received (18 bytes) for hPort 3
[1268] 14:02:08:366: >PPP packet received at 07/23/2008 12:02:08:366
[1268] 14:02:08:366: >Protocol = LCP, Type = Protocol-Reject, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:08:366: >C0 21 08 05 00 10 80 FD 01 04 00 0A 12 06 00 00 |.!..............|
[1268] 14:02:08:366: >00 41 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.A..............|
[1268] 14:02:08:366:
[1268] 14:02:08:366: PPP Protocol Reject, Protocol = 80fd
[1268] 14:02:08:366: RemoveFromTimerQ called portid=164,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:08:366: FsmThisLayerFinished called for protocol = 80fd, port = 3
[1268] 14:02:08:366: NotifyCaller(hPort=3, dwMsgId=14)
[748] 14:02:08:366: PppDdmStop

[748] 14:02:08:366: PPPEMSG_Stop recvd

[1268] 14:02:08:366: FsmClose event received for protocol c021 on port 3
[1268] 14:02:08:366: RemoveFromTimerQ called portid=164,Id=2,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:08:366: FsmThisLayerDown called for protocol = c021, port = 3
[1268] 14:02:08:366: FsmDown event received for protocol 80fd on port 3
[1268] 14:02:08:366: RemoveFromTimerQ called portid=164,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:08:366: FsmReset called for protocol = 80fd, port = 3
[1268] 14:02:08:366: FsmDown event received for protocol 8021 on port 3
[1268] 14:02:08:366: RemoveFromTimerQ called portid=164,Id=5,Protocol=8021,EventType=0,fAuth=0
[1268] 14:02:08:366: FsmReset called for protocol = 8021, port = 3
[1268] 14:02:08:366: <PPP packet sent at 07/23/2008 12:02:08:366
[1268] 14:02:08:366: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0x6, Port = 3
[1268] 14:02:08:366: <C0 21 05 06 00 10 62 9D 4D D6 00 3C CD 74 00 00 |.!....b.M..<.t..|
[1268] 14:02:08:366: <02 E6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:366:
[1268] 14:02:08:366: InsertInTimerQ called portid=164,Id=6,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:08:366: Packet received (12 bytes) for hPort 3
[1268] 14:02:08:366: >PPP packet received at 07/23/2008 12:02:08:366
[1268] 14:02:08:366: >Protocol = IPCP, Type = Configure-Reject, Length = 0xc, Id = 0x5, Port = 3
[1268] 14:02:08:366: >80 21 04 05 00 0A 02 06 00 2D 0F 01 00 00 00 00 |.!.......-......|
[1268] 14:02:08:366:
[1268] 14:02:08:366: Non-LCP packet received when LCP is not opened
[1268] 14:02:08:366: Packet being silently discarded
[376] 14:02:08:397: Packet received (24 bytes) for hPort 3
[1268] 14:02:08:397: >PPP packet received at 07/23/2008 12:02:08:397
[1268] 14:02:08:397: >Protocol = IPCP, Type = Configure-Req, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:08:397: >80 21 01 02 00 16 03 06 00 00 00 00 81 06 00 00 |.!..............|
[1268] 14:02:08:397: >00 00 83 06 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:08:397:
[1268] 14:02:08:397: Non-LCP packet received when LCP is not opened
[1268] 14:02:08:397: Packet being silently discarded
[376] 14:02:08:397: Packet received (6 bytes) for hPort 3
[1268] 14:02:08:397: >PPP packet received at 07/23/2008 12:02:08:397
[1268] 14:02:08:397: >Protocol = LCP, Type = Terminate-Req, Length = 0x6, Id = 0x6, Port = 3
[1268] 14:02:08:397: >C0 21 05 06 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1268] 14:02:08:397:
[1268] 14:02:08:397: RemoveFromTimerQ called portid=164,Id=6,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:08:397: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=2,fAuth=0
[1268] 14:02:08:397: <PPP packet sent at 07/23/2008 12:02:08:397
[1268] 14:02:08:397: <Protocol = LCP, Type = Terminate-Ack, Length = 0x6, Id = 0x6, Port = 3
[1268] 14:02:08:397: <C0 21 06 06 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1268] 14:02:08:397:
[1268] 14:02:08:397: InsertInTimerQ called portid=164,Id=0,Protocol=0,EventType=2,fAuth=0
[376] 14:02:08:397: Packet received (6 bytes) for hPort 3
[1268] 14:02:08:397: >PPP packet received at 07/23/2008 12:02:08:397
[1268] 14:02:08:397: >Protocol = LCP, Type = Terminate-Ack, Length = 0x6, Id = 0x5, Port = 3
[1268] 14:02:08:397: >C0 21 06 05 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1268] 14:02:08:397:
[1268] 14:02:08:397: Term Ack with on port 3 silently discarded. Invalid Id
[376] 14:02:09:006: PPPEMSG_LineDown recvd, hPort=3

[1268] 14:02:09:006: Line down event occurred on port 3
[1268] 14:02:09:006: FsmDown event received for protocol c021 on port 3
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=6,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:09:006: FsmReset called for protocol = c021, port = 3
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=3,fAuth=0
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=7,fAuth=0
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=2,fAuth=0
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=1,fAuth=0
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=4,fAuth=0
[1268] 14:02:09:006: RemoveFromTimerQ called portid=164,Id=0,Protocol=0,EventType=6,fAuth=0
[1268] 14:02:09:006: LcpEnd
[1464] 14:02:09:006: Stopping Accounting for port 3
[1268] 14:02:11:006: Post line down event occurred on port 3
[1268] 14:02:11:006: NotifyCaller(hPort=3, dwMsgId=23)
[1268] 14:02:13:694: Line up event occurred on port 3
[1268] 14:02:13:694: PortName: ISDN15-1
[1268] 14:02:13:694: Starting PPP on link with IfType=0x0,IPIf=0x0,IPXIf=0x0
[1268] 14:02:13:694: RasGetBuffer returned 979a758 for SendBuf
[1268] 14:02:13:694: FsmInit called for protocol = c021, port = 3
[1268] 14:02:13:694: ConfigInfo = 80270a
[1268] 14:02:13:694: APs available = 6
[1268] 14:02:13:694: FsmReset called for protocol = c021, port = 3
[1268] 14:02:13:694: Inserting port in bucket # 3
[1268] 14:02:13:694: Inserting bundle in bucket # 6
[1268] 14:02:13:694: FsmOpen event received for protocol c021 on port 3
[1268] 14:02:13:694: FsmThisLayerStarted called for protocol = c021, port = 3
[1268] 14:02:13:694: FsmUp event received for protocol c021 on port 3
[1268] 14:02:13:694: <PPP packet sent at 07/23/2008 12:02:13:694
[1268] 14:02:13:694: <Protocol = LCP, Type = Configure-Req, Length = 0x37, Id = 0x0, Port = 3
[1268] 14:02:13:694: <C0 21 01 00 00 35 01 04 05 D4 03 05 C2 23 81 05 |.!...5.......#..|
[1268] 14:02:13:694: <06 29 C5 23 89 0D 03 06 11 04 06 4E 13 17 01 F9 |.).#.......N....|
[1268] 14:02:13:694: <0D 16 D4 94 5E 42 29 82 02 A6 2C FD AC 3E 10 00 |....^B)...,..>..|
[1268] 14:02:13:694: <00 00 00 17 04 00 27 00 00 00 00 00 00 00 00 00 |......'.........|
[1268] 14:02:13:694:
[1268] 14:02:13:694: InsertInTimerQ called portid=166,Id=0,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:13:694: InsertInTimerQ called portid=166,Id=0,Protocol=0,EventType=3,fAuth=0
[1268] 14:02:13:694: >PPP packet received at 07/23/2008 12:02:13:694
[1268] 14:02:13:694: >Protocol = LCP, Type = Configure-Req, Length = 0x17, Id = 0x0, Port = 3
[1268] 14:02:13:694: >C0 21 01 00 00 15 01 04 05 E0 05 06 97 52 3F E4 |.!...........R?.|
[1268] 14:02:13:694: >07 02 08 02 0D 03 06 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:13:694:
[1268] 14:02:13:694: <PPP packet sent at 07/23/2008 12:02:13:694
[1268] 14:02:13:694: <Protocol = LCP, Type = Configure-Reject, Length = 0xa, Id = 0x0, Port = 3
[1268] 14:02:13:694: <C0 21 04 00 00 08 07 02 08 02 00 00 00 00 00 00 |.!..............|
[1268] 14:02:13:694:
[376] 14:02:13:725: Packet received (37 bytes) for hPort 3
[1268] 14:02:13:725: >PPP packet received at 07/23/2008 12:02:13:725
[1268] 14:02:13:725: >Protocol = LCP, Type = Configure-Reject, Length = 0x25, Id = 0x0, Port = 3
[1268] 14:02:13:725: >C0 21 04 00 00 23 11 04 06 4E 13 17 01 F9 0D 16 |.!...#...N......|
[1268] 14:02:13:725: >D4 94 5E 42 29 82 02 A6 2C FD AC 3E 10 00 00 00 |..^B)...,..>....|
[1268] 14:02:13:725: >00 17 04 00 27 00 00 00 00 00 00 00 00 00 00 00 |....'...........|
[1268] 14:02:13:725:
[1268] 14:02:13:725: RemoveFromTimerQ called portid=166,Id=0,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:13:725: <PPP packet sent at 07/23/2008 12:02:13:725
[1268] 14:02:13:725: <Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x1, Port = 3
[1268] 14:02:13:725: <C0 21 01 01 00 16 01 04 05 D4 03 05 C2 23 81 05 |.!...........#..|
[1268] 14:02:13:725: <06 29 C5 23 89 0D 03 06 00 00 00 00 00 00 00 00 |.).#............|
[1268] 14:02:13:725:
[1268] 14:02:13:725: InsertInTimerQ called portid=166,Id=1,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:13:741: Packet received (19 bytes) for hPort 3
[1268] 14:02:13:741: >PPP packet received at 07/23/2008 12:02:13:741
[1268] 14:02:13:741: >Protocol = LCP, Type = Configure-Req, Length = 0x13, Id = 0x2, Port = 3
[1268] 14:02:13:741: >C0 21 01 02 00 11 01 04 05 E0 05 06 97 52 3F E4 |.!...........R?.|
[1268] 14:02:13:741: >0D 03 06 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:13:741:
[1268] 14:02:13:741: <PPP packet sent at 07/23/2008 12:02:13:741
[1268] 14:02:13:741: <Protocol = LCP, Type = Configure-Ack, Length = 0x13, Id = 0x2, Port = 3
[1268] 14:02:13:741: <C0 21 02 02 00 11 01 04 05 E0 05 06 97 52 3F E4 |.!...........R?.|
[1268] 14:02:13:741: <0D 03 06 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:13:741:
[376] 14:02:13:772: Packet received (11 bytes) for hPort 3
[1268] 14:02:13:772: >PPP packet received at 07/23/2008 12:02:13:772
[1268] 14:02:13:772: >Protocol = LCP, Type = Configure-Nak, Length = 0xb, Id = 0x1, Port = 3
[1268] 14:02:13:772: >C0 21 03 01 00 09 03 05 C2 23 05 00 00 00 00 00 |.!.......#......|
[1268] 14:02:13:772:
[1268] 14:02:13:772: RemoveFromTimerQ called portid=166,Id=1,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:13:772: <PPP packet sent at 07/23/2008 12:02:13:772
[1268] 14:02:13:772: <Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:13:772: <C0 21 01 02 00 16 01 04 05 D4 03 05 C2 23 80 05 |.!...........#..|
[1268] 14:02:13:772: <06 29 C5 23 89 0D 03 06 00 00 00 00 00 00 00 00 |.).#............|
[1268] 14:02:13:772:
[1268] 14:02:13:772: InsertInTimerQ called portid=166,Id=2,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:13:803: Packet received (24 bytes) for hPort 3
[1268] 14:02:13:803: >PPP packet received at 07/23/2008 12:02:13:803
[1268] 14:02:13:803: >Protocol = LCP, Type = Configure-Ack, Length = 0x18, Id = 0x2, Port = 3
[1268] 14:02:13:803: >C0 21 02 02 00 16 01 04 05 D4 03 05 C2 23 80 05 |.!...........#..|
[1268] 14:02:13:803: >06 29 C5 23 89 0D 03 06 00 00 00 00 00 00 00 00 |.).#............|
[1268] 14:02:13:803:
[1268] 14:02:13:803: RemoveFromTimerQ called portid=166,Id=2,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:13:803: FsmThisLayerUp called for protocol = c021, port = 3
[1268] 14:02:13:803: LCP Local Options-------------
[1268] 14:02:13:803: MRU=1492,ACCM=-1,Auth=c223,MagicNumber=700785545,PFC=OFF,ACFC=OFF
[1268] 14:02:13:803: Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=27,BAP=OFF
[1268] 14:02:13:803: LCP Remote Options-------------
[1268] 14:02:13:803: MRU=1504,ACCM=-1,Auth=0,MagicNumber=-1756217372,PFC=OFF,ACFC=OFF
[1268] 14:02:13:803: Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[1268] 14:02:13:803: LCP Configured successfully
[1268] 14:02:13:803: Authenticating phase started
[1268] 14:02:13:803: Calling APWork in APStart
[1268] 14:02:13:803: <PPP packet sent at 07/23/2008 12:02:13:803
[1268] 14:02:13:803: <Protocol = CHAP, Type = Protocol specific, Length = 0x18, Id = 0x0, Port = 3
[1268] 14:02:13:803: <C2 23 01 00 00 16 08 AD 96 50 CB CA 69 2B 96 4E |.#.......P..i+.N|
[1268] 14:02:13:803: <4F 54 45 42 4F 4F 4B 31 00 00 00 00 00 00 00 00 |OTEBOOK1........|
[1268] 14:02:13:803:
[1268] 14:02:13:803: InsertInTimerQ called portid=166,Id=0,Protocol=c223,EventType=0,fAuth=1
[376] 14:02:13:850: Packet received (64 bytes) for hPort 3
[1268] 14:02:13:850: >PPP packet received at 07/23/2008 12:02:13:850
[1268] 14:02:13:850: >Protocol = CHAP, Type = Protocol specific, Length = 0x40, Id = 0x0, Port = 3
[1268] 14:02:13:850: >C2 23 02 00 00 3E 31 E1 6A E4 82 8F CE 84 7D F3 |.#...>1.j.....}.|
[1268] 14:02:13:850: >BC EE 1A B2 03 36 A5 BB AA DC 80 FF 0C C9 FF 85 |.....6..........|
[1268] 14:02:13:850: >67 69 2B A9 D8 ED D2 9A F5 12 A9 B1 93 07 FC 62 |gi+............b|
[1268] 14:02:13:850: >3B 67 9B 4E DD 35 6C 01 48 61 74 7A 66 65 6C 64 |;g.N.5l.Hatzfeld|
[1268] 14:02:13:850:
[1268] 14:02:13:850: RemoveFromTimerQ called portid=166,Id=0,Protocol=c223,EventType=0,fAuth=1
[1268] 14:02:13:850: <PPP packet sent at 07/23/2008 12:02:13:850
[1268] 14:02:13:850: <Protocol = CHAP, Type = Protocol specific, Length = 0x6, Id = 0x0, Port = 3
[1268] 14:02:13:850: <C2 23 03 00 00 04 00 00 00 00 00 00 00 00 00 00 |.#..............|
[1268] 14:02:13:850:
[1268] 14:02:13:850: Encryption
[1268] 14:02:13:850: Strong encryption
[1268] 14:02:13:850: MS-CHAP-MPPE-Keys set
[1268] 14:02:13:850: Auth Attribute Domain = NOTEBOOK1
[1268] 14:02:13:850: Auth Attribute Idle Timeout Seconds = 0
[1268] 14:02:13:850: AuthAttribute MaxChannelsAllowed = -1
[1268] 14:02:13:850: FsmThisLayerUp called for protocol = c223, port = 3
[1268] 14:02:13:850: NotifyCaller(hPort=3, dwMsgId=17)
[1268] 14:02:13:850: Callback phase started
[1268] 14:02:13:850: CallbackPriv in CB = 1
[1268] 14:02:13:850: <PPP packet sent at 07/23/2008 12:02:13:850
[1268] 14:02:13:850: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:13:850: <C0 29 01 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:13:850:
[1268] 14:02:13:850: InsertInTimerQ called portid=166,Id=1,Protocol=c029,EventType=0,fAuth=0
[376] 14:02:13:881: Packet received (8 bytes) for hPort 3
[1268] 14:02:13:881: >PPP packet received at 07/23/2008 12:02:13:881
[1268] 14:02:13:881: >Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:13:881: >C0 29 02 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:13:881:
[1268] 14:02:13:881: RemoveFromTimerQ called portid=166,Id=1,Protocol=c029,EventType=0,fAuth=0
[1268] 14:02:13:881: <PPP packet sent at 07/23/2008 12:02:13:881
[1268] 14:02:13:881: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 3
[1268] 14:02:13:881: <C0 29 03 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
[1268] 14:02:13:881:
[1268] 14:02:13:881: FsmThisLayerUp called for protocol = c029, port = 3
[1268] 14:02:13:881: NotifyCaller(hPort=3, dwMsgId=20)
[748] 14:02:13:881: PPPEMSG_DdmInterfaceInfo recvd,IPXif=0,IPif=30,Type=0

[1268] 14:02:13:881: Will not initialize CP 8029
[1268] 14:02:13:881: Will not initialize CP 802b
[1268] 14:02:13:881: FsmInit called for protocol = 80fd, port = 3
[1268] 14:02:13:881: FsmReset called for protocol = 80fd, port = 3
[1268] 14:02:13:881: FsmInit called for protocol = 8021, port = 3
[1268] 14:02:13:881: FsmReset called for protocol = 8021, port = 3
[1268] 14:02:13:881: FsmOpen event received for protocol 80fd on port 3
[1268] 14:02:13:881: FsmThisLayerStarted called for protocol = 80fd, port = 3
[1268] 14:02:13:881: FsmUp event received for protocol 80fd on port 3
[1268] 14:02:13:881: <PPP packet sent at 07/23/2008 12:02:13:881
[1268] 14:02:13:881: <Protocol = CCP, Type = Configure-Req, Length = 0xc, Id = 0x4, Port = 3
[1268] 14:02:13:881: <80 FD 01 04 00 0A 12 06 00 00 00 41 00 00 00 00 |...........A....|
[1268] 14:02:13:881:
[1268] 14:02:13:881: InsertInTimerQ called portid=166,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:13:881: FsmOpen event received for protocol 8021 on port 3
[1268] 14:02:13:881: FsmThisLayerStarted called for protocol = 8021, port = 3
[1268] 14:02:13:881: FsmUp event received for protocol 8021 on port 3
[1268] 14:02:13:881: <PPP packet sent at 07/23/2008 12:02:13:881
[1268] 14:02:13:881: <Protocol = IPCP, Type = Configure-Req, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:13:881: <80 21 01 05 00 10 02 06 00 2D 0F 01 03 06 C0 A8 |.!.......-......|
[1268] 14:02:13:881: <02 7B 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.{..............|
[1268] 14:02:13:881:
[1268] 14:02:13:881: InsertInTimerQ called portid=166,Id=5,Protocol=8021,EventType=0,fAuth=0
[376] 14:02:13:928: Packet received (36 bytes) for hPort 3
[1268] 14:02:13:928: >PPP packet received at 07/23/2008 12:02:13:928
[1268] 14:02:13:928: >Protocol = IPCP, Type = Configure-Req, Length = 0x24, Id = 0x0, Port = 3
[1268] 14:02:13:928: >80 21 01 00 00 22 03 06 00 00 00 00 81 06 00 00 |.!..."..........|
[1268] 14:02:13:928: >00 00 83 06 00 00 00 00 82 06 00 00 00 00 84 06 |................|
[1268] 14:02:13:928: >00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:13:928:
[1268] 14:02:13:928: <PPP packet sent at 07/23/2008 12:02:13:928
[1268] 14:02:13:928: <Protocol = IPCP, Type = Configure-Reject, Length = 0x12, Id = 0x0, Port = 3
[1268] 14:02:13:928: <80 21 04 00 00 10 82 06 00 00 00 00 84 06 00 00 |.!..............|
[1268] 14:02:13:928: <00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:13:928:
[376] 14:02:13:928: Packet received (18 bytes) for hPort 3
[1268] 14:02:13:928: >PPP packet received at 07/23/2008 12:02:13:928
[1268] 14:02:13:928: >Protocol = LCP, Type = Protocol-Reject, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:13:928: >C0 21 08 05 00 10 80 FD 01 04 00 0A 12 06 00 00 |.!..............|
[1268] 14:02:13:928: >00 41 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.A..............|
[1268] 14:02:13:928:
[1268] 14:02:13:928: PPP Protocol Reject, Protocol = 80fd
[1268] 14:02:13:928: RemoveFromTimerQ called portid=166,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:13:928: FsmThisLayerFinished called for protocol = 80fd, port = 3
[1268] 14:02:13:928: NotifyCaller(hPort=3, dwMsgId=14)
[748] 14:02:13:928: PppDdmStop

[748] 14:02:13:928: PPPEMSG_Stop recvd

[1268] 14:02:13:928: FsmClose event received for protocol c021 on port 3
[1268] 14:02:13:928: RemoveFromTimerQ called portid=166,Id=2,Protocol=c021,EventType=0,fAuth=0
[1268] 14:02:13:928: FsmThisLayerDown called for protocol = c021, port = 3
[1268] 14:02:13:928: FsmDown event received for protocol 80fd on port 3
[1268] 14:02:13:928: RemoveFromTimerQ called portid=166,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:13:928: FsmReset called for protocol = 80fd, port = 3
[1268] 14:02:13:928: FsmDown event received for protocol 8021 on port 3
[1268] 14:02:13:928: RemoveFromTimerQ called portid=166,Id=5,Protocol=8021,EventType=0,fAuth=0
[1268] 14:02:13:928: FsmReset called for protocol = 8021, port = 3
[1268] 14:02:13:928: <PPP packet sent at 07/23/2008 12:02:13:928
[1268] 14:02:13:928: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0x6, Port = 3
[1268] 14:02:13:928: <C0 21 05 06 00 10 29 C5 23 89 00 3C CD 74 00 00 |.!....).#..<.t..|
[1268] 14:02:13:928: <02 E6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1268] 14:02:13:928:
[1268] 14:02:13:928: InsertInTimerQ called portid=166,Id=6,Protocol=c021,EventType=0,fAuth=0
[376] 14:02:13:928: Packet received (12 bytes) for hPort 3
[1268] 14:02:13:928: >PPP packet received at 07/23/2008 12:02:13:928
[1268] 14:02:13:928: >Protocol = IPCP, Type = Configure-Reject, Length = 0xc, Id = 0x5, Port = 3
[1268] 14:02:13:928: >80 21 04 05 00 0A 02 06 00 2D 0F 01 00 00 00 00 |.!.......-......|
[1268] 14:02:13:928:
[1268] 14:02:13:928: Non-LCP packet received when LCP is not opened
[1268] 14:02:13:928: Packet being silently discarded
[376] 14:02:13:959: Packet received (24 bytes) for hPort 3
[1268] 14:02:13:959: >PPP packet received at

backslash · Beitrag von **backslash** » 23 Jul 2008, 16:37

Hi KK

der Fall ist eindeutig: Windows will daß Verbindung verschlüsseln, was das LANCOM nicht kann. Man sieht das an folhenden Stellen im Windows Trace:

Windows initiliasiert die Verschüsselung:

[1268] 14:02:03:163: Encryption
[1268] 14:02:03:163: Strong encryption
[1268] 14:02:03:163: MS-CHAP-MPPE-Keys set

(...)

[1268] 14:02:03:225: FsmInit called for protocol = 80fd, port = 3
[1268] 14:02:03:225: FsmReset called for protocol = 80fd, port = 3

(...)

Windows fordert vom LANCOM die Verschlüsselung an - versteckt als Komression (CCP)

[1268] 14:02:03:366: FsmOpen event received for protocol 80fd on port 3
[1268] 14:02:03:366: FsmThisLayerStarted called for protocol = 80fd, port = 3
[1268] 14:02:03:366: FsmUp event received for protocol 80fd on port 3
[1268] 14:02:03:366: <PPP packet sent at 07/23/2008 12:02:03:366
[1268] 14:02:03:366: <Protocol = CCP, Type = Configure-Req, Length = 0xc, Id = 0x4, Port = 3
[1268] 14:02:03:366: <80 FD 01 04 00 0A 12 06 00 00 00 41 00 00 00 00 |...........A....|

(...)

Das LANCOM lehnt nun CCP ab, weil du dort die Kompression komplett deaktiviert hast
Im verherigen Trace hat das LANCOM den Kompressionstyp als "unknown Option 12 06 00 00 00 41" zurückgewiesen.
Beides führt zum gleichen Ergebnis: Windows mag nicht mehr:

1268] 14:02:03:397: >PPP packet received at 07/23/2008 12:02:03:397
[1268] 14:02:03:397: >Protocol = LCP, Type = Protocol-Reject, Length = 0x12, Id = 0x5, Port = 3
[1268] 14:02:03:397: >C0 21 08 05 00 10 80 FD 01 04 00 0A 12 06 00 00 |.!..............|
[1268] 14:02:03:397: >00 41 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |.A..............|
[1268] 14:02:03:397:
[1268] 14:02:03:397: PPP Protocol Reject, Protocol = 80fd
[1268] 14:02:03:397: RemoveFromTimerQ called portid=162,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1268] 14:02:03:397: FsmThisLayerFinished called for protocol = 80fd, port = 3
[1268] 14:02:03:397: NotifyCaller(hPort=3, dwMsgId=14)

Das wars... Windows macht die Leitung dicht:

[376] 14:02:03:413: Packet received (12 bytes) for hPort 3
[1268] 14:02:03:413: >PPP packet received at 07/23/2008 12:02:03:413
[1268] 14:02:03:413: >Protocol = IPCP, Type = Configure-Reject, Length = 0xc, Id = 0x5, Port = 3
[1268] 14:02:03:413: >80 21 04 05 00 0A 02 06 00 2D 0F 01 00 00 00 00 |.!.......-......|
[1268] 14:02:03:413:
[1268] 14:02:03:413: Non-LCP packet received when LCP is not opened

(...)

Es hat also rein gar nichts mit der Header-Kompression zu tun. Jetzt stellt sich nur die Frage, wo der Verschlüsselungszwang abschaltbar bzw. wieso er bei dir überhaupt aktiv ist.

Gruß
Backslash

KK · Beitrag von KK » 30 Jul 2008, 17:08

Hallo Backslash,

vielen Dank für die Info. Unsere Firma hat beschlossen, auf tiefergehende Windows-Konfigurationen zu verzichten

Die entsprechende Gegenstelle wurde zwischenzeitlich mit einem 1823 ausgerüstet. Läuft jetzt erwartungsgemäß tatellos.

Leider bleibt noch ein kleines anderes Problem:
http://www.lancom-forum.de/htopic,7440,.html

Es grüßt

KK