From owner-freebsd-isdn Mon Jan 29 5:25:26 2001 Delivered-To: freebsd-isdn@freebsd.org Received: from gnu.in-berlin.de (gnu.in-berlin.de [192.109.42.4]) by hub.freebsd.org (Postfix) with ESMTP id 1346B37B402 for ; Mon, 29 Jan 2001 05:25:04 -0800 (PST) Received: from hirsch.in-berlin.de (root@hirsch.colt.in-berlin.de [213.61.118.6]) by gnu.in-berlin.de (8.10.1/8.10.1) with ESMTP id f0TDP2D25191; Mon, 29 Jan 2001 14:25:02 +0100 (CET) (envelope-from balu@dva.in-berlin.de) Received: (from uucp@localhost) by hirsch.in-berlin.de (8.11.1/8.11.1/Debian 8.11.0-6) with UUCP id f0TDP2j13385; Mon, 29 Jan 2001 14:25:02 +0100 X-Envelope-From: balu@dva.in-berlin.de X-Envelope-To: freebsd-isdn@FreeBSD.ORG Received: by dva.in-berlin.de (Postfix, from userid 200) id E3AF33FF1; Mon, 29 Jan 2001 14:15:35 +0100 (CET) Date: Mon, 29 Jan 2001 14:15:35 +0100 From: Boris Staeblow To: Brian Somers Cc: freebsd-isdn@FreeBSD.ORG Subject: Re: ppp dialin didn't work with userland ppp Message-ID: <20010129141535.A43873@dva.in-berlin.de> References: <200101282318.f0SNILd01132@hak.lan.Awfulhak.org> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.2.5i In-Reply-To: <200101282318.f0SNILd01132@hak.lan.Awfulhak.org>; from brian@Awfulhak.org on Sun, Jan 28, 2001 at 11:18:21PM +0000 Sender: owner-freebsd-isdn@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org On Sun, Jan 28, 2001 at 11:18:21PM +0000, Brian Somers wrote: > > tun0: IPCP: deflink: LayerStart. > > tun0: IPCP: deflink: SendConfigReq(1) state = Closed > > tun0: IPCP: IPADDR[6] 0.0.0.0 > > tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression > [.....] > > tun0: IPCP: deflink: State change Closed --> Req-Sent > > tun0: Error: DoLoop: select(): Bad file descriptor > > tun0: Debug: DoLoop done. > [.....] > > Boris > > Looks like the peer is going away - perhaps due to you asking for > 0.0.0.0 as an IP number. What's the story on the client side ? Is > the client supposed to be deciding on the server's IP number (an > unusual setup) ? I've changed my setup to static ip numbers, but it didn't fix the problem. I dialup with a Windows 98 system, static IP: 10.1.0.111, to FreeBSD 4.2-STABLE, i4b 00.96, userland ppp via rbch0,static IP: 10.1.0.101. Same problem with dynamic IP-adresses and/or with the original i4b from -stable. The dialup works with sppp via ispX from i4b! Boris ----------------------------------------------------------------------------- /etc/isdn/isdnd.rc: entry name = ULPPP usrdevicename = rbch usrdeviceunit = 0 isdncontroller = 0 isdnchannel = -1 local-phone-incoming = 2766 remote-phone-incoming = * local-phone-dialout = 2766 remote-phone-dialout = 2766 # ppp(8) will override this remdial-handling = first dialin-reaction = accept dialout-type = normal b1protocol = hdlc idletime-incoming = 900 # Should be larger than ppps timeout idletime-outgoing = 900 # Should be larger than ppps timeout ratetype = 0 unitlength = 90 unitlengthsrc = aocd dialretries = 2 recoverytime = 5 /etc/ppp/ppp.conf: default: set device /dev/cuaa1 enable proxy set log CBCP CCP Chat Connect Command IPCP Tun Phase Debug inc_rbch0: set proctitle ppp-rbch0 disable chap chap81 pap MSChap accept pap chap chap81 MSChap enable proxy set ifaddr 10.1.0.101 10.1.0.111 255.255.255.255 set timeout 120 set dial set login set hangup set device /dev/i4brbch0 set speed sync link * set cd 10 # accept dns # set dns 10.0.0.10 # set nbns 10.0.0.10 enable lqr set lqrperiod 45 disable pred1 deflate deny pred1 deflate ppp.log: Jan 29 14:01:40 dva ppp[44556]: Phase: Using interface: tun0 Jan 29 14:01:40 dva ppp[44556]: Phase: deflink: Created in closed state Jan 29 14:01:40 dva ppp[44556]: Phase: Listening at port 3000. Jan 29 14:01:40 dva ppp[44556]: tun0: Debug: ReadSystem: Checking inc_rbch0 (/etc/ppp/ppp.conf). Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set proctitle ppp-rbch0 Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: disable chap chap81 pap MSChap Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: accept pap chap chap81 MSChap Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: enable proxy Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set ifaddr 10.1.0.101 10.1.0.111 255.255.255.255 Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set timeout 120 Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set dial Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set login Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set hangup Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set device /dev/i4brbch0 Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set speed sync Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: link * set cd 10 Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: enable lqr Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: set lqrperiod 45 Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: disable pred1 deflate Jan 29 14:01:40 dva ppp[44556]: tun0: Command: inc_rbch0: deny pred1 deflate Jan 29 14:01:40 dva ppp[44556]: tun0: Phase: PPP Started (direct mode). Jan 29 14:01:40 dva ppp[44556]: tun0: Debug: Select changes time: no Jan 29 14:01:40 dva ppp[44556]: tun0: Phase: bundle: Establish Jan 29 14:01:40 dva ppp[44556]: tun0: Phase: deflink: closed -> opening Jan 29 14:01:40 dva ppp[44556]: tun0: Debug: deflink: Input is an i4b version 0.96.0 isdn device (/dev/i4brbch0) Jan 29 14:01:40 dva ppp[44556]: tun0: Phase: deflink: Connected! Jan 29 14:01:40 dva ppp[44556]: tun0: Phase: deflink: opening -> carrier Jan 29 14:01:40 dva ppp[44556]: tun0: Debug: deflink: Using i4b_Timeout [0x8086480] Jan 29 14:01:40 dva ppp[44556]: tun0: Debug: Waiting for carrier Jan 29 14:01:41 dva ppp[44556]: tun0: Phase: deflink: /dev/i4brbch0: CD detected Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: Entering i4b_Raw Jan 29 14:01:41 dva ppp[44556]: tun0: Phase: deflink: carrier -> lcp Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorRead: read 44/2048 from 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPull: unknown -> 0x8021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PullPacket: Despatch proto 0x8021 Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: deflink: Error: Unexpected IPCP in phase Establish (ignored) Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorRead: read 27/2048 from 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPull: unknown -> 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PullPacket: Despatch proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 01 01 00 16 01 04 05 dc 05 06 eb d2 fd 9b 04 08 ................ Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: c0 25 00 00 11 94 .%.... Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 04 01 00 07 0d 03 06 ....... Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 2 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_dequeue: queue len = 2 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_Dequeue: Dequeued from queue 1, containing 1 more packets Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorWrite: wrote 26(26) to 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_dequeue: queue len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorWrite: wrote 11(11) to 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorRead: read 16/2048 from 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPull: unknown -> 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PullPacket: Despatch proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 01 02 00 0e 01 04 05 dc 05 06 eb d2 fd 9b .............. Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_dequeue: queue len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorWrite: wrote 18(18) to 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorRead: read 24/2048 from 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPull: unknown -> 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PullPacket: Despatch proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 02 02 00 14 02 06 00 0a 00 00 05 06 00 21 73 c5 .............!s. Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 07 02 08 02 .... Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_dequeue: queue len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorWrite: wrote 24(24) to 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: deflink: DescriptorRead: read 18/2048 from 0 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPull: unknown -> 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PullPacket: Despatch proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 09 00 00 10 eb d2 fd 9b 59 4e 4f 54 00 00 00 00 ........YNOT.... Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0xc021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 1 Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: FSM: Using "deflink" as a transport Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: deflink: State change Initial --> Closed Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: deflink: LayerStart. Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: MPPE: InitOptsOutput Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: MPPE: MasterKey is invalid, MPPE is capable only with CHAP81 authentication Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: deflink: SendConfigReq(1) state = Closed Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: MPPE[6] value 0x00000000 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 01 01 00 0a 12 06 00 00 00 00 .......... Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0x80fd Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0x80fd Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 2 Jan 29 14:01:41 dva ppp[44556]: tun0: CCP: deflink: State change Closed --> Req-Sent Jan 29 14:01:41 dva ppp[44556]: tun0: Phase: deflink: lcp -> open Jan 29 14:01:41 dva ppp[44556]: tun0: Phase: bundle: Network Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: FSM: Using "deflink" as a transport Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: deflink: State change Initial --> Closed Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: deflink: LayerStart. Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: IPADDR[6] 10.1.0.101 Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 01 01 00 10 03 06 0a 01 00 65 02 06 00 2d 0f 01 .........e...-.. Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0x8021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0x8021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: m_enqueue: len = 3 Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: deflink: State change Closed --> Req-Sent Jan 29 14:01:41 dva ppp[44556]: tun0: Error: DoLoop: select(): Bad file descriptor Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: DoLoop done. Jan 29 14:01:41 dva ppp[44556]: tun0: Phase: PPP Terminated (normal). Jan 29 14:01:41 dva ppp[44556]: tun0: IPCP: deflink: SendTerminateReq(1) state = Req-Sent Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: fsm_Output Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: 05 01 00 04 .... Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: proto_LayerPush: Using 0x8021 Jan 29 14:01:41 dva ppp[44556]: tun0: Debug: link_PushPacket: Transmit proto 0x8021 To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-isdn" in the body of the message