Link to home
Create AccountLog in
Avatar of enari
enari

asked on

RRAS hanging at "verify username and password"

Hi.

My RRAS server was working fine, and had worked fine in the past, and then one day it didn't.  I wasn't too worried about it, so i am not sure exactly when.  I disabled and reinstalled the service.  I have RRAS working in two other locations with no issues.  I know when it stopped working, there was no change to the Router/Firewall.

When I go to VPN into the server, from within my network, using an internal IP it works.  When I try to access it from the external Ip address, it fails, always at "verifying username and password".  

I tried modifying the firewall, by disabiling the port for PPTP i had enabled and moving the server to the DMZ zone, but i still get locked up at the same point.  I know the external IP address is correct & my other services access the server just fine through the firewall.

I turned on logging "all events" on the server & it doesn't even show that i am attempting to log on when going through the firewall.  But it does show success, when i log in via internal IP.

I turned on tracing on my desktop.  It seems to repeat this over and over...
[2104] 21:33:40: RASCS_AuthNotify



[2548] 21:33:32: RasGetCredentialsW
[2548] 21:33:32: RasGetCredentialsW
[2548] 21:33:35: RasSetCredentialsW
[2548] 21:33:35: RasSetCredentialsW
[2548] 21:33:35: RasDialW...
[2548] 21:33:35: _RasDial(start)
[2548] 21:33:35: looking up subentry 1
[2548] 21:33:35: RasCreateConnection...
[2548] 21:33:35: RasCreateConnection(0) hrasconn=2293760, ConnectionAlreadyPresent=0
[2548] 21:33:35: dwIdleDisconnectSeconds=-1
[2548] 21:33:35: SaveProjectionResults...
[2548] 21:33:35: SaveProjectionResults: saving results (dwSubEntry=0, nbf.dwError=720)
[2548] 21:33:35: SaveProjectionResults(0)
[2548] 21:33:35: StartAsyncMachine
[2548] 21:33:35: starting subentry 1
[2548] 21:33:35: SignalDone: pOverlapped=0x34c00d4
[2548] 21:33:35: RasDialA done(0)
[2104] 21:33:35: WaitForEvent
[2104] 21:33:35: WorkerThread: pOverlapped=0x34c00d4
[2104] 21:33:35: WorkerThread: type=2
[2104] 21:33:35: Unblock i=1, h=0x34c007c
[2104] 21:33:35: NotifyCaller(nt=0x2,su=1,s=0,e=0,xe=0)...
[2104] 21:33:35: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:35: RASCS_OpenPort
[2104] 21:33:35: DwOpenPort
[2104] 21:33:35: RasGetDeviceName(rdt=8)...
[2104] 21:33:35: RasGetDeviceName. 0x0
[2104] 21:33:35: DwOpenPort: RasPortOpenEx(WAN Miniport (PPTP),0)...
[2104] 21:33:35: DwOpenPort: RasPortOpenEx done(0). Flags=0x2
[2104] 21:33:35: DwOpenPort: PortOpened = VPN3-1
[2104] 21:33:35: DwOpenPort done. 0
[2104] 21:33:35: RasSetPortUserData(dialparamsuid) for 3
[2104] 21:33:35: RasSetPortUserData returned 0
[2104] 21:33:35: EnableAsyncMachine: hport=3, pasyncmachine=0x34c007c, OvDrop=0x34c00b4, OvStateChange=0x34c00d4, OvPpp=0x34c00f4, OvLast=0x34c0114
[2104] 21:33:35: RasSetConnectionParams...
[2104] 21:33:35: RasSetConnectionParams(0)
[2104] 21:33:35: RasAddConnectionPort(BDS,1)...
[2104] 21:33:35: RasAddConnectionPort(0)
[2104] 21:33:35: RDM errors=0,0
[2104] 21:33:35: RasDialMachine: SignalDone: prasconncb=0x34c0070
[2104] 21:33:35: SignalDone: pOverlapped=0x34c00d4
[2104] 21:33:35: WaitForEvent
[2104] 21:33:35: WorkerThread: pOverlapped=0x34c00d4
[2104] 21:33:35: WorkerThread: type=2
[2104] 21:33:35: Unblock i=1, h=0x34c007c
[2104] 21:33:35: setting rasman state to 1
[2104] 21:33:35: NotifyCaller(nt=0x2,su=1,s=1,e=0,xe=0)...
[2548] 21:33:35: RasGetSubEntryHandleW
[2104] 21:33:35: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:35: RASCS_PortOpened
[2104] 21:33:35: FindNextDevice: (vpn, WAN Miniport (PPTP))
[2104] 21:33:35: RDM errors=0,0
[2104] 21:33:35: RasDialMachine: SignalDone: prasconncb=0x34c0070
[2104] 21:33:35: SignalDone: pOverlapped=0x34c00d4
[2104] 21:33:35: WaitForEvent
[2104] 21:33:35: WorkerThread: pOverlapped=0x34c00d4
[2104] 21:33:35: WorkerThread: type=2
[2104] 21:33:35: Unblock i=1, h=0x34c007c
[2104] 21:33:35: setting rasman state to 2
[2104] 21:33:35: NotifyCaller(nt=0x2,su=1,s=2,e=0,xe=0)...
[2548] 21:33:35: RasGetConnectStatusW
[2548] 21:33:35: SubEntryFromConnection: hrasconn=0x230000, dwSubEntry=1
[2548] 21:33:35: RasGetConnectStatus: read device (vpn,WAN Miniport (PPTP)) from port user data
[2548] 21:33:35: RasGetConnectStatus: read phonenumber www.blackabydataservices.com from port user data
[2104] 21:33:35: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:35: RASCS_ConnectDevice
[2104] 21:33:35: DwRasGetHostByName returned cAddresses=1 for www.blackabydataservices.com
[2104] 21:33:35: SetDefaultParams: Using address 0= REMOVED
[2104] 21:33:35: RasDeviceSetInfo(PhoneNumber=REMOVED)...
[2104] 21:33:35: RasDeviceSetInfo done(0)
[2104] 21:33:35: SetDeviceParams(rastapi, WAN Miniport (PPTP), 0)
[2104] 21:33:35: RasDeviceConnect(rastapi,WAN Miniport (PPTP))...
[2104] 21:33:35: RasDeviceConnect done(600)
[2104] 21:33:35: RDM errors=600,0
[2104] 21:33:35: WaitForEvent
[2104] 21:33:35: WorkerThread: pOverlapped=0x34c00d4
[2104] 21:33:35: WorkerThread: type=2
[2104] 21:33:35: Unblock i=1, h=0x34c007c
[2104] 21:33:35: RasGetInfo...
[2104] 21:33:35: RasGetInfo done(0)
[2104] 21:33:35: setting rasman state to 3
[2104] 21:33:35: NotifyCaller(nt=0x2,su=1,s=3,e=0,xe=0)...
[2104] 21:33:35: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:35: RASCS_DeviceConnected
[2104] 21:33:35: SaveVpnStrategyInformation...
[2104] 21:33:35: Saving 2 as the vpn strategy
[2104] 21:33:35: SaveVpnStrategyInformation done
[2104] 21:33:35: RDM errors=0,0
[2104] 21:33:35: RasDialMachine: SignalDone: prasconncb=0x34c0070
[2104] 21:33:35: SignalDone: pOverlapped=0x34c00d4
[2104] 21:33:35: WaitForEvent
[2104] 21:33:35: WorkerThread: pOverlapped=0x34c00d4
[2104] 21:33:35: WorkerThread: type=2
[2104] 21:33:35: Unblock i=1, h=0x34c007c
[2104] 21:33:35: setting rasman state to 4
[2104] 21:33:35: NotifyCaller(nt=0x2,su=1,s=4,e=0,xe=0)...
[2104] 21:33:35: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:35: RASCS_AllDevicesConnected
[2104] 21:33:35: RasPortConnectComplete...
[2104] 21:33:35: RasPortConnectComplete done(0)
[2104] 21:33:35: RDM errors=0,0
[2104] 21:33:35: RasDialMachine: SignalDone: prasconncb=0x34c0070
[2104] 21:33:35: SignalDone: pOverlapped=0x34c00d4
[2104] 21:33:35: WaitForEvent
[2104] 21:33:35: WorkerThread: pOverlapped=0x34c00d4
[2104] 21:33:35: WorkerThread: type=2
[2104] 21:33:35: Unblock i=1, h=0x34c007c
[2104] 21:33:35: setting rasman state to 5
[2104] 21:33:35: NotifyCaller(nt=0x2,su=1,s=5,e=0,xe=0)...
[2104] 21:33:35: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:35: RASCS_Authenticate
[2104] 21:33:35: RasPortSetFramingEx(PPP)...
[2104] 21:33:35: RasPortSetFramingEx done(0)
[2104] 21:33:35: subentry 1 has suspend state 0
[2104] 21:33:35: subentry 1 suspending all other subentries
[2104] 21:33:35: RasPppStart(cfg=8393610)...
[2104] 21:33:35: RasSetRasdialInfo 3...
[2104] 21:33:35: RasSetRasdialInfo 3 done. e = 0
[2104] 21:33:35: RasPppStart done(0)
[2104] 21:33:35: RDM errors=0,0
[2104] 21:33:35: WaitForEvent
[2104] 21:33:37: WorkerThread: pOverlapped=0x34c00f4
[2104] 21:33:37: WorkerThread: type=3
[2104] 21:33:37: Unblock i=2, h=0x34c007c
[2104] 21:33:37: setting rasman state to 6
[2104] 21:33:37: NotifyCaller(nt=0x2,su=1,s=6,e=0,xe=0)...
[2104] 21:33:37: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:37: RASCS_AuthNotify
[2104] 21:33:37: RasPppGetInfo...
[2104] 21:33:37: RasPppGetInfo done(0), dwMsgId=9
[2104] 21:33:37: RDM errors=0,0
[2104] 21:33:37: WaitForEvent
[2104] 21:33:40: WorkerThread: pOverlapped=0x34c00f4
[2104] 21:33:40: WorkerThread: type=3
[2104] 21:33:40: Unblock i=2, h=0x34c007c
[2104] 21:33:40: setting rasman state to 6
[2104] 21:33:40: NotifyCaller(nt=0x2,su=1,s=6,e=0,xe=0)...
[2104] 21:33:40: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:40: RASCS_AuthNotify
[2104] 21:33:40: RasPppGetInfo...
[2104] 21:33:40: RasPppGetInfo done(0), dwMsgId=9
[2104] 21:33:40: RDM errors=0,0
[2104] 21:33:40: WaitForEvent
[2104] 21:33:44: WorkerThread: pOverlapped=0x34c00f4
[2104] 21:33:44: WorkerThread: type=3
[2104] 21:33:44: Unblock i=2, h=0x34c007c
[2104] 21:33:44: setting rasman state to 6
[2104] 21:33:44: NotifyCaller(nt=0x2,su=1,s=6,e=0,xe=0)...
[2104] 21:33:44: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:44: RASCS_AuthNotify
[2104] 21:33:44: RasPppGetInfo...
[2104] 21:33:44: RasPppGetInfo done(0), dwMsgId=9
[2104] 21:33:44: RDM errors=0,0
[2104] 21:33:44: WaitForEvent
[2104] 21:33:48: WorkerThread: pOverlapped=0x34c00f4
[2104] 21:33:48: WorkerThread: type=3
[2104] 21:33:48: Unblock i=2, h=0x34c007c
[2104] 21:33:48: setting rasman state to 6
[2104] 21:33:48: NotifyCaller(nt=0x2,su=1,s=6,e=0,xe=0)...
[2104] 21:33:48: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:48: RASCS_AuthNotify
[2104] 21:33:48: RasPppGetInfo...
[2104] 21:33:48: RasPppGetInfo done(0), dwMsgId=9
[2104] 21:33:48: RDM errors=0,0
[2104] 21:33:48: WaitForEvent
[2104] 21:33:52: WorkerThread: pOverlapped=0x34c00f4
[2104] 21:33:52: WorkerThread: type=3
[2104] 21:33:52: Unblock i=2, h=0x34c007c
[2104] 21:33:52: setting rasman state to 6
[2104] 21:33:52: NotifyCaller(nt=0x2,su=1,s=6,e=0,xe=0)...
[2104] 21:33:52: NotifyCaller done (dwNotifyResult=1)
[2104] 21:33:52: RASCS_AuthNotify
[2104] 21:33:52: RasPppGetInfo...
[2104] 21:33:52: RasPppGetInfo done(0), dwMsgId=9
[2104] 21:33:52: RDM errors=0,0
[2104] 21:33:52: WaitForEvent

Open in new window

ASKER CERTIFIED SOLUTION
Avatar of TheCleaner
TheCleaner
Flag of United States of America image

Link to home
membership
Create an account to see this answer
Signing up is free. No credit card required.
Create Account
SOLUTION
Avatar of tigermatt
tigermatt
Flag of United Kingdom of Great Britain and Northern Ireland image

Link to home
membership
Create an account to see this answer
Signing up is free. No credit card required.
Create Account
Avatar of enari
enari

ASKER

I thought it was GRE too., but have 2 reasons to rule out

1. doesn't work in DMZ.  (when i made it dmz, i disabled the pptp route shown below)

2.  I have a DL-524 router... under the firewall it says:

       Action        Name        Source        Destination        Protocol
      Allow      PPTP      WAN,*      LAN,192.168.0.200      TCP,1723      
      Allow      PPTP_GRE      WAN,*      LAN,192.168.0.200      GRE


So... I can connect OUT from my desktop to work, with no problem - so GRE is accepted as incomming.
I can connect internally to my VPN, this works, so server seems set right?
I can't go Out from my desktop to my server using my external IP.  This fails with what i showed in the log.

So .. connecting to 192.168.0.200 internal to internal works
connecting to same server using external ip addres fails, with .200 set as a DMZ or with the rules above.

Avatar of enari

ASKER

I replaced the router with one that allows GREs