From owner-freebsd-net@FreeBSD.ORG Thu Aug 24 04:31:46 2006 Return-Path: X-Original-To: freebsd-net@freebsd.org Delivered-To: freebsd-net@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 3B63116A4DA for ; Thu, 24 Aug 2006 04:31:46 +0000 (UTC) (envelope-from prvs=julian=3845287f1@elischer.org) Received: from a50.ironport.com (a50.ironport.com [63.251.108.112]) by mx1.FreeBSD.org (Postfix) with ESMTP id EAE7043D45 for ; Thu, 24 Aug 2006 04:31:42 +0000 (GMT) (envelope-from prvs=julian=3845287f1@elischer.org) Received: from unknown (HELO [192.168.2.3]) ([10.251.60.33]) by a50.ironport.com with ESMTP; 23 Aug 2006 21:31:37 -0700 Message-ID: <44ED2BA8.60408@elischer.org> Date: Wed, 23 Aug 2006 21:31:36 -0700 From: Julian Elischer User-Agent: Mozilla/5.0 (Macintosh; U; PPC Mac OS X Mach-O; en-US; rv:1.7.13) Gecko/20060414 X-Accept-Language: en-us, en MIME-Version: 1.0 To: Stefan Bethke References: In-Reply-To: Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Cc: freebsd-net@freebsd.org Subject: Re: ppp(8) / ng_pppoe: choked output queue? X-BeenThere: freebsd-net@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Networking and TCP/IP with FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 24 Aug 2006 04:31:46 -0000 Stefan Bethke wrote: > Hi, > > just found my router being unable to reestablish the PPPoE connection > this morning. This had been going on for about four hours before I > restarted ppp. Any ideas what would cause the output queue to choke, > why it would report Already in NETWORK phase, why the output queue is > choking, and why ppp can't successfully clear it? > > Aug 24 05:52:32 diesel ppp[330]: Phase: deflink: Connected! > Aug 24 05:52:32 diesel ppp[330]: Phase: deflink: opening -> dial > Aug 24 05:52:32 diesel ppp[330]: Phase: deflink: dial -> carrier > Aug 24 05:52:33 diesel ppp[330]: Phase: Received NGM_PPPOE_ACNAME > (hook "HN-XDSL") > Aug 24 05:52:33 diesel ppp[330]: Phase: Received NGM_PPPOE_SESSIONID > Aug 24 05:52:33 diesel ppp[330]: Phase: Received NGM_PPPOE_SUCCESS > Aug 24 05:52:33 diesel ppp[330]: Phase: deflink: carrier -> login > Aug 24 05:52:33 diesel ppp[330]: Phase: deflink: login -> lcp > Aug 24 05:52:34 diesel ppp[330]: Phase: deflink: his = CHAP 0x05, > mine = none > Aug 24 05:52:34 diesel ppp[330]: Phase: Chap Input: CHALLENGE (24 > bytes from BRUN-0176-03-11) > Aug 24 05:52:34 diesel ppp[330]: Phase: Chap Output: RESPONSE > (04022758623) > Aug 24 05:52:34 diesel ppp[330]: Phase: Chap Input: SUCCESS > Aug 24 05:52:34 diesel ppp[330]: Phase: deflink: Already in NETWORK > phase > Aug 24 05:52:34 diesel ppp[330]: Phase: deflink: lcp -> open > Aug 24 05:52:39 diesel ppp[330]: Phase: Clearing choked output queue > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: open -> lcp > Aug 24 05:54:34 diesel ppp[330]: Phase: Received NGM_PPPOE_CLOSE > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: Device disconnected > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: Disconnected! > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: lcp -> logout > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: Disconnected! > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: logout -> hangup > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: Connect time: 122 > secs: 841 octets in, 533 octets out > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: 985248 packets in, > 622067 packets out > Aug 24 05:54:34 diesel ppp[330]: Phase: total 11 bytes/sec, peak 61 > bytes/sec on Thu Aug 24 05:52:35 2006 > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: hangup -> opening > Aug 24 05:54:34 diesel ppp[330]: Phase: deflink: Enter pause (15) for > redialing. > Aug 24 05:54:39 diesel ppp[330]: Phase: Clearing choked output queue > > Here's the successful connection from by restarting ppp: > Aug 24 06:11:08 diesel ppp[91689]: Phase: Using interface: tun0 > Aug 24 06:11:08 diesel ppp[91689]: Phase: deflink: Created in closed > state > Aug 24 06:11:08 diesel ppp[91690]: Phase: PPP Started (ddial mode). > Aug 24 06:11:08 diesel ppp[91690]: Phase: bundle: Establish > Aug 24 06:11:08 diesel ppp[91690]: Phase: deflink: closed -> opening > Aug 24 06:11:08 diesel ppp[91690]: Phase: deflink: Connected! > Aug 24 06:11:08 diesel ppp[91690]: Phase: deflink: opening -> dial > Aug 24 06:11:08 diesel ppp[91690]: Phase: deflink: dial -> carrier > Aug 24 06:11:09 diesel ppp[91690]: Phase: Received NGM_PPPOE_ACNAME > (hook "HN-XDSL") > Aug 24 06:11:09 diesel ppp[91690]: Phase: Received NGM_PPPOE_SESSIONID > Aug 24 06:11:09 diesel ppp[91690]: Phase: Received NGM_PPPOE_SUCCESS > Aug 24 06:11:09 diesel ppp[91690]: Phase: deflink: carrier -> login > Aug 24 06:11:09 diesel ppp[91690]: Phase: deflink: login -> lcp > Aug 24 06:11:10 diesel ppp[91690]: Phase: bundle: Authenticate > Aug 24 06:11:10 diesel ppp[91690]: Phase: deflink: his = CHAP 0x05, > mine = none > Aug 24 06:11:10 diesel ppp[91690]: Phase: Chap Input: CHALLENGE (17 > bytes from BRUN-0176-03-11) > Aug 24 06:11:10 diesel ppp[91690]: Phase: Chap Output: RESPONSE > (04022758623) > Aug 24 06:11:11 diesel ppp[91690]: Phase: Chap Input: SUCCESS > Aug 24 06:11:11 diesel ppp[91690]: Phase: deflink: lcp -> open > Aug 24 06:11:11 diesel ppp[91690]: Phase: bundle: Network > > > Stefan > if it happens again, get a tcpdump of your ethernet interface.. (the one pppoe is using)