PPP disconnect on Win2k Hangs...

From: "Pavan" <pavanchebbi@xxxxxxxxx>
Date: 21 Aug 2006 05:32:23 -0700

Hi All,

I have my own serial driver which creates virtual port and I can
connect to another machine using PPP. The PPP connection works fine in
case of Win XP but in 2k when I disconnect the PPP, the DUN app hangs
and does not disconnect.

Surprisingly, in XP the connect and disconnect happen perfectly fine.

Can anybody throw some light on this problem which I am facing.

Would be glad if anyone can help.

Below I am pasting the PPP.log file's contents in case that be of any
help.

Thanks in advance,

Pavan. ( sorry for a lengthy posting )

PPP.log contents:

[1124] 17:49:17:250: PPPEMSG_Start recvd, d=,
hPort=7,callback=0,mask=1060a,IfType=-1
[1300] 17:49:17:265: Line up event occurred on port 7
[1300] 17:49:17:265: Local identification = MSRAS-1-DT-TEST2
[1300] 17:49:17:265: PortName: COM3
[1300] 17:49:17:265: Starting PPP on link with
IfType=0xffffffff,IPIf=0xffffffff,IPXIf=0xffffffff
[1300] 17:49:17:265: RasGetBuffer returned 15c280 for SendBuf
[1300] 17:49:17:265: FsmInit called for protocol = c021, port = 7
[1300] 17:49:17:265: ConfigInfo = 1060a
[1300] 17:49:17:265: APs available = 40
[1300] 17:49:17:265: FsmReset called for protocol = c021, port = 7
[1300] 17:49:17:265: Inserting port in bucket # 7
[1300] 17:49:17:265: Inserting bundle in bucket # 1
[1300] 17:49:17:265: FsmOpen event received for protocol c021 on port 7
[1300] 17:49:17:265: FsmThisLayerStarted called for protocol = c021,
port = 7
[1300] 17:49:17:265: FsmUp event received for protocol c021 on port 7
[1300] 17:49:17:265: <PPP packet sent at 08/21/2006 12:19:17:265
[1300] 17:49:17:265: <Protocol = LCP, Type = Configure-Req, Length =
0x34, Id = 0x0, Port = 7
[1300] 17:49:17:265: <C0 21 01 00 00 32 02 06 00 00 00 00 05 06 51 CA
|.!...2........Q.|
[1300] 17:49:17:265: <71 70 07 02 08 02 0D 03 06 11 04 06 4E 13 17 01
|qp..........N...|
[1300] 17:49:17:265: <DD 8D 16 1A 06 96 49 7A BA 23 A9 7A ED B5 AA 3F
|......Iz.#.z...?|
[1300] 17:49:17:265: <00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:17:265:
[1300] 17:49:17:343: InsertInTimerQ called
portid=0,Id=0,Protocol=c021,EventType=0,fAuth=0
[1300] 17:49:17:343: InsertInTimerQ called
portid=0,Id=0,Protocol=0,EventType=3,fAuth=0
[1132] 17:49:17:593: Packet received (36 bytes) for hPort 7
[1300] 17:49:17:593: >PPP packet received at 08/21/2006 12:19:17:593
[1300] 17:49:17:593: >Protocol = LCP, Type = Configure-Reject, Length =
0x24, Id = 0x0, Port = 7
[1300] 17:49:17:609: >C0 21 04 00 00 22 0D 03 06 11 04 06 4E 13 17 01
|.!..."......N...|
[1300] 17:49:17:609: >DD 8D 16 1A 06 96 49 7A BA 23 A9 7A ED B5 AA 3F
|......Iz.#.z...?|
[1300] 17:49:17:609: >00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:17:609:
[1300] 17:49:17:609: RemoveFromTimerQ called
portid=0,Id=0,Protocol=c021,EventType=0,fAuth=0
[1300] 17:49:17:609: <PPP packet sent at 08/21/2006 12:19:17:609
[1300] 17:49:17:609: <Protocol = LCP, Type = Configure-Req, Length =
0x16, Id = 0x1, Port = 7
[1300] 17:49:17:609: <C0 21 01 01 00 14 02 06 00 00 00 00 05 06 51 CA
|.!............Q.|
[1300] 17:49:17:609: <71 70 07 02 08 02 00 00 00 00 00 00 00 00 00 00
|qp..............|
[1300] 17:49:17:609:
[1300] 17:49:17:687: InsertInTimerQ called
portid=0,Id=1,Protocol=c021,EventType=0,fAuth=0
[1132] 17:49:17:921: Packet received (22 bytes) for hPort 7
[1300] 17:49:17:921: >PPP packet received at 08/21/2006 12:19:17:921
[1300] 17:49:17:921: >Protocol = LCP, Type = Configure-Ack, Length =
0x16, Id = 0x1, Port = 7
[1300] 17:49:17:921: >C0 21 02 01 00 14 02 06 00 00 00 00 05 06 51 CA
|.!............Q.|
[1300] 17:49:17:921: >71 70 07 02 08 02 00 00 00 00 00 00 00 00 00 00
|qp..............|
[1300] 17:49:17:921:
[1300] 17:49:17:921: RemoveFromTimerQ called
portid=0,Id=1,Protocol=c021,EventType=0,fAuth=0
[1132] 17:49:20:546: Packet received (24 bytes) for hPort 7
[1300] 17:49:20:546: >PPP packet received at 08/21/2006 12:19:20:546
[1300] 17:49:20:546: >Protocol = LCP, Type = Configure-Req, Length =
0x18, Id = 0x10, Port = 7
[1300] 17:49:20:546: >C0 21 01 10 00 16 01 04 05 DC 03 04 C0 23 05 06
|.!...........#..|
[1300] 17:49:20:546: >00 00 05 A2 07 02 08 02 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:20:546:
[1300] 17:49:20:546: <PPP packet sent at 08/21/2006 12:19:20:546
[1300] 17:49:20:546: <Protocol = LCP, Type = Configure-Ack, Length =
0x18, Id = 0x10, Port = 7
[1300] 17:49:20:546: <C0 21 02 10 00 16 01 04 05 DC 03 04 C0 23 05 06
|.!...........#..|
[1300] 17:49:20:546: <00 00 05 A2 07 02 08 02 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:20:546:
[1300] 17:49:20:625: FsmThisLayerUp called for protocol = c021, port =
7
[1300] 17:49:20:625: LCP Local Options-------------
[1300] 17:49:20:625:
MRU=1500,ACCM=0,Auth=0,MagicNumber=1372221808,PFC=ON,ACFC=ON
[1300] 17:49:20:625: Recv Framing =
PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0,BAP=OFF
[1300] 17:49:20:625: LCP Remote Options-------------
[1300] 17:49:20:625:
MRU=1500,ACCM=-1,Auth=c023,MagicNumber=1442,PFC=ON,ACFC=ON
[1300] 17:49:20:625: Send Framing =
PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[1300] 17:49:20:625: LCP Configured successfully
[1300] 17:49:20:625: <PPP packet sent at 08/21/2006 12:19:20:625
[1300] 17:49:20:625: <Protocol = LCP, Type = Identification, Length =
0x14, Id = 0x2, Port = 7
[1300] 17:49:20:625: <C0 21 0C 02 00 12 51 CA 71 70 4D 53 52 41 53 56
|.!....Q.qpMSRASV|
[1300] 17:49:20:625: <35 2E 30 30 00 00 00 00 00 00 00 00 00 00 00 00
|5.00............|
[1300] 17:49:20:625:
[1132] 17:49:20:703: Packet received (10 bytes) for hPort 7
[1300] 17:49:20:703: <PPP packet sent at 08/21/2006 12:19:20:703
[1300] 17:49:20:703: <Protocol = LCP, Type = Identification, Length =
0x1a, Id = 0x3, Port = 7
[1300] 17:49:20:703: <C0 21 0C 03 00 18 51 CA 71 70 4D 53 52 41 53 2D
|.!....Q.qpMSRAS-|
[1300] 17:49:20:703: <31 2D 44 54 2D 54 45 53 54 32 00 00 00 00 00 00
|1-DT-TEST2......|
[1300] 17:49:20:703:
[1132] 17:49:20:781: Packet received (24 bytes) for hPort 7
[1300] 17:49:20:781: Authenticating phase started
[1300] 17:49:20:781: <PPP packet sent at 08/21/2006 12:19:20:781
[1300] 17:49:20:781: <Protocol = PAP, Type = Protocol specific, Length
= 0x16, Id = 0x0, Port = 7
[1300] 17:49:20:781:
[1132] 17:49:20:859: Packet received (30 bytes) for hPort 7
[1300] 17:49:20:859: InsertInTimerQ called
portid=0,Id=0,Protocol=c023,EventType=0,fAuth=0
[1300] 17:49:20:859: >PPP packet received at 08/21/2006 12:19:20:859
[1300] 17:49:20:859: >Protocol = LCP, Type = Echo-Request, Length =
0xa, Id = 0x0, Port = 7
[1300] 17:49:20:859: >C0 21 09 00 00 08 00 00 05 A2 00 00 00 00 00 00
|.!..............|
[1300] 17:49:20:859:
[1300] 17:49:20:859: <PPP packet sent at 08/21/2006 12:19:20:859
[1300] 17:49:20:859: <Protocol = LCP, Type = Echo-Reply, Length = 0xa,
Id = 0x0, Port = 7
[1300] 17:49:20:859: <C0 21 0A 00 00 08 51 CA 71 70 00 00 00 00 00 00
|.!....Q.qp......|
[1300] 17:49:20:859:
[1132] 17:49:20:937: Packet received (15 bytes) for hPort 7
[1132] 17:49:20:937: Packet received (12 bytes) for hPort 7
[1300] 17:49:20:937: >PPP packet received at 08/21/2006 12:19:20:937
[1300] 17:49:20:937: >Protocol = LCP, Type = Code-Reject, Length =
0x18, Id = 0x11, Port = 7
[1300] 17:49:20:937: >C0 21 07 11 00 16 0C 02 00 12 51 CA 71 70 4D 53
|.!........Q.qpMS|
[1300] 17:49:20:937: >52 41 53 56 35 2E 30 30 00 00 00 00 00 00 00 00
|RASV5.00........|
[1300] 17:49:20:937:
[1300] 17:49:20:937: PPP Code Reject rcvd, rejected Code = 12
[1300] 17:49:20:937: >PPP packet received at 08/21/2006 12:19:20:937
[1300] 17:49:20:937: >Protocol = LCP, Type = Code-Reject, Length =
0x1e, Id = 0x12, Port = 7
[1300] 17:49:20:937: >C0 21 07 12 00 1C 0C 03 00 18 51 CA 71 70 4D 53
|.!........Q.qpMS|
[1300] 17:49:20:937: >52 41 53 2D 31 2D 44 54 2D 54 45 53 54 32 00 00
|RAS-1-DT-TEST2..|
[1300] 17:49:20:937:
[1300] 17:49:20:937: PPP Code Reject rcvd, rejected Code = 12
[1300] 17:49:20:937: >PPP packet received at 08/21/2006 12:19:20:937
[1300] 17:49:20:937: >Protocol = PAP, Type = Protocol specific, Length
= 0xf, Id = 0x0, Port = 7
[1300] 17:49:20:937:
[1300] 17:49:20:937: RemoveFromTimerQ called
portid=0,Id=0,Protocol=c023,EventType=0,fAuth=0
[1300] 17:49:20:937: FsmThisLayerUp called for protocol = c023, port =
7
[1300] 17:49:20:937: NotifyCaller(hPort=7, dwMsgId=17)
[1300] 17:49:20:937: NotifyCaller(hPort=7, dwMsgId=3)
[1300] 17:49:20:937: Will not initialize CP 8029
[1300] 17:49:20:937: Will not initialize CP 803f
[1300] 17:49:20:937: Will not initialize CP 802b
[1300] 17:49:20:937: FsmInit called for protocol = 80fd, port = 7
[1300] 17:49:20:937: FsmReset called for protocol = 80fd, port = 7
[1300] 17:49:20:937: FsmInit called for protocol = 8021, port = 7
[1300] 17:49:20:937: FsmReset called for protocol = 8021, port = 7
[1300] 17:49:20:937: FsmOpen event received for protocol 80fd on port 7
[1300] 17:49:20:937: FsmThisLayerStarted called for protocol = 80fd,
port = 7
[1300] 17:49:20:937: FsmUp event received for protocol 80fd on port 7
[1300] 17:49:20:937: <PPP packet sent at 08/21/2006 12:19:20:937
[1300] 17:49:20:937: <Protocol = CCP, Type = Configure-Req, Length =
0xc, Id = 0x4, Port = 7
[1300] 17:49:20:937: <80 FD 01 04 00 0A 12 06 00 00 00 01 00 00 00 00
|................|
[1300] 17:49:20:937:
[1300] 17:49:21:015: InsertInTimerQ called
portid=0,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1300] 17:49:21:015: FsmOpen event received for protocol 8021 on port 7
[1300] 17:49:21:015: FsmThisLayerStarted called for protocol = 8021,
port = 7
[1300] 17:49:21:015: FsmUp event received for protocol 8021 on port 7
[1300] 17:49:21:015: <PPP packet sent at 08/21/2006 12:19:21:015
[1300] 17:49:21:015: <Protocol = IPCP, Type = Configure-Req, Length =
0x2a, Id = 0x5, Port = 7
[1300] 17:49:21:015: <80 21 01 05 00 28 02 06 00 2D 0F 01 03 06 00 00
|.!...(...-......|
[1300] 17:49:21:015: <00 00 81 06 00 00 00 00 82 06 00 00 00 00 83 06
|................|
[1300] 17:49:21:015: <00 00 00 00 84 06 00 00 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:21:015:
[1300] 17:49:21:015: InsertInTimerQ called
portid=0,Id=5,Protocol=8021,EventType=0,fAuth=0
[1300] 17:49:21:015: >PPP packet received at 08/21/2006 12:19:21:015
[1300] 17:49:21:015: >Protocol = IPCP, Type = Configure-Req, Length =
0xc, Id = 0xec, Port = 7
[1300] 17:49:21:015: >80 21 01 EC 00 0A 03 06 5A 00 00 01 00 00 00 00
|.!......Z.......|
[1300] 17:49:21:015:
[1300] 17:49:21:015: <PPP packet sent at 08/21/2006 12:19:21:015
[1300] 17:49:21:015: <Protocol = IPCP, Type = Configure-Ack, Length =
0xc, Id = 0xec, Port = 7
[1300] 17:49:21:015: <80 21 02 EC 00 0A 03 06 5A 00 00 01 00 00 00 00
|.!......Z.......|
[1300] 17:49:21:015:
[1132] 17:49:21:234: Packet received (18 bytes) for hPort 7
[1300] 17:49:21:234: >PPP packet received at 08/21/2006 12:19:21:234
[1300] 17:49:21:234: >Protocol = LCP, Type = Protocol-Reject, Length =
0x12, Id = 0x13, Port = 7
[1300] 17:49:21:234: >C0 21 08 13 00 10 80 FD 01 04 00 0A 12 06 00 00
|.!..............|
[1300] 17:49:21:234: >00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:21:234:
[1300] 17:49:21:234: PPP Protocol Reject, Protocol = 80fd
[1300] 17:49:21:234: RemoveFromTimerQ called
portid=0,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1300] 17:49:21:234: FsmThisLayerFinished called for protocol = 80fd,
port = 7
[1132] 17:49:21:390: Packet received (36 bytes) for hPort 7
[1300] 17:49:21:390: >PPP packet received at 08/21/2006 12:19:21:390
[1300] 17:49:21:390: >Protocol = IPCP, Type = Configure-Reject, Length
= 0x24, Id = 0x5, Port = 7
[1300] 17:49:21:390: >80 21 04 05 00 22 02 06 00 2D 0F 01 81 06 00 00
|.!..."...-......|
[1300] 17:49:21:390: >00 00 82 06 00 00 00 00 83 06 00 00 00 00 84 06
|................|
[1300] 17:49:21:390: >00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:21:390:
[1300] 17:49:21:390: RemoveFromTimerQ called
portid=0,Id=5,Protocol=8021,EventType=0,fAuth=0
[1300] 17:49:21:390: <PPP packet sent at 08/21/2006 12:19:21:390
[1300] 17:49:21:390: <Protocol = IPCP, Type = Configure-Req, Length =
0xc, Id = 0x6, Port = 7
[1300] 17:49:21:390: <80 21 01 06 00 0A 03 06 00 00 00 00 00 00 00 00
|.!..............|
[1300] 17:49:21:390:
[1300] 17:49:21:468: InsertInTimerQ called
portid=0,Id=6,Protocol=8021,EventType=0,fAuth=0
[1132] 17:49:21:609: Packet received (12 bytes) for hPort 7
[1300] 17:49:21:609: >PPP packet received at 08/21/2006 12:19:21:609
[1300] 17:49:21:609: >Protocol = IPCP, Type = Configure-Nak, Length =
0xc, Id = 0x6, Port = 7
[1300] 17:49:21:609: >80 21 03 06 00 0A 03 06 5A 00 00 16 00 00 00 00
|.!......Z.......|
[1300] 17:49:21:609:
[1300] 17:49:21:609: RemoveFromTimerQ called
portid=0,Id=6,Protocol=8021,EventType=0,fAuth=0
[1300] 17:49:21:609: <PPP packet sent at 08/21/2006 12:19:21:609
[1300] 17:49:21:609: <Protocol = IPCP, Type = Configure-Req, Length =
0xc, Id = 0x7, Port = 7
[1300] 17:49:21:609: <80 21 01 07 00 0A 03 06 5A 00 00 16 00 00 00 00
|.!......Z.......|
[1300] 17:49:21:609:
[1300] 17:49:21:687: InsertInTimerQ called
portid=0,Id=7,Protocol=8021,EventType=0,fAuth=0
[1132] 17:49:21:828: Packet received (12 bytes) for hPort 7
[1300] 17:49:21:828: >PPP packet received at 08/21/2006 12:19:21:828
[1300] 17:49:21:828: >Protocol = IPCP, Type = Configure-Ack, Length =
0xc, Id = 0x7, Port = 7
[1300] 17:49:21:828: >80 21 02 07 00 0A 03 06 5A 00 00 16 00 00 00 00
|.!......Z.......|
[1300] 17:49:21:828:
[1300] 17:49:21:828: RemoveFromTimerQ called
portid=0,Id=7,Protocol=8021,EventType=0,fAuth=0
[1300] 17:49:21:828: FsmThisLayerUp called for protocol = 8021, port =
7
[1300] 17:49:28:562: Notifying IPCP of projection notification
[1300] 17:49:28:875: RemoveFromTimerQ called
portid=0,Id=0,Protocol=0,EventType=3,fAuth=0
[1300] 17:49:28:875: NotifyCaller(hPort=7, dwMsgId=4)
[1300] 17:49:28:875: NotifyCaller(hPort=7, dwMsgId=0)
[1300] 17:49:28:875: Inserting autodisconnect in timer q for port=7,
sec=-1
[1300] 17:49:28:875: RemoveFromTimerQ called
portid=0,Id=0,Protocol=0,EventType=1,fAuth=0
[1300] 17:49:28:875: InsertInTimerQ called
portid=0,Id=0,Protocol=0,EventType=1,fAuth=0
[1124] 17:49:32:140: PppStop

[1124] 17:49:32:140: PPPEMSG_Stop recvd

[1300] 17:49:41:031: FsmClose event received for protocol c021 on port
7
[1300] 17:49:41:031: RemoveFromTimerQ called
portid=0,Id=1,Protocol=c021,EventType=0,fAuth=0
[1300] 17:49:41:031: FsmThisLayerDown called for protocol = c021, port
= 7
[1300] 17:49:41:031: FsmDown event received for protocol 80fd on port 7
[1300] 17:49:41:031: RemoveFromTimerQ called
portid=0,Id=4,Protocol=80fd,EventType=0,fAuth=0
[1300] 17:49:41:031: FsmThisLayerStarted called for protocol = 80fd,
port = 7
[1300] 17:49:41:031: FsmReset called for protocol = 80fd, port = 7
[1300] 17:49:41:031: FsmDown event received for protocol 8021 on port 7
[1300] 17:49:41:031: RemoveFromTimerQ called
portid=0,Id=7,Protocol=8021,EventType=0,fAuth=0
[1300] 17:49:41:031: FsmThisLayerDown called for protocol = 8021, port
= 7
[1300] 17:49:41:031: FsmReset called for protocol = 8021, port = 7
[1300] 17:49:41:031: RemoveFromTimerQ called
portid=0,Id=0,Protocol=c023,EventType=0,fAuth=0
[1300] 17:49:41:031: RemoveFromTimerQ called
portid=0,Id=0,Protocol=c029,EventType=0,fAuth=0
[1300] 17:49:41:031: <PPP packet sent at 08/21/2006 12:19:41:031
[1300] 17:49:41:031: <Protocol = LCP, Type = Terminate-Req, Length =
0x12, Id = 0x8, Port = 7
[1300] 17:49:41:031: <C0 21 05 08 00 10 51 CA 71 70 00 3C CD 74 00 00
|.!....Q.qp.<.t..|
[1300] 17:49:41:031: <00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
|................|
[1300] 17:49:41:031:
[1300] 17:49:41:031: InsertInTimerQ called
portid=0,Id=8,Protocol=c021,EventType=0,fAuth=0
[1132] 17:49:41:187: Packet received (6 bytes) for hPort 7
[1300] 17:49:41:187: >PPP packet received at 08/21/2006 12:19:41:187
[1300] 17:49:41:187: >Protocol = LCP, Type = Terminate-Ack, Length =
0x6, Id = 0x8, Port = 7
[1300] 17:49:41:187: >C0 21 06 08 00 04 00 00 00 00 00 00 00 00 00 00
|.!..............|
[1300] 17:49:41:187:
[1300] 17:49:41:187: RemoveFromTimerQ called
portid=0,Id=8,Protocol=c021,EventType=0,fAuth=0
[1300] 17:49:41:187: FsmThisLayerFinished called for protocol = c021,
port = 7
[1300] 17:49:41:187: FsmThisLayerFinished called for protocol = 80fd,
port = 7: 0
[1300] 17:49:41:203: FsmThisLayerFinished called for protocol = 8021,
port = 7: 0
[1300] 17:49:41:203: NotifyCaller(hPort=7, dwMsgId=10)

.

Prev by Date: PPP tunnel on XP
Next by Date: Peer terminates PPP connection after establishing (successful?) a connection
Previous by thread: PPP tunnel on XP
Next by thread: Peer terminates PPP connection after establishing (successful?) a connection
Index(es):
- Date
- Thread

Relevant Pages

Re: Correction
... Normally to physically disconnect is just a matter of reaching for the ... >> I have an ADSL connection which polls my computer from time to time, ... > disallow each and every port with Windows Firewall? ...
(microsoft.public.windowsxp.messenger)
Re: logitech wireless mouse works for a while but then disconnects
... Also, for some reason every so often, I have to physically disconnect and ... My USB Controller is: ... ums0: ... > port 1 powered ...
(freebsd-current)
Re: Port Scans
... >I only had one time I was suspicious of some port scans. ... >disconnect from the net, and run a Trojan scan to be on the safe side? ... >E-mail addy used is invalid to prevent spam. ... Can an outsider penetrate a firewall that has no open ...
(comp.security.firewalls)
[patch 13/18] USB: serial: fix lifetime and locking problems
... This patch fixes a few lifetime and locking problems in the ... Each open file should hold a reference to the corresponding ... port structure, and the reference should be released when ... resolve the classic race between open and disconnect. ...
(Linux-Kernel)
Re: GPRS internet Wince5.0
... I doubt that it will disconnect when it finds an ATH in that stream. ... If the rasdial says that the port is not available, ... If the modem isn't in "command ... I assume you can see the status of the dialup connection. ...
(microsoft.public.windowsce.platbuilder)