[pptp-server] Error with select() ?...

Thu Mar 16 12:50:39 CST 2000

OK, got it -- I had specified "daemon.debug" in syslog.conf -- the wildcard
did generate much more data...  :)

[root at spr0cket log]# cat pptpd.log
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: pppd speed = 115200
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Client 63.198.86.73 control
connect
ion started
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Received PPTP Control Message
(type
: 1)
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Made a START CTRL CONN RPLY
packet
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: I wrote 156 bytes to the
client.
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Sent packet to client
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Received PPTP Control Message
(type
: 7)
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Set parameters to 0 maxbps, 16
wind
ow size
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Made a OUT CALL RPLY packet
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Starting call (launching pppd,
open
ing GRE)
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: pty_fd = 4
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: tty_fd = 5
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: I wrote 32 bytes to the client.
Mar 16 10:43:09 spr0cket pptpd[15926]: CTRL: Sent packet to client
Mar 16 10:43:09 spr0cket pptpd[15927]: CTRL (PPPD Launcher): Connection
speed =
115200
Mar 16 10:43:09 spr0cket pppd[15927]: pppd 2.3.10 started by root, uid 0
Mar 16 10:43:09 spr0cket pppd[15927]: Using interface ppp0
Mar 16 10:43:09 spr0cket pppd[15927]: Connect: ppp0 <--> /dev/pts/0
Mar 16 10:43:09 spr0cket pppd[15927]: sent [LCP ConfReq id=0x1 <asyncmap
0x0> <a
uth chap 81> <magic 0x9a738a19> <pcomp> <accomp>]
Mar 16 10:43:09 spr0cket pppd[15927]: Timeout 0x8050780:0x8078920 in 3
seconds.
Mar 16 10:43:09 spr0cket pppd[15927]: rcvd [LCP ConfReq id=0x1 <magic
0x3bcbbc0>
 <pcomp> <accomp>]
Mar 16 10:43:09 spr0cket pppd[15927]: lcp_reqci: returning CONFACK.
Mar 16 10:43:09 spr0cket pppd[15927]: sent [LCP ConfAck id=0x1 <magic
0x3bcbbc0>
 <pcomp> <accomp>]
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [LCP ConfAck id=0x1 <asyncmap
0x0> <a
uth chap 81> <magic 0x9a738a19> <pcomp> <accomp>]
Mar 16 10:43:10 spr0cket pppd[15927]: Untimeout 0x8050780:0x8078920.
Mar 16 10:43:10 spr0cket pppd[15927]: sent [CHAP Challenge id=0x1
<cc592fe2379b3
743a80f5ebda0725b69>, name = "spr0cket.abinnovations.com"]
Mar 16 10:43:10 spr0cket pppd[15927]: Timeout 0x8056290:0x8078c00 in 3
seconds.
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [CHAP Response id=0x1
<105b4dd004c38c
72709c4d9376155f3d00000000000000005312a29e4576ab0cf8159444aaa050f0f13ea649d6
e13d
7d04>, name = "abivpn"]
Mar 16 10:43:10 spr0cket pppd[15927]: Untimeout 0x8056290:0x8078c00.
Mar 16 10:43:10 spr0cket pppd[15927]: ChapReceiveResponse: rcvd type
MS-CHAP-V2
Mar 16 10:43:10 spr0cket pppd[15927]: sent [CHAP Success id=0x1
"S=EC4CB0BABA047
0253B10CF283FF8886F880FEF6F"]
Mar 16 10:43:10 spr0cket pppd[15927]: sent [IPCP ConfReq id=0x1 <addr
10.0.0.1>
<compress VJ 0f 01>]
Mar 16 10:43:10 spr0cket pppd[15927]: Timeout 0x8050780:0x8078b80 in 3
seconds.
Mar 16 10:43:10 spr0cket kernel: PPP BSD Compression module registered
Mar 16 10:43:10 spr0cket kernel: PPP MPPE compression module registered
Mar 16 10:43:10 spr0cket kernel: PPP Deflate Compression module registered
Mar 16 10:43:10 spr0cket pppd[15927]: sent [CCP ConfReq id=0x1 <deflate 15>
<def
late(old#) 15> <mppe 1 0 0 60> <bsd v1 15>]
Mar 16 10:43:10 spr0cket pppd[15927]: Timeout 0x8050780:0x8078cc0 in 3
seconds.
Mar 16 10:43:10 spr0cket pppd[15927]: MSCHAP-v2 peer authentication
succeeded fo
r abivpn
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [IPCP ConfReq id=0x1 <addr
0.0.0.0> <
ms-dns1 63.198.144.210> <ms-wins 63.201.100.34> <ms-dns3 63.201.100.34>
<ms-wins
 0.0.0.0>]
Mar 16 10:43:10 spr0cket pppd[15927]: ipcp: returning Configure-REJ
Mar 16 10:43:10 spr0cket pppd[15927]: sent [IPCP ConfRej id=0x1 <addr
0.0.0.0> <
ms-dns1 63.198.144.210> <ms-wins 63.201.100.34> <ms-dns3 63.201.100.34>
<ms-wins
 0.0.0.0>]
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [IPCP ConfRej id=0x1 <compress VJ
0f
01>]
Mar 16 10:43:10 spr0cket pppd[15927]: Untimeout 0x8050780:0x8078b80.
Mar 16 10:43:10 spr0cket pppd[15927]: sent [IPCP ConfReq id=0x2 <addr
10.0.0.1>]
Mar 16 10:43:10 spr0cket pppd[15927]: Timeout 0x8050780:0x8078b80 in 3
seconds.
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [LCP ProtRej id=0x2 80 fd 01 01
00 15
 1a 04 78 00 18 04 78 00 12 06 01 00 00 60 15 03 2f]
Mar 16 10:43:10 spr0cket pppd[15927]: Untimeout 0x8050780:0x8078cc0.
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [IPCP ConfReq id=0x2 <addrs
0.0.0.0 0
.0.0.0>]
Mar 16 10:43:10 spr0cket pppd[15927]: ipcp: returning Configure-REJ
Mar 16 10:43:10 spr0cket pppd[15927]: sent [IPCP ConfRej id=0x2 <addrs
0.0.0.0 0
.0.0.0>]
Mar 16 10:43:10 spr0cket pppd[15927]: rcvd [IPCP ConfAck id=0x2 <addr
10.0.0.1>]
Mar 16 10:43:13 spr0cket pppd[15927]: sent [IPCP ConfReq id=0x2 <addr
10.0.0.1>]
Mar 16 10:43:13 spr0cket pppd[15927]: Timeout 0x8050780:0x8078b80 in 3
seconds.
Mar 16 10:43:13 spr0cket pppd[15927]: rcvd [IPCP ConfReq id=0x3 <addrs
0.0.0.0 1
0.0.0.1>]
Mar 16 10:43:13 spr0cket pppd[15927]: ipcp: returning Configure-REJ
Mar 16 10:43:13 spr0cket pppd[15927]: sent [IPCP ConfRej id=0x3 <addrs
0.0.0.0 1
0.0.0.1>]
Mar 16 10:43:14 spr0cket pppd[15927]: rcvd [IPCP TermReq id=0x4]
Mar 16 10:43:14 spr0cket pppd[15927]: sent [IPCP TermAck id=0x4]
Mar 16 10:43:14 spr0cket pppd[15927]: rcvd [LCP TermReq id=0x3]
Mar 16 10:43:14 spr0cket pppd[15927]: LCP terminated by peer
Mar 16 10:43:14 spr0cket pppd[15927]: Untimeout 0x8050780:0x8078b80.
Mar 16 10:43:14 spr0cket pppd[15927]: CCP: Down event in state 1!
Mar 16 10:43:14 spr0cket pppd[15927]: Timeout 0x8050780:0x8078920 in 3
seconds.
Mar 16 10:43:14 spr0cket pppd[15927]: sent [LCP TermAck id=0x3]
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Received PPTP Control Message
(type
: 12)
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Made a CALL DISCONNECT RPLY
packet
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Received CALL CLR request
(closing
call)
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: I wrote 148 bytes to the
client.
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Sent packet to client
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Error with select(), quitting
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Client 63.198.86.73 control
connect
ion finished
Mar 16 10:43:14 spr0cket pptpd[15926]: CTRL: Exiting now
Mar 16 10:43:14 spr0cket pppd[15927]: Modem hangup
Mar 16 10:43:14 spr0cket pppd[15927]: Untimeout 0x8050780:0x8078920.
Mar 16 10:43:14 spr0cket pppd[15927]: Connection terminated.
Mar 16 10:43:14 spr0cket pppd[15927]: Connect time 0.1 minutes.
Mar 16 10:43:14 spr0cket pppd[15927]: Sent 466 bytes, received 436 bytes.
Mar 16 10:43:14 spr0cket pppd[15927]: Exit.

----- Original Message -----
From: "tmk" <tmk at netmagic.net>
To: "Don Lindner" <don at lindner2k.com>
Sent: Thursday, March 16, 2000 8:05 AM
Subject: Re: [pptp-server] Error with select() ?...

> that wasnt debug output... use the debug option in your ppp options file
> and add a line like *.debug /var/log/debug to your /etc/syslog.conf file
> (and kill -HUP <pid of syslogd>
>
> Kevin