[pptp-server] Errors 650 629 645

[John Sutton]

Has anyone got a solution to this hideous problem?  It runs as follows:

kernel 2.0.36
pptpd 0.8.7
pppd 2.3.7
Win95 + DUN 1.3 (get the latter from:
 http://www.microsoft.com/ntserver/nts/downloads/recommended/dun13win95/dun13sites.asp

I had very little success at all before I installed this.)

First case is where the PPTP connection is over a LAN.  Using the Microsoft VPN adapter, the first time you attempt to connect you get:

Error 650: The computer you're dialling in to does not respond to a network request.

If you immediately try again, you get:

Error 629: You have been disconnected from the computer you dialled.

And you get this repeatedly.  Examination of the pptpd debug log would seem to indicate that the client makes absolutely no attempt to reconnect to the server.  Guessing here (and what else can you do with Windows?), it appears that the client notices that the socket which it established on the first attempt is still ESTABLISHED and so announces just the opposite - "You have been disconnected..." - classic stuff really.

The "solution" is to kill the pppd after you've got the 650 and then retry.  This works providing you retry *before* the connection to port 1723 timesout.  If you leave it too long, you'll be back at the 650 stage or stuck in permanent 629 state which can only be resolved with a reboot...  Well, after many hours of testing, that's my best effort at a description of the situation.

Second case is when the PPTP connection is over a dialup.  In this case instead of the 650 you get a 645:

Error 645: The Microsoft Dial-Up Adapter is in use or not responding properly.

On retrying you get the 629 as before.  But the so-called "solution" above does not work in this case.  I can't get it to work at all...

Here is the log from the LAN case:

PPTPD [1515]: dots=192 168 2 30-33
PPTPD [1515]: dots=192 168 2 40-43
PPTPD [1515]: Manager Started
PPTPD [1520]: launching PPTPCTRL...
PPTPD [1520]: CTRL: I got a valid PPTP packet with control type 1
PPTPD [1520]: CTRL: I've made a suitable START_CTRL_CONN_RPLY..
PPTPD [1520]: CTRL: I wrote 156 bytes to the client.
PPTPD [1520]: CTRL: sent packet to client
PPTPD [1520]: MSG TYPE READ = 1, stat2 (send) = 156
PPTPD [1520]: CTRL: I got a valid PPTP packet with control type 7
PPTPD [1520]: CTRL: I've made a suitable OUT_CALL_RPLY..
PPTPD [1520]: CTRL: I wrote 32 bytes to the client.
PPTPD [1520]: CTRL: sent packet to client
PPTPD [1520]: CTRL: permission to launch pppd/gre granted
PPTPD [1520]: MSG TYPE READ = 7, stat2 (send) = 32
PPTPD [1521]: CTRL: Calling startCall
PPTPD [1521]: CTRL: ttydev = /dev/ttya0
PPTPD [1522]: launching PPPD...
PPTPD [1523]: CTRL: pty_fd = 10
PPTPD [1523]: CTRL: ctrl_gre_fd = 8, 9
PPTPD [1523]: CTRL: callid = 0x0000
PPTPD [1523]: CTRL: inet_addr = 0xc0a80101
PPTPD [1523]: Launcher: ptyfd=10
PPTPD [1523]: Launcher: fd1=8
PPTPD [1523]: Launcher: fd2=9
PPTPD [1523]: GRE manager running
PPTPD [1523]: GRE: ctrl_gre_fd = 8, 9
PPTPD [1523]: GRE: ptyfd = 10
PPTPD [1523]: Entering GRE dispatch loop
pppd[1527]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xfb4ad8b8> <pcomp> <accomp>]
PPTPD [1523]: Closing IP connection...
PPTPD [1523]: Closing PTY_FD...

[ Is this the problem (above)?  The IP connection has been closed before the pppd dialog has had a chance? ]

pppd[1527]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xfb4ad8b8> <pcomp> <accomp>]
last message repeated 8 times
PPTPD [1520]: CTRL: I got a valid PPTP packet with control type 12
PPTPD [1520]: CTRL: I've made a suitable CALL DISCONNECT reply packet..
PPTPD [1520]: CTRL: I wrote 148 bytes to the client.
PPTPD [1520]: CTRL: sent packet to client
PPTPD [1520]: We have told the client we r disconnecting...
PPTPD [1520]: MSG TYPE READ = 12, stat2 (send) = 148
PPTPD [1520]: CTRL: this CTRL session finished...
PPTPD [1520]: CTRL: manger has been informed of our death

[ Here is where I kill the outstanding pppd and retry ]

PPTPD [1529]: launching PPTPCTRL...
PPTPD [1529]: CTRL: I got a valid PPTP packet with control type 1
PPTPD [1529]: CTRL: I've made a suitable START_CTRL_CONN_RPLY..
PPTPD [1529]: CTRL: I wrote 156 bytes to the client.
PPTPD [1529]: CTRL: sent packet to client
PPTPD [1529]: MSG TYPE READ = 1, stat2 (send) = 156
PPTPD [1529]: CTRL: I got a valid PPTP packet with control type 7
PPTPD [1529]: CTRL: I've made a suitable OUT_CALL_RPLY..
PPTPD [1529]: CTRL: I wrote 32 bytes to the client.
PPTPD [1529]: CTRL: sent packet to client
PPTPD [1529]: CTRL: permission to launch pppd/gre granted
PPTPD [1529]: MSG TYPE READ = 7, stat2 (send) = 32
PPTPD [1530]: CTRL: Calling startCall
PPTPD [1530]: CTRL: ttydev = /dev/ttya0
PPTPD [1531]: launching PPPD...
PPTPD [1532]: CTRL: pty_fd = 11
PPTPD [1532]: CTRL: ctrl_gre_fd = 9, 10
PPTPD [1532]: CTRL: callid = 0x0000
PPTPD [1532]: CTRL: inet_addr = 0xc0a80101
PPTPD [1532]: Launcher: ptyfd=11
PPTPD [1532]: Launcher: fd1=9
PPTPD [1532]: Launcher: fd2=10
PPTPD [1532]: GRE manager running
pppd[1533]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x54e5f0c> <pcomp> <accomp>]
pppd[1533]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x54e5f0c> <pcomp> <accomp>]

So now it's talking.

Is all of this peculiar to Win95 and fixable by changing to Win98?

TIA
John Sutton

***************************************************
John Sutton
SCL Computer Services
URL http://www.scl.co.uk/
Tel. +44 (0) 1239 621021
***************************************************