PPPoE stuff

PPPoE stuff

[Ionut Turturica]

Any hints for this?
I'm trying to connect a XP machine with a rp-pppoe server. I see that XP  
client keeps requesting CallBack, and we refuse it..

Feb  6 22:06:22 lfs pppoe-server[31771]: Session 2 created for client  
00:11:2f:22:c0:7a (10.67.15.2) on br0 using Service-Name ''
Feb  6 22:06:22 lfs pppd[31771]: pppd 2.4.4b1 started by root, uid 0
Feb  6 22:06:22 lfs pppd[31771]: using channel 495
Feb  6 22:06:22 lfs pppd[31771]: Using interface ppp1
Feb  6 22:06:22 lfs pppd[31771]: Connect: ppp1 <--> /dev/pts/11
Feb  6 22:06:23 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:26 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:27 lfs pppd[31771]: rcvd [LCP ConfReq id=0x2 <mru 1480>  
<magic 0x4c9160fa> <callback CBCP>]
Feb  6 22:06:27 lfs pppd[31771]: sent [LCP ConfRej id=0x2 <callback CBCP>]
Feb  6 22:06:29 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:32 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:35 lfs pppd[31771]: rcvd [LCP ConfReq id=0x4 <mru 1480>  
<magic 0x4c9160fa> <callback CBCP>]
Feb  6 22:06:35 lfs pppd[31771]: sent [LCP ConfRej id=0x4 <callback CBCP>]
Feb  6 22:06:35 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:41 lfs last message repeated 2 times
Feb  6 22:06:42 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]:  
buffering packet 1003 (expecting 1000, lost or reordered)
Feb  6 22:06:43 lfs pppd[31771]: rcvd [LCP ConfReq id=0x6 <mru 1480>  
<magic 0x4c9160fa> <callback CBCP>]
Feb  6 22:06:43 lfs pppd[31771]: sent [LCP ConfRej id=0x6 <callback CBCP>]
Feb  6 22:06:44 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:46 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]:  
buffering packet 1012 (expecting 1011, lost or reordered)
Feb  6 22:06:47 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:50 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  
<magic 0x3d25fa22>]
Feb  6 22:06:51 lfs pppd[31771]: rcvd [LCP ConfReq id=0x8 <mru 1480>  
<magic 0x4c9160fa> <callback CBCP>]
Feb  6 22:06:51 lfs pppd[31771]: sent [LCP ConfRej id=0x8 <callback CBCP>]
Feb  6 22:06:53 lfs pppd[31771]: LCP: timeout sending Config-Requests
Feb  6 22:06:53 lfs pppd[31771]: Connection terminated.
Feb  6 22:06:53 lfs pppd[31771]: Modem hangup
Feb  6 22:06:53 lfs pppd[31771]: Waiting for 1 child processes...
Feb  6 22:06:53 lfs pppd[31771]:   script /usr/sbin/pppoe -n -I br0 -e  
2:00:11:2f:22:c0:7a -S '', pid 31772
Feb  6 22:06:57 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]:  
buffering packet 1075 (expecting 1074, lost or reordered)
Feb  6 22:06:58 lfs pppd[31771]: sending SIGTERM to process 31772
Feb  6 22:06:58 lfs pppd[31771]: Exit.
Feb  6 22:06:58 lfs pppoe-server[31611]: Session 2 closed for client  
00:11:2f:22:c0:7a (10.67.15.2) on br0
Feb  6 22:06:58 lfs pppoe-server[31611]: Sent PADT

Re: PPPoE stuff

[James Carlson]

Ionut Turturica writes:
> Any hints for this?
> I'm trying to connect a XP machine with a rp-pppoe server. I see that XP  
> client keeps requesting CallBack, and we refuse it..

The lack of response to your LCP Configure-Request messages (he should
have sent at least an ack or nak) seems to indicate that this is a
low-level communications problem of some sort.  No packets are getting
through at all, so negotiation is simply impossible.  PPP is running,
but the PPPoE mechanism is failing.

> Feb  6 22:06:42 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]:  
> buffering packet 1003 (expecting 1000, lost or reordered)
[...]
> Feb  6 22:06:58 lfs pppoe-server[31611]: Sent PADT

That's sort of scary.  Is this PPTP, PPPoE, or what?  Why messages
from both?  (Or are those just unrelated?)

-- 
James Carlson         42.703N 71.076W         <carlsonj@workingcode.com>

Re: PPPoE stuff

[Bill Unruh]

On Mon, 6 Feb 2006, Ionut Turturica wrote:

>
> Any hints for this?
> I'm trying to connect a XP machine with a rp-pppoe server. I see that XP 
> client keeps requesting CallBack, and we refuse it..

The remote machine refuses to do anything. In fact there is no evidence it
is receiving your messages at all. It responds to nothing you send it.

Are you sure that the two machines can speak to each other?

>
> Feb  6 22:06:22 lfs pppoe-server[31771]: Session 2 created for client 
> 00:11:2f:22:c0:7a (10.67.15.2) on br0 using Service-Name ''
> Feb  6 22:06:22 lfs pppd[31771]: pppd 2.4.4b1 started by root, uid 0
> Feb  6 22:06:22 lfs pppd[31771]: using channel 495
> Feb  6 22:06:22 lfs pppd[31771]: Using interface ppp1
> Feb  6 22:06:22 lfs pppd[31771]: Connect: ppp1 <--> /dev/pts/11
> Feb  6 22:06:23 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:26 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:27 lfs pppd[31771]: rcvd [LCP ConfReq id=0x2 <mru 1480>  <magic 
> 0x4c9160fa> <callback CBCP>]
> Feb  6 22:06:27 lfs pppd[31771]: sent [LCP ConfRej id=0x2 <callback CBCP>]
> Feb  6 22:06:29 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:32 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:35 lfs pppd[31771]: rcvd [LCP ConfReq id=0x4 <mru 1480>  <magic 
> 0x4c9160fa> <callback CBCP>]
> Feb  6 22:06:35 lfs pppd[31771]: sent [LCP ConfRej id=0x4 <callback CBCP>]
> Feb  6 22:06:35 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:41 lfs last message repeated 2 times
> Feb  6 22:06:42 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]: 
> buffering packet 1003 (expecting 1000, lost or reordered)
> Feb  6 22:06:43 lfs pppd[31771]: rcvd [LCP ConfReq id=0x6 <mru 1480>  <magic 
> 0x4c9160fa> <callback CBCP>]
> Feb  6 22:06:43 lfs pppd[31771]: sent [LCP ConfRej id=0x6 <callback CBCP>]
> Feb  6 22:06:44 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:46 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]: 
> buffering packet 1012 (expecting 1011, lost or reordered)
> Feb  6 22:06:47 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:50 lfs pppd[31771]: sent [LCP ConfReq id=0x1 <auth pap>  <magic 
> 0x3d25fa22>]
> Feb  6 22:06:51 lfs pppd[31771]: rcvd [LCP ConfReq id=0x8 <mru 1480>  <magic 
> 0x4c9160fa> <callback CBCP>]
> Feb  6 22:06:51 lfs pppd[31771]: sent [LCP ConfRej id=0x8 <callback CBCP>]
> Feb  6 22:06:53 lfs pppd[31771]: LCP: timeout sending Config-Requests
> Feb  6 22:06:53 lfs pppd[31771]: Connection terminated.
> Feb  6 22:06:53 lfs pppd[31771]: Modem hangup
> Feb  6 22:06:53 lfs pppd[31771]: Waiting for 1 child processes...
> Feb  6 22:06:53 lfs pppd[31771]:   script /usr/sbin/pppoe -n -I br0 -e 
> 2:00:11:2f:22:c0:7a -S '', pid 31772
> Feb  6 22:06:57 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]: 
> buffering packet 1075 (expecting 1074, lost or reordered)
> Feb  6 22:06:58 lfs pppd[31771]: sending SIGTERM to process 31772
> Feb  6 22:06:58 lfs pppd[31771]: Exit.
> Feb  6 22:06:58 lfs pppoe-server[31611]: Session 2 closed for client 
> 00:11:2f:22:c0:7a (10.67.15.2) on br0
> Feb  6 22:06:58 lfs pppoe-server[31611]: Sent PADT
>
> -
> To unsubscribe from this list: send the line "unsubscribe linux-ppp" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html
>

-- 
William G. Unruh   |  Canadian Institute for|     Tel: +1(604)822-3273
Physics&Astronomy  |     Advanced Research  |     Fax: +1(604)822-5324
UBC, Vancouver,BC  |   Program in Cosmology |     unruh@physics.ubc.ca
Canada V6T 1Z1     |      and Gravity       |  www.theory.physics.ubc.ca/

Re: PPPoE stuff

[Jar]

Ionut Turturica wrote:

> Feb  6 22:06:22 lfs pppoe-server[31771]: Session 2 created for client  
> 00:11:2f:22:c0:7a (10.67.15.2) on br0 using Service-Name ''
> Feb  6 22:06:53 lfs pppd[31771]:   script /usr/sbin/pppoe -n -I br0 -e  
> 2:00:11:2f:22:c0:7a -S '', pid 31772

Why you run pppoe over bridge interface br0? Is that going to work? 
Which bridge port it is going to use? Usually pppoe is running over 
ethernet device, it doesn't need ip interface. Interface can have an ip, 
but pppoe doesn't use it.

-- 
Best Regards, Jar

Re: PPPoE stuff

[Ionut Turturica]

I bridged a tap device + eth0 and resulted br0, I see no problem with  
this. PPPoE does the initial handshake and starts a new session then  
passes to pppd which fails..

On Mon, 06 Feb 2006 22:36:45 +0200, Jar <jar@pcuf.fi> wrote:

> Ionut Turturica wrote:
>
>> Feb  6 22:06:22 lfs pppoe-server[31771]: Session 2 created for client   
>> 00:11:2f:22:c0:7a (10.67.15.2) on br0 using Service-Name ''
>> Feb  6 22:06:53 lfs pppd[31771]:   script /usr/sbin/pppoe -n -I br0 -e   
>> 2:00:11:2f:22:c0:7a -S '', pid 31772
>
> Why you run pppoe over bridge interface br0? Is that going to work?  
> Which bridge port it is going to use? Usually pppoe is running over  
> ethernet device, it doesn't need ip interface. Interface can have an ip,  
> but pppoe doesn't use it.
>

Re: PPPoE stuff

[Ionut Turturica]

Indeed, the XP machine doesn't receive any ppp packet as if anything  
that's written to that pty is lost :/

PS: ignore that pptp messages..I used same log file for both pptp and  
pppoe.

On Mon, 06 Feb 2006 22:27:20 +0200, James Carlson  
<carlsonj@workingcode.com> wrote:

> Ionut Turturica writes:
>> Any hints for this?
>> I'm trying to connect a XP machine with a rp-pppoe server. I see that XP
>> client keeps requesting CallBack, and we refuse it..
>
> The lack of response to your LCP Configure-Request messages (he should
> have sent at least an ack or nak) seems to indicate that this is a
> low-level communications problem of some sort.  No packets are getting
> through at all, so negotiation is simply impossible.  PPP is running,
> but the PPPoE mechanism is failing.
>
>> Feb  6 22:06:42 lfs pptp[31703]: anon log[decaps_gre:pptp_gre.c:407]:
>> buffering packet 1003 (expecting 1000, lost or reordered)
> [...]
>> Feb  6 22:06:58 lfs pppoe-server[31611]: Sent PADT
>
> That's sort of scary.  Is this PPTP, PPPoE, or what?  Why messages
> from both?  (Or are those just unrelated?)
>