> I can't see how this can happen without any previous log lines saying
> that a chap packet has been received.
>
> If this is repeatable, can you try doing a ``show timer'' right after
> the SUCCESS response has been sent ? If the radius timer wasn't
> cleared properly this might result, but I can't see how that could
> happen...
Hmm... Repeatable every time on the machine in question. (Time passes
while I configure the a similar on a completely different set of
boxes.) Yup - repeatable on another machine as well.
Two machines - core-1 and twikki. Twikki is receiving the call.
I don't have the muscle trigger upgrade in my finger yet to be able to hit
return right when the SUCCESS rsp comes through (goes by way too fast),
but I did turn on timer debugging for these logs -- maybe it'll help. If
there's some way to trigger a "show timer" in the source at a certain
point, I'd be happy to try that...
Thanks - Mike
Feb 3 01:37:39 twikki ppp[77098]: Phase: Using interface: tun3
Feb 3 01:37:39 twikki ppp[77098]: Phase: deflink: Created in closed sta
te
Feb 3 01:37:39 twikki ppp[77098]: tun3: Phase: PPP Started (direct mode
).
Feb 3 01:37:39 twikki ppp[77098]: tun3: Phase: bundle: Establish
Feb 3 01:37:39 twikki ppp[77098]: tun3: Phase: deflink: closed -> openi
ng
Feb 3 01:37:40 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting p
hysical throughput timer[0x80ad068]
Feb 3 01:37:40 twikki ppp[77098]: tun3: Phase: deflink: Connected!
Feb 3 01:37:40 twikki ppp[77098]: tun3: Phase: deflink: opening -> carr
ier
Feb 3 01:37:40 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting t
ty CD timer[0x80b1340] before physical throughput timer[0x80ad068], delt
a = 10
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: Select returns -1
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: ---- Begin of Timer Serv
ice List---
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: tty CD timer[0x80b1340]:
freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: physical throughput time
r[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: ---- End of Timer Servic
e List ---
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting p
hysical throughput timer[0x80ad068]
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting t
ty CD timer[0x80b1340] before physical throughput timer[0x80ad068], delt
a = 10
Feb 3 01:37:41 twikki ppp[77098]: tun3: Phase: deflink: /dev/cuac04: CD
detected
Feb 3 01:37:41 twikki ppp[77098]: tun3: Phase: deflink: carrier -> lcp
Feb 3 01:37:41 twikki ppp[77098]: tun3: LCP: FSM: Using "deflink" as a
transport
Feb 3 01:37:41 twikki ppp[77098]: tun3: LCP: deflink: State change Init
ial --> Closed
Feb 3 01:37:41 twikki ppp[77098]: tun3: LCP: deflink: State change Clos
ed --> Stopped
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting L
CP openmode timer[0x80ad15c] before tty CD timer[0x80b1340], delta = 10
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:41 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns -1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: ---- Begin of Timer Serv
ice List---
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: LCP openmode timer[0x80a
d15c]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: tty CD timer[0x80b1340]:
freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: physical throughput time
r[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: ---- End of Timer Servic
e List ---
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting p
hysical throughput timer[0x80ad068]
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting t
ty CD timer[0x80b1340] before physical throughput timer[0x80ad068], delt
a = 10
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: LayerStart
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: SendConfigReq(1)
state = Stopped
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: ACFCOMP[2]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: PROTOCOMP[2]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: ACCMAP[6] 0x00000000
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: MRU[4] 1500
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: MAGICNUM[6] 0x9b5bae81
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: AUTHPROTO[5] 0xc223 (CHAP
0x05)
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting L
CP restart timer[0x80ad13c]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: State change Stop
ped --> Req-Sent
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: RecvConfigAck(1)
state = Req-Sent
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: State change Req-
Sent --> Ack-Rcvd
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: RecvConfigReq(1)
state = Ack-Rcvd
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: ACFCOMP[2]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: PROTOCOMP[2]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: ACCMAP[6] 0x00000000
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: MRU[4] 1500
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: MAGICNUM[6] 0x94cc8313
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: SendConfigAck(1)
state = Ack-Rcvd
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: ACFCOMP[2]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: PROTOCOMP[2]
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: ACCMAP[6] 0x00000000
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: MRU[4] 1500
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: MAGICNUM[6] 0x94cc8313
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: State change Ack-
Rcvd --> Opened
Feb 3 01:37:42 twikki ppp[77098]: tun3: LCP: deflink: LayerUp
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting h
dlc timer[0x80afd84]
Feb 3 01:37:42 twikki ppp[77098]: tun3: Phase: bundle: Authenticate
Feb 3 01:37:42 twikki ppp[77098]: tun3: Phase: deflink: his = none, min
e = CHAP 0x05
Feb 3 01:37:42 twikki ppp[77098]: tun3: Phase: Chap Output: CHALLENGE
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting a
uth timer[0x80ac034] before hdlc timer[0x80afd84], delta = 29
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:42 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns -1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: ---- Begin of Timer Serv
ice List---
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tty CD timer[0x80b1340]:
freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: physical throughput time
r[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: auth timer[0x80ac034]: f
req = 3.00s, next = 2.90s, state = running
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: hdlc timer[0x80afd84]: f
req = 60.00s, next = 59.90s, state = running
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: ---- End of Timer Servic
e List ---
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting p
hysical throughput timer[0x80ad068] before auth timer[0x80ac034], delta
= 10
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting t
ty CD timer[0x80b1340] before physical throughput timer[0x80ad068], delt
a = 10
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: Chap Input: RESPONSE (16
bytes from theo)
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: Radius: Request sent
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting r
adius timer[0x80a43b4] before hdlc timer[0x80afd84], delta = 21
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Radius: fdset(r) 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: Radius: ACCEPT received
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: IP 10.96.9.98
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: Netmask 255.255.
255.255
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: MTU 1500
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: VJ enabled
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: Chap Output: SUCCESS
Feb 3 01:37:43 twikki ppp[77098]: tun3: Warning: 10.96.9.98: Cannot det
ermine ethernet address for proxy ARP
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting C
CP restart timer[0x80adc14] before hdlc timer[0x80afd84], delta = 21
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: deflink: lcp -> open
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: bundle: Network
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: FSM: Using "deflink" as a
transport
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: State change Ini
tial --> Closed
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: LayerStart.
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting I
PCP throughput timer[0x80a1d98] before CCP restart timer[0x80adc14], del
ta = 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: SendConfigReq(1)
state = Closed
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: IPADDR[6] 216.233.245.1
02
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: COMPPROTO[6] 16 VJ slot
s with slot compression
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting I
PCP restart timer[0x8090ae8] before CCP restart timer[0x80adc14], delta
= 20
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: State change Clo
sed --> Req-Sent
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting i
dle timer[0x80a4350]
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 2
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 2
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: RecvConfigReq(1)
state = Req-Sent
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: IPADDR[6] 0.0.0.0
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: COMPPROTO[6] 16 VJ slot
s with slot compression
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: SendConfigNak(1)
state = Req-Sent
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: IPADDR[6] 10.96.9.98
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting C
CP restart timer[0x80adc14] before hdlc timer[0x80afd84], delta = 2
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: RecvConfigAck(1)
state = Req-Sent
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: State change Req
-Sent --> Ack-Rcvd
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: RecvConfigReq(2)
state = Ack-Rcvd
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: IPADDR[6] 10.96.9.98
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: COMPPROTO[6] 16 VJ slot
s with slot compression
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: SendConfigAck(2)
state = Ack-Rcvd
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: IPADDR[6] 10.96.9.98
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: COMPPROTO[6] 16 VJ slot
s with slot compression
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: State change Ack
-Rcvd --> Opened
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: deflink: LayerUp.
Feb 3 01:37:43 twikki ppp[77098]: tun3: IPCP: myaddr 216.233.245.102 hi
saddr = 10.96.9.98
Feb 3 01:37:43 twikki ppp[77098]: tun3: Warning: 10.96.9.98: Cannot det
ermine ethernet address for proxy ARP
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: radius: No RADIUS server
s specified
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: Chap Output: FAILURE
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: deflink: open -> lcp
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: LayerDown
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: SendTerminateReq(
2) state = Opened
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting L
CP restart timer[0x80ad13c] before idle timer[0x80a4350], delta = 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: State change Open
ed --> Closing
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: timer_Start: Inserting i
dle timer[0x80a4350]
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 2
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: RecvTerminateReq(
2) state = Closing
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: SendTerminateAck(
2) state = Closing
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(w) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: tun: fdset(r) 3
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(r) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: deflink: fdset(e) 0
Feb 3 01:37:43 twikki ppp[77098]: tun3: Timer: Select returns 1
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: RecvTerminateAck(
2) state = Closing
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: LayerFinish
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: State change Clos
ing --> Closed
Feb 3 01:37:43 twikki ppp[77098]: tun3: LCP: deflink: State change Clos
ed --> Initial
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: deflink: Disconnected!
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: deflink: Connect time: 3
secs: 354 octets in, 338 octets out
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: deflink: : 22 packets in
, 14 packets out
Feb 3 01:37:43 twikki ppp[77098]: tun3: Phase: total 230 bytes/sec, pe
ak 75 bytes/sec on Sat Feb 3 01:37:43 2001
Feb 3 01:37:34 core-1 ppp[8605]: Phase: Using interface: tun2
Feb 3 01:37:34 core-1 ppp[8605]: Phase: deflink: Created in closed stat
e
Feb 3 01:37:34 core-1 ppp[8605]: tun2: Phase: PPP Started (interactive
mode).
Feb 3 01:37:34 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Phase: bundle: Establish
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Phase: deflink: closed -> openin
g
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068]
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Phase: deflink: Connected!
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Phase: deflink: opening -> dial
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: Phone: 330-315-6459
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: deflink: Dial attempt 1 of
1
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: Send: ATE1Q0^M
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: Expect(5): OK
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ch
at timeout timer[0x80aaab4]
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: Received: ATE1Q0^M^M
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: Received: OK^M
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ch
at pause timer[0x80aaa94]
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Chat: Send: ATDT330-315-6459^M
Feb 3 01:37:35 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: chat pause timer[0x80aaa9
4]: freq = 2.00s, next = 1.00s, state = running
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat pause timer[0x80aaa94], d
elta = 10
Feb 3 01:37:36 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: chat pause timer[0x80aaa9
4]: freq = 2.00s, next = 0.00s, state = running
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068]
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Chat: Expect(40): CONNECT
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ch
at timeout timer[0x80aaab4]
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:37 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 39.00s, state = running
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:38 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 38.00s, state = running
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:39 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 37.00s, state = running
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:40 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 36.00s, state = running
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:41 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 35.00s, state = running
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:42 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 34.00s, state = running
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:43 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 33.00s, state = running
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:44 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 32.00s, state = running
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:45 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 31.00s, state = running
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:46 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 30.00s, state = running
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:47 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 29.00s, state = running
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:48 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 28.00s, state = running
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:49 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 27.00s, state = running
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:50 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 26.00s, state = running
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:51 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 25.00s, state = running
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:52 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 24.00s, state = running
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:53 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 23.00s, state = running
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:54 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 22.00s, state = running
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:55 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 21.00s, state = running
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:56 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 20.00s, state = running
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:57 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 19.00s, state = running
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:58 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 18.00s, state = running
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:37:59 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 17.00s, state = running
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:00 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 16.00s, state = running
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:01 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 15.00s, state = running
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:02 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 14.00s, state = running
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:03 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 13.00s, state = running
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:04 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 12.00s, state = running
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:05 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: chat timeout timer[0x80aa
ab4]: freq = 40.00s, next = 11.00s, state = running
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before chat timeout timer[0x80aaab4],
delta = 10
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Chat: Received: ATDT330-315-6459
^M^M
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Chat: Received: CONNECT 28800 V4
2bis^M
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Phase: deflink: dial -> carrier
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting tt
y CD timer[0x80a4140]
Feb 3 01:38:06 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: tty CD timer[0x80a4140]:
freq = 1.00s, next = 0.30s, state = running
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068]
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting tt
y CD timer[0x80a4140]
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Phase: deflink: /dev/cuaR3: CD d
etected
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Phase: deflink: carrier -> login
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Phase: deflink: login -> lcp
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting LC
P openmode timer[0x80ad15c] before tty CD timer[0x80a4140], delta = 3
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:07 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: LCP openmode timer[0x80ad
15c]: freq = 1.00s, next = 0.30s, state = running
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: tty CD timer[0x80a4140]:
freq = 1.00s, next = 0.30s, state = running
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068]
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting tt
y CD timer[0x80a4140]
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting LC
P restart timer[0x80ad13c]
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:08 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: tty CD timer[0x80a4140]:
freq = 1.00s, next = 0.30s, state = running
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: LCP restart timer[0x80ad1
3c]: freq = 3.00s, next = 2.30s, state = running
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before LCP restart timer[0x80ad13c],
delta = 7
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting tt
y CD timer[0x80a4140] before LCP restart timer[0x80ad13c], delta = 3
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:09 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: tty CD timer[0x80a4140]:
freq = 1.00s, next = 0.30s, state = running
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: LCP restart timer[0x80ad1
3c]: freq = 3.00s, next = 1.30s, state = running
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068] before LCP restart timer[0x80ad13c],
delta = 7
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting tt
y CD timer[0x80a4140] before LCP restart timer[0x80ad13c], delta = 3
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:10 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: ---- Begin of Timer Servi
ce List---
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: physical throughput timer
[0x80ad068]: freq = 1.00s, next = 0.00s, state = running
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: tty CD timer[0x80a4140]:
freq = 1.00s, next = 0.30s, state = running
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: LCP restart timer[0x80ad1
3c]: freq = 3.00s, next = 0.30s, state = running
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: ---- End of Timer Service
List ---
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting ph
ysical throughput timer[0x80ad068]
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: Select returns -1
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting LC
P restart timer[0x80ad13c]
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting LC
P restart timer[0x80ad13c]
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting tt
y CD timer[0x80a4140] before LCP restart timer[0x80ad13c], delta = 3
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:11 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting hd
lc timer[0x80afd7c]
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: bundle: Authenticate
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: deflink: his = CHAP 0x05,
mine = none
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: Chap Input: CHALLENGE (16
bytes)
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: Chap Output: RESPONSE (th
eo)
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: Chap Input: SUCCESS (Welc
ome!!)
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting CC
P restart timer[0x80adc14] before hdlc timer[0x80afd7c], delta = 24
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: deflink: lcp -> open
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: bundle: Network
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: FSM: Using "deflink" as a
transport
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: State change Init
ial --> Closed
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: LayerStart.
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting IP
CP throughput timer[0x809f654] before CCP restart timer[0x80adc14], delt
a = 4
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: SendConfigReq(1)
state = Closed
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: IPADDR[6] 0.0.0.0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: COMPPROTO[6] 16 VJ slots
with slot compression
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: timer_Start: Inserting IP
CP restart timer[0x808e3a4] before CCP restart timer[0x80adc14], delta =
20
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: State change Clos
ed --> Req-Sent
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: RecvConfigReq(1)
state = Req-Sent
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: IPADDR[6] 216.233.245.10
2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: COMPPROTO[6] 16 VJ slots
with slot compression
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: SendConfigAck(1)
state = Req-Sent
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: IPADDR[6] 216.233.245.10
2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: COMPPROTO[6] 16 VJ slots
with slot compression
Feb 3 01:38:12 core-1 ppp[8605]: tun2: IPCP: deflink: State change Req-
Sent --> Ack-Sent
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: tun: fdset(r) 3
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: tun: fdset(r) 3
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: tun: fdset(r) 3
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: tun: fdset(r) 3
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(w) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: tun: fdset(r) 3
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(e) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: prompt /dev/tty: fdset(r)
0
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: Chap Input: FAILURE (Inva
lid!!)
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Timer: deflink: fdset(r) 2
Feb 3 01:38:12 core-1 ppp[8605]: tun2: Phase: deflink: hangup -> closed
Feb 3 01:38:21 core-1 ppp[8605]: tun2: Timer: Select returns 1
Feb 3 01:38:21 core-1 ppp[8605]: tun2: Phase: /dev/tty: Client connecti
on closed.
Feb 3 01:38:21 core-1 ppp[8605]: tun2: Phase: PPP Terminated (normal).
To Unsubscribe: send mail to [EMAIL PROTECTED]
with "unsubscribe freebsd-net" in the body of the message
