ppp(8) / ng_pppoe: choked output queue?

[Stefan Bethke]

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

-- 
Stefan Bethke <stb at lassitu.de>   Fon +49 170 346 0140