[pptp-server] Connection Problems

David Morris lists at morris-clan.net
Thu Aug 30 15:19:44 CDT 2001 

I recently installed pptp-server on my Debian Linux box (Version
1.0.0).  I followed many suggestions in various versions of HOWTOs and
FAQs arround the net, but nothing works.  A summary of my problem:

I configure and start up pptpd, and it has no complaints.  I then go
to connect the VPN from a windows computer, but the connection is
never made.  It fails with an error:

    Error 629: The port was disconnected by the remote machine.

(This error produced by WinNT.  Win2k produces a similar but different
error.)


All configuration files are included below, along with all log files.
Any suggetions on what is going wrong?

(Usernames/Passwords/IP Addresses changed to protect the guilty).

Thanks for any suggestions anyone can provide.

--David

--- pptpd.conf ---


speed 115200
option /etc/ppp/pptpd-options
debug
localip 192.168.1.101-199
remoteip 192.168.4.101-199

--- /etc/ppp/pptpd-options ---

debug
name pptpdserver
auth
#require-chap
proxyarp

## Old Options (commented out to try above simpler version)
## Various combinations of the above and below have been tried.
#auth
#require-chap
#require-chapms
#require-chapms-v2
#+chap
#+chapms
#+chapms-v2
#mppe-40
#mppe-128
#mppe-stateless

## Fill in your addresses
#ms-dns xx.xx.xx.xx
#ms-wins xx.xx.xx.xx

## Fill in your netmask
#netmask 255.255.255.0

## some defaults
#defaultroute
#proxyarp
#lock

--- /etc/ppp/chap-secrets ---

# Secrets for authentication using CHAP
# client	server	secret			IP addresses
pptpduser pptpdserver password *

--- /var/log/syslog ---

Aug 30 14:03:16 pptpdserver pptpd[14618]: MGR: Manager process started
Aug 30 14:05:06 pptpdserver pptpd[14624]: MGR: Launching /usr/sbin/pptpctrl to handle client
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: local address = 192.168.1.101
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: remote address = 192.168.4.101
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: pppd speed = 115200
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: pppd options file = /etc/ppp/pptpd-options
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: Client xx.xx.xx.xx control connection started
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: Received PPTP Control Message (type: 1)
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: Made a START CTRL CONN RPLY packet
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: I wrote 156 bytes to the client.
Aug 30 14:05:06 pptpdserver pptpd[14624]: CTRL: Sent packet to client
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Received PPTP Control Message (type: 7)
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Set parameters to 10000000 maxbps, 3 window size
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Made a OUT CALL RPLY packet
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Starting call (launching pppd, opening GRE)
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: pty_fd = 5
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: tty_fd = 6
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: I wrote 32 bytes to the client.
Aug 30 14:05:07 pptpdserver pptpd[14625]: CTRL (PPPD Launcher): Connection speed = 115200
Aug 30 14:05:07 pptpdserver pptpd[14625]: CTRL (PPPD Launcher): local address = 192.168.1.101
Aug 30 14:05:07 pptpdserver pptpd[14625]: CTRL (PPPD Launcher): remote address = 192.168.4.101
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Sent packet to client
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Received PPTP Control Message (type: 15)
Aug 30 14:05:07 pptpdserver pptpd[14624]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug 30 14:05:07 pptpdserver pppd[14625]: pppd 2.3.11 started by root, uid 0
Aug 30 14:05:07 pptpdserver pppd[14625]: Using interface ppp0
Aug 30 14:05:07 pptpdserver pppd[14625]: Connect: ppp0 <--> /dev/pts/0
Aug 30 14:05:07 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:07 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:07 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:10 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:10 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:10 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:13 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:13 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:13 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:16 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:17 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:17 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:19 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:21 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:21 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:22 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:25 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:25 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:25 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:28 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:29 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:29 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:31 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:33 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:33 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:34 pptpdserver pppd[14625]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0x69bf931a> <pcomp> <accomp>]
Aug 30 14:05:37 pptpdserver pppd[14625]: rcvd [LCP ConfReq id=0x0 <magic 0x7eb5> <pcomp> <accomp> <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:37 pptpdserver pppd[14625]: sent [LCP ConfRej id=0x0 <callback CBCP> < 11 04 06 4e> < 13 17 01 7b 69 a8 f0 9b c9 11 d5 84 ca 00 b0 d0 27 f4 9b 00 00 00 00>]
Aug 30 14:05:37 pptpdserver pppd[14625]: LCP: timeout sending Config-Requests 
Aug 30 14:05:38 pptpdserver pppd[14625]: Connection terminated.
Aug 30 14:05:38 pptpdserver pppd[14625]: Exit.
Aug 30 14:05:38 pptpdserver pptpd[14618]: MGR: Reaped child 14624
Aug 30 14:05:38 pptpdserver pptpd[14624]: GRE: read(fd=5,buffer=25bd8,len=8196) from PTY failed: status = -1 error = Input/output error
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: PTY read or GRE write failed (pty,gre)=(5,6)
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Client xx.xx.xx.xx control connection finished
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Exiting with active call
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Made a CALL DISCONNECT RPLY packet
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Couldn't write packet to client.
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Made a STOP CTRL REQ packet
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Couldn't write packet to client.
Aug 30 14:05:38 pptpdserver pptpd[14624]: CTRL: Exiting now

More information about the pptp-server mailing list