PPPoE Setup Problems

I am having issues configuring PPPoE on a new Nethserver installation. I have two WAN interfaces so as to provide a backup internet connection. The primary internet connection at this location is a cable internet connection with a static IP address, and it works properly. The problem is that the DSL connection always shows as down. I have deleted the interface and re-added it, but I always get the same error.

Task completed with errors
S70network-start #9 (exit status 256)

The error logged in /var/log/messages is:

Jul 27 20:02:54 gateway pppd[29848]: LCP: timeout sending Config-Requests
Jul 27 20:02:54 gateway pppd[29848]: Connection terminated.
Jul 27 20:02:54 gateway pppd[29848]: Modem hangup
Jul 27 20:02:58 gateway pppoe[29849]: Timeout waiting for PADO packets
Jul 27 20:02:58 gateway pppd[29848]: Exit.
Jul 27 20:02:58 gateway pppoe-connect: PPPoE connection lost; attempting re-connection.
Jul 27 20:03:03 gateway pppd[29930]: pppd 2.4.5 started by root, uid 0
Jul 27 20:03:03 gateway pppd[29930]: Using interface ppp0
Jul 27 20:03:03 gateway pppd[29930]: Connect: ppp0 <–> /dev/pts/0
Jul 27 20:03:17 gateway dnsmasq-dhcp[10897]: no address range available for DHCP request via eth2
Jul 27 20:03:29 gateway esmith::event[28581]: Bringing up interface ppp0: /usr/sbin/adsl-start: line 217: 29824 Terminated $CONNECT “$@” > /dev/null 2>&1
Jul 27 20:03:29 gateway esmith::event[28581]: [FAILED]#015
Jul 27 20:03:29 gateway esmith::event[28581]: Action: /etc/e-smith/events/interface-update/S70network-start FAILED: 1 [85.746711]
Jul 27 20:03:29 gateway esmith::event[28581]: Action: /etc/e-smith/events/interface-update/S75interface-config-hostname SUCCESS [0.254547]
Jul 27 20:03:29 gateway esmith::event[30008]: Event: static-routes-save
Jul 27 20:03:29 gateway esmith::event[30008]: Action: /etc/e-smith/events/static-routes-save/S15network-route-down SUCCESS [0.157121]
Jul 27 20:03:29 gateway esmith::event[30008]: Action: /etc/e-smith/events/static-routes-save/S25interface-config-write SUCCESS [0.167972]
Jul 27 20:03:30 gateway esmith::event[30008]: Action: /etc/e-smith/events/static-routes-save/S30interface-config-write-pppoe SUCCESS [0.160727]
Jul 27 20:03:30 gateway esmith::event[30008]: Action: /etc/e-smith/events/static-routes-save/S35network-route-up SUCCESS [0.224684]
Jul 27 20:03:30 gateway esmith::event[30008]: Event: static-routes-save SUCCESS
Jul 27 20:03:30 gateway esmith::event[28581]: Action: /etc/e-smith/events/interface-update/S80static-routes-save SUCCESS [0.881564]
Jul 27 20:03:30 gateway esmith::event[28581]: [INFO] squid is disabled: skipped
Jul 27 20:03:30 gateway esmith::event[28581]: [INFO]
Jul 27 20:03:30 gateway esmith::event[28581]: [INFO] service ntopng restart
Jul 27 20:03:32 gateway kernel: device eth0 left promiscuous mode
Jul 27 20:03:32 gateway kernel: device eth1 left promiscuous mode
Jul 27 20:03:32 gateway kernel: ntopng[10830]: segfault at 8 ip 00000000004541c0 sp 00007ffd16df3020 error 4 in ntopng[400000+208000]
Jul 27 20:03:32 gateway kernel: device eth2 left promiscuous mode
Jul 27 20:03:32 gateway kernel: device eth3 left promiscuous mode
Jul 27 20:03:32 gateway init: ntopng main process (10830) killed by SEGV signal
Jul 27 20:03:33 gateway esmith::event[28581]: ntopng start/running, process 30049
Jul 27 20:03:33 gateway esmith::event[28581]: [INFO] ntopng restart
Jul 27 20:03:33 gateway ntopng: [pro/NtopPro.cpp:100] ERROR: [LICENSE] Invalid or missing ntopng License [Empty license file]
Jul 27 20:03:33 gateway ntopng: [pro/NtopPro.cpp:111] WARNING: [LICENSE] ntopng will now run in pro mode for 10 minutes
Jul 27 20:03:33 gateway ntopng: [pro/NtopPro.cpp:113] WARNING: [LICENSE] before returning to community mode
Jul 27 20:03:33 gateway ntopng: [pro/NtopPro.cpp:114] WARNING: [LICENSE] You can buy a permanent license at http://shop.ntop.org
Jul 27 20:03:33 gateway ntopng: [pro/NtopPro.cpp:115] WARNING: [LICENSE] or run ntopng in community mode starting
Jul 27 20:03:33 gateway ntopng: [pro/NtopPro.cpp:116] WARNING: [LICENSE] ntopng --community
Jul 27 20:03:33 gateway esmith::event[28581]: [INFO] service dnsmasq restart
Jul 27 20:03:33 gateway kernel: device eth0 entered promiscuous mode
Jul 27 20:03:33 gateway dnsmasq[10897]: exiting on receipt of SIGTERM
Jul 27 20:03:33 gateway kernel: device eth1 entered promiscuous mode
Jul 27 20:03:33 gateway esmith::event[28581]: Shutting down dnsmasq: [ OK ]#015
Jul 27 20:03:33 gateway dnsmasq[30114]: started, version 2.48 cachesize 4000
Jul 27 20:03:33 gateway dnsmasq[30114]: compile time options: IPv6 GNU-getopt DBus no-I18N DHCP TFTP "–bind-interfaces with SO_BINDTODEVICE"
Jul 27 20:03:33 gateway dnsmasq-dhcp[30114]: DHCP, IP range 192.168.1.100 – 192.168.1.200, lease time 1h
Jul 27 20:03:33 gateway dnsmasq-tftp[30114]: TFTP root is /var/lib/tftpboot
Jul 27 20:03:33 gateway dnsmasq[30114]: using nameserver 8.8.8.8#53
Jul 27 20:03:33 gateway dnsmasq[30114]: using nameserver 10.0.0.5#53
Jul 27 20:03:33 gateway dnsmasq[30114]: read /etc/hosts - 2 addresses
Jul 27 20:03:33 gateway dnsmasq[30114]: read /etc/dnsmasq-dhcp-hosts
Jul 27 20:03:33 gateway esmith::event[28581]: Starting dnsmasq: [ OK ]#015
Jul 27 20:03:33 gateway esmith::event[28581]: [INFO] dnsmasq restart
Jul 27 20:03:33 gateway esmith::event[28581]: Action: /etc/e-smith/events/actions/adjust-services SUCCESS [2.943166]
Jul 27 20:03:33 gateway kernel: device eth2 entered promiscuous mode
Jul 27 20:03:33 gateway kernel: device eth2.4 entered promiscuous mode
Jul 27 20:03:33 gateway kernel: device eth3 entered promiscuous mode
Jul 27 20:03:33 gateway esmith::event[30118]: Event: nethserver-firewall-base-save interface-update
Jul 27 20:03:33 gateway esmith::event[30118]: Action: /etc/e-smith/events/nethserver-firewall-base-save/S02providers-cleanup SUCCESS [0.156264]
Jul 27 20:03:33 gateway esmith::event[30118]: expanding /var/www/html/wpad.dat
Jul 27 20:03:34 gateway pppd[29930]: LCP: timeout sending Config-Requests
Jul 27 20:03:34 gateway pppd[29930]: Connection terminated.
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/masq
Jul 27 20:03:34 gateway pppd[29930]: Modem hangup
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/actions
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/rules
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/nat
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/hosts
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/zones
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/providers
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/tcpri
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/tcrules
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/stoppedrules
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/rtrules
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/interfaces
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/maclist
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/tcinterfaces
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/shorewall.conf
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/tunnels
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/shorewall/policy
Jul 27 20:03:34 gateway esmith::event[30118]: expanding /etc/lsm/lsm.conf
Jul 27 20:03:34 gateway esmith::event[30118]: Action: /etc/e-smith/events/actions/generic_template_expand SUCCESS [0.850633]
Jul 27 20:03:34 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:03:38 gateway pppoe[29931]: Timeout waiting for PADO packets
Jul 27 20:03:38 gateway logger: Shorewall restarted
Jul 27 20:03:38 gateway esmith::event[30118]: [NOTICE] Shorewall restart
Jul 27 20:03:38 gateway pppd[29930]: Exit.
Jul 27 20:03:38 gateway esmith::event[30118]: Action: /etc/e-smith/events/nethserver-firewall-base-save/S89nethserver-shorewall-restart SUCCESS [3.497349]
Jul 27 20:03:38 gateway esmith::event[30118]: [INFO] service lsm restart
Jul 27 20:03:38 gateway init: lsm main process (26963) killed by TERM signal
Jul 27 20:03:38 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:03:38 gateway esmith::event[30118]: lsm start/running, process 30714
Jul 27 20:03:38 gateway esmith::event[30118]: [INFO] lsm restart
Jul 27 20:03:38 gateway esmith::event[30118]: Action: /etc/e-smith/events/actions/adjust-services SUCCESS [0.581169]
Jul 27 20:03:38 gateway esmith::event[30118]: Event: nethserver-firewall-base-save SUCCESS
Jul 27 20:03:38 gateway esmith::event[28581]: Action: /etc/e-smith/events/interface-update/S90firewall-adjust SUCCESS [5.395724]
Jul 27 20:03:38 gateway lsm[30714]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:03:38 gateway lsm[30714]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:03:38 gateway esmith::event[30715]: Event: trusted-networks-modify
Jul 27 20:03:39 gateway esmith::event[30715]: expanding /etc/samba/smb.conf
Jul 27 20:03:39 gateway esmith::event[30715]: expanding /etc/snort/snort.conf
Jul 27 20:03:39 gateway esmith::event[30715]: expanding /etc/httpd/conf/httpd.conf
Jul 27 20:03:39 gateway esmith::event[30715]: Action: /etc/e-smith/events/actions/generic_template_expand SUCCESS [0.661792]
Jul 27 20:03:39 gateway esmith::event[30715]: Action: /etc/e-smith/events/trusted-networks-modify/S10legacy-call-network-create SUCCESS [0.003906]
Jul 27 20:03:39 gateway esmith::event[30715]: Stopping snort: [FAILED]#015
Jul 27 20:03:39 gateway esmith::event[30715]: [INFO] snortd is disabled and has been stopped
Jul 27 20:03:39 gateway esmith::event[30715]:
Jul 27 20:03:40 gateway esmith::event[30715]: [INFO] service smb restart
Jul 27 20:03:40 gateway esmith::event[30715]: Shutting down SMB services: [ OK ]#015
Jul 27 20:03:40 gateway esmith::event[30715]: Starting SMB services: [ OK ]#015
Jul 27 20:03:40 gateway esmith::event[30715]: [INFO] smb restart
Jul 27 20:03:40 gateway smbd[30807]: [2016/07/27 20:03:40.271313, 0] printing/print_cups.c:151(cups_connect)
Jul 27 20:03:40 gateway smbd[30807]: Unable to connect to CUPS server localhost:631 - Connection refused
Jul 27 20:03:40 gateway smbd[30806]: [2016/07/27 20:03:40.271746, 0] printing/print_cups.c:528(cups_async_callback)
Jul 27 20:03:40 gateway smbd[30806]: failed to retrieve printer list: NT_STATUS_UNSUCCESSFUL
Jul 27 20:03:40 gateway esmith::event[30715]: [INFO] service nmb restart
Jul 27 20:03:41 gateway esmith::event[30715]: Shutting down NMB services: [ OK ]#015
Jul 27 20:03:41 gateway esmith::event[30715]: Starting NMB services: [ OK ]#015
Jul 27 20:03:41 gateway esmith::event[30715]: [INFO] nmb restart
Jul 27 20:03:41 gateway esmith::event[30715]: [INFO] service httpd reload
Jul 27 20:03:41 gateway esmith::event[30715]: Reloading httpd:
Jul 27 20:03:41 gateway esmith::event[30715]: [INFO] httpd reload
Jul 27 20:03:41 gateway esmith::event[30715]: Action: /etc/e-smith/events/actions/adjust-services SUCCESS [2.149669]
Jul 27 20:03:42 gateway esmith::event[30867]: Event: nethserver-firewall-base-save trusted-networks-modify
Jul 27 20:03:42 gateway esmith::event[30867]: Action: /etc/e-smith/events/nethserver-firewall-base-save/S02providers-cleanup SUCCESS [0.154525]
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /var/www/html/wpad.dat
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/masq
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/actions
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/rules
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/nat
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/hosts
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/zones
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/providers
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/tcpri
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/tcrules
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/stoppedrules
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/rtrules
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/interfaces
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/maclist
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/tcinterfaces
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/shorewall.conf
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/tunnels
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/shorewall/policy
Jul 27 20:03:42 gateway esmith::event[30867]: expanding /etc/lsm/lsm.conf
Jul 27 20:03:42 gateway esmith::event[30867]: Action: /etc/e-smith/events/actions/generic_template_expand SUCCESS [0.830121]
Jul 27 20:03:46 gateway logger: Shorewall restarted
Jul 27 20:03:46 gateway esmith::event[30867]: [NOTICE] Shorewall restart
Jul 27 20:03:46 gateway esmith::event[30867]: Action: /etc/e-smith/events/nethserver-firewall-base-save/S89nethserver-shorewall-restart SUCCESS [3.346355]
Jul 27 20:03:46 gateway esmith::event[30867]: [INFO] service lsm restart
Jul 27 20:03:46 gateway init: lsm main process (30714) killed by TERM signal
Jul 27 20:03:46 gateway esmith::event[30867]: lsm start/running, process 31464
Jul 27 20:03:46 gateway esmith::event[30867]: [INFO] lsm restart
Jul 27 20:03:46 gateway esmith::event[30867]: Action: /etc/e-smith/events/actions/adjust-services SUCCESS [0.558535]
Jul 27 20:03:46 gateway esmith::event[30867]: Event: nethserver-firewall-base-save SUCCESS
Jul 27 20:03:46 gateway esmith::event[30715]: Action: /etc/e-smith/events/trusted-networks-modify/S94firewall-adjust SUCCESS [5.193203]
Jul 27 20:03:46 gateway esmith::event[30715]: Event: trusted-networks-modify SUCCESS
Jul 27 20:03:46 gateway esmith::event[28581]: Action: /etc/e-smith/events/interface-update/S95trusted-networks-modify SUCCESS [8.182117]
Jul 27 20:03:46 gateway esmith::event[28581]: Event: interface-update FAILED
Jul 27 20:03:46 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:03:46 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:03:49 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:03:56 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:03:56 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:06 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:06 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:11 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:04:16 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:17 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:25 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:04:27 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:27 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:34 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:04:37 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:37 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:40 gateway smbd[31677]: [2016/07/27 20:04:40.335625, 0] printing/print_cups.c:151(cups_connect)
Jul 27 20:04:40 gateway smbd[31677]: Unable to connect to CUPS server localhost:631 - Connection refused
Jul 27 20:04:40 gateway smbd[30806]: [2016/07/27 20:04:40.336328, 0] printing/print_cups.c:528(cups_async_callback)
Jul 27 20:04:40 gateway smbd[30806]: failed to retrieve printer list: NT_STATUS_UNSUCCESSFUL
Jul 27 20:04:44 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2
Jul 27 20:04:47 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:47 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:57 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:04:57 gateway lsm[31464]: failed to bind to ping interface device “ppp0”, "No such device"
Jul 27 20:05:00 gateway dnsmasq-dhcp[30114]: no address range available for DHCP request via eth2

I believe that captures all the relevant bits on the PPPoE configuration attempt. Where should I look next? Is there any further information that I con provide to you?

It seems that the ISP ppp server doesn’t respond.
AFAIK, two pppoe connections aren’t supported (not sure if it’s a centos limitation or nethserver).
I don’t have experience in troubleshooting pppoe problems.

Not really a solution, but just a workaround: buy a router for PPPoE, then attach to NS one PPPoE connection and the router itself :wink:

At the end, you will be able to use both links in a multiwan setup.

There is only one PPPoE connection. The other is cable.

Sort of attempted something similar. The DSL modem has routing functionality so I attempted to just add it as a DHCP internet connection and let it pull an address. While that seemed to work for a little bit, I believe the double-NAT caused issues with the multi-WAN causing it to take that connection offline.