From owner-freebsd-questions@FreeBSD.ORG Tue Feb 22 14:31:48 2005 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 75A8316A4CE for ; Tue, 22 Feb 2005 14:31:48 +0000 (GMT) Received: from ctb-mesg5.saix.net (ctb-mesg5.saix.net [196.25.240.77]) by mx1.FreeBSD.org (Postfix) with ESMTP id 832B643D5F for ; Tue, 22 Feb 2005 14:31:47 +0000 (GMT) (envelope-from savage@savage.za.org) Received: from netsphere.cenergynetworks.com (wblv-146-240-203.telkomadsl.co.za [165.146.240.203]) by ctb-mesg5.saix.net (Postfix) with ESMTP id 75BA53A34 for ; Tue, 22 Feb 2005 16:31:43 +0200 (SAST) Received: from pmx.ournet.co.za ([198.19.0.73] helo=netsphere.cenergynetworks.com) by netsphere.cenergynetworks.com with smtp (Exim 4.41) id 1D3b4h-0004mB-qw for freebsd-questions@freebsd.org; Tue, 22 Feb 2005 16:31:43 +0200 Received: from [192.168.1.10] (helo=netphobia) by netsphere.cenergynetworks.com with smtp (Exim 4.41) id 1D3b4f-0004m7-ra for freebsd-questions@freebsd.org; Tue, 22 Feb 2005 16:31:41 +0200 Message-ID: <000701c518eb$4b3346c0$0a01a8c0@ops.cenergynetworks.com> From: "Chris Knipe" To: Date: Tue, 22 Feb 2005 16:32:10 +0200 MIME-Version: 1.0 Content-Type: text/plain; format=flowed; charset="iso-8859-1"; reply-type=original Content-Transfer-Encoding: 7bit X-Priority: 3 X-MSMail-Priority: Normal X-Mailer: Microsoft Outlook Express 6.00.2900.2527 X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.2527 X-Broken-Reverse-DNS: 192.168.1.10 X-PMX-Version: 4.7.0.111621, Antispam-Engine: 2.0.2.0, Antispam-Data: 2005.2.8.1 Subject: ppp failure X-BeenThere: freebsd-questions@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list Reply-To: Chris Knipe List-Id: User questions List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 22 Feb 2005 14:31:48 -0000 Hi, Can some ppp guru not by any change tell me why the below session is failing? I never seem to be able to properly read these PPP sessions... Feb 22 16:31:44 wsmd01 pppoed[7845]: Listening Feb 22 16:31:44 wsmd01 pppoed[8045]: Offering to .:exec-8045 as access concentrator wsmd01 Feb 22 16:31:44 wsmd01 pppoed[8045]: adding to .:exec-8045 as offered service wsmd01 Feb 22 16:31:44 wsmd01 pppoed[8045]: Received NGM_PPPOE_SESSIONID (hook "%") Feb 22 16:31:44 wsmd01 pppoed[8045]: Received NGM_PPPOE_SUCCESS (hook "exec-8045") Feb 22 16:31:44 wsmd01 pppoed[8045]: Executing: exec /usr/sbin/ppp -direct PPPoE Feb 22 16:31:44 wsmd01 ppp[8045]: Phase: Using interface: tun1 Feb 22 16:31:44 wsmd01 ppp[8045]: Phase: deflink: Created in closed state Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: default: set device /dev/cuaa1 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: default: set speed 115200 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 "" AT OK-AT-OK ATE1Q0 OK \dATDT\T TIMEOUT 40 CONNECT Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: PPPoE: enable lqr proxy Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: PPPoE: enable pap chap passwdauth Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: PPPoE: set ifaddr 192.168.1.20 192.168.1.200-192.168.1.254 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: IPCP: Selected IP address 192.168.1.241 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Command: PPPoE: accept dns Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: PPP Started (direct mode). Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: bundle: Establish Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: deflink: closed -> opening Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: deflink: Link is a netgraph node Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: deflink: Connected! Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: deflink: opening -> carrier Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Phase: deflink: carrier -> lcp Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: FSM: Using "deflink" as a transport Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: State change Initial --> Closed Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: State change Closed --> Stopped Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: RecvConfigReq(1) state = Stopped Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MRU[4] 1440 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM[6] 0x00001771 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: Warning: deflink: Reducing configured MRU from 1500 to 1492 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: SendConfigReq(1) state = Stopped Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: ACFCOMP[2] Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: PROTOCOMP[2] Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: ACCMAP[6] 0x00000000 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MRU[4] 1492 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM[6] 0xf9d75109 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: QUALPROTO[8] proto c025, interval 30000ms Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: SendConfigAck(1) state = Stopped Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MRU[4] 1440 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM[6] 0x00001771 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: LayerStart Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: State change Stopped --> Ack-Sent Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: RecvConfigRej(1) state = Ack-Sent Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: SendIdent(0) state = Ack-Sent Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM f9d75109 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: TEXT user-ppp 3.1 (built Feb 23 2004) Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: QUALPROTO[8] proto c025, interval 30000ms Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: deflink: SendConfigReq(2) state = Ack-Sent Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: ACFCOMP[2] Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: PROTOCOMP[2] Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: ACCMAP[6] 0x00000000 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MRU[4] 1492 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM[6] 0xf9d75109 Feb 22 16:31:44 wsmd01 ppp[8045]: tun1: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Feb 22 16:31:46 wsmd01 ppp[8045]: tun1: LCP: deflink: RecvConfigReq(2) state = Ack-Sent Feb 22 16:31:46 wsmd01 ppp[8045]: tun1: LCP: MRU[4] 1440 Feb 22 16:31:46 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM[6] 0x00001771 Feb 22 16:31:46 wsmd01 ppp[8045]: tun1: LCP: deflink: SendConfigAck(2) state = Ack-Sent Feb 22 16:31:46 wsmd01 ppp[8045]: tun1: LCP: MRU[4] 1440 Feb 22 16:31:46 wsmd01 ppp[8045]: tun1: LCP: MAGICNUM[6] 0x00001771 Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: LCP: deflink: LayerFinish Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: LCP: deflink: State change Ack-Sent --> Stopped Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: LCP: deflink: State change Stopped --> Closed Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: LCP: deflink: State change Closed --> Initial Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: deflink: Disconnected! Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: deflink: Connect time: 15 secs: 126 octets in, 348 octets out Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: deflink: 8 packets in, 14 packets out Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: total 31 bytes/sec, peak 79 bytes/sec on Tue Feb 22 16:31:32 2005 Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: deflink: lcp -> closed Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: bundle: Dead Feb 22 16:31:46 wsmd01 ppp[8043]: tun0: Phase: PPP Terminated (normal). Thanks, much appreciated. -- Chris.