Skip site navigation (1)Skip section navigation (2)
Date:      Mon, 12 Sep 2005 08:37:03 +0200
From:      ComteZero _ <comte0@gmail.com>
To:        freebsd-stable@freebsd.org
Subject:   problem with PPPoE STABLE and ADSL 3Com HomeConnect
Message-ID:  <1d881b2f05091123371d69f15a@mail.gmail.com>

next in thread | raw e-mail | index | archive | help
Hello,

it's been two weeks I try to find out what's wrong. Clean install from cvsu=
p=20
STABLE (5).
my ADSL account works fine with REL. 4.4+rp_pppoe but not with my new STABL=
E=20
(5) (without using rp_pppoe).
could someone help me on this issue (logs provided here)... two PADI are=20
emitted but nothing happens after.
(i saw that someone had a similar problem, but with previous netgraph=20
revisions).

thank you.

Since my ADSL modem is 3Com HomeConnect, I've set the=20
net.graph.nonstandard_pppoe=3D1

ng_pppoe.c rev. is 1.67.2.1 <http://1.67.2.1/>;
ng_socket.c rev. is 1.53.2.3 <http://1.53.2.3/>;

my ppp.conf is :
default:
set log all
set ifaddr X.X.X.X/0 10.0.0.2/0 <http://10.0.0.2/0>;

my_isp :
set device PPPoE:xl0
set authname MY_USER
set authkey MY_PWD
set dial
#set login
add default HISADDR


here is a tcpdump -vv -i xl0 :

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]


here is my 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 <http://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
=20
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=20
easyconnect (/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=20
easyconnect (/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
=20
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<http://0.0.0.0/0>;

Sep 11 19:42:23 fidelio ppp[24723]: tun0: TCP/IP: rt_Set: Gateway =3D
10.0.0.2<http://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 <http://0.0.0.0/0>, gateway =3D 10.0.0.2 <http://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 <none>

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

Thx,



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?1d881b2f05091123371d69f15a>