PPPoE not working after upgrade to 2.4.4



  • Using an SG-3100, on 2.4.3 my PPPoE internet connections (aerial direct in UK) was working fine, after upgrade to 2.4.4 the firewall can no longer bring up the connection. The logs don't really seem to tell me much. I have switched this interface to static configuration and back to PPPoE just to test and still cannot get it to connect.

    If anyone can give me any advice on what to check and where that would be great. The DSL modem is attached to mvneta0 via ethernet, no vlans are being used on the interface. mvneta0 shows link state up etc.

    Sep 27 14:17:31	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 27 14:17:31	ppp		[opt1_link0] Link: reconnection attempt 2699
    Sep 27 14:17:29	ppp		[opt1_link0] Link: reconnection attempt 2699 in 2 seconds
    Sep 27 14:17:29	ppp		[opt1_link0] LCP: Down event
    Sep 27 14:17:29	ppp		[opt1_link0] Link: DOWN event
    Sep 27 14:17:29	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 27 14:17:20	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 27 14:17:20	ppp		[opt1_link0] Link: reconnection attempt 2698
    Sep 27 14:17:16	ppp		[opt1_link0] Link: reconnection attempt 2698 in 4 seconds
    Sep 27 14:17:16	ppp		[opt1_link0] LCP: Down event
    Sep 27 14:17:16	ppp		[opt1_link0] Link: DOWN event
    Sep 27 14:17:16	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 27 14:17:07	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 27 14:17:07	ppp		[opt1_link0] Link: reconnection attempt 2697
    Sep 27 14:17:03	ppp		[opt1_link0] Link: reconnection attempt 2697 in 4 seconds
    Sep 27 14:17:03	ppp		[opt1_link0] LCP: Down event
    Sep 27 14:17:03	ppp		[opt1_link0] Link: DOWN event
    Sep 27 14:17:03	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 27 14:16:54	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 27 14:16:54	ppp		[opt1_link0] Link: reconnection attempt 2696
    

  • Netgate Administrator

    I have seen one issue with PPPoE which is yet unexplained. Try running at the command line or in Diag > Command Prompt:
    ifconfig mvneta0 promisc

    Does that allow the connection to come up?

    Just for reference I use PPPoE (in the UK) and have seen no issues. Different ISP though.

    Steve



  • It has no effect unfortunately.


  • Netgate Administrator

    In that case we need to see the logs showing the initial setup and fail.

    Try to stop the connection in Status > Interfaces then start it again. Recheck the system and ppp logs for errors.

    If that shows nothing reboot and check what the ppp daemon logs there when it first starts.

    Steve



  • PPPoE log after reboot

    Sep 28 02:28:15	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:28:15	ppp		[opt1_link0] Link: reconnection attempt 3
    Sep 28 02:28:12	ppp		[opt1_link0] Link: reconnection attempt 3 in 3 seconds
    Sep 28 02:28:12	ppp		[opt1_link0] LCP: Down event
    Sep 28 02:28:12	ppp		[opt1_link0] Link: DOWN event
    Sep 28 02:28:12	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 28 02:28:03	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:28:03	ppp		[opt1_link0] Link: reconnection attempt 2
    Sep 28 02:28:02	ppp		[opt1_link0] Link: reconnection attempt 2 in 1 seconds
    Sep 28 02:28:02	ppp		[opt1_link0] LCP: Down event
    Sep 28 02:28:02	ppp		[opt1_link0] Link: DOWN event
    Sep 28 02:28:02	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 28 02:27:53	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:27:53	ppp		[opt1_link0] Link: reconnection attempt 1
    Sep 28 02:27:49	ppp		[opt1_link0] Link: reconnection attempt 1 in 4 seconds
    Sep 28 02:27:49	ppp		[opt1_link0] LCP: Down event
    Sep 28 02:27:49	ppp		[opt1_link0] Link: DOWN event
    Sep 28 02:27:49	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 28 02:27:40	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:27:40	ppp		[opt1_link0] LCP: LayerStart
    Sep 28 02:27:40	ppp		[opt1_link0] LCP: state change Initial --> Starting
    Sep 28 02:27:40	ppp		[opt1_link0] LCP: Open event
    Sep 28 02:27:40	ppp		[opt1_link0] Link: OPEN event
    Sep 28 02:27:40	ppp		[opt1] Bundle: Interface ng0 created
    Sep 28 02:27:40	ppp		web: web is not running
    Sep 28 02:27:40	ppp		process 14813 started, version 5.8 (nobody@pfSense_factory-v2_4_4_armv6-pfSense_factory-v2_4_4-job-10 03:28 5-Sep-2018)
    Sep 28 02:27:40	ppp		Multi-link PPP daemon for FreeBSD
    

    System log entries that seem relevant

    Sep 28 02:28:15	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:28:15	ppp		[opt1_link0] Link: reconnection attempt 3
    Sep 28 02:28:12	php-fpm	359	/index.php: Successful login for user 'xxxxx' from: 23.92.140.217 (Local Database)
    Sep 28 02:28:12	ppp		[opt1_link0] Link: reconnection attempt 3 in 3 seconds
    Sep 28 02:28:12	ppp		[opt1_link0] LCP: Down event
    Sep 28 02:28:12	ppp		[opt1_link0] Link: DOWN event
    Sep 28 02:28:12	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 28 02:28:03	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:28:03	ppp		[opt1_link0] Link: reconnection attempt 2
    Sep 28 02:28:02	ppp		[opt1_link0] Link: reconnection attempt 2 in 1 seconds
    Sep 28 02:28:02	ppp		[opt1_link0] LCP: Down event
    Sep 28 02:28:02	ppp		[opt1_link0] Link: DOWN event
    Sep 28 02:28:02	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    Sep 28 02:27:53	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:27:53	ppp		[opt1_link0] Link: reconnection attempt 1
    Sep 28 02:27:50	kernel		.done.
    Sep 28 02:27:50	php-fpm	360	/rc.dyndns.update: phpDynDNS (xxx.xxxxxx.xxx): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry.
    Sep 28 02:27:49	ppp		[opt1_link0] Link: reconnection attempt 1 in 4 seconds
    Sep 28 02:27:49	ppp		[opt1_link0] LCP: Down event
    Sep 28 02:27:49	ppp		[opt1_link0] Link: DOWN event
    Sep 28 02:27:49	ppp		[opt1_link0] PPPoE connection timeout after 9 seconds
    -----
    Sep 28 02:27:40	ppp		[opt1_link0] PPPoE: Connecting to ''
    Sep 28 02:27:40	ppp		[opt1_link0] LCP: LayerStart
    Sep 28 02:27:40	ppp		[opt1_link0] LCP: state change Initial --> Starting
    Sep 28 02:27:40	ppp		[opt1_link0] LCP: Open event
    Sep 28 02:27:40	kernel		ng0: changing name to 'pppoe0'
    Sep 28 02:27:40	ppp		[opt1_link0] Link: OPEN event
    Sep 28 02:27:40	ppp		[opt1] Bundle: Interface ng0 created
    Sep 28 02:27:40	ppp		web: web is not running
    Sep 28 02:27:40	ppp		process 14813 started, version 5.8 (nobody@pfSense_factory-v2_4_4_armv6-pfSense_factory-v2_4_4-job-10 03:28 5-Sep-2018)
    Sep 28 02:27:40	ppp		Multi-link PPP daemon for FreeBSD
    Sep 28 02:27:40	kernel		mvneta0: link state changed to UP
    Sep 28 02:27:40	check_reload_status		Linkup starting mvneta0
    

  • Netgate Administrator

    Hmm, nothing.
    In that case I would assign mvneta0 as an interface and enable it. Then run a packet capture on there to see if anything at all it coming back from the other end. Or indeed if anything is actually being sent.

    Steve



  • on 2.4.4 it looks like they bundle the actual interface with a pppoe one in a different way than was done on 2.4.3 which is causing this issue. I've deleted the interface entirely, and re-configured wan2 on mvneta0 as a static IP, and then switched it to pppoe at which point the system bundles it into ng0 and changes the wan2 interface assignment.


  • Netgate Administrator

    Those logs look identical to what I have for connections to Plusnet and BT except that I see logged replies.

    I would want to check a pcap to make sure anything is actually being sent or received.

    Steve



  • the (1) file is a packet capture of wan2 (which is the pppoe0 bundled interface during a connect request, the second is mvneta0 (assigned to opt3) while requesting a pppoe connection on wan2, both were in promiscuous mode.

    I'm getting a straight up DSL modem delivered to test with the connection since it looks like the draytek router is still looking for the access points which used to be attached to it even though it's supposed to be in just bridge mode...

    0_1538111765949_Downloads.7z


  • Netgate Administrator

    Hmm, yeah, so absolutely nothing coming back over the connection.

    The Draytek is certainly still configured with some info there as you say.

    If a straight modem behaves the same next step might be to try to capture using a mirror port on the Ethernet connection just to be sure the NIC isn't filtering that traffic.

    Steve



  • I'm facing very similar but not identical issues (here)



  • Swapping in a straight up DSL modem resolved this issue. Looks like the draytek router was to blame, perhaps a power outage reset it into an unusable state etc.