Problems with Userland PPP as PPTP/PPPoE server

From: Brett Glass (brett_at_lariat.org)
Date: 06/30/03

  • Next message: Kris Kennaway: "Re: performance tuning?"
    Date: Mon, 30 Jun 2003 15:32:46 -0600
    To: questions@freebsd.org
    
    

    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

    _______________________________________________
    freebsd-questions@freebsd.org mailing list
    http://lists.freebsd.org/mailman/listinfo/freebsd-questions
    To unsubscribe, send any mail to "freebsd-questions-unsubscribe@freebsd.org"


  • Next message: Kris Kennaway: "Re: performance tuning?"

    Relevant Pages

    • Urgent, PPP Problems.
      ... I've successfully set up FreeBSD on numerous occasions as a PPP Dialin ... Server, both for modem usage, as well as PPTP and other types of VPNs. ... Stopped --> Req-Sent ...
      (freebsd-questions)
    • Urgent, PPP Problems.
      ... I've successfully set up FreeBSD on numerous occasions as a PPP Dialin ... Server, both for modem usage, as well as PPTP and other types of VPNs. ... Stopped --> Req-Sent ...
      (freebsd-questions)
    • Re: FreeBSD and MPD PPTP VPN headache
      ... did was to screw up my Apache server. ... # PPTP Server ... Feb 25 01:53:17 bsdbox mpd: COMPPROTO VJCOMP, ... link 0 (Req-Sent) ...
      (comp.unix.bsd.freebsd.misc)