ppp redial unsuccessful

Wed Oct 4 18:52:06 UTC 2006

Hello all,

with my ADSL provider (a reseller of the german Telekom), I'm unable to
make ppp redial after the link has been lost. With Telekom, you usually
get disconnected every 24h hours, but you can simply reconnect .... if
our ppp would support it.

Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: open -> lcp
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_CLOSE
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: Device disconnected
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: Disconnected!
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: lcp -> logout
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: Disconnected!
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: logout -> hangup
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: Connect time: 120 secs: 395 octets in, 131 octets out
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: 3122183 packets in, 3135772 packets out
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase:  total 4 bytes/sec, peak 72 bytes/sec on Wed Oct  4 20:37:53 2006
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: hangup -> opening
Oct  4 20:39:51 coyote ppp[57670]: tun0: Phase: deflink: Enter pause (3) for redialing.
Oct  4 20:39:54 coyote ppp[57670]: tun0: Phase: deflink: Connected!
Oct  4 20:39:54 coyote ppp[57670]: tun0: Phase: deflink: opening -> dial
Oct  4 20:39:54 coyote ppp[57670]: tun0: Phase: deflink: dial -> carrier
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "HN-XDSL")
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: deflink: carrier -> login
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: deflink: login -> lcp
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: Chap Input: CHALLENGE (23 bytes from BRUN-FX-0001-01-01)
Oct  4 20:39:55 coyote ppp[57670]: tun0: Phase: Chap Output: RESPONSE (06109724173)
Oct  4 20:39:56 coyote ppp[57670]: tun0: Phase: Chap Input: SUCCESS
Oct  4 20:39:56 coyote ppp[57670]: tun0: Phase: deflink: Already in NETWORK phase
Oct  4 20:39:56 coyote ppp[57670]: tun0: Phase: deflink: lcp -> open
Oct  4 20:41:48 coyote ppp[57670]: tun0: Phase: Clearing choked output queue
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: open -> lcp
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_CLOSE
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: Device disconnected
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: Disconnected!
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: lcp -> logout
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: Disconnected!
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: logout -> hangup
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: Connect time: 121 secs: 395 octets in, 131 octets out
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: 3122213 packets in, 3135778 packets out
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase:  total 4 bytes/sec, peak 72 bytes/sec on Wed Oct  4 20:39:56 2006
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: hangup -> opening
Oct  4 20:41:55 coyote ppp[57670]: tun0: Phase: deflink: Enter pause (3) for redialing.
Oct  4 20:41:58 coyote ppp[57670]: tun0: Phase: deflink: Connected!
Oct  4 20:41:58 coyote ppp[57670]: tun0: Phase: deflink: opening -> dial
Oct  4 20:41:58 coyote ppp[57670]: tun0: Phase: deflink: dial -> carrier
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "HN-XDSL")
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: deflink: carrier -> login
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: deflink: login -> lcp
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: Chap Input: CHALLENGE (31 bytes from BRUN-FX-0001-01-01)
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: Chap Output: RESPONSE (06109724173)
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: Chap Input: SUCCESS
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: deflink: Already in NETWORK phase
Oct  4 20:41:59 coyote ppp[57670]: tun0: Phase: deflink: lcp -> open

My ppp.conf looks like this:

default:
 set log Phase Chat LCP IPCP CCP tun command
 ident user-ppp VERSION (built COMPILATIONDATE)
 set device /dev/cuad1
 set speed 115200
 set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \
           \"\" AT OK-AT-OK ATE1Q0 OK \\dATDT\\T TIMEOUT 40 CONNECT"
 set timeout 180                        # 3 minute idle timer (the default)
 enable dns                             # request DNS info (for resolv.conf)

alice:
  resolv readonly
  set log Phase tun command
  set device PPPoE:dc0
  set dial
  #set redial 40+10-10.90 0
  set redial 90.91 0
  set crtscts off
  set speed sync
  set mru 1492
  set mtu 1492
  set authname XXXXXX
  set authkey XXXXXX
  add default HISADDR

How are other people circumventing this? I know that I could just
forcefully restart ppp at 3 o'clock in the morning, but I'm more
interested in a permanent fix.

And why is it that ppp *completely* ignores the redial timeout? It
should wait either 90 or 91 seconds, but instead goes on flooding my
/var/log/ppp.log

Any help or hints would be appreciated.

Ulrich Spoerlein
-- 
A: Yes.
>Q: Are you sure?
> >A: Because it reverses the logical flow of conversation.
> >>Q: Why is top posting frowned upon?