ppp fails to get default route on reconnect

[Nick Nauwelaerts <nick_(_at_)_nauwelaerts_(_dot_)_net>]

Hello,
I have a problem with my pppoe adsl connection. My isp has a 7 day ip
rotation scheme, so once every week I get thrown of the net and need to
reconnect, getting a new ip from the pool in the process. While the
reconnecting goes fine it seems to have some problems with updating it's
default route. I get a new IP but my default route still points to the
old gateway. Sometimes this isn't a big deal since I'll stay in the same
range, other times I get moved to another IP range and thus can't
connect to anything. I've tried reloading my pf rules with a "pf -F all
&& pf -f /etc/pf.conf" but that doesn't help. Only killing and
restarting the ppp process will restore the connection.

Anyone seen this? I've looked for the "Warning: 0.0.0.0/0: Change route
failed: errno: No such process" error but didn't come up with something
relevant.

Here is my /etc/ppp/ppp.conf
default:
 set log Phase Chat LCP IPCP CCP LQM tun command
 set redial 15 0
 set reconnect 15 10

pppoe:
 set device "!/usr/sbin/pppoe -i sf3"
 set mtu max 1454
 set mru max 1454
 set speed sync
 disable acfcomp protocomp
 deny acfcomp
 add default HISADDR
 set authname "1234xxx"
 set authkey "1234xxx"
 enable lqr
 set lqrperiod 5
 set cd 5
 set dial
 set login
 set timeout 0
 enable mssfixup



And the part of the log where it disconnects and reconnects:

Feb  7 05:55:16 zombie ppp[5023]: tun0: LCP: deflink:
RecvTerminateReq(11) state = Opened 
Feb  7 05:55:16 zombie ppp[5023]: tun0: LCP: deflink: LayerDown 
Feb  7 05:55:16 zombie ppp[5023]: tun0: LCP: deflink:
SendTerminateAck(11) state = Opened 
Feb  7 05:55:16 zombie ppp[5023]: tun0: LCP: deflink: State change
Opened --> Stopping 
Feb  7 05:55:16 zombie ppp[5023]: tun0: CCP: deflink: State change
Stopped --> Closed 
Feb  7 05:55:16 zombie ppp[5023]: tun0: CCP: deflink: State change
Closed --> Initial 
Feb  7 05:55:16 zombie ppp[5023]: tun0: Phase: deflink: open -> lcp 
Feb  7 05:55:16 zombie ppp[5023]: tun0: Warning: 0.0.0.0/0: Change route
failed: errno: No such process 
Feb  7 05:55:16 zombie ppp[5023]: tun0: Warning: ff02::%tun0/32: Change
route failed: errno: Undefined error: 0 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP: deflink: LayerDown:
82.146.104.48 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP: deflink: State change
Opened --> Starting 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP: deflink: LayerFinish. 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP: Connect time: 174704 secs:
43274934 octets in, 10130650 octets out 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP: 985269 packets in, 971555
packets out 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP:  total 305 bytes/sec, peak
124615 bytes/sec on Sun Feb  6 23:56:52 2005 
Feb  7 05:55:16 zombie ppp[5023]: tun0: IPCP: deflink: State change
Starting --> Initial 
Feb  7 05:55:16 zombie ppp[5023]: tun0: Phase: bundle: Terminate 
Feb  7 05:55:19 zombie ppp[5023]: tun0: LCP: deflink: LayerFinish 
Feb  7 05:55:19 zombie ppp[5023]: tun0: LCP: deflink: State change
Stopping --> Stopped 
Feb  7 05:55:19 zombie ppp[5023]: tun0: LCP: deflink: State change
Stopped --> Closed 
Feb  7 05:55:19 zombie ppp[5023]: tun0: LCP: deflink: State change
Closed --> Initial 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: Disconnected! 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: lcp -> logout 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: logout -> hangup

Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: Disconnected! 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: Connect time:
174710 secs: 43275259 octets in, 10576470 octets out 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: 985340 packets
in, 971625 packets out 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase:  total 308 bytes/sec,
peak 0 bytes/sec on Sat Feb  5 05:23:29 2005 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: HUPing 6802 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: hangup ->
opening 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: bundle: Establish 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Phase: deflink: Enter pause (15)
for redialing. 
Feb  7 05:55:19 zombie ppp[5023]: tun0: Chat: deflink: Reconnect try 4
of 10 
Feb  7 05:55:34 zombie ppp[5023]: tun0: Chat: deflink: Redial timer
expired. 
Feb  7 05:55:34 zombie ppp[5023]: tun0: Warning: Carrier settings
ignored 
Feb  7 05:55:34 zombie ppp[5023]: tun0: Phase: deflink: Connected! 
Feb  7 05:55:34 zombie ppp[5023]: tun0: Phase: deflink: opening -> dial 
Feb  7 05:55:34 zombie ppp[5023]: tun0: Phase: deflink: dial -> carrier 
Feb  7 05:55:34 zombie ppp[5023]: tun0: Phase: deflink: carrier -> login

Feb  7 05:55:34 zombie ppp[5023]: tun0: Phase: deflink: login -> lcp 
Feb  7 05:55:34 zombie ppp[5023]: tun0: LCP: FSM: Using "deflink" as a
transport 
Feb  7 05:55:34 zombie ppp[5023]: tun0: LCP: deflink: State change
Initial --> Closed 
Feb  7 05:55:34 zombie ppp[5023]: tun0: LCP: deflink: State change
Closed --> Stopped 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: RecvConfigReq(129)
state = Stopped 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1492 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP
0x05) 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MAGICNUM[6] 0x64820c9a 
Feb  7 05:55:35 zombie ppp[5023]: tun0: Warning: deflink: Reducing
configured MRU from 1500 to 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: SendConfigReq(9)
state = Stopped 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MAGICNUM[6] 0x1001eee1 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  QUALPROTO[8] proto c025,
interval 5000ms 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: SendConfigNak(129)
state = Stopped 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: LayerStart 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: State change
Stopped --> Req-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: RecvConfigRej(9)
state = Req-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  QUALPROTO[8] proto c025,
interval 5000ms 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: SendConfigReq(10)
state = Req-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MAGICNUM[6] 0x1001eee1 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: RecvConfigReq(130)
state = Req-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP
0x05) 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MAGICNUM[6] 0x64820c9a 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: SendConfigAck(130)
state = Req-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP
0x05) 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MAGICNUM[6] 0x64820c9a 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: State change
Req-Sent --> Ack-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: RecvConfigAck(10)
state = Ack-Sent 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MRU[4] 1454 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP:  MAGICNUM[6] 0x1001eee1 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: State change
Ack-Sent --> Opened 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: LayerUp 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LQM: deflink: Will send ECHO LQR
every 5.00 secs 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: SendEchoRequest(0)
state = Opened 
Feb  7 05:55:35 zombie ppp[5023]: tun0: Phase: bundle: Authenticate 
Feb  7 05:55:35 zombie ppp[5023]: tun0: Phase: deflink: his = CHAP 0x05,
mine = none 
Feb  7 05:55:35 zombie ppp[5023]: tun0: Phase: Chap Input: CHALLENGE (16
bytes from kpn_be_vil_rb01) 
Feb  7 05:55:35 zombie ppp[5023]: tun0: Phase: Chap Output: RESPONSE
(015758860_(_at_)_XS4ALL) 
Feb  7 05:55:35 zombie ppp[5023]: tun0: LCP: deflink: RecvEchoReply(0)
state = Opened 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Phase: Chap Input: SUCCESS (CHAP
authentication success, unit 990) 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: FSM: Using "deflink" as a
transport 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: deflink: State change
Initial --> Closed 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: deflink: LayerStart. 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: MPPE: Not usable without
CHAP81 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: deflink: SendConfigReq(1)
state = Closed 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP:  DEFLATE[4] win 15 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP:  PRED1[2]  
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: deflink: State change
Closed --> Req-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Phase: deflink: lcp -> open 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Phase: bundle: Network 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: FSM: Using "deflink" as a
transport 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: State change
Initial --> Closed 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: LayerStart. 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: SendConfigReq(11)
state = Closed 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  IPADDR[6] 82.146.104.48 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots
with slot compression 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: State change
Closed --> Req-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink:
RecvConfigReq(152) state = Req-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  IPADDR[6] 82.146.105.254 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink:
SendConfigAck(152) state = Req-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  IPADDR[6] 82.146.105.254 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: State change
Req-Sent --> Ack-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(131) state = Opened 
Feb  7 05:55:36 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x80fd
(Compression Control Protocol) was rejected! 
Feb  7 05:55:36 zombie ppp[5023]: tun0: CCP: deflink: State change
Req-Sent --> Stopped 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: RecvConfigRej(11)
state = Ack-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots
with slot compression 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: SendConfigReq(12)
state = Ack-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  IPADDR[6] 82.146.104.48 
Feb  7 05:55:36 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(132) state = Opened 
Feb  7 05:55:36 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x8057
(Internet Protocol V6 Control Protocol) was rejected! 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Phase: deflink: IPV6CP protocol
reject closes IPV6CP ! 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: RecvConfigAck(12)
state = Ack-Sent 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP:  IPADDR[6] 82.146.104.48 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: State change
Ack-Sent --> Opened 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: deflink: LayerUp. 
Feb  7 05:55:36 zombie ppp[5023]: tun0: IPCP: myaddr 82.146.104.48
hisaddr = 82.146.105.254 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Warning: 0.0.0.0/0: Change route
failed: errno: No such process 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Warning: ff02::%tun0/32: Change
route failed: errno: Undefined error: 0 
Feb  7 05:55:36 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(133) state = Opened 
Feb  7 05:55:36 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x8057
(Internet Protocol V6 Control Protocol) was rejected! 
Feb  7 05:55:36 zombie ppp[5023]: tun0: Phase: deflink: IPV6CP protocol
reject closes IPV6CP ! 
Feb  7 05:55:39 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(134) state = Opened 
Feb  7 05:55:39 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x8057
(Internet Protocol V6 Control Protocol) was rejected! 
Feb  7 05:55:39 zombie ppp[5023]: tun0: Phase: deflink: IPV6CP protocol
reject closes IPV6CP ! 
Feb  7 05:55:42 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(135) state = Opened 
Feb  7 05:55:42 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x8057
(Internet Protocol V6 Control Protocol) was rejected! 
Feb  7 05:55:42 zombie ppp[5023]: tun0: Phase: deflink: IPV6CP protocol
reject closes IPV6CP ! 
Feb  7 05:55:45 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(136) state = Opened 
Feb  7 05:55:45 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x8057
(Internet Protocol V6 Control Protocol) was rejected! 
Feb  7 05:55:45 zombie ppp[5023]: tun0: Phase: deflink: IPV6CP protocol
reject closes IPV6CP ! 
Feb  7 05:55:48 zombie ppp[5023]: tun0: LCP: deflink:
RecvProtocolRej(137) state = Opened 
Feb  7 05:55:48 zombie ppp[5023]: tun0: LCP: deflink: -- Protocol 0x8057
(Internet Protocol V6 Control Protocol) was rejected! 
Feb  7 05:55:48 zombie ppp[5023]: tun0: Phase: deflink: IPV6CP protocol
reject closes IPV6CP ! 



Thanks.

// nick