From owner-freebsd-mobile Tue Feb 6 9:48:31 2001 Delivered-To: freebsd-mobile@freebsd.org Received: from pine.pinetel.com (pine.pinetel.com [143.227.44.106]) by hub.freebsd.org (Postfix) with ESMTP id 08AE237B491 for ; Tue, 6 Feb 2001 09:48:02 -0800 (PST) Received: from thinkpad (ip69-cu2.pinetel.com [143.227.42.69]) by pine.pinetel.com (8.9.3/8.9.3) with SMTP id JAA13709; Tue, 6 Feb 2001 09:47:55 -0800 (PST) Message-ID: <001501c09065$3e86eb60$452ae38f@thinkpad> From: "Hal Weaver" To: "Brian Somers" Cc: , References: <200102060854.f168sng47206@hak.lan.Awfulhak.org> Subject: Re: PPP problem: PCMCIA modem on ThinkPad Date: Tue, 6 Feb 2001 09:50:05 -0800 MIME-Version: 1.0 Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: 7bit X-Priority: 3 X-MSMail-Priority: Normal X-Mailer: Microsoft Outlook Express 5.00.2615.200 X-MimeOLE: Produced By Microsoft MimeOLE V5.00.2615.200 Sender: owner-freebsd-mobile@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org The portion of ppp.log covering a failed ppp session is copied on to the end of this email. But its importance is gone, since I just found the problem 30 minutes ago. My error was in the ppp.conf file: it lacked the 'set dial' line in the default section. This got dropped when I was thrashing around trying to find the problem earlier. For reasons I still don't understand exactly, the probe of my PCMCIA modem often failed. So setting my dialer device /dev/cuaa4 didn't work, nor did the build of a custom kernel. In the midst of this, I monkeyed with ppp.conf. When I finally got the ducks all lined up, the incomplete ppp.conf remained the final obstacle to a working internet connection. "One thing at a time ....". I know; I know... Thanks again. Hal ----- Original Message ----- From: Brian Somers > > Sorry, Brian, but I should have said that log was created by running a standard > > example ppp.conf script. Worse than that, I just copied part of the ppp.log into > > the email. I did not think to send the portion of the ppp.log that represented > > my successful session. In that session I did as you implied: set device ... set > > dial ... at ... atdt######## ... username ... password. But running a standard > > script, e.g. 'dial demand' wouldn't work. This install shares the hard drive > > with Win98. Perhaps that had something to do with it. > > > > I've since done an install of the same release, 4.2, on another removable hard > > drive inserted into the same bay in the same machine wherein FreeBSD has it all > > to itself. A result of that is I have a functional connection and am writing to > > you from that system. The ppp.conf script that I invoked was the sample that > > came with the the installation. All I did was give the command: 'dial papchap'. > > Well ... ok, I entered the correct dialer, cuaa4, and my ISP's phone #, my > > username, and password into the script. > > > > I'll try to get you a copy of that failed ppp session's log shortly, but I'm > > working on this other hard drive just now. > > Ok, thanks. > > > Thanks. > > > > Hal > > -- > Brian > > Don't _EVER_ lose your sense of humour ! ============================ failed ppp session from /var/log/ppp.log ============================ ... Feb 4 20:11:53 pdx ppp[182]: Phase: Using interface: tun0 Feb 4 20:11:53 pdx ppp[182]: Phase: deflink: Created in closed state Feb 4 20:11:53 pdx ppp[182]: tun0: Command: default: set device /dev/cuaa4 Feb 4 20:11:53 pdx ppp[182]: tun0: Command: default: set speed 115200 Feb 4 20:11:53 pdx ppp[182]: tun0: Phase: PPP Started (interactive mode). Feb 4 20:11:57 pdx ppp[182]: tun0: Command: /dev/tty: dial demand Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: set phone 8936990 Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: set login Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: set authname hweaverP Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: set authkey ******** Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: set timeout 120 Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0 Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: add default HISADDR Feb 4 20:11:57 pdx ppp[182]: tun0: Command: demand: enable dns Feb 4 20:11:57 pdx ppp[182]: tun0: Phase: bundle: Establish Feb 4 20:11:57 pdx ppp[182]: tun0: Phase: deflink: closed -> opening Feb 4 20:11:59 pdx ppp[182]: tun0: Phase: deflink: Connected! Feb 4 20:11:59 pdx ppp[182]: tun0: Phase: deflink: opening -> dial Feb 4 20:11:59 pdx ppp[182]: tun0: Chat: deflink: Dial attempt 1 of 1 Feb 4 20:11:59 pdx ppp[182]: tun0: Phase: deflink: dial -> carrier Feb 4 20:12:00 pdx ppp[182]: tun0: Phase: deflink: /dev/cuaa4 doesn't support CD Feb 4 20:12:00 pdx ppp[182]: tun0: Phase: deflink: carrier -> login Feb 4 20:12:00 pdx ppp[182]: tun0: Phase: deflink: login -> lcp Feb 4 20:12:00 pdx ppp[182]: tun0: LCP: FSM: Using "deflink" as a transport Feb 4 20:12:00 pdx ppp[182]: tun0: LCP: deflink: State change Initial --> Closed Feb 4 20:12:00 pdx ppp[182]: tun0: LCP: deflink: State change Closed --> Stopped Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: deflink: LayerStart Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x2f3229f5 Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: deflink: State change Stopped --> Req-Sent Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: deflink: RecvConfigReq(1) state = Req-Sent Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x2f3229f5 Feb 4 20:12:01 pdx ppp[182]: tun0: LCP: Magic is same (2f3229f5) - 1 times Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: deflink: SendConfigNak(1) state = Req-Sent Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x2f3229f5 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: deflink: RecvConfigNak(1) state = Req-Sent Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x2f3229f5 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: Magic 0x2f3229f5 is NAKed! Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: deflink: SendConfigReq(2) state = Req-Sent Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xd886c2e4 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: deflink: RecvConfigReq(2) state = Req-Sent Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xd886c2e4 Feb 4 20:12:02 pdx ppp[182]: tun0: LCP: Magic is same (d886c2e4) - 2 times Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: deflink: SendConfigNak(2) state = Req-Sent Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xd886c2e4 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: deflink: RecvConfigNak(2) state = Req-Sent Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xd886c2e4 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: Magic 0xd886c2e4 is NAKed! Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: deflink: SendConfigReq(3) state = Req-Sent Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xa6a21807 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: deflink: RecvConfigReq(3) state = Req-Sent Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xa6a21807 Feb 4 20:12:03 pdx ppp[182]: tun0: LCP: Magic is same (a6a21807) - 3 times Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: deflink: SendConfigNak(3) state = Req-Sent Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xa6a21807 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: deflink: RecvConfigNak(3) state = Req-Sent Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xa6a21807 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: Magic 0xa6a21807 is NAKed! Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: deflink: SendConfigReq(4) state = Req-Sent Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xeac793b2 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: deflink: RecvConfigReq(4) state = Req-Sent Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xeac793b2 Feb 4 20:12:05 pdx ppp[182]: tun0: LCP: Magic is same (eac793b2) - 4 times Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: deflink: SendConfigNak(4) state = Req-Sent Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xeac793b2 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: deflink: RecvConfigNak(4) state = Req-Sent Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xeac793b2 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: Magic 0xeac793b2 is NAKed! Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xb8fb9623 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: deflink: RecvConfigReq(5) state = Req-Sent Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xb8fb9623 Feb 4 20:12:07 pdx ppp[182]: tun0: LCP: Magic is same (b8fb9623) - 5 times Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: deflink: SendConfigNak(5) state = Req-Sent Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xb8fb9623 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: deflink: RecvConfigNak(5) state = Req-Sent Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0xb8fb9623 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: Magic 0xb8fb9623 is NAKed! Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: deflink: SendConfigReq(6) state = Req-Sent Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x3966afa0 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: deflink: RecvConfigReq(6) state = Req-Sent Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: ACFCOMP[2] Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: PROTOCOMP[2] Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: ACCMAP[6] 0x00000000 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: MRU[4] 1500 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x3966afa0 Feb 4 20:12:10 pdx ppp[182]: tun0: LCP: Magic is same (3966afa0) - 6 times Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: SendConfigNak(6) state = Req-Sent Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: MAGICNUM[6] 0x3966afa0 Feb 4 20:12:12 pdx ppp[182]: tun0: Command: /dev/tty: quit Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: SendTerminateReq(6) state = Req-Sent Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: State change Req-Sent --> Closing Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: RecvConfigNak(6) state = Closing Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: RecvTerminateReq(6) state = Closing Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: SendTerminateAck(6) state = Closing Feb 4 20:12:12 pdx ppp[182]: tun0: LCP: deflink: RecvTerminateAck(6), dropped (expected 7) Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: SendTerminateReq(7) state = Closing Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: RecvTerminateReq(7) state = Closing Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: SendTerminateAck(7) state = Closing Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: RecvTerminateAck(7) state = Closing Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: LayerFinish Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: State change Closing --> Closed Feb 4 20:12:15 pdx ppp[182]: tun0: LCP: deflink: State change Closed --> Initial Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: deflink: Disconnected! Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: deflink: lcp -> logout Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: deflink: logout -> hangup Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: deflink: Disconnected! Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: deflink: Connect time: 16 secs: 539 octets in, 539 octets out Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: deflink: : 33 packets in, 16 packets out Feb 4 20:12:15 pdx ppp[182]: tun0: Phase: total 67 bytes/sec, peak 156 bytes/sec on Sun Feb 4 20:12:15 2001 Feb 4 20:12:17 pdx ppp[182]: tun0: Phase: deflink: hangup -> closed Feb 4 20:12:17 pdx ppp[182]: tun0: Phase: bundle: Dead Feb 4 20:12:17 pdx ppp[182]: tun0: Phase: PPP Terminated (normal). ... To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-mobile" in the body of the message