From owner-freebsd-isdn Sun Apr 14 13:10:24 2002 Delivered-To: freebsd-isdn@freebsd.org Received: from jello277.jellocom.de (jello277.jellocom.de [217.17.194.152]) by hub.freebsd.org (Postfix) with ESMTP id B153037B405 for ; Sun, 14 Apr 2002 13:10:09 -0700 (PDT) Received: from there (ppp045.jellonet.de [217.17.194.45]) by jello277.jellocom.de (8.12.2/8.12.1) with SMTP id g3EK9wOu008790 for ; Sun, 14 Apr 2002 22:09:59 +0200 Message-Id: <200204142009.g3EK9wOu008790@jello277.jellocom.de> From: Ingolf Steinbach To: freebsd-isdn@freebsd.org Subject: What's happening here? Date: Sun, 14 Apr 2002 22:10:31 +0200 X-Mailer: KMail [version 1.3.2] X-Faith: Jesus Christ MIME-Version: 1.0 Content-Type: Multipart/Mixed; boundary="------------Boundary-00=_JPQKAOXZFAJP4IQ0PPIC" Sender: owner-freebsd-isdn@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.org --------------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 Apr 14 20:37:20 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:37:20 hermes /netbsd: isp0: lcp input(req-sent): Apr 14 20:37:20 hermes /netbsd: isp0: lcp input(ack-rcvd): 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 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 Apr 14 20:37:20 hermes /netbsd: isp0: chap output 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 Apr 14 20:37:21 hermes /netbsd: isp0: ipcp up(starting) Apr 14 20:37:21 hermes /netbsd: isp0: ipcp output 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 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 Apr 14 20:37:32 hermes /netbsd: isp0: lcp TO(closing) rst_counter = 2 Apr 14 20:37:32 hermes /netbsd: isp0: lcp output Apr 14 20:37:32 hermes /netbsd: isp0: invalid input protocol Apr 14 20:37:32 hermes /netbsd: isp0: lcp input(closing): 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): 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): 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): 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): 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 Apr 14 20:37:33 hermes /netbsd: isp0: lcp input(closing): 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 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 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 Apr 14 20:37:35 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:37:35 hermes /netbsd: isp0: lcp input(req-sent): 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 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 Apr 14 20:37:37 hermes /netbsd: isp0: lcp input(ack-sent): 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 Apr 14 20:37:37 hermes /netbsd: isp0: chap output 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 Apr 14 20:37:37 hermes /netbsd: isp0: lcp input(closing): 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 Apr 14 20:37:41 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:37:41 hermes /netbsd: isp0: lcp input(req-sent): 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): 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 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 Apr 14 20:37:42 hermes /netbsd: isp0: chap output 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 Apr 14 20:37:42 hermes /netbsd: isp0: lcp input(closing): 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 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 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 Apr 14 20:37:44 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:37:44 hermes /netbsd: isp0: lcp input(req-sent): 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 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 Apr 14 20:37:45 hermes /netbsd: isp0: lcp input(ack-sent): 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 Apr 14 20:37:45 hermes /netbsd: isp0: chap output 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 Apr 14 20:37:45 hermes /netbsd: isp0: lcp input(closing): 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 Apr 14 20:38:35 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:38:35 hermes /netbsd: isp0: lcp input(req-sent): 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): 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 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 Apr 14 20:38:36 hermes /netbsd: isp0: chap output 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 Apr 14 20:38:36 hermes /netbsd: isp0: lcp input(closing): 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 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): 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 Apr 14 20:38:38 hermes /netbsd: isp0: lcp input(req-sent): Apr 14 20:38:38 hermes /netbsd: isp0: lcp input(ack-rcvd): 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 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 Apr 14 20:38:39 hermes /netbsd: isp0: chap output 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 Apr 14 20:38:39 hermes /netbsd: isp0: lcp input(closing): 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 Apr 14 20:38:40 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:38:40 hermes /netbsd: isp0: lcp input(req-sent): Apr 14 20:38:40 hermes /netbsd: isp0: lcp input(ack-rcvd): 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 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 Apr 14 20:38:41 hermes /netbsd: isp0: chap output 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 Apr 14 20:38:42 hermes /netbsd: isp0: lcp input(closing): 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 Apr 14 20:38:43 hermes /netbsd: isp0: lcp input(req-sent): 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 Apr 14 20:38:43 hermes /netbsd: isp0: lcp input(req-sent): 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): 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 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 Apr 14 20:38:44 hermes /netbsd: isp0: chap output 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 Apr 14 20:38:44 hermes /netbsd: isp0: lcp input(closing): 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