[pptp-server] Re: [pptp-server] Strange problem ...

Fri Oct 19 03:06:27 CDT 2001

Hi,

first thank Roland for your hints and info. I'm still trying to
establish VPN with MPPE. Here's what I found ...

This problem with second connection (not at the same time!) I only
have with clients connecting by dial up. Before that I was testing
VPN with client connected by fixed leased line and I did not have
these problems. Could be coincidence though.

I'm trying to correct this situation with creating the ip-down.local
script. I'm getting into another sort of problem, it could not be
directly connected to pptpd. Sometimes it takes some time to unload
mppe module from memory, sometimes it is ok. Here are the logs. Look
where is exit and where the unloading. Of course, that if a client is
trying to log in and module was not unloaded yet, it just hangs.

Oct 17 21:00:13 indus pptpd[18123]: CTRL: Client 10.0.1.1 control
connection started
Oct 17 21:00:13 indus pptpd[18123]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 17 21:00:14 indus kernel: CSLIP: code copyright 1989 Regents of
the University of California
Oct 17 21:00:14 indus kernel: PPP generic driver version 2.4.1
Oct 17 21:00:14 indus pppd[18124]: pppd 2.4.0 started by root, uid 0
Oct 17 21:00:15 indus pppd[18124]: Using interface ppp0
Oct 17 21:00:15 indus pppd[18124]: Connect: ppp0 <--> /dev/pts/0
Oct 17 21:00:15 indus pptpd[18123]: GRE: Discarding duplicate packet
Oct 17 21:00:17 indus kernel: PPP BSD Compression module registered
Oct 17 21:00:17 indus kernel: PPP MPPE compression module registered
Oct 17 21:00:17 indus kernel: PPP Deflate Compression module
registered
Oct 17 21:00:17 indus pppd[18124]: MSCHAP-v2 peer authentication
succeeded for ms
Oct 17 21:00:17 indus pppd[18124]: found interface eth0 for proxy arp
Oct 17 21:00:17 indus pppd[18124]: local  IP address 10.0.1.2
Oct 17 21:00:17 indus pppd[18124]: remote IP address 10.0.1.25
Oct 17 21:00:17 indus pppd[18124]: MPPE 128 bit, stateless
compression enabled
Oct 17 21:01:23 indus pptpd[18123]: GRE: Discarding out of order
packet
Oct 17 21:01:24 indus pptpd[18123]: GRE: Discarding out of order
packet
Oct 17 21:04:59 indus pppd[18124]: LCP terminated by peer
Oct 17 21:05:00 indus pptpd[18123]: CTRL: Error with select(),
quitting
Oct 17 21:05:00 indus pptpd[18123]: CTRL: Client 10.0.1.1 control
connection finished
Oct 17 21:05:00 indus pppd[18124]: Modem hangup
Oct 17 21:05:00 indus pppd[18124]: Connection terminated.
Oct 17 21:05:00 indus pppd[18124]: Connect time 4.8 minutes.
Oct 17 21:05:00 indus pppd[18124]: Sent 17028 bytes, received 18827
bytes.
Oct 17 21:05:00 indus pppd[18124]: Exit.
Oct 17 21:07:36 indus pptpd[18173]: CTRL: Client 10.0.1.1 control
connection started
Oct 17 21:07:36 indus pptpd[18173]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 17 21:07:37 indus pppd[18174]: pppd 2.4.0 started by root, uid 0
Oct 17 21:07:37 indus pppd[18174]: Using interface ppp0
Oct 17 21:07:37 indus pppd[18174]: Connect: ppp0 <--> /dev/pts/0
Oct 17 21:08:07 indus pptpd[18173]: CTRL: Error with select(),
quitting
Oct 17 21:08:07 indus pptpd[18173]: CTRL: Client 10.0.1.1 control
connection finished
Oct 17 21:08:07 indus pppd[18174]: Modem hangup
Oct 17 21:08:07 indus pppd[18174]: Connection terminated.
Oct 17 21:08:07 indus pppd[18174]: Exit.
Oct 17 21:08:50 indus pptpd[18198]: CTRL: Client 10.0.1.1 control
connection started
Oct 17 21:08:50 indus pptpd[18198]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 17 21:08:50 indus pppd[18199]: pppd 2.4.0 started by root, uid 0
Oct 17 21:08:50 indus pppd[18199]: Using interface ppp0
Oct 17 21:08:50 indus pppd[18199]: Connect: ppp0 <--> /dev/pts/0
Oct 17 21:09:20 indus pptpd[18198]: CTRL: Error with select(),
quitting
Oct 17 21:09:20 indus pptpd[18198]: CTRL: Client 10.0.1.1 control
connection finished
Oct 17 21:09:20 indus pppd[18199]: Modem hangup
Oct 17 21:09:20 indus pppd[18199]: Connection terminated.
Oct 17 21:09:20 indus pppd[18199]: Exit.
Oct 17 21:09:36 indus pptpd[18223]: CTRL: Client 10.0.1.1 control
connection started
Oct 17 21:09:36 indus pptpd[18223]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 17 21:09:36 indus pppd[18224]: pppd 2.4.0 started by root, uid 0
Oct 17 21:09:37 indus pppd[18224]: Using interface ppp0
Oct 17 21:09:37 indus pppd[18224]: Connect: ppp0 <--> /dev/pts/0
Oct 17 21:10:07 indus pptpd[18223]: CTRL: Error with select(),
quitting
Oct 17 21:10:07 indus pptpd[18223]: CTRL: Client 10.0.1.1 control
connection finished
Oct 17 21:10:07 indus pppd[18224]: Modem hangup
Oct 17 21:10:07 indus pppd[18224]: Connection terminated.
Oct 17 21:10:07 indus pppd[18224]: Exit.
Oct 17 21:12:03 indus pptpd[18252]: CTRL: Client 10.0.1.1 control
connection started
Oct 17 21:12:03 indus pptpd[18252]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 17 21:12:03 indus pppd[18253]: pppd 2.4.0 started by root, uid 0
Oct 17 21:12:03 indus pppd[18253]: Using interface ppp0
Oct 17 21:12:03 indus pppd[18253]: Connect: ppp0 <--> /dev/pts/0
Oct 17 21:12:33 indus pptpd[18252]: CTRL: Error with select(),
quitting
Oct 17 21:12:33 indus pptpd[18252]: CTRL: Client 10.0.1.1 control
connection finished
Oct 17 21:12:33 indus pppd[18253]: Modem hangup
Oct 17 21:12:33 indus pppd[18253]: Connection terminated.
Oct 17 21:12:34 indus pppd[18253]: Exit.
Oct 17 21:14:01 indus pptpd[18277]: CTRL: Client 10.0.1.1 control
connection started
Oct 17 21:14:01 indus pptpd[18277]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 17 21:14:01 indus pppd[18278]: pppd 2.4.0 started by root, uid 0
Oct 17 21:14:01 indus pppd[18278]: Using interface ppp0
Oct 17 21:14:01 indus pppd[18278]: Connect: ppp0 <--> /dev/pts/0
Oct 17 21:14:31 indus pptpd[18277]: CTRL: Error with select(),
quitting
Oct 17 21:14:31 indus pptpd[18277]: CTRL: Client 10.0.1.1 control
connection finished
Oct 17 21:14:31 indus pppd[18278]: Modem hangup
Oct 17 21:14:31 indus pppd[18278]: Connection terminated.
Oct 17 21:14:32 indus pppd[18278]: Exit.
Oct 17 21:20:00 indus kernel: PPP MPPE compression module
unregistered

You see after one succesfull connection, the module did not unload
itself. It took 6 minutes!

But here is another client.

Oct 18 09:26:43 indus pptpd[19428]: CTRL: Client 10.0.0.2 control
connection started
Oct 18 09:26:43 indus pptpd[19428]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 18 09:26:44 indus kernel: CSLIP: code copyright 1989 Regents of
the University of California
Oct 18 09:26:44 indus kernel: PPP generic driver version 2.4.1
Oct 18 09:26:44 indus pppd[19429]: pppd 2.4.0 started by root, uid 0
Oct 18 09:26:44 indus pppd[19429]: Using interface ppp0
Oct 18 09:26:44 indus pppd[19429]: Connect: ppp0 <--> /dev/pts/2
Oct 18 09:26:44 indus pptpd[19428]: CTRL: Ignored a SET LINK INFO
packet with real ACCMs!
Oct 18 09:26:45 indus kernel: PPP BSD Compression module registered
Oct 18 09:26:45 indus kernel: PPP MPPE compression module registered
Oct 18 09:26:46 indus kernel: PPP Deflate Compression module
registered
Oct 18 09:26:46 indus pppd[19429]: MSCHAP-v2 peer authentication
succeeded for MEGOS\\br
Oct 18 09:26:46 indus pppd[19429]: MPPE 40 bit, stateless compression
enabled
Oct 18 09:26:49 indus pppd[19429]: found interface eth0 for proxy arp
Oct 18 09:26:49 indus pppd[19429]: local  IP address 10.0.1.2
Oct 18 09:26:49 indus pppd[19429]: remote IP address 10.0.1.22
Oct 18 09:27:27 indus pptpd[19428]: CTRL: Error with select(),
quitting
Oct 18 09:27:27 indus pptpd[19428]: CTRL: Client 10.0.0.2 control
connection finished
Oct 18 09:27:27 indus pppd[19429]: Modem hangup
Oct 18 09:27:27 indus pppd[19429]: Connection terminated.
Oct 18 09:27:27 indus pppd[19429]: Connect time 0.8 minutes.
Oct 18 09:27:27 indus pppd[19429]: Sent 3663 bytes, received 177
bytes.
Oct 18 09:27:28 indus kernel: PPP MPPE compression module
unregistered
Oct 18 09:27:28 indus pppd[19429]: Exit.
Oct 18 09:42:26 indus pptpd[19570]: CTRL: Client 10.0.0.2 control
connection started
Oct 18 09:42:26 indus pptpd[19570]: CTRL: Starting call (launching
pppd, opening GRE)
Oct 18 09:42:26 indus kernel: CSLIP: code copyright 1989 Regents of
the University of California
Oct 18 09:42:26 indus kernel: PPP generic driver version 2.4.1
Oct 18 09:42:26 indus pppd[19571]: pppd 2.4.0 started by root, uid 0
Oct 18 09:42:26 indus pppd[19571]: Using interface ppp0
Oct 18 09:42:26 indus pppd[19571]: Connect: ppp0 <--> /dev/pts/1
Oct 18 09:42:26 indus pptpd[19570]: CTRL: Ignored a SET LINK INFO
packet with real ACCMs!
Oct 18 09:42:27 indus kernel: PPP BSD Compression module registered
Oct 18 09:42:27 indus kernel: PPP MPPE compression module registered
Oct 18 09:42:27 indus kernel: PPP Deflate Compression module
registered
Oct 18 09:42:27 indus pppd[19571]: MSCHAP-v2 peer authentication
succeeded for MEGOS\\br
Oct 18 09:42:27 indus pppd[19571]: found interface eth0 for proxy arp
Oct 18 09:42:27 indus pppd[19571]: local  IP address 10.0.1.2
Oct 18 09:42:27 indus pppd[19571]: remote IP address 10.0.1.22
Oct 18 09:42:27 indus pppd[19571]: MPPE 40 bit, stateless compression
enabled
Oct 18 09:43:40 indus pptpd[19570]: CTRL: Error with select(),
quitting
Oct 18 09:43:40 indus pptpd[19570]: CTRL: Client 10.0.0.2 control
connection finished
Oct 18 09:43:40 indus pppd[19571]: Modem hangup
Oct 18 09:43:40 indus pppd[19571]: Connection terminated.
Oct 18 09:43:40 indus pppd[19571]: Connect time 1.3 minutes.
Oct 18 09:43:40 indus pppd[19571]: Sent 2230 bytes, received 143
bytes.
Oct 18 09:43:40 indus kernel: PPP MPPE compression module
unregistered
Oct 18 09:43:41 indus pppd[19571]: Exit.

Interesting thing for me is that the module is unloading before exit
and not after.

I would be grateful for any comments.

Regards
MArek
Butas

______________________________________________________________________
Co davaji v TV? http://tv.seznam.cz