[pptp-server] pptp connection acting like one armed bandit

Wed Jun 6 22:09:15 CDT 2001

When attempting pptp connection I get:

	"Error 778: It was not possible to verify the identity of the server"

About 1 out of every 3-8 times on attempting to reconnect I do eventually
get connected properly and I am able to ping hosts etc on the private LAN
and the VPN is perfect.

I set up the Linux pptp server (2.4.5 RH7.1) in-house behind a Linux
firewall (2.2.19 RH 6.2) and it tested perfect with outside (ie Inet
connected) Win2K client (128bit support installed). When I moved the server
to customer site behind identical firewall I started getting the 778 errors.
I had to set up hosts and lmhosts on the same Win2K client so name
resolution was ok.

I have tried fiddling with ./ppp/options but most changes lead to the same
result.

If anyone can figure this one out its worth a beer or many!

pptpd version is 1.1.2

./ppp/options.pptp
******************
name *
lock
mtu 1490
mru 1490
proxyarp
auth
debug
+chap
+chapms-v2
ipcp-accept-local
ipcp-accept-remote
lcp-echo-failure 3
lcp-echo-interval 3
deflate 0
mppe-40
mppe-128
mppe-stateless

When its not happy
******************
Jun  6 22:47:26 localhost pptpd[5326]: CTRL: Client 204.225.93.61 control
connection st
arted
Jun  6 22:47:26 localhost pptpd[5326]: CTRL: Starting call (launching pppd,
opening GRE
)
Jun  6 22:47:26 localhost pppd[5327]: pppd 2.4.0 started by root, uid 0
Jun  6 22:47:26 localhost pppd[5327]: Using interface ppp0
Jun  6 22:47:26 localhost pppd[5327]: Connect: ppp0 <--> /dev/pts/0
Jun  6 22:47:29 localhost pptpd[5326]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:47:29 localhost pptpd[5326]: Buffering out-of-order packet; got 4
after 2
Jun  6 22:47:29 localhost pptpd[5326]: Buffering out-of-order packet; got 5
after 2
Jun  6 22:47:29 localhost pppd[5327]: MSCHAP-v2 peer authentication
succeeded for hld
Jun  6 22:47:31 localhost pptpd[5326]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:47:31 localhost pppd[5327]: LCP terminated by peer
(@^R^Tl^@<M-Mt^@^@^C^J)
Jun  6 22:47:31 localhost pppd[5327]: Modem hangup
Jun  6 22:47:31 localhost pppd[5327]: Connection terminated.
Jun  6 22:47:31 localhost pppd[5327]: Connect time 0.1 minutes.
Jun  6 22:47:31 localhost pppd[5327]: Sent 29 bytes, received 0 bytes.
Jun  6 22:47:31 localhost pppd[5327]: Exit.
Jun  6 22:47:31 localhost pptpd[5326]: GRE: read error: Bad file descriptor
Jun  6 22:47:31 localhost pptpd[5326]: CTRL: PTY read or GRE write failed
(pty,gre)=(-1
,-1)
Jun  6 22:47:31 localhost pptpd[5326]: CTRL: Client 204.225.93.61 control
connection fi
nished
(END)

When its happy (ie I can VPN)
*****************************
Jun  6 22:43:19 localhost pptpd[5245]: CTRL: Client 204.225.93.61 control
connection st
arted
Jun  6 22:43:19 localhost pptpd[5245]: CTRL: Starting call (launching pppd,
opening GRE
)
Jun  6 22:43:19 localhost kernel: PPP generic driver version 2.4.1
Jun  6 22:43:19 localhost pppd[5246]: pppd 2.4.0 started by root, uid 0
Jun  6 22:43:20 localhost pppd[5246]: Using interface ppp0
Jun  6 22:43:20 localhost pppd[5246]: Connect: ppp0 <--> /dev/pts/0
Jun  6 22:43:25 localhost pptpd[5245]: Buffering out-of-order packet; got 9
after 7
Jun  6 22:43:25 localhost pptpd[5245]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:43:28 localhost kernel: PPP BSD Compression module registered
Jun  6 22:43:28 localhost kernel: PPP MPPE compression module registered
Jun  6 22:43:28 localhost pppd[5246]: MSCHAP-v2 peer authentication
succeeded for hld
Jun  6 22:43:32 localhost pptpd[5245]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:43:32 localhost pppd[5246]: Modem hangup
Jun  6 22:43:32 localhost pppd[5246]: Connection terminated.
Jun  6 22:43:32 localhost pppd[5246]: Connect time 0.2 minutes.
Jun  6 22:43:32 localhost pppd[5246]: Sent 58 bytes, received 0 bytes.
Jun  6 22:43:32 localhost pppd[5246]: Exit.
Jun  6 22:43:32 localhost pptpd[5245]: GRE: read error: Bad file descriptor
Jun  6 22:43:32 localhost pptpd[5245]: CTRL: PTY read or GRE write failed
(pty,gre)=(-1
,-1)
Jun  6 22:43:32 localhost pptpd[5245]: CTRL: Client 204.225.93.61 control
connection fi
nished
Jun  6 22:43:33 localhost pptpd[5277]: CTRL: Client 204.225.93.61 control
connection st
arted
Jun  6 22:43:33 localhost pptpd[5277]: CTRL: Starting call (launching pppd,
opening GRE
)
Jun  6 22:43:33 localhost pppd[5278]: pppd 2.4.0 started by root, uid 0
Jun  6 22:43:33 localhost pppd[5278]: Using interface ppp0
Jun  6 22:43:33 localhost pppd[5278]: Connect: ppp0 <--> /dev/pts/0
Jun  6 22:43:38 localhost pptpd[5277]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:43:38 localhost pptpd[5277]: Buffering out-of-order packet; got 7
after 5
Jun  6 22:43:41 localhost pptpd[5277]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:43:41 localhost pptpd[5277]: Buffering out-of-order packet; got 9
after 7
Jun  6 22:43:41 localhost pptpd[5277]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:43:41 localhost pppd[5278]: MSCHAP-v2 peer authentication
succeeded for hld
Jun  6 22:43:41 localhost pptpd[5277]: Buffering out-of-order packet; got 15
after 12
Jun  6 22:43:44 localhost pptpd[5277]: Buffering out-of-order packet; got 21
after 19
Jun  6 22:43:44 localhost pptpd[5277]: Packet reorder timeout waiting for 20
Jun  6 22:43:44 localhost pptpd[5277]: Buffering out-of-order packet; got 22
after 20
Jun  6 22:43:44 localhost pppd[5278]: found interface eth0 for proxy arp
Jun  6 22:43:44 localhost pppd[5278]: local  IP address 10.10.10.249
Jun  6 22:43:44 localhost pppd[5278]: remote IP address 10.10.10.200
Jun  6 22:43:44 localhost pptpd[5277]: Buffering out-of-order packet; got 26
after 24
Jun  6 22:43:50 localhost pptpd[5277]: Buffering out-of-order packet; got 38
after 34
Jun  6 22:43:50 localhost pptpd[5277]: Buffering out-of-order packet; got 37
after 34
Jun  6 22:43:50 localhost pptpd[5277]: Buffering out-of-order packet; got 39
after 34
Jun  6 22:43:50 localhost pptpd[5277]: Gave up waiting for 2 lost packets
beginning wit
h 35
Jun  6 22:43:50 localhost pptpd[5277]: Buffering out-of-order packet; got 42
after 39
Jun  6 22:43:50 localhost pptpd[5277]: Buffering out-of-order packet; got 41
after 39
Jun  6 22:43:50 localhost pptpd[5277]: Discarding out-of-order packet 35,
already have
39
Jun  6 22:43:50 localhost pptpd[5277]: Discarding out-of-order packet 36,
already have
39
Jun  6 22:43:51 localhost pptpd[5277]: Buffering out-of-order packet; got 44
after 42
Jun  6 22:43:51 localhost pppd[5278]: MPPE 128 bit, stateless compression
enabled
Jun  6 22:43:53 localhost pptpd[5277]: Buffering out-of-order packet; got 52
after 50
Jun  6 22:46:15 localhost pptpd[5277]: CTRL: Ignored a SET LINK INFO packet
with real A
CCMs!
Jun  6 22:46:15 localhost pppd[5278]: LCP terminated by peer
(^]^LbM-2^@<M-Mt^@^@^@^@)
Jun  6 22:46:15 localhost pppd[5278]: Modem hangup
Jun  6 22:46:15 localhost pppd[5278]: Connection terminated.
Jun  6 22:46:15 localhost pppd[5278]: Connect time 2.7 minutes.
Jun  6 22:46:15 localhost pppd[5278]: Sent 183 bytes, received 3330 bytes.
Jun  6 22:46:15 localhost pppd[5278]: Exit.
Jun  6 22:46:15 localhost pptpd[5277]: GRE: read error: Bad file descriptor
Jun  6 22:46:15 localhost pptpd[5277]: CTRL: PTY read or GRE write failed
(pty,gre)=(-1
,-1)
Jun  6 22:46:15 localhost pptpd[5277]: CTRL: Client 204.225.93.61 control
connection finished