ppp client not relinquishing IP address

andrew clarke mail at ozzmosis.com
Thu Sep 17 05:33:34 UTC 2015 

Hi,

I run my ADSL modem in bridge mode. Often when my the ADSL service
disconnects for whatever reason, FreeBSD's ppp client will 'hold' the
old IP address despite it no longer being connected:

$ ifconfig tun0
tun0: flags=8051<UP,POINTOPOINT,RUNNING,MULTICAST> metric 0 mtu 1492
        options=80000<LINKSTATE>
        inet 203.206.32.1 --> 150.101.32.78 netmask 0xffffff00 
        inet 114.198.37.186 --> 150.101.32.44 netmask 0xffffff00 
        nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
        Opened by PID 10470

What's causing this, and is there any way I can prevent it?

For one, ddclient doesn't update my dynamic DNS server with the new IP
address.

My /etc/ppp.conf is as follows, and hopefully some useful bits of
/var/log/ppp.log below it.

Thanks.

Regards
Andrew

default:
  set log phase ipcp ccp tun command
  nat enable yes
  enable echo
  enable lqr
  set speed sync
  set ctsrts off
  set ifaddr 10.2.0.1/0 10.2.0.2/0 255.255.255.0 0.0.0.0

iinet:
  set device PPPoE:bge0
  set authname username
  set authkey password
  set dial
  set login
  set mru 1492
  set mtu 1492
  set timeout 0
  set redial 30 0
  add default HISADDR

Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: ** Too many LCP ECHO packets lost **
Sep 17 13:59:19 xyz ppp[10470]: tun0: CCP: deflink: State change Stopped --> Closed
Sep 17 13:59:19 xyz ppp[10470]: tun0: CCP: deflink: State change Closed --> Initial
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: open -> lcp
Sep 17 13:59:19 xyz ppp[10470]: tun0: Warning: 0.0.0.0: Change route failed: errno: No such process
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: deflink: LayerDown: 203.206.32.1
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: Using trigger address 0.0.0.0
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: deflink: State change Opened --> Starting
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: deflink: LayerFinish.
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: Connect time: 177032 secs: 4603586184 octets in, 3639482180 octets out
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: 5337253 packets in, 5181568 packets out
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP:  total 46562 bytes/sec, peak 554035 bytes/sec on Wed Sep 16 09:05:32 2015
Sep 17 13:59:19 xyz ppp[10470]: tun0: IPCP: deflink: State change Starting --> Initial
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: bundle: Terminate
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: Disconnected!
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: lcp -> logout
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: Disconnected!
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: logout -> hangup
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: Connect time: 177034 secs: 4593045207 octets in, 3649979017 octets out
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: 5345986 packets in, 5190311 packets out
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase:  total 46561 bytes/sec, peak 554561 bytes/sec on Wed Sep 16 09:06:03 2015
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: hangup -> opening
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: bundle: Establish
Sep 17 13:59:19 xyz ppp[10470]: tun0: Phase: deflink: Enter pause (3) for redialing.

...

Sep 17 14:05:53 xyz ppp[10470]: tun0: Phase: deflink: hangup -> opening
Sep 17 14:05:53 xyz ppp[10470]: tun0: Phase: deflink: Enter pause (30) for redialing.
Sep 17 14:06:23 xyz ppp[10470]: tun0: Phase: deflink: Connected!
Sep 17 14:06:23 xyz ppp[10470]: tun0: Phase: deflink: opening -> dial
Sep 17 14:06:23 xyz ppp[10470]: tun0: Phase: deflink: dial -> carrier
Sep 17 14:06:24 xyz ppp[10470]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "bng1.mel4")
Sep 17 14:06:24 xyz ppp[10470]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Sep 17 14:06:24 xyz ppp[10470]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Sep 17 14:06:24 xyz ppp[10470]: tun0: Phase: deflink: carrier -> login
Sep 17 14:06:24 xyz ppp[10470]: tun0: Phase: deflink: login -> lcp
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: bundle: Authenticate
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: deflink: his = PAP, mine = none
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: Pap Output: username ********
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: Pap Input: SUCCESS ()
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: Using trigger address 0.0.0.0
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: FSM: Using "deflink" as a transport
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: deflink: State change Initial --> Closed
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: deflink: LayerStart.
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: MPPE: Not usable without CHAP81
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP:  DEFLATE[4] win 15
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP:  PRED1[2] 
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: deflink: State change Closed --> Req-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: deflink: lcp -> open
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: bundle: Network
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: FSM: Using "deflink" as a transport
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: State change Initial --> Closed
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: LayerStart.
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: SendConfigReq(4) state = Closed
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: RecvConfigReq(117) state = Req-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 150.101.32.44
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: SendConfigAck(117) state = Req-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 150.101.32.44
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: RecvConfigRej(4) state = Ack-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: SendConfigReq(5) state = Ack-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: deflink: IPV6CP protocol reject closes IPV6CP !
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: RecvConfigNak(5) state = Ack-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 114.198.37.186
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] changing address: 0.0.0.0  --> 114.198.37.186
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: SendConfigReq(6) state = Ack-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 114.198.37.186
Sep 17 14:06:25 xyz ppp[10470]: tun0: Phase: deflink: IPV6CP protocol reject closes IPV6CP !
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: RecvConfigAck(6) state = Ack-Sent
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP:  IPADDR[6] 114.198.37.186
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: State change Ack-Sent --> Opened
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: deflink: LayerUp.
Sep 17 14:06:25 xyz ppp[10470]: tun0: IPCP: myaddr 114.198.37.186 hisaddr = 150.101.32.44
Sep 17 14:06:25 xyz ppp[10470]: tun0: Command: iinet: !bg sh /etc/ppp/freedns.sh
Sep 17 14:06:25 xyz ppp[10470]: tun0: Warning: 0.0.0.0: Change route failed: errno: No such process
Sep 17 14:06:28 xyz ppp[10470]: tun0: Phase: deflink: IPV6CP protocol reject closes IPV6CP !
Sep 17 14:06:37 xyz last message repeated 3 times

EOT

More information about the freebsd-questions mailing list