From owner-freebsd-stable@FreeBSD.ORG Sun Dec 9 16:34:04 2007 Return-Path: Delivered-To: freebsd-stable@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 99C2C16A417; Sun, 9 Dec 2007 16:34:04 +0000 (UTC) (envelope-from cpghost@cordula.ws) Received: from fw.farid-hajji.net (fw.farid-hajji.net [213.146.115.42]) by mx1.freebsd.org (Postfix) with ESMTP id 0EBCA13C457; Sun, 9 Dec 2007 16:34:02 +0000 (UTC) (envelope-from cpghost@cordula.ws) Received: from epia-2.farid-hajji.net (epia-2 [192.168.254.11]) by fw.farid-hajji.net (Postfix) with ESMTP id 535D4E0A11; Sun, 9 Dec 2007 17:34:01 +0100 (CET) Date: Sun, 9 Dec 2007 17:33:59 +0100 From: cpghost To: freebsd-stable@freebsd.org Message-ID: <20071209173359.710ea5bd@epia-2.farid-hajji.net> In-Reply-To: <20071206161107.3c0c9a82@epia-2.farid-hajji.net> References: <20071206030500.746c782d@epia-2.farid-hajji.net> <4757E39C.8020009@FreeBSD.org> <20071206161107.3c0c9a82@epia-2.farid-hajji.net> Organization: Cordula's Web X-Mailer: Claws Mail 3.0.2 (GTK+ 2.12.1; i386-portbld-freebsd6.2) Mime-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit Cc: Alexander Motin , Julian Elischer Subject: Re: "no matching session" in ng_pppoe.c 1.74.2.4? (RELENG_6) X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sun, 09 Dec 2007 16:34:04 -0000 On Thu, 6 Dec 2007 16:11:07 +0100 cpghost wrote: > On Thu, 06 Dec 2007 13:57:16 +0200 > Alexander Motin 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 PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0xC0CC63C1] [Service-Name] 17:10:08.577511 00:00:24:c2:45:74 > ff:ff:ff:ff:ff:ff, ethertype PPPoE D (0x8863), length 32: PPPoE > PADI [Host-Uniq 0x00E745C1] [Service-Name] 17:10:08.577517 00:00:24:c2:45:74 > ff:ff:ff:ff:ff:ff, ethertype PPPoE D (0x8863), length 32: PPPoE PADI [Host-Uniq 0x40EC45C1] [Service-Name] 17:10:08.631297 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 0x80D063C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:08.637181 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 0x80C463C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:08.646060 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 0xC0CC63C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:08.649262 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 0x00E745C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:08.660599 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 0x40EC45C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] ----------- 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] 17:10:47.359585 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 0x40C663C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:47.365720 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 0xC06220C1] [Service-Name] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:47.365911 00:00:24:c2:45:74 > 00:90:1a:a0:15:b7, ethertype PPPoE D (0x8863), length 66: PPPoE PADR [Host-Uniq 0xC06220C1] [AC-Cookie "..7\t.K.,.!y.y.E"] [AC-Name "DSSX43-erx"] [Service-Name] 17:10:47.463059 00:90:1a:a0:15:b7 > 00:00:24:c2:45:74, ethertype PPPoE D (0x8863), length 66: PPPoE PADS [ses 0x1906] [Service-Name] [Host-Uniq 0xC06220C1] [AC-Name "DSSX43-erx"] [AC-Cookie "..7\t.K.,.!y.y.E"] 17:10:49.338956 00:00:24:c2:45:74 > 00:90:1a:a0:15:b7, ethertype PPPoE S (0x8864), length 36: PPPoE [ses 0x1906] LCP (0xc021), length 16: LCP, Conf-Request (0x01), id 1, length 16 17:10:49.570907 00:90:1a:a0:15:b7 > 00:00:24:c2:45:74, ethertype PPPoE S (0x8864), length 64: PPPoE [ses 0x1906] LCP (0xc021), length 20: LCP, Conf-Request (0x01), id 175, length 20 17:10:49.571646 00:90:1a:a0:15:b7 > 00:00:24:c2:45:74, ethertype PPPoE S (0x8864), length 64: PPPoE [ses 0x1906] LCP (0xc021), length 16: LCP, Conf-Ack (0x02), id 1, length 16 17:10:49.584762 00:00:24:c2:45:74 > 00:90:1a:a0:15:b7, ethertype PPPoE S (0x8864), length 40: PPPoE [ses 0x1906] LCP (0xc021), length 20: LCP, Conf-Ack (0x02), id 175, length 20 ------- More LCP packets from ses 0x1906 then regular traffic -------- HTH... Thanks, -cpghost. -- Cordula's Web. http://www.cordula.ws/