From owner-freebsd-questions@FreeBSD.ORG Mon Jun 30 14:36:18 2003 Return-Path: Delivered-To: freebsd-questions@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 0819137B443 for ; Mon, 30 Jun 2003 14:36:18 -0700 (PDT) Received: from lariat.org (lariat.org [63.229.157.2]) by mx1.FreeBSD.org (Postfix) with ESMTP id DF1F64406E for ; Mon, 30 Jun 2003 14:33:01 -0700 (PDT) (envelope-from brett@lariat.org) Received: from mustang.lariat.org (IDENT:ppp1000.lariat.org@lariat.org [63.229.157.2]) by lariat.org (8.9.3/8.9.3) with ESMTP id PAA17636 for ; Mon, 30 Jun 2003 15:32:56 -0600 (MDT) X-message-flag: Warning! Use of Microsoft Outlook renders your system susceptible to Internet worms. Message-Id: <4.3.2.7.2.20030630151927.00ba8460@localhost> X-Sender: brett@localhost X-Mailer: QUALCOMM Windows Eudora Version 4.3.2 Date: Mon, 30 Jun 2003 15:32:46 -0600 To: questions@freebsd.org From: Brett Glass Mime-Version: 1.0 Content-Type: text/plain; charset="us-ascii"; format=flowed Subject: Problems with Userland PPP as PPTP/PPPoE server X-BeenThere: freebsd-questions@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: User questions List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 30 Jun 2003 21:36:20 -0000 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