[pptp-server] GRE: bad file descriptor

[Robert Dege]

I'm getting a GRE: bad file descriptor error.  Here's what happens:
My Win98 VPN makes a connection to the PPTP server.  It then sits there
saying that it's verifying username & password.  During this time
(according to the syslogs) it's sending & receiving a bunch of apckets
in 3 second intervals.

Win98 finally disconnects, & I get a GRE:bad file descriptor error.  But
it doesn't say what file.  I'm assuming that it is the chap-secrets
file.  Can anyone help here?  I've included the entire log of the
session.  The middle part (longest part) is the repetitive 3 second send
& recieves.

Is there any way to see if chap is being checked & if it's successful??

-Rob

Jan 10 15:33:20 quark pptpd[14779]: MGR: Launching
/usr/local/sbin/pptpctrl to handle client
Jan 10 15:33:20 quark pptpd[14779]: CTRL: pppd options file =
/etc/ppp/options.pptp
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Client 12.19.228.57 control
connection started
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Received PPTP Control Message
(type: 1)
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Made a START CTRL CONN RPLY
packet
Jan 10 15:33:20 quark pptpd[14779]: CTRL: I wrote 156 bytes to the
client.
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Sent packet to client
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Received PPTP Control Message
(type: 7)
Jan 10 15:33:20 quark pptpd[14779]: CTRL: 0 min_bps, 0 max_bps, 32
window size
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Made a OUT CALL RPLY packet
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Starting call (launching pppd,
opening GRE)
Jan 10 15:33:20 quark pptpd[14779]: CTRL: pty_fd = 5
Jan 10 15:33:20 quark pptpd[14779]: CTRL: tty_fd = 6
Jan 10 15:33:20 quark pptpd[14779]: CTRL: I wrote 32 bytes to the
client.
Jan 10 15:33:20 quark pptpd[14779]: CTRL: Sent packet to client
Jan 10 15:33:20 quark pptpd[14780]: CTRL (PPPD Launcher): Connection
speed = 115200
Jan 10 15:33:20 quark pppd[14780]: pppd 2.3.11 started by root, uid 0
Jan 10 15:33:20 quark pppd[14780]: Using interface ppp0
Jan 10 15:33:20 quark pppd[14780]: Connect: ppp0 <--> /dev/pts/3
Jan 10 15:33:20 quark pppd[14780]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <auth chap MD5> <magic 0x6b50681> <pcomp> <accomp>]
Jan 10 15:33:20 quark pppd[14780]: Timeout 0x80503d4:0x80784c0 in 15
seconds.
Jan 10 15:33:20 quark pptpd[14779]: Buffering out-of-order packet; got 1
after 4294967295
Jan 10 15:33:23 quark pptpd[14779]: Packet reorder timeout waiting for 0
Jan 10 15:33:23 quark pptpd[14779]: Buffering out-of-order packet; got 2
after 0
Jan 10 15:33:23 quark pppd[14780]: rcvd [LCP ConfReq id=0x1 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:23 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:23 quark pppd[14780]: sent [LCP ConfAck id=0x1 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:23 quark pppd[14780]: rcvd [LCP ConfReq id=0x2 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:23 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:23 quark pppd[14780]: sent [LCP ConfAck id=0x1 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:23 quark pppd[14780]: rcvd [LCP ConfReq id=0x2 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:23 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:23 quark pppd[14780]: sent [LCP ConfAck id=0x2 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:26 quark pppd[14780]: rcvd [LCP ConfReq id=0x3 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:26 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:26 quark pppd[14780]: sent [LCP ConfAck id=0x3 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:29 quark pppd[14780]: rcvd [LCP ConfReq id=0x4 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:29 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:29 quark pppd[14780]: sent [LCP ConfAck id=0x4 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:32 quark pppd[14780]: rcvd [LCP ConfReq id=0x5 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:32 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:32 quark pppd[14780]: sent [LCP ConfAck id=0x5 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:35 quark pppd[14780]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <auth chap MD5> <magic 0x6b50681> <pcomp> <accomp>]
Jan 10 15:33:35 quark pppd[14780]: Timeout 0x80503d4:0x80784c0 in 15
seconds.
Jan 10 15:33:35 quark pppd[14780]: rcvd [LCP ConfReq id=0x6 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:35 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:35 quark pppd[14780]: sent [LCP ConfAck id=0x6 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:38 quark pppd[14780]: rcvd [LCP ConfReq id=0x7 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:38 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:38 quark pppd[14780]: sent [LCP ConfAck id=0x7 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:41 quark pppd[14780]: rcvd [LCP ConfReq id=0x8 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:41 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:41 quark pppd[14780]: sent [LCP ConfAck id=0x8 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:44 quark pppd[14780]: rcvd [LCP ConfReq id=0x9 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:44 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:44 quark pppd[14780]: sent [LCP ConfAck id=0x9 <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:47 quark pppd[14780]: rcvd [LCP ConfReq id=0xa <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:47 quark pppd[14780]: lcp_reqci: returning CONFACK.
Jan 10 15:33:47 quark pppd[14780]: sent [LCP ConfAck id=0xa <magic
0xf49e3> <pcomp> <accomp>]
Jan 10 15:33:50 quark pppd[14780]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <auth chap MD5> <magic 0x6b50681> <pcomp> <accomp>]
Jan 10 15:33:50 quark pppd[14780]: Timeout 0x80503d4:0x80784c0 in 15
seconds.
Jan 10 15:34:05 quark pppd[14780]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <auth chap MD5> <magic 0x6b50681> <pcomp> <accomp>]
Jan 10 15:34:05 quark pppd[14780]: Timeout 0x80503d4:0x80784c0 in 15
seconds.
Jan 10 15:34:13 quark pptpd[14779]: CTRL: Received PPTP Control Message
(type: 12)
Jan 10 15:34:13 quark pptpd[14779]: CTRL: Made a CALL DISCONNECT RPLY
packet
Jan 10 15:34:13 quark pptpd[14779]: CTRL: Received CALL CLR request
(closing call)
Jan 10 15:34:13 quark pptpd[14779]: CTRL: I wrote 148 bytes to the
client.
Jan 10 15:34:13 quark pptpd[14779]: CTRL: Sent packet to client
Jan 10 15:34:13 quark pppd[14780]: Modem hangup
Jan 10 15:34:13 quark pppd[14780]: Untimeout 0x80503d4:0x80784c0.
Jan 10 15:34:13 quark pppd[14780]: Connection terminated.
Jan 10 15:34:13 quark pppd[14780]: Exit.
Jan 10 15:34:18 quark pptpd[14779]: GRE: read error: Bad file descriptor
Jan 10 15:34:18 quark pptpd[14779]: CTRL: PTY read or GRE write failed
(pty,gre)=(-1,-1)
Jan 10 15:34:18 quark pptpd[14779]: CTRL: Client 12.19.228.57 control
connection finished
Jan 10 15:34:18 quark pptpd[14779]: CTRL: Exiting now
Jan 10 15:34:18 quark pptpd[14667]: MGR: Reaped child 14779