From owner-freebsd-net@FreeBSD.ORG Wed Sep 14 14:28:53 2005 Return-Path: X-Original-To: freebsd-net@freebsd.org Delivered-To: freebsd-net@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 2DA1C16A420 for ; Wed, 14 Sep 2005 14:28:53 +0000 (GMT) (envelope-from comte0@gmail.com) Received: from nproxy.gmail.com (nproxy.gmail.com [64.233.182.196]) by mx1.FreeBSD.org (Postfix) with ESMTP id 934E943D46 for ; Wed, 14 Sep 2005 14:28:50 +0000 (GMT) (envelope-from comte0@gmail.com) Received: by nproxy.gmail.com with SMTP id a4so73588nfc for ; Wed, 14 Sep 2005 07:28:50 -0700 (PDT) DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws; s=beta; d=gmail.com; h=received:message-id:date:from:reply-to:to:subject:in-reply-to:mime-version:content-type:references; b=h90j0vEdeukm3tRwoRNoY7P0GUkzT8oMLuwd9lyNSE85XSbAW8ecHddBOJUej71iUWwd4FGe5kvGNSPa2bSG1qK9hFQRaXtly3NGevfIXCttL1zTUC3WBV4Vad1A0dHOnWYyiJFxGGrNavKgCq6R114CFpop5be9t+g2v+5Xnsg= Received: by 10.49.5.13 with SMTP id h13mr38570nfi; Wed, 14 Sep 2005 07:28:50 -0700 (PDT) Received: by 10.48.157.6 with HTTP; Wed, 14 Sep 2005 07:28:49 -0700 (PDT) Message-ID: <1d881b2f05091407283fcaa73f@mail.gmail.com> Date: Wed, 14 Sep 2005 16:28:50 +0200 From: ComteZero _ To: freebsd-net@freebsd.org In-Reply-To: <1d881b2f050914072350d79a65@mail.gmail.com> Mime-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_1337_6303685.1126708130023" References: <1d881b2f050914072350d79a65@mail.gmail.com> X-Content-Filtered-By: Mailman/MimeDel 2.1.5 Subject: Re: PPPoE (STABLE 5) : two PADI packets emitted and then nothing... X-BeenThere: freebsd-net@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: comte0@gmail.com List-Id: Networking and TCP/IP with FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 14 Sep 2005 14:28:53 -0000 ------=_Part_1337_6303685.1126708130023 Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: quoted-printable Content-Disposition: inline here is ppp.log in plain text : here is ppp.log : Sep 11 19:42:17 fidelio ppp[24723]: Phase: Using interface: tun0 Sep 11 19:42:17 fidelio ppp[24723]: Phase: deflink: Created in closed state Sep 11 19:42:17 fidelio ppp[24723]: tun0: Command: default: set ifaddr=20 X.X.X.X/0 10.0.0.2/0 Sep 11 19:42:17 fidelio ppp[24723]: tun0: Phase: PPP Started (interactive= =20 mode). Sep 11 19:42:17 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:23 fidelio ppp[24723]: tun0: Timer: Select returns 1 Sep 11 19:42:23 fidelio ppp[24723]: tun0: Command: /dev/ttyv0: load MY_ISP Sep 11 19:42:23 fidelio ppp[24723]: tun0: ID0: 0x282e97c0 =3D=20 fopen("/etc/ppp/ppp.conf", "r") Sep 11 19:42:23 fidelio ppp[24723]: tun0: Debug: ReadSystem: Checking=20 default (/etc/ppp/ppp.conf). Sep 11 19:42:23 fidelio ppp[24723]: tun0: ID0: 0x282e97c0 =3D=20 fopen("/etc/ppp/ppp.conf", "r") Sep 11 19:42:23 fidelio ppp[24723]: tun0: Debug: ReadSystem: Checking MY_IS= P=20 (/etc/ppp/ppp.conf). Sep 11 19:42:23 fidelio ppp[24723]: tun0: ID0: 0x282e97c0 =3D=20 fopen("/etc/ppp/ppp.conf", "r") Sep 11 19:42:23 fidelio ppp[24723]: tun0: Debug: ReadSystem: Checking MY_IS= P=20 (/etc/ppp/ppp.conf). Sep 11 19:42:23 fidelio ppp[24723]: tun0: Command: easyconnect: set device= =20 PPPoE:xl0 Sep 11 19:42:23 fidelio ppp[24723]: tun0: Command: easyconnect: set authnam= e=20 MY_USER Sep 11 19:42:23 fidelio ppp[24723]: tun0: Command: easyconnect: set authkey= =20 ******** Sep 11 19:42:23 fidelio ppp[24723]: tun0: Command: easyconnect: set dial Sep 11 19:42:23 fidelio ppp[24723]: tun0: Command: easyconnect: add default= =20 HISADDR Sep 11 19:42:23 fidelio ppp[24723]: tun0: ID0: 3 =3D socket(17, 3, 0) Sep 11 19:42:23 fidelio ppp[24723]: tun0: ID0: -1 =3D write(3, data, 140) Sep 11 19:42:23 fidelio ppp[24723]: tun0: TCP/IP: rt_Set failure: Sep 11 19:42:23 fidelio ppp[24723]: tun0: TCP/IP: rt_Set: Cmd =3D Add Sep 11 19:42:23 fidelio ppp[24723]: tun0: TCP/IP: rt_Set: Dst =3D 0.0.0.0/0 Sep 11 19:42:23 fidelio ppp[24723]: tun0: TCP/IP: rt_Set: Gateway =3D 10.0.0.2 Sep 11 19:42:23 fidelio ppp[24723]: tun0: Debug: wrote -1: cmd =3D Add, dst= =3D=20 0.0.0.0/0 , gateway =3D 10.0.0.2 Sep 11 19:42:23 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:26 fidelio ppp[24723]: tun0: Timer: Select returns 1 Sep 11 19:42:26 fidelio ppp[24723]: tun0: Command: /dev/ttyv0: dial Sep 11 19:42:26 fidelio ppp[24723]: tun0: Phase: bundle: Establish Sep 11 19:42:26 fidelio ppp[24723]: tun0: Phase: deflink: closed -> opening Sep 11 19:42:26 fidelio ppp[24723]: tun0: ID0: 0 =3D NgMkSockNode("", &cs,= =20 &ds) Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: List of netgraph node=20 ``xl0:'' (id 2) hooks: Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Found orphans -> ethernet Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Connecting netgraph socket= =20 .:tun0 -> [4]::tun0 Sep 11 19:42:26 fidelio ppp[24723]: tun0: ID0: 4 =3D socket(2, 2, 0) Sep 11 19:42:26 fidelio ppp[24723]: tun0: ID0: 0 =3D ioctl(4, 3223349521,= =20 0xbfbfdd90) Sep 11 19:42:26 fidelio ppp[24723]: tun0: ID0: 0 =3D ioctl(4, 2149607696,= =20 0xbfbfdd90) Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Sending PPPOE_CONNECT to= =20 .:tun0 Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Found the following=20 interfaces: Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Index 1, name "fxp0" Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Index 2, name "xl0" Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Index 3, name "plip0" Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Index 4, name "lo0" Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Index 5, name "tun0" Sep 11 19:42:26 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting=20 physical throughput timer[0x80be068] Sep 11 19:42:26 fidelio ppp[24723]: tun0: Phase: deflink: Connected! Sep 11 19:42:26 fidelio ppp[24723]: tun0: Phase: deflink: opening -> dial Sep 11 19:42:26 fidelio ppp[24723]: tun0: Chat: deflink: Dial attempt 1 of = 1 Sep 11 19:42:26 fidelio ppp[24723]: tun0: Phase: deflink: dial -> carrier Sep 11 19:42:26 fidelio ppp[24723]: tun0: Debug: Waiting for carrier Sep 11 19:42:26 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:27 fidelio ppp[24723]: tun0: Timer: Select returns -1 Sep 11 19:42:27 fidelio ppp[24723]: tun0: Timer: ---- Begin of Timer Servic= e=20 List--- Sep 11 19:42:27 fidelio ppp[24723]: tun0: Timer: physical throughput=20 timer[0x80be068]: freq =3D 1.00s, next =3D 0.00s, state =3D running Sep 11 19:42:27 fidelio ppp[24723]: tun0: Timer: ---- End of Timer Service= =20 List --- Sep 11 19:42:27 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting=20 physical throughput timer[0x80be068] Sep 11 19:42:27 fidelio ppp[24723]: tun0: Debug: Waiting for carrier Sep 11 19:42:27 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:28 fidelio ppp[24723]: tun0: Timer: Select returns -1 Sep 11 19:42:28 fidelio ppp[24723]: tun0: Timer: ---- Begin of Timer Servic= e=20 List--- Sep 11 19:42:28 fidelio ppp[24723]: tun0: Timer: physical throughput=20 timer[0x80be068]: freq =3D 1.00s, next =3D 0.00s, state =3D running Sep 11 19:42:28 fidelio ppp[24723]: tun0: Timer: ---- End of Timer Service= =20 List --- Sep 11 19:42:28 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting=20 physical throughput timer[0x80be068] Sep 11 19:42:28 fidelio ppp[24723]: tun0: Debug: Waiting for carrier Sep 11 19:42:28 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:29 fidelio ppp[24723]: tun0: Timer: Select returns -1 Sep 11 19:42:29 fidelio ppp[24723]: tun0: Timer: ---- Begin of Timer Servic= e=20 List--- Sep 11 19:42:29 fidelio ppp[24723]: tun0: Timer: physical throughput=20 timer[0x80be068]: freq =3D 1.00s, next =3D 0.00s, state =3D running Sep 11 19:42:29 fidelio ppp[24723]: tun0: Timer: ---- End of Timer Service= =20 List --- Sep 11 19:42:29 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting=20 physical throughput timer[0x80be068] Sep 11 19:42:29 fidelio ppp[24723]: tun0: Debug: Waiting for carrier Sep 11 19:42:29 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:30 fidelio ppp[24723]: tun0: Timer: Select returns -1 Sep 11 19:42:30 fidelio ppp[24723]: tun0: Timer: ---- Begin of Timer Servic= e=20 List--- Sep 11 19:42:30 fidelio ppp[24723]: tun0: Timer: physical throughput=20 timer[0x80be068]: freq =3D 1.00s, next =3D 0.00s, state =3D running Sep 11 19:42:30 fidelio ppp[24723]: tun0: Timer: ---- End of Timer Service= =20 List --- Sep 11 19:42:30 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting=20 physical throughput timer[0x80be068] Sep 11 19:42:30 fidelio ppp[24723]: tun0: Debug: Waiting for carrier Sep 11 19:42:30 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: Select returns -1 Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: ---- Begin of Timer Servic= e=20 List--- Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: physical throughput=20 timer[0x80be068]: freq =3D 1.00s, next =3D 0.00s, state =3D running Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: ---- End of Timer Service= =20 List --- Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting=20 physical throughput timer[0x80be068] Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: deflink: Disconnected! Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: deflink: carrier -> hangup Sep 11 19:42:31 fidelio ppp[24723]: tun0: Debug: deflink: Close Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: deflink: Connect time: 5= =20 secs: 0 octets in, 0 octets out Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: deflink: 0 packets in, 0= =20 packets out Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: total 0 bytes/sec, peak 0= =20 bytes/sec on Sun Sep 11 19:42:26 2005 Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: deflink: hangup -> closed Sep 11 19:42:31 fidelio ppp[24723]: tun0: Debug: route_IfDelete (5) Sep 11 19:42:31 fidelio ppp[24723]: tun0: Debug: Found ff02:5::/32=20 fe80:5::208:c7ff:fe7f:7535 Sep 11 19:42:31 fidelio ppp[24723]: tun0: Debug: route_IfDelete: Skip it=20 (pass 0) Sep 11 19:42:31 fidelio ppp[24723]: tun0: Debug: Found ff02:5::/32=20 fe80:5::208:c7ff:fe7f:7535 Sep 11 19:42:31 fidelio ppp[24723]: tun0: ID0: 2 =3D socket(17, 3, 0) Sep 11 19:42:31 fidelio ppp[24723]: tun0: ID0: 148 =3D write(2, data, 148) Sep 11 19:42:31 fidelio ppp[24723]: tun0: Debug: wrote 148: cmd =3D Delete,= =20 dst =3D ff02:5::/32, gateway =3D Sep 11 19:42:31 fidelio ppp[24723]: tun0: ID0: 2 =3D socket(2, 2, 0) Sep 11 19:42:31 fidelio ppp[24723]: tun0: ID0: 0 =3D ioctl(2, 3223349521,= =20 0xbfbfe970) Sep 11 19:42:31 fidelio ppp[24723]: tun0: ID0: 0 =3D ioctl(2, 2149607696,= =20 0xbfbfe970) Sep 11 19:42:31 fidelio ppp[24723]: tun0: Phase: bundle: Dead Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: timer_Start: Inserting dia= l=20 timer[0x80bcd44] Sep 11 19:42:31 fidelio ppp[24723]: tun0: Timer: prompt /dev/ttyv0: fdset(r= )=20 0 On 9/14/05, ComteZero _ wrote: >=20 > Hello, >=20 > I already posted this thread in freebsd-stable but seems that this list i= s=20 > more appropriate. >=20 > it's been two weeks I try to find out what's wrong. Clean install from=20 > cvsup STABLE (5). > my ADSL account works fine with REL. 4.4+rp_pppoe but not with my new=20 > STABLE (5) (without using rp_pppoe). > could someone help me on this issue (logs provided here, ppp.log in=20 > attached file)...=20 > two PADI are emitted but nothing happens after. > (i saw that someone had a similar problem, but with previous netgraph=20 > revisions). >=20 > thank you. >=20 > Since my ADSL modem is 3Com HomeConnect, I've set the=20 > net.graph.nonstandard_pppoe=3D1 >=20 > ng_pppoe.c rev. is 1.67.2.1 > ng_socket.c rev. is 1.53.2.3 >=20 > my ppp.conf is : > default: > set log all > set ifaddr X.X.X.X/0 10.0.0.2/0 >=20 > my_isp : > set device PPPoE:xl0 > set authname MY_USER > set authkey MY_PWD > set dial > #set login > add default HISADDR >=20 >=20 > here is a tcpdump -vv -i xl0 : >=20 > 18:48:40.808687 PPPoE PADI [Host-Uniq 0x00E654C1] > 18:48:42.807533 PPPoE PADI [Host-Uniq 0x00E654C1] > 18:51:44.010839 PPPoE PADI [Host-Uniq 0x40F195C1] > 18:51:46.009639 PPPoE PADI [Host-Uniq 0x40F195C1] > =20 > thx for your comments. > =20 > ------=_Part_1337_6303685.1126708130023--