VPN won't connect

[Bruce Wilkinson]

I'm running Vista Ultimate and trying to VPN in to a Windows 2003 server
using MS VPN. The server side is setup and functional. Windows XP clients
can connect to it. On this client, I get "Error 800: Unable to establish the
VPN connection..." If I run diagnostics on the connection, I get "Cannot
load TAPI information. Error 0x80000048. There was an unknown error."

How can I diagnose the TAPI problem?

Bruce

 

[Lastone]

Same problem here

 

[Lastone]

I'm running Vista Ultimate and trying to VPN in to a Windows 2003 server
using MS VPN. The server side is setup and functional. Windows XP clients
can connect to it. On this client, I get "Error 800: Unable to establish the
VPN connection..." If I run diagnostics on the connection, I get "Cannot
load TAPI information. Error 0x80000048. There was an unknown error."

How can I diagnose the TAPI problem?

Bruce

Same problem here..!

 

[Bruce Wilkinson]

I pulled up the RASMAN.LOG file from Windows\tracing and stipped off parts
of it. The full log is over 900K. I can provide the full file if needed. The
condensed file follows. Can anyone help me resolve this?

Thanks,
Bruce

[6060] 01-31 14:07:10:073: GetParamsListFromLsa Default=0
[6060] 01-31 14:07:10:086: GetParamsListFromLsa. 0x0
[6060] 01-31 14:07:10:099: GetParamsListFromLsa Default=1
[6060] 01-31 14:07:10:100: GetParamsListFromLsa. 0x2
[6060] 01-31 14:07:10:112: GetParamsListFromLsa Default=0
[6060] 01-31 14:07:10:114: GetParamsListFromLsa. 0x0
[6060] 01-31 14:07:10:114: CreateConnection: entry=VPN - mydomain.com,
pbk=C:\Users\bruce.wilkinson.ITS\AppData\Roaming\Microsoft\Network\Connections\Pbk\rasphone.pbk
[6060] 01-31 14:07:10:114: Info: fSecureRoutingCompartment: 0 and
dwConSessionId 1 old Compartment ID 1:
[6060] 01-31 14:07:10:115: SetRasmanServiceStopControl: Enabled 0
[6060] 01-31 14:07:10:115: CreateConnection: Created new connection.
hconn=0x10000, ref=1, pConn=0x43adf50
[6060] 01-31 14:07:10:161: IsTrustedCustomDll: pwsz=, fTrusted=0, rc=0
[6060] 01-31 14:07:10:170: PortOpenEx: WAN Miniport (PPTP)
[6060] 01-31 14:07:10:389: SetRasmanServiceStopControl: Enabled 0
[6060] 01-31 14:07:10:389: PortOpen (2, VPN1-1) OpenInstances = (0)
[6060] 01-31 14:07:10:389: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 2 state chg: prev=0, new=4
[6060] 01-31 14:07:10:389: PortOpenEx (2) : OpenInstances = 1
[6060] 01-31 14:07:10:389: PortOpenEx: rc=0x0. DeviceFound=1
[6060] 01-31 14:07:10:390: CleanUpDeadClientProcessBlock
[6060] 01-31 14:07:10:390: SetDialMachineEventHandleCommon:
[6060] 01-31 14:07:10:390: SetDialMachineEventHandleCommon:The process is
never added, something wrong in the Ras Initialization
[6060] 01-31 14:07:10:391: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=2, dwSubEntry=1
[6060] 01-31 14:07:17:816: ReferenceCustomCount
[6060] 01-31 14:07:17:816: ReferenceCustomCount done. 0
[6060] 01-31 14:07:17:817: RefConnection: 0x10000
[6060] 01-31 14:07:17:817: refcount=0, maxports=6, ports=1
[6060] 01-31 14:07:17:817: RefConnection: ref on 0x10000 = 0
[6060] 01-31 14:07:17:817: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[6060] 01-31 14:07:17:863: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[6060] 01-31 14:07:17:863: QueueCloseConnections: no dependent connections
[6060] 01-31 14:07:17:863: PortDisconnectRequest on VPN1-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[6060] 01-31 14:07:17:863: PortDisconnectRequest: Disconnecting VPN1-1
[6060] 01-31 14:07:17:863: Disconnecting Port 0xVPN1-1, reason 0
[6060] 01-31 14:07:17:863: QueueCloseConnections: no dependent connections
[6060] 01-31 14:07:17:864: link handle for VPN1-1 = INVALID_HANDLE_VALUE
[6060] 01-31 14:07:17:864: 4. Notifying of disconnect on port 2
[6060] 01-31 14:07:17:864: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 2 state chg: prev=4, new=4
[6060] 01-31 14:07:17:864: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 2 async reqtype chg: prev=0, new=0
[6060] 01-31 14:07:17:864: CompleteDisconnectRequest: signalling 0x2b1c for
VPN1-1
[6060] 01-31 14:07:17:864: SignalPortDisconnect: pOverlapped=0x78f38ec
[6060] 01-31 14:07:17:864: PostDialEventContext: for pid:0x1730, client
Event:0x132ccontext pointer=0x78f38ec, type=1
[6060] 01-31 14:07:17:864: AppendNewMsgToQueue:Set client Event:0x132c
[6060] 01-31 14:07:17:864: Disconnect completed on port: VPN1-1
[6060] 01-31 14:07:17:864: Disconnect request on port: VPN1-1
[6060] 01-31 14:07:17:864: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[6060] 01-31 14:07:17:864: PortClose: port (2). OpenInstances = 1
[6060] 01-31 14:07:17:864: PortClose: going to clear the rasapi32 event
handle
[6060] 01-31 14:07:17:864: SetDialMachineEventHandleCommon:
[6060] 01-31 14:07:17:864: SetDialEventHandleCommon: posting last event for
port 2
[6060] 01-31 14:07:17:864: PostDialEventContext: for pid:0x1730, client
Event:0x132ccontext pointer=0x78f394c, type=4
[6060] 01-31 14:07:17:864: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[6060] 01-31 14:07:17:864: Freeing the notifier list for port 2
[6060] 01-31 14:07:17:864: PortClose (2). OpenInstances = 0
[6060] 01-31 14:07:17:871: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 2 async reqtype chg: prev=0, new=0
[6060] 01-31 14:07:17:871: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 2 state chg: prev=4, new=4
[6060] 01-31 14:07:17:871: RemoveConnectionPort: port 2, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[6060] 01-31 14:07:17:871: SendSensNotification(_RAS_DISCONNECT) for
0x00010000 returns 0x00000000
[6060] 01-31 14:07:17:873: DwSendNotificationInternal(ENTRY_DISCONNECTED)
rc=0x0
[6060] 01-31 14:07:17:873: RemoveConnectionPort: FreeConnection
hconn=0x10000, pconn=0x43adf50, AutoClose=0
[6060] 01-31 14:07:17:873: FreeConnection: pConn=0x43adf50, 0
[6060] 01-31 14:07:17:873: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 2
[8156] 01-31 14:07:21:117: d:\vistartm\net\rras\ras\rasman\rasman\util.c:
2788: port 2 state chg: prev=4, new=0
[8156] 01-31 14:07:21:117: Connect request on port: VPN1-1, error code 633
[8156] 01-31 14:07:21:118: ReferenceCustomCount
[8156] 01-31 14:07:21:118: ReferenceCustomCount done. 668
[8156] 01-31 14:07:21:118: Connection 0x00010000 not found
[8156] 01-31 14:07:21:118: GetHportFromConnection: connection 0x00010000 not
found.
[8156] 01-31 14:07:21:118: RefConnection: 0x10000
[8156] 01-31 14:07:21:118: RefConnection: pConn = NULL for 0x10000
[8156] 01-31 14:07:21:119: CleanUpDeadClientProcessBlock
[8156] 01-31 14:07:21:119: SetDialMachineEventHandleCommon:
[8156] 01-31 14:07:21:119: SetDialMachineEventHandleCommon:Error:
meaningless operation
[8156] 01-31 14:07:21:119: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x132c
[8156] 01-31 14:07:21:119: PickOneMsgFromQueueList:
[8156] 01-31 14:07:21:119: PickOneMsgFromQueueList:Set client Event:0x132c
[8156] 01-31 14:07:21:119: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x132c
[8156] 01-31 14:07:21:119: PickOneMsgFromQueueList:
[8156] 01-31 14:07:53:877: SetEntryDialParams: Removing uid=13017456 from
lsa
[8156] 01-31 14:07:53:877: SetEntryDialParams: Writing to fDefault=0
[5156] 01-31 14:07:54:880: DwDeleteInterface: Failed to delte
System\CurrentControlSet\Services\Tcpip\Parameters\Interfaces\, 0x2
[5156] 01-31 14:07:54:880: DwDeleteInterface: Failed to delte
System\CurrentControlSet\Services\NetBT\Parameters\Interfaces\, 0x2
[5156] 01-31 14:07:54:911: SendNotificationRequest
[5156] 01-31 14:07:54:914: DwSendNotification returned 0x0
[5156] 01-31 14:08:48:943: SendNotificationRequest
[5156] 01-31 14:08:48:945: DwSendNotification returned 0x0
[5012] 01-31 14:08:50:416: GetParamsListFromLsa Default=0
[5012] 01-31 14:08:50:416: GetParamsListFromLsa. 0x2
[5012] 01-31 14:08:50:416: GetParamsListFromLsa Default=1
[5012] 01-31 14:08:50:417: GetParamsListFromLsa. 0x2
[5012] 01-31 14:08:50:417: CreateConnection: entry=VPN Connection,

pbk=C:\Users\bruce.wilkinson.ITS\AppData\Roaming\Microsoft\Network\Connections\Pbk\rasphone.pbk
[5012] 01-31 14:08:50:417: Info: fSecureRoutingCompartment: 0 and
dwConSessionId 1 old Compartment ID 1:
[5012] 01-31 14:08:50:417: SetRasmanServiceStopControl: Enabled 0
[5012] 01-31 14:08:50:417: CreateConnection: Created new connection.
hconn=0x20000, ref=1, pConn=0x43adf50
[5012] 01-31 14:08:50:420: IsTrustedCustomDll: pwsz=, fTrusted=0, rc=0
[5012] 01-31 14:08:50:421: PortOpenEx: WAN Miniport (PPTP)
[5012] 01-31 14:08:50:441: SetRasmanServiceStopControl: Enabled 0
[5012] 01-31 14:08:50:441: PortOpen (2, VPN1-1) OpenInstances = (0)
[5012] 01-31 14:08:50:441: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 2 state chg: prev=0, new=4
[5012] 01-31 14:08:50:441: PortOpenEx (2) : OpenInstances = 1
[5012] 01-31 14:08:50:441: PortOpenEx: rc=0x0. DeviceFound=1
[5012] 01-31 14:08:50:442: CleanUpDeadClientProcessBlock
[5012] 01-31 14:08:50:442: SetDialMachineEventHandleCommon:
[5012] 01-31 14:08:50:442: Cleaning up process 5936
[5012] 01-31 14:08:50:442: CleanUpEventQueue:
[5012] 01-31 14:08:50:442: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=2, dwSubEntry=1
[5012] 01-31 14:08:54:950: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[5012] 01-31 14:08:54:951: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[5012] 01-31 14:08:54:951: QueueCloseConnections: no dependent connections
[5012] 01-31 14:08:54:951: PortDisconnectRequest on VPN1-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[5012] 01-31 14:08:54:951: PortDisconnectRequest: Disconnecting VPN1-1
[5012] 01-31 14:08:54:951: Disconnecting Port 0xVPN1-1, reason 0
[5012] 01-31 14:08:54:951: QueueCloseConnections: no dependent connections
[5012] 01-31 14:08:54:951: link handle for VPN1-1 = INVALID_HANDLE_VALUE
[5012] 01-31 14:08:54:951: 4. Notifying of disconnect on port 2
[5012] 01-31 14:08:54:951: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 2 state chg: prev=4, new=4
[5012] 01-31 14:08:54:951: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 2 async reqtype chg: prev=0, new=0
[5012] 01-31 14:08:54:951: CompleteDisconnectRequest: signalling 0x2f40 for
VPN1-1
[5012] 01-31 14:08:54:951: SignalPortDisconnect: pOverlapped=0x65d1a1c
[5012] 01-31 14:08:54:951: PostDialEventContext: for pid:0x1730, client
Event:0x116ccontext pointer=0x65d1a1c, type=1
[5012] 01-31 14:08:54:951: AppendNewMsgToQueue:Set client Event:0x116c
[5012] 01-31 14:08:54:951: Disconnect completed on port: VPN1-1
[5012] 01-31 14:08:54:951: Disconnect request on port: VPN1-1
[5156] 01-31 14:08:54:951: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[5156] 01-31 14:08:54:951: PortClose: port (2). OpenInstances = 1
[5156] 01-31 14:08:54:951: PortClose: going to clear the rasapi32 event
handle
[5156] 01-31 14:08:54:951: SetDialMachineEventHandleCommon:
[5156] 01-31 14:08:54:952: SetDialEventHandleCommon: posting last event for
port 2
[5156] 01-31 14:08:54:952: PostDialEventContext: for pid:0x1730, client
Event:0x116ccontext pointer=0x65d1a7c, type=4
[5156] 01-31 14:08:54:952: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[5156] 01-31 14:08:54:952: Freeing the notifier list for port 2
[5156] 01-31 14:08:54:952: PortClose (2). OpenInstances = 0
[5156] 01-31 14:08:54:956: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 2 async reqtype chg: prev=0, new=0
[5156] 01-31 14:08:54:956: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 2 state chg: prev=4, new=4
[5156] 01-31 14:08:54:956: RemoveConnectionPort: port 2, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[5156] 01-31 14:08:54:956: Connection not freed for 0x20000! CB_Ports=0,
CB_Ref=1
[5156] 01-31 14:08:54:956: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 2
[5156] 01-31 14:08:54:957: PortOpenEx: WAN Miniport (L2TP)
[5156] 01-31 14:08:55:056: SetRasmanServiceStopControl: Enabled 0
[5156] 01-31 14:08:55:056: PortOpen (0, VPN0-1) OpenInstances = (0)
[5156] 01-31 14:08:55:056: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 0 state chg: prev=0, new=4
[5156] 01-31 14:08:55:056: PortOpenEx (0) : OpenInstances = 1
[5156] 01-31 14:08:55:056: PortOpenEx: rc=0x0. DeviceFound=1
[5156] 01-31 14:08:55:057: CleanUpDeadClientProcessBlock
[5156] 01-31 14:08:55:057: SetDialMachineEventHandleCommon:
[5156] 01-31 14:08:55:057: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=0, dwSubEntry=1
[5156] 01-31 14:08:57:316: ReferenceCustomCount
[5156] 01-31 14:08:57:316: ReferenceCustomCount done. 0
[5156] 01-31 14:08:57:317: RefConnection: 0x20000
[5156] 01-31 14:08:57:317: refcount=0, maxports=6, ports=1
[5156] 01-31 14:08:57:317: RefConnection: ref on 0x20000 = 0
[5156] 01-31 14:08:57:317: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[5156] 01-31 14:08:57:318: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[5156] 01-31 14:08:57:318: QueueCloseConnections: no dependent connections
[5156] 01-31 14:08:57:318: PortDisconnectRequest on VPN0-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[5156] 01-31 14:08:57:318: PortDisconnectRequest: Disconnecting VPN0-1
[5156] 01-31 14:08:57:318: Disconnecting Port 0xVPN0-1, reason 0
[5156] 01-31 14:08:57:319: QueueCloseConnections: no dependent connections
[5156] 01-31 14:08:57:319: link handle for VPN0-1 = INVALID_HANDLE_VALUE
[5156] 01-31 14:08:57:319: 4. Notifying of disconnect on port 0
[5156] 01-31 14:08:57:319: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 0 state chg: prev=4, new=4
[5156] 01-31 14:08:57:319: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 0 async reqtype chg: prev=0, new=0
[5156] 01-31 14:08:57:319: CompleteDisconnectRequest: signalling 0x2a94 for
VPN0-1
[5156] 01-31 14:08:57:319: SignalPortDisconnect: pOverlapped=0x7949974
[5156] 01-31 14:08:57:319: PostDialEventContext: for pid:0x1730, client
Event:0x116ccontext pointer=0x7949974, type=1
[5156] 01-31 14:08:57:319: Disconnect completed on port: VPN0-1
[5156] 01-31 14:08:57:319: Disconnect request on port: VPN0-1
[5012] 01-31 14:08:57:319: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[5012] 01-31 14:08:57:319: PortClose: port (0). OpenInstances = 1
[5012] 01-31 14:08:57:319: PortClose: going to clear the rasapi32 event
handle
[5012] 01-31 14:08:57:319: SetDialMachineEventHandleCommon:
[5012] 01-31 14:08:57:319: SetDialEventHandleCommon: posting last event for
port 0
[5012] 01-31 14:08:57:319: PostDialEventContext: for pid:0x1730, client
Event:0x116ccontext pointer=0x79499d4, type=4
[5012] 01-31 14:08:57:319: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[5012] 01-31 14:08:57:319: Freeing the notifier list for port 0
[5012] 01-31 14:08:57:319: PortClose (0). OpenInstances = 0
[5012] 01-31 14:08:57:324: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 0 async reqtype chg: prev=0, new=0
[5012] 01-31 14:08:57:324: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 0 state chg: prev=4, new=4
[5012] 01-31 14:08:57:324: DwInitializeIpSec: fOnFailure set to 0
[5012] 01-31 14:08:57:370: DwInitializeIpSec: FwpmEngineOpen0 returned 0
[5012] 01-31 14:08:57:370: Deleting client ipsec filter on 0
[5012] 01-31 14:08:57:370: DwDeleteFilter for VPN0-1 returned 0x57
[5012] 01-31 14:08:57:370: RemoveConnectionPort: port 0, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[5012] 01-31 14:08:57:370: SendSensNotification(_RAS_DISCONNECT) for
0x00020000 returns 0x00000000
[5012] 01-31 14:08:57:371: DwSendNotificationInternal(ENTRY_DISCONNECTED)
rc=0x0
[5012] 01-31 14:08:57:371: RemoveConnectionPort: FreeConnection
hconn=0x20000, pconn=0x43adf50, AutoClose=0
[5012] 01-31 14:08:57:371: FreeConnection: pConn=0x43adf50, 0
[5012] 01-31 14:08:57:371: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 0
[5012] 01-31 14:08:57:372: CleanUpDeadClientProcessBlock
[5012] 01-31 14:08:57:372: SetDialMachineEventHandleCommon:
[5012] 01-31 14:08:57:372: SetDialMachineEventHandleCommon:Error:
meaningless operation
[5012] 01-31 14:08:57:372: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x116c
[5012] 01-31 14:08:57:372: PickOneMsgFromQueueList:
[5012] 01-31 14:08:57:372: PickOneMsgFromQueueList:Set client Event:0x116c
[5156] 01-31 14:08:57:373: CleanUpDeadClientProcessBlock
[5156] 01-31 14:08:57:374: SetDialMachineEventHandleCommon:
[5156] 01-31 14:08:57:374: SetDialMachineEventHandleCommon:Error:
meaningless operation
[5012] 01-31 14:08:57:374: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x116c
[5012] 01-31 14:08:57:374: PickOneMsgFromQueueList:
[5012] 01-31 14:08:57:374: PickOneMsgFromQueueList:Set client Event:0x116c
[5156] 01-31 14:08:57:374: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x116c
[5156] 01-31 14:08:57:374: PickOneMsgFromQueueList:
[5156] 01-31 14:08:57:374: PickOneMsgFromQueueList:Set client Event:0x116c
[5012] 01-31 14:08:57:374: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x116c
[5012] 01-31 14:08:57:374: PickOneMsgFromQueueList:
[5012] 01-31 14:09:01:751: SetEntryDialParams: Adding 18749994 to lsa
[5012] 01-31 14:09:01:751: SetEntryDialParams: Writing to fDefault=0
[5156] 01-31 14:09:14:335: ReferenceRasman: process 4580
[5156] 01-31 14:09:14:335: Rasman RefCount = 13
[4244] 01-31 14:11:52:960: ReferenceRasman: process 4580
[4244] 01-31 14:11:52:960: Cleaning up process 4580
[4244] 01-31 14:11:52:960: CleanUpEventQueue:
[4244] 01-31 14:11:52:960: Freeing handle for 4580
[4244] 01-31 14:11:52:960: Rasman RefCount = 12
[4244] 01-31 14:12:02:737: GetNdiswanDriverCaps..
[4244] 01-31 14:12:02:737: GetNdiswanDriverCaps rc=0x0
[8084] 01-31 14:12:34:646: SendNotificationRequest
[8084] 01-31 14:12:34:667: DwSendNotification returned 0x0
[8084] 01-31 14:12:37:700: GetParamsListFromLsa Default=0
[8084] 01-31 14:12:37:701: GetParamsListFromLsa. 0x0
[6396] 01-31 14:12:37:717: GetParamsListFromLsa Default=1
[6396] 01-31 14:12:37:717: GetParamsListFromLsa. 0x2
[6396] 01-31 14:12:37:772: GetParamsListFromLsa Default=0
[6396] 01-31 14:12:37:773: GetParamsListFromLsa. 0x0
[6396] 01-31 14:12:37:774: CreateConnection: entry=VPN Connection,

pbk=C:\Users\bruce.wilkinson.ITS\AppData\Roaming\Microsoft\Network\Connections\Pbk\rasphone.pbk
[6396] 01-31 14:12:37:774: Info: fSecureRoutingCompartment: 0 and
dwConSessionId 1 old Compartment ID 1:
[6396] 01-31 14:12:37:774: SetRasmanServiceStopControl: Enabled 0
[6396] 01-31 14:12:37:774: CreateConnection: Created new connection.
hconn=0x30000, ref=1, pConn=0x43adf50
[6396] 01-31 14:12:37:780: IsTrustedCustomDll: pwsz=, fTrusted=0, rc=0
[6396] 01-31 14:12:37:781: PortOpenEx: WAN Miniport (PPTP)
[6396] 01-31 14:12:37:794: SetRasmanServiceStopControl: Enabled 0
[6396] 01-31 14:12:37:794: PortOpen (2, VPN1-1) OpenInstances = (0)
[6396] 01-31 14:12:37:794: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 2 state chg: prev=4, new=4
[6396] 01-31 14:12:37:794: PortOpenEx (2) : OpenInstances = 1
[6396] 01-31 14:12:37:794: PortOpenEx: rc=0x0. DeviceFound=1
[6396] 01-31 14:12:37:795: CleanUpDeadClientProcessBlock
[6396] 01-31 14:12:37:795: SetDialMachineEventHandleCommon:
[6396] 01-31 14:12:37:795: Cleaning up process 5936
[6396] 01-31 14:12:37:795: CleanUpEventQueue:
[6396] 01-31 14:12:37:796: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=2, dwSubEntry=1
[6396] 01-31 14:12:42:330: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[6396] 01-31 14:12:42:331: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[6396] 01-31 14:12:42:331: QueueCloseConnections: no dependent connections
[6396] 01-31 14:12:42:331: PortDisconnectRequest on VPN1-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[6396] 01-31 14:12:42:331: PortDisconnectRequest: Disconnecting VPN1-1
[6396] 01-31 14:12:42:331: Disconnecting Port 0xVPN1-1, reason 0
[6396] 01-31 14:12:42:331: QueueCloseConnections: no dependent connections
[6396] 01-31 14:12:42:331: link handle for VPN1-1 = INVALID_HANDLE_VALUE
[6396] 01-31 14:12:42:331: 4. Notifying of disconnect on port 2
[6396] 01-31 14:12:42:331: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 2 state chg: prev=4, new=4
[6396] 01-31 14:12:42:331: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 2 async reqtype chg: prev=0, new=0
[6396] 01-31 14:12:42:331: CompleteDisconnectRequest: signalling 0x2af0 for
VPN1-1
[6396] 01-31 14:12:42:331: SignalPortDisconnect: pOverlapped=0x65aa1a4
[6396] 01-31 14:12:42:331: PostDialEventContext: for pid:0x1730, client
Event:0xf14context pointer=0x65aa1a4, type=1
[6396] 01-31 14:12:42:331: AppendNewMsgToQueue:Set client Event:0xf14
[6396] 01-31 14:12:42:331: Disconnect completed on port: VPN1-1
[6396] 01-31 14:12:42:331: Disconnect request on port: VPN1-1
[6396] 01-31 14:12:42:331: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[6396] 01-31 14:12:42:332: PortClose: port (2). OpenInstances = 1
[6396] 01-31 14:12:42:332: PortClose: going to clear the rasapi32 event
handle
[6396] 01-31 14:12:42:332: SetDialMachineEventHandleCommon:
[6396] 01-31 14:12:42:332: SetDialEventHandleCommon: posting last event for
port 2
[6396] 01-31 14:12:42:332: PostDialEventContext: for pid:0x1730, client
Event:0xf14context pointer=0x65aa204, type=4
[6396] 01-31 14:12:42:332: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[6396] 01-31 14:12:42:332: Freeing the notifier list for port 2
[6396] 01-31 14:12:42:332: PortClose (2). OpenInstances = 0
[6396] 01-31 14:12:42:340: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 2 async reqtype chg: prev=0, new=0
[6396] 01-31 14:12:42:340: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 2 state chg: prev=4, new=4
[6396] 01-31 14:12:42:340: RemoveConnectionPort: port 2, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[6396] 01-31 14:12:42:340: Connection not freed for 0x30000! CB_Ports=0,
CB_Ref=1
[6396] 01-31 14:12:42:340: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 2
[8084] 01-31 14:12:42:343: PortOpenEx: WAN Miniport (L2TP)
[8084] 01-31 14:12:42:359: SetRasmanServiceStopControl: Enabled 0
[8084] 01-31 14:12:42:359: PortOpen (0, VPN0-1) OpenInstances = (0)
[8084] 01-31 14:12:42:359: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 0 state chg: prev=4, new=4
[8084] 01-31 14:12:42:359: PortOpenEx (0) : OpenInstances = 1
[8084] 01-31 14:12:42:359: PortOpenEx: rc=0x0. DeviceFound=1
[6396] 01-31 14:12:42:360: CleanUpDeadClientProcessBlock
[6396] 01-31 14:12:42:360: SetDialMachineEventHandleCommon:
[6396] 01-31 14:12:42:360: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=0, dwSubEntry=1
[6396] 01-31 14:12:44:620: ReferenceCustomCount
[6396] 01-31 14:12:44:620: ReferenceCustomCount done. 0
[6396] 01-31 14:12:44:621: RefConnection: 0x30000
[6396] 01-31 14:12:44:621: refcount=0, maxports=6, ports=1
[6396] 01-31 14:12:44:621: RefConnection: ref on 0x30000 = 0
[6396] 01-31 14:12:44:621: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[6396] 01-31 14:12:44:623: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[6396] 01-31 14:12:44:623: QueueCloseConnections: no dependent connections
[6396] 01-31 14:12:44:623: PortDisconnectRequest on VPN0-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[6396] 01-31 14:12:44:623: PortDisconnectRequest: Disconnecting VPN0-1
[6396] 01-31 14:12:44:623: Disconnecting Port 0xVPN0-1, reason 0
[6396] 01-31 14:12:44:623: QueueCloseConnections: no dependent connections
[6396] 01-31 14:12:44:623: link handle for VPN0-1 = INVALID_HANDLE_VALUE
[6396] 01-31 14:12:44:623: 4. Notifying of disconnect on port 0
[6396] 01-31 14:12:44:623: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 0 state chg: prev=4, new=4
[6396] 01-31 14:12:44:623: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 0 async reqtype chg: prev=0, new=0
[6396] 01-31 14:12:44:623: CompleteDisconnectRequest: signalling 0x538 for
VPN0-1
[6396] 01-31 14:12:44:623: SignalPortDisconnect: pOverlapped=0x65af584
[6396] 01-31 14:12:44:623: PostDialEventContext: for pid:0x1730, client
Event:0xf14context pointer=0x65af584, type=1
[6396] 01-31 14:12:44:623: Disconnect completed on port: VPN0-1
[6396] 01-31 14:12:44:623: Disconnect request on port: VPN0-1
[6396] 01-31 14:12:44:623: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[6396] 01-31 14:12:44:623: PortClose: port (0). OpenInstances = 1
[6396] 01-31 14:12:44:623: PortClose: going to clear the rasapi32 event
handle
[6396] 01-31 14:12:44:623: SetDialMachineEventHandleCommon:
[6396] 01-31 14:12:44:623: SetDialEventHandleCommon: posting last event for
port 0
[6396] 01-31 14:12:44:623: PostDialEventContext: for pid:0x1730, client
Event:0xf14context pointer=0x65af5e4, type=4
[6396] 01-31 14:12:44:623: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[6396] 01-31 14:12:44:623: Freeing the notifier list for port 0
[6396] 01-31 14:12:44:623: PortClose (0). OpenInstances = 0
[6396] 01-31 14:12:44:629: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 0 async reqtype chg: prev=0, new=0
[6396] 01-31 14:12:44:629: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 0 state chg: prev=4, new=4
[6396] 01-31 14:12:44:629: DwInitializeIpSec: fOnFailure set to 0
[6396] 01-31 14:12:44:629: Deleting client ipsec filter on 0
[6396] 01-31 14:12:44:629: DwDeleteFilter for VPN0-1 returned 0x57
[6396] 01-31 14:12:44:629: RemoveConnectionPort: port 0, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[6396] 01-31 14:12:44:629: SendSensNotification(_RAS_DISCONNECT) for
0x00030000 returns 0x00000000
[6396] 01-31 14:12:44:630: DwSendNotificationInternal(ENTRY_DISCONNECTED)
rc=0x0
[6396] 01-31 14:12:44:630: RemoveConnectionPort: FreeConnection
hconn=0x30000, pconn=0x43adf50, AutoClose=0
[6396] 01-31 14:12:44:630: FreeConnection: pConn=0x43adf50, 0
[6396] 01-31 14:12:44:630: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 0
[8084] 01-31 14:12:44:631: CleanUpDeadClientProcessBlock
[8084] 01-31 14:12:44:631: SetDialMachineEventHandleCommon:
[8084] 01-31 14:12:44:631: SetDialMachineEventHandleCommon:Error:
meaningless operation
[8084] 01-31 14:12:44:631: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0xf14
[8084] 01-31 14:12:44:631: PickOneMsgFromQueueList:
[8084] 01-31 14:12:44:631: PickOneMsgFromQueueList:Set client Event:0xf14
[6396] 01-31 14:12:44:634: CleanUpDeadClientProcessBlock
[6396] 01-31 14:12:44:634: SetDialMachineEventHandleCommon:
[6396] 01-31 14:12:44:634: SetDialMachineEventHandleCommon:Error:
meaningless operation
[6396] 01-31 14:12:44:635: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0xf14
[6396] 01-31 14:12:44:635: PickOneMsgFromQueueList:
[6396] 01-31 14:12:44:635: PickOneMsgFromQueueList:Set client Event:0xf14
[6396] 01-31 14:12:44:635: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0xf14
[6396] 01-31 14:12:44:635: PickOneMsgFromQueueList:
[6396] 01-31 14:12:44:635: PickOneMsgFromQueueList:Set client Event:0xf14
[6396] 01-31 14:12:44:636: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0xf14
[6396] 01-31 14:12:44:636: PickOneMsgFromQueueList:
[6396] 01-31 14:12:57:611: GetParamsListFromLsa Default=0
[6396] 01-31 14:12:57:612: GetParamsListFromLsa. 0x0
[6396] 01-31 14:12:57:612: CreateConnection: entry=VPN Connection,

pbk=C:\Users\bruce.wilkinson.ITS\AppData\Roaming\Microsoft\Network\Connections\Pbk\rasphone.pbk
[6396] 01-31 14:12:57:612: Info: fSecureRoutingCompartment: 0 and
dwConSessionId 1 old Compartment ID 1:
[6396] 01-31 14:12:57:613: SetRasmanServiceStopControl: Enabled 0
[6396] 01-31 14:12:57:613: CreateConnection: Created new connection.
hconn=0x40000, ref=1, pConn=0x43adf50
[6396] 01-31 14:12:57:616: IsTrustedCustomDll: pwsz=, fTrusted=0, rc=0
[6396] 01-31 14:12:57:617: PortOpenEx: WAN Miniport (PPTP)
[6396] 01-31 14:12:57:629: SetRasmanServiceStopControl: Enabled 0
[6396] 01-31 14:12:57:629: PortOpen (2, VPN1-1) OpenInstances = (0)
[6396] 01-31 14:12:57:629: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 2 state chg: prev=4, new=4
[6396] 01-31 14:12:57:629: PortOpenEx (2) : OpenInstances = 1
[6396] 01-31 14:12:57:629: PortOpenEx: rc=0x0. DeviceFound=1
[6396] 01-31 14:12:57:630: CleanUpDeadClientProcessBlock
[6396] 01-31 14:12:57:630: SetDialMachineEventHandleCommon:
[6396] 01-31 14:12:57:630: Cleaning up process 5936
[6396] 01-31 14:12:57:630: CleanUpEventQueue:
[6396] 01-31 14:12:57:631: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=2, dwSubEntry=1
[8084] 01-31 14:13:02:136: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[8084] 01-31 14:13:02:138: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[8084] 01-31 14:13:02:138: QueueCloseConnections: no dependent connections
[8084] 01-31 14:13:02:138: PortDisconnectRequest on VPN1-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[8084] 01-31 14:13:02:138: PortDisconnectRequest: Disconnecting VPN1-1
[8084] 01-31 14:13:02:138: Disconnecting Port 0xVPN1-1, reason 0
[8084] 01-31 14:13:02:138: QueueCloseConnections: no dependent connections
[8084] 01-31 14:13:02:138: link handle for VPN1-1 = INVALID_HANDLE_VALUE
[8084] 01-31 14:13:02:138: 4. Notifying of disconnect on port 2
[8084] 01-31 14:13:02:138: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 2 state chg: prev=4, new=4
[8084] 01-31 14:13:02:138: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 2 async reqtype chg: prev=0, new=0
[8084] 01-31 14:13:02:138: CompleteDisconnectRequest: signalling 0x2ba8 for
VPN1-1
[8084] 01-31 14:13:02:138: SignalPortDisconnect: pOverlapped=0x65aa1a4
[8084] 01-31 14:13:02:138: PostDialEventContext: for pid:0x1730, client
Event:0x10accontext pointer=0x65aa1a4, type=1
[8084] 01-31 14:13:02:138: AppendNewMsgToQueue:Set client Event:0x10ac
[8084] 01-31 14:13:02:138: Disconnect completed on port: VPN1-1
[8084] 01-31 14:13:02:138: Disconnect request on port: VPN1-1
[8084] 01-31 14:13:02:138: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[8084] 01-31 14:13:02:139: PortClose: port (2). OpenInstances = 1
[8084] 01-31 14:13:02:139: PortClose: going to clear the rasapi32 event
handle
[8084] 01-31 14:13:02:139: SetDialMachineEventHandleCommon:
[8084] 01-31 14:13:02:139: SetDialEventHandleCommon: posting last event for
port 2
[8084] 01-31 14:13:02:139: PostDialEventContext: for pid:0x1730, client
Event:0x10accontext pointer=0x65aa204, type=4
[8084] 01-31 14:13:02:139: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[8084] 01-31 14:13:02:139: Freeing the notifier list for port 2
[8084] 01-31 14:13:02:139: PortClose (2). OpenInstances = 0
[8084] 01-31 14:13:02:144: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 2 async reqtype chg: prev=0, new=0
[8084] 01-31 14:13:02:144: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 2 state chg: prev=4, new=4
[8084] 01-31 14:13:02:144: RemoveConnectionPort: port 2, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[8084] 01-31 14:13:02:144: Connection not freed for 0x40000! CB_Ports=0,
CB_Ref=1
[8084] 01-31 14:13:02:144: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 2
[8084] 01-31 14:13:02:145: PortOpenEx: WAN Miniport (L2TP)
[8084] 01-31 14:13:02:170: SetRasmanServiceStopControl: Enabled 0
[8084] 01-31 14:13:02:170: PortOpen (0, VPN0-1) OpenInstances = (0)
[8084] 01-31 14:13:02:170: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 0 state chg: prev=4, new=4
[8084] 01-31 14:13:02:170: PortOpenEx (0) : OpenInstances = 1
[8084] 01-31 14:13:02:170: PortOpenEx: rc=0x0. DeviceFound=1
[8084] 01-31 14:13:02:171: CleanUpDeadClientProcessBlock
[8084] 01-31 14:13:02:171: SetDialMachineEventHandleCommon:
[8084] 01-31 14:13:02:171: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=0, dwSubEntry=1
[8084] 01-31 14:13:04:431: ReferenceCustomCount
[8084] 01-31 14:13:04:431: ReferenceCustomCount done. 0
[8084] 01-31 14:13:04:432: RefConnection: 0x40000
[8084] 01-31 14:13:04:432: refcount=0, maxports=6, ports=1
[8084] 01-31 14:13:04:432: RefConnection: ref on 0x40000 = 0
[8084] 01-31 14:13:04:432: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[8084] 01-31 14:13:04:433: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[8084] 01-31 14:13:04:433: QueueCloseConnections: no dependent connections
[8084] 01-31 14:13:04:433: PortDisconnectRequest on VPN0-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[8084] 01-31 14:13:04:433: PortDisconnectRequest: Disconnecting VPN0-1
[8084] 01-31 14:13:04:433: Disconnecting Port 0xVPN0-1, reason 0
[8084] 01-31 14:13:04:433: QueueCloseConnections: no dependent connections
[8084] 01-31 14:13:04:433: link handle for VPN0-1 = INVALID_HANDLE_VALUE
[8084] 01-31 14:13:04:433: 4. Notifying of disconnect on port 0
[8084] 01-31 14:13:04:433: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 0 state chg: prev=4, new=4
[8084] 01-31 14:13:04:434: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 0 async reqtype chg: prev=0, new=0
[8084] 01-31 14:13:04:434: CompleteDisconnectRequest: signalling 0x2f20 for
VPN0-1
[8084] 01-31 14:13:04:434: SignalPortDisconnect: pOverlapped=0x65af584
[8084] 01-31 14:13:04:434: PostDialEventContext: for pid:0x1730, client
Event:0x10accontext pointer=0x65af584, type=1
[8084] 01-31 14:13:04:434: Disconnect completed on port: VPN0-1
[8084] 01-31 14:13:04:434: Disconnect request on port: VPN0-1
[8084] 01-31 14:13:04:434: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[8084] 01-31 14:13:04:434: PortClose: port (0). OpenInstances = 1
[8084] 01-31 14:13:04:434: PortClose: going to clear the rasapi32 event
handle
[8084] 01-31 14:13:04:434: SetDialMachineEventHandleCommon:
[8084] 01-31 14:13:04:434: SetDialEventHandleCommon: posting last event for
port 0
[8084] 01-31 14:13:04:434: PostDialEventContext: for pid:0x1730, client
Event:0x10accontext pointer=0x65af5e4, type=4
[8084] 01-31 14:13:04:434: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[8084] 01-31 14:13:04:434: Freeing the notifier list for port 0
[8084] 01-31 14:13:04:434: PortClose (0). OpenInstances = 0
[8084] 01-31 14:13:04:439: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 0 async reqtype chg: prev=0, new=0
[8084] 01-31 14:13:04:439: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 0 state chg: prev=4, new=4
[8084] 01-31 14:13:04:439: DwInitializeIpSec: fOnFailure set to 0
[8084] 01-31 14:13:04:439: Deleting client ipsec filter on 0
[8084] 01-31 14:13:04:439: DwDeleteFilter for VPN0-1 returned 0x57
[8084] 01-31 14:13:04:440: RemoveConnectionPort: port 0, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[8084] 01-31 14:13:04:440: SendSensNotification(_RAS_DISCONNECT) for
0x00040000 returns 0x00000000
[8084] 01-31 14:13:04:441: DwSendNotificationInternal(ENTRY_DISCONNECTED)
rc=0x0
[8084] 01-31 14:13:04:441: RemoveConnectionPort: FreeConnection
hconn=0x40000, pconn=0x43adf50, AutoClose=0
[8084] 01-31 14:13:04:441: FreeConnection: pConn=0x43adf50, 0
[8084] 01-31 14:13:04:441: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 0
[8084] 01-31 14:13:04:441: CleanUpDeadClientProcessBlock
[8084] 01-31 14:13:04:441: SetDialMachineEventHandleCommon:
[8084] 01-31 14:13:04:441: SetDialMachineEventHandleCommon:Error:
meaningless operation
[8084] 01-31 14:13:04:442: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x10ac
[8084] 01-31 14:13:04:442: PickOneMsgFromQueueList:
[8084] 01-31 14:13:04:442: PickOneMsgFromQueueList:Set client Event:0x10ac
[8084] 01-31 14:13:04:443: CleanUpDeadClientProcessBlock
[8084] 01-31 14:13:04:444: SetDialMachineEventHandleCommon:
[8084] 01-31 14:13:04:444: SetDialMachineEventHandleCommon:Error:
meaningless operation
[8084] 01-31 14:13:04:444: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x10ac
[8084] 01-31 14:13:04:444: PickOneMsgFromQueueList:
[8084] 01-31 14:13:04:444: PickOneMsgFromQueueList:Set client Event:0x10ac
[8084] 01-31 14:13:04:444: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x10ac
[8084] 01-31 14:13:04:444: PickOneMsgFromQueueList:
[8084] 01-31 14:13:04:444: PickOneMsgFromQueueList:Set client Event:0x10ac
[8084] 01-31 14:13:04:445: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x10ac
[8084] 01-31 14:13:04:445: PickOneMsgFromQueueList:
[4292] 01-31 14:13:27:736: GetParamsListFromLsa Default=0
[4292] 01-31 14:13:27:737: GetParamsListFromLsa. 0x0
[4292] 01-31 14:13:27:738: CreateConnection: entry=VPN Connection,

pbk=C:\Users\bruce.wilkinson.ITS\AppData\Roaming\Microsoft\Network\Connections\Pbk\rasphone.pbk
[4292] 01-31 14:13:27:738: Info: fSecureRoutingCompartment: 0 and
dwConSessionId 1 old Compartment ID 1:
[4292] 01-31 14:13:27:738: SetRasmanServiceStopControl: Enabled 0
[4292] 01-31 14:13:27:738: CreateConnection: Created new connection.
hconn=0x50000, ref=1, pConn=0x43adf50
[4292] 01-31 14:13:27:741: IsTrustedCustomDll: pwsz=, fTrusted=0, rc=0
[4292] 01-31 14:13:27:742: PortOpenEx: WAN Miniport (PPTP)
[4292] 01-31 14:13:27:754: SetRasmanServiceStopControl: Enabled 0
[4292] 01-31 14:13:27:754: PortOpen (2, VPN1-1) OpenInstances = (0)
[4292] 01-31 14:13:27:754: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 2 state chg: prev=4, new=4
[4292] 01-31 14:13:27:754: PortOpenEx (2) : OpenInstances = 1
[4292] 01-31 14:13:27:754: PortOpenEx: rc=0x0. DeviceFound=1
[4292] 01-31 14:13:27:755: CleanUpDeadClientProcessBlock
[4292] 01-31 14:13:27:755: SetDialMachineEventHandleCommon:
[4292] 01-31 14:13:27:755: Cleaning up process 5936
[4292] 01-31 14:13:27:755: CleanUpEventQueue:
[4292] 01-31 14:13:27:755: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=2, dwSubEntry=1
[4292] 01-31 14:13:32:261: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[4292] 01-31 14:13:32:266: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[4292] 01-31 14:13:32:266: QueueCloseConnections: no dependent connections
[4292] 01-31 14:13:32:266: PortDisconnectRequest on VPN1-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[4292] 01-31 14:13:32:266: PortDisconnectRequest: Disconnecting VPN1-1
[4292] 01-31 14:13:32:266: Disconnecting Port 0xVPN1-1, reason 0
[4292] 01-31 14:13:32:266: QueueCloseConnections: no dependent connections
[4292] 01-31 14:13:32:266: link handle for VPN1-1 = INVALID_HANDLE_VALUE
[4292] 01-31 14:13:32:266: 4. Notifying of disconnect on port 2
[4292] 01-31 14:13:32:266: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 2 state chg: prev=4, new=4
[4292] 01-31 14:13:32:266: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 2 async reqtype chg: prev=0, new=0
[4292] 01-31 14:13:32:266: CompleteDisconnectRequest: signalling 0x2d64 for
VPN1-1
[4292] 01-31 14:13:32:266: SignalPortDisconnect: pOverlapped=0x65aa1a4
[4292] 01-31 14:13:32:266: PostDialEventContext: for pid:0x1730, client
Event:0x1490context pointer=0x65aa1a4, type=1
[4292] 01-31 14:13:32:266: AppendNewMsgToQueue:Set client Event:0x1490
[4292] 01-31 14:13:32:266: Disconnect completed on port: VPN1-1
[4292] 01-31 14:13:32:266: Disconnect request on port: VPN1-1
[6396] 01-31 14:13:32:266: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[6396] 01-31 14:13:32:267: PortClose: port (2). OpenInstances = 1
[6396] 01-31 14:13:32:267: PortClose: going to clear the rasapi32 event
handle
[6396] 01-31 14:13:32:267: SetDialMachineEventHandleCommon:
[6396] 01-31 14:13:32:267: SetDialEventHandleCommon: posting last event for
port 2
[6396] 01-31 14:13:32:267: PostDialEventContext: for pid:0x1730, client
Event:0x1490context pointer=0x65aa204, type=4
[6396] 01-31 14:13:32:267: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[6396] 01-31 14:13:32:267: Freeing the notifier list for port 2
[6396] 01-31 14:13:32:267: PortClose (2). OpenInstances = 0
[6396] 01-31 14:13:32:275: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 2 async reqtype chg: prev=0, new=0
[6396] 01-31 14:13:32:275: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 2 state chg: prev=4, new=4
[6396] 01-31 14:13:32:275: RemoveConnectionPort: port 2, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[6396] 01-31 14:13:32:275: Connection not freed for 0x50000! CB_Ports=0,
CB_Ref=1
[6396] 01-31 14:13:32:275: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 2
[4292] 01-31 14:13:32:277: PortOpenEx: WAN Miniport (L2TP)
[4292] 01-31 14:13:32:302: SetRasmanServiceStopControl: Enabled 0
[4292] 01-31 14:13:32:302: PortOpen (0, VPN0-1) OpenInstances = (0)
[4292] 01-31 14:13:32:302: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
2770: port 0 state chg: prev=4, new=4
[4292] 01-31 14:13:32:302: PortOpenEx (0) : OpenInstances = 1
[4292] 01-31 14:13:32:302: PortOpenEx: rc=0x0. DeviceFound=1
[6396] 01-31 14:13:32:303: CleanUpDeadClientProcessBlock
[6396] 01-31 14:13:32:303: SetDialMachineEventHandleCommon:
[6396] 01-31 14:13:32:303: AddConnectionPort: pConn=0x43adf50,
pConn->CB_Ports=1, port=0, dwSubEntry=1
[4292] 01-31 14:13:34:561: ReferenceCustomCount
[4292] 01-31 14:13:34:561: ReferenceCustomCount done. 0
[4292] 01-31 14:13:34:562: RefConnection: 0x50000
[4292] 01-31 14:13:34:562: refcount=0, maxports=6, ports=1
[4292] 01-31 14:13:34:562: RefConnection: ref on 0x50000 = 0
[4292] 01-31 14:13:34:563: PortDisconnectRequest - calling
CheckIfAllowedToDisconnectOREnumerate
[4292] 01-31 14:13:34:564: DwSendNotificationInternal(DISCONNECTING)
returned 0x0
[4292] 01-31 14:13:34:564: QueueCloseConnections: no dependent connections
[4292] 01-31 14:13:34:564: PortDisconnectRequest on VPN0-1
Connection=0x43adf50 ,RasmanReceiveFlags=0x0
[4292] 01-31 14:13:34:564: PortDisconnectRequest: Disconnecting VPN0-1
[4292] 01-31 14:13:34:564: Disconnecting Port 0xVPN0-1, reason 0
[4292] 01-31 14:13:34:564: QueueCloseConnections: no dependent connections
[4292] 01-31 14:13:34:564: link handle for VPN0-1 = INVALID_HANDLE_VALUE
[4292] 01-31 14:13:34:564: 4. Notifying of disconnect on port 0
[4292] 01-31 14:13:34:564: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4904: port 0 state chg: prev=4, new=4
[4292] 01-31 14:13:34:564: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
4915: port 0 async reqtype chg: prev=0, new=0
[4292] 01-31 14:13:34:564: CompleteDisconnectRequest: signalling 0x2f2c for
VPN0-1
[4292] 01-31 14:13:34:564: SignalPortDisconnect: pOverlapped=0x65af584
[4292] 01-31 14:13:34:564: PostDialEventContext: for pid:0x1730, client
Event:0x1490context pointer=0x65af584, type=1
[4292] 01-31 14:13:34:564: Disconnect completed on port: VPN0-1
[4292] 01-31 14:13:34:564: Disconnect request on port: VPN0-1
[4292] 01-31 14:13:34:564: PortCloseRequest - Calling
CheckIfAllowedToDisconnectOREnumerate
[4292] 01-31 14:13:34:564: PortClose: port (0). OpenInstances = 1
[4292] 01-31 14:13:34:564: PortClose: going to clear the rasapi32 event
handle
[4292] 01-31 14:13:34:564: SetDialMachineEventHandleCommon:
[4292] 01-31 14:13:34:564: SetDialEventHandleCommon: posting last event for
port 0
[4292] 01-31 14:13:34:564: PostDialEventContext: for pid:0x1730, client
Event:0x1490context pointer=0x65af5e4, type=4
[4292] 01-31 14:13:34:565: PostDialEventContext: going to put the
OVEVT_DIAL_LAST message in the queue

[4292] 01-31 14:13:34:565: Freeing the notifier list for port 0
[4292] 01-31 14:13:34:565: PortClose (0). OpenInstances = 0
[4292] 01-31 14:13:34:569: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3588: port 0 async reqtype chg: prev=0, new=0
[4292] 01-31 14:13:34:569: d:\vistartm\net\rras\ras\rasman\rasman\request.c:
3591: port 0 state chg: prev=4, new=4
[4292] 01-31 14:13:34:569: DwInitializeIpSec: fOnFailure set to 0
[4292] 01-31 14:13:34:569: Deleting client ipsec filter on 0
[4292] 01-31 14:13:34:569: DwDeleteFilter for VPN0-1 returned 0x57
[4292] 01-31 14:13:34:569: RemoveConnectionPort: port 0, fOwnerClose=1,
pConn=0x43adf50, pConn->CB_Ports=0

[4292] 01-31 14:13:34:569: SendSensNotification(_RAS_DISCONNECT) for
0x00050000 returns 0x00000000
[4292] 01-31 14:13:34:571: DwSendNotificationInternal(ENTRY_DISCONNECTED)
rc=0x0
[4292] 01-31 14:13:34:571: RemoveConnectionPort: FreeConnection
hconn=0x50000, pconn=0x43adf50, AutoClose=0
[4292] 01-31 14:13:34:571: FreeConnection: pConn=0x43adf50, 0
[4292] 01-31 14:13:34:572: d:\vistartm\net\rras\ras\rasman\rasman\request.c,
3680: Clearing the autoclose flag for port 0
[6396] 01-31 14:13:34:572: CleanUpDeadClientProcessBlock
[6396] 01-31 14:13:34:572: SetDialMachineEventHandleCommon:
[6396] 01-31 14:13:34:572: SetDialMachineEventHandleCommon:Error:
meaningless operation
[6396] 01-31 14:13:34:572: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x1490
[6396] 01-31 14:13:34:572: PickOneMsgFromQueueList:
[6396] 01-31 14:13:34:572: PickOneMsgFromQueueList:Set client Event:0x1490
[6396] 01-31 14:13:34:574: CleanUpDeadClientProcessBlock
[6396] 01-31 14:13:34:574: SetDialMachineEventHandleCommon:
[6396] 01-31 14:13:34:574: SetDialMachineEventHandleCommon:Error:
meaningless operation
[6396] 01-31 14:13:34:574: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x1490
[6396] 01-31 14:13:34:574: PickOneMsgFromQueueList:
[6396] 01-31 14:13:34:574: PickOneMsgFromQueueList:Set client Event:0x1490
[6396] 01-31 14:13:34:575: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x1490
[6396] 01-31 14:13:34:575: PickOneMsgFromQueueList:
[6396] 01-31 14:13:34:575: PickOneMsgFromQueueList:Set client Event:0x1490
[6396] 01-31 14:13:34:575: PickOneMsgForAEvent:send context to Pid:0x1730,
clientEvent:0x1490
[6396] 01-31 14:13:34:575: PickOneMsgFromQueueList:

 

[Jian-Ping Zhu [MSFT]]

Hello Bruce,

Thank you for your post.

I need more information for further investigation.

Please collect the following information:

RAS tracing on Server and remote client:
======
1. Logon Server, go to command prompt, type "netsh ras set tracing *
disabled".
2. Please open %SYSTEMROOT%\Tracing folder and back up all the log files
under this folder.
3. Please delete all logs under %SYSTEMROOT%\Tracing folder
4. Go to command prompt, type "netsh ras set tracing * Enabled"
5. Please also do the above steps on the remote client computer.
6. Try to connect from the remote client again, if the issue is
reproduced successfully, please compress and email me with the
C:\Winodows\Tracing folder on both client and the Server.

Network MPS report:
=======
1. Download MPSRPT_NETWORK.EXE
http://www.microsoft.com/downloads/details.aspx?familyid=cebf3c7c-7ca5-408f-
88b7-f9c79b7306c0&displaylang=en

2. Run the file on Windows 2003 Server, it generates a CAB file:
%SYSTEMROOT%\MPSReports\Network\Reports\cab\%COMPUTERNAME%_MPSReports_.CAB

Please compress these report files and send them to me at
(e-mail address removed)

If possible, please also test VPN connection on other Vista machines and
check whether the same issue will occur.

Thank you for your time and I look forward to hearing from you soon.

Sincerely,
Neo Zhu,
Microsoft Online Support
Microsoft Global Technical Support Center

Get Secure! - www.microsoft.com/security
=====================================================
When responding to posts, please "Reply to Group" via your newsreader so
that others may learn and benefit from your issue.
=====================================================
This posting is provided "AS IS" with no warranties, and confers no rights.

 

[Bruce Wilkinson]

Hello Neo Zhu:

I will collect everything later when I'm back at the office.

I have been running Network Monitor on the server and client. Neither shows
any RAS traffic when I try to VPN connect to the server. The client doesn't
send anything.

I don't have another Vista Ultimate client to test with. I can use a Vista
Home Premium is that's ok.

Bruce

 

[Jian-Ping Zhu [MSFT]]

Hello Bruce,

Thank you for your reply.

If you don't have other Vista Ultimate OS machines, let's focus on the
current one first.

It is preferred if you could run Network Monitor on both Server and Client
sides when you try to reproduce this issue and collect the information I
need.

Please send the result files of captured WAN traffics as well as the
information I requested in my last post to me at (e-mail address removed).

Please also tell me the WAN IP addresses of your Vista and Windows Server
2003 machines.

By the way, in order to narrow down the issue, I recommend you disable
Windows Firewall and third-party firewall on Vista machine when doing the
test.

Thank you for your time and I look forward to hearing from you. : )

Sincerely,
Neo Zhu,
Microsoft Online Support
Microsoft Global Technical Support Center

Get Secure! - www.microsoft.com/security
=====================================================
When responding to posts, please "Reply to Group" via your newsreader so
that others may learn and benefit from your issue.
=====================================================
This posting is provided "AS IS" with no warranties, and confers no rights.

 

[Bruce Wilkinson]

Neo Zhu:

The server (SBS 2003 R2) does not have a tracing folder. The client (Vista
Ultimate) does. I'll send the compressed files separately. Note, I couldn't
delete the Kerberos subdirectory, even as an administrator.

Network MPS Report is not available at the link you included. Many
variations of it are available at
http://www.microsoft.com/downloads/...7C-7CA5-408F-88B7-F9C79B7306C0&displaylang=en.
Which one should I download?

Bruce

 

[Bruce Wilkinson]

Correction regarding Network MPS Report, the executable, MPSRPT_NETWORK.EXE
is one of the files. I downloaded and ran it. The exe runs, displays
messages about files being copied, but the CAB folder is empty.

Bruce

 

[Jian-Ping Zhu [MSFT]]

Hello Bruce,

Thanks for your reply.

I have received the mail you sent together with the tracing log. However, I
need the tracing logs on both Vista and SBS server after the issue has been
reproduced successfully on Vista machine.

If the tracing folder on SBS server doesn't exist under %SYSTEMROOT%\,
please open Windows Command Prompt and type the following command:
Netsh ras set tracing * enabled

Please ensure that you run MPSRPT_NETWORK.EXE on the correct machine (SBS
server not Vista), it might take several minutes to finish generating the
report. Please also send me the WAN traffic you captured by Network
Monitor.

Moreover, after some research, I find this issue might be caused by
settings on SBS server or the old driver of the NIC on your Vista machine.

Please try the solution in the following KB article:
http://support.microsoft.com/default.aspx?scid=kb;EN-US;927695

And please update the driver of the NIC on your Vista machine and check
whether the situation will improve.

Thanks for your time and I look forward to hearing from you.

Sincerely,
Neo Zhu,
Microsoft Online Support
Microsoft Global Technical Support Center

Get Secure! - www.microsoft.com/security
=====================================================
When responding to posts, please "Reply to Group" via your newsreader so
that others may learn and benefit from your issue.
=====================================================
This posting is provided "AS IS" with no warranties, and confers no rights.

 

[Jian-Ping Zhu [MSFT]]

Hello,

Regarding your last email , I am just check and follow-up on your status.

I was wondering , if you were available to try conduct test and collect
the information for further investigation.

Thank you for your time and attention.

Sincerely,
Neo Zhu,
Microsoft Online Support
Microsoft Global Technical Support Center

Get Secure! - www.microsoft.com/security
=====================================================
When responding to posts, please "Reply to Group" via your newsreader so
that others may learn and benefit from your issue.
=====================================================
This posting is provided "AS IS" with no warranties, and confers no rights.

 

[Bruce Wilkinson]

Neo Zhu:

I am available, depending on when. I am an IT consultant, and visit clients
during much of the business day. Can we schedule a time?

I was considering calling Microsoft Support and using one of my support
incidents. If we can find a time that works for both of us, I won't need to
call MS.

Thanks,
Bruce

 

[Jian-Ping Zhu [MSFT]]

Hello,

Thank you for letting me know the current status there. I will be standing
by here for your further update. If you are available, please feel free to
post back and we can continue the troubleshooting.

Also, if you want a real time troubleshooting via phone, I would like to
suggest that you contact Microsoft Customer Service and Support (CSS). For
more information on available CSS services, please click here:
http://support.microsoft.com/default.aspx?scid=fh;EN-US;OfferProPhone#faq607

 

[Bruce Wilkinson]

Neo Zhu:

I will contact CSS.

Thanks
Bruce