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 24.0k 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.