From owner-freebsd-questions Thu Apr 27 11:24:39 2000 Delivered-To: freebsd-questions@freebsd.org Received: from web3202.mail.yahoo.com (web3202.mail.yahoo.com [204.71.202.199]) by hub.freebsd.org (Postfix) with SMTP id CB5DB37B757 for ; Thu, 27 Apr 2000 11:24:23 -0700 (PDT) (envelope-from sky_tracker@yahoo.com) Message-ID: <20000427182421.8081.qmail@web3202.mail.yahoo.com> Received: from [216.191.61.140] by web3202.mail.yahoo.com; Thu, 27 Apr 2000 11:24:21 PDT Date: Thu, 27 Apr 2000 11:24:21 -0700 (PDT) From: Dave Banning Subject: Re: ppp error when ISP igns DNS each session To: andrew@ugh.net.au Cc: questions@freebsd.org MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG OK - so here is my ppp.log - it seems that now I am connected before being disconnected - here is my log which I have scanned but have to admit I find confusing. --- andrew@ugh.net.au wrote: > > > On Wed, 26 Apr 2000, Dave Banning wrote: > > > I can't seem to get a connection happening now - > log > > states "ppp chat script failed" > > > > The connection DOES work under Windows 95 - my > guess > > is that the problem lies in that I used to connect > > through a primary and secondary DNS and now this > > > igned DNS stuff doesn't want to mix with me under > > FreeBSD. > > No if the chat script failed you didnt get as far as > needing DNS. DNS is > only when you are using network applications that > need to from > name.domain.com -> 123.234.132.21. Try turning on > debugging and posting an > extract from ppp.log to the list. > > Andrew > Log follows; Apr 27 14:05:59 tracker1 ppp[264]: Phase: Using interface: tun0 Apr 27 14:05:59 tracker1 ppp[264]: Phase: deflink: Created in closed state Apr 27 14:05:59 tracker1 ppp[264]: Command: default: set speed 57600 Apr 27 14:05:59 tracker1 ppp[264]: Command: default: disable pred1 Apr 27 14:05:59 tracker1 ppp[264]: Command: default: deny pred1 Apr 27 14:05:59 tracker1 ppp[264]: Command: default: disable lqr Apr 27 14:05:59 tracker1 ppp[264]: Command: default: deny lqr Apr 27 14:05:59 tracker1 ppp[264]: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 "" AT OK-AT-OK ATE1Q0 OK \dATM0DT\T TIMEOUT 40 CONNECT Apr 27 14:05:59 tracker1 ppp[264]: Command: default: set redial 3 10 Apr 27 14:05:59 tracker1 ppp[264]: Phase: PPP Started (interactive mode). Apr 27 14:05:59 tracker1 ppp[264]: Debug: Select changes time: no Apr 27 14:06:03 tracker1 ppp[264]: Command: /dev/tty: dial ISP Apr 27 14:06:03 tracker1 ppp[264]: Debug: ReadSystem: Checking default (/etc/ppp/ppp.conf). Apr 27 14:06:03 tracker1 ppp[264]: Debug: ReadSystem: Checking ISP (/etc/ppp/ppp.conf). Apr 27 14:06:03 tracker1 ppp[264]: Debug: ReadSystem: Checking ISP (/etc/ppp/ppp.conf). Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set phone 6422952 Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set authname tracker1 Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set authkey ******** Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set timeout 300 Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set openmode active Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: accept chap Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set ifaddr 127.1.1.1/0 127.2.2.2/0 255.255.255.0 Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: add 0 0 127.2.2.2 Apr 27 14:06:03 tracker1 ppp[264]: Debug: wrote 140: cmd = Add, dst = 0, gateway = 202027f Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: enable dns Apr 27 14:06:03 tracker1 ppp[264]: Command: ISP: set redial 3 10 Apr 27 14:06:03 tracker1 ppp[264]: Phase: bundle: Establish Apr 27 14:06:03 tracker1 ppp[264]: Phase: deflink: closed -> opening Apr 27 14:06:03 tracker1 ppp[264]: Debug: deflink: Opened /dev/cuaa1 Apr 27 14:06:03 tracker1 ppp[264]: Debug: deflink: tty_Create: physical (get): fd = 7, iflag = 0, oflag = 0, cflag = 4b00 Apr 27 14:06:03 tracker1 ppp[264]: Debug: deflink: physical (put): iflag = 201, oflag = 0, cflag = 3cb00 Apr 27 14:06:03 tracker1 ppp[264]: Phase: deflink: Connected! Apr 27 14:06:03 tracker1 ppp[264]: Phase: deflink: opening -> dial Apr 27 14:06:03 tracker1 ppp[264]: Phase: Phone: 6422952 Apr 27 14:06:03 tracker1 ppp[264]: Chat: deflink: Dial attempt 1 of 10 Apr 27 14:06:03 tracker1 ppp[264]: Chat: Send: AT^M Apr 27 14:06:03 tracker1 ppp[264]: Chat: Expect(5): OK Apr 27 14:06:03 tracker1 ppp[264]: Chat: Received: AT^M^M Apr 27 14:06:03 tracker1 ppp[264]: Chat: Received: OK^M Apr 27 14:06:03 tracker1 ppp[264]: Chat: Send: ATE1Q0^M Apr 27 14:06:03 tracker1 ppp[264]: Chat: Expect(5): OK Apr 27 14:06:03 tracker1 ppp[264]: Chat: Received: ATE1Q0^M^M Apr 27 14:06:03 tracker1 ppp[264]: Chat: Received: OK^M Apr 27 14:06:03 tracker1 ppp[264]: Chat: Send: ATM0DT6422952^M Apr 27 14:06:05 tracker1 ppp[264]: Chat: Expect(40): CONNECT Apr 27 14:06:29 tracker1 ppp[264]: Chat: Received: ATM0DT6422952^M^M Apr 27 14:06:29 tracker1 ppp[264]: Chat: Received: CONNECT 45333/ARQ/V90/LAPM/V42BIS^M Apr 27 14:06:29 tracker1 ppp[264]: Phase: deflink: dial -> carrier Apr 27 14:06:29 tracker1 ppp[264]: Debug: deflink: Using tty_Timeout [0x806f23c] Apr 27 14:06:29 tracker1 ppp[264]: Debug: Waiting for carrier Apr 27 14:06:30 tracker1 ppp[264]: Debug: Waiting for carrier Apr 27 14:06:30 tracker1 ppp[264]: Phase: deflink: /dev/cuaa1: CD detected Apr 27 14:06:30 tracker1 ppp[264]: Phase: deflink: carrier -> login Apr 27 14:06:30 tracker1 ppp[264]: Debug: deflink: Entering tty_Raw Apr 27 14:06:30 tracker1 ppp[264]: Phase: deflink: login -> lcp Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: Still online Apr 27 14:06:31 tracker1 ppp[264]: Debug: fsm_Output Apr 27 14:06:31 tracker1 ppp[264]: Debug: 01 01 00 18 08 02 07 02 02 06 00 00 00 00 01 04 ................ Apr 27 14:06:31 tracker1 ppp[264]: Debug: 05 dc 05 06 2c 96 d3 1e ....,... Apr 27 14:06:31 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 1 Apr 27 14:06:31 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 1 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 54(54) to 7 Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 53/2048 from 7 Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:31 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: fsm_Output Apr 27 14:06:31 tracker1 ppp[264]: Debug: 02 cd 00 18 02 06 00 0a 00 00 03 04 c0 23 05 06 .............#.. Apr 27 14:06:31 tracker1 ppp[264]: Debug: 1c 6c 14 15 07 02 08 02 .l...... Apr 27 14:06:31 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 1 Apr 27 14:06:31 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 1 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 53(53) to 7 Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 53/2048 from 7 Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:31 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0xc021 Apr 27 14:06:31 tracker1 ppp[264]: Phase: bundle: Authenticate Apr 27 14:06:31 tracker1 ppp[264]: Phase: deflink: his = PAP, mine = none Apr 27 14:06:31 tracker1 ppp[264]: Debug: pap_Req: namelen = 8, keylen = 7 Apr 27 14:06:31 tracker1 ppp[264]: Phase: Pap Output: tracker1 ******** Apr 27 14:06:31 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0xc023 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0xc023 Apr 27 14:06:31 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 1 Apr 27 14:06:31 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 1 Apr 27 14:06:31 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Apr 27 14:06:31 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 27(27) to 7 Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: Still online Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 31/2048 from 7 Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:32 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0xc023 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0xc023 Apr 27 14:06:32 tracker1 ppp[264]: Phase: Pap Input: SUCCESS () Apr 27 14:06:32 tracker1 ppp[264]: Debug: fsm_Output Apr 27 14:06:32 tracker1 ppp[264]: Debug: 01 01 00 08 1a 04 78 00 ......x. Apr 27 14:06:32 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0x80fd Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0x80fd Apr 27 14:06:32 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 1 Apr 27 14:06:32 tracker1 ppp[264]: Phase: deflink: lcp -> open Apr 27 14:06:32 tracker1 ppp[264]: Phase: bundle: Network Apr 27 14:06:32 tracker1 ppp[264]: Debug: fsm_Output Apr 27 14:06:32 tracker1 ppp[264]: Debug: 01 01 00 1c 03 06 7f 01 01 01 02 06 00 2d 0f 01 .............-.. Apr 27 14:06:32 tracker1 ppp[264]: Debug: 81 06 d8 0d 55 0d 83 06 cf b5 65 04 ....U.....e. Apr 27 14:06:32 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0x8021 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0x8021 Apr 27 14:06:32 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 2 Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:32 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0x8021 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0x8021 Apr 27 14:06:32 tracker1 ppp[264]: Debug: fsm_Output Apr 27 14:06:32 tracker1 ppp[264]: Debug: 02 d6 00 0a 03 06 c0 a8 46 0b ........F. Apr 27 14:06:32 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0x8021 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0x8021 Apr 27 14:06:32 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 3 Apr 27 14:06:32 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 3 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 2 more packets Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 14(14) to 7 Apr 27 14:06:32 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 2 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 1 more packets Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 34(34) to 7 Apr 27 14:06:32 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 1 Apr 27 14:06:32 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Apr 27 14:06:32 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 16(16) to 7 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 22/2048 from 7 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:33 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0xc021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0xc021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 18/2048 from 7 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:33 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0x8021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0x8021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: fsm_Output Apr 27 14:06:33 tracker1 ppp[264]: Debug: 01 02 00 16 03 06 7f 01 01 01 81 06 d8 0d 55 0d ..............U. Apr 27 14:06:33 tracker1 ppp[264]: Debug: 83 06 cf b5 65 04 ....e. Apr 27 14:06:33 tracker1 ppp[264]: Debug: proto_LayerPush: Using 0x8021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: link_PushPacket: Transmit proto 0x8021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: mbuf_Enqueue: len = 1 Apr 27 14:06:33 tracker1 ppp[264]: Debug: mbuf_Dequeue: queue len = 1 Apr 27 14:06:33 tracker1 ppp[264]: Debug: link_Dequeue: Dequeued from queue 1, containing 0 more packets Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: DescriptorWrite: wrote 28(28) to 7 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 28/2048 from 7 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: DescriptorRead: read 2/2048 from 7 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: hdlc_Input: fcs = f0b8 (good) Apr 27 14:06:33 tracker1 ppp[264]: Debug: proto_LayerPull: unknown -> 0x8021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: link_PullPacket: Despatch proto 0x8021 Apr 27 14:06:33 tracker1 ppp[264]: Debug: Add 127.1.1.1 -> 192.168.70.11 Apr 27 14:06:33 tracker1 ppp[264]: Debug: ReadSystem: Checking 127.1.1.1 (/etc/ppp/ppp.linkup). Apr 27 14:06:33 tracker1 ppp[264]: Debug: ReadSystem: Checking ISP (/etc/ppp/ppp.linkup). Apr 27 14:06:33 tracker1 ppp[264]: Debug: ReadSystem: Checking MYADDR (/etc/ppp/ppp.linkup). Apr 27 14:06:33 tracker1 ppp[264]: Command: MYADDR: delete ALL Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete (2) Apr 27 14:06:33 tracker1 ppp[264]: Debug: Found the following interfaces: Apr 27 14:06:33 tracker1 ppp[264]: Debug: Index 1, name "lp0" Apr 27 14:06:33 tracker1 ppp[264]: Debug: Index 2, name "tun0" Apr 27 14:06:33 tracker1 ppp[264]: Debug: Index 3, name "sl0" Apr 27 14:06:33 tracker1 ppp[264]: Debug: Index 4, name "ppp0" Apr 27 14:06:33 tracker1 ppp[264]: Debug: Index 5, name "lo0" Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete: addrs: 7, Netif: 5 (lo0), flags: 10803, dst: 0.0.0.0 ? Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete: addrs: 3, Netif: 5 (lo0), flags: 200005, dst: 127.0.0.1 ? Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete: addrs: 3, Netif: 2 (tun0), flags: 5, dst: 192.168.70.11 ? Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete: addrs: 7, Netif: 5 (lo0), flags: 10803, dst: 0.0.0.0 ? Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete: addrs: 3, Netif: 5 (lo0), flags: 200005, dst: 127.0.0.1 ? Apr 27 14:06:33 tracker1 ppp[264]: Debug: route_IfDelete: addrs: 3, Netif: 2 (tun0), flags: 5, dst: 192.168.70.11 ? Apr 27 14:06:33 tracker1 ppp[264]: Command: MYADDR: add default HISADDR Apr 27 14:06:33 tracker1 ppp[264]: Warning: Add route failed: default already exists Apr 27 14:06:33 tracker1 ppp[264]: Debug: wrote -1: cmd = Add, dst = 0, gateway = b46a8c0 Apr 27 14:06:33 tracker1 ppp[264]: Debug: deflink: Still online __________________________________________________ Do You Yahoo!? Talk to your friends online and get email alerts with Yahoo! Messenger. http://im.yahoo.com/ To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message