[pptp-server] 128 bit encryption fails

Steve Wood swood at collectrite.com
Thu Oct 5 10:58:37 CDT 2000


Hi all,
Something weird started happening to my pptp server yesterday and I have no
idea what caused it.  I have been running my pptpd server for 50 days with
no problems whatsoever but suddenly yesterday my win98/2000 clients stopped
connecting to my server with a (571 encryption not supported) error.  If I
do not have the 128 bit upgrade on my win box then I can connect fine with
40 bit encryption but if it is installed i have to disable all encryption to
connect.  I haven't made any changes and I checked all my modules and they
are all loaded.  Has anybody else seen this strange behavior before?

Here is a typical win2000/98se session trying to connect with 128bit
encryption. I'm not sure what the error with select() is but maybe this is
my problem?
--------------------------------BEGIN--------------------------------------
Oct  4 22:13:46 spock pptpd[2818]: MGR: Reaped child 2870
Oct  4 23:24:29 spock pptpd[2958]: MGR: Launching /usr/local/sbin/pptpctrl
to handle client
Oct  4 23:24:29 spock pptpd[2958]: CTRL: local address = 198.186.150.101
Oct  4 23:24:29 spock pptpd[2958]: CTRL: remote address = 198.186.150.112
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Client 209.136.33.71 control
connection started
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Received PPTP Control Message
(type: 1)
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Made a START CTRL CONN RPLY packet
Oct  4 23:24:29 spock pptpd[2958]: CTRL: I wrote 156 bytes to the client.
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Sent packet to client
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Received PPTP Control Message
(type: 7)
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Set parameters to 1525 maxbps, 64
window size
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Made a OUT CALL RPLY packet
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Starting call (launching pppd,
opening GRE)
Oct  4 23:24:29 spock pptpd[2958]: CTRL: pty_fd = 4
Oct  4 23:24:29 spock pptpd[2958]: CTRL: tty_fd = 5
Oct  4 23:24:29 spock pptpd[2958]: CTRL: I wrote 32 bytes to the client.
Oct  4 23:24:29 spock pptpd[2959]: CTRL (PPPD Launcher): Connection speed =
115200
Oct  4 23:24:29 spock pptpd[2959]: CTRL (PPPD Launcher): local address =
198.186.150.101
Oct  4 23:24:29 spock pptpd[2959]: CTRL (PPPD Launcher): remote address =
198.186.150.112
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Sent packet to client
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Received PPTP Control Message
(type: 15)
Oct  4 23:24:30 spock pptpd[2958]: CTRL: Got a SET LINK INFO packet with
standard ACCMs
Oct  4 23:24:30 spock pptpd[2958]: GRE: Discarding duplicate packet
Oct  4 23:24:31 spock pptpd[2958]: CTRL: Received PPTP Control Message
(type: 15)
Oct  4 23:24:31 spock pptpd[2958]: CTRL: Ignored a SET LINK INFO packet with
real ACCMs!
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Received PPTP Control Message
(type: 15)
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Ignored a SET LINK INFO packet with
real ACCMs!
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Received PPTP Control Message
(type: 12)
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Made a CALL DISCONNECT RPLY packet
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Received CALL CLR request (closing
call)
Oct  4 23:24:32 spock pptpd[2958]: CTRL: I wrote 148 bytes to the client.
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Sent packet to client
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Error with select(), quitting
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Client 209.136.33.71 control
connection finished
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Exiting now
---------------------------------END---------------------------------------

here are the corresponding entries in my messages file:
--------------------------------BEGIN--------------------------------------
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Client 209.136.33.71 control
connection started
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Client 209.136.33.71 control
connection started
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Starting call (launching pppd,
opening GRE)
Oct  4 23:24:29 spock pptpd[2958]: CTRL: Starting call (launching pppd,
opening GRE)
Oct  4 23:24:29 spock kernel: CSLIP: code copyright 1989 Regents of the
University of California
Oct  4 23:24:29 spock kernel: CSLIP: code copyright 1989 Regents of the
University of California
Oct  4 23:24:30 spock kernel: PPP: version 2.3.7 (demand dialling)
Oct  4 23:24:30 spock kernel: PPP: version 2.3.7 (demand dialling)
Oct  4 23:24:30 spock kernel: PPP line discipline registered.
Oct  4 23:24:30 spock kernel: PPP line discipline registered.
Oct  4 23:24:30 spock kernel: registered device ppp0
Oct  4 23:24:30 spock kernel: registered device ppp0
Oct  4 23:24:30 spock pppd[2959]: pppd 2.3.11 started by root, uid 0
Oct  4 23:24:30 spock pppd[2959]: pppd 2.3.11 started by root, uid 0
Oct  4 23:24:30 spock pppd[2959]: Using interface ppp0
Oct  4 23:24:30 spock pppd[2959]: Using interface ppp0
Oct  4 23:24:30 spock pppd[2959]: Connect: ppp0 <--> /dev/pts/0
Oct  4 23:24:30 spock pppd[2959]: Connect: ppp0 <--> /dev/pts/0
Oct  4 23:24:30 spock pptpd[2958]: GRE: Discarding duplicate packet
Oct  4 23:24:30 spock pptpd[2958]: GRE: Discarding duplicate packet
Oct  4 23:24:31 spock pptpd[2958]: CTRL: Ignored a SET LINK INFO packet with
real ACCMs!
Oct  4 23:24:31 spock pptpd[2958]: CTRL: Ignored a SET LINK INFO packet with
real ACCMs!
Oct  4 23:24:31 spock kernel: PPP BSD Compression module registered
Oct  4 23:24:31 spock kernel: PPP BSD Compression module registered
Oct  4 23:24:32 spock kernel: PPP MPPE compression module registered
Oct  4 23:24:32 spock kernel: PPP MPPE compression module registered
Oct  4 23:24:32 spock kernel: PPP Deflate Compression module registered
Oct  4 23:24:32 spock kernel: PPP Deflate Compression module registered
Oct  4 23:24:32 spock pppd[2959]: MSCHAP-v2 peer authentication succeeded
for Steve Wood
Oct  4 23:24:32 spock pppd[2959]: MSCHAP-v2 peer authentication succeeded
for Steve Wood
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Ignored a SET LINK INFO packet with
real ACCMs!
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Ignored a SET LINK INFO packet with
real ACCMs!
Oct  4 23:24:32 spock pppd[2959]: LCP terminated by peer
(^VM-^@,M-2^@<M-Mt^@^@^BM-f)
Oct  4 23:24:32 spock pppd[2959]: LCP terminated by peer
(^VM-^@,M-2^@<M-Mt^@^@^BM-f)
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Error with select(), quitting
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Error with select(), quitting
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Client 209.136.33.71 control
connection finished
Oct  4 23:24:32 spock pptpd[2958]: CTRL: Client 209.136.33.71 control
connection finished
Oct  4 23:24:32 spock pppd[2959]: Modem hangup
Oct  4 23:24:32 spock pppd[2959]: Modem hangup
Oct  4 23:24:32 spock pppd[2959]: Connection terminated.
Oct  4 23:24:32 spock pppd[2959]: Connection terminated.
Oct  4 23:24:32 spock pppd[2959]: Connect time 0.1 minutes.
Oct  4 23:24:32 spock pppd[2959]: Connect time 0.1 minutes.
Oct  4 23:24:32 spock pppd[2959]: Sent 612 bytes, received 606 bytes.
Oct  4 23:24:32 spock pppd[2959]: Sent 612 bytes, received 606 bytes.
Oct  4 23:24:32 spock pppd[2959]: Exit.
Oct  4 23:24:32 spock pppd[2959]: Exit.
---------------------------------END---------------------------------------

Here is my /etc/ppp/options file:
--------------------------------BEGIN--------------------------------------
lock
auth
+chap
+chapms
+chapms-v2
mppe-40
mppe-128
mppe-stateless
proxyarp
require-chap
ipcp-accept-local
ipcp-accept-remote
lcp-echo-failure 20
lcp-echo-interval 5
---------------------------------END---------------------------------------
-------------------------------------------------------------------------
Steve Wood                 (Systems Programmer/Analyst)     (505)647-0265
swood at collectrite.com      http://www.collectrite.com   Fax (505)523-1247
CRI Information Systems    Las Cruces, NM USA





More information about the pptp-server mailing list