Date: Sun, 14 Apr 2002 22:10:31 +0200 From: Ingolf Steinbach <ingolf@steinba.ch> To: freebsd-isdn@freebsd.org Subject: What's happening here? Message-ID: <200204142009.g3EK9wOu008790@jello277.jellocom.de>
next in thread | raw e-mail | index | archive | help
--------------Boundary-00=_JPQKAOXZFAJP4IQ0PPIC
Content-Type: text/plain;
charset="iso-8859-1"
Content-Transfer-Encoding: 8bit
Hi,
in order to investigate the problem which I described on 2002-04-06,
I switched isp0 to debug mode. The attachment shows an extract of
/var/log/messages (I hope that I have x'ed out all of the sensitive
information -- and not too much)...
There is one relatively long connection attempt from 20:37:18 to
20:37:33 (CHD 00014). Some rst_counter goes from 10 down to 0.
After this connection attempt, the router starts this strange
behaviour to dial out and immediately hanging up again.
What is happening here? Does anyone have an explanation? Do
you need more input?
BTW, during the first connection, I got the following messages on
the console:
Apr 14 20:37:19 hermes.steinba.ch /netbsd: i4b-L2 i4b_T200_timeout: unit 0,
RC = 0
Apr 14 20:37:19 hermes.steinba.ch /netbsd: i4b-L2 i4b_T200_timeout: unit 0,
RC = 0
(The router is now running NetBSD/i386 1.5.3, but the os release
seems not to have an influence on that behaviour...)
Regards
Ingolf
--------------Boundary-00=_JPQKAOXZFAJP4IQ0PPIC
Content-Type: text/plain;
charset="iso-8859-1";
name="messages.log"
Content-Transfer-Encoding: 8bit
Content-Description: Extract from /var/log/messages
Content-Disposition: attachment; filename="messages.log"
Apr 14 20:37:18 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:37:18 hermes /netbsd: isp0: phase establish
Apr 14 20:37:18 hermes isdnd[185]: CHD 00014 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:37:18 hermes isdnd[185]: CHD 00014 I4BPPP dialing out from xxx to yyy
Apr 14 20:37:19 hermes /netbsd: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
Apr 14 20:37:19 hermes isdnd[185]: CHD 00014 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:37:19 hermes /netbsd: isp0: Up event
Apr 14 20:37:19 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:37:19 hermes /netbsd: isp0: lcp output <conf-req id=0x17 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:20 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x5 len=30 03-05-c2-23-05-05-06-04-aa-df-f0-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:20 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:37:20 hermes /netbsd: isp0: lcp output <conf-rej id=0x5 len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:20 hermes /netbsd: isp0: lcp input(req-sent): <conf-ack id=0x17 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:20 hermes /netbsd: isp0: lcp input(ack-rcvd): <conf-req id=0x6 len=15 03-05-c2-23-05-05-06-04-aa-df-f0>
Apr 14 20:37:20 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:37:20 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4aadff0 send conf-ack
Apr 14 20:37:20 hermes /netbsd: isp0: lcp output <conf-ack id=0x6 len=15 03-05-c2-23-05-05-06-04-aa-df-f0>
Apr 14 20:37:20 hermes /netbsd: isp0: lcp tlu
Apr 14 20:37:20 hermes /netbsd: isp0: phase authenticate
Apr 14 20:37:20 hermes /netbsd: isp0: chap input <challenge id=0x4 len=29 name=jellocom value-size=16 value= e6-78-3c-a9-8c-14-21-c6-c2-43-b1-9f-ca-6a-ef-a6>
Apr 14 20:37:20 hermes /netbsd: isp0: chap output <response id=0x4 len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:37:19 hermes isdnd[185]: CHD 00014 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:37:21 hermes /netbsd: isp0: chap success
Apr 14 20:37:21 hermes /netbsd: isp0: phase network
Apr 14 20:37:21 hermes /netbsd: isp0: ipcp open(initial)
Apr 14 20:37:21 hermes /netbsd: isp0: ipv6cp open(closed)
Apr 14 20:37:21 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x2d len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:21 hermes /netbsd: isp0: ipcp up(starting)
Apr 14 20:37:21 hermes /netbsd: isp0: ipcp output <conf-req id=0x1e len=10 03-06-00-00-00-00>
Apr 14 20:37:21 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 10
Apr 14 20:37:21 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x2e len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:21 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 10
Apr 14 20:37:21 hermes /netbsd: isp0: ipcp output <conf-req id=0x1f len=10 03-06-00-00-00-00>
Apr 14 20:37:22 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 9
Apr 14 20:37:22 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x2f len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:22 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 9
Apr 14 20:37:22 hermes /netbsd: isp0: ipcp output <conf-req id=0x20 len=10 03-06-00-00-00-00>
Apr 14 20:37:23 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 8
Apr 14 20:37:23 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x30 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:23 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 8
Apr 14 20:37:23 hermes /netbsd: isp0: ipcp output <conf-req id=0x21 len=10 03-06-00-00-00-00>
Apr 14 20:37:24 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 7
Apr 14 20:37:24 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x31 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:24 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 7
Apr 14 20:37:24 hermes /netbsd: isp0: ipcp output <conf-req id=0x22 len=10 03-06-00-00-00-00>
Apr 14 20:37:25 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 6
Apr 14 20:37:25 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x32 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:25 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 6
Apr 14 20:37:25 hermes /netbsd: isp0: ipcp output <conf-req id=0x23 len=10 03-06-00-00-00-00>
Apr 14 20:37:26 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 5
Apr 14 20:37:26 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x33 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:26 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 5
Apr 14 20:37:26 hermes /netbsd: isp0: ipcp output <conf-req id=0x24 len=10 03-06-00-00-00-00>
Apr 14 20:37:27 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 4
Apr 14 20:37:27 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x34 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:27 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 4
Apr 14 20:37:27 hermes /netbsd: isp0: ipcp output <conf-req id=0x25 len=10 03-06-00-00-00-00>
Apr 14 20:37:28 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 3
Apr 14 20:37:28 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x35 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:28 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 3
Apr 14 20:37:28 hermes /netbsd: isp0: ipcp output <conf-req id=0x26 len=10 03-06-00-00-00-00>
Apr 14 20:37:29 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 2
Apr 14 20:37:29 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x36 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:29 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 2
Apr 14 20:37:29 hermes /netbsd: isp0: ipcp output <conf-req id=0x27 len=10 03-06-00-00-00-00>
Apr 14 20:37:30 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 1
Apr 14 20:37:30 hermes /netbsd: isp0: ipv6cp output <conf-req id=0x37 len=14 01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:30 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 1
Apr 14 20:37:30 hermes /netbsd: isp0: ipcp output <conf-req id=0x28 len=10 03-06-00-00-00-00>
Apr 14 20:37:31 hermes /netbsd: isp0: ipv6cp TO(req-sent) rst_counter = 0
Apr 14 20:37:31 hermes /netbsd: isp0: ipcp TO(req-sent) rst_counter = 0
Apr 14 20:37:31 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:37:31 hermes /netbsd: isp0: phase terminate
Apr 14 20:37:31 hermes /netbsd: isp0: lcp output <term-req id=0x18 len=4>
Apr 14 20:37:32 hermes /netbsd: isp0: lcp TO(closing) rst_counter = 2
Apr 14 20:37:32 hermes /netbsd: isp0: lcp output <term-req id=0x19 len=4>
Apr 14 20:37:32 hermes /netbsd: isp0: invalid input protocol <addr=0xff ctrl=0x3 proto=0x80fd>
Apr 14 20:37:32 hermes /netbsd: isp0: lcp input(closing): <proto-rej id=0x1 len=20 80-57-01-2d-00-0e-01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:32 hermes /netbsd: isp0: lcp: RXJ+ (proto-rej) for proto 0x8057 (ipv6cp/stopped)
Apr 14 20:37:32 hermes /netbsd: isp0: lcp input(closing): <proto-rej id=0x2 len=20 80-57-01-2e-00-0e-01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:32 hermes /netbsd: isp0: lcp: RXJ+ (proto-rej) for proto 0x8057 (ipv6cp/stopped)
Apr 14 20:37:32 hermes /netbsd: isp0: lcp input(closing): <proto-rej id=0x3 len=20 80-57-01-2f-00-0e-01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:32 hermes /netbsd: isp0: lcp: RXJ+ (proto-rej) for proto 0x8057 (ipv6cp/stopped)
Apr 14 20:37:32 hermes /netbsd: isp0: lcp input(closing): <proto-rej id=0x4 len=20 80-57-01-30-00-0e-01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:32 hermes /netbsd: isp0: lcp: RXJ+ (proto-rej) for proto 0x8057 (ipv6cp/stopped)
Apr 14 20:37:33 hermes /netbsd: isp0: lcp input(closing): <proto-rej id=0x5 len=20 80-57-01-31-00-0e-01-0a-02-00-c0-ff-fe-20-6b-63>
Apr 14 20:37:33 hermes /netbsd: isp0: lcp: RXJ+ (proto-rej) for proto 0x8057 (ipv6cp/stopped)
Apr 14 20:37:33 hermes /netbsd: isp0: lcp TO(closing) rst_counter = 1
Apr 14 20:37:33 hermes /netbsd: isp0: lcp output <term-req id=0x1a len=4>
Apr 14 20:37:33 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x1a len=4>
Apr 14 20:37:33 hermes /netbsd: isp0: phase dead
Apr 14 20:37:33 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:37:33 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:37:33 hermes isdnd[185]: CHD 00014 I4BPPP outgoing call disconnected (local)
Apr 14 20:37:33 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:37:33 hermes /netbsd: isp0: phase establish
Apr 14 20:37:33 hermes isdnd[185]: CHD 00014 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:37:33 hermes isdnd[185]: CHD 00014 I4BPPP charging: 0 units, 14 seconds
Apr 14 20:37:33 hermes isdnd[185]: CHD 00014 I4BPPP accounting: in 334, out 470
Apr 14 20:37:33 hermes isdnd[185]: CHD 00015 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:37:33 hermes isdnd[185]: CHD 00015 I4BPPP dialing out from xxx to yyy
Apr 14 20:37:33 hermes isdnd[185]: CHD 00015 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:37:33 hermes /netbsd: isp0: Up event
Apr 14 20:37:33 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:37:33 hermes /netbsd: isp0: lcp output <conf-req id=0x1b len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:33 hermes isdnd[185]: CHD 00015 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:37:34 hermes /netbsd: isp0: lcp TO(req-sent) rst_counter = 10
Apr 14 20:37:34 hermes /netbsd: isp0: lcp output <conf-req id=0x1c len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:35 hermes /netbsd: isp0: lcp TO(req-sent) rst_counter = 9
Apr 14 20:37:35 hermes /netbsd: isp0: lcp output <conf-req id=0x1d len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:35 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x17 len=30 03-05-c2-23-05-05-06-04-ab-1e-94-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:35 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:37:35 hermes /netbsd: isp0: lcp output <conf-rej id=0x17 len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:35 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x18 len=15 03-05-c2-23-05-05-06-04-ab-1e-94>
Apr 14 20:37:35 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:37:35 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4ab1e94 send conf-ack
Apr 14 20:37:35 hermes /netbsd: isp0: lcp output <conf-ack id=0x18 len=15 03-05-c2-23-05-05-06-04-ab-1e-94>
Apr 14 20:37:36 hermes /netbsd: isp0: lcp TO(ack-sent) rst_counter = 8
Apr 14 20:37:36 hermes /netbsd: isp0: lcp output <conf-req id=0x1e len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:37 hermes /netbsd: isp0: lcp input(ack-sent): <conf-ack id=0x1e len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:37 hermes /netbsd: isp0: lcp tlu
Apr 14 20:37:37 hermes /netbsd: isp0: phase authenticate
Apr 14 20:37:37 hermes /netbsd: isp0: chap input <challenge id=0xd len=29 name=jellocom value-size=16 value= 32-ba-e7-c7-d4-92-d5-51-c2-43-b1-9f-46-2e-c0-5f>
Apr 14 20:37:37 hermes /netbsd: isp0: chap output <response id=0xd len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:37:37 hermes /netbsd: isp0: chap success
Apr 14 20:37:37 hermes /netbsd: isp0: phase network
Apr 14 20:37:37 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:37:37 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:37:37 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:37:37 hermes /netbsd: isp0: phase terminate
Apr 14 20:37:37 hermes /netbsd: isp0: lcp output <term-req id=0x1f len=4>
Apr 14 20:37:37 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x1f len=4>
Apr 14 20:37:38 hermes /netbsd: isp0: phase dead
Apr 14 20:37:38 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:37:38 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:37:37 hermes isdnd[185]: CHD 00015 I4BPPP outgoing call disconnected (local)
Apr 14 20:37:37 hermes isdnd[185]: CHD 00015 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:37:37 hermes isdnd[185]: CHD 00015 I4BPPP charging: 0 units, 4 seconds
Apr 14 20:37:37 hermes isdnd[185]: CHD 00015 I4BPPP accounting: in 116, out 144 (in 100, out 136)
Apr 14 20:37:40 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:37:40 hermes /netbsd: isp0: phase establish
Apr 14 20:37:40 hermes isdnd[185]: CHD 00016 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:37:40 hermes isdnd[185]: CHD 00016 I4BPPP dialing out from xxx to yyy
Apr 14 20:37:40 hermes isdnd[185]: CHD 00016 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:37:40 hermes /netbsd: isp0: Up event
Apr 14 20:37:41 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:37:41 hermes /netbsd: isp0: lcp output <conf-req id=0x20 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:41 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0xa len=30 03-05-c2-23-05-05-06-04-ab-33-59-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:41 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:37:41 hermes /netbsd: isp0: lcp output <conf-rej id=0xa len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:41 hermes /netbsd: isp0: lcp input(req-sent): <conf-ack id=0x20 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:41 hermes isdnd[185]: CHD 00016 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:37:41 hermes /netbsd: isp0: lcp input(ack-rcvd): <conf-req id=0xb len=15 03-05-c2-23-05-05-06-04-ab-33-59>
Apr 14 20:37:41 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:37:41 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4ab3359 send conf-ack
Apr 14 20:37:41 hermes /netbsd: isp0: lcp output <conf-ack id=0xb len=15 03-05-c2-23-05-05-06-04-ab-33-59>
Apr 14 20:37:41 hermes /netbsd: isp0: lcp tlu
Apr 14 20:37:42 hermes /netbsd: isp0: phase authenticate
Apr 14 20:37:42 hermes /netbsd: isp0: chap input <challenge id=0x6 len=29 name=jellocom value-size=16 value= e3-28-db-54-9f-08-d9-40-c2-43-b1-9f-dc-26-f0-dd>
Apr 14 20:37:42 hermes /netbsd: isp0: chap output <response id=0x6 len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:37:42 hermes /netbsd: isp0: chap success
Apr 14 20:37:42 hermes /netbsd: isp0: phase network
Apr 14 20:37:42 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:37:42 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:37:42 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:37:42 hermes /netbsd: isp0: phase terminate
Apr 14 20:37:42 hermes /netbsd: isp0: lcp output <term-req id=0x21 len=4>
Apr 14 20:37:42 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x21 len=4>
Apr 14 20:37:42 hermes /netbsd: isp0: phase dead
Apr 14 20:37:42 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:37:42 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:37:43 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:37:43 hermes /netbsd: isp0: phase establish
Apr 14 20:37:43 hermes /netbsd: isp0: Up event
Apr 14 20:37:43 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:37:43 hermes /netbsd: isp0: lcp output <conf-req id=0x22 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:41 hermes isdnd[185]: CHD 00016 I4BPPP outgoing call disconnected (local)
Apr 14 20:37:41 hermes isdnd[185]: CHD 00016 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:37:41 hermes isdnd[185]: CHD 00016 I4BPPP charging: 0 units, 0 seconds
Apr 14 20:37:41 hermes isdnd[185]: CHD 00016 I4BPPP accounting: in 116, out 102 (in 0, out 0)
Apr 14 20:37:41 hermes isdnd[185]: CHD 00017 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:37:41 hermes isdnd[185]: CHD 00017 I4BPPP dialing out from xxx to yyy
Apr 14 20:37:42 hermes isdnd[185]: CHD 00017 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:37:42 hermes isdnd[185]: CHD 00017 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:37:43 hermes /netbsd: isp0: lcp TO(req-sent) rst_counter = 10
Apr 14 20:37:43 hermes /netbsd: isp0: lcp output <conf-req id=0x23 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:44 hermes /netbsd: isp0: lcp TO(req-sent) rst_counter = 9
Apr 14 20:37:44 hermes /netbsd: isp0: lcp output <conf-req id=0x24 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:44 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x13 len=30 03-05-c2-23-05-05-06-04-ab-3f-fb-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:44 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:37:44 hermes /netbsd: isp0: lcp output <conf-rej id=0x13 len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:37:44 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x14 len=15 03-05-c2-23-05-05-06-04-ab-3f-fb>
Apr 14 20:37:44 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:37:44 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4ab3ffb send conf-ack
Apr 14 20:37:44 hermes /netbsd: isp0: lcp output <conf-ack id=0x14 len=15 03-05-c2-23-05-05-06-04-ab-3f-fb>
Apr 14 20:37:45 hermes /netbsd: isp0: lcp TO(ack-sent) rst_counter = 8
Apr 14 20:37:45 hermes /netbsd: isp0: lcp output <conf-req id=0x25 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:45 hermes /netbsd: isp0: lcp input(ack-sent): <conf-ack id=0x25 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:37:45 hermes /netbsd: isp0: lcp tlu
Apr 14 20:37:45 hermes /netbsd: isp0: phase authenticate
Apr 14 20:37:45 hermes /netbsd: isp0: chap input <challenge id=0xb len=29 name=jellocom value-size=16 value= ca-7c-2e-9f-7f-56-84-52-c2-43-b1-9f-15-2c-58-04>
Apr 14 20:37:45 hermes /netbsd: isp0: chap output <response id=0xb len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:37:45 hermes /netbsd: isp0: chap success
Apr 14 20:37:45 hermes /netbsd: isp0: phase network
Apr 14 20:37:45 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:37:45 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:37:45 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:37:45 hermes /netbsd: isp0: phase terminate
Apr 14 20:37:45 hermes /netbsd: isp0: lcp output <term-req id=0x26 len=4>
Apr 14 20:37:45 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x26 len=4>
Apr 14 20:37:46 hermes /netbsd: isp0: phase dead
Apr 14 20:37:46 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:37:46 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:37:45 hermes isdnd[185]: CHD 00017 I4BPPP outgoing call disconnected (local)
Apr 14 20:37:45 hermes isdnd[185]: CHD 00017 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:37:45 hermes isdnd[185]: CHD 00017 I4BPPP charging: 0 units, 3 seconds
Apr 14 20:37:45 hermes isdnd[185]: CHD 00017 I4BPPP accounting: in 116, out 144 (in 0, out 28)
Apr 14 20:38:26 hermes /netbsd: isp0: lcp close(initial)
Apr 14 20:38:26 hermes /netbsd: isp0: lcp close(initial)
Apr 14 20:38:34 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:38:34 hermes /netbsd: isp0: phase establish
Apr 14 20:38:34 hermes isdnd[185]: CHD 00018 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:38:34 hermes isdnd[185]: CHD 00018 I4BPPP dialing out from xxx to yyy
Apr 14 20:38:35 hermes isdnd[185]: CHD 00018 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:38:35 hermes /netbsd: isp0: Up event
Apr 14 20:38:35 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:38:35 hermes /netbsd: isp0: lcp output <conf-req id=0x27 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:35 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0xf len=30 03-05-c2-23-05-05-06-04-ac-07-20-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:35 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:38:35 hermes /netbsd: isp0: lcp output <conf-rej id=0xf len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:35 hermes /netbsd: isp0: lcp input(req-sent): <conf-ack id=0x27 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:35 hermes isdnd[185]: CHD 00018 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:38:35 hermes /netbsd: isp0: lcp input(ack-rcvd): <conf-req id=0x10 len=15 03-05-c2-23-05-05-06-04-ac-07-20>
Apr 14 20:38:35 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:38:35 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4ac0720 send conf-ack
Apr 14 20:38:35 hermes /netbsd: isp0: lcp output <conf-ack id=0x10 len=15 03-05-c2-23-05-05-06-04-ac-07-20>
Apr 14 20:38:35 hermes /netbsd: isp0: lcp tlu
Apr 14 20:38:36 hermes /netbsd: isp0: phase authenticate
Apr 14 20:38:36 hermes /netbsd: isp0: chap input <challenge id=0x9 len=29 name=jellocom value-size=16 value= c7-09-bc-20-ac-fe-4b-9b-c2-43-b1-9f-cb-f1-05-72>
Apr 14 20:38:36 hermes /netbsd: isp0: chap output <response id=0x9 len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:38:36 hermes /netbsd: isp0: chap success
Apr 14 20:38:36 hermes /netbsd: isp0: phase network
Apr 14 20:38:36 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:38:36 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:38:36 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:38:36 hermes /netbsd: isp0: phase terminate
Apr 14 20:38:36 hermes /netbsd: isp0: lcp output <term-req id=0x28 len=4>
Apr 14 20:38:36 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x28 len=4>
Apr 14 20:38:36 hermes /netbsd: isp0: phase dead
Apr 14 20:38:36 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:38:36 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:38:35 hermes isdnd[185]: CHD 00018 I4BPPP outgoing call disconnected (local)
Apr 14 20:38:35 hermes isdnd[185]: CHD 00018 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:38:35 hermes isdnd[185]: CHD 00018 I4BPPP charging: 0 units, 0 seconds
Apr 14 20:38:35 hermes isdnd[185]: CHD 00018 I4BPPP accounting: in 116, out 102 (in 0, out 0)
Apr 14 20:38:37 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:38:38 hermes /netbsd: isp0: phase establish
Apr 14 20:38:37 hermes isdnd[185]: CHD 00019 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:38:37 hermes isdnd[185]: CHD 00019 I4BPPP dialing out from xxx to yyy
Apr 14 20:38:38 hermes isdnd[185]: CHD 00019 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:38:38 hermes /netbsd: isp0: Up event
Apr 14 20:38:38 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:38:38 hermes /netbsd: isp0: lcp output <conf-req id=0x29 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:38 hermes isdnd[185]: CHD 00019 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:38:38 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x5 len=30 03-05-c2-23-05-05-06-04-ac-13-95-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:38 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:38:38 hermes /netbsd: isp0: lcp output <conf-rej id=0x5 len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:38 hermes /netbsd: isp0: lcp input(req-sent): <conf-ack id=0x29 len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:38 hermes /netbsd: isp0: lcp input(ack-rcvd): <conf-req id=0x6 len=15 03-05-c2-23-05-05-06-04-ac-13-95>
Apr 14 20:38:38 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:38:38 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4ac1395 send conf-ack
Apr 14 20:38:38 hermes /netbsd: isp0: lcp output <conf-ack id=0x6 len=15 03-05-c2-23-05-05-06-04-ac-13-95>
Apr 14 20:38:38 hermes /netbsd: isp0: lcp tlu
Apr 14 20:38:38 hermes /netbsd: isp0: phase authenticate
Apr 14 20:38:38 hermes /netbsd: isp0: chap input <challenge id=0x3 len=29 name=jellocom value-size=16 value= 11-b8-32-2e-c5-87-2b-7e-c2-43-b1-9f-74-39-eb-99>
Apr 14 20:38:39 hermes /netbsd: isp0: chap output <response id=0x3 len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:38:39 hermes /netbsd: isp0: chap success
Apr 14 20:38:39 hermes /netbsd: isp0: phase network
Apr 14 20:38:39 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:38:39 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:38:39 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:38:39 hermes /netbsd: isp0: phase terminate
Apr 14 20:38:39 hermes /netbsd: isp0: lcp output <term-req id=0x2a len=4>
Apr 14 20:38:39 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x2a len=4>
Apr 14 20:38:39 hermes /netbsd: isp0: phase dead
Apr 14 20:38:40 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:38:40 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:38:38 hermes isdnd[185]: CHD 00019 I4BPPP outgoing call disconnected (local)
Apr 14 20:38:40 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:38:40 hermes /netbsd: isp0: phase establish
Apr 14 20:38:40 hermes /netbsd: isp0: Up event
Apr 14 20:38:40 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:38:40 hermes /netbsd: isp0: lcp output <conf-req id=0x2b len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:40 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x1f len=30 03-05-c2-23-05-05-06-28-be-53-73-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:40 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:38:40 hermes /netbsd: isp0: lcp output <conf-rej id=0x1f len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:40 hermes /netbsd: isp0: lcp input(req-sent): <conf-ack id=0x2b len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:40 hermes /netbsd: isp0: lcp input(ack-rcvd): <conf-req id=0x20 len=15 03-05-c2-23-05-05-06-28-be-53-73>
Apr 14 20:38:41 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:38:41 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x28be5373 send conf-ack
Apr 14 20:38:41 hermes /netbsd: isp0: lcp output <conf-ack id=0x20 len=15 03-05-c2-23-05-05-06-28-be-53-73>
Apr 14 20:38:41 hermes /netbsd: isp0: lcp tlu
Apr 14 20:38:41 hermes /netbsd: isp0: phase authenticate
Apr 14 20:38:41 hermes /netbsd: isp0: chap input <challenge id=0x6d len=29 name=jellocom value-size=16 value= 8b-00-cd-3e-70-45-2d-76-c2-43-b1-9f-5b-43-12-81>
Apr 14 20:38:41 hermes /netbsd: isp0: chap output <response id=0x6d len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:38:41 hermes /netbsd: isp0: chap success
Apr 14 20:38:41 hermes /netbsd: isp0: phase network
Apr 14 20:38:41 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:38:41 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:38:41 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:38:42 hermes /netbsd: isp0: phase terminate
Apr 14 20:38:42 hermes /netbsd: isp0: lcp output <term-req id=0x2c len=4>
Apr 14 20:38:42 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x2c len=4>
Apr 14 20:38:42 hermes /netbsd: isp0: phase dead
Apr 14 20:38:42 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:38:42 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:38:38 hermes isdnd[185]: CHD 00019 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:38:38 hermes isdnd[185]: CHD 00019 I4BPPP charging: 0 units, 0 seconds
Apr 14 20:38:38 hermes isdnd[185]: CHD 00019 I4BPPP accounting: in 116, out 102 (in 0, out 0)
Apr 14 20:38:39 hermes isdnd[185]: CHD 00020 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:38:39 hermes isdnd[185]: CHD 00020 I4BPPP dialing out from xxx to yyy
Apr 14 20:38:39 hermes isdnd[185]: CHD 00020 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:38:39 hermes isdnd[185]: CHD 00020 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:38:40 hermes isdnd[185]: CHD 00020 I4BPPP outgoing call disconnected (local)
Apr 14 20:38:40 hermes isdnd[185]: CHD 00020 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:38:40 hermes isdnd[185]: CHD 00020 I4BPPP charging: 0 units, 1 seconds
Apr 14 20:38:40 hermes isdnd[185]: CHD 00020 I4BPPP accounting: in 116, out 102 (in 0, out 0)
Apr 14 20:38:42 hermes /netbsd: isp0: lcp open(initial)
Apr 14 20:38:43 hermes /netbsd: isp0: phase establish
Apr 14 20:38:42 hermes isdnd[185]: CHD 00021 I4BPPP rate 120 sec/unit (conf)
Apr 14 20:38:42 hermes isdnd[185]: CHD 00021 I4BPPP dialing out from xxx to yyy
Apr 14 20:38:43 hermes isdnd[185]: CHD 00021 I4BPPP outgoing call proceeding (ctl 0, ch 0)
Apr 14 20:38:43 hermes /netbsd: isp0: Up event
Apr 14 20:38:43 hermes /netbsd: isp0: lcp up(starting)
Apr 14 20:38:43 hermes /netbsd: isp0: lcp output <conf-req id=0x2d len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:43 hermes /netbsd: isp0: lcp input(req-sent): <conf-req id=0x25 len=30 03-05-c2-23-05-05-06-04-ac-26-db-11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:43 hermes /netbsd: isp0: lcp parse opts: auth-proto magic 0x11 [rej] 0x13 [rej] send conf-rej
Apr 14 20:38:43 hermes /netbsd: isp0: lcp output <conf-rej id=0x25 len=19 11-04-05-f4-13-0b-01-6a-65-6c-6c-6f-63-6f-6d>
Apr 14 20:38:43 hermes /netbsd: isp0: lcp input(req-sent): <conf-ack id=0x2d len=10 05-06-5a-bd-e9-1b>
Apr 14 20:38:43 hermes isdnd[185]: CHD 00021 I4BPPP outgoing call active (ctl 0, ch 0, isp0)
Apr 14 20:38:43 hermes /netbsd: isp0: lcp input(ack-rcvd): <conf-req id=0x26 len=15 03-05-c2-23-05-05-06-04-ac-26-db>
Apr 14 20:38:43 hermes /netbsd: isp0: lcp parse opts: auth-proto magic
Apr 14 20:38:43 hermes /netbsd: isp0: lcp parse opt values: auth-proto magic 0x4ac26db send conf-ack
Apr 14 20:38:43 hermes /netbsd: isp0: lcp output <conf-ack id=0x26 len=15 03-05-c2-23-05-05-06-04-ac-26-db>
Apr 14 20:38:44 hermes /netbsd: isp0: lcp tlu
Apr 14 20:38:44 hermes /netbsd: isp0: phase authenticate
Apr 14 20:38:44 hermes /netbsd: isp0: chap input <challenge id=0x1e len=29 name=jellocom value-size=16 value= 56-2e-86-28-9f-2a-c2-c8-c2-43-b1-9f-69-02-b6-29>
Apr 14 20:38:44 hermes /netbsd: isp0: chap output <response id=0x1e len=34 xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx-xx>
Apr 14 20:38:44 hermes /netbsd: isp0: chap success
Apr 14 20:38:44 hermes /netbsd: isp0: phase network
Apr 14 20:38:44 hermes /netbsd: isp0: ipcp open(stopped)
Apr 14 20:38:44 hermes /netbsd: isp0: ipv6cp open(stopped)
Apr 14 20:38:44 hermes /netbsd: isp0: lcp close(opened)
Apr 14 20:38:44 hermes /netbsd: isp0: phase terminate
Apr 14 20:38:44 hermes /netbsd: isp0: lcp output <term-req id=0x2e len=4>
Apr 14 20:38:44 hermes /netbsd: isp0: lcp input(closing): <term-ack id=0x2e len=4>
Apr 14 20:38:44 hermes /netbsd: isp0: phase dead
Apr 14 20:38:45 hermes /netbsd: isp0: lcp down(closed)
Apr 14 20:38:45 hermes /netbsd: isp0: Down event (carrier loss)
Apr 14 20:38:43 hermes isdnd[185]: CHD 00021 I4BPPP outgoing call disconnected (local)
Apr 14 20:38:43 hermes isdnd[185]: CHD 00021 I4BPPP cause 0: normal call clearing (I4B)
Apr 14 20:38:43 hermes isdnd[185]: CHD 00021 I4BPPP charging: 0 units, 0 seconds
Apr 14 20:38:43 hermes isdnd[185]: CHD 00021 I4BPPP accounting: in 116, out 102 (in 0, out 0)
--------------Boundary-00=_JPQKAOXZFAJP4IQ0PPIC--
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-isdn" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200204142009.g3EK9wOu008790>