From owner-freebsd-questions Tue Jul 25 16:57:59 2000 Delivered-To: freebsd-questions@freebsd.org Received: from watson.ficsgrp.com (watson.ficsgrp.com [194.74.111.3]) by hub.freebsd.org (Postfix) with ESMTP id ADB3D37B8AF for ; Tue, 25 Jul 2000 16:57:49 -0700 (PDT) (envelope-from harry.woodward-clarke@s1.com) Received: from mail.au.ficsgrp.com ([194.74.111.35]) by watson.ficsgrp.com (Netscape Messaging Server 3.6) with ESMTP id AAA27F5 for ; Wed, 26 Jul 2000 01:57:45 +0200 Received: from S1.com ([172.16.48.219]) by mail.au.ficsgrp.com (Netscape Messaging Server 3.6) with ESMTP id 538 for ; Wed, 26 Jul 2000 10:01:03 +1000 Message-ID: <397E29AB.10B28F55@S1.com> Date: Wed, 26 Jul 2000 09:58:35 +1000 From: Harry Woodward-Clarke X-Mailer: Mozilla 4.72 [en] (X11; I; Linux 2.2.12 i386) X-Accept-Language: en MIME-Version: 1.0 To: FreeBSD Questions Subject: User PPP & NT/RAS Callback Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG Hi y'all, The problem: I have successfully used user ppp to dial in to the WinNT4/RAS connection here at work. Works just fine. However, as I live out of the 'local call zone', I've decided to get the RAS box to callback, this is a one minute job on the NT server (45 seconds being the logon ;'). However, convincing user-ppp to wait around long enough for the RAS box to actually dial back has become rather frustrating. It appears to 'redial timeout' after 2 seconds! As you can guess, this is not quite long enough for the RAS system to get back to me ;') Information on the setup follows. data: ppp is invoked by "$ ppp -nat -auto work" bash-2.03$ uname -a FreeBSD nbhwc01.syd.s1.com 4.0-RELEASE FreeBSD 4.0-RELEASE #11: Sat Jul 1 20:27:38 EST 2000 root@nbhwc01.syd.s1.com:/usr/src/sys/compile/NBHWC01 i386 bash-2.03$ Modem is a V90 (PsionDacom Gold Card PCMCIA) in a Dell Latitude CPX. "user-ppp", I couldn't get it to tell me which version it is, so... bash-2.03$ ls -la /usr/sbin/ppp -r-sr-xr-- 1 root network 264920 Mar 21 09:54 /usr/sbin/ppp bash-2.03$ is the closest I can do ;') Extract from the log (/var/log/ppp.log): Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set openmode active Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: deny CHAP [we're using PAP on the dial-ups] Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set phone XXXXXXXX [removed all the way through, replaced as 'XXXXXXXX'] Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set callback cbcp [the MS way] Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set cbcp HHHHHHHH [call me back on this number HHHHHHHH] Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set authname me Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set authkey ******** Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set timeout 3600 Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: set ifaddr 10.0.0.1/0 10. 0.0.2/0 255.255.255.0 0.0.0.0 Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: add default HISADDR Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Warning: Add route failed: default alrea dy exists Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: enable dns Jul 25 20:04:37 nbhwc01 ppp[272]: tun0: Command: work: nat deny_incoming yes Jul 25 20:04:37 nbhwc01 ppp[273]: tun0: Phase: PPP Started (auto mode). Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Phase: bundle: Establish Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Phase: deflink: closed -> opening Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Phase: deflink: Connected! Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Phase: deflink: opening -> dial [I 'ping'd one of the machines at work to force the link to come up] Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Phase: Phone: XXXXXXXX Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Chat: deflink: Dial attempt 1 of 1 Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Chat: Send: AT^M Jul 25 20:05:08 nbhwc01 ppp[273]: tun0: Chat: Expect(5): OK Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Received: AT^M^M Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Received: OK^M Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Send: ATM1E1Q0^M Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Expect(5): OK Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Received: ATM1E1Q0^M^M Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Received: OK^M Jul 25 20:05:09 nbhwc01 ppp[273]: tun0: Chat: Send: ATDTXXXXXXXX^M Jul 25 20:05:11 nbhwc01 ppp[273]: tun0: Chat: Expect(40): CONNECT Jul 25 20:05:35 nbhwc01 ppp[273]: tun0: Chat: Received: ATDTXXXXXXXX^M^M Jul 25 20:05:35 nbhwc01 ppp[273]: tun0: Chat: Received: CONNECT 115200/V34/LAPM/ V42BIS/31200:TX/33600:RX^M [33k6 is expected] Jul 25 20:05:35 nbhwc01 ppp[273]: tun0: Phase: deflink: dial -> carrier Jul 25 20:05:36 nbhwc01 ppp[273]: tun0: Phase: deflink: /dev/cuaa1: CD detected Jul 25 20:05:36 nbhwc01 ppp[273]: tun0: Phase: deflink: carrier -> login Jul 25 20:05:36 nbhwc01 ppp[273]: tun0: Phase: deflink: login -> lcp Jul 25 20:05:36 nbhwc01 ppp[273]: tun0: LCP: FSM: Using "deflink" as a transport Jul 25 20:05:36 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Initial --> C losed Jul 25 20:05:36 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Closed --> St opped Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: deflink: LayerStart Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: deflink: SendConfigReq(1) state = S topped Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: ACFCOMP[2] Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: PROTOCOMP[2] Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: ACCMAP[6] 0x00000000 Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: MRU[4] 1500 Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: MAGICNUM[6] 0x3859d848 Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: CALLBACK[3] CBCP Jul 25 20:05:37 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Stopped --> R eq-Sent Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: RecvConfigReq(0) state = R eq-Sent Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: ACCMAP[6] 0x00000000 Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x80) Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Warning: CHAP 0x80 not available without DES Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: MAGICNUM[6] 0x00004658 Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: PROTOCOMP[2] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: ACFCOMP[2] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: SendConfigNak(0) state = R eq-Sent Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP) Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: RecvConfigAck(1) state = R eq-Sent Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Req-Sent --> Ack-Rcvd Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: RecvConfigReq(1) state = A ck-Rcvd Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: ACCMAP[6] 0x00000000 Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP) Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: MAGICNUM[6] 0x00004658 Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: PROTOCOMP[2] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: ACFCOMP[2] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: SendConfigAck(1) state = A ck-Rcvd Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: ACCMAP[6] 0x00000000 Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP) Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: MAGICNUM[6] 0x00004658 Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: PROTOCOMP[2] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: ACFCOMP[2] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Ack-Rcvd --> Opened Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: LayerUp Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: bundle: Authenticate Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: deflink: his = PAP, mine = none Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: Pap Output: me ******** Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: Pap Input: SUCCESS () [we've successfully authenticated] Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: deflink: lcp -> cbcp Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: deflink: cbcp -> lcp Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: LayerDown Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: SendTerminateReq(2) state = Opened Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Opened --> Cl osing Jul 25 20:05:38 nbhwc01 ppp[273]: tun0: Phase: deflink: CBCP: Peer will dial bac k [getting ready for the callback...] Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: LCP: deflink: RecvTerminateAck(2) state = Closing Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: LCP: deflink: LayerFinish Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Closing --> C losed Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: LCP: deflink: State change Closed --> In itial Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: Disconnected! Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: lcp -> logout Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: logout -> hangup Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: Disconnected! Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: Connect time: 31 secs: 2 36 octets in, 203 octets out Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: total 14 bytes/sec, peak 65 byte s/sec on Tue Jul 25 20:05:39 2000 Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: hangup -> opening Jul 25 20:05:39 nbhwc01 ppp[273]: tun0: Phase: deflink: Enter pause (2) for redi aling. [waiting for callback] Jul 25 20:05:41 nbhwc01 ppp[273]: tun0: Phase: deflink: Redial timer expired. [2 seconds later!!!??? come on!] Jul 25 20:05:42 nbhwc01 ppp[273]: tun0: Phase: deflink: Connected! Jul 25 20:05:42 nbhwc01 ppp[273]: tun0: Phase: deflink: opening -> dial Jul 25 20:05:42 nbhwc01 ppp[273]: tun0: Phase: Phone: XXXXXXXX Jul 25 20:05:42 nbhwc01 ppp[273]: tun0: Chat: deflink: Dial attempt 1 of 1 [and attempt to redial again...] The /etc/ppp/ppp.conf is: ################################################################# # PPP Sample Configuration File # Originally written by Toshiharu OHNO # Simplified 5/14/1999 by wself@cdrom.com # # $FreeBSD: src/etc/ppp/ppp.conf,v 1.2 1999/08/27 23:24:08 peter Exp $ ################################################################# default: # # Make sure that "device" references the correct serial port # for your modem. (cuaa0 = COM1, cuaa1 = COM2) # set device /dev/cuaa1 set log Phase Chat LCP IPCP CCP tun command set speed 115200 set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" AT OK-AT-OK ATM0E1Q0 OK \\dATDT\\T TIMEOUT 140 CONNECT" set server +3000 d delete all set reconnect 2 5 # set timeout 120 set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0 add default HISADDR enable dns allow users me set filter alive 0 deny icmp set filter alive 1 deny udp src eq 53 set filter alive 2 deny udp dst eq 53 set filter alive 3 permit 0 0 set filter dial 0 deny icmp set filter dial 1 permit 0 0 work: set openmode active 60 deny CHAP set phone XXXXXXXX set callback cbcp set cbcp HHHHHHHH set authname me set authkey supersecretauthkey #set timeout 3600 set timeout 0 set redial 180 set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0 add default HISADDR enable dns nat deny_incoming yes [end] I've been through the PedanticPPP-Primer, but there didn't appear to be much there regarding callback. I've read the man page for user ppp (man 8 ppp), and while there is a section there on callback, anything I could find on "timeouts" just didn't seem to have any effect on the "Redial timer". I *know* it's there somewhere - would someone like to help this twink out and point to it, please? ;') thanks and regards, haxxa To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message