From owner-freebsd-questions Fri Aug 6 20:29:19 1999 Delivered-To: freebsd-questions@freebsd.org Received: from css.tuu.utas.edu.au (css.tuu.utas.edu.au [131.217.115.65]) by hub.freebsd.org (Postfix) with ESMTP id 7C04014C0B for ; Fri, 6 Aug 1999 20:29:05 -0700 (PDT) (envelope-from iaint@css.tuu.utas.edu.au) Received: from localhost (iaint@localhost) by css.tuu.utas.edu.au (8.9.3/8.9.3) with ESMTP id NAA52067 for ; Sat, 7 Aug 1999 13:28:47 +1000 (EST) (envelope-from iaint@depravitas.tuu.utas.edu.au) Date: Sat, 7 Aug 1999 13:28:46 +1000 (EST) From: Iain Templeton To: freebsd-questions@freebsd.org Subject: PPP failing in LCP configuration Message-ID: MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG Hi, After upgrading my home machine to FreeBSD 3.2 I found that my PPP dialin no longer worked. This was with the same configuration file, and also with a configuration file used by somebody else under 3.x. It seems to be failing when trying to negotiate on LDBACP (which apart from being mentioned in src/usr.sbin/ppp/lcp.c as being an LCP option doesn't get used). The value of this option changes on each time. I tried adding a line to lcp.c that adds TY_LDBACP, and just silently ignores it - instead of just rejecting because it is unknown, but that only produces the second ppp log I have, where I have ConfigAck's, but it never actually works. As far as I know, the routers used at the other end are Cisco Systems equipment, but that was all I was able to get out of the ISP; and it is not the account itself that is the problem as I can still connect using Win98 (as I am now). When I can find a copy of 2.2.X's PPP I shall also try that... Thanks, Iain. ---------- Here is my config file /etc/ppp/ppp.conf default: set device /dev/cuaa1 set speed 115200 allow user iaint set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" ATZ0 OK-AT-OK \\dATDT\\T TIMEOUT 40 CONNECT" deny lqr tassie: set log chat lcp ccp connect ipcp phase set log local chat lcp ccp connect ipcp phase set phone "XXXXXXXX" set authname itemple set authkey XXXXXXXXXXX set login "TIMEOUT 10 name: itemple word: \\P > ppp" set mru 1600 set mrru 1600 set timeout 6000 add default HISADDR And the following is the PPP log without me hacking lcp.c ppp[912]: Phase: Using interface: tun0 ppp[912]: Phase: deflink: Created in closed state ppp[912]: Phase: PPP Started (interactive mode). ppp[912]: Phase: bundle: Establish ppp[912]: Phase: deflink: closed -> opening ppp[912]: Phase: deflink: Connected! ppp[912]: Phase: deflink: opening -> dial ppp[912]: Phase: Phone: 62260900 ppp[912]: Chat: deflink: Dial attempt 1 of 1 ppp[912]: Chat: Send: ATZ0^M ppp[912]: Chat: Expect(5): OK ppp[912]: Chat: Received: ^M ppp[912]: Chat: Received: OK^M ppp[912]: Chat: Send: ATDTXXXXXXXX^M ppp[912]: Chat: Expect(40): CONNECT ppp[912]: Chat: Received: ATDTXXXXXXXX^M^M ppp[912]: Chat: Received: CARRIER 28800^M ppp[912]: Chat: Received: ^M ppp[912]: Chat: Received: PROTOCOL: LAP-M^M ppp[912]: Chat: Received: ^M ppp[912]: Chat: Received: COMPRESSION: V.42BIS^M ppp[912]: Chat: Received: ^M ppp[912]: Chat: Received: CONNECT 115200/ARQ^M ppp[912]: Phase: deflink: dial -> carrier ppp[912]: Phase: deflink: /dev/cuaa1: CD detected ppp[912]: Phase: deflink: carrier -> login ppp[912]: Chat: Expect(10): name: ppp[912]: Chat: Received: ^M ppp[912]: Chat: Received: Welcome to Tasmania's Access Server^M ppp[912]: Chat: Received: Username: ppp[912]: Chat: Send: itemple^M ppp[912]: Chat: Expect(10): word: ppp[912]: Chat: Received: itemple^M ppp[912]: Chat: Received: Password: ppp[912]: Chat: Send: \P ppp[912]: Chat: Expect(10): > ppp[912]: Chat: Received: ***********^M ppp[912]: Chat: Received: Tigris.HBT> ppp[912]: Chat: Send: ppp^M ppp[912]: Phase: deflink: login -> lcp ppp[912]: LCP: FSM: Using "deflink" as a transport ppp[912]: LCP: deflink: State change Initial --> Closed ppp[912]: LCP: deflink: State change Closed --> Stopped ppp[912]: LCP: deflink: LayerStart ppp[912]: LCP: deflink: SendConfigReq(1) state = Stopped ppp[912]: LCP: ACFCOMP[2] ppp[912]: LCP: PROTOCOMP[2] ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x12c04b85 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: SHORTSEQ[2] ppp[912]: LCP: deflink: State change Stopped --> Req-Sent ppp[912]: LCP: deflink: RecvConfigReq(172) state = Req-Sent ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x491b7eb6 ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[912]: LCP: deflink: SendConfigRej(172) state = Req-Sent ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: deflink: SendConfigReq(1) state = Req-Sent ppp[912]: LCP: ACFCOMP[2] ppp[912]: LCP: PROTOCOMP[2] ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x12c04b85 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: SHORTSEQ[2] ppp[912]: LCP: deflink: RecvConfigReq(173) state = Req-Sent ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x491b7eb6 ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[912]: LCP: deflink: SendConfigRej(173) state = Req-Sent ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: deflink: SendConfigReq(1) state = Req-Sent ppp[912]: LCP: ACFCOMP[2] ppp[912]: LCP: PROTOCOMP[2] ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x12c04b85 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: SHORTSEQ[2] ppp[912]: LCP: deflink: RecvConfigReq(174) state = Req-Sent ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x491b7eb6 ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[912]: LCP: deflink: SendConfigRej(174) state = Req-Sent ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: deflink: SendConfigReq(1) state = Req-Sent ppp[912]: LCP: ACFCOMP[2] ppp[912]: LCP: PROTOCOMP[2] ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x12c04b85 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: SHORTSEQ[2] ppp[912]: LCP: deflink: RecvConfigReq(175) state = Req-Sent ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x491b7eb6 ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[912]: LCP: deflink: SendConfigRej(175) state = Req-Sent ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: deflink: SendConfigReq(1) state = Req-Sent ppp[912]: LCP: ACFCOMP[2] ppp[912]: LCP: PROTOCOMP[2] ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x12c04b85 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: SHORTSEQ[2] ppp[912]: LCP: deflink: RecvConfigReq(176) state = Req-Sent ppp[912]: LCP: MRU[4] 1600 ppp[912]: LCP: MAGICNUM[6] 0x491b7eb6 ppp[912]: LCP: ACCMAP[6] 0x00000000 ppp[912]: LCP: MRRU[4] 1600 ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[912]: LCP: deflink: SendConfigRej(176) state = Req-Sent ppp[912]: LCP: LDBACP[4] 0010 ppp[912]: LCP: deflink: LayerFinish ppp[912]: LCP: deflink: State change Req-Sent --> Stopped ppp[912]: LCP: deflink: State change Stopped --> Closed ppp[912]: LCP: deflink: State change Closed --> Initial ppp[912]: Phase: deflink: Disconnected! ppp[912]: Phase: deflink: lcp -> hangup ppp[912]: Phase: deflink: Connect time: 42 secs: 382 octets in, 446 octets out ppp[912]: Phase: total 19 bytes/sec, peak 66 bytes/sec on Sat Aug 7 12:18:08 1999 ppp[912]: Phase: deflink: hangup -> closed ppp[912]: Phase: bundle: Dead ppp[912]: Phase: PPP Terminated (normal). Here is the PPP log with lcp.c hacked. ppp[950]: Phase: Using interface: tun0 ppp[950]: Phase: deflink: Created in closed state ppp[950]: Phase: PPP Started (interactive mode). ppp[950]: Phase: bundle: Establish ppp[950]: Phase: deflink: closed -> opening ppp[950]: Phase: deflink: Connected! ppp[950]: Phase: deflink: opening -> dial ppp[950]: Phase: Phone: 62260900 ppp[950]: Chat: deflink: Dial attempt 1 of 1 ppp[950]: Chat: Send: ATZ0^M ppp[950]: Chat: Expect(5): OK ppp[950]: Chat: Received: ^M ppp[950]: Chat: Received: OK^M ppp[950]: Chat: Send: ATDT62260900^M ppp[950]: Chat: Expect(40): CONNECT ppp[950]: Chat: Received: ATDT62260900^M^M ppp[950]: Chat: Received: CARRIER 28800^M ppp[950]: Chat: Received: ^M ppp[950]: Chat: Received: PROTOCOL: LAP-M^M ppp[950]: Chat: Received: ^M ppp[950]: Chat: Received: COMPRESSION: V.42BIS^M ppp[950]: Chat: Received: ^M ppp[950]: Chat: Received: CONNECT 115200/ARQ^M ppp[950]: Phase: deflink: dial -> carrier ppp[950]: Phase: deflink: /dev/cuaa1: CD detected ppp[950]: Phase: deflink: carrier -> login ppp[950]: Chat: Expect(10): name: ppp[950]: Chat: Received: ^M ppp[950]: Chat: Received: Welcome to Tasmania's Access Server^M ppp[950]: Chat: Received: Username: ppp[950]: Chat: Send: itemple^M ppp[950]: Chat: Expect(10): word: ppp[950]: Chat: Received: itemple^M ppp[950]: Chat: Received: Password: ppp[950]: Chat: Send: \P ppp[950]: Chat: Expect(10): > ppp[950]: Chat: Received: ***********^M ppp[950]: Chat: Received: Tigris.HBT> ppp[950]: Chat: Send: ppp^M ppp[950]: Phase: deflink: login -> lcp ppp[950]: LCP: FSM: Using "deflink" as a transport ppp[950]: LCP: deflink: State change Initial --> Closed ppp[950]: LCP: deflink: State change Closed --> Stopped ppp[950]: LCP: deflink: LayerStart ppp[950]: LCP: deflink: SendConfigReq(1) state = Stopped ppp[950]: LCP: ACFCOMP[2] ppp[950]: LCP: PROTOCOMP[2] ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x5c544a28 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: SHORTSEQ[2] ppp[950]: LCP: deflink: State change Stopped --> Req-Sent ppp[950]: LCP: deflink: RecvConfigReq(47) state = Req-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigAck(47) state = Req-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: State change Req-Sent --> Ack-Sent ppp[950]: LCP: deflink: SendConfigReq(1) state = Ack-Sent ppp[950]: LCP: ACFCOMP[2] ppp[950]: LCP: PROTOCOMP[2] ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x5c544a28 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: SHORTSEQ[2] ppp[950]: LCP: deflink: RecvConfigReq(48) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigAck(48) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigReq(1) state = Ack-Sent ppp[950]: LCP: ACFCOMP[2] ppp[950]: LCP: PROTOCOMP[2] ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x5c544a28 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: SHORTSEQ[2] ppp[950]: LCP: deflink: RecvConfigReq(49) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigAck(49) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigReq(1) state = Ack-Sent ppp[950]: LCP: ACFCOMP[2] ppp[950]: LCP: PROTOCOMP[2] ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x5c544a28 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: SHORTSEQ[2] ppp[950]: LCP: deflink: RecvConfigReq(50) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigAck(50) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigReq(1) state = Ack-Sent ppp[950]: LCP: ACFCOMP[2] ppp[950]: LCP: PROTOCOMP[2] ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x5c544a28 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: SHORTSEQ[2] ppp[950]: LCP: deflink: RecvConfigReq(51) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: SendConfigAck(51) state = Ack-Sent ppp[950]: LCP: MRU[4] 1600 ppp[950]: LCP: MAGICNUM[6] 0x632d5645 ppp[950]: LCP: ACCMAP[6] 0x00000000 ppp[950]: LCP: MRRU[4] 1600 ppp[950]: LCP: ENDDISC[9] MAC 08:00:03:04:05:db ppp[950]: LCP: deflink: LayerFinish ppp[950]: LCP: deflink: State change Ack-Sent --> Stopped ppp[950]: LCP: deflink: State change Stopped --> Closed ppp[950]: LCP: deflink: State change Closed --> Initial ppp[950]: Phase: deflink: Disconnected! ppp[950]: Phase: deflink: lcp -> hangup ppp[950]: Phase: deflink: Connect time: 42 secs: 367 octets in, 646 octets out ppp[950]: Phase: total 24 bytes/sec, peak 81 bytes/sec on Sat Aug 7 12:25:54 1999 ppp[950]: Phase: deflink: hangup -> closed ppp[950]: Phase: bundle: Dead ppp[950]: Phase: PPP Terminated (normal). -- Iain Templeton, Computer Science Society sysadmin BE (Computer Systems Engineering) 4th year University of Tasmania, Australia To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message