From owner-freebsd-isp Mon Aug 9 10:46:14 1999 Delivered-To: freebsd-isp@freebsd.org Received: from velvet.sensation.net.au (multippp0-velvet-brunswick.sensation.net.au [203.20.114.195]) by hub.freebsd.org (Postfix) with ESMTP id CFB2314E14 for ; Mon, 9 Aug 1999 10:46:04 -0700 (PDT) (envelope-from rowan@sensation.net.au) Received: from localhost (rowan@localhost) by velvet.sensation.net.au (8.8.8/8.8.8) with SMTP id AAA17523 for ; Tue, 10 Aug 1999 00:32:23 +1000 (EST) (envelope-from rowan@sensation.net.au) X-Authentication-Warning: velvet.sensation.net.au: rowan owned process doing -bs Date: Tue, 10 Aug 1999 00:32:21 +1000 (EST) From: Rowan Crowe To: freebsd-isp@freebsd.org Subject: ppp + multilink problem - followup Message-ID: MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII Sender: owner-freebsd-isp@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org Just as a followup here's the relevant part of ppp.log on the server and my commentary... Aug 10 00:14:22 core1-mel ppp[29387]: Phase: Using interface: tun0 Aug 10 00:14:22 core1-mel ppp[29387]: Phase: deflink: Created in closed state Aug 10 00:14:22 core1-mel ppp[29213]: Phase: Using interface: tun1 Aug 10 00:14:22 core1-mel ppp[29387]: Phase: bundle: Establish Aug 10 00:14:22 core1-mel ppp[29213]: Phase: deflink: Created in closed state Aug 10 00:14:22 core1-mel ppp[29387]: Phase: deflink: closed -> opening Aug 10 00:14:22 core1-mel ppp[29387]: Phase: 1: Cloned in closed state Aug 10 00:14:22 core1-mel ppp[29387]: Phase: 2: Cloned in closed state Aug 10 00:14:22 core1-mel ppp[29387]: Warning: remove: Cannot delete links that aren't closed Aug 10 00:14:22 core1-mel ppp[29387]: Warning: link deflink remove: Failed 2 ...is this a warning I can ignore or an indication of a problem? Aug 10 00:14:22 core1-mel ppp[29387]: Phase: PPP Started (direct mode). Aug 10 00:14:22 core1-mel ppp[29213]: Phase: bundle: Establish Aug 10 00:14:22 core1-mel ppp[29213]: Phase: deflink: closed -> opening Aug 10 00:14:22 core1-mel ppp[29213]: Phase: 1: Cloned in closed state Aug 10 00:14:22 core1-mel ppp[29213]: Phase: 2: Cloned in closed state Aug 10 00:14:22 core1-mel ppp[29213]: Warning: remove: Cannot delete links that aren't closed Aug 10 00:14:22 core1-mel ppp[29213]: Warning: link deflink remove: Failed 2 Aug 10 00:14:22 core1-mel ppp[29213]: Phase: PPP Started (direct mode). Aug 10 00:14:23 core1-mel ppp[29387]: Phase: deflink: Connected! Aug 10 00:14:23 core1-mel ppp[29387]: Phase: deflink: opening -> lcp Aug 10 00:14:23 core1-mel ppp[29213]: Phase: deflink: Connected! Aug 10 00:14:23 core1-mel ppp[29213]: Phase: deflink: opening -> lcp Aug 10 00:14:23 core1-mel ppp[29387]: Phase: mp: Listening on /var/run/ppp--01-6d70 Aug 10 00:14:23 core1-mel ppp[29387]: Phase: First link: deflink Aug 10 00:14:23 core1-mel ppp[29387]: Phase: deflink: lcp -> open Aug 10 00:14:23 core1-mel ppp[29387]: Phase: bundle: Network Aug 10 00:14:24 core1-mel ppp[29213]: Phase: mp: Transfer link on /var/run/ppp--01-6d70 Aug 10 00:14:24 core1-mel ppp[29387]: Phase: Receiving datalink Aug 10 00:14:24 core1-mel ppp[29213]: Phase: Transmitting datalink deflink Aug 10 00:14:24 core1-mel ppp[29213]: Phase: bundle: Dead Aug 10 00:14:24 core1-mel ppp[29387]: Phase: Rename link deflink to deflink-1 Aug 10 00:14:24 core1-mel ppp[29387]: Phase: deflink-1: Transferred in lcp state Aug 10 00:14:24 core1-mel ppp[29387]: Phase: deflink-1: lcp -> open Aug 10 00:14:24 core1-mel ppp[29866]: Phase: 29213 -> 29866: Passed session control Multilink successfully established, the 2 PIDs of ppp are 29387 and 29866 *pulls out cable of one of the modems at the client end* The client end has detected the carrier drop after a few seconds, but the server end is still sitting there doing nothing. According to 'who' the modem is still connected at the server end. As soon as the first link drops all IP traffic stops, and it will not resume until both the stray PPP processes at the server are killed off and the entire thing is redialled. After a few minutes the lack of LQR replies causes this... Aug 10 00:19:57 core1-mel ppp[29387]: Phase: deflink-1: ** Too many LQR packets lost ** Aug 10 00:19:57 core1-mel ppp[29387]: Phase: deflink-1: open -> lcp Aug 10 00:19:57 core1-mel ppp[29387]: Phase: deflink-1: Disconnected! Aug 10 00:19:57 core1-mel ppp[29387]: Phase: deflink-1: Connect time: 333 secs: 1075 octets in, 781 octets out Aug 10 00:19:57 core1-mel ppp[29387]: Phase: total 5 bytes/sec, peak 43 bytes/sec on Tue Aug 10 00:19:57 1999 Aug 10 00:19:57 core1-mel ppp[29387]: Phase: deflink-1: lcp -> closed ppp pid 29387 has detected the link is down, but it's still running: 29387 a1 Ss+ 0:01.39 /usr/sbin/ppp -direct mp I'm wondering why it doesn't exit, am I doing something wrong in the config? Perhaps it's sitting there in passive mode waiting for a new connection to start? (even though it never will as long as it's attached to cuaa1 which is now an offline modem) If it helps this is what top says about 29387... PID USERNAME PRI NICE SIZE RES STATE TIME WCPU CPU COMMAND 29387 sbruns 2 0 852K 1368K select 0:02 0.00% 0.00% ppp Thank you all for your suggestions so far. By the way, this is the ppp that came with 3.2-RELEASE (recompiled on the 2.2.8-R machines) - unfortunately it didn't fix the problem. BTW, why does the originating ppp occasionally do strange things like initiate a *new* dial 15-20 sec after I kill -TERM it? Is this the correct way to shut it down? (I just did it then and it's dialled about 4-6 times before exiting) Cheers. -- Rowan Crowe http://www.rowan.sensation.net.au/ Sensation Internet Services http://www.sensation.net.au/ Melbourne, Australia Phone: +61-3-9388-9260 To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-isp" in the body of the message