Date: Sat, 22 Jun 2002 23:13:03 -0400 From: Andrew Lankford <arlankfo@141.com> To: Brian Somers <brian@Awfulhak.org>, freebsd-net@freebsd.org Subject: Re: More pppoe debugging info Message-ID: <200206222114390.SM01584@141.com> In-Reply-To: Your message of "Sun, 23 Jun 2002 00:17:25 BST." <20020623001725.214aa17f.brian@Awfulhak.org>
next in thread | previous in thread | raw e-mail | index | archive | help
Another one.... This may not prove to be very helpful, because this wasn't a successful connection. After rebooting and (on a wild hunch) typing sh /etc/rc.firewall open My connection came up a minute or two later. I don't remember the ipfw rules interfering with my ppp connection before, but... ... perhaps it's a coincidence and something else is going on. The connection only comes up after lots of attempts long after I boot up when I boot up with rc.firewall in "open". Think I'll rebuild world and build a kernel with ipfw disabled. Andrew Lankford VERSION INFO: FreeBSD bogushost2 4.6-STABLE FreeBSD 4.6-STABLE #2: Sat Jun 22 20:47:48 EDT 2002 (same src tree as before,but a recompile) Same ppp compile as before. PPP.CONF: default: allow users arlankfo nat unregistered_only yes set log +lcp +debug +physical ipcp phase tun command ccp verizondsl: # deny pap # deny chap # accept chap81 set socket /var/ppp/ppp "" 0117 set device PPPoE:xl0 set authname ******* set authkey ******** set speed sync set mru 1492 set redial 2 set timeout 0 # enable lqr to see if this will deal with bad connections or disconnects? enable lqr set dial set login set ifaddr 10.10.10.9/0 10.1.32.1/0 set urgent udp +53 # DNS searches faster? # enable dns add default HISADDR # dial PPP.LOG: Jun 22 21:03:06 bogushost2 ppp[294]: Phase: Using interface: tun0 Jun 22 21:03:06 bogushost2 ppp[294]: Phase: deflink: Created in closed state Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Debug: ReadSystem: Checking verizondsl (/etc/ppp/ppp.conf). Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set socket /var/ppp/ppp 0117 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Phase: Listening at local socket /var/ppp/ppp. Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set device PPPoE:xl0 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set authname vze2ztys Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set authkey ******** Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set speed sync Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set mru 1492 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set redial 2 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set timeout 0 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: enable lqr Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set dial Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set login Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set ifaddr 10.10.10.9/0 10.1.32.1/0 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Debug: Add 10.10.10.9 -> 10.1.32.1 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: set urgent udp +53 Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Command: verizondsl: add default HISADDR Jun 22 21:03:06 bogushost2 ppp[294]: tun0: Debug: wrote 140: cmd = Add, dst = 0, gateway = 120010a Jun 22 21:03:06 bogushost2 ppp[295]: tun0: Phase: PPP Started (auto mode). Jun 22 21:03:06 bogushost2 ppp[295]: tun0: Debug: Select changes time: no Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: Connected to local client. Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Command: /var/ppp/ppp: dial Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: bundle: Establish Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: deflink: closed -> opening Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Debug: List of netgraph node ``xl0:'' (id 2) hooks: Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Debug: Found orphans -> ethernet Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Debug: Connecting netgraph socket .:tun0 -> [4]::tun0 Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Debug: Sending PPPOE_CONNECT to .:tun0 Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: deflink: Connected! Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: deflink: opening -> dial Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: deflink: dial -> carrier Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Debug: Waiting for carrier Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Phase: /var/ppp/ppp: Client connection closed. Jun 22 21:03:13 bogushost2 ppp[295]: tun0: Debug: Waiting for carrier Jun 22 21:03:15 bogushost2 last message repeated 2 times Jun 22 21:03:16 bogushost2 ppp[295]: tun0: Phase: Received NGM_PPPOE_ACNAME (hook "RES-6400-3-NRP2") Jun 22 21:03:16 bogushost2 ppp[295]: tun0: Debug: Waiting for carrier Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: deflink: Disconnected! Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: deflink: carrier -> hangup Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Debug: deflink: Close Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: deflink: Connect time: 4 secs: 0 octets in, 0 octets out Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: deflink: : 0 packets in, 0 packets out Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: total 0 bytes/sec, peak 0 bytes/sec on Sat Jun 22 21:03:13 2002 Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: deflink: hangup -> closed Jun 22 21:03:17 bogushost2 ppp[295]: tun0: Phase: bundle: Dead TCPDUMP OUTPUT: 21:03:13.526350 0:1:2:73:ee:49 Broadcast 8863 32: PPPoE PADI [Host-Uniq UTF8] 21:03:15.523805 0:1:2:73:ee:49 Broadcast 8863 32: PPPoE PADI [Host-Uniq UTF8] 21:03:15.551092 0:4:c1:48:cb:13 0:1:2:73:ee:49 8863 71: PPPoE PADO [Host-Uniq UTF8] [Service-Name] [AC-Name "RES-6400-3-NRP2"] [AC-Cookie UTF8] 21:03:15.551140 0:1:2:73:ee:49 0:4:c1:48:cb:13 8863 71: PPPoE PADR [Host-Uniq UTF8] [AC-Cookie UTF8] [AC-Name "RES-6400-3-NRP2"] 21:03:15.610007 0:4:c1:48:cb:13 0:1:2:73:ee:49 8863 71: PPPoE PADS [ses 0x9bc0] [Host-Uniq UTF8] [AC-Cookie UTF8] [AC-Name "RES-6400-3-NRP2"] 21:03:15.632923 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(74), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:17.565353 0:1:2:73:ee:49 0:4:c1:48:cb:13 8863 38: PPPoE PADT [ses 0x9bc0] [Generic-Error "session closed"] 21:03:17.643232 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(75), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:19.644930 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(76), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:21.645385 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(77), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:23.646871 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(78), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:25.649299 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(79), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:27.662022 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(80), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:29.660258 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(81), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:31.662180 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(82), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:33.664153 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(83), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:35.665355 0:4:c1:48:cb:13 0:1:2:73:ee:49 8864 60: PPPoE [ses 0x9bc0] LCP 20: Conf-Req(84), MRU=1492, Auth-Prot PAP, Magic-Num=cd931399 21:03:37.666760 0:4:c1:48:cb:13 0:1:2:73:ee:49 8863 60: PPPoE PADT [ses 0x9bc0] To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-net" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200206222114390.SM01584>