[pptp-server] Strange problem ...

Mon Oct 22 09:06:14 CDT 2001

So Jordan, any ideas on mine and Marek's problem?

----- Original Message -----
From: "Marek Butas" <MarekButas at seznam.cz>
To: "Jim Roland" <jroland at roland.net>; "PPTP List"
<pptp-server at lists.schulte.org>
Sent: Friday, October 19, 2001 3:06 AM
Subject: Re: [pptp-server] Strange problem ...

> 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
>