Netgate Discussion Forum
    • Categories
    • Recent
    • Tags
    • Popular
    • Users
    • Search
    • Register
    • Login

    WAN periodically Rebooting

    Scheduled Pinned Locked Moved General pfSense Questions
    184 Posts 5 Posters 22.9k Views
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • stephenw10S
      stephenw10 Netgate Administrator
      last edited by stephenw10

      Yup I assume so. I used the same battery backup unit I was using with the HG612. (So, yes, it must be!)

      D 1 Reply Last reply Reply Quote 0
      • D
        diyhouse @stephenw10
        last edited by

        @stephenw10 Hi Stephen,.. well MT992 installed... and although speed test seems to have some improvements ( but this could just be time of day etc ), ..'uptime' on the other hand does not seem to be much better,... as my 1st disconnect occurred 50mins after 1st boot and connect of modem,.. logs as follows

        I see at 13:59:51 connection is lost,.. but how do I see what caused the the event...?
        Many Tx

        Aug 12 14:15:17	php	31129	[pfBlockerNG] No changes to Firewall rules, skipping Filter Reload
        Aug 12 14:15:00	php	31129	[pfBlockerNG] Starting cron process.
        Aug 12 14:15:00	sshguard	32443	Now monitoring attacks.
        Aug 12 14:15:00	sshguard	54598	Exiting on signal.
        Aug 12 14:06:00	sshguard	54598	Now monitoring attacks.
        Aug 12 14:06:00	sshguard	28085	Exiting on signal.
        Aug 12 14:04:00	sshguard	28085	Now monitoring attacks.
        Aug 12 14:04:00	sshguard	94791	Exiting on signal.
        Aug 12 14:01:00	sshguard	94791	Now monitoring attacks.
        Aug 12 14:01:00	sshguard	18557	Exiting on signal.
        Aug 12 14:00:56	ppp	19091	[wan_link0] LCP: no reply to 2 echo request(s)
        Aug 12 14:00:46	ppp	19091	[wan_link0] LCP: no reply to 1 echo request(s)
        Aug 12 14:00:30	php_pfb	85217	[pfBlockerNG] filterlog daemon started
        Aug 12 14:00:30	php	84813	[pfBlockerNG] DNSBL parser daemon started
        Aug 12 14:00:29	vnstatd	85867	Error: pidfile "/var/run/vnstat/vnstat.pid" lock failed (Resource temporarily unavailable), exiting.
        Aug 12 14:00:29	vnstatd	81408	Monitoring (11): pppoe0 (1000 Mbit) pfsync0 (1000 Mbit) pflog0 (1000 Mbit) igb3.30 (1000 Mbit) igb3.20 (1000 Mbit) igb3.10 (1000 Mbit) igb3 (1000 Mbit) igb2 (10 Mbit) igb1 (1000 Mbit) igb0 (1000 Mbit) enc0 (1000 Mbit)
        Aug 12 14:00:29	vnstatd	81408	Data retention: 48 5MinuteHours, 4 HourlyDays, 62 DailyDays, 25 MonthlyMonths, -1 YearlyYears, 20 TopDayEntries
        Aug 12 14:00:29	vnstatd	81408	vnStat daemon 2.11 (pid:81408 uid:0 gid:0, SQLite 3.43.1)
        Aug 12 14:00:29	tail_pfb	84427	[pfBlockerNG] Firewall Filter Service started
        Aug 12 14:00:29	lighttpd_pfb	81906	[pfBlockerNG] DNSBL Webserver started
        Aug 12 14:00:29	php_pfb	80281	[pfBlockerNG] filterlog daemon stopped
        Aug 12 14:00:29	tail_pfb	78860	[pfBlockerNG] Firewall Filter Service stopped
        Aug 12 14:00:29	lighttpd_pfb	77070	[pfBlockerNG] DNSBL Webserver stopped
        Aug 12 14:00:29	vnstatd	22588	SIGTERM received, exiting.
        Aug 12 14:00:19	vnstatd	49205	Error: pidfile "/var/run/vnstat/vnstat.pid" lock failed (Resource temporarily unavailable), exiting.
        Aug 12 14:00:19	bandwidthd	49002	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	48834	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	49002	Opening igb1
        Aug 12 14:00:19	bandwidthd	48764	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	48834	Opening igb1
        Aug 12 14:00:19	bandwidthd	49176	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	49176	Opening igb1
        Aug 12 14:00:19	bandwidthd	48764	Opening igb1
        Aug 12 14:00:19	bandwidthd	48227	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	48227	Opening igb1
        Aug 12 14:00:19	bandwidthd	48477	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	47880	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	48649	Packet Encoding: Ethernet
        Aug 12 14:00:19	bandwidthd	48477	Opening igb1
        Aug 12 14:00:19	bandwidthd	48649	Opening igb1
        Aug 12 14:00:19	bandwidthd	47880	Opening igb1
        Aug 12 14:00:19	bandwidthd	46528	Monitoring subnet 192.168.3.0 with netmask 255.255.255.0
        Aug 12 14:00:19	bandwidthd	46381	Monitoring subnet 192.168.3.0 with netmask 255.255.255.0
        Aug 12 14:00:19	php-fpm	18605	/rc.start_packages: The command '/usr/local/etc/rc.d/bandwidthd.sh stop' returned exit code '1', the output was 'killall: warning: kill -TERM 63951: No such process killall: warning: kill -TERM 65017: No such process'
        Aug 12 14:00:18	php-fpm	18605	/rc.start_packages: Restarting/Starting all packages.
        Aug 12 14:00:17	check_reload_status	430	Reloading filter
        Aug 12 14:00:17	check_reload_status	430	Starting packages
        Aug 12 14:00:17	php-fpm	88053	/rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 86.146.217.76 -> 86.168.50.162 - Restarting packages.
        Aug 12 14:00:16	php-fpm	62823	/rc.openvpn: Gateway, none 'available' for inet6, use the first one configured. 'LAN1_DHCP6'
        Aug 12 14:00:16	php-fpm	62823	/rc.openvpn: Gateway, none 'available' for inet, use the first one configured. '1_WAN_PPPOE'
        Aug 12 14:00:14	php-fpm	88053	/rc.newwanip: Creating rrd update script
        Aug 12 14:00:14	php-fpm	88053	/rc.newwanip: Resyncing OpenVPN instances for interface 1_WAN.
        Aug 12 14:00:14	php-fpm	88053	/rc.newwanip: IP Address has changed, killing states on former IP Address 86.146.217.76.
        Aug 12 14:00:14	php-fpm	88053	/rc.newwanip: Gateway, none 'available' for inet6, use the first one configured. 'LAN1_DHCP6'
        Aug 12 14:00:14	check_reload_status	430	Reloading filter
        Aug 12 14:00:14	check_reload_status	430	Restarting OpenVPN tunnels/interfaces
        Aug 12 14:00:14	check_reload_status	430	Restarting IPsec tunnels
        Aug 12 14:00:14	check_reload_status	430	updating dyndns 1_WAN_PPPOE
        Aug 12 14:00:14	php-fpm	88053	/rc.newwanip: Default gateway setting Interface 1_WAN_PPPOE Gateway as default.
        Aug 12 14:00:14	rc.gateway_alarm	42980	>>> Gateway alarm: 1_WAN_PPPOE (Addr:172.16.12.102 Alarm:1 RTT:0ms RTTsd:0ms Loss:100%)
        Aug 12 14:00:14	php-fpm	88053	/rc.newwanip: Gateway, none 'available' for inet, use the first one configured. '1_WAN_PPPOE'
        Aug 12 14:00:09	ppp	19091	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
        Aug 12 14:00:08	php-fpm	88053	/rc.newwanip: rc.newwanip: on (IP address: 86.168.50.162) (interface: 1_WAN[wan]) (real interface: pppoe0).
        Aug 12 14:00:08	php-fpm	88053	/rc.newwanip: rc.newwanip: Info: starting on pppoe0.
        Aug 12 14:00:07	ppp	19091	[wan] IFACE: Add description "1_WAN"
        Aug 12 14:00:07	ppp	19091	[wan] IFACE: Rename interface ng0 to pppoe0
        Aug 12 14:00:07	ppp	19091	[wan] IFACE: Up event
        Aug 12 14:00:07	check_reload_status	430	rc.newwanip starting pppoe0
        Aug 12 14:00:06	check_reload_status	430	Rewriting resolv.conf
        Aug 12 14:00:06	ppp	19091	[wan] 86.168.50.162 -> 172.16.12.102
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: LayerUp
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: state change Ack-Sent --> Opened
        Aug 12 14:00:06	ppp	19091	[wan] SECDNS 81.139.57.100
        Aug 12 14:00:06	ppp	19091	[wan] PRIDNS 81.139.56.100
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 86.168.50.162
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: rec'd Configure Ack #7 (Ack-Sent)
        Aug 12 14:00:06	ppp	19091	[wan] SECDNS 81.139.57.100
        Aug 12 14:00:06	ppp	19091	[wan] PRIDNS 81.139.56.100
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 86.168.50.162
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: SendConfigReq #7
        Aug 12 14:00:06	ppp	19091	[wan] SECDNS 81.139.57.100
        Aug 12 14:00:06	ppp	19091	[wan] PRIDNS 81.139.56.100
        Aug 12 14:00:06	ppp	19091	[wan] 86.168.50.162 is OK
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 86.168.50.162
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: rec'd Configure Nak #6 (Ack-Sent)
        Aug 12 14:00:06	ppp	19091	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
        Aug 12 14:00:06	ppp	19091	[wan] SECDNS 0.0.0.0
        Aug 12 14:00:06	ppp	19091	[wan] PRIDNS 0.0.0.0
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 0.0.0.0
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: SendConfigReq #6
        Aug 12 14:00:06	ppp	19091	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: rec'd Configure Reject #5 (Ack-Sent)
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: state change Req-Sent --> Ack-Sent
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 172.16.12.102
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: SendConfigAck #187
        Aug 12 14:00:06	ppp	19091	[wan] 172.16.12.102 is OK
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 172.16.12.102
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: rec'd Configure Request #187 (Req-Sent)
        Aug 12 14:00:06	ppp	19091	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
        Aug 12 14:00:06	ppp	19091	[wan] SECDNS 0.0.0.0
        Aug 12 14:00:06	ppp	19091	[wan] PRIDNS 0.0.0.0
        Aug 12 14:00:06	ppp	19091	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
        Aug 12 14:00:06	ppp	19091	[wan] IPADDR 0.0.0.0
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: SendConfigReq #5
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: state change Starting --> Req-Sent
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: Up event
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: LayerStart
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: state change Initial --> Starting
        Aug 12 14:00:06	ppp	19091	[wan] IPCP: Open event
        Aug 12 14:00:06	ppp	19091	[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
        Aug 12 14:00:06	ppp	19091	[wan_link0] Link: Join bundle "wan"
        Aug 12 14:00:06	ppp	19091	[wan_link0] Link: Matched action 'bundle "wan" ""'
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: authorization successful
        Aug 12 14:00:06	ppp	19091	[wan_link0] MESG: CHAP authentication success
        Aug 12 14:00:06	ppp	19091	[wan_link0] CHAP: rec'd SUCCESS #1 len: 31
        Aug 12 14:00:06	ppp	19091	[wan_link0] CHAP: sending RESPONSE #1 len: 52
        Aug 12 14:00:06	ppp	19091	[wan_link0] CHAP: Using authname "green-light@service.btclick.com"
        Aug 12 14:00:06	ppp	19091	[wan_link0] Name: "acc-aln2.tbs"
        Aug 12 14:00:06	ppp	19091	[wan_link0] CHAP: rec'd CHALLENGE #1 len: 59
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: LayerUp
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: state change Ack-Sent --> Opened
        Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x53da0584
        Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: rec'd Configure Ack #4 (Ack-Sent)
        Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x53da0584
        Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: SendConfigReq #4
        Aug 12 14:00:06	ppp	19091	[wan_link0] PROTOCOMP
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: rec'd Configure Reject #3 (Ack-Sent)
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: state change Req-Sent --> Ack-Sent
        Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x4fd3fb4b
        Aug 12 14:00:06	ppp	19091	[wan_link0] AUTHPROTO CHAP MD5
        Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: SendConfigAck #58
        Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x4fd3fb4b
        Aug 12 14:00:06	ppp	19091	[wan_link0] AUTHPROTO CHAP MD5
        Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: rec'd Configure Request #58 (Req-Sent)
        Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x53da0584
        Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
        Aug 12 14:00:06	ppp	19091	[wan_link0] PROTOCOMP
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: SendConfigReq #3
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: state change Starting --> Req-Sent
        Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: Up event
        Aug 12 14:00:06	ppp	19091	[wan_link0] Link: UP event
        Aug 12 14:00:06	ppp	19091	[wan_link0] PPPoE: connection successful
        Aug 12 14:00:06	ppp	19091	PPPoE: rec'd ACNAME "acc-aln2.tbs"
        Aug 12 14:00:06	ppp	19091	[wan_link0] PPPoE: Connecting to ''
        Aug 12 14:00:06	ppp	19091	[wan_link0] Link: reconnection attempt 2
        Aug 12 14:00:05	ppp	19091	[wan_link0] Link: reconnection attempt 2 in 1 seconds
        Aug 12 14:00:05	ppp	19091	[wan_link0] LCP: Down event
        Aug 12 14:00:05	ppp	19091	[wan_link0] Link: DOWN event
        Aug 12 14:00:05	ppp	19091	[wan_link0] PPPoE connection timeout after 9 seconds
        Aug 12 13:59:56	ppp	19091	[wan_link0] PPPoE: Connecting to ''
        Aug 12 13:59:56	ppp	19091	[wan_link0] Link: reconnection attempt 1
        Aug 12 13:59:52	ppp	19091	[wan_link0] Link: reconnection attempt 1 in 4 seconds
        Aug 12 13:59:52	ppp	19091	[wan_link0] LCP: LayerDown
        Aug 12 13:59:52	ppp	19091	[wan] Bundle: Last link has gone, no links for bw-manage defined
        Aug 12 13:59:52	ppp	19091	[wan] IPCP: state change Closing --> Initial
        Aug 12 13:59:52	ppp	19091	[wan] Bundle: No NCPs left. Closing links...
        Aug 12 13:59:52	ppp	19091	[wan] IPCP: LayerFinish
        Aug 12 13:59:52	ppp	19091	[wan] IPCP: Down event
        Aug 12 13:59:52	ppp	19091	[wan] IFACE: Set description "1_WAN"
        Aug 12 13:59:52	ppp	19091	[wan] IFACE: Rename interface pppoe0 to pppoe0
        Aug 12 13:59:52	ppp	19091	[wan] IFACE: Down event
        Aug 12 13:59:52	ppp	19091	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
        Aug 12 13:59:52	check_reload_status	430	Rewriting resolv.conf
        Aug 12 13:59:51	ppp	19091	[wan] IPCP: LayerDown
        Aug 12 13:59:51	ppp	19091	[wan] IPCP: SendTerminateReq #4
        Aug 12 13:59:51	ppp	19091	[wan] IPCP: state change Opened --> Closing
        Aug 12 13:59:51	ppp	19091	[wan] IPCP: Close event
        Aug 12 13:59:51	ppp	19091	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
        Aug 12 13:59:51	ppp	19091	[wan_link0] Link: Leave bundle "wan"
        Aug 12 13:59:51	ppp	19091	[wan_link0] LCP: state change Opened --> Starting
        Aug 12 13:59:51	ppp	19091	[wan_link0] LCP: Down event
        Aug 12 13:59:51	ppp	19091	[wan_link0] Link: DOWN event
        Aug 12 13:59:51	ppp	19091	[wan_link0] PPPoE: connection closed
        Aug 12 13:59:33	ppp	19091	[wan_link0] LCP: no reply to 1 echo request(s)
        Aug 12 13:53:00	sshguard	18557	Now monitoring attacks.
        
        D 1 Reply Last reply Reply Quote 0
        • stephenw10S
          stephenw10 Netgate Administrator
          last edited by

          Anything different/additonal in the ppp log?

          1 Reply Last reply Reply Quote 0
          • D
            diyhouse @diyhouse
            last edited by

            @diyhouse Stephen,.. just a thought,.. do I need / require to tweak any settings in the advanced page of the WAN PPPoE connection,.. currently everything is blank ( none checked ) in the advanced page

            D 1 Reply Last reply Reply Quote 0
            • D
              diyhouse @diyhouse
              last edited by

              @diyhouseThis is the prelude to the reboot,...

              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: LayerUp
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: state change Ack-Sent --> Opened
              Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x53da0584
              Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: rec'd Configure Ack #4 (Ack-Sent)
              Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x53da0584
              Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: SendConfigReq #4
              Aug 12 14:00:06	ppp	19091	[wan_link0] PROTOCOMP
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: rec'd Configure Reject #3 (Ack-Sent)
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: state change Req-Sent --> Ack-Sent
              Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x4fd3fb4b
              Aug 12 14:00:06	ppp	19091	[wan_link0] AUTHPROTO CHAP MD5
              Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: SendConfigAck #58
              Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x4fd3fb4b
              Aug 12 14:00:06	ppp	19091	[wan_link0] AUTHPROTO CHAP MD5
              Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: rec'd Configure Request #58 (Req-Sent)
              Aug 12 14:00:06	ppp	19091	[wan_link0] MAGICNUM 0x53da0584
              Aug 12 14:00:06	ppp	19091	[wan_link0] MRU 1492
              Aug 12 14:00:06	ppp	19091	[wan_link0] PROTOCOMP
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: SendConfigReq #3
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: state change Starting --> Req-Sent
              Aug 12 14:00:06	ppp	19091	[wan_link0] LCP: Up event
              Aug 12 14:00:06	ppp	19091	[wan_link0] Link: UP event
              Aug 12 14:00:06	ppp	19091	[wan_link0] PPPoE: connection successful
              Aug 12 14:00:06	ppp	19091	PPPoE: rec'd ACNAME "acc-aln2.tbs"
              Aug 12 14:00:06	ppp	19091	[wan_link0] PPPoE: Connecting to ''
              Aug 12 14:00:06	ppp	19091	[wan_link0] Link: reconnection attempt 2
              Aug 12 14:00:05	ppp	19091	[wan_link0] Link: reconnection attempt 2 in 1 seconds
              Aug 12 14:00:05	ppp	19091	[wan_link0] LCP: Down event
              Aug 12 14:00:05	ppp	19091	[wan_link0] Link: DOWN event
              Aug 12 14:00:05	ppp	19091	[wan_link0] PPPoE connection timeout after 9 seconds
              Aug 12 13:59:56	ppp	19091	[wan_link0] PPPoE: Connecting to ''
              Aug 12 13:59:56	ppp	19091	[wan_link0] Link: reconnection attempt 1
              Aug 12 13:59:52	ppp	19091	[wan_link0] Link: reconnection attempt 1 in 4 seconds
              Aug 12 13:59:52	ppp	19091	[wan_link0] LCP: LayerDown
              Aug 12 13:59:52	ppp	19091	[wan] Bundle: Last link has gone, no links for bw-manage defined
              Aug 12 13:59:52	ppp	19091	[wan] IPCP: state change Closing --> Initial
              Aug 12 13:59:52	ppp	19091	[wan] Bundle: No NCPs left. Closing links...
              Aug 12 13:59:52	ppp	19091	[wan] IPCP: LayerFinish
              Aug 12 13:59:52	ppp	19091	[wan] IPCP: Down event
              Aug 12 13:59:52	ppp	19091	[wan] IFACE: Set description "1_WAN"
              Aug 12 13:59:52	ppp	19091	[wan] IFACE: Rename interface pppoe0 to pppoe0
              Aug 12 13:59:52	ppp	19091	[wan] IFACE: Down event
              Aug 12 13:59:52	ppp	19091	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
              Aug 12 13:59:51	ppp	19091	[wan] IPCP: LayerDown
              Aug 12 13:59:51	ppp	19091	[wan] IPCP: SendTerminateReq #4
              Aug 12 13:59:51	ppp	19091	[wan] IPCP: state change Opened --> Closing
              Aug 12 13:59:51	ppp	19091	[wan] IPCP: Close event
              Aug 12 13:59:51	ppp	19091	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
              Aug 12 13:59:51	ppp	19091	[wan_link0] Link: Leave bundle "wan"
              Aug 12 13:59:51	ppp	19091	[wan_link0] LCP: state change Opened --> Starting
              Aug 12 13:59:51	ppp	19091	[wan_link0] LCP: Down event
              Aug 12 13:59:51	ppp	19091	[wan_link0] Link: DOWN event
              Aug 12 13:59:51	ppp	19091	[wan_link0] PPPoE: connection closed
              Aug 12 13:59:33	ppp	19091	[wan_link0] LCP: no reply to 1 echo request(s)
              Aug 12 13:49:22	ppp	19091	[wan_link0] LCP: no reply to 1 echo request(s)
              Aug 12 13:28:00	ppp	19091	[wan_link0] LCP: no reply to 2 echo request(s)
              Aug 12 13:27:50	ppp	19091	[wan_link0] LCP: no reply to 1 echo request(s)
              Aug 12 13:27:10	ppp	19091	[wan_link0] LCP: no reply to 1 echo request(s)
              Aug 12 13:09:02	ppp	19091	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
              Aug 12 13:09:00	ppp	19091	[wan] IFACE: Add description "1_WAN"
              Aug 12 13:09:00	ppp	19091	[wan] IFACE: Rename interface ng0 to pppoe0
              Aug 12 13:09:00	ppp	19091	[wan] IFACE: Up event
              Aug 12 13:08:59	ppp	19091	[wan] 86.146.217.76 -> 172.16.12.102
              
              1 Reply Last reply Reply Quote 0
              • stephenw10S
                stephenw10 Netgate Administrator
                last edited by

                @diyhouse said in WAN periodically Rebooting:

                Aug 12 13:59:51 ppp 19091 [wan_link0] Link: DOWN event
                Aug 12 13:59:51 ppp 19091 [wan_link0] PPPoE: connection closed
                Aug 12 13:59:33 ppp 19091 [wan_link0] LCP: no reply to 1 echo request(s)
                Aug 12 13:49:22 ppp 19091 [wan_link0] LCP: no reply to 1 echo request(s)
                Aug 12 13:28:00 ppp 19091 [wan_link0] LCP: no reply to 2 echo request(s)
                Aug 12 13:27:50 ppp 19091 [wan_link0] LCP: no reply to 1 echo request(s)
                Aug 12 13:27:10 ppp 19091 [wan_link0] LCP: no reply to 1 echo request(s)
                Aug 12 13:09:02 ppp 19091 [wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                Aug 12 13:09:00 ppp 19091 [wan] IFACE: Add description "1_WAN"
                Aug 12 13:09:00 ppp 19091 [wan] IFACE: Rename interface ng0 to pppoe0
                Aug 12 13:09:00 ppp 19091 [wan] IFACE: Up event

                Huh that's interesting. I wonder if it's counting those 5 alerts as enough to reconnect even though they are not consecutive. 🤔

                Though it doesn't show the LCP: peer not responding to echo requests message that would normally indicate it being triggered. And the value there should be a max time of 60s which would never be reached.

                No you should not need any settings in the advanced options.

                If you look in /var/etc/mpd_wan.conf you will see the generated conf file used to connect. It's possible to make a custom conf file by copying that to /conf and then editing it there. The mpd daemon will use a file there in preference.

                D 1 Reply Last reply Reply Quote 0
                • D
                  diyhouse @stephenw10
                  last edited by

                  @stephenw10 this is my 'mpd_wan.conf' file.. credentials removed,..
                  what do you suggest I modify,..
                  If I update this file how do I force it to be used,.. ie how do I force a 'reload'

                  I see these two values,.. set link keep-alive 10 60,.. what do you suggest,. 100 300,
                  ie 5 mins....
                  Can I not just edit the primary file,.. or does this get overwritten..

                  2.7.2-RELEASE][mark@pfSense.mylocal]/var/etc: cat mpd_wan.conf 
                  startup:
                  	# configure the console
                  	set console close
                  	# configure the web server
                  	set web close
                  
                  default:
                  pppoeclient:
                  	create bundle static wan
                  	set bundle period 6
                  	set bundle lowat 0
                  	set bundle hiwat 0
                  	set bundle min-con 3
                  	set bundle min-dis 6
                  	set bundle enable bw-manage
                  	
                  	set iface name pppoe0
                  	set iface description "1_WAN"
                  	set iface disable on-demand
                  	set iface idle 0
                  	set iface enable tcpmssfix
                  	set iface up-script /usr/local/sbin/ppp-linkup
                  	set iface down-script /usr/local/sbin/ppp-linkdown
                  	set ipcp ranges 0.0.0.0/0 0.0.0.0/0
                  	set ipcp enable req-pri-dns
                  	set ipcp enable req-sec-dns
                  	#log -bund -ccp -chat -iface -ipcp -lcp -link
                  
                  	create link static wan_link0 pppoe
                  	set link action bundle wan
                  	set link disable multilink
                  	set link keep-alive 10 60
                  	set link max-redial 0
                  	set link disable chap pap
                  	set link accept chap pap eap
                  	set link disable incoming
                  	set link mtu 1492
                  	set auth authname "account"
                  	set auth password passwd
                  	set pppoe service ""
                  	set pppoe iface igb0
                  	open
                  
                  
                  1 Reply Last reply Reply Quote 0
                  • stephenw10S
                    stephenw10 Netgate Administrator
                    last edited by

                    Indeed the file in /var/etc gets overwritten at boot or whenever the config is rebuilt so you have to copy it to /conf to make permanent changes to it.

                    I would start by setting link keep-alive to something just larger to see if that has any effect so maybe: 20 120

                    D 1 Reply Last reply Reply Quote 0
                    • D
                      diyhouse @stephenw10
                      last edited by

                      @stephenw10
                      Tx,..
                      Ok,.. just trying to copy file over to /conf... but insufficient privs,.. sudo does not work...
                      and from what I can read I have to use the shell window offered from the console,.. with a direct login at the h/w,.. or have I missed something..?

                      D 1 Reply Last reply Reply Quote 0
                      • stephenw10S
                        stephenw10 Netgate Administrator
                        last edited by

                        You can do it from the gui Diag > Command Prompt.

                        Then edit it from Diag > Edit File

                        1 Reply Last reply Reply Quote 0
                        • D
                          diyhouse @diyhouse
                          last edited by

                          @diyhouse well bit the bullet,.. suffered in 40C loft ambient ☺ 😓
                          File copied ,. and edited with a bit of vi... followed by a reboot..
                          Now I wait for next event..
                          Thanks

                          1 Reply Last reply Reply Quote 0
                          • stephenw10S
                            stephenw10 Netgate Administrator
                            last edited by

                            Ha, hottest day of the year, clearly the best day for it. 😁

                            For reference though you can do it from the GUI or by logging in as admin.

                            D 1 Reply Last reply Reply Quote 0
                            • D
                              diyhouse @stephenw10
                              last edited by

                              @stephenw10 Yep it was hot,.. But file edited,.. I did try ssh'ing into pfsense as 'admin',..
                              but this did not work,.
                              also how do I reload setting and force the new setting into play...?
                              ( all good so far 20 mins in,.. no errors on PPP,.. )
                              Tx

                              D 1 Reply Last reply Reply Quote 0
                              • stephenw10S
                                stephenw10 Netgate Administrator
                                last edited by

                                Re-saving the WAN interface should do it.

                                1 Reply Last reply Reply Quote 0
                                • D
                                  diyhouse @diyhouse
                                  last edited by

                                  @diyhouse,.. Well spoke to soon,.. had numerous reboots,.. around 5:30am this morning,.. and its been off several times since,.. These are the most recent PPP down... Any thoughts..

                                  Aug 13 13:52:42	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 13:52:41	ppp	71373	[wan] IFACE: Add description "1_WAN"
                                  Aug 13 13:52:41	ppp	71373	[wan] IFACE: Rename interface ng0 to pppoe0
                                  Aug 13 13:52:41	ppp	71373	[wan] IFACE: Up event
                                  Aug 13 13:52:40	ppp	71373	[wan] 109.147.50.193 -> 172.16.12.102
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: LayerUp
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: state change Ack-Sent --> Opened
                                  Aug 13 13:52:40	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 13:52:40	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 109.147.50.193
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: rec'd Configure Ack #47 (Ack-Sent)
                                  Aug 13 13:52:40	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 13:52:40	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 109.147.50.193
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: SendConfigReq #47
                                  Aug 13 13:52:40	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 13:52:40	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 13:52:40	ppp	71373	[wan] 109.147.50.193 is OK
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 109.147.50.193
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: rec'd Configure Nak #46 (Ack-Sent)
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 13:52:40	ppp	71373	[wan] SECDNS 0.0.0.0
                                  Aug 13 13:52:40	ppp	71373	[wan] PRIDNS 0.0.0.0
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 0.0.0.0
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: SendConfigReq #46
                                  Aug 13 13:52:40	ppp	71373	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: rec'd Configure Reject #45 (Ack-Sent)
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: state change Req-Sent --> Ack-Sent
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 172.16.12.102
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: SendConfigAck #81
                                  Aug 13 13:52:40	ppp	71373	[wan] 172.16.12.102 is OK
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 172.16.12.102
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: rec'd Configure Request #81 (Req-Sent)
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 13:52:40	ppp	71373	[wan] SECDNS 0.0.0.0
                                  Aug 13 13:52:40	ppp	71373	[wan] PRIDNS 0.0.0.0
                                  Aug 13 13:52:40	ppp	71373	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
                                  Aug 13 13:52:40	ppp	71373	[wan] IPADDR 0.0.0.0
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: SendConfigReq #45
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: state change Starting --> Req-Sent
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: Up event
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: LayerStart
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: state change Initial --> Starting
                                  Aug 13 13:52:40	ppp	71373	[wan] IPCP: Open event
                                  Aug 13 13:52:40	ppp	71373	[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] Link: Join bundle "wan"
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] Link: Matched action 'bundle "wan" ""'
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: authorization successful
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MESG: CHAP authentication success
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] CHAP: rec'd SUCCESS #1 len: 31
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] CHAP: sending RESPONSE #1 len: 52
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] CHAP: Using authname "green-light@service.btclick.com"
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] Name: "acc-aln2.tbs"
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] CHAP: rec'd CHALLENGE #1 len: 60
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: LayerUp
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: state change Ack-Sent --> Opened
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MAGICNUM 0x2128a255
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: rec'd Configure Ack #40 (Ack-Sent)
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MAGICNUM 0x2128a255
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: SendConfigReq #40
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] PROTOCOMP
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: rec'd Configure Reject #39 (Ack-Sent)
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: state change Req-Sent --> Ack-Sent
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MAGICNUM 0x04a69d35
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] AUTHPROTO CHAP MD5
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: SendConfigAck #159
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MAGICNUM 0x04a69d35
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] AUTHPROTO CHAP MD5
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:52:40	ppp	71373	[wan_link0] LCP: rec'd Configure Request #159 (Req-Sent)
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] MAGICNUM 0x2128a255
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] PROTOCOMP
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] LCP: SendConfigReq #39
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] LCP: state change Starting --> Req-Sent
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] LCP: Up event
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] Link: UP event
                                  Aug 13 13:52:39	ppp	71373	[wan_link0] PPPoE: connection successful
                                  Aug 13 13:52:39	ppp	71373	PPPoE: rec'd ACNAME "acc-aln2.tbs"
                                  Aug 13 13:52:33	ppp	71373	[wan_link0] PPPoE: Connecting to ''
                                  Aug 13 13:52:33	ppp	71373	[wan_link0] Link: reconnection attempt 2
                                  Aug 13 13:52:32	ppp	71373	[wan_link0] Link: reconnection attempt 2 in 1 seconds
                                  Aug 13 13:52:32	ppp	71373	[wan_link0] LCP: Down event
                                  Aug 13 13:52:32	ppp	71373	[wan_link0] Link: DOWN event
                                  Aug 13 13:52:32	ppp	71373	[wan_link0] PPPoE connection timeout after 9 seconds
                                  Aug 13 13:52:23	ppp	71373	[wan_link0] PPPoE: Connecting to ''
                                  Aug 13 13:52:23	ppp	71373	[wan_link0] Link: reconnection attempt 1
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] Link: reconnection attempt 1 in 2 seconds
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] LCP: LayerDown
                                  Aug 13 13:52:21	ppp	71373	[wan] Bundle: Last link has gone, no links for bw-manage defined
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: state change Closing --> Initial
                                  Aug 13 13:52:21	ppp	71373	[wan] Bundle: No NCPs left. Closing links...
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: LayerFinish
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: Down event
                                  Aug 13 13:52:21	ppp	71373	[wan] IFACE: Set description "1_WAN"
                                  Aug 13 13:52:21	ppp	71373	[wan] IFACE: Rename interface pppoe0 to pppoe0
                                  Aug 13 13:52:21	ppp	71373	[wan] IFACE: Down event
                                  Aug 13 13:52:21	ppp	71373	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: LayerDown
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: SendTerminateReq #44
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: state change Opened --> Closing
                                  Aug 13 13:52:21	ppp	71373	[wan] IPCP: Close event
                                  Aug 13 13:52:21	ppp	71373	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] Link: Leave bundle "wan"
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] LCP: state change Opened --> Starting
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] LCP: Down event
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] Link: DOWN event
                                  Aug 13 13:52:21	ppp	71373	[wan_link0] PPPoE: connection closed
                                  Aug 13 13:52:16	ppp	71373	[wan_link0] LCP: no reply to 1 echo request(s)
                                  Aug 13 13:21:58	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 13:21:56	ppp	71373	[wan] IFACE: Add description "1_WAN"
                                  Aug 13 13:21:56	ppp	71373	[wan] IFACE: Rename interface ng0 to pppoe0
                                  Aug 13 13:21:56	ppp	71373	[wan] IFACE: Up event
                                  Aug 13 13:21:55	ppp	71373	[wan] 109.147.50.193 -> 172.16.12.102
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: LayerUp
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: state change Ack-Sent --> Opened
                                  Aug 13 13:21:55	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 13:21:55	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 109.147.50.193
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: rec'd Configure Ack #43 (Ack-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 13:21:55	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 109.147.50.193
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: SendConfigReq #43
                                  Aug 13 13:21:55	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 13:21:55	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 13:21:55	ppp	71373	[wan] 109.147.50.193 is OK
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 109.147.50.193
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: rec'd Configure Nak #42 (Ack-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 13:21:55	ppp	71373	[wan] SECDNS 0.0.0.0
                                  Aug 13 13:21:55	ppp	71373	[wan] PRIDNS 0.0.0.0
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 0.0.0.0
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: SendConfigReq #42
                                  Aug 13 13:21:55	ppp	71373	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: rec'd Configure Reject #41 (Ack-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: state change Req-Sent --> Ack-Sent
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 172.16.12.102
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: SendConfigAck #224
                                  Aug 13 13:21:55	ppp	71373	[wan] 172.16.12.102 is OK
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 172.16.12.102
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: rec'd Configure Request #224 (Req-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 13:21:55	ppp	71373	[wan] SECDNS 0.0.0.0
                                  Aug 13 13:21:55	ppp	71373	[wan] PRIDNS 0.0.0.0
                                  Aug 13 13:21:55	ppp	71373	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
                                  Aug 13 13:21:55	ppp	71373	[wan] IPADDR 0.0.0.0
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: SendConfigReq #41
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: state change Starting --> Req-Sent
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: Up event
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: LayerStart
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: state change Initial --> Starting
                                  Aug 13 13:21:55	ppp	71373	[wan] IPCP: Open event
                                  Aug 13 13:21:55	ppp	71373	[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] Link: Join bundle "wan"
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] Link: Matched action 'bundle "wan" ""'
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: authorization successful
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MESG: CHAP authentication success
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] CHAP: rec'd SUCCESS #1 len: 31
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] CHAP: sending RESPONSE #1 len: 52
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] CHAP: Using authname "green-light@service.btclick.com"
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] Name: "acc-aln2.tbs"
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] CHAP: rec'd CHALLENGE #1 len: 55
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: LayerUp
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: state change Ack-Sent --> Opened
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0xfead80b7
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: rec'd Configure Ack #38 (Ack-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0xfead80b7
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: SendConfigReq #38
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] PROTOCOMP
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: rec'd Configure Reject #37 (Ack-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: state change Req-Sent --> Ack-Sent
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0x554ebe8a
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] AUTHPROTO CHAP MD5
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: SendConfigAck #190
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0x554ebe8a
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] AUTHPROTO CHAP MD5
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: rec'd Configure Request #190 (Req-Sent)
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0xfead80b7
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] PROTOCOMP
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: SendConfigReq #37
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: state change Starting --> Req-Sent
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: Up event
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] Link: UP event
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] PPPoE: connection successful
                                  Aug 13 13:21:55	ppp	71373	PPPoE: rec'd ACNAME "acc-aln2.tbs"
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] PPPoE: Connecting to ''
                                  Aug 13 13:21:55	ppp	71373	[wan_link0] Link: reconnection attempt 2
                                  Aug 13 13:21:53	ppp	71373	[wan_link0] Link: reconnection attempt 2 in 2 seconds
                                  Aug 13 13:21:53	ppp	71373	[wan_link0] LCP: Down event
                                  Aug 13 13:21:53	ppp	71373	[wan_link0] Link: DOWN event
                                  Aug 13 13:21:53	ppp	71373	[wan_link0] PPPoE connection timeout after 9 seconds
                                  Aug 13 13:21:44	ppp	71373	[wan_link0] PPPoE: Connecting to ''
                                  Aug 13 13:21:44	ppp	71373	[wan_link0] Link: reconnection attempt 1
                                  Aug 13 13:21:40	ppp	71373	[wan_link0] Link: reconnection attempt 1 in 4 seconds
                                  Aug 13 13:21:40	ppp	71373	[wan_link0] LCP: LayerDown
                                  Aug 13 13:21:40	ppp	71373	[wan] Bundle: Last link has gone, no links for bw-manage defined
                                  Aug 13 13:21:40	ppp	71373	[wan] IPCP: state change Closing --> Initial
                                  Aug 13 13:21:40	ppp	71373	[wan] Bundle: No NCPs left. Closing links...
                                  Aug 13 13:21:40	ppp	71373	[wan] IPCP: LayerFinish
                                  Aug 13 13:21:40	ppp	71373	[wan] IPCP: Down event
                                  Aug 13 13:21:40	ppp	71373	[wan] IFACE: Set description "1_WAN"
                                  Aug 13 13:21:40	ppp	71373	[wan] IFACE: Rename interface pppoe0 to pppoe0
                                  Aug 13 13:21:40	ppp	71373	[wan] IFACE: Down event
                                  Aug 13 13:21:40	ppp	71373	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
                                  Aug 13 13:21:39	ppp	71373	[wan] IPCP: LayerDown
                                  Aug 13 13:21:39	ppp	71373	[wan] IPCP: SendTerminateReq #40
                                  Aug 13 13:21:39	ppp	71373	[wan] IPCP: state change Opened --> Closing
                                  Aug 13 13:21:39	ppp	71373	[wan] IPCP: Close event
                                  Aug 13 13:21:39	ppp	71373	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
                                  Aug 13 13:21:39	ppp	71373	[wan_link0] Link: Leave bundle "wan"
                                  Aug 13 13:21:39	ppp	71373	[wan_link0] LCP: state change Opened --> Starting
                                  Aug 13 13:21:39	ppp	71373	[wan_link0] LCP: Down event
                                  Aug 13 13:21:39	ppp	71373	[wan_link0] Link: DOWN event
                                  Aug 13 13:21:39	ppp	71373	[wan_link0] PPPoE: connection closed
                                  Aug 13 12:31:49	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 12:31:47	ppp	71373	[wan] IFACE: Add description "1_WAN"
                                  Aug 13 12:31:47	ppp	71373	[wan] IFACE: Rename interface ng0 to pppoe0
                                  Aug 13 12:31:47	ppp	71373	[wan] IFACE: Up event
                                  Aug 13 12:31:46	ppp	71373	[wan] 86.153.138.131 -> 172.16.12.102
                                  Aug 13 12:31:46	ppp	71373	[wan] IPCP: LayerUp
                                  Aug 13 12:31:46	ppp	71373	[wan] IPCP: state change Ack-Sent --> Opened
                                  Aug 13 12:31:46	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 12:31:46	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 12:31:46	ppp	71373	[wan] IPADDR 86.153.138.131
                                  Aug 13 12:31:46	ppp	71373	[wan] IPCP: rec'd Configure Ack #39 (Ack-Sent)
                                  Aug 13 12:31:46	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 12:31:46	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 12:31:46	ppp	71373	[wan] IPADDR 86.153.138.131
                                  Aug 13 12:31:46	ppp	71373	[wan] IPCP: SendConfigReq #39
                                  Aug 13 12:31:46	ppp	71373	[wan] SECDNS 81.139.57.100
                                  Aug 13 12:31:46	ppp	71373	[wan] PRIDNS 81.139.56.100
                                  Aug 13 12:31:46	ppp	71373	[wan] 86.153.138.131 is OK
                                  Aug 13 12:31:46	ppp	71373	[wan] IPADDR 86.153.138.131
                                  Aug 13 12:31:46	ppp	71373	[wan] IPCP: rec'd Configure Nak #38 (Ack-Sent)
                                  Aug 13 12:31:46	ppp	71373	[wan_link0] rec'd unexpected protocol IPV6CP, rejecting
                                  Aug 13 12:31:46	ppp	71373	[wan] SECDNS 0.0.0.0
                                  Aug 13 12:31:46	ppp	71373	[wan] PRIDNS 0.0.0.0
                                  Aug 13 12:31:46	ppp	71373	[wan] IPADDR 0.0.0.0
                                  Aug 13 12:31:46	ppp	71373	[wan] IPCP: SendConfigReq #38
                                  
                                  1 Reply Last reply Reply Quote 0
                                  • stephenw10S
                                    stephenw10 Netgate Administrator
                                    last edited by

                                    @diyhouse said in WAN periodically Rebooting:

                                    Aug 13 13:21:39 ppp 71373 [wan_link0] Link: DOWN event
                                    Aug 13 13:21:39 ppp 71373 [wan_link0] PPPoE: connection closed

                                    Well it doesn't appear to be LCP echo requests timing out. In each case the first thing logged is the connection closing. Hmm. Nothing in the main log at that point?

                                    D 1 Reply Last reply Reply Quote 0
                                    • D
                                      diyhouse @stephenw10
                                      last edited by

                                      @stephenw10 The Main system=>General Log looks as follows just prior to the connection lost..
                                      Just in passing what does this mean,

                                      Aug 13 13:21:40	ppp	71373	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
                                      

                                      Thankyou,....
                                      and main system log:-

                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: LayerUp
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: state change Ack-Sent --> Opened
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0xfead80b7
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: rec'd Configure Ack #38 (Ack-Sent)
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0xfead80b7
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: SendConfigReq #38
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] PROTOCOMP
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: rec'd Configure Reject #37 (Ack-Sent)
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: state change Req-Sent --> Ack-Sent
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0x554ebe8a
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] AUTHPROTO CHAP MD5
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: SendConfigAck #190
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0x554ebe8a
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] AUTHPROTO CHAP MD5
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: rec'd Configure Request #190 (Req-Sent)
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MAGICNUM 0xfead80b7
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] MRU 1492
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] PROTOCOMP
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: SendConfigReq #37
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: state change Starting --> Req-Sent
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] LCP: Up event
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] Link: UP event
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] PPPoE: connection successful
                                      Aug 13 13:21:55	ppp	71373	PPPoE: rec'd ACNAME "acc-aln2.tbs"
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] PPPoE: Connecting to ''
                                      Aug 13 13:21:55	ppp	71373	[wan_link0] Link: reconnection attempt 2
                                      Aug 13 13:21:53	ppp	71373	[wan_link0] Link: reconnection attempt 2 in 2 seconds
                                      Aug 13 13:21:53	ppp	71373	[wan_link0] LCP: Down event
                                      Aug 13 13:21:53	ppp	71373	[wan_link0] Link: DOWN event
                                      Aug 13 13:21:53	ppp	71373	[wan_link0] PPPoE connection timeout after 9 seconds
                                      Aug 13 13:21:44	ppp	71373	[wan_link0] PPPoE: Connecting to ''
                                      Aug 13 13:21:44	ppp	71373	[wan_link0] Link: reconnection attempt 1
                                      Aug 13 13:21:40	ppp	71373	[wan_link0] Link: reconnection attempt 1 in 4 seconds
                                      Aug 13 13:21:40	ppp	71373	[wan_link0] LCP: LayerDown
                                      Aug 13 13:21:40	ppp	71373	[wan] Bundle: Last link has gone, no links for bw-manage defined
                                      Aug 13 13:21:40	ppp	71373	[wan] IPCP: state change Closing --> Initial
                                      Aug 13 13:21:40	ppp	71373	[wan] Bundle: No NCPs left. Closing links...
                                      Aug 13 13:21:40	ppp	71373	[wan] IPCP: LayerFinish
                                      Aug 13 13:21:40	ppp	71373	[wan] IPCP: Down event
                                      Aug 13 13:21:40	ppp	71373	[wan] IFACE: Set description "1_WAN"
                                      Aug 13 13:21:40	ppp	71373	[wan] IFACE: Rename interface pppoe0 to pppoe0
                                      Aug 13 13:21:40	ppp	71373	[wan] IFACE: Down event
                                      Aug 13 13:21:40	ppp	71373	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
                                      Aug 13 13:21:40	check_reload_status	430	Rewriting resolv.conf
                                      Aug 13 13:21:39	ppp	71373	[wan] IPCP: LayerDown
                                      Aug 13 13:21:39	ppp	71373	[wan] IPCP: SendTerminateReq #40
                                      Aug 13 13:21:39	ppp	71373	[wan] IPCP: state change Opened --> Closing
                                      Aug 13 13:21:39	ppp	71373	[wan] IPCP: Close event
                                      Aug 13 13:21:39	ppp	71373	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
                                      Aug 13 13:21:39	ppp	71373	[wan_link0] Link: Leave bundle "wan"
                                      Aug 13 13:21:39	ppp	71373	[wan_link0] LCP: state change Opened --> Starting
                                      Aug 13 13:21:39	ppp	71373	[wan_link0] LCP: Down event
                                      Aug 13 13:21:39	ppp	71373	[wan_link0] Link: DOWN event
                                      Aug 13 13:21:39	ppp	71373	[wan_link0] PPPoE: connection closed
                                      Aug 13 13:18:00	sshguard	19745	Now monitoring attacks.
                                      Aug 13 13:18:00	sshguard	9383	Exiting on signal.
                                      Aug 13 13:15:13	php	41056	[pfBlockerNG] No changes to Firewall rules, skipping Filter Reload
                                      Aug 13 13:15:00	php	41056	[pfBlockerNG] Starting cron process.
                                      Aug 13 13:07:00	sshguard	9383	Now monitoring attacks.
                                      Aug 13 13:07:00	sshguard	23133	Exiting on signal.
                                      Aug 13 12:57:00	sshguard	23133	Now monitoring attacks.
                                      
                                      D 1 Reply Last reply Reply Quote 0
                                      • D
                                        diyhouse @diyhouse
                                        last edited by

                                        @diyhouse Looking in General=> Gateways...
                                        I get the following errors...
                                        I don't know if this is a 'red herring',.. but here it is

                                        Aug 13 13:52:24	dpinger	19013	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:52:24	dpinger	19013	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:52:23	dpinger	19013	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:52:23	dpinger	19013	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:52:22	dpinger	19013	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:52:22	dpinger	19013	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:22:03	dpinger	19013	1_WAN_PPPOE 172.16.12.102: Alarm latency 0us stddev 0us loss 100%
                                        Aug 13 13:22:01	dpinger	19013	send_interval 500ms loss_interval 2000ms time_period 60000ms report_interval 0ms data_len 1 alert_interval 1000ms latency_alarm 500ms loss_alarm 20% alarm_hold 10000ms dest_addr 172.16.12.102 bind_addr 109.147.50.193 identifier "1_WAN_PPPOE "
                                        Aug 13 13:22:01	dpinger	69150	exiting on signal 15
                                        Aug 13 13:21:55	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:54	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:54	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:53	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:53	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:52	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:52	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:51	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:50	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:50	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:49	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:49	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:48	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:48	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:47	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:47	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:46	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:46	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:45	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:45	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:44	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:44	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:43	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:43	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        Aug 13 13:21:42	dpinger	69150	1_WAN_PPPOE 172.16.12.102: sendto error: 65
                                        
                                        1 Reply Last reply Reply Quote 0
                                        • stephenw10S
                                          stephenw10 Netgate Administrator
                                          last edited by

                                          Hmm, all that looks like it's caused by the PPPoE connection closing. Really feels like the server is closing it. Might need to turn up the logging to see it which is not something I've ever tried for PPP. Let me see...

                                          D 1 Reply Last reply Reply Quote 0
                                          • D
                                            diyhouse @stephenw10
                                            last edited by

                                            @stephenw10
                                            Stephen,... In desperation,... I have just tried to setup an 'original BT Modem',.. and found the credentials I was using to access the broadband servers,.. were incorrect.!!
                                            The credentials I have been using for maybe 6yrs+.... are old,..
                                            Rang BT support,.. and they supplied a completely different set of credentials, which are ( as they say ) in line with my updated line,

                                            Needless to say I am running with the new line config and credentials on pfsense,.. lets see how this pans out
                                            upload/ download is now at 60/10,. which is in line with what I recall were the speeds when i 1st went pfsense FTTC....

                                            So lets see how this pans out,... hopeful,.. but not holding my breath..

                                            D 1 Reply Last reply Reply Quote 0
                                            • First post
                                              Last post
                                            Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.