[pptp-server] auth success, connection still dropped

[Frank van Tol]

Going from a linux client to Windows server, we keep running into following
scenario:

AUTH is OK, we get an IPaddress but connection is dropped right after that.
Any ideas what's causign this?  I've X-out some details.

-Frank

May  2 11:44:06 towtruck pptp[1024]:
log[pptp_dispatch_ctrl_packet:pptp_ctrl.c:538]: Client connection
established.
May  2 11:44:07 towtruck pptp[1024]:
log[pptp_dispatch_ctrl_packet:pptp_ctrl.c:645]: Outgoing call established
(call ID 0, peer's call ID 986). 
May  2 11:44:07 towtruck pppd[1026]: pppd 2.4.0 started by root, uid 0
May  2 11:44:07 towtruck pppd[1026]: Using interface ppp0
May  2 11:44:07 towtruck pppd[1026]: Connect: ppp0 <--> /dev/pts/3
May  2 11:44:07 towtruck pppd[1026]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x222a4b56> <pcomp> <accomp>]
May  2 11:44:09 towtruck pppd[1026]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0x222a4b56> <pcomp> <accomp>]
May  2 11:44:10 towtruck pppd[1026]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x222a4b56> <pcomp> <accomp>]
May  2 11:44:10 towtruck pppd[1026]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0x222a4b56> <pcomp> <accomp>]
May  2 11:44:11 towtruck pppd[1026]: rcvd [LCP ConfReq id=0x1 <auth chap 81>
<magic 0x786951d7> <pcomp> <accomp> < 0d 03 06> <mrru 1614> <endpoint
[local:de.5b.df.f0.4c.36.49.e1.8d.96.d1.c4.f6.f4.f3.c7.00.00.00.00]> < 17 04
14 e8>]
May  2 11:44:11 towtruck pppd[1026]: sent [LCP ConfRej id=0x1 < 0d 03 06>
<mrru 1614> < 17 04 14 e8>]
May  2 11:44:11 towtruck pppd[1026]: rcvd [LCP ConfReq id=0x2 <auth chap 81>
<magic 0x786951d7> <pcomp> <accomp> <endpoint
[local:de.5b.df.f0.4c.36.49.e1.8d.96.d1.c4.f6.f4.f3.c7.00.00.00.00]>]
May  2 11:44:11 towtruck pppd[1026]: sent [LCP ConfAck id=0x2 <auth chap 81>
<magic 0x786951d7> <pcomp> <accomp> <endpoint
[local:de.5b.df.f0.4c.36.49.e1.8d.96.d1.c4.f6.f4.f3.c7.00.00.00.00]>]
May  2 11:44:11 towtruck pppd[1026]: sent [LCP EchoReq id=0x0
magic=0x222a4b56]
May  2 11:44:11 towtruck pppd[1026]: rcvd [CHAP Challenge id=0x0
<1e136c418425ce464ba6760a3111d076>, name = "XXXXX"]
May  2 11:44:11 towtruck pppd[1026]: sent [CHAP Response id=0x0
<762bc3e53d7de51328228db3ecea5f9b00000000000000002cb061bfc0c334f3417380cc0e5
ad9cbfe4f7d1c6456180300>, name = "XXXXXXX"]
May  2 11:44:11 towtruck pppd[1026]: rcvd [LCP EchoRep id=0x0
magic=0x786951d7]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CHAP Success id=0x0
"S=91D1BA63539D04C96759E125863B762EC7052671"]
May  2 11:44:12 towtruck pppd[1026]: Remote message:
S=91D1BA63539D04C96759E125863B762EC7052671
May  2 11:44:12 towtruck pppd[1026]: sent [IPCP ConfReq id=0x1 <addr
0.0.0.0> <compress VJ 0f 01>]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfReq id=0x4 <mppe 1 0 0
c1>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x1]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfRej id=0x4 <mppe 1 0 0
60>]
May  2 11:44:12 towtruck pppd[1026]: rcvd [IPCP ConfReq id=0x5 <addr
XXX.44.117.100>]
May  2 11:44:12 towtruck pppd[1026]: sent [IPCP ConfAck id=0x5 <addr
XXX.44.117.100>]
May  2 11:44:12 towtruck pppd[1026]: rcvd [IPCP ConfRej id=0x1 <compress VJ
0f 01>]
May  2 11:44:12 towtruck pppd[1026]: sent [IPCP ConfReq id=0x2 <addr
0.0.0.0>]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x1 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x2]
May  2 11:44:12 towtruck pppd[1026]: rcvd [IPCP ConfNak id=0x2 <addr
XXX.44.117.102>]
May  2 11:44:12 towtruck pppd[1026]: sent [IPCP ConfReq id=0x3 <addr
XXX.44.117.102>]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x2 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x3]
May  2 11:44:12 towtruck pppd[1026]: rcvd [IPCP ConfAck id=0x3 <addr
XXX.44.117.102>]
May  2 11:44:12 towtruck pppd[1026]: Cannot determine ethernet address for
proxy ARP
May  2 11:44:12 towtruck pppd[1026]: local  IP address XXX.XX.117.102
May  2 11:44:12 towtruck pppd[1026]: remote IP address XXX.XX.117.100
May  2 11:44:12 towtruck pppd[1026]: Script /etc/ppp/ip-up started (pid
1028)
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x3 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x4]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x4 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x5]
May  2 11:44:12 towtruck pppd[1026]: Script /etc/ppp/ip-up finished (pid
1028), status = 0x0
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x5 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x6]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x6 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x7]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x7 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x8]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x8 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0x9]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0x9 <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0xa]
May  2 11:44:12 towtruck pppd[1026]: rcvd [CCP ConfNak id=0xa <mppe 0 0 0
0>]
May  2 11:44:12 towtruck pppd[1026]: sent [CCP ConfReq id=0xb]
May  2 11:44:12 towtruck pppd[1026]: rcvd [LCP TermReq id=0x6
"xiQ\37777777727\000<\37777777715t\000\000\002\37777777746"]
May  2 11:44:12 towtruck pppd[1026]: LCP terminated by peer
(xiQM-W^@<M-Mt^@^@^BM-f)
May  2 11:44:12 towtruck pppd[1026]: Script /etc/ppp/ip-down started (pid
1031)
May  2 11:44:12 towtruck pppd[1026]: sent [LCP TermAck id=0x6]
May  2 11:44:12 towtruck pppd[1026]: Script /etc/ppp/ip-down finished (pid
1031), status = 0x0
May  2 11:44:15 towtruck pppd[1026]: Connection terminated.
May  2 11:44:15 towtruck pppd[1026]: Connect time 0.1 minutes.
May  2 11:44:15 towtruck pppd[1026]: Sent 602 bytes, received 780 bytes.
May  2 11:44:16 towtruck pppd[1026]: Exit.
May  2 11:44:16 towtruck pptp[1024]: log[pptp_conn_close:pptp_ctrl.c:275]:
Closing PPTP connection