Date: Tue, 21 Dec 1999 19:55:53 +0100 (MET) From: Martin Machacek <mm@i.cz> To: freebsd-questions@freebsd.org Subject: callback in userland ppp Message-ID: <XFMail.991221195553.mm@i.cz>
next in thread | raw e-mail | index | archive | help
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 <here was the password>
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
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?XFMail.991221195553.mm>