Problems with Userland PPP as PPTP/PPPoE server
Brett Glass
brett at lariat.org
Mon Jun 30 14:36:20 PDT 2003
All:
I'm having a terrible time with servers in which I'm using FreeBSD's
"userland" PPP to provide PPPoE or PPTP service. It appears that every so
often -- usually after a client's connection is severed for some reason
(for example, if the client simply shuts of his or her machine without
doing a complete disconnect first) -- he or she can't log in again until
the machine is rebooted. Sometimes, in fact, NO ONE can get in until the
reboot.
Here's an example. Log excerpt 1 below is from a machine that's operating
as a PPTP server. It's using userland PPP with PoPToP (which is GPLed, I
know, but I can't find any other way of doing a PPTP server under FreeBSD
without using mpd.
Why am I not using mpd, you might ask? Well, for one thing, mpdrequires
huge configuration files -- and huge numbers of Netgraph nodes, which can
eat kernel memory -- to act as a server for many potential clients. (Each
client needs to have an "ng" device permanently reserved for it.) And its
configuration language is so limited that we simply can't do connection
management and accounting. We have accounts that expire and also want to
be able to prevent multiple simultaneous logins under the same user name.
Userland PPP lets us do what we need to do by letting us shell out to our
own software and pass it information; a kludge, but it works. But mpd
doesn't even have this "escape."
In any event, back to the problem.
As you can see, when a connection attempt fails, it seems as if responses
from the client during negotiation are just not coming though. The server
gives up after a few tries.
After I reboot the server, the client can connect, as the second log
excerpt shows.
I've had similar quirky behavior with PPPoE, using pppoed. (In this case,
pppoed keeps spawning new instances of ppp, none of which connect
successfully.)
I can't find anything that I can put in the userland ppp configuration
files that prevents this problem from occurring.
I've heard that there are problems with, and/or limitations in, the tun
device that may keep individual tun devices from being fully "reusable"
for multiple successive connections. Could they be responsible for this
problem?
--Brett
Log excerpt 1: Failed connection
Jun 29 14:47:16 www ppp[4829]: Phase: Using interface: tun1
Jun 29 14:47:16 www ppp[4829]: Phase: deflink: Created in closed state
Jun 29 14:47:16 www ppp[4829]: tun1: Command: default: ident user-ppp
VERSION (built COMPILATIONDATE)
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set timeout 0
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set dial
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set login
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set ifaddr 192.168.0.1/32
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set server
/var/run/pptp_ppp_%d ******** 0700
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: Listening at local socket
/var/run/pptp_ppp_1.
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: enable lqr
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set lqrperiod 15
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: disable chap
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: deny chap
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: disable pap
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: disable passwdauth
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: disable deflate pred1
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: deny deflate pred1
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: disable utmp
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: enable mschapv2 mppe
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set mppe * stateless
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: enable proxy
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: accept dns
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set dns 192.168.0.1
Jun 29 14:47:16 www ppp[4829]: tun1: Command: pptp: set nbns 192.168.0.2
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: PPP Started (direct mode).
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: bundle: Establish
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: deflink: closed -> opening
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: deflink: Connected!
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: deflink: opening -> carrier
Jun 29 14:47:16 www ppp[4829]: tun1: Phase: deflink: carrier -> lcp
Jun 29 14:47:16 www ppp[4829]: tun1: LCP: FSM: Using "deflink" as a transport
Jun 29 14:47:16 www ppp[4829]: tun1: LCP: deflink: State change Initial
--> Closed
Jun 29 14:47:16 www ppp[4829]: tun1: LCP: deflink: State change Closed
--> Stopped
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: deflink: LayerStart
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: deflink: SendConfigReq(1) state
= Stopped
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: ACFCOMP[2]
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: PROTOCOMP[2]
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: MRU[4] 1500
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: MAGICNUM[6] 0xb48208d8
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 14:47:17 www ppp[4829]: tun1: LCP: deflink: State change Stopped
--> Req-Sent
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: deflink: SendConfigReq(1) state
= Req-Sent
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: ACFCOMP[2]
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: PROTOCOMP[2]
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: MRU[4] 1500
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: MAGICNUM[6] 0xb48208d8
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 14:47:20 www ppp[4829]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: deflink: SendConfigReq(1) state
= Req-Sent
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: ACFCOMP[2]
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: PROTOCOMP[2]
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: MRU[4] 1500
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: MAGICNUM[6] 0xb48208d8
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 14:47:23 www ppp[4829]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: deflink: SendConfigReq(1) state
= Req-Sent
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: ACFCOMP[2]
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: PROTOCOMP[2]
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: MRU[4] 1500
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: MAGICNUM[6] 0xb48208d8
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 14:47:26 www ppp[4829]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: deflink: SendConfigReq(1) state
= Req-Sent
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: ACFCOMP[2]
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: PROTOCOMP[2]
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: MRU[4] 1500
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: MAGICNUM[6] 0xb48208d8
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 14:47:29 www ppp[4829]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 14:47:33 www ppp[4829]: tun1: LCP: deflink: LayerFinish
Jun 29 14:47:33 www ppp[4829]: tun1: LCP: deflink: State change Req-Sent
--> Stopped
Jun 29 14:47:33 www ppp[4829]: tun1: LCP: deflink: State change Stopped
--> Closed
Jun 29 14:47:33 www ppp[4829]: tun1: LCP: deflink: State change Closed
--> Initial
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: deflink: Disconnected!
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: deflink: Connect time: 17
secs: 0 octets in, 360 octets out
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: deflink: : 0 packets in, 5
packets out
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: total 21 bytes/sec, peak 28
bytes/sec on Sun Jun 29 14:47:33 2003
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: deflink: lcp -> closed
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: bundle: Dead
Jun 29 14:47:33 www ppp[4829]: tun1: Phase: PPP Terminated (normal).
Jun 29 14:47:50 www ppp[4831]: Phase: Using interface: tun1
Log excerpt 2: Same server after a reboot. Successful connection.
Jun 29 15:07:35 www ppp[170]: Phase: Using interface: tun1
Jun 29 15:07:35 www ppp[170]: Phase: deflink: Created in closed state
Jun 29 15:07:35 www ppp[170]: tun1: Command: default: ident user-ppp
VERSION (built COMPILATIONDATE)
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set timeout 0
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set dial
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set login
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set ifaddr 192.168.0.1/32
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set server
/var/run/pptp_ppp_%d ******** 0700
Jun 29 15:07:35 www ppp[170]: tun1: Phase: Listening at local socket
/var/run/pptp_ppp_1.
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: enable lqr
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set lqrperiod 15
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: disable chap
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: deny chap
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: disable pap
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: disable passwdauth
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: disable deflate pred1
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: deny deflate pred1
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: disable utmp
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: enable mschapv2 mppe
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set mppe * stateless
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: enable proxy
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: accept dns
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set dns 192.168.0.1
Jun 29 15:07:35 www ppp[170]: tun1: Command: pptp: set nbns 192.168.0.2
Jun 29 15:07:35 www ppp[170]: tun1: Phase: PPP Started (direct mode).
Jun 29 15:07:35 www ppp[170]: tun1: Phase: bundle: Establish
Jun 29 15:07:35 www ppp[170]: tun1: Phase: deflink: closed -> opening
Jun 29 15:07:35 www ppp[170]: tun1: Phase: deflink: Connected!
Jun 29 15:07:35 www ppp[170]: tun1: Phase: deflink: opening -> carrier
Jun 29 15:07:35 www ppp[170]: tun1: Phase: deflink: carrier -> lcp
Jun 29 15:07:35 www ppp[170]: tun1: LCP: FSM: Using "deflink" as a transport
Jun 29 15:07:35 www ppp[170]: tun1: LCP: deflink: State change Initial
--> Closed
Jun 29 15:07:35 www ppp[170]: tun1: LCP: deflink: State change Closed -->
Stopped
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: LayerStart
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: SendConfigReq(1) state
= Stopped
Jun 29 15:07:36 www ppp[170]: tun1: LCP: ACFCOMP[2]
Jun 29 15:07:36 www ppp[170]: tun1: LCP: PROTOCOMP[2]
Jun 29 15:07:36 www ppp[170]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 15:07:36 www ppp[170]: tun1: LCP: MRU[4] 1500
Jun 29 15:07:36 www ppp[170]: tun1: LCP: MAGICNUM[6] 0x8ac2cdf9
Jun 29 15:07:36 www ppp[170]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 15:07:36 www ppp[170]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: State change Stopped
--> Req-Sent
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: RecvConfigRej(1) state
= Req-Sent
Jun 29 15:07:36 www ppp[170]: tun1: LCP: Sending ident magic 8ac2cdf9
text user-ppp 2.3.3 (built Jan 6 2003)
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: SendIdent(0) state = Req-Sent
Jun 29 15:07:36 www ppp[170]: tun1: LCP: QUALPROTO[8] proto c025,
interval 15000ms
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: SendConfigReq(2) state
= Req-Sent
Jun 29 15:07:36 www ppp[170]: tun1: LCP: ACFCOMP[2]
Jun 29 15:07:36 www ppp[170]: tun1: LCP: PROTOCOMP[2]
Jun 29 15:07:36 www ppp[170]: tun1: LCP: ACCMAP[6] 0x00000000
Jun 29 15:07:36 www ppp[170]: tun1: LCP: MRU[4] 1500
Jun 29 15:07:36 www ppp[170]: tun1: LCP: MAGICNUM[6] 0x8ac2cdf9
Jun 29 15:07:36 www ppp[170]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x81)
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: RecvConfigAck(2) state
= Req-Sent
Jun 29 15:07:36 www ppp[170]: tun1: LCP: deflink: State change Req-Sent
--> Ack-Rcvd
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: RecvConfigReq(1) state
= Ack-Rcvd
Jun 29 15:07:37 www ppp[170]: tun1: LCP: MRU[4] 1400
Jun 29 15:07:37 www ppp[170]: tun1: LCP: MAGICNUM[6] 0x786856ca
Jun 29 15:07:37 www ppp[170]: tun1: LCP: PROTOCOMP[2]
Jun 29 15:07:37 www ppp[170]: tun1: LCP: ACFCOMP[2]
Jun 29 15:07:37 www ppp[170]: tun1: LCP: CALLBACK[3] CBCP
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendConfigRej(1) state
= Ack-Rcvd
Jun 29 15:07:37 www ppp[170]: tun1: LCP: CALLBACK[3] CBCP
Jun 29 15:07:37 www ppp[170]: tun1: LCP: Sending ident magic 8ac2cdf9
text user-ppp 2.3.3 (built Jan 6 2003)
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendIdent(1) state = Ack-Rcvd
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: RecvConfigReq(2) state
= Ack-Rcvd
Jun 29 15:07:37 www ppp[170]: tun1: LCP: MRU[4] 1400
Jun 29 15:07:37 www ppp[170]: tun1: LCP: MAGICNUM[6] 0x786856ca
Jun 29 15:07:37 www ppp[170]: tun1: LCP: PROTOCOMP[2]
Jun 29 15:07:37 www ppp[170]: tun1: LCP: ACFCOMP[2]
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendConfigAck(2) state
= Ack-Rcvd
Jun 29 15:07:37 www ppp[170]: tun1: LCP: MRU[4] 1400
Jun 29 15:07:37 www ppp[170]: tun1: LCP: MAGICNUM[6] 0x786856ca
Jun 29 15:07:37 www ppp[170]: tun1: LCP: PROTOCOMP[2]
Jun 29 15:07:37 www ppp[170]: tun1: LCP: ACFCOMP[2]
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: State change Ack-Rcvd
--> Opened
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: LayerUp
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendEchoRequest(0)
state = Opened
Jun 29 15:07:37 www ppp[170]: tun1: LCP: Sending ident magic 8ac2cdf9
text user-ppp 2.3.3 (built Jan 6 2003)
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendIdent(2) state = Opened
Jun 29 15:07:37 www ppp[170]: tun1: Phase: bundle: Authenticate
Jun 29 15:07:37 www ppp[170]: tun1: Phase: deflink: his = none, mine =
CHAP 0x81
Jun 29 15:07:37 www ppp[170]: tun1: Phase: Chap Output: CHALLENGE
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: RecvIdent(3) state = Opened
Jun 29 15:07:37 www ppp[170]: tun1: LCP: Received ident: MSRASV5.10
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: RecvIdent(4) state = Opened
Jun 29 15:07:37 www ppp[170]: tun1: LCP: Received ident: MSRAS-1-LAPTOP
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: RecvEchoReply(0) state
= Opened
Jun 29 15:07:37 www ppp[170]: tun1: Phase: Chap Input: RESPONSE (49 bytes
from pptpuser)
Jun 29 15:07:37 www ppp[170]: tun1: Phase: Chap Output: SUCCESS
Jun 29 15:07:37 www ppp[170]: tun1: Phase: Found interface fxp0 for
192.168.0.253
Jun 29 15:07:37 www ppp[170]: tun1: CCP: FSM: Using "deflink" as a transport
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: State change Initial
--> Closed
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: LayerStart.
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: SendConfigReq(1) state
= Closed
Jun 29 15:07:37 www ppp[170]: tun1: CCP: MPPE[6] value 0x010000e0
(128/56/40 bits, stateless)
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: State change Closed -->
Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: Phase: deflink: lcp -> open
Jun 29 15:07:37 www ppp[170]: tun1: Phase: bundle: Network
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: FSM: Using "deflink" as a transport
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: State change Initial
--> Closed
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: LayerStart.
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: SendConfigReq(1) state
= Closed
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: IPADDR[6] 192.168.0.1
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with
slot compression
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: State change Closed
--> Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: RecvConfigReq(5) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: CCP: MPPE[6] value 0x01000040 (128
bits, stateless)
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: SendConfigAck(5) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: CCP: MPPE[6] value 0x01000040 (128
bits, stateless)
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: State change Req-Sent
--> Ack-Sent
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: RecvConfigReq(6) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: IPADDR[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: PRIDNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: PRINBNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: SECDNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: SECNBNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: NBNS REQ - rejected - nbns not set
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: SendConfigRej(6) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: SECNBNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: LCP: Sending ident magic 8ac2cdf9
text user-ppp 2.3.3 (built Jan 6 2003)
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendIdent(3) state = Opened
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: RecvConfigNak(1) state
= Ack-Sent
Jun 29 15:07:37 www ppp[170]: tun1: CCP: MPPE[6] value 0x01000040 (128
bits, stateless)
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: SendConfigReq(2) state
= Ack-Sent
Jun 29 15:07:37 www ppp[170]: tun1: CCP: MPPE[6] value 0x01000040 (128
bits, stateless)
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: RecvConfigRej(1) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: LCP: Sending ident magic 8ac2cdf9
text user-ppp 2.3.3 (built Jan 6 2003)
Jun 29 15:07:37 www ppp[170]: tun1: LCP: deflink: SendIdent(4) state = Opened
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with
slot compression
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: SendConfigReq(2) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: IPADDR[6] 192.168.0.1
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: RecvConfigReq(7) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: IPADDR[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: PRIDNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: PRINBNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: SECDNS[6] 0.0.0.0
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: deflink: SendConfigNak(7) state
= Req-Sent
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: IPADDR[6] 192.168.0.253
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: PRIDNS[6] 192.168.0.1
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: PRINBNS[6] 192.168.0.2
Jun 29 15:07:37 www ppp[170]: tun1: IPCP: SECDNS[6] 192.168.0.1
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: RecvConfigAck(2) state
= Ack-Sent
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: State change Ack-Sent
--> Opened
Jun 29 15:07:37 www ppp[170]: tun1: CCP: deflink: LayerUp.
Jun 29 15:07:37 www ppp[170]: tun1: CCP: MPPE: Input channel initiated
Jun 29 15:07:38 www ppp[170]: tun1: CCP: MPPE: Output channel initiated
Jun 29 15:07:38 www ppp[170]: tun1: CCP: deflink: Out = MPPE[18], In = MPPE[18]
Jun 29 15:07:38 www ppp[170]: tun1: LCP: Reducing MTU from 1400 to 1398
(CCP requirement)
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: deflink: RecvConfigAck(2) state
= Req-Sent
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: deflink: State change Req-Sent
--> Ack-Rcvd
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: deflink: RecvConfigReq(8) state
= Ack-Rcvd
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: IPADDR[6] 192.168.0.253
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: PRIDNS[6] 192.168.0.1
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: PRINBNS[6] 192.168.0.2
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: SECDNS[6] 192.168.0.1
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: deflink: SendConfigAck(8) state
= Ack-Rcvd
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: IPADDR[6] 192.168.0.253
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: PRIDNS[6] 192.168.0.1
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: PRINBNS[6] 192.168.0.2
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: SECDNS[6] 192.168.0.1
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: deflink: State change Ack-Rcvd
--> Opened
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: deflink: LayerUp.
Jun 29 15:07:38 www ppp[170]: tun1: IPCP: myaddr 192.168.0.1 hisaddr =
192.168.0.253
Jun 29 15:07:38 www ppp[170]: tun1: Phase: Found interface fxp0 for
192.168.0.253
Jun 29 15:07:38 www ppp[170]: tun1: Command: pptp: shell logger -i -t ppp
The user USER has successfully connected via PPTP and has
the IP address HISADDR
Jun 29 15:07:38 www ppp[171]: The user pptpuser has successfully
connected via PPTP and has the IP address 192.168.0.253
Jun 29 15:07:38 www ppp[170]: tun1: LCP: Reducing MTU from 1400 to 1398
(CCP requirement)
Jun 29 15:07:53 www ppp[170]: tun1: LCP: deflink: SendEchoRequest(1)
state = Opened
Jun 29 15:07:53 www ppp[170]: tun1: LCP: deflink: RecvEchoReply(1) state
= Opened
Jun 29 15:08:08 www ppp[170]: tun1: LCP: deflink: SendEchoRequest(2)
state = Opened
Jun 29 15:08:08 www ppp[170]: tun1: LCP: deflink: RecvEchoReply(2) state
= Opened
More information about the freebsd-questions
mailing list