From owner-freebsd-questions Tue Dec 21 10:56: 4 1999 Delivered-To: freebsd-questions@freebsd.org Received: from vidle.i.cz (vidle.i.cz [193.179.36.138]) by hub.freebsd.org (Postfix) with ESMTP id EF85C154FD for ; Tue, 21 Dec 1999 10:56:00 -0800 (PST) (envelope-from mm@i.cz) Received: from ns.i.cz (brana.i.cz [193.179.36.134]) by vidle.i.cz (Postfix) with ESMTP id A672130701 for ; Tue, 21 Dec 1999 19:55:59 +0100 (CET) Received: from woody.i.cz (woody.i.cz [192.168.18.29]) by ns.i.cz (Postfix) with ESMTP id 7E3B836417 for ; Tue, 21 Dec 1999 19:55:53 +0100 (CET) Content-Length: 7757 Message-ID: X-Mailer: XFMail 1.3 [p0] on FreeBSD X-Priority: 3 (Normal) Content-Type: text/plain; charset=iso-8859-2 Content-Transfer-Encoding: 8bit MIME-Version: 1.0 Date: Tue, 21 Dec 1999 19:55:53 +0100 (MET) Reply-To: mm@i.cz From: Martin Machacek To: freebsd-questions@freebsd.org Subject: callback in userland ppp Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG Hi, I'm having troubles using ppp (cbcp) callback with userlevel ppp and Cisco as the dial-up server. Actually precisely the same problem has been reported to this list by andy@sympad.net on April 22 but I wasn't able to find any answer in the archive. Details - The calling machine is FreeBSD 3.2 on Fujitsu Laptop and PCMCIA modem (ppp working perfectly without callback) The dialup server is Cisco 3640 with working cbcp callback (for other users). Here is relevant part of configuration from ppp.conf (some lines wrapped by mailer): icz-cb-test: set log +connect +lcp +cbcp +chat set AuthName martin-cb-test set AuthKey set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" AT&FZ0 OK \\d ATDT\\T TIMEOUT 90 CONNECT" set phone 227 set ifaddr 10.0.0.0/0 10.0.0.0/0 set timeout 600 set callback cbcp set cbcp 252 delete ALL What happens is that I call the Cisco, authenticate and negotiate the callback (Cisco aggrees to call me back on 252) and (according to the log) I await the callback. Here is the log: Dec 21 19:08:45 snoopy ppp[495]: Phase: PPP Started (interactive mode). Dec 21 19:42:20 snoopy ppp[495]: Phase: bundle: Establish Dec 21 19:42:20 snoopy ppp[495]: Phase: deflink: closed -> opening Dec 21 19:42:20 snoopy ppp[495]: Phase: deflink: Connected! Dec 21 19:42:20 snoopy ppp[495]: Phase: deflink: opening -> dial Dec 21 19:42:20 snoopy ppp[495]: Phase: Phone: 227 Dec 21 19:42:20 snoopy ppp[495]: Chat: deflink: Dial attempt 1 of 1 Dec 21 19:42:20 snoopy ppp[495]: Chat: Send: AT&FZ0^M Dec 21 19:42:20 snoopy ppp[495]: Chat: Expect(5): OK Dec 21 19:42:20 snoopy ppp[495]: Chat: Received: AT&FZ0^M^M Dec 21 19:42:20 snoopy ppp[495]: Chat: Received: OK^M Dec 21 19:42:20 snoopy ppp[495]: Chat: Send: ATDT227^M Dec 21 19:42:22 snoopy ppp[495]: Chat: Expect(90): CONNECT Dec 21 19:42:45 snoopy ppp[495]: Chat: Received: ATDT227^M^M Dec 21 19:42:45 snoopy ppp[495]: Chat: Received: CONNECT Dec 21 19:42:45 snoopy ppp[495]: Phase: deflink: dial -> login Dec 21 19:42:45 snoopy ppp[495]: Phase: deflink: login -> lcp Dec 21 19:42:45 snoopy ppp[495]: LCP: FSM: Using "deflink" as a transport Dec 21 19:42:45 snoopy ppp[495]: LCP: deflink: State change Initial --> Closed Dec 21 19:42:45 snoopy ppp[495]: LCP: deflink: State change Closed --> Stopped Dec 21 19:42:46 snoopy ppp[495]: LCP: deflink: LayerStart Dec 21 19:42:46 snoopy ppp[495]: LCP: deflink: SendConfigReq(1) state = Stopped Dec 21 19:42:46 snoopy ppp[495]: LCP: ACFCOMP[2] Dec 21 19:42:46 snoopy ppp[495]: LCP: PROTOCOMP[2] Dec 21 19:42:46 snoopy ppp[495]: LCP: ACCMAP[6] 0x00000000 Dec 21 19:42:46 snoopy ppp[495]: LCP: MRU[4] 1500 Dec 21 19:42:46 snoopy ppp[495]: LCP: MAGICNUM[6] 0x0d42f507 Dec 21 19:42:46 snoopy ppp[495]: LCP: CALLBACK[3] CBCP Dec 21 19:42:46 snoopy ppp[495]: LCP: deflink: State change Stopped --> Req-Sent Dec 21 19:42:48 snoopy ppp[495]: LCP: deflink: RecvConfigReq(129) state = Req-Sent Dec 21 19:42:48 snoopy ppp[495]: LCP: ACCMAP[6] 0x000a0000 Dec 21 19:42:48 snoopy ppp[495]: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Dec 21 19:42:48 snoopy ppp[495]: LCP: MAGICNUM[6] 0x085a2e1a Dec 21 19:42:48 snoopy ppp[495]: LCP: PROTOCOMP[2] Dec 21 19:42:48 snoopy ppp[495]: LCP: ACFCOMP[2] Dec 21 19:42:48 snoopy ppp[495]: LCP: deflink: SendConfigAck(129) state = Req-Sent Dec 21 19:42:48 snoopy ppp[495]: LCP: ACCMAP[6] 0x000a0000 Dec 21 19:42:48 snoopy ppp[495]: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Dec 21 19:42:48 snoopy ppp[495]: LCP: MAGICNUM[6] 0x085a2e1a Dec 21 19:42:48 snoopy ppp[495]: LCP: PROTOCOMP[2] Dec 21 19:42:48 snoopy ppp[495]: LCP: ACFCOMP[2] Dec 21 19:42:48 snoopy ppp[495]: LCP: deflink: State change Req-Sent --> Ack-Sent Dec 21 19:42:49 snoopy ppp[495]: LCP: deflink: SendConfigReq(1) state = Ack-Sent Dec 21 19:42:49 snoopy ppp[495]: LCP: ACFCOMP[2] Dec 21 19:42:49 snoopy ppp[495]: LCP: PROTOCOMP[2] Dec 21 19:42:49 snoopy ppp[495]: LCP: ACCMAP[6] 0x00000000 Dec 21 19:42:49 snoopy ppp[495]: LCP: MRU[4] 1500 Dec 21 19:42:49 snoopy ppp[495]: LCP: MAGICNUM[6] 0x0d42f507 Dec 21 19:42:49 snoopy ppp[495]: LCP: CALLBACK[3] CBCP Dec 21 19:42:49 snoopy ppp[495]: LCP: deflink: RecvConfigAck(1) state = Ack-Sent Dec 21 19:42:49 snoopy ppp[495]: LCP: deflink: State change Ack-Sent --> Opened Dec 21 19:42:49 snoopy ppp[495]: LCP: deflink: LayerUp Dec 21 19:42:49 snoopy ppp[495]: Phase: bundle: Authenticate Dec 21 19:42:49 snoopy ppp[495]: Phase: deflink: his = CHAP 0x05, mine = none Dec 21 19:42:49 snoopy ppp[495]: Phase: Chap Input: CHALLENGE (16 bytes from gwi) Dec 21 19:42:49 snoopy ppp[495]: Phase: Chap Output: RESPONSE (martin-cb-test) Dec 21 19:42:50 snoopy ppp[495]: Phase: Chap Input: SUCCESS Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: lcp -> cbcp Dec 21 19:42:50 snoopy ppp[495]: CBCP: deflink: State change closed --> stopped Dec 21 19:42:50 snoopy ppp[495]: CBCP: deflink: RecvReq(39) state = stopped Dec 21 19:42:50 snoopy ppp[495]: CBCP: TYPE User-spec Dec 21 19:42:50 snoopy ppp[495]: CBCP: DELAY 0 Dec 21 19:42:50 snoopy ppp[495]: CBCP: ADDR Dec 21 19:42:50 snoopy ppp[495]: CBCP: deflink: SendResponse(39) state = stopped Dec 21 19:42:50 snoopy ppp[495]: CBCP: TYPE User-spec Dec 21 19:42:50 snoopy ppp[495]: CBCP: DELAY 0 Dec 21 19:42:50 snoopy ppp[495]: CBCP: ADDR 252 Dec 21 19:42:50 snoopy ppp[495]: CBCP: deflink: State change stopped --> resp-sent Dec 21 19:42:50 snoopy ppp[495]: CBCP: deflink: RecvAck(40) state = resp-sent Dec 21 19:42:50 snoopy ppp[495]: CBCP: TYPE User-spec Dec 21 19:42:50 snoopy ppp[495]: CBCP: DELAY 0 Dec 21 19:42:50 snoopy ppp[495]: CBCP: ADDR 252 Dec 21 19:42:50 snoopy ppp[495]: CBCP: Warning: Expected id was 39, not 40 Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: cbcp -> lcp Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: LayerDown Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: SendTerminateReq(2) state = Opened Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: State change Opened --> Closing Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: CBCP: Peer will dial back ^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: RecvTerminateAck(2) state = Closing Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: LayerFinish Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: State change Closing --> Closed Dec 21 19:42:50 snoopy ppp[495]: LCP: deflink: State change Closed --> Initial Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: Disconnected! Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: lcp -> hangup Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: Connect time: 30 secs: 215 octets in, 252 octets out Dec 21 19:42:50 snoopy ppp[495]: Phase: total 15 bytes/sec, peak 46 bytes/sec on Tue Dec 21 19:42:50 1999 Dec 21 19:42:50 snoopy ppp[495]: Phase: deflink: hangup -> closed Dec 21 19:42:50 snoopy ppp[495]: Phase: bundle: Dead The Cisco actually calls back but ppp never answers the call. The modem is configured to answer after first ring (ats0=1) but never goes off-hook. Where could be the problem? My current theory is that the modem does not answer calls if DTR is low but I does not know how to verify it. Has anybody seen this problem or even solved it? I've searched through available ppp documentation, starting from the manpage (ppp.8), supplied configuration samples and ending with documents on http://www.Awfulhak.org but I wasn't able to find anything helpful or at least relevant to my problem. Any hints are highly appreciated. Please, answer me directly or at least CC me since I'm not subscribed to "questions". Martin --- [PGP KeyID F3F409C4] To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message