[pptp-server] Authentication problem?

[Jeff Clark]

I'm assuming this is a ppp authentication problem.  The client is Windows
2000 Professional.  I've turned off software compression and ip header
compression.

Here is the log of a session:

Aug 16 13:46:51 junk pptpd[16168]: MGR: Manager process started
Aug 16 13:50:00 junk pptpd[16173]: MGR: Launching /usr/local/sbin/pptpctrl
to handle client
Aug 16 13:50:00 junk pptpd[16173]: CTRL: local address = <REMOVED>
Aug 16 13:50:00 junk pptpd[16173]: CTRL: remote address = <REMOVED>
Aug 16 13:50:00 junk pptpd[16173]: CTRL: pppd speed = 115200
Aug 16 13:50:00 junk pptpd[16173]: CTRL: Client 198.147.221.163 control
connection started
Aug 16 13:50:00 junk pptpd[16173]: CTRL: Received PPTP Control Message
(type: 1)
Aug 16 13:50:00 junk pptpd[16173]: CTRL: Made a START CTRL CONN RPLY
packet
Aug 16 13:50:00 junk pptpd[16173]: CTRL: I wrote 156 bytes to the client.
Aug 16 13:50:00 junk pptpd[16173]: CTRL: Sent packet to client
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Received PPTP Control Message
(type: 7)
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Set parameters to 1525 maxbps, 64
window size
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Made a OUT CALL RPLY packet
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Starting call (launching pppd,
opening GRE)
Aug 16 13:50:03 junk pptpd[16173]: CTRL: pty_fd = 4
Aug 16 13:50:03 junk pptpd[16173]: CTRL: tty_fd = 5
Aug 16 13:50:03 junk pptpd[16173]: CTRL: I wrote 32 bytes to the client.
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Sent packet to client
Aug 16 13:50:03 junk pptpd[16174]: CTRL (PPPD Launcher): Connection speed
= 115200
Aug 16 13:50:03 junk pptpd[16174]: CTRL (PPPD Launcher): local address =
129.186.203.26
Aug 16 13:50:03 junk pptpd[16174]: CTRL (PPPD Launcher): remote address =
192.168.203.30
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Received PPTP Control Message
(type: 15)
Aug 16 13:50:03 junk pptpd[16173]: CTRL: Got a SET LINK INFO packet with
standard ACCMs
Aug 16 13:50:03 junk pppd[16174]: pppd 2.3.8 started by root, uid 0
Aug 16 13:50:03 junk pppd[16174]: Using interface ppp0
Aug 16 13:50:03 junk pppd[16174]: Connect: ppp0 <--> /dev/pts/0
Aug 16 13:50:03 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:03 junk pptpd[16173]: GRE: Discarding duplicate packet
Aug 16 13:50:05 junk pppd[16174]: rcvd [LCP ConfReq id=0x1 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:05 junk pppd[16174]: sent [LCP ConfAck id=0x1 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:06 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:08 junk pppd[16174]: rcvd [LCP ConfReq id=0x2 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:08 junk pppd[16174]: sent [LCP ConfAck id=0x2 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:09 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:12 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:12 junk pppd[16174]: rcvd [LCP ConfReq id=0x3 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:12 junk pppd[16174]: sent [LCP ConfAck id=0x3 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:15 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:16 junk pppd[16174]: rcvd [LCP ConfReq id=0x4 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:16 junk pppd[16174]: sent [LCP ConfAck id=0x4 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:18 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:20 junk pppd[16174]: rcvd [LCP ConfReq id=0x5 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:20 junk pppd[16174]: sent [LCP ConfAck id=0x5 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:21 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:24 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:24 junk pppd[16174]: rcvd [LCP ConfReq id=0x6 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:24 junk pppd[16174]: sent [LCP ConfAck id=0x6 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:27 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:28 junk pppd[16174]: rcvd [LCP ConfReq id=0x7 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:28 junk pppd[16174]: sent [LCP ConfAck id=0x7 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:30 junk pppd[16174]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<auth chap 81> <magic 0x5d6a4235> <pcomp> <accomp>]
Aug 16 13:50:32 junk pppd[16174]: rcvd [LCP ConfReq id=0x8 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:32 junk pppd[16174]: sent [LCP ConfAck id=0x8 <magic
0x506e346d> <pcomp> <accomp>]
Aug 16 13:50:33 junk pptpd[16168]: MGR: Reaped child 16173
Aug 16 13:50:33 junk pptpd[16173]: GRE: read(fd=4,buffer=804d5a0,len=8196)
from PTY failed: status = -1 error = Input/output error
Aug 16 13:50:33 junk pptpd[16173]: CTRL: PTY read or GRE write failed
(pty,gre)=(4,5)
Aug 16 13:50:33 junk pptpd[16173]: CTRL: Client 198.147.221.163 control
connection finished
Aug 16 13:50:33 junk pptpd[16173]: CTRL: Exiting now
Aug 16 13:50:33 junk pppd[16174]: LCP: timeout sending Config-Requests
Aug 16 13:50:33 junk pppd[16174]: Connection terminated.
Aug 16 13:50:33 junk pppd[16174]: Exit.

/etc/ppp/options:

lock
name junk
debug
auth
+chap
+chapms
+chapms-v2
mppe-40
mppe-128
mppe-stateless
proxyarp

-

Any ideas?

Thanks

-
Jeff Clark
XNet Information Systems
"All Routes Lead Here"