[1100] 06-04 10:22:13:727: PPPEMSG_Start recvd, d=, hPort=7,callback=0,mask=883020a,IfType=-1
[3608] 06-04 10:22:13:727: Line up event occurred on port 7
[3608] 06-04 10:22:13:727: Local identification = MSRAS-0-RNT-PC
[3608] 06-04 10:22:13:727: PortName: COM6
[3608] 06-04 10:22:13:727: Starting PPP on link with IfType=0xffffffff,IPIf=0xffffffff,IPv6If=0xffffffff,IPXIf=0xffffffff
[3608] 06-04 10:22:13:727: RasGetBuffer returned 4d0bf90 for SendBuf
[3608] 06-04 10:22:13:727: FsmInit called for protocol = c021, port = 7
[3608] 06-04 10:22:13:727: ConfigInfo = 883020a
[3608] 06-04 10:22:13:727: APs available = 4a
[3608] 06-04 10:22:13:727: FsmReset called for protocol = c021, port = 7
[3608] 06-04 10:22:13:727: Inserting port in bucket # 7
[3608] 06-04 10:22:13:727: Inserting bundle in bucket # 2
[3608] 06-04 10:22:13:727: FsmOpen event received for protocol c021 on port 7
[3608] 06-04 10:22:13:727: FsmThisLayerStarted called for protocol = c021, port = 7
[3608] 06-04 10:22:13:727: FsmUp event received for protocol c021 on port 7
[3608] 06-04 10:22:13:727: <PPP packet sent at 06/04/2012 14:22:13:727
[3608] 06-04 10:22:13:727: <Protocol = LCP, Type = Configure-Req, Length = 0x19, Id = 0x0, Port = 7
[3608] 10:22:13:727: <C0 21 01 00 00 17 02 06 00 00 00 00 05 06 04 9C |.!..............|
[3608] 10:22:13:727: <3B 7D 07 02 08 02 0D 03 06 00 00 00 00 00 00 00 |;}..............|
[3608] 06-04 10:22:13:727:  
[3608] 06-04 10:22:13:727: InsertInTimerQ called portid=2,Id=0,Protocol=c021,EventType=0,fAuth=0
[3608] 06-04 10:22:13:727: InsertInTimerQ called portid=2,Id=0,Protocol=0,EventType=3,fAuth=0
[3548] 06-04 10:22:13:914: Packet received (41 bytes) for hPort 7
[3608] 06-04 10:22:13:914: >PPP packet received at 06/04/2012 14:22:13:914
[3608] 06-04 10:22:13:914: >Protocol = LCP, Type = Configure-Req, Length = 0x29, Id = 0x1, Port = 7
[3608] 10:22:13:914: >C0 21 01 01 00 27 00 04 00 00 01 04 05 F4 02 06 |.!...'..........|
[3608] 10:22:13:914: >00 0A 00 00 03 04 C0 23 07 02 08 02 11 04 05 F4 |.......#........|
[3608] 10:22:13:914: >13 09 03 00 D0 52 04 94 23 00 00 00 00 00 00 00 |.....R..#.......|
[3608] 06-04 10:22:13:914:  
[3608] 06-04 10:22:13:914: CheckOption: Negotiated Options 2
[3608] 06-04 10:22:13:914: CheckOption: Negotiated Options 6
[3608] 06-04 10:22:13:914: CheckOption: Negotiated Options e
[3608] 06-04 10:22:13:914: CheckOption: Negotiated Options 8e
[3608] 06-04 10:22:13:914: CheckOption: Negotiated Options 18e
[3608] 06-04 10:22:13:914: <PPP packet sent at 06/04/2012 14:22:13:914
[3608] 06-04 10:22:13:914: <Protocol = LCP, Type = Configure-Reject, Length = 0x17, Id = 0x1, Port = 7
[3608] 10:22:13:914: <C0 21 04 01 00 15 00 04 00 00 11 04 05 F4 13 09 |.!..............|
[3608] 10:22:13:914: <03 00 D0 52 04 94 23 00 00 00 00 00 00 00 00 00 |...R..#.........|
[3608] 06-04 10:22:13:914:  
[3548] 06-04 10:22:13:945: Packet received (9 bytes) for hPort 7
[3608] 06-04 10:22:13:945: >PPP packet received at 06/04/2012 14:22:13:945
[3608] 06-04 10:22:13:945: >Protocol = LCP, Type = Configure-Reject, Length = 0x9, Id = 0x0, Port = 7
[3608] 10:22:13:945: >C0 21 04 00 00 07 0D 03 06 00 00 00 00 00 00 00 |.!..............|
[3608] 06-04 10:22:13:945:  
[3608] 06-04 10:22:13:945: RemoveFromTimerQ called portid=2,Id=0,Protocol=c021,EventType=0,fAuth=0
[3608] 06-04 10:22:13:945: <PPP packet sent at 06/04/2012 14:22:13:945
[3608] 06-04 10:22:13:945: <Protocol = LCP, Type = Configure-Req, Length = 0x16, Id = 0x1, Port = 7
[3608] 10:22:13:945: <C0 21 01 01 00 14 02 06 00 00 00 00 05 06 04 9C |.!..............|
[3608] 10:22:13:945: <3B 7D 07 02 08 02 00 00 00 00 00 00 00 00 00 00 |;}..............|
[3608] 06-04 10:22:13:945:  
[3608] 06-04 10:22:13:945: InsertInTimerQ called portid=2,Id=1,Protocol=c021,EventType=0,fAuth=0
[3548] 06-04 10:22:14:086: Packet received (24 bytes) for hPort 7
[3608] 06-04 10:22:14:086: >PPP packet received at 06/04/2012 14:22:14:086
[3608] 06-04 10:22:14:086: >Protocol = LCP, Type = Configure-Req, Length = 0x18, Id = 0x2, Port = 7
[3608] 10:22:14:086: >C0 21 01 02 00 16 01 04 05 F4 02 06 00 0A 00 00 |.!..............|
[3608] 10:22:14:086: >03 04 C0 23 07 02 08 02 00 00 00 00 00 00 00 00 |...#............|
[3608] 06-04 10:22:14:086:  
[3608] 06-04 10:22:14:086: CheckOption: Negotiated Options 18e
[3608] 06-04 10:22:14:086: CheckOption: Negotiated Options 18e
[3608] 06-04 10:22:14:086: CheckOption: Negotiated Options 18e
[3608] 06-04 10:22:14:086: CheckOption: Negotiated Options 18e
[3608] 06-04 10:22:14:086: CheckOption: Negotiated Options 18e
[3608] 06-04 10:22:14:086: <PPP packet sent at 06/04/2012 14:22:14:086
[3608] 06-04 10:22:14:086: <Protocol = LCP, Type = Configure-Ack, Length = 0x18, Id = 0x2, Port = 7
[3608] 10:22:14:086: <C0 21 02 02 00 16 01 04 05 F4 02 06 00 0A 00 00 |.!..............|
[3608] 10:22:14:086: <03 04 C0 23 07 02 08 02 00 00 00 00 00 00 00 00 |...#............|
[3608] 06-04 10:22:14:086:  
[3548] 06-04 10:22:14:117: Packet received (22 bytes) for hPort 7
[3608] 06-04 10:22:14:117: >PPP packet received at 06/04/2012 14:22:14:117
[3608] 06-04 10:22:14:117: >Protocol = LCP, Type = Configure-Ack, Length = 0x16, Id = 0x1, Port = 7
[3608] 10:22:14:117: >C0 21 02 01 00 14 02 06 00 00 00 00 05 06 04 9C |.!..............|
[3608] 10:22:14:117: >3B 7D 07 02 08 02 00 00 00 00 00 00 00 00 00 00 |;}..............|
[3608] 06-04 10:22:14:117:  
[3608] 06-04 10:22:14:117: RemoveFromTimerQ called portid=2,Id=1,Protocol=c021,EventType=0,fAuth=0
[3608] 06-04 10:22:14:117: FsmThisLayerUp called for protocol = c021, port = 7
[3608] 06-04 10:22:14:117: LCP Local Options-------------
[3608] 06-04 10:22:14:117: 	MRU=1500,ACCM=0,Auth=0,MagicNumber=77347709,PFC=ON,ACFC=ON
[3608] 06-04 10:22:14:117: 	Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0,BAP=OFF
[3608] 06-04 10:22:14:117: LCP Remote Options-------------
[3608] 06-04 10:22:14:117: 	MRU=1524,ACCM=655360,Auth=c023,MagicNumber=0,PFC=ON,ACFC=ON
[3608] 06-04 10:22:14:117: 	Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[3608] 06-04 10:22:14:117: LCP Configured successfully
[3608] 06-04 10:22:14:117: Sending Version Identification MSRASV5.20
[3608] 06-04 10:22:14:117: <PPP packet sent at 06/04/2012 14:22:14:117
[3608] 06-04 10:22:14:117: <Protocol = LCP, Type = Identification, Length = 0x14, Id = 0x2, Port = 7
[3608] 10:22:14:117: <C0 21 0C 02 00 12 04 9C 3B 7D 4D 53 52 41 53 56 |.!......;}MSRASV|
[3608] 10:22:14:117: <35 2E 32 30 00 00 00 00 00 00 00 00 00 00 00 00 |5.20............|
[3608] 06-04 10:22:14:117:  
[3608] 06-04 10:22:14:117: Sending ComputerName Identification MSRAS-0-RNT-PC
[3608] 06-04 10:22:14:117: <PPP packet sent at 06/04/2012 14:22:14:117
[3608] 06-04 10:22:14:117: <Protocol = LCP, Type = Identification, Length = 0x18, Id = 0x3, Port = 7
[3608] 10:22:14:117: <C0 21 0C 03 00 16 04 9C 3B 7D 4D 53 52 41 53 2D |.!......;}MSRAS-|
[3608] 10:22:14:117: <30 2D 4A 45 4F 2D 50 43 00 00 00 00 00 00 00 00 |0-RNT-PC........|
[3608] 06-04 10:22:14:117:  
[3608] 06-04 10:22:14:117: Client: LCP Configured successfully for Guid {ECC487F6-A513-4428-9818-52A39B694B5A}
[3608] 06-04 10:22:14:117: Sending Correlation Guid {ECC487F6-A513-4428-9818-52A39B694B5A}
[3608] 06-04 10:22:14:117: <PPP packet sent at 06/04/2012 14:22:14:117
[3608] 06-04 10:22:14:117: <Protocol = LCP, Type = Identification, Length = 0x1a, Id = 0x4, Port = 7
[3608] 10:22:14:117: <C0 21 0C 04 00 18 04 9C 3B 7D F6 87 C4 EC 13 A5 |.!......;}......|
[3608] 10:22:14:117: <28 44 98 18 52 A3 9B 69 4B 5A 00 00 00 00 00 00 |(D..R..iKZ......|
[3608] 06-04 10:22:14:117:  
[3608] 06-04 10:22:14:117: Authenticating phase started
[3608] 06-04 10:22:14:117: Calling APWork in APStart
[3608] 06-04 10:22:14:117: <PPP packet sent at 06/04/2012 14:22:14:117
[3608] 06-04 10:22:14:117: <Protocol = PAP, Type = Protocol specific, Length = 0x27, Id = 0x1, Port = 7
[3608] 06-04 10:22:14:117:  
[3608] 06-04 10:22:14:117: InsertInTimerQ called portid=2,Id=1,Protocol=c023,EventType=0,fAuth=0
[3548] 06-04 10:22:14:538: Packet received (57 bytes) for hPort 7
[3608] 06-04 10:22:14:538: >PPP packet received at 06/04/2012 14:22:14:538
[3608] 06-04 10:22:14:538: >Protocol = PAP, Type = Protocol specific, Length = 0x39, Id = 0x1, Port = 7
[3608] 06-04 10:22:14:538:  
[3608] 06-04 10:22:14:538: RemoveFromTimerQ called portid=2,Id=1,Protocol=c023,EventType=0,fAuth=0
[3608] 06-04 10:22:14:538: FsmThisLayerUp called for protocol = c023, port = 7
[3608] 06-04 10:22:14:538: NotifyCaller(hPort=7, dwMsgId=17)
[3608] 06-04 10:22:14:538: NotifyCaller(hPort=7, dwMsgId=3)
[3608] 06-04 10:22:14:538: FsmInit called for protocol = 8057, port = 7
[3608] 06-04 10:22:14:538: FsmReset called for protocol = 8057, port = 7
[3608] 06-04 10:22:14:538: Will not initialize CP 8029
[3608] 06-04 10:22:14:538: Will not initialize CP 802b
[3608] 06-04 10:22:14:538: FsmInit called for protocol = 80fd, port = 7
[3608] 06-04 10:22:14:538: FsmReset called for protocol = 80fd, port = 7
[3608] 06-04 10:22:14:538: FsmInit called for protocol = 8021, port = 7
[3608] 06-04 10:22:14:538: FsmReset called for protocol = 8021, port = 7
[3608] 06-04 10:22:14:538: FsmOpen event received for protocol 8057 on port 7
[3608] 06-04 10:22:14:538: FsmThisLayerStarted called for protocol = 8057, port = 7
[3608] 06-04 10:22:14:538: FsmUp event received for protocol 8057 on port 7
[3548] 06-04 10:22:14:554: Packet received (18 bytes) for hPort 7
[3608] 06-04 10:22:14:554: <PPP packet sent at 06/04/2012 14:22:14:554
[3608] 06-04 10:22:14:554: <Protocol = IPv6CP, Type = Configure-Req, Length = 0x10, Id = 0x5, Port = 7
[3608] 10:22:14:554: <80 57 01 05 00 0E 01 0A BC A7 09 BB A8 6C 23 4F |.W...........l#O|
[3608] 06-04 10:22:14:554:  
[3608] 06-04 10:22:14:554: InsertInTimerQ called portid=2,Id=5,Protocol=8057,EventType=0,fAuth=0
[3608] 06-04 10:22:14:554: FsmOpen event received for protocol 80fd on port 7
[3608] 06-04 10:22:14:554: FsmThisLayerStarted called for protocol = 80fd, port = 7
[3608] 06-04 10:22:14:554: FsmUp event received for protocol 80fd on port 7
[3608] 06-04 10:22:14:554: <PPP packet sent at 06/04/2012 14:22:14:554
[3608] 06-04 10:22:14:554: <Protocol = CCP, Type = Configure-Req, Length = 0xc, Id = 0x6, Port = 7
[3608] 10:22:14:554: <80 FD 01 06 00 0A 12 06 00 00 00 01 00 00 00 00 |................|
[3608] 06-04 10:22:14:554:  
[3608] 06-04 10:22:14:554: InsertInTimerQ called portid=2,Id=6,Protocol=80fd,EventType=0,fAuth=0
[3608] 06-04 10:22:14:554: FsmOpen event received for protocol 8021 on port 7
[3608] 06-04 10:22:14:554: FsmThisLayerStarted called for protocol = 8021, port = 7
[3608] 06-04 10:22:14:554: FsmUp event received for protocol 8021 on port 7
[3608] 06-04 10:22:14:554: <PPP packet sent at 06/04/2012 14:22:14:554
[3608] 06-04 10:22:14:554: <Protocol = IPCP, Type = Configure-Req, Length = 0x2a, Id = 0x7, Port = 7
[3608] 10:22:14:554: <80 21 01 07 00 28 02 06 00 2D 0F 01 03 06 00 00 |.!...(...-......|
[3608] 10:22:14:554: <00 00 81 06 00 00 00 00 82 06 00 00 00 00 83 06 |................|
[3608] 10:22:14:554: <00 00 00 00 84 06 00 00 00 00 00 00 00 00 00 00 |................|
[3608] 06-04 10:22:14:554:  
[3608] 06-04 10:22:14:554: InsertInTimerQ called portid=2,Id=7,Protocol=8021,EventType=0,fAuth=0
[3608] 06-04 10:22:14:554: >PPP packet received at 06/04/2012 14:22:14:554
[3608] 06-04 10:22:14:554: >Protocol = IPCP, Type = Configure-Req, Length = 0x12, Id = 0x1, Port = 7
[3608] 10:22:14:554: >80 21 01 01 00 10 02 06 00 2D 0F 01 03 06 3F D7 |.!.......-....?.|
[3608] 10:22:14:554: >1D F5 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[3608] 06-04 10:22:14:554:  
[3608] 06-04 10:22:14:554: <PPP packet sent at 06/04/2012 14:22:14:554
[3608] 06-04 10:22:14:554: <Protocol = IPCP, Type = Configure-Ack, Length = 0x12, Id = 0x1, Port = 7
[3608] 10:22:14:554: <80 21 02 01 00 10 02 06 00 2D 0F 01 03 06 3F D7 |.!.......-....?.|
[3608] 10:22:14:554: <1D F5 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[3608] 06-04 10:22:14:554:  
[3548] 06-04 10:22:14:741: Packet received (22 bytes) for hPort 7
[3608] 06-04 10:22:14:741: >PPP packet received at 06/04/2012 14:22:14:741
[3608] 06-04 10:22:14:741: >Protocol = LCP, Type = Protocol-Reject, Length = 0x16, Id = 0x3, Port = 7
[3608] 10:22:14:741: >C0 21 08 03 00 14 80 57 01 05 00 0E 01 0A BC A7 |.!.....W........|
[3608] 10:22:14:741: >09 BB A8 6C 23 4F 00 00 00 00 00 00 00 00 00 00 |...l#O..........|
[3608] 06-04 10:22:14:741:  
[3608] 06-04 10:22:14:741: PPP Protocol Reject, Protocol = 0
[3608] 06-04 10:22:14:741: RemoveFromTimerQ called portid=2,Id=5,Protocol=8057,EventType=0,fAuth=0
[3608] 06-04 10:22:14:741: FsmThisLayerFinished called for protocol = 8057, port = 7
[3548] 06-04 10:22:14:741: Packet received (18 bytes) for hPort 7
[3608] 06-04 10:22:14:741: >PPP packet received at 06/04/2012 14:22:14:741
[3608] 06-04 10:22:14:741: >Protocol = LCP, Type = Protocol-Reject, Length = 0x12, Id = 0x4, Port = 7
[3608] 10:22:14:741: >C0 21 08 04 00 10 80 FD 01 06 00 0A 12 06 00 00 |.!..............|
[3608] 10:22:14:741: >00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[3608] 06-04 10:22:14:741:  
[3608] 06-04 10:22:14:741: PPP Protocol Reject, Protocol = 0
[3608] 06-04 10:22:14:741: RemoveFromTimerQ called portid=2,Id=6,Protocol=80fd,EventType=0,fAuth=0
[3608] 06-04 10:22:14:741: FsmThisLayerFinished called for protocol = 80fd, port = 7
[3548] 06-04 10:22:14:772: Packet received (18 bytes) for hPort 7
[3608] 06-04 10:22:14:772: >PPP packet received at 06/04/2012 14:22:14:772
[3608] 06-04 10:22:14:772: >Protocol = IPCP, Type = Configure-Reject, Length = 0x12, Id = 0x7, Port = 7
[3608] 10:22:14:772: >80 21 04 07 00 10 82 06 00 00 00 00 84 06 00 00 |.!..............|
[3608] 10:22:14:772: >00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[3608] 06-04 10:22:14:772:  
[3608] 06-04 10:22:14:772: RemoveFromTimerQ called portid=2,Id=7,Protocol=8021,EventType=0,fAuth=0
[3608] 06-04 10:22:14:772: <PPP packet sent at 06/04/2012 14:22:14:772
[3608] 06-04 10:22:14:772: <Protocol = IPCP, Type = Configure-Req, Length = 0x1e, Id = 0x8, Port = 7
[3608] 10:22:14:772: <80 21 01 08 00 1C 02 06 00 2D 0F 01 03 06 00 00 |.!.......-......|
[3608] 10:22:14:772: <00 00 81 06 00 00 00 00 83 06 00 00 00 00 00 00 |................|
[3608] 06-04 10:22:14:772:  
[3608] 06-04 10:22:14:772: InsertInTimerQ called portid=2,Id=8,Protocol=8021,EventType=0,fAuth=0
[3548] 06-04 10:22:14:928: Packet received (24 bytes) for hPort 7
[3608] 06-04 10:22:14:928: >PPP packet received at 06/04/2012 14:22:14:928
[3608] 06-04 10:22:14:928: >Protocol = IPCP, Type = Configure-Nak, Length = 0x18, Id = 0x8, Port = 7
[3608] 10:22:14:928: >80 21 03 08 00 16 03 06 04 E1 5D 25 81 06 40 88 |.!........]%..@.|
[3608] 10:22:14:928: >AD 04 83 06 40 88 A4 4C 00 00 00 00 00 00 00 00 |....@..L........|
[3608] 06-04 10:22:14:928:  
[3608] 06-04 10:22:14:928: RemoveFromTimerQ called portid=2,Id=8,Protocol=8021,EventType=0,fAuth=0
[3608] 06-04 10:22:14:928: <PPP packet sent at 06/04/2012 14:22:14:928
[3608] 06-04 10:22:14:928: <Protocol = IPCP, Type = Configure-Req, Length = 0x1e, Id = 0x9, Port = 7
[3608] 10:22:14:928: <80 21 01 09 00 1C 02 06 00 2D 0F 01 03 06 04 E1 |.!.......-......|
[3608] 10:22:14:928: <5D 25 81 06 40 88 AD 04 83 06 40 88 A4 4C 00 00 |]%..@.....@..L..|
[3608] 06-04 10:22:14:928:  
[3608] 06-04 10:22:14:928: InsertInTimerQ called portid=2,Id=9,Protocol=8021,EventType=0,fAuth=0
[3548] 06-04 10:22:15:115: Packet received (30 bytes) for hPort 7
[3608] 06-04 10:22:15:115: >PPP packet received at 06/04/2012 14:22:15:115
[3608] 06-04 10:22:15:115: >Protocol = IPCP, Type = Configure-Ack, Length = 0x1e, Id = 0x9, Port = 7
[3608] 10:22:15:115: >80 21 02 09 00 1C 02 06 00 2D 0F 01 03 06 04 E1 |.!.......-......|
[3608] 10:22:15:115: >5D 25 81 06 40 88 AD 04 83 06 40 88 A4 4C 00 00 |]%..@.....@..L..|
[3608] 06-04 10:22:15:115:  
[3608] 06-04 10:22:15:115: RemoveFromTimerQ called portid=2,Id=9,Protocol=8021,EventType=0,fAuth=0
[3608] 06-04 10:22:15:115: FsmThisLayerUp called for protocol = 8021, port = 7
[3608] 06-04 10:22:15:490: Notifying IPCP of projection notification
[3608] 06-04 10:22:15:490: Client: Ipv4 successful for Guid {ECC487F6-A513-4428-9818-52A39B694B5A}
[3608] 06-04 10:22:15:490: RemoveFromTimerQ called portid=2,Id=0,Protocol=0,EventType=3,fAuth=0
[3608] 06-04 10:22:15:490: NotifyCaller(hPort=7, dwMsgId=4)
[3608] 06-04 10:22:15:490: NotifyCaller(hPort=7, dwMsgId=0)
[3608] 06-04 10:22:15:490: Inserting autodisconnect in timer q for port=7, sec=1200
[3608] 06-04 10:22:15:490: RemoveFromTimerQ called portid=2,Id=0,Protocol=0,EventType=1,fAuth=0
[3608] 06-04 10:22:15:490: InsertInTimerQ called portid=2,Id=0,Protocol=0,EventType=1,fAuth=0