Unable to login with PPPoE (2.10-RELEASE x86)

Georg Bege therion at ninth-art.de
Thu Jul 7 10:34:34 PDT 2011


Hi

I just tried your MTU/MRU suggestion, but I doubt it has anything to do
with that.
I commented "set mru" out, also adjusted a lil' bit on the config.
However you'll see that within my ppp.log there's a warning that mru
setting will be adjusted to 1492 as well.

-----------------------snip-----------------------------
root at aquina ~ > ping -p 1500 84.63.152.118
PATTERN: 0x1500
PING 84.63.152.118 (84.63.152.118): 56 data bytes
64 bytes from 84.63.152.118: icmp_seq=0 ttl=64 time=0.099 ms
64 bytes from 84.63.152.118: icmp_seq=1 ttl=64 time=0.085 ms
64 bytes from 84.63.152.118: icmp_seq=2 ttl=64 time=0.084 ms
64 bytes from 84.63.152.118: icmp_seq=3 ttl=64 time=0.082 ms
64 bytes from 84.63.152.118: icmp_seq=4 ttl=64 time=0.084 ms
64 bytes from 84.63.152.118: icmp_seq=5 ttl=64 time=0.089 ms
64 bytes from 84.63.152.118: icmp_seq=6 ttl=64 time=0.085 ms
^C
--- 84.63.152.118 ping statistics ---
7 packets transmitted, 7 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 0.082/0.087/0.099/0.005 ms
-----------------------snip-----------------------------

-----------------------snip-----------------------------
root at aquina ~ > ping -p 1500 84.63.128.1 
PATTERN: 0x1500
PING 84.63.128.1 (84.63.128.1): 56 data bytes
^C
--- 84.63.128.1 ping statistics ---
34 packets transmitted, 0 packets received, 100.0% packet loss
-----------------------snip-----------------------------

-----------------------snip-----------------------------
root at aquina ~ > ping -p 1492 84.63.152.118 
PATTERN: 0x1492
PING 84.63.152.118 (84.63.152.118): 56 data bytes
64 bytes from 84.63.152.118: icmp_seq=0 ttl=64 time=0.101 ms
64 bytes from 84.63.152.118: icmp_seq=1 ttl=64 time=0.086 ms
64 bytes from 84.63.152.118: icmp_seq=2 ttl=64 time=0.081 ms
64 bytes from 84.63.152.118: icmp_seq=3 ttl=64 time=0.085 ms
64 bytes from 84.63.152.118: icmp_seq=4 ttl=64 time=0.086 ms
64 bytes from 84.63.152.118: icmp_seq=5 ttl=64 time=0.083 ms
64 bytes from 84.63.152.118: icmp_seq=6 ttl=64 time=0.083 ms
^C
--- 84.63.152.118 ping statistics ---
7 packets transmitted, 7 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 0.081/0.086/0.101/0.006 ms
-----------------------snip-----------------------------

-----------------------snip-----------------------------
root at aquina ~ > ping -D -p 1492 84.63.128.1 
PATTERN: 0x1492
PING 84.63.128.1 (84.63.128.1): 56 data bytes
^C
--- 84.63.128.1 ping statistics ---
35 packets transmitted, 0 packets received, 100.0% packet loss

-----------------------snip-----------------------------

As you can see, no change - whether options I use, it wont matter much.
Im also going to paste my updated ppp.conf again:

-----------------------snip-----------------------------
# ppp.conf (PPPoE)
default:
        set log Phase Chat LCP IPCP CCP tun command
        enable lqr
        disable dns
        disable ipv6cp
        set mtu 1492
arcor:
        set speed sync
        set device PPPoE:re0
        set authname *somebody*
        set authkey *secret*
        set ifaddr 10.0.0.1/0 10.0.0.2/0
-----------------------snip-----------------------------

On behalf of someone within #dragonflybsd @ efnet I moved the routing
rules to ppp.linkup (he had it that way), I just wanted to test it.:

-----------------------snip-----------------------------
root at aquina /etc/ppp > cat ppp.linkup 
MYADDR:
 delete default
 add default HISADDR
-----------------------snip-----------------------------

Im going to attach my ppp.log again (renamed ppp_2.log).

You'll notice a specific line:
> Jul  7 19:16:24 aquina ppp[337]: tun0: Warning: 84.63.128.1: Change
route failed: errno: No such process <

I think that this is about the problem, but Im pretty unsure how to
interpret that.

cheers
Georg

Am Donnerstag, den 07.07.2011, 10:05 +0000 schrieb Chris Turner:
> 
> On Thu, Jul 07, 2011 at 10:50:46AM +0200, Georg Bege wrote:
> > The funny thing is, addresses do get resolved (if I dont have any
> > default) I dont get anything (no dns/resolving).
> > But ping doesnt get through nor any kind of connection.
> 
> Do I have this correct:
> 
>  - without the route assignment, dns lookups are working
>  - with the route assignment, dns lookups are not working?
> 
> I didn't actually see the address assignment in your ifconfig
> output - was the ppp0 device output truncated or ?
> 
> If I am correct, it sounds like perhaps some data is flowing but
> not all - which could indicate an MTU mismatch  
> 
> Though I don't have direct pppoe experience on FreeBSD/DragonFly -
> I did once use the OpenBSD implementation (which uses a userspace daemon
> rather than the netgraph device) -
> 
> I had some issues with the mtu matchup which caused some issues -
> 
> My archived configuration had :
> 
>  set mtu max 1492
>  # set mru max 1492
> 
> so you might try commenting out the mru portion?
> 
> What does the ping of e.g. the remote gateway, show exactly?
> host unreachable or something else?
> 
> Its been a while since I debugged an MTU mismatch but iirc 
> if you can ping the gateway but not something else (like say your upstream
>  dns server ) and the routes look ok (and tcpdump is showing the packets 
> flowing out )
> 
> you can set some combination of ping -p and -D to detect the mismatch
> 
> again, IIRC, I think e.g.:
> 
> ping -p 1500 <routed IP? gateway IP?>
> ping -p 1492 <routed IP? gateway IP?>
> -> works
> ping -D -p 1492 <routed IP? gateway IP?> 
> -> works
> ping -D -p 1500 <routed IP? gateway IP?> 
> -> fails
> 
> or something like this - I'd verify what I'm saying with some searching :D
> 
> or maybe someone can chime in?
> 
> that wouldn't explain why the config is working on one but not the other,
> though I do recall that some of the default settings might be different
> w/r/t freebsd, etc. in ppp from previous adventures with PPP (using GSM devices)
> 
> good luck!
> 
> Cheers,
> 
> - Chris
> 
> 
> 
> 
> 
> 

-- 
Georg Bege <therion at ninth-art.de>


!DSPAM:4e15ed39844722088513084!
Jul  7 04:24:04 aquina ppp[337]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "duibr1")
Jul  7 04:24:04 aquina ppp[337]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Jul  7 04:24:04 aquina ppp[337]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Jul  7 04:24:04 aquina ppp[337]: tun0: Phase: deflink: carrier -> login
Jul  7 04:24:04 aquina ppp[337]: tun0: Phase: deflink: login -> lcp
Jul  7 04:24:04 aquina ppp[337]: tun0: LCP: FSM: Using "deflink" as a transport
Jul  7 04:24:04 aquina ppp[337]: tun0: LCP: deflink: State change Initial --> Closed
Jul  7 04:24:04 aquina ppp[337]: tun0: LCP: deflink: State change Closed --> Stopped
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: LayerStart
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  ACFCOMP[2]
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  PROTOCOMP[2]
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  ACCMAP[6] 0x00000000
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x526c492e
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  QUALPROTO[8] proto c025, interval 30000ms
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: State change Stopped --> Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: RecvConfigReq(4) state = Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x5c512db9
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: SendConfigAck(4) state = Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x5c512db9
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: RecvConfigRej(1) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  ACFCOMP[2]
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  PROTOCOMP[2]
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  ACCMAP[6] 0x00000000
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  QUALPROTO[8] proto c025, interval 30000ms
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: SendConfigReq(2) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x526c492e
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: RecvConfigAck(2) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x526c492e
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: LayerUp
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(0) state = Opened
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: bundle: Authenticate
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: Chap Input: CHALLENGE (16 bytes from duibr1)
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: Chap Output: RESPONSE (ar117311828)
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(0) state = Opened
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: Chap Input: SUCCESS (access accepted : ar117311828)
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: FSM: Using "deflink" as a transport
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: deflink: State change Initial --> Closed
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: deflink: LayerStart.
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: MPPE: Not usable without CHAP81
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP:  DEFLATE[4] win 15
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP:  PRED1[2] 
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: deflink: State change Closed --> Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: deflink: lcp -> open
Jul  7 04:24:05 aquina ppp[337]: tun0: Phase: bundle: Network
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: FSM: Using "deflink" as a transport
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: State change Initial --> Closed
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: LayerStart.
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 10.0.0.1
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigReq(92) state = Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.62.128.1
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: SendConfigAck(92) state = Req-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.62.128.1
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: RecvProtocolRej(5) state = Opened
Jul  7 04:24:05 aquina ppp[337]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Jul  7 04:24:05 aquina ppp[337]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigRej(1) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: SendConfigReq(2) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 10.0.0.1
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigNak(2) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.62.131.143
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] changing address: 10.0.0.1  --> 84.62.131.143
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: SendConfigReq(3) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.62.131.143
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigAck(3) state = Ack-Sent
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.62.131.143
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: State change Ack-Sent --> Opened
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: deflink: LayerUp.
Jul  7 04:24:05 aquina ppp[337]: tun0: IPCP: myaddr 84.62.131.143 hisaddr = 84.62.128.1
Jul  7 04:24:05 aquina ppp[337]: tun0: Warning: 84.62.128.1: Change route failed: errno: No such process
Jul  7 04:24:35 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(1) state = Opened
Jul  7 04:24:35 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(1) state = Opened
Jul  7 04:24:51 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(0) state = Opened
Jul  7 04:24:51 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(0) state = Opened
Jul  7 04:25:06 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(2) state = Opened
Jul  7 04:25:06 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(2) state = Opened
Jul  7 04:25:36 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(3) state = Opened
Jul  7 04:25:36 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(3) state = Opened
Jul  7 04:26:06 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(4) state = Opened
Jul  7 04:26:06 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(4) state = Opened
Jul  7 04:26:37 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(5) state = Opened
Jul  7 04:26:37 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(5) state = Opened
Jul  7 04:26:56 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(1) state = Opened
Jul  7 04:26:56 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(1) state = Opened
Jul  7 04:27:07 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(6) state = Opened
Jul  7 04:27:07 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(6) state = Opened
Jul  7 04:27:37 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(7) state = Opened
Jul  7 04:27:37 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(7) state = Opened
Jul  7 10:45:31 aquina ppp[338]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "duibr1")
Jul  7 10:45:32 aquina ppp[338]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Jul  7 10:45:32 aquina ppp[338]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Jul  7 10:45:32 aquina ppp[338]: tun0: Phase: deflink: carrier -> login
Jul  7 10:45:32 aquina ppp[338]: tun0: Phase: deflink: login -> lcp
Jul  7 10:45:32 aquina ppp[338]: tun0: LCP: FSM: Using "deflink" as a transport
Jul  7 10:45:32 aquina ppp[338]: tun0: LCP: deflink: State change Initial --> Closed
Jul  7 10:45:32 aquina ppp[338]: tun0: LCP: deflink: State change Closed --> Stopped
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: LayerStart
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  ACFCOMP[2]
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  PROTOCOMP[2]
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  ACCMAP[6] 0x00000000
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MRU[4] 1492
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MAGICNUM[6] 0x2d50bde5
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  QUALPROTO[8] proto c025, interval 30000ms
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: State change Stopped --> Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: RecvConfigReq(150) state = Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MRU[4] 1492
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MAGICNUM[6] 0x30c796e1
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: SendConfigAck(150) state = Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MRU[4] 1492
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MAGICNUM[6] 0x30c796e1
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: RecvConfigRej(1) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  ACFCOMP[2]
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  PROTOCOMP[2]
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  ACCMAP[6] 0x00000000
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  QUALPROTO[8] proto c025, interval 30000ms
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: SendConfigReq(2) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MRU[4] 1492
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MAGICNUM[6] 0x2d50bde5
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: RecvConfigAck(2) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MRU[4] 1492
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP:  MAGICNUM[6] 0x2d50bde5
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: LayerUp
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: SendEchoRequest(0) state = Opened
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: bundle: Authenticate
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: Chap Input: CHALLENGE (16 bytes from duibr1)
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: Chap Output: RESPONSE (ar117311828)
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: RecvEchoReply(0) state = Opened
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: Chap Input: SUCCESS (access accepted : ar117311828)
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: FSM: Using "deflink" as a transport
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: deflink: State change Initial --> Closed
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: deflink: LayerStart.
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: MPPE: Not usable without CHAP81
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP:  DEFLATE[4] win 15
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP:  PRED1[2] 
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: deflink: State change Closed --> Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: deflink: lcp -> open
Jul  7 10:45:33 aquina ppp[338]: tun0: Phase: bundle: Network
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: FSM: Using "deflink" as a transport
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: State change Initial --> Closed
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: LayerStart.
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 10.0.0.1
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: RecvConfigReq(73) state = Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 84.63.128.1
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: SendConfigAck(73) state = Req-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 84.63.128.1
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: RecvProtocolRej(151) state = Opened
Jul  7 10:45:33 aquina ppp[338]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Jul  7 10:45:33 aquina ppp[338]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: RecvConfigRej(1) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: SendConfigReq(2) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 10.0.0.1
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: RecvConfigNak(2) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 84.63.128.140
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] changing address: 10.0.0.1  --> 84.63.128.140
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: SendConfigReq(3) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 84.63.128.140
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: RecvConfigAck(3) state = Ack-Sent
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP:  IPADDR[6] 84.63.128.140
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: State change Ack-Sent --> Opened
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: deflink: LayerUp.
Jul  7 10:45:33 aquina ppp[338]: tun0: IPCP: myaddr 84.63.128.140 hisaddr = 84.63.128.1
Jul  7 10:45:33 aquina ppp[338]: tun0: Warning: 84.63.128.1: Change route failed: errno: No such process
Jul  7 10:46:04 aquina ppp[338]: tun0: LCP: deflink: SendEchoRequest(1) state = Opened
Jul  7 10:46:04 aquina ppp[338]: tun0: LCP: deflink: RecvEchoReply(1) state = Opened
Jul  7 10:46:34 aquina ppp[338]: tun0: LCP: deflink: SendEchoRequest(2) state = Opened
Jul  7 10:46:34 aquina ppp[338]: tun0: LCP: deflink: RecvEchoReply(2) state = Opened
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:09:42 2011
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:09:47 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:10:17 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:10:17 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:10:17 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:10:17 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:10:17 2011
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:10:22 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:10:52 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:10:52 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:10:52 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:10:52 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:10:52 2011
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:10:57 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:11:27 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:11:27 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:11:27 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:11:27 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:11:27 2011
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:11:32 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:12:02 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:12:02 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:12:02 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:12:02 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:12:02 2011
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:12:07 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:12:37 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:12:37 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:12:37 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:12:37 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:12:37 2011
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:12:42 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:13:12 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:13:12 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:13:12 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:13:12 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:13:12 2011
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:13:17 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:13:47 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:13:47 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:13:47 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:13:47 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:13:47 2011
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:13:52 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:14:22 aquina ppp[338]: tun0: Chat: deflink: Redial timer expired.
Jul  7 19:14:22 aquina ppp[338]: tun0: Phase: deflink: Connected!
Jul  7 19:14:22 aquina ppp[338]: tun0: Phase: deflink: opening -> dial
Jul  7 19:14:22 aquina ppp[338]: tun0: Phase: deflink: dial -> carrier
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase: deflink: Disconnected!
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase: deflink: carrier -> hangup
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase:  total 0 bytes/sec, peak 0 bytes/sec on Thu Jul  7 19:14:22 2011
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase: deflink: hangup -> opening
Jul  7 19:14:27 aquina ppp[338]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul  7 19:16:22 aquina ppp[337]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "duibr1")
Jul  7 19:16:23 aquina ppp[337]: tun0: Phase: Received NGM_PPPOE_SESSIONID
Jul  7 19:16:23 aquina ppp[337]: tun0: Phase: Received NGM_PPPOE_SUCCESS
Jul  7 19:16:23 aquina ppp[337]: tun0: Phase: deflink: carrier -> login
Jul  7 19:16:23 aquina ppp[337]: tun0: Phase: deflink: login -> lcp
Jul  7 19:16:23 aquina ppp[337]: tun0: LCP: FSM: Using "deflink" as a transport
Jul  7 19:16:23 aquina ppp[337]: tun0: LCP: deflink: State change Initial --> Closed
Jul  7 19:16:23 aquina ppp[337]: tun0: LCP: deflink: State change Closed --> Stopped
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: LayerStart
Jul  7 19:16:24 aquina ppp[337]: tun0: Warning: deflink: Reducing configured MRU from 1500 to 1492
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x1c70d79a
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  QUALPROTO[8] proto c025, interval 30000ms
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: State change Stopped --> Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: RecvConfigReq(5) state = Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x3df4f45f
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: SendConfigAck(5) state = Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x3df4f45f
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: RecvConfigRej(1) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  QUALPROTO[8] proto c025, interval 30000ms
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: SendConfigReq(2) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x1c70d79a
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: RecvConfigAck(2) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MRU[4] 1492
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP:  MAGICNUM[6] 0x1c70d79a
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: LayerUp
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(0) state = Opened
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: bundle: Authenticate
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: Chap Input: CHALLENGE (16 bytes from duibr1)
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: Chap Output: RESPONSE (ar117311828)
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(0) state = Opened
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: Chap Input: SUCCESS (access accepted : ar117311828)
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: FSM: Using "deflink" as a transport
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: deflink: State change Initial --> Closed
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: deflink: LayerStart.
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: MPPE: Not usable without CHAP81
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP:  DEFLATE[4] win 15
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP:  PRED1[2] 
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: deflink: State change Closed --> Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: deflink: lcp -> open
Jul  7 19:16:24 aquina ppp[337]: tun0: Phase: bundle: Network
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: FSM: Using "deflink" as a transport
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: State change Initial --> Closed
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: LayerStart.
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 10.0.0.1
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigReq(59) state = Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.63.128.1
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: SendConfigAck(59) state = Req-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.63.128.1
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: RecvProtocolRej(6) state = Opened
Jul  7 19:16:24 aquina ppp[337]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Jul  7 19:16:24 aquina ppp[337]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigRej(1) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: SendConfigReq(2) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 10.0.0.1
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigNak(2) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.63.152.118
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] changing address: 10.0.0.1  --> 84.63.152.118
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: SendConfigReq(3) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.63.152.118
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: RecvConfigAck(3) state = Ack-Sent
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP:  IPADDR[6] 84.63.152.118
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: State change Ack-Sent --> Opened
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: deflink: LayerUp.
Jul  7 19:16:24 aquina ppp[337]: tun0: IPCP: myaddr 84.63.152.118 hisaddr = 84.63.128.1
Jul  7 19:16:24 aquina ppp[337]: tun0: Command: MYADDR: delete default
Jul  7 19:16:24 aquina ppp[337]: tun0: Warning: Del route failed: 0.0.0.0: Non-existent
Jul  7 19:16:24 aquina ppp[337]: tun0: Command: MYADDR: add default HISADDR
Jul  7 19:16:24 aquina ppp[337]: tun0: Warning: 84.63.128.1: Change route failed: errno: No such process
Jul  7 19:16:54 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(1) state = Opened
Jul  7 19:16:54 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(1) state = Opened
Jul  7 19:17:10 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(0) state = Opened
Jul  7 19:17:10 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(0) state = Opened
Jul  7 19:17:25 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(2) state = Opened
Jul  7 19:17:25 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(2) state = Opened
Jul  7 19:17:44 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(1) state = Opened
Jul  7 19:17:44 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(1) state = Opened
Jul  7 19:17:56 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(3) state = Opened
Jul  7 19:17:56 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(3) state = Opened
Jul  7 19:18:19 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(2) state = Opened
Jul  7 19:18:19 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(2) state = Opened
Jul  7 19:18:26 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(4) state = Opened
Jul  7 19:18:26 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(4) state = Opened
Jul  7 19:18:57 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(5) state = Opened
Jul  7 19:18:57 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(5) state = Opened
Jul  7 19:19:27 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(6) state = Opened
Jul  7 19:19:27 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(6) state = Opened
Jul  7 19:19:55 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(3) state = Opened
Jul  7 19:19:55 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(3) state = Opened
Jul  7 19:19:58 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(7) state = Opened
Jul  7 19:19:58 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(7) state = Opened
Jul  7 19:20:29 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(8) state = Opened
Jul  7 19:20:29 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(8) state = Opened
Jul  7 19:20:59 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(9) state = Opened
Jul  7 19:20:59 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(9) state = Opened
Jul  7 19:21:17 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(4) state = Opened
Jul  7 19:21:17 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(4) state = Opened
Jul  7 19:21:30 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(10) state = Opened
Jul  7 19:21:30 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(10) state = Opened
Jul  7 19:21:52 aquina ppp[337]: tun0: LCP: deflink: RecvEchoRequest(5) state = Opened
Jul  7 19:21:52 aquina ppp[337]: tun0: LCP: deflink: SendEchoReply(5) state = Opened
Jul  7 19:22:00 aquina ppp[337]: tun0: LCP: deflink: SendEchoRequest(11) state = Opened
Jul  7 19:22:00 aquina ppp[337]: tun0: LCP: deflink: RecvEchoReply(11) state = Opened




More information about the Users mailing list