This discussion has been locked.

You can no longer post new replies to this discussion. If you have a question you can start a new discussion

Version 5.001-5.003: PPTP DOES NOT WORK

PPTP does not function properly.  When running with local accounts, the first attempt always fails with a 737 (loopback detected).  The second or third or fourth attempts may work.

With RADIUS Authentication, the connection never works.  The following is the log file of what ASL reports:

2004:04:28-10:58:06 (none) pptpd[16824]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: local address = 10.67.86.1
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: remote address = 10.67.86.2
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: pppd options file = /etc/ppp/options
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Client 12.5.250.194 control connection started
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Received PPTP Control Message (type: 1)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Made a START CTRL CONN RPLY packet
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: I wrote 156 bytes to the client.
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Sent packet to client
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Received PPTP Control Message (type: 7)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Set parameters to 1525 maxbps, 64 window size
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Made a OUT CALL RPLY packet
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Starting call (launching pppd, opening GRE)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: pty_fd = 5
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: tty_fd = 6
2004:04:28-10:58:06 (none) pptpd[16825]: CTRL (PPPD Launcher): Connection speed = 115200
2004:04:28-10:58:06 (none) pptpd[16825]: CTRL (PPPD Launcher): local address = 10.67.86.1
2004:04:28-10:58:06 (none) pptpd[16825]: CTRL (PPPD Launcher): remote address = 10.67.86.2
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Plugin /usr/sbin/radius.so loaded.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: RADIUS plugin initialized.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Plugin /usr/sbin/radattr.so loaded.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: RADATTR plugin initialized.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Plugin /usr/sbin/radius.so loaded.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: RADIUS plugin initialized.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Plugin /usr/sbin/radattr.so loaded.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: RADATTR plugin initialized.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: pppd 2.4.2 started by (unknown), uid 0
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: I wrote 32 bytes to the client.
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Sent packet to client
2004:04:28-10:58:06 (none) pppd-pptp[16825]: using channel 20
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Starting negotiation on /dev/ttyp0
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP ConfReq id=0x1        ]
2004:04:28-10:58:06 (none) pptpd[16824]: GRE: Bad checksum from pppd.
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Received PPTP Control Message (type: 15)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Got a SET LINK INFO packet with standard ACCMs
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP ConfReq id=0x0     ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP ConfRej id=0x0 ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP ConfRej id=0x1  ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP ConfReq id=0x2      ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP ConfReq id=0x1    ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP ConfAck id=0x1    ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP ConfAck id=0x2      ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP EchoReq id=0x0 magic=0xff6215ec]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [CHAP Challenge id=0x2 , name = "pptp"]
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Received PPTP Control Message (type: 15)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP code=0xc id=0x2 61 99 4c 85 4d 53 52 41 53 56 35 2e 31 30]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP CodeRej id=0x3 0c 02 00 12 61 99 4c 85 4d 53 52 41 53 56 35 2e 31 30]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP code=0xc id=0x3 61 99 4c 85 4d 53 52 41 53 2d 31 2d 57 32 4e 5a 34 58 36 39 30 32]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP CodeRej id=0x4 0c 03 00 1a 61 99 4c 85 4d 53 52 41 53 2d 31 2d 57 32 4e 5a 34 58 36 39 30 32]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP EchoRep id=0x0 magic=0x61994c85]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [CHAP Response id=0x2 , name = "tallison"]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rc_map2id: can't find tty /dev/ in map database
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [CHAP Success id=0x2 "S=07149F98931C007058A4CD1A50C73B2CA3B1AB4D"]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Using interface ppp0
2004:04:28-10:58:06 (none) pppd-pptp[16825]: MPPE required, but keys are not available. Possible plugin problem?
2004:04:28-10:58:06 (none) pppd-pptp[16825]: sent [LCP TermReq id=0x5 "MPPE required but not available"]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [CCP ConfReq id=0x4 ]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Discarded non-LCP packet when LCP not open
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [IPCP ConfReq id=0x5     ]
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Received PPTP Control Message (type: 15)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Got a SET LINK INFO packet with standard ACCMs
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Discarded non-LCP packet when LCP not open
2004:04:28-10:58:06 (none) pppd-pptp[16825]: rcvd [LCP TermAck id=0x5 "MPPE required but not available"]
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Connection terminated.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Connect time 0.0 minutes.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Sent 0 bytes, received 0 bytes.
2004:04:28-10:58:06 (none) pppd-pptp[16825]: Exit.
2004:04:28-10:58:06 (none) pptpd[16824]: GRE: read(fd=5,buffer=804e7a0,len=8196) from PTY failed: status = -1 error = Input/output error
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: PTY read or GRE write failed (pty,gre)=(5,6)
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Closing child BCrelay with pid 0
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Closing child ppp with pid 16825
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Client 12.5.250.194 control connection finished
2004:04:28-10:58:06 (none) pptpd[16824]: CTRL: Exiting now
2004:04:28-10:58:06 (none) pptpd[16820]: MGR: Reaped child 16824

This thread was automatically locked due to age.

0 scottie over 21 years ago

I've got the same problem. ASL 5.003 [:(]
According to the v5 manual for setting up IAS you must enable PAP authentication, the log on the IAS server shows that the request is using MSCHAPv2 not PAP. Which one is it?

2004:04:29-10:31:03 (none) pptpd[29497]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: local address = 10.220.167.1
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: remote address = 10.220.167.3
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: pppd options file = /etc/ppp/options
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Client 192.168.100.251 control connection started
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received PPTP Control Message (type: 1)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Made a START CTRL CONN RPLY packet
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: I wrote 156 bytes to the client.
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Sent packet to client
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received PPTP Control Message (type: 7)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Set parameters to 1525 maxbps, 64 window size
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Made a OUT CALL RPLY packet
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Starting call (launching pppd, opening GRE)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: pty_fd = 5
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: tty_fd = 6
2004:04:29-10:31:03 (none) pptpd[29498]: CTRL (PPPD Launcher): Connection speed = 115200
2004:04:29-10:31:03 (none) pptpd[29498]: CTRL (PPPD Launcher): local address = 10.220.167.1
2004:04:29-10:31:03 (none) pptpd[29498]: CTRL (PPPD Launcher): remote address = 10.220.167.3
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Plugin /usr/sbin/radius.so loaded.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: RADIUS plugin initialized.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Plugin /usr/sbin/radattr.so loaded.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: RADATTR plugin initialized.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Plugin /usr/sbin/radius.so loaded.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: RADIUS plugin initialized.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Plugin /usr/sbin/radattr.so loaded.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: RADATTR plugin initialized.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: pppd 2.4.2 started by (unknown), uid 0
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: I wrote 32 bytes to the client.
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Sent packet to client
2004:04:29-10:31:03 (none) pppd-pptp[29498]: using channel 91
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received PPTP Control Message (type: 15)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Got a SET LINK INFO packet with standard ACCMs
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Starting negotiation on /dev/ttyp0
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP ConfReq id=0x1        ]
2004:04:29-10:31:03 (none) pptpd[29497]: GRE: Bad checksum from pppd.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP ConfReq id=0x0     ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP ConfRej id=0x0 ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP ConfRej id=0x1  ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP ConfReq id=0x2      ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP ConfReq id=0x1    ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP ConfAck id=0x1    ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP ConfAck id=0x2      ]
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received PPTP Control Message (type: 15)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP EchoReq id=0x0 magic=0x87bf6d2]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [CHAP Challenge id=0xeb , name = "pptp"]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP code=0xc id=0x2 59 ce 10 aa 4d 53 52 41 53 56 35 2e 31 30]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP CodeRej id=0x3 0c 02 00 12 59 ce 10 aa 4d 53 52 41 53 56 35 2e 31 30]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP code=0xc id=0x3 59 ce 10 aa 4d 53 52 41 53 2d 31 2d 49 54 2d 4d 2d 58 50 30 32]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP CodeRej id=0x4 0c 03 00 19 59 ce 10 aa 4d 53 52 41 53 2d 31 2d 49 54 2d 4d 2d 58 50 30 32]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP EchoRep id=0x0 magic=0x59ce10aa]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [CHAP Response id=0xeb , name = "josephss"]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rc_map2id: can't find tty /dev/ in map database
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [CHAP Success id=0xeb "S=544FEE205DAD22610A8BC9716A567616F0EBED1D"]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Using interface ppp0
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [CCP ConfReq id=0x1 ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [CCP ConfReq id=0x4 ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: MPPE required but peer negotiation failed
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [LCP TermReq id=0x5 "MPPE required but peer negotiation failed"]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: sent [CCP ConfRej id=0x4 ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [IPCP ConfReq id=0x5     ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Discarded non-LCP packet when LCP not open
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [CCP ConfAck id=0x1 ]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Discarded non-LCP packet when LCP not open
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received PPTP Control Message (type: 15)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Got a SET LINK INFO packet with standard ACCMs
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received PPTP Control Message (type: 12)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Made a CALL DISCONNECT RPLY packet
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Received CALL CLR request (closing call)
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Closing child BCrelay with pid 0
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Closing child ppp with pid 29498
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Client 192.168.100.251 control connection finished
2004:04:29-10:31:03 (none) pptpd[29497]: CTRL: Exiting now
2004:04:29-10:31:03 (none) pptpd[28125]: MGR: Reaped child 29497
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Terminating on signal 2.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: rcvd [LCP TermAck id=0x5 "MPPE required but peer negotiation failed"]
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Connection terminated.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Connect time 0.0 minutes.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Sent 10 bytes, received 54 bytes.
2004:04:29-10:31:03 (none) pppd-pptp[29498]: tcflush failed: Input/output error
2004:04:29-10:31:03 (none) pppd-pptp[29498]: Exit.
Cancel
Vote Up 0 Vote Down

Cancel
0 toddyo_01 over 21 years ago

It should be MSCHAP not PAP
Cancel
Vote Up 0 Vote Down

Cancel
0 Stephan_Scholz over 21 years ago in reply to toddyo_01

Hi toddyo,

for IAS, please make sure that the "No encryption" option in your IAS profile (tab "Encryption") is deactivated.
Also MSCHAPv2 is required for PPTP.

Hope this helps,
Stephan
Cancel
Vote Up 0 Vote Down

Cancel
0 toddyo_01 over 21 years ago in reply to Stephan_Scholz

That fixed it --- Thanks!
Cancel
Vote Up 0 Vote Down

Cancel