Show Posts
1
General Discussion / PPPoE WAN connection drops
« on: May 25, 2022, 11:51:50 pm »
Hello
I know there are many PPPoE-related topics here (and in other places), but I did not find any help so far...
I've upgraded from the TPLink router to OPNSense (mini PC: GIGABYTE GA-J1900N-D3V Mini ITX with dual NIC / 4G RAM / 120SSD + 320GB HDD for logs) to get some more control, security, and extra functionalities but having trouble fixing my PPPoE connection...
I've been struggling with this for 2+ months now, I have tried googling for some help, and found similar problems, but none of the solutions mentioned (eg. "Disable Gateway Monitoring" or removing IPv6 gateway) there worked for me...
The problem is that PPPoE goes down occasionally, and the only thing that helps is a reboot (just restarting the interface has no effect). As a workaround, I created some shell script and added it to cron, so it checked every minute if there is Internet access, and if not, it reboots the router. Problem is that it takes some time, and if it occurs a few times a day, it is quite annoying.
From my observations, it occurs more often, when traffic is high (eg. I run speedtest.net or saturate the connection with multiple downloads and other high traffic use), but it happens occasionally, even with very low LAN/WAN traffic.
/var/log/ppps/ppps_20220525.log:
I know there are many PPPoE-related topics here (and in other places), but I did not find any help so far...
I've upgraded from the TPLink router to OPNSense (mini PC: GIGABYTE GA-J1900N-D3V Mini ITX with dual NIC / 4G RAM / 120SSD + 320GB HDD for logs) to get some more control, security, and extra functionalities but having trouble fixing my PPPoE connection...
I've been struggling with this for 2+ months now, I have tried googling for some help, and found similar problems, but none of the solutions mentioned (eg. "Disable Gateway Monitoring" or removing IPv6 gateway) there worked for me...
The problem is that PPPoE goes down occasionally, and the only thing that helps is a reboot (just restarting the interface has no effect). As a workaround, I created some shell script and added it to cron, so it checked every minute if there is Internet access, and if not, it reboots the router. Problem is that it takes some time, and if it occurs a few times a day, it is quite annoying.
From my observations, it occurs more often, when traffic is high (eg. I run speedtest.net or saturate the connection with multiple downloads and other high traffic use), but it happens occasionally, even with very low LAN/WAN traffic.
/var/log/ppps/ppps_20220525.log:
Code: [Select]
[code]<30>1 2022-05-25T22:55:58+02:00 OPNsense.bursztynowa4 ppp 64823 - [meta sequenceId="52"] [opt1_link0] Link: DOWN event
<30>1 2022-05-25T22:55:58+02:00 OPNsense.bursztynowa4 ppp 64823 - [meta sequenceId="53"] [opt1_link0] LCP: Down event
<30>1 2022-05-25T22:55:58+02:00 OPNsense.bursztynowa4 ppp 64823 - [meta sequenceId="54"] [opt1_link0] Link: reconnection attempt 5 in 1 seconds
<30>1 2022-05-25T22:55:59+02:00 OPNsense.bursztynowa4 ppp 64823 - [meta sequenceId="55"] [opt1_link0] Link: reconnection attempt 5
<30>1 2022-05-25T22:55:59+02:00 OPNsense.bursztynowa4 ppp 64823 - [meta sequenceId="56"] [opt1_link0] PPPoE: Connecting to ''
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="1"] Multi-link PPP daemon for FreeBSD
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="2"]
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="3"] process 19916 started, version 5.9
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="4"] web: web is not running
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="5"] [opt1] Bundle: Interface ng0 created
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="6"] [opt1_link0] Link: OPEN event
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="7"] [opt1_link0] LCP: Open event
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="8"] [opt1_link0] LCP: state change Initial --> Starting
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="9"] [opt1_link0] LCP: LayerStart
<30>1 2022-05-25T22:58:03+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="10"] [opt1_link0] PPPoE: Connecting to ''
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="11"] PPPoE: rec'd ACNAME "BRAS1_JUST_SM"
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="12"] [opt1_link0] PPPoE: connection successful
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="13"] [opt1_link0] Link: UP event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="14"] [opt1_link0] LCP: Up event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="15"] [opt1_link0] LCP: state change Starting --> Req-Sent
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="16"] [opt1_link0] LCP: SendConfigReq #1
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="17"] [opt1_link0] PROTOCOMP
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="18"] [opt1_link0] MRU 1492
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="19"] [opt1_link0] MAGICNUM 0xafe7d97c
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="20"] [opt1_link0] LCP: rec'd Configure Request #1 (Req-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="21"] [opt1_link0] AUTHPROTO PAP
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="22"] [opt1_link0] MRU 1492
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="23"] [opt1_link0] MAGICNUM 0x32dc8633
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="24"] [opt1_link0] LCP: SendConfigAck #1
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="25"] [opt1_link0] AUTHPROTO PAP
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="26"] [opt1_link0] MRU 1492
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="27"] [opt1_link0] MAGICNUM 0x32dc8633
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="28"] [opt1_link0] LCP: state change Req-Sent --> Ack-Sent
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="29"] [opt1_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="30"] [opt1_link0] PROTOCOMP
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="31"] [opt1_link0] LCP: SendConfigReq #2
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="32"] [opt1_link0] MRU 1492
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="33"] [opt1_link0] MAGICNUM 0xafe7d97c
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="34"] [opt1_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="35"] [opt1_link0] MRU 1492
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="36"] [opt1_link0] MAGICNUM 0xafe7d97c
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="37"] [opt1_link0] LCP: state change Ack-Sent --> Opened
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="38"] [opt1_link0] LCP: auth: peer wants PAP, I want nothing
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="39"] [opt1_link0] PAP: using authname "balickikrzysztof"
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="40"] [opt1_link0] PAP: sending REQUEST #1 len: 32
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="41"] [opt1_link0] LCP: LayerUp
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="42"] [opt1_link0] PAP: rec'd ACK #0 len: 13
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="43"] [opt1_link0] MESG: Login ok
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="44"] [opt1_link0] LCP: authorization successful
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="45"] [opt1_link0] Link: Matched action 'bundle "opt1" ""'
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="46"] [opt1_link0] Link: Join bundle "opt1"
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="47"] [opt1] Bundle: Status update: up 1 link, total bandwidth 64000 bps
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="48"] [opt1] IPCP: Open event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="49"] [opt1] IPCP: state change Initial --> Starting
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="50"] [opt1] IPCP: LayerStart
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="51"] [opt1] IPV6CP: Open event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="52"] [opt1] IPV6CP: state change Initial --> Starting
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="53"] [opt1] IPV6CP: LayerStart
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="54"] [opt1] IPCP: Up event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="55"] [opt1] IPCP: state change Starting --> Req-Sent
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="56"] [opt1] IPCP: SendConfigReq #1
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="57"] [opt1] IPADDR 0.0.0.0
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="58"] [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="59"] [opt1] IPV6CP: Up event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="60"] [opt1] IPV6CP: state change Starting --> Req-Sent
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="61"] [opt1] IPV6CP: SendConfigReq #1
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="62"] [opt1] IPCP: rec'd Configure Request #1 (Req-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="63"] [opt1] IPADDR 10.100.44.103
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="64"] [opt1] 10.100.44.103 is OK
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="65"] [opt1] IPCP: SendConfigAck #1
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="66"] [opt1] IPADDR 10.100.44.103
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="67"] [opt1] IPCP: state change Req-Sent --> Ack-Sent
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="68"] [opt1] IPCP: rec'd Configure Reject #1 (Ack-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="69"] [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="70"] [opt1] IPCP: SendConfigReq #2
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="71"] [opt1] IPADDR 0.0.0.0
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="72"] [opt1_link0] LCP: rec'd Protocol Reject #2 (Opened)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="73"] [opt1_link0] LCP: protocol IPV6CP was rejected
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="74"] [opt1] IPV6CP: protocol was rejected by peer
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="75"] [opt1] IPV6CP: state change Req-Sent --> Stopped
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="76"] [opt1] IPV6CP: LayerFinish
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="77"] [opt1] IPCP: rec'd Configure Nak #2 (Ack-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="78"] [opt1] IPADDR 185.246.205.52
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="79"] [opt1] 185.246.205.52 is OK
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="80"] [opt1] IPCP: SendConfigReq #3
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="81"] [opt1] IPADDR 185.246.205.52
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="82"] [opt1] IPCP: rec'd Configure Ack #3 (Ack-Sent)
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="83"] [opt1] IPADDR 185.246.205.52
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="84"] [opt1] IPCP: state change Ack-Sent --> Opened
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="85"] [opt1] IPCP: LayerUp
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="86"] [opt1] 185.246.205.52 -> 10.100.44.103
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="87"] [opt1] IFACE: Up event
<30>1 2022-05-25T22:58:06+02:00 OPNsense.bursztynowa4 ppp 19916 - [meta sequenceId="88"] [opt1] IFACE: Rename interface ng0 to pppoe3
[/code]