Aug 9 15:16:10 sol ppp[9258]: Phase: Using interface: tun0
Aug 9 15:16:10 sol ppp[9258]: Phase: deflink: Created in closed state
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: default: set redial 15 0
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: default: set reconnect 15 10000
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set device !/usr/sbin/pppoe -i de3
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set mtu max 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set mru max 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set speed sync
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: disable ipv6cp
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: disable acfcomp protocomp
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: deny acfcomp
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set login
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set authname user@versatel
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set authkey ********
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set dial
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set timeout 0
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: enable mssfixup
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: accept lqr
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set lqrperiod 5
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: add default HISADDR
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: pppoe: set cd 5
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: PPP Started (foreground mode).
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: bundle: Establish
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: closed -> opening
Aug 9 15:16:10 sol ppp[9258]: tun0: Warning: Carrier settings ignored
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: Connected!
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: opening -> dial
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: dial -> carrier
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: carrier -> login
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: login -> lcp
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: FSM: Using "deflink" as a transport
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: State change Initial --> Closed
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: State change Closed --> Stopped
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: RecvConfigReq(242) state = Stopped
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MRU[4] 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05)
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MAGICNUM[6] 0x0613ec0e
Aug 9 15:16:10 sol ppp[9258]: tun0: Warning: deflink: Reducing configured MRU from 1500 to 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MRU[4] 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MAGICNUM[6] 0x6fe5c0c3
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: SendConfigAck(242) state = Stopped
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MRU[4] 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05)
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MAGICNUM[6] 0x0613ec0e
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: LayerStart
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: State change Stopped --> Ack-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MRU[4] 1492
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: MAGICNUM[6] 0x6fe5c0c3
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: LayerUp
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: bundle: Authenticate
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: Chap Input: CHALLENGE (17 bytes from dor002ibr003)
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: Chap Output: RESPONSE (mg-kh@versatel)
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: Chap Input: SUCCESS
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: FSM: Using "deflink" as a transport
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: deflink: State change Initial --> Closed
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: deflink: LayerStart.
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: MPPE: Not usable without CHAP81
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: DEFLATE[4] win 15
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: PRED1[2]
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: deflink: State change Closed --> Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: deflink: lcp -> open
Aug 9 15:16:10 sol ppp[9258]: tun0: Phase: bundle: Network
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: FSM: Using "deflink" as a transport
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: State change Initial --> Closed
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: LayerStart.
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 192.168.0.1
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: RecvProtocolRej(243) state = Opened
Aug 9 15:16:10 sol ppp[9258]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Aug 9 15:16:10 sol ppp[9258]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: RecvConfigRej(1) state = Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: SendConfigReq(2) state = Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 192.168.0.1
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: RecvConfigNak(2) state = Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 83.135.160.182
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] changing address: 192.168.0.1 --> 83.135.160.182
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: SendConfigReq(3) state = Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 83.135.160.182
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: RecvConfigAck(3) state = Req-Sent
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 83.135.160.182
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Rcvd
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: RecvConfigReq(229) state = Ack-Rcvd
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 62.214.64.76
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: SendConfigAck(229) state = Ack-Rcvd
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: IPADDR[6] 62.214.64.76
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: State change Ack-Rcvd --> Opened
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: deflink: LayerUp.
Aug 9 15:16:10 sol ppp[9258]: tun0: IPCP: myaddr 83.135.160.182 hisaddr = 62.214.64.76
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /sbin/pfctl -f /etc/pf.conf.router
Aug 9 15:16:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /bin/cp /var/named/standard/root.hint.real /var/named/standard/root.hint
Aug 9 15:16:11 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /usr/sbin/rndc reload
Aug 9 15:16:11 sol named[3171]: loading configuration from '/etc/named.conf'
Aug 9 15:16:11 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /usr/local/sbin/ddclient
Aug 9 15:16:12 sol ppp[9258]: tun0: Warning: 0.0.0.0/0: Change route failed: errno: No such process
Aug 9 15:16:12 sol ppp[9258]: tun0: Warning: ff02::%tun0/32: Change route failed: errno: Undefined error: 0
Aug 9 15:16:40 sol ppp[9258]: tun0: LCP: deflink: RecvEchoRequest(244) state = Opened
Aug 9 15:16:40 sol ppp[9258]: tun0: LCP: deflink: SendEchoReply(244) state = Opened
Aug 9 15:17:40 sol ppp[9258]: tun0: LCP: deflink: RecvEchoRequest(245) state = Opened
Aug 9 15:17:40 sol ppp[9258]: tun0: LCP: deflink: SendEchoReply(245) state = Opened
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: Caught signal 2, abort connection(s)
Aug 9 15:18:10 sol ppp[9258]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 9 15:18:10 sol ppp[9258]: tun0: CCP: deflink: State change Closed --> Initial
Aug 9 15:18:10 sol ppp[9258]: tun0: LCP: deflink: LayerDown
Aug 9 15:18:10 sol ppp[9258]: tun0: LCP: deflink: State change Opened --> Starting
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: open -> lcp
Aug 9 15:18:10 sol ppp[9258]: tun0: Warning: 0.0.0.0/0: Change route failed: errno: No such process
Aug 9 15:18:10 sol ppp[9258]: tun0: Warning: ff02::%tun0/32: Change route failed: errno: Undefined error: 0
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: deflink: LayerDown: 83.135.160.182
Aug 9 15:18:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c kill `cat /var/run/ddclient.pid`
Aug 9 15:18:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /bin/cp /var/named/standard/root.hint.fake /var/named/standard/root.hint
Aug 9 15:18:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /usr/sbin/rndc reconfig
Aug 9 15:18:10 sol named[3171]: loading configuration from '/etc/named.conf'
Aug 9 15:18:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /sbin/pfctl -f /etc/pf.conf
Aug 9 15:18:10 sol ppp[9258]: tun0: Command: MYADDR: ! sh -c /sbin/route delete default
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: deflink: State change Opened --> Starting
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: deflink: LayerFinish.
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: Connect time: 120 secs: 2876 octets in, 0 octets out
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: 37 packets in, 0 packets out
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: total 23 bytes/sec, peak 0 bytes/sec on Tue Aug 9 15:16:10 2005
Aug 9 15:18:10 sol ppp[9258]: tun0: IPCP: deflink: State change Starting --> Initial
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: bundle: Terminate
Aug 9 15:18:10 sol ppp[9258]: tun0: LCP: deflink: LayerFinish
Aug 9 15:18:10 sol ppp[9258]: tun0: LCP: deflink: State change Starting --> Initial
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: Disconnected!
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: lcp -> logout
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: Disconnected!
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: logout -> hangup
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: Connect time: 120 secs: 3091 octets in, 180 octets out
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: 48 packets in, 10 packets out
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: total 27 bytes/sec, peak 0 bytes/sec on Tue Aug 9 15:16:10 2005
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: HUPing 12433
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: deflink: hangup -> closed
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: bundle: Dead
Aug 9 15:18:10 sol ppp[9258]: tun0: Phase: PPP Terminated (normal).