PPPoE client failing to parse PADO replies



  • It appears that for some reason the PPPoE client in pfSense can fail to connect in some circimstances. The remote access concentrator replies to the discovery initiation but this doesn't make it up to the PPP client which doesn't see the AC-name and then sends another PADI. I've been seeing this problem for some years now and it doesn't appear to be specific to pfSense hardware, ADSL / VDSL bridge hardware or ISP.

    This experiment was done on a freshly installed pfsense 2.4.1 box with a completely stock configuration save the PPPoE WAN settings.

    Here's a PPP log:

    Apr 22 00:56:50 	ppp 		[wan_link0] PPPoE: Connecting to ''
    Apr 22 00:56:50 	ppp 		[wan_link0] Link: reconnection attempt 3
    Apr 22 00:56:46 	ppp 		[wan_link0] Link: reconnection attempt 3 in 4 seconds
    Apr 22 00:56:46 	ppp 		[wan_link0] LCP: Down event
    Apr 22 00:56:46 	ppp 		[wan_link0] Link: DOWN event
    Apr 22 00:56:46 	ppp 		[wan_link0] PPPoE connection timeout after 9 seconds
    Apr 22 00:56:37 	ppp 		[wan_link0] PPPoE: Connecting to ''
    Apr 22 00:56:37 	ppp 		[wan_link0] Link: reconnection attempt 2
    Apr 22 00:56:33 	ppp 		[wan_link0] Link: reconnection attempt 2 in 4 seconds
    Apr 22 00:56:33 	ppp 		[wan_link0] LCP: Down event
    Apr 22 00:56:33 	ppp 		[wan_link0] Link: DOWN event
    Apr 22 00:56:33 	ppp 		[wan_link0] PPPoE connection timeout after 9 seconds
    Apr 22 00:56:24 	ppp 		[wan_link0] PPPoE: Connecting to ''
    Apr 22 00:56:24 	ppp 		[wan_link0] Link: reconnection attempt 1
    Apr 22 00:56:22 	ppp 		[wan_link0] Link: reconnection attempt 1 in 2 seconds
    Apr 22 00:56:22 	ppp 		[wan_link0] LCP: Down event
    
    

    And here's a packet capture of the interface on which PPPoE is run, showing the PADI / PADO sequence

    10:03:04.527057 PPPoE PADI [Host-Uniq 0x00AFB35000F8FFFF] [Service-Name]
    10:03:04.539562 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x00AFB35000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:06.526946 PPPoE PADI [Host-Uniq 0x00AFB35000F8FFFF] [Service-Name]
    10:03:06.539342 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x00AFB35000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:10.526947 PPPoE PADI [Host-Uniq 0x00AFB35000F8FFFF] [Service-Name]
    10:03:10.538986 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x00AFB35000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:14.552063 PPPoE PADI [Host-Uniq 0x80D9141701F8FFFF] [Service-Name]
    10:03:14.564778 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x80D9141701F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:16.551947 PPPoE PADI [Host-Uniq 0x80D9141701F8FFFF] [Service-Name]
    10:03:16.565033 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x80D9141701F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:20.551949 PPPoE PADI [Host-Uniq 0x80D9141701F8FFFF] [Service-Name]
    10:03:20.564840 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x80D9141701F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:27.653003 PPPoE PADI [Host-Uniq 0x40DBB25000F8FFFF] [Service-Name]
    10:03:27.665587 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x40DBB25000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:29.652945 PPPoE PADI [Host-Uniq 0x40DBB25000F8FFFF] [Service-Name]
    10:03:29.673354 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x40DBB25000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:33.652947 PPPoE PADI [Host-Uniq 0x40DBB25000F8FFFF] [Service-Name]
    10:03:33.665914 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x40DBB25000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:37.696252 PPPoE PADI [Host-Uniq 0x40DBB25000F8FFFF] [Service-Name]
    10:03:37.707916 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x40DBB25000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:39.695928 PPPoE PADI [Host-Uniq 0x40DBB25000F8FFFF] [Service-Name]
    10:03:39.708697 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x40DBB25000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:43.695989 PPPoE PADI [Host-Uniq 0x40DBB25000F8FFFF] [Service-Name]
    10:03:43.707980 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x40DBB25000F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    10:03:49.702238 PPPoE PADI [Host-Uniq 0x00E8141701F8FFFF] [Service-Name]
    10:03:49.715166 PPPoE PADO [Service-Name] [AC-Name "acc-aln1.elh"] [Host-Uniq 0x00E8141701F8FFFF] [AC-Cookie ".'.&...(..Z'/t)Y"]
    
    

Log in to reply