From owner-freebsd-questions@FreeBSD.ORG Fri Jan 12 21:27:05 2007 Return-Path: X-Original-To: freebsd-questions@freebsd.org Delivered-To: freebsd-questions@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 1EE3216A407 for ; Fri, 12 Jan 2007 21:27:05 +0000 (UTC) (envelope-from list@museum.rain.com) Received: from ns.umpquanet.com (ns.umpquanet.com [63.105.30.37]) by mx1.freebsd.org (Postfix) with ESMTP id 03CFA13C459 for ; Fri, 12 Jan 2007 21:27:02 +0000 (UTC) (envelope-from list@museum.rain.com) Received: from ns.umpquanet.com (localhost [127.0.0.1]) by ns.umpquanet.com (8.13.8/8.13.8) with ESMTP id l0CLR2OM036023 for ; Fri, 12 Jan 2007 13:27:02 -0800 (PST) (envelope-from list@museum.rain.com) Received: (from james@localhost) by ns.umpquanet.com (8.13.8/8.13.8/Submit) id l0CLR2Hu036022 for freebsd-questions@freebsd.org; Fri, 12 Jan 2007 13:27:02 -0800 (PST) (envelope-from list@museum.rain.com) Date: Fri, 12 Jan 2007 13:27:02 -0800 From: James Long To: freebsd-questions@freebsd.org Message-ID: <20070112212701.GA34217@ns.umpquanet.com> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.5.13 (2006-08-11) Subject: server-side PPPoE: how to get ppp to automatically assign IPs? X-BeenThere: freebsd-questions@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: User questions List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 12 Jan 2007 21:27:05 -0000 I'm working with PPPoE. I have one client and a provider working. I'm using pppoed to spawn ppp instances on the provider: /usr/libexec/pppoed -F -p t30 fxp0 The provider's ppp.conf: default: set log Phase Chat LCP IPCP CCP tun command ident user-ppp VERSION (built COMPILATIONDATE) set device /dev/cual0 set speed 115200 set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \ \"\" ATZ OK-AT-OK \\dATDT\\T TIMEOUT 40 CONNECT" set timeout 1200 set server +3000 pppass t30: # this is pppoe server-side set device PPPoE:fxp0:t30 set ifaddr 192.168.255.1 192.168.0.128/25 255.255.255.255 enable proxy enable pap enable passwdauth The client's ppp.conf is: default: set log Phase Chat LCP IPCP CCP tun command ident user-ppp VERSION (built COMPILATIONDATE) set device /dev/cual0 set speed 115200 set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \ \"\" ATZ OK-AT-OK \\dATDT\\T TIMEOUT 40 CONNECT" set timeout 1200 set server +3000 pppass t30: # this is ppp over ethernet # set ifaddr mine/0 his/0 255.255.255.255 set ifaddr 10.0.0.2/0 10.0.0.1/0 255.255.255.255 add default HISADDR set timeout 0 set dial set device PPPoE:fxp0:t30 set authname ppp set authkey xxxxxxxxxxxx I can connect the first client no problem, and it is assigned 192.168.0.128. When the second client tries to connect, it fails. Actually, the "ppp -background t30" on the client goes well, and says "PPP enabled" but then the client ppp dies, I presume because the server side ppp closed the connection. It appears that the problem is client IP number conflict. The server-side ppp.log shows that it tries to assign the first client's IP to the second client, and though ppp detects the conflict, it does not try to assign a different IP, even though a /25 is provided in the server's ppp.conf, and the client is configured to accept any IP. How can I get the pppoe provider to automatically assign a unique IP to each client that requests service? Also, what if my pool of available IPs has discontinuities, such as a.b.c.d/24 and then i.j.k.l/25 and w.x.y.z/22? I don't see how that can be accomodated in the "set ifaddr" line. Is there a more extensible mechanism for assigning client IPs? How many tun devices is a low-end P4 machine likely to support? Thank you! Jim When the second client attempts to connect, the server ppp.log shows: Jan 12 12:59:17 t30 ppp[2513]: tun1: LCP: deflink: LayerFinish Jan 12 12:59:17 t30 ppp[2513]: tun1: LCP: deflink: State change Closing --> Closed Jan 12 12:59:17 t30 ppp[2513]: tun1: LCP: deflink: State change Closed --> Initial Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: deflink: Disconnected! Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: deflink: Connect time: 1 secs: 373 octets in, 400 octets out Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: deflink: 16 packets in, 17 packets out Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: total 773 bytes/sec, peak 0 bytes/sec on Fri Jan 12 12:59:16 2007 Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: deflink: lcp -> closed Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: bundle: Dead Jan 12 12:59:17 t30 ppp[2513]: tun1: Phase: PPP Terminated (normal). Jan 12 12:59:50 t30 ppp[2516]: Phase: Using interface: tun1 Jan 12 12:59:50 t30 ppp[2516]: Phase: deflink: Created in closed state Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: default: set device /dev/cual0 Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: default: set speed 115200 Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 "" ATZ OK-AT-OK \dATDT\T TIMEOUT 40 CONNECT Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: default: set timeout 1200 Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: default: set server +3000 ******** Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: Listening at port 3001. Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: t30: set device PPPoE:fxp0:t30 Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: t30: set ifaddr 192.168.255.1 192.168.0.128/25 255.255.255.255 Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: t30: enable proxy Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: t30: enable pap Jan 12 12:59:50 t30 ppp[2516]: tun1: Command: t30: enable passwdauth Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: PPP Started (direct mode). Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: bundle: Establish Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: deflink: closed -> opening Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: deflink: Link is a netgraph node Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: deflink: Connected! Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: deflink: opening -> carrier Jan 12 12:59:50 t30 ppp[2516]: tun1: Phase: deflink: carrier -> lcp Jan 12 12:59:50 t30 ppp[2516]: tun1: LCP: FSM: Using "deflink" as a transport Jan 12 12:59:50 t30 ppp[2516]: tun1: LCP: deflink: State change Initial --> Closed Jan 12 12:59:50 t30 ppp[2516]: tun1: LCP: deflink: State change Closed --> Stopped Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: LayerStart Jan 12 12:59:51 t30 ppp[2516]: tun1: Warning: deflink: Reducing configured MRU from 1500 to 1492 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendConfigReq(1) state = Stopped Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACFCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: PROTOCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACCMAP[6] 0x00000000 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MRU[4] 1492 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM[6] 0x7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: AUTHPROTO[4] 0xc023 (PAP) Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: State change Stopped --> Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvConfigReq(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACFCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: PROTOCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACCMAP[6] 0x00000000 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MRU[4] 1492 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM[6] 0x81bad0e2 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendConfigAck(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACFCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: PROTOCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACCMAP[6] 0x00000000 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MRU[4] 1492 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM[6] 0x81bad0e2 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: State change Req-Sent --> Ack-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvConfigAck(1) state = Ack-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACFCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: PROTOCOMP[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: ACCMAP[6] 0x00000000 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MRU[4] 1492 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM[6] 0x7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: AUTHPROTO[4] 0xc023 (PAP) Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: State change Ack-Sent --> Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: LayerUp Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendIdent(0) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Jan 10 2007) Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: bundle: Authenticate Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: his = none, mine = PAP Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvIdent(0) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 81bad0e2 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Dec 11 Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: Pap Input: REQUEST (ppp) Jan 12 12:59:51 t30 ppp[2516]: tun1: Warning: OpenSecret: Can't open /etc/ppp/ppp.secret. Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: Pap Output: SUCCESS Jan 12 12:59:51 t30 ppp[2516]: tun1: Warning: OpenSecret: Can't open /etc/ppp/ppp.secret. Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: FSM: Using "deflink" as a transport Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: State change Initial --> Closed Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: LayerStart. Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: MPPE: Not usable without CHAP81 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: SendConfigReq(1) state = Closed Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: DEFLATE[4] win 15 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: PRED1[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: State change Closed --> Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: lcp -> open Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: bundle: Network Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: FSM: Using "deflink" as a transport Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Initial --> Closed Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: LayerStart. Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: SendConfigReq(1) state = Closed Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: IPADDR[6] 192.168.255.1 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Closed --> Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: RecvConfigReq(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: DEFLATE[4] win 15 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: PRED1[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: SendConfigRej(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: PRED1[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendIdent(1) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Jan 10 2007) Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: RecvConfigReq(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: IPADDR[6] 192.168.0.128 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: PRIDNS[6] 10.0.0.1 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: SECDNS[6] 255.255.255.255 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: SendConfigRej(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: PRIDNS[6] 10.0.0.1 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: SECDNS[6] 255.255.255.255 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendIdent(2) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Jan 10 2007) Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: RecvConfigRej(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendIdent(3) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Jan 10 2007) Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: PRED1[2] Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: MPPE: Not usable without CHAP81 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: SendConfigReq(2) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: DEFLATE[4] win 15 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvIdent(1) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 81bad0e2 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Dec 11 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: RecvConfigAck(1) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: IPADDR[6] 192.168.255.1 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Req-Sent --> Ack-Rcvd Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvIdent(2) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 81bad0e2 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Dec 11 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: RecvConfigReq(2) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: DEFLATE[4] win 15 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: SendConfigAck(2) state = Req-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: DEFLATE[4] win 15 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: State change Req-Sent --> Ack-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvIdent(3) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 81bad0e2 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Dec 11 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: RecvConfigReq(2) state = Ack-Rcvd Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: IPADDR[6] 192.168.0.128 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: SendConfigAck(2) state = Ack-Rcvd Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: IPADDR[6] 192.168.0.128 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Ack-Rcvd --> Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: LayerUp. Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: myaddr 192.168.255.1 hisaddr = 192.168.0.128 Jan 12 12:59:51 t30 ppp[2516]: tun1: Warning: iface add: ioctl(SIOCAIFADDR, 192.168.255.1 -> 192.168.0.128): File exists Jan 12 12:59:51 t30 ppp[2516]: tun1: Error: ipcp_InterfaceUp: unable to set ip address Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: LayerDown: 192.168.255.1 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: SendTerminateReq(2) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Opened --> Closing Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendIdent(4) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: MAGICNUM 7893e875 Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: TEXT user-ppp 3.4.2 (built Jan 10 2007) Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: RecvConfigAck(2) state = Ack-Sent Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: DEFLATE[4] win 15 Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: State change Ack-Sent --> Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: LayerUp. Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: Deflate: Input channel reset Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: Deflate: Output channel reset Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: Out = DEFLATE[26], In = DEFLATE[26] Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: RecvTerminateAck(2) state = Closing Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: LayerFinish. Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: Connect time: 0 secs: 0 octets in, 0 octets out Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: 0 packets in, 0 packets out Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: total 0 bytes/sec, peak 0 bytes/sec on Fri Jan 12 12:59:51 2007 Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Closing --> Closed Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: bundle: Terminate Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: LayerDown. Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: State change Opened --> Starting Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: LayerFinish. Jan 12 12:59:51 t30 ppp[2516]: tun1: CCP: deflink: State change Starting --> Initial Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: LayerDown Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: SendTerminateReq(4) state = Opened Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: State change Opened --> Closing Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: open -> lcp Jan 12 12:59:51 t30 ppp[2516]: tun1: IPCP: deflink: State change Closed --> Initial Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: RecvTerminateAck(4) state = Closing Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: LayerFinish Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: State change Closing --> Closed Jan 12 12:59:51 t30 ppp[2516]: tun1: LCP: deflink: State change Closed --> Initial Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: Disconnected! Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: Connect time: 1 secs: 373 octets in, 400 octets out Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: 16 packets in, 17 packets out Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: total 773 bytes/sec, peak 0 bytes/sec on Fri Jan 12 12:59:50 2007 Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: deflink: lcp -> closed Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: bundle: Dead Jan 12 12:59:51 t30 ppp[2516]: tun1: Phase: PPP Terminated (normal).