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: [1300] 17:49:17:265: 0x34, Id = 0x0, Port = 7
[1300] 17:49:17:265: |.!...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:
|......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: [1300] 17:49:17:609: 0x16, Id = 0x1, Port = 7
[1300] 17:49:17:609: |.!............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: [1300] 17:49:20:546: 0x18, Id = 0x10, Port = 7
[1300] 17:49:20:546: |.!...........#..|
[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: [1300] 17:49:20:625: 0x14, Id = 0x2, Port = 7
[1300] 17:49:20:625: |.!....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: [1300] 17:49:20:703: 0x1a, Id = 0x3, Port = 7
[1300] 17:49:20:703: |.!....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: [1300] 17:49:20:781: = 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: [1300] 17:49:20:859: Id = 0x0, Port = 7
[1300] 17:49:20:859: |.!....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: [1300] 17:49:20:937: 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: [1300] 17:49:21:015: 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: [1300] 17:49:21:015: 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: [1300] 17:49:21:390: 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: [1300] 17:49:21:609: 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: [1300] 17:49:41:031: 0x12, Id = 0x8, Port = 7
[1300] 17:49:41:031: |.!....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)