From owner-freebsd-net Fri Feb 2 22:56:40 2001 Delivered-To: freebsd-net@freebsd.org Received: from jason.argos.org (a1-3b044.neo.rr.com [24.93.181.44]) by hub.freebsd.org (Postfix) with ESMTP id 7DAD337B65D for ; Fri, 2 Feb 2001 22:55:50 -0800 (PST) Received: from localhost (mike@localhost) by jason.argos.org (8.10.1/8.10.1) with ESMTP id f136jZc32290; Sat, 3 Feb 2001 01:45:36 -0500 Date: Sat, 3 Feb 2001 01:45:35 -0500 (EST) From: Mike Nowlin To: Brian Somers Cc: freebsd-net@FreeBSD.ORG Subject: Re: PPP - CHAP failure after CHAP success??? In-Reply-To: <200102022323.f12NNW606872@hak.lan.Awfulhak.org> Message-ID: MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII Sender: owner-freebsd-net@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org > 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 majordomo@FreeBSD.org with "unsubscribe freebsd-net" in the body of the message