ppp(8) / ng_pppoe: choked output queue?

[Julian Elischer]

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)