Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-17 Thread cpghost
On Sun, 09 Dec 2007 14:01:27 -0800
Julian Elischer [EMAIL PROTECTED] wrote:

 cpghost wrote:
  On Sun, 09 Dec 2007 11:13:13 -0800
  Julian Elischer [EMAIL PROTECTED] wrote:
  
  --- manually restarting ppp(1), then:
  
 
  17:10:47.306928 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40C663C1]
[Service-Name]
 
  17:10:47.306939 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC06220C1]
[Service-Name]
 
  we still have 2 sessions instead of 1, but there is less confusion 
  so things sort themselves out.
  
  Just one more thing:
  
  If I remember correctly, sending two PADIs in quick succession
  was ppp's normal behaviour for *years* now (is it expected or
  required by the protocol? I don't know). I've always wondered
  why it was so. But that didn't cause any harm as it seemed one
  of the two PADO was picked up and eventually turned into a session.
  
  -cpghost.
  
 
 btw try mpd as well.

So... I'm running net/mpd5 on that router for a few days now, and
it managed 3 forced disconnects in a row and no session chaos at
all, while ppp(8) would probably have initiated a lot of parallel
sessions again but no connection.

So up until now (but perhaps it's too early to be sure?),
net/mpd5 is fine, while ppp(8) is not.

Btw, I've compared the sources of ppp(8) from 2007-09-24/25
when it was still working, and 2007-11-30 when I've updated
the router, and there's NO difference there at all. Whatever
broke ppp(8), it was not ppp(8) but something else
(I suspect ng_pppoe.c): maybe the code clean up exposed
some hidden bug in ppp(8)?

I hope ppp(8) will be fixed before 6.3-RELEASE; even though
net/mpd5 is excellent and very snappy as well. ;-)

Regards,
-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-17 Thread Julian Elischer

cpghost wrote:

On Sun, 09 Dec 2007 14:01:27 -0800
Julian Elischer [EMAIL PROTECTED] wrote:


cpghost wrote:

On Sun, 09 Dec 2007 11:13:13 -0800
Julian Elischer [EMAIL PROTECTED] wrote:


--- manually restarting ppp(1), then:


17:10:47.306928 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40C663C1]
  [Service-Name]

17:10:47.306939 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC06220C1]
  [Service-Name]

we still have 2 sessions instead of 1, but there is less confusion 
so things sort themselves out.

Just one more thing:

If I remember correctly, sending two PADIs in quick succession
was ppp's normal behaviour for *years* now (is it expected or
required by the protocol? I don't know). I've always wondered
why it was so. But that didn't cause any harm as it seemed one
of the two PADO was picked up and eventually turned into a session.

-cpghost.


btw try mpd as well.


So... I'm running net/mpd5 on that router for a few days now, and
it managed 3 forced disconnects in a row and no session chaos at
all, while ppp(8) would probably have initiated a lot of parallel
sessions again but no connection.

So up until now (but perhaps it's too early to be sure?),
net/mpd5 is fine, while ppp(8) is not.

Btw, I've compared the sources of ppp(8) from 2007-09-24/25
when it was still working, and 2007-11-30 when I've updated
the router, and there's NO difference there at all. Whatever
broke ppp(8), it was not ppp(8) but something else
(I suspect ng_pppoe.c): maybe the code clean up exposed
some hidden bug in ppp(8)?

I hope ppp(8) will be fixed before 6.3-RELEASE; even though
net/mpd5 is excellent and very snappy as well. ;-)


mpd is also using ng_pppoe of course.



Regards,
-cpghost.



___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-11 Thread cpghost
On Sun, 09 Dec 2007 14:02:50 -0800
Julian Elischer [EMAIL PROTECTED] wrote:

 Adding brian to CC list.
 
 
 Alexander Motin wrote:
  cpghost wrote:
  I think such behaviour can take place if ppp daemon for some
  reason don't waits for reply but closes session immediately after
  sending connect request. If it so it also explains original no
  matching session errors as for the answer received time
  session/hook can already be destroyed.
 
  Provide please your ppp configuration files and part of detailed
  log file (set log All) describing connection attempts.
 
  ppp.conf already sent. I don't have a 'set log All' turned on, but
  maybe the following logfile of the aborted session would help?
 
  http://www.cordula.ws/tests/ppp-tcpdump.txt
  
  Here is part of your logs which proves my assumption that it is ppp
  who creates numerous sessions:
  
  Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Establish
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: closed - opening
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connected!
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: opening - dial
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: dial - carrier
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Disconnected!
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: carrier - hangup
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connect time: 0
  secs: 0 octets in, 0 octets out
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: 7070012 packets in, 
  6467630 packets out
  Dec  9 17:06:07 fw ppp[35265]: Phase:  total 0 bytes/sec, peak 0 
  bytes/sec on Sun Dec  9 17:06:07 2007
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: hangup - closed
  Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Dead
  Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Establish
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: closed - opening
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connected!
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: opening - dial
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: dial - carrier
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Disconnected!
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: carrier - hangup
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connect time: 0
  secs: 0 octets in, 0 octets out
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: 7070012 packets in, 
  6467630 packets out
  Dec  9 17:06:07 fw ppp[35265]: Phase:  total 0 bytes/sec, peak 0 
  bytes/sec on Sun Dec  9 17:06:07 2007
  Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: hangup - closed
  Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Dead
  Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Establish
  
  For the some reason ppp logs Disconnected! message and terminates 
  session (which is strange as it have not logged any message from
  ng_ppp node) just to initiate new without delay. Could you enable
  any more logs to understant why is it Disconnected!?

Here's a new, more detailed log:

http://www.cordula.ws/tests/ppp-tcpdump2.txt

That's all I can do here. I guess it's up to Brian now...
If you have some patches for ppp, I'd be happy to try
them and report back.

Thanks,
-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread cpghost
On Thu, 6 Dec 2007 16:11:07 +0100
cpghost [EMAIL PROTECTED] wrote:

 On Thu, 06 Dec 2007 13:57:16 +0200
 Alexander Motin [EMAIL PROTECTED] wrote:
 
  cpghost wrote:
   The problem is that the last mile carrier of the PPP provider
   that this router is attached to disconnects the ppp session
   forcibly once every 24h. Before the update, ppp would detect
   this and reconnect immediately. After the update, ppp doesn't
   recover gracefully from this anymore, but spits out on the
   console:
   
   ng_pppoe[5]: no matching session
   
   for hours, and tries to connect again every two minutes without 
   success, until I manually stop and restart the userland ppp daemon
   (and then the connection is immediately restored with a new
   session). I've tried this for a few days now, and it is always the
   same: it's definitely not a problem on the provider's side: As
   soon as ppp restarts, it gets a new session without any problems
   and connects again.
   
   Since the last working sources were from 2007/09/25, and
   ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
   ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
   was changed there could be the cause (because this no matching
   session is being logged from there).
  
  I have tested and unable to reproduce that myself with ppp - mpd or
  mpd
  - - mpd PPPoE connections. Actually I am not sure about any
  difference between reconnect and ppp restart. From the ng_pppoe node
  point of view it should be the same.
  
  Could you provide tcpdump output for connection tries from your
  Ethernet interface? Use -pes 0 options please.
 
 Will do; but I'll first have to wait 24h from now to get a
 forcibly disconnected session (I've just had to restart ppp
 again).

All right, I've got a good tcpdump now:

# tcpdump -i sis0 -n -pes 0
tcpdump: verbose output suppressed, use -v or -vv for full protocol
decode listening on sis0, link-type EN10MB (Ethernet), capture size
65535 bytes

17:06:08.400881 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0xC0C263C1] [Service-Name]

17:06:08.400891 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC0ED45C1]
  [Service-Name]

17:06:08.400898 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40C263C1]
  [Service-Name]

17:06:08.400904 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x80CA63C1] [Service-Name]

17:06:08.400910 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80C963C1]
  [Service-Name]

17:06:08.528227 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0xC0C263C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.488679 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x806320C1]
  [Service-Name]

17:08:08.488690 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40D063C1]
  [Service-Name]

17:08:08.488696 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x00C063C1] [Service-Name]

17:08:08.488702 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40CE63C1]
  [Service-Name]

17:08:08.488708 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80EC45C1]
  [Service-Name]

17:08:08.552036 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE  PADO [AC-Name DSSX43-erx]
  [Host-Uniq 0x806320C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.557191 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x40D063C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.572971 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x00C063C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.577148 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x40CE63C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.581343 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x80EC45C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:10:08.577488 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80D063C1]
  [Service-Name]

17:10:08.577499 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x80C463C1] [Service-Name]

17:10:08.577505 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype 

Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread Julian Elischer

cpghost wrote:

On Thu, 6 Dec 2007 16:11:07 +0100
cpghost [EMAIL PROTECTED] wrote:


On Thu, 06 Dec 2007 13:57:16 +0200
Alexander Motin [EMAIL PROTECTED] wrote:


cpghost wrote:

The problem is that the last mile carrier of the PPP provider
that this router is attached to disconnects the ppp session
forcibly once every 24h. Before the update, ppp would detect
this and reconnect immediately. After the update, ppp doesn't
recover gracefully from this anymore, but spits out on the
console:

ng_pppoe[5]: no matching session

for hours, and tries to connect again every two minutes without 
success, until I manually stop and restart the userland ppp daemon

(and then the connection is immediately restored with a new
session). I've tried this for a few days now, and it is always the
same: it's definitely not a problem on the provider's side: As
soon as ppp restarts, it gets a new session without any problems
and connects again.

Since the last working sources were from 2007/09/25, and
ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
was changed there could be the cause (because this no matching
session is being logged from there).

I have tested and unable to reproduce that myself with ppp - mpd or
mpd
- - mpd PPPoE connections. Actually I am not sure about any
difference between reconnect and ppp restart. From the ng_pppoe node
point of view it should be the same.

Could you provide tcpdump output for connection tries from your
Ethernet interface? Use -pes 0 options please.

Will do; but I'll first have to wait 24h from now to get a
forcibly disconnected session (I've just had to restart ppp
again).


All right, I've got a good tcpdump now:

# tcpdump -i sis0 -n -pes 0
tcpdump: verbose output suppressed, use -v or -vv for full protocol
decode listening on sis0, link-type EN10MB (Ethernet), capture size
65535 bytes

17:06:08.400881 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0xC0C263C1] [Service-Name]

17:06:08.400891 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC0ED45C1]
  [Service-Name]

17:06:08.400898 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40C263C1]
  [Service-Name]

17:06:08.400904 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x80CA63C1] [Service-Name]

17:06:08.400910 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80C963C1]
  [Service-Name]



why are  we sending PADIs for 4 different sessions?
what does ngctl list
show?




17:06:08.528227 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0xC0C263C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]




they respond to the first one we sent.
this info should now be sent the ppp daemon.



17:08:08.488679 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x806320C1]
  [Service-Name]

17:08:08.488690 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40D063C1]
  [Service-Name]

17:08:08.488696 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x00C063C1] [Service-Name]

17:08:08.488702 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40CE63C1]
  [Service-Name]

17:08:08.488708 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80EC45C1]
  [Service-Name]



hey these are 4 more new pppoe sessions making 8
Are they just accumlating from each try?  maybe we are not cleaning up?



17:08:08.552036 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE  PADO [AC-Name DSSX43-erx]
  [Host-Uniq 0x806320C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.557191 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x40D063C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.572971 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x00C063C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.577148 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x40CE63C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.581343 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x80EC45C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]



router has responded to 4 more..  they should all be reporting back to
whatever started them.


some retries..
I haven't looked at 

Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread cpghost
On Sun, 09 Dec 2007 11:13:13 -0800
Julian Elischer [EMAIL PROTECTED] wrote:

 cpghost wrote:
  On Thu, 6 Dec 2007 16:11:07 +0100
  cpghost [EMAIL PROTECTED] wrote:
  
  On Thu, 06 Dec 2007 13:57:16 +0200
  Alexander Motin [EMAIL PROTECTED] wrote:
 
  cpghost wrote:
  The problem is that the last mile carrier of the PPP provider
  that this router is attached to disconnects the ppp session
  forcibly once every 24h. Before the update, ppp would detect
  this and reconnect immediately. After the update, ppp doesn't
  recover gracefully from this anymore, but spits out on the
  console:
 
  ng_pppoe[5]: no matching session
 
  for hours, and tries to connect again every two minutes without 
  success, until I manually stop and restart the userland ppp
  daemon (and then the connection is immediately restored with a
  new session). I've tried this for a few days now, and it is
  always the same: it's definitely not a problem on the provider's
  side: As soon as ppp restarts, it gets a new session without any
  problems and connects again.
 
  Since the last working sources were from 2007/09/25, and
  ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
  ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
  was changed there could be the cause (because this no matching
  session is being logged from there).
  I have tested and unable to reproduce that myself with ppp - mpd
  or mpd
  - - mpd PPPoE connections. Actually I am not sure about any
  difference between reconnect and ppp restart. From the ng_pppoe
  node point of view it should be the same.
 
  Could you provide tcpdump output for connection tries from your
  Ethernet interface? Use -pes 0 options please.
  Will do; but I'll first have to wait 24h from now to get a
  forcibly disconnected session (I've just had to restart ppp
  again).
  
  All right, I've got a good tcpdump now:
  
  # tcpdump -i sis0 -n -pes 0
  tcpdump: verbose output suppressed, use -v or -vv for full protocol
  decode listening on sis0, link-type EN10MB (Ethernet), capture size
  65535 bytes
  
  17:06:08.400881 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
0xC0C263C1] [Service-Name]
  
  17:06:08.400891 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC0ED45C1]
[Service-Name]
  
  17:06:08.400898 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40C263C1]
[Service-Name]
  
  17:06:08.400904 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
0x80CA63C1] [Service-Name]
  
  17:06:08.400910 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80C963C1]
[Service-Name]
 
 
 why are  we sending PADIs for 4 different sessions?
 what does ngctl list
 show?

Right now, with the working connection:

# ngctl list
There are 6 total nodes:
  Name: ngctl53522Type: socketID: 02ec   Num hooks: 0
  Name: unnamed Type: socketID: 02eb   Num hooks: 1
  Name: unnamed Type: pppoe ID: 0005   Num hooks: 2
  Name: sis2  Type: ether ID: 0003   Num hooks: 0
  Name: sis1  Type: ether ID: 0002   Num hooks: 0
  Name: sis0  Type: ether ID: 0001   Num hooks: 1

  17:06:08.528227 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
  PPPoE D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx]
  [Host-Uniq 0xC0C263C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]
  
 
 
 they respond to the first one we sent.
 this info should now be sent the ppp daemon.
 
 
  17:08:08.488679 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x806320C1]
[Service-Name]
  
  17:08:08.488690 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40D063C1]
[Service-Name]
  
  17:08:08.488696 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
0x00C063C1] [Service-Name]
  
  17:08:08.488702 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40CE63C1]
[Service-Name]
  
  17:08:08.488708 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80EC45C1]
[Service-Name]
 
 
 hey these are 4 more new pppoe sessions making 8
 Are they just accumlating from each try?  maybe we are not cleaning
 up?
 
  
  17:08:08.552036 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
  PPPoE D (0x8863), length 66: PPPoE  PADO [AC-Name DSSX43-erx]
[Host-Uniq 0x806320C1] [Service-Name] [AC-Cookie
  ..7\t.K.,.!y.y.E]
  
  17:08:08.557191 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
  PPPoE D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx]
  [Host-Uniq 0x40D063C1] [Service-Name] [AC-Cookie 

Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread Alexander Motin

cpghost wrote:

Could you provide tcpdump output for connection tries from your
Ethernet interface? Use -pes 0 options please.

Will do; but I'll first have to wait 24h from now to get a
forcibly disconnected session (I've just had to restart ppp
again).


All right, I've got a good tcpdump now:

# tcpdump -i sis0 -n -pes 0
tcpdump: verbose output suppressed, use -v or -vv for full protocol
decode listening on sis0, link-type EN10MB (Ethernet), capture size
65535 bytes

17:06:08.400881 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0xC0C263C1] [Service-Name]

17:06:08.400891 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC0ED45C1]
  [Service-Name]

17:06:08.400898 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40C263C1]
  [Service-Name]


Your host generates huge number of simultaneous session requests. 
Looking on different Host-Uniq values it should be different ng_pppoe 
sessions/hooks as Host-Uniq is actually pointer to the hook/session 
internal data and it stays persistent for all packets of the same 
session. So it looks that something creates those many hooks.


I think such behaviour can take place if ppp daemon for some reason 
don't waits for reply but closes session immediately after sending 
connect request. If it so it also explains original no matching 
session errors as for the answer received time session/hook can already 
be destroyed.


Provide please your ppp configuration files and part of detailed log 
file (set log All) describing connection attempts.


PS: I am not ppp daemon expert, but quick code look shows that 
connection waiting can be configured with 'set cd ...' command. Setting 
'set cd 0' gives results close to yours. Have you something like that in 
your configuration?


--
Alexander Motin
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread cpghost
On Sun, 09 Dec 2007 11:13:13 -0800
Julian Elischer [EMAIL PROTECTED] wrote:

  --- manually restarting ppp(1), then:
  
  
  17:10:47.306928 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40C663C1]
[Service-Name]
  
  17:10:47.306939 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC06220C1]
[Service-Name]
  
 
 we still have 2 sessions instead of 1, but there is less confusion 
 so things sort themselves out.

Just one more thing:

If I remember correctly, sending two PADIs in quick succession
was ppp's normal behaviour for *years* now (is it expected or
required by the protocol? I don't know). I've always wondered
why it was so. But that didn't cause any harm as it seemed one
of the two PADO was picked up and eventually turned into a session.

-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread Alexander Motin

cpghost wrote:

If I remember correctly, sending two PADIs in quick succession
was ppp's normal behaviour for *years* now (is it expected or
required by the protocol? I don't know). I've always wondered
why it was so. But that didn't cause any harm as it seemed one
of the two PADO was picked up and eventually turned into a session.


It is not required by protocol as I remember. ppp does not sends PADI 
itselt, it is done by ng_ppp node. And it retransmits packets with 
increasing time-out starting from 2 seconds. If you see many packets 
check their Host-Uniq values.


--
Alexander Motin
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread cpghost
On Sun, 09 Dec 2007 22:57:46 +0200
Alexander Motin [EMAIL PROTECTED] wrote:

 Your host generates huge number of simultaneous session requests. 
 Looking on different Host-Uniq values it should be different ng_pppoe 
 sessions/hooks as Host-Uniq is actually pointer to the hook/session 
 internal data and it stays persistent for all packets of the same 
 session. So it looks that something creates those many hooks.
 
 I think such behaviour can take place if ppp daemon for some reason 
 don't waits for reply but closes session immediately after sending 
 connect request. If it so it also explains original no matching 
 session errors as for the answer received time session/hook can
 already be destroyed.
 
 Provide please your ppp configuration files and part of detailed log 
 file (set log All) describing connection attempts.

ppp.conf already sent. I don't have a 'set log All' turned on, but maybe
the following logfile of the aborted session would help?

http://www.cordula.ws/tests/ppp-tcpdump.txt

 PS: I am not ppp daemon expert, but quick code look shows that 
 connection waiting can be configured with 'set cd ...' command.
 Setting 'set cd 0' gives results close to yours. Have you something
 like that in your configuration?

Uh... I'm not sure I understand what you're asking for here?

Thanks,
-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread Julian Elischer

cpghost wrote:

On Sun, 09 Dec 2007 11:13:13 -0800
Julian Elischer [EMAIL PROTECTED] wrote:


cpghost wrote:

On Thu, 6 Dec 2007 16:11:07 +0100
cpghost [EMAIL PROTECTED] wrote:


On Thu, 06 Dec 2007 13:57:16 +0200
Alexander Motin [EMAIL PROTECTED] wrote:


cpghost wrote:

The problem is that the last mile carrier of the PPP provider
that this router is attached to disconnects the ppp session
forcibly once every 24h. Before the update, ppp would detect
this and reconnect immediately. After the update, ppp doesn't
recover gracefully from this anymore, but spits out on the
console:

ng_pppoe[5]: no matching session

for hours, and tries to connect again every two minutes without 
success, until I manually stop and restart the userland ppp

daemon (and then the connection is immediately restored with a
new session). I've tried this for a few days now, and it is
always the same: it's definitely not a problem on the provider's
side: As soon as ppp restarts, it gets a new session without any
problems and connects again.

Since the last working sources were from 2007/09/25, and
ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
was changed there could be the cause (because this no matching
session is being logged from there).

I have tested and unable to reproduce that myself with ppp - mpd
or mpd
- - mpd PPPoE connections. Actually I am not sure about any
difference between reconnect and ppp restart. From the ng_pppoe
node point of view it should be the same.

Could you provide tcpdump output for connection tries from your
Ethernet interface? Use -pes 0 options please.

Will do; but I'll first have to wait 24h from now to get a
forcibly disconnected session (I've just had to restart ppp
again).

All right, I've got a good tcpdump now:

# tcpdump -i sis0 -n -pes 0
tcpdump: verbose output suppressed, use -v or -vv for full protocol
decode listening on sis0, link-type EN10MB (Ethernet), capture size
65535 bytes

17:06:08.400881 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0xC0C263C1] [Service-Name]

17:06:08.400891 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC0ED45C1]
  [Service-Name]

17:06:08.400898 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40C263C1]
  [Service-Name]

17:06:08.400904 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x80CA63C1] [Service-Name]

17:06:08.400910 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80C963C1]
  [Service-Name]


why are  we sending PADIs for 4 different sessions?
what does ngctl list
show?


Right now, with the working connection:

# ngctl list
There are 6 total nodes:
  Name: ngctl53522Type: socketID: 02ec   Num hooks: 0
  Name: unnamed Type: socketID: 02eb   Num hooks: 1
  Name: unnamed Type: pppoe ID: 0005   Num hooks: 2
  Name: sis2  Type: ether ID: 0003   Num hooks: 0
  Name: sis1  Type: ether ID: 0002   Num hooks: 0
  Name: sis0  Type: ether ID: 0001   Num hooks: 1


17:06:08.528227 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
PPPoE D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx]
[Host-Uniq 0xC0C263C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]



they respond to the first one we sent.
this info should now be sent the ppp daemon.



17:08:08.488679 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x806320C1]
  [Service-Name]

17:08:08.488690 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE  PADI [Host-Uniq 0x40D063C1]
  [Service-Name]

17:08:08.488696 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff,
  ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq
  0x00C063C1] [Service-Name]

17:08:08.488702 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40CE63C1]
  [Service-Name]

17:08:08.488708 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x80EC45C1]
  [Service-Name]


hey these are 4 more new pppoe sessions making 8
Are they just accumlating from each try?  maybe we are not cleaning
up?


17:08:08.552036 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
PPPoE D (0x8863), length 66: PPPoE  PADO [AC-Name DSSX43-erx]
  [Host-Uniq 0x806320C1] [Service-Name] [AC-Cookie
..7\t.K.,.!y.y.E]

17:08:08.557191 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
PPPoE D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx]
[Host-Uniq 0x40D063C1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

17:08:08.572971 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype
PPPoE D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx]
[Host-Uniq 0x00C063C1] [Service-Name] 

Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread Julian Elischer

cpghost wrote:

On Sun, 09 Dec 2007 11:13:13 -0800
Julian Elischer [EMAIL PROTECTED] wrote:


--- manually restarting ppp(1), then:


17:10:47.306928 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40C663C1]
  [Service-Name]

17:10:47.306939 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC06220C1]
  [Service-Name]

we still have 2 sessions instead of 1, but there is less confusion 
so things sort themselves out.


Just one more thing:

If I remember correctly, sending two PADIs in quick succession
was ppp's normal behaviour for *years* now (is it expected or
required by the protocol? I don't know). I've always wondered
why it was so. But that didn't cause any harm as it seemed one
of the two PADO was picked up and eventually turned into a session.

-cpghost.



btw try mpd as well.

___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread cpghost
On Sun, 09 Dec 2007 23:52:01 +0200
Alexander Motin [EMAIL PROTECTED] wrote:

 cpghost wrote:
  I think such behaviour can take place if ppp daemon for some
  reason don't waits for reply but closes session immediately after
  sending connect request. If it so it also explains original no
  matching session errors as for the answer received time
  session/hook can already be destroyed.
 
  Provide please your ppp configuration files and part of detailed
  log file (set log All) describing connection attempts.
  
  ppp.conf already sent. I don't have a 'set log All' turned on, but
  maybe the following logfile of the aborted session would help?
  
  http://www.cordula.ws/tests/ppp-tcpdump.txt
 
 Here is part of your logs which proves my assumption that it is ppp
 who creates numerous sessions:
 
 Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Establish
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: closed - opening
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connected!
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: opening - dial
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: dial - carrier
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Disconnected!
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: carrier - hangup
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connect time: 0 secs:
 0 octets in, 0 octets out
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: 7070012 packets in, 
 6467630 packets out
 Dec  9 17:06:07 fw ppp[35265]: Phase:  total 0 bytes/sec, peak 0 
 bytes/sec on Sun Dec  9 17:06:07 2007
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: hangup - closed
 Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Dead
 Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Establish
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: closed - opening
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connected!
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: opening - dial
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: dial - carrier
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Disconnected!
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: carrier - hangup
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: Connect time: 0 secs:
 0 octets in, 0 octets out
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: 7070012 packets in, 
 6467630 packets out
 Dec  9 17:06:07 fw ppp[35265]: Phase:  total 0 bytes/sec, peak 0 
 bytes/sec on Sun Dec  9 17:06:07 2007
 Dec  9 17:06:07 fw ppp[35265]: Phase: deflink: hangup - closed
 Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Dead
 Dec  9 17:06:07 fw ppp[35265]: Phase: bundle: Establish
 
 For the some reason ppp logs Disconnected! message and terminates 
 session (which is strange as it have not logged any message from
 ng_ppp node) just to initiate new without delay. Could you enable any
 more logs to understant why is it Disconnected!?

If you let me know which one to turn on:

PPP ON fw set log +connect
PPP ON fw show log
Log:  Log:   CCP Chat Command Connect IPCP LCP Phase Tun Warning Error
Alert Local: Warning Error Alert
PPP ON fw

I've briefly tried to turn on 'all' but since it's an active router
but a slow box, I'd rather not log this for very long... :(
it generates huge logs VERY fast.

PPP ON fw set log all
PPP ON fw show log
Log:   Async CBCP CCP Chat Command Connect Debug DNS Filter HDLC ID0
IPCP IPV6CP LCP LQM Phase Physical Radius Sync TCP/IP Timer Tun Warning
Error Alert Local: Warning Error Alert
PPP ON fw set log phase chat lcp ipcp ccp tun command
PPP ON fw 

Should I try to re-connect with all enabled now? Of course, it will
reset the 24h period...

-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-09 Thread cpghost
On Sun, 09 Dec 2007 14:01:27 -0800
Julian Elischer [EMAIL PROTECTED] wrote:

 cpghost wrote:
  On Sun, 09 Dec 2007 11:13:13 -0800
  Julian Elischer [EMAIL PROTECTED] wrote:
  
  --- manually restarting ppp(1), then:
  
 
  17:10:47.306928 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40C663C1]
[Service-Name]
 
  17:10:47.306939 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype
  PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC06220C1]
[Service-Name]
 
  we still have 2 sessions instead of 1, but there is less confusion 
  so things sort themselves out.
  
  Just one more thing:
  
  If I remember correctly, sending two PADIs in quick succession
  was ppp's normal behaviour for *years* now (is it expected or
  required by the protocol? I don't know). I've always wondered
  why it was so. But that didn't cause any harm as it seemed one
  of the two PADO was picked up and eventually turned into a session.
  
  -cpghost.
  
 
 btw try mpd as well.

Never did before, but I'll definitely try it in a few days... ;)

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-07 Thread cpghost
On Thu, 06 Dec 2007 13:57:16 +0200
Alexander Motin [EMAIL PROTECTED] wrote:

 cpghost wrote:
  The problem is that the last mile carrier of the PPP provider
  that this router is attached to disconnects the ppp session
  forcibly once every 24h. Before the update, ppp would detect
  this and reconnect immediately. After the update, ppp doesn't
  recover gracefully from this anymore, but spits out on the
  console:
  
  ng_pppoe[5]: no matching session
  
  for hours, and tries to connect again every two minutes without 
  success, until I manually stop and restart the userland ppp daemon
  (and then the connection is immediately restored with a new
  session). I've tried this for a few days now, and it is always the
  same: it's definitely not a problem on the provider's side: As soon
  as ppp restarts, it gets a new session without any problems and
  connects again.
  
  Since the last working sources were from 2007/09/25, and
  ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
  ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
  was changed there could be the cause (because this no matching
  session is being logged from there).
 
 I have tested and unable to reproduce that myself with ppp - mpd or
 mpd
 - - mpd PPPoE connections. Actually I am not sure about any
 difference between reconnect and ppp restart. From the ng_pppoe node
 point of view it should be the same.
 
 Could you provide tcpdump output for connection tries from your
 Ethernet interface? Use -pes 0 options please.

Hi again,

no luck this time: I just went through the 24h disconnect with tcpdump
watching, but this time, ppp did reconnect flawlessly:

[... packets belonging to ses 0x1d06 ..., then:]

16:03:28.367734 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 64: PPPoE PADT [ses 0x1d06] 16:03:28.368035

00:00:24:c2:45:74  00:90:1a:a0:15:b7, ethertype PPPoE D (0x8863),
  length 38: PPPoE PADT [ses 0x1d06] [Generic-Error session closed]

16:06:22.211545 00:00:24:c2:45:74  ff:ff:ff:ff:ff:ff, ethertype PPPoE
  D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x405B1AC1]
  [Service-Name]

16:06:22.383675 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADO [AC-Name DSSX43-erx] [Host-Uniq
  0x405B1AC1] [Service-Name] [AC-Cookie ..7\t.K.,.!y.y.E]

16:06:22.383871 00:00:24:c2:45:74  00:90:1a:a0:15:b7, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADR [Host-Uniq 0x405B1AC1] [AC-Cookie
  ..7\t.K.,.!y.y.E] [AC-Name DSSX43-erx] [Service-Name]

16:06:22.503154 00:90:1a:a0:15:b7  00:00:24:c2:45:74, ethertype PPPoE
  D (0x8863), length 66: PPPoE PADS [ses 0xb6b] [Service-Name]
  [Host-Uniq 0x405B1AC1] [AC-Name DSSX43-erx] [AC-Cookie
  ..7\t.K.,.!y.y.E]

16:06:24.243677 00:00:24:c2:45:74  00:90:1a:a0:15:b7, ethertype PPPoE
  S (0x8864), length 36: PPPoE  [ses 0xb6b] LCP (0xc021), length 16:
  LCP, Conf-Request (0x01), id 4, length 16

[... more LCP packets belonging to ses 0xb6b ...]

So the PADT/PADI/PADO/PADR/PADS sequence is correct. Maybe the PADT got
lost on its way to this box the last times? Would ng_pppoe.c handle this
case gracefully?

Anyway, I will monitor this a few more days with tcpdump and report back
should a no matching session come up again.

Thanks,
-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-06 Thread Alexander Motin
-BEGIN PGP SIGNED MESSAGE-
Hash: SHA1

cpghost wrote:
 The problem is that the last mile carrier of the PPP provider
 that this router is attached to disconnects the ppp session
 forcibly once every 24h. Before the update, ppp would detect
 this and reconnect immediately. After the update, ppp doesn't
 recover gracefully from this anymore, but spits out on the
 console:
 
 ng_pppoe[5]: no matching session
 
 for hours, and tries to connect again every two minutes without 
 success, until I manually stop and restart the userland ppp daemon
 (and then the connection is immediately restored with a new session).
 I've tried this for a few days now, and it is always the same: it's
 definitely not a problem on the provider's side: As soon as ppp
 restarts, it gets a new session without any problems and connects
 again.
 
 Since the last working sources were from 2007/09/25, and
 ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
 ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
 was changed there could be the cause (because this no matching
 session is being logged from there).

I have tested and unable to reproduce that myself with ppp - mpd or mpd
- - mpd PPPoE connections. Actually I am not sure about any difference
between reconnect and ppp restart. From the ng_pppoe node point of view
it should be the same.

Could you provide tcpdump output for connection tries from your Ethernet
interface? Use -pes 0 options please.

- --
Alexander Motin
-BEGIN PGP SIGNATURE-
Version: GnuPG v1.4.7 (FreeBSD)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org

iD8DBQFHV+Oc0kCgngV3usoRAnZtAKCQ/7hW63Zli9UiytDK1xj8lNrsBQCffwhl
377pyCH24ytoR8tOnbtYqX8=
=v+x0
-END PGP SIGNATURE-
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]


Re: no matching session in ng_pppoe.c 1.74.2.4? (RELENG_6)

2007-12-06 Thread cpghost
On Thu, 06 Dec 2007 13:57:16 +0200
Alexander Motin [EMAIL PROTECTED] wrote:

 cpghost wrote:
  The problem is that the last mile carrier of the PPP provider
  that this router is attached to disconnects the ppp session
  forcibly once every 24h. Before the update, ppp would detect
  this and reconnect immediately. After the update, ppp doesn't
  recover gracefully from this anymore, but spits out on the
  console:
  
  ng_pppoe[5]: no matching session
  
  for hours, and tries to connect again every two minutes without 
  success, until I manually stop and restart the userland ppp daemon
  (and then the connection is immediately restored with a new
  session). I've tried this for a few days now, and it is always the
  same: it's definitely not a problem on the provider's side: As soon
  as ppp restarts, it gets a new session without any problems and
  connects again.
  
  Since the last working sources were from 2007/09/25, and
  ng_pppoe.c was at rev. 1.74.2.3; and the new revision of
  ng_pppoe.c is now at 1.74.2.4; I'm suspecting that whatever
  was changed there could be the cause (because this no matching
  session is being logged from there).
 
 I have tested and unable to reproduce that myself with ppp - mpd or
 mpd
 - - mpd PPPoE connections. Actually I am not sure about any
 difference between reconnect and ppp restart. From the ng_pppoe node
 point of view it should be the same.
 
 Could you provide tcpdump output for connection tries from your
 Ethernet interface? Use -pes 0 options please.

Will do; but I'll first have to wait 24h from now to get a
forcibly disconnected session (I've just had to restart ppp
again).

Thanks for looking into this. ;)

 - --
 Alexander Motin
 -BEGIN PGP SIGNATURE-
 Version: GnuPG v1.4.7 (FreeBSD)
 Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org

Regards,
-cpghost.

-- 
Cordula's Web. http://www.cordula.ws/
___
freebsd-stable@freebsd.org mailing list
http://lists.freebsd.org/mailman/listinfo/freebsd-stable
To unsubscribe, send any mail to [EMAIL PROTECTED]