PPPoE reconenction fix - mpd fix ($100)
-
here u go, included both the logs
system log
Jan 15 10:07:38 syslogd: kernel boot file is /boot/kernel/kernel Jan 15 10:07:46 kernel: vr1: link state changed to DOWN Jan 15 10:08:43 check_reload_status: Restarting ipsec tunnels Jan 15 10:08:43 check_reload_status: Restarting OpenVPN tunnels/interfaces Jan 15 10:08:43 check_reload_status: Reloading filter Jan 15 10:08:46 php-fpm[56184]: /rc.openvpn: OpenVPN: One or more OpenVPN tunnel endpoints may have changed its IP. Reloading endpoints that may use WAN. Jan 15 10:08:46 php-fpm[56184]: /rc.openvpn: OpenVPN: Resync client1 ExpressVPN Jan 15 10:08:49 php-fpm[56184]: /rc.openvpn: OpenVPN ID client1 PID 20721 still running, killing. Jan 15 10:08:49 kernel: ovpnc1: link state changed to DOWN Jan 15 10:08:50 check_reload_status: Reloading filter Jan 15 10:08:53 php-fpm[41289]: /rc.dyndns.update: phpDynDNS (firewall.domain.com): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry. Jan 15 10:08:55 php: rc.kill_states: rc.kill_states: Removing states for IP 86.99.125.32/32 Jan 15 10:08:55 check_reload_status: Rewriting resolv.conf Jan 15 10:08:59 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:08:59 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:08:59 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:08:59 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:00 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:00 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:00 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:00 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:00 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:00 php-fpm[55844]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:02 check_reload_status: Linkup starting vr1 Jan 15 10:09:02 kernel: vr1: link state changed to UP Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:07 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:08 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:08 php-fpm[56184]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 15 10:09:15 check_reload_status: Restarting ipsec tunnels Jan 15 10:09:20 check_reload_status: updating dyndns wanppp logs
Jan 15 10:08:34 ppp: [wan_link0] LCP: no reply to 3 echo request(s) Jan 15 10:08:44 ppp: [wan_link0] LCP: no reply to 4 echo request(s) Jan 15 10:08:54 ppp: [wan_link0] LCP: no reply to 5 echo request(s) Jan 15 10:08:54 ppp: [wan_link0] LCP: peer not responding to echo requests Jan 15 10:08:54 ppp: [wan_link0] LCP: state change Opened --> Stopping Jan 15 10:08:54 ppp: [wan_link0] Link: Leave bundle "wan" Jan 15 10:08:54 ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps Jan 15 10:08:54 ppp: [wan] IPCP: Close event Jan 15 10:08:54 ppp: [wan] IPCP: state change Opened --> Closing Jan 15 10:08:54 ppp: [wan] IPCP: SendTerminateReq #4 Jan 15 10:08:54 ppp: [wan] IPCP: LayerDown Jan 15 10:08:55 ppp: [wan] IFACE: Delete route 0.0.0.0/0 2.48.4.1 failed: No such process Jan 15 10:08:55 ppp: [wan] IFACE: Down event Jan 15 10:08:55 ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0 Jan 15 10:08:55 ppp: [wan] IPV6CP: Close event Jan 15 10:08:55 ppp: [wan] IPV6CP: state change Stopped --> Closed Jan 15 10:08:55 ppp: [wan] IPCP: Down event Jan 15 10:08:55 ppp: [wan] IPCP: LayerFinish Jan 15 10:08:55 ppp: [wan] Bundle: No NCPs left. Closing links... Jan 15 10:08:55 ppp: [wan] IPCP: state change Closing --> Initial Jan 15 10:08:55 ppp: [wan] IPV6CP: Down event Jan 15 10:08:55 ppp: [wan] IPV6CP: state change Closed --> Initial Jan 15 10:08:55 ppp: [wan_link0] LCP: SendTerminateReq #3 Jan 15 10:08:55 ppp: [wan_link0] LCP: LayerDown Jan 15 10:08:58 ppp: [wan_link0] LCP: SendTerminateReq #4 Jan 15 10:09:00 ppp: [wan_link0] LCP: state change Stopping --> Stopped Jan 15 10:09:00 ppp: [wan_link0] LCP: LayerFinish Jan 15 10:09:00 ppp: [wan_link0] PPPoE: connection closed Jan 15 10:09:00 ppp: [wan_link0] Link: DOWN event Jan 15 10:09:00 ppp: [wan_link0] LCP: Down event Jan 15 10:09:00 ppp: [wan_link0] LCP: state change Stopped --> Starting Jan 15 10:09:00 ppp: [wan_link0] LCP: LayerStart Jan 15 10:09:00 ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds Jan 15 10:09:01 ppp: [wan_link0] Link: reconnection attempt 1 Jan 15 10:09:01 ppp: [wan_link0] PPPoE: Connecting to '' Jan 15 10:09:05 ppp: Multi-link PPP daemon for FreeBSD Jan 15 10:09:05 ppp: Jan 15 10:09:05 ppp: process 67927 started, version 5.7 (root@pfsense-22-i386-builder 13:22 18-Nov-2014) Jan 15 10:09:05 ppp: caught fatal signal TERM Jan 15 10:09:05 ppp: [wan] IFACE: Close event Jan 15 10:09:05 ppp: [wan] IPCP: Close event Jan 15 10:09:05 ppp: [wan] IPV6CP: Close event Jan 15 10:09:05 ppp: waiting for process 6370 to die... Jan 15 10:09:06 ppp: waiting for process 6370 to die... Jan 15 10:09:07 ppp: [wan] Bundle: Shutdown Jan 15 10:09:07 ppp: [wan_link0] Link: Shutdown Jan 15 10:09:07 ppp: process 6370 terminated Jan 15 10:09:07 ppp: web: web is not running Jan 15 10:09:07 ppp: [wan] Bundle: Interface ng0 created Jan 15 10:09:07 ppp: [wan_link0] Link: OPEN event Jan 15 10:09:07 ppp: [wan_link0] LCP: Open event Jan 15 10:09:07 ppp: [wan_link0] LCP: state change Initial --> Starting Jan 15 10:09:07 ppp: [wan_link0] LCP: LayerStart Jan 15 10:09:07 ppp: [wan_link0] PPPoE: Connecting to '' Jan 15 10:09:16 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 15 10:09:16 ppp: [wan_link0] Link: DOWN event Jan 15 10:09:16 ppp: [wan_link0] LCP: Down event Jan 15 10:09:16 ppp: [wan_link0] Link: reconnection attempt 1 in 3 seconds Jan 15 10:09:19 ppp: [wan_link0] Link: reconnection attempt 1 Jan 15 10:09:19 ppp: [wan_link0] PPPoE: Connecting to '' Jan 15 10:09:28 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 15 10:09:28 ppp: [wan_link0] Link: DOWN event Jan 15 10:09:28 ppp: [wan_link0] LCP: Down event Jan 15 10:09:28 ppp: [wan_link0] Link: reconnection attempt 2 in 4 seconds Jan 15 10:09:32 ppp: [wan_link0] Link: reconnection attempt 2 Jan 15 10:09:32 ppp: [wan_link0] PPPoE: Connecting to '' Jan 15 10:09:41 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 15 10:09:41 ppp: [wan_link0] Link: DOWN event Jan 15 10:09:41 ppp: [wan_link0] LCP: Down event Jan 15 10:09:41 ppp: [wan_link0] Link: reconnection attempt 3 in 2 seconds Jan 15 10:09:43 ppp: [wan_link0] Link: reconnection attempt 3 Jan 15 10:09:43 ppp: [wan_link0] PPPoE: Connecting to '' Jan 15 10:09:52 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 15 10:09:52 ppp: [wan_link0] Link: DOWN event Jan 15 10:09:52 ppp: [wan_link0] LCP: Down event Jan 15 10:09:52 ppp: [wan_link0] Link: reconnection attempt 4 in 4 seconds Jan 15 10:09:56 ppp: [wan_link0] Link: reconnection attempt 4 Jan 15 10:09:56 ppp: [wan_link0] PPPoE: Connecting to '' Jan 15 10:10:05 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 15 10:10:05 ppp: [wan_link0] Link: DOWN event Jan 15 10:10:05 ppp: [wan_link0] LCP: Down event Jan 15 10:10:05 ppp: [wan_link0] Link: reconnection attempt 5 in 2 seconds -
From what i see seems mpd does not exit and the new instance is started.
Probably need modification to kill the first the run the other one. -
is there some easy fix or patch which i can test for now?
-
diff --git a/etc/inc/interfaces.inc b/etc/inc/interfaces.inc index cd709a9..e388498 100644 --- a/etc/inc/interfaces.inc +++ b/etc/inc/interfaces.inc @@ -1810,6 +1810,8 @@ EOD; } /* fire up mpd */ + if (isvalidpid("{$g['varrun_path']}/" . escapeshellarg($ppp['type']) . "_{$interface}.pid")) + killbypid("{$g['varrun_path']}/" . escapeshellarg($ppp['type']) . "_{$interface}.pid"); mwexec("/usr/local/sbin/mpd5 -b -k -d {$g['varetc_path']} -f mpd_{$interface}.conf -p {$g['varrun_path']}/" . escapeshellarg($ppp['type']) . "_{$interface}.pid -s ppp " . escapeshellarg($ppp['type']) . "client"); -
well the patch works in one situation and doesnt in other, suppose if ftth modem is up and running and i pull wan plug cable from it to pfsense and then replug after sometime then connection comes back up, the other situation in which if i reset the ftth modem or power off that and repower on then while the modem is initializing, after in a few seconds the interface shows linkup and then mpd starts reconnecting but then it goes on for ever and never reconnects.
in summary, if modem is up and running and interface goes through link down and link up then it connects fine but suppose if the modem is rebooting then the interface link down and link up happens soon enough and mpd tries to reconnect but till then the modem isnt yet completely up and in that mpd never reconnects, even then when modem is completely up and i pull wan cable and reconnect then also it never reconnects unless pfsense is rebooted.
-
i think mpd doesnt completely exit before a new one is created or something like that, probably it needs to completely exit and detach from interface before a new one is created
system log
Jan 16 13:39:43 kernel: vr1: link state changed to DOWN Jan 16 13:40:42 check_reload_status: updating dyndns WAN Jan 16 13:40:42 check_reload_status: Restarting ipsec tunnels Jan 16 13:40:42 check_reload_status: Restarting OpenVPN tunnels/interfaces Jan 16 13:40:42 check_reload_status: Reloading filter Jan 16 13:40:44 php-fpm[4437]: /rc.openvpn: OpenVPN: One or more OpenVPN tunnel endpoints may have changed its IP. Reloading endpoints that may use WAN. Jan 16 13:40:44 php-fpm[4437]: /rc.openvpn: OpenVPN: Resync client1 ExpressVPN Jan 16 13:40:48 php-fpm[4437]: /rc.openvpn: OpenVPN ID client1 PID 20369 still running, killing. Jan 16 13:40:48 kernel: ovpnc1: link state changed to DOWN Jan 16 13:40:48 php: rc.kill_states: rc.kill_states: Removing states for IP 2.48.5.170/32 Jan 16 13:40:48 check_reload_status: Reloading filter Jan 16 13:40:48 check_reload_status: Rewriting resolv.conf Jan 16 13:40:55 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:55 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:55 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:55 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:56 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:56 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:56 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:56 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:56 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:56 php-fpm[4374]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:40:59 kernel: vr1: link state changed to UP Jan 16 13:40:59 check_reload_status: Linkup starting vr1 Jan 16 13:41:03 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:03 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:03 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:03 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:03 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:03 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:04 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:04 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:04 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:04 php-fpm[4437]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 16 13:41:11 check_reload_status: Restarting ipsec tunnels Jan 16 13:41:16 check_reload_status: updating dyndns wanppp log
Jan 16 13:40:17 ppp: [wan_link0] LCP: no reply to 2 echo request(s) Jan 16 13:40:27 ppp: [wan_link0] LCP: no reply to 3 echo request(s) Jan 16 13:40:37 ppp: [wan_link0] LCP: no reply to 4 echo request(s) Jan 16 13:40:47 ppp: [wan_link0] LCP: no reply to 5 echo request(s) Jan 16 13:40:47 ppp: [wan_link0] LCP: peer not responding to echo requests Jan 16 13:40:47 ppp: [wan_link0] LCP: state change Opened --> Stopping Jan 16 13:40:47 ppp: [wan_link0] Link: Leave bundle "wan" Jan 16 13:40:47 ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps Jan 16 13:40:47 ppp: [wan] IPCP: Close event Jan 16 13:40:47 ppp: [wan] IPCP: state change Opened --> Closing Jan 16 13:40:47 ppp: [wan] IPCP: SendTerminateReq #4 Jan 16 13:40:47 ppp: [wan] IPCP: LayerDown Jan 16 13:40:48 ppp: [wan] IFACE: Delete route 0.0.0.0/0 2.48.4.1 failed: No such process Jan 16 13:40:48 ppp: [wan] IFACE: Down event Jan 16 13:40:48 ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0 Jan 16 13:40:48 ppp: [wan] IPV6CP: Close event Jan 16 13:40:48 ppp: [wan] IPV6CP: state change Stopped --> Closed Jan 16 13:40:48 ppp: [wan] IPCP: Down event Jan 16 13:40:48 ppp: [wan] IPCP: LayerFinish Jan 16 13:40:48 ppp: [wan] Bundle: No NCPs left. Closing links... Jan 16 13:40:48 ppp: [wan] IPCP: state change Closing --> Initial Jan 16 13:40:48 ppp: [wan] IPV6CP: Down event Jan 16 13:40:48 ppp: [wan] IPV6CP: state change Closed --> Initial Jan 16 13:40:48 ppp: [wan_link0] LCP: SendTerminateReq #3 Jan 16 13:40:48 ppp: [wan_link0] LCP: LayerDown Jan 16 13:40:52 ppp: [wan_link0] LCP: SendTerminateReq #4 Jan 16 13:40:54 ppp: [wan_link0] LCP: state change Stopping --> Stopped Jan 16 13:40:54 ppp: [wan_link0] LCP: LayerFinish Jan 16 13:40:54 ppp: [wan_link0] PPPoE: connection closed Jan 16 13:40:54 ppp: [wan_link0] Link: DOWN event Jan 16 13:40:54 ppp: [wan_link0] LCP: Down event Jan 16 13:40:54 ppp: [wan_link0] LCP: state change Stopped --> Starting Jan 16 13:40:54 ppp: [wan_link0] LCP: LayerStart Jan 16 13:40:54 ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds Jan 16 13:40:55 ppp: [wan_link0] Link: reconnection attempt 1 Jan 16 13:40:55 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:41:01 ppp: Multi-link PPP daemon for FreeBSD Jan 16 13:41:01 ppp: Jan 16 13:41:01 ppp: process 14494 started, version 5.7 (root@pfsense-22-i386-builder 13:22 18-Nov-2014) Jan 16 13:41:01 ppp: caught fatal signal TERM Jan 16 13:41:01 ppp: [wan] IFACE: Close event Jan 16 13:41:01 ppp: [wan] IPCP: Close event Jan 16 13:41:01 ppp: [wan] IPV6CP: Close event Jan 16 13:41:01 ppp: waiting for process 5956 to die... Jan 16 13:41:02 ppp: waiting for process 5956 to die... Jan 16 13:41:03 ppp: [wan] Bundle: Shutdown Jan 16 13:41:03 ppp: [wan_link0] Link: Shutdown Jan 16 13:41:03 ppp: process 5956 terminated Jan 16 13:41:03 ppp: web: web is not running Jan 16 13:41:03 ppp: [wan] Bundle: Interface ng0 created Jan 16 13:41:03 ppp: [wan_link0] Link: OPEN event Jan 16 13:41:03 ppp: [wan_link0] LCP: Open event Jan 16 13:41:03 ppp: [wan_link0] LCP: state change Initial --> Starting Jan 16 13:41:03 ppp: [wan_link0] LCP: LayerStart Jan 16 13:41:03 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:41:12 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:41:12 ppp: [wan_link0] Link: DOWN event Jan 16 13:41:12 ppp: [wan_link0] LCP: Down event Jan 16 13:41:12 ppp: [wan_link0] Link: reconnection attempt 1 in 4 seconds Jan 16 13:41:16 ppp: [wan_link0] Link: reconnection attempt 1 Jan 16 13:41:16 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:41:25 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:41:25 ppp: [wan_link0] Link: DOWN event Jan 16 13:41:25 ppp: [wan_link0] LCP: Down event Jan 16 13:41:25 ppp: [wan_link0] Link: reconnection attempt 2 in 4 seconds Jan 16 13:41:29 ppp: [wan_link0] Link: reconnection attempt 2 Jan 16 13:41:29 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:41:38 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:41:38 ppp: [wan_link0] Link: DOWN event Jan 16 13:41:38 ppp: [wan_link0] LCP: Down event Jan 16 13:41:38 ppp: [wan_link0] Link: reconnection attempt 3 in 3 seconds Jan 16 13:41:41 ppp: [wan_link0] Link: reconnection attempt 3 Jan 16 13:41:41 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:41:50 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:41:50 ppp: [wan_link0] Link: DOWN event Jan 16 13:41:50 ppp: [wan_link0] LCP: Down event Jan 16 13:41:50 ppp: [wan_link0] Link: reconnection attempt 4 in 4 seconds Jan 16 13:41:54 ppp: [wan_link0] Link: reconnection attempt 4 Jan 16 13:41:54 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:43:43 ppp: [wan_link0] Link: DOWN event Jan 16 13:43:43 ppp: [wan_link0] LCP: Down event Jan 16 13:43:43 ppp: [wan_link0] Link: reconnection attempt 14 in 1 seconds Jan 16 13:43:44 ppp: [wan_link0] Link: reconnection attempt 14 Jan 16 13:43:44 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:43:53 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:43:53 ppp: [wan_link0] Link: DOWN event Jan 16 13:43:53 ppp: [wan_link0] LCP: Down event Jan 16 13:43:53 ppp: [wan_link0] Link: reconnection attempt 15 in 1 seconds Jan 16 13:43:54 ppp: [wan_link0] Link: reconnection attempt 15 Jan 16 13:43:54 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:44:03 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:44:03 ppp: [wan_link0] Link: DOWN event Jan 16 13:44:03 ppp: [wan_link0] LCP: Down event Jan 16 13:44:03 ppp: [wan_link0] Link: reconnection attempt 16 in 4 seconds Jan 16 13:44:07 ppp: [wan_link0] Link: reconnection attempt 16 Jan 16 13:44:07 ppp: [wan_link0] PPPoE: Connecting to '' Jan 16 13:44:16 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 16 13:44:16 ppp: [wan_link0] Link: DOWN event Jan 16 13:44:16 ppp: [wan_link0] LCP: Down event Jan 16 13:44:16 ppp: [wan_link0] Link: reconnection attempt 17 in 2 seconds Jan 16 13:44:18 ppp: [wan_link0] Link: reconnection attempt 17 Jan 16 13:44:18 ppp: [wan_link0] PPPoE: Connecting to '' -
Try putting a sleep(5) after the killbypid call.
-
@ermal:
Try putting a sleep(5) after the killbypid call.
ill try that but i think the if statement doesnt come out true so probably its not killing mpd because the logs appear same before and after the patch plus even with modem up after some while and i try cable unplug and replug it still wont work
-
i tried sleep as well as that if statement i put in some logging and it seems that if statement never turns out to be true so the kill command isnt executed
-
i tried sleep as well as that if statement i put in some logging and it seems that if statement never turns out to be true so the kill command isnt exucuted
Did you try leaving out the PID check and running sigkillbyname() or killbyname() instead? (Don't think checking PIDs is the best idea around in similar messy state.)
-
i tried sleep as well as that if statement i put in some logging and it seems that if statement never turns out to be true so the kill command isnt exucuted
Did you try leaving out the PID check and running sigkillbyname() or killbyname() instead? (Don't think checking PIDs is the best idea around in similar messy state.)
can u provide a replacement line for the below as im not aware of the possible functions created
killbypid("{$g['varrun_path']}/" . escapeshellarg($ppp['type']) . "_{$interface}.pid"); -
You can try
killbyname("mpd5");or
sigkillbyname("mpd5", "TERM");or
sigkillbyname("mpd5", "KILL"); -
tried all 3 but once modem is rebooted and up and running, nothing helps, tried unplugging wan cable also with each of the commands but all still same, only get it to work is reboot pfsense
there is something that happens at reboot which makes it reconnect that doesnt happen when modem goes down and comes up again, probably the nic powering down and up makes it successfully connect at pfsense reboot. it would be great to disable the nic using software and then enabling it
-
So… is that command executed at all? (You mentioned you tried logging.)
-
i removed the logging and put in the command, im assuming its running it because i see the interface going down and up a multiple times now, no idea if thats supposed to happen
-
Well, so… put the logging back? :) (That, and adding the sleep in between...)
-
tried it and that command is executed but it seems somewhere else something is creating another mpd instance or something like that, also in system log i get messages like some package is already running so not starting another instance so i believe that command is doing few other bad things than actually doing good because the situation still is same, it wont reconnect
-
it seems somewhere else something is creating another mpd instance or something like that
Perhaps we are finally getting somewhere here. Posting the real logs could help.
-
the part about some other mpd starting is actually the netgraph thing which has multiple instances, ill try some more and get the logs shortly as this is a production box so need to get some work done for now
-
here u go
system log
Jan 17 14:38:16 syslogd: kernel boot file is /boot/kernel/kernel Jan 17 14:38:42 kernel: vr1: link state changed to DOWN Jan 17 14:39:29 check_reload_status: updating dyndns WAN Jan 17 14:39:29 check_reload_status: Restarting ipsec tunnels Jan 17 14:39:29 check_reload_status: Restarting OpenVPN tunnels/interfaces Jan 17 14:39:29 check_reload_status: Reloading filter Jan 17 14:39:33 php-fpm[77652]: /rc.openvpn: OpenVPN: One or more OpenVPN tunnel endpoints may have changed its IP. Reloading endpoints that may use WAN. Jan 17 14:39:33 php-fpm[77652]: /rc.openvpn: OpenVPN: Resync client1 ExpressVPN Jan 17 14:39:33 php-fpm[74920]: /rc.dyndns.update: phpDynDNS (firewall.domain.com): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry. Jan 17 14:39:33 check_reload_status: Reloading filter Jan 17 14:39:33 kernel: ovpnc1: link state changed to DOWN Jan 17 14:39:49 php: rc.kill_states: rc.kill_states: Removing states for IP 86.99.127.163/32 Jan 17 14:39:49 check_reload_status: Rewriting resolv.conf Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:51 php-fpm[74920]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Jan 17 14:39:57 check_reload_status: Linkup starting vr1 Jan 17 14:39:57 kernel: vr1: link state changed to UP Jan 17 14:39:59 php-fpm[77522]: /rc.linkup: REACHED HERE BEFORE KILL COMMAND AND SLEEP Jan 17 14:40:04 php-fpm[77522]: /rc.linkup: REACHED HERE AFTER KILL COMMAND AND SLEEP Jan 17 14:40:14 check_reload_status: Restarting ipsec tunnels Jan 17 14:40:19 check_reload_status: updating dyndns wanppp log
Jan 17 14:39:18 ppp: [wan_link0] LCP: no reply to 2 echo request(s) Jan 17 14:39:28 ppp: [wan_link0] LCP: no reply to 3 echo request(s) Jan 17 14:39:38 ppp: [wan_link0] LCP: no reply to 4 echo request(s) Jan 17 14:39:48 ppp: [wan_link0] LCP: no reply to 5 echo request(s) Jan 17 14:39:48 ppp: [wan_link0] LCP: peer not responding to echo requests Jan 17 14:39:48 ppp: [wan_link0] LCP: state change Opened --> Stopping Jan 17 14:39:48 ppp: [wan_link0] Link: Leave bundle "wan" Jan 17 14:39:48 ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps Jan 17 14:39:48 ppp: [wan] IPCP: Close event Jan 17 14:39:48 ppp: [wan] IPCP: state change Opened --> Closing Jan 17 14:39:48 ppp: [wan] IPCP: SendTerminateReq #4 Jan 17 14:39:48 ppp: [wan] IPCP: LayerDown Jan 17 14:39:49 ppp: [wan] IFACE: Delete route 0.0.0.0/0 2.48.4.1 failed: No such process Jan 17 14:39:49 ppp: [wan] IFACE: Down event Jan 17 14:39:49 ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0 Jan 17 14:39:49 ppp: [wan] IPV6CP: Close event Jan 17 14:39:49 ppp: [wan] IPV6CP: state change Stopped --> Closed Jan 17 14:39:49 ppp: [wan] IPCP: Down event Jan 17 14:39:49 ppp: [wan] IPCP: LayerFinish Jan 17 14:39:49 ppp: [wan] Bundle: No NCPs left. Closing links... Jan 17 14:39:49 ppp: [wan] IPCP: state change Closing --> Initial Jan 17 14:39:49 ppp: [wan] IPV6CP: Down event Jan 17 14:39:49 ppp: [wan] IPV6CP: state change Closed --> Initial Jan 17 14:39:49 ppp: [wan_link0] LCP: SendTerminateReq #3 Jan 17 14:39:49 ppp: [wan_link0] LCP: LayerDown Jan 17 14:39:52 ppp: [wan_link0] LCP: SendTerminateReq #4 Jan 17 14:39:54 ppp: [wan_link0] LCP: state change Stopping --> Stopped Jan 17 14:39:54 ppp: [wan_link0] LCP: LayerFinish Jan 17 14:39:54 ppp: [wan_link0] PPPoE: connection closed Jan 17 14:39:54 ppp: [wan_link0] Link: DOWN event Jan 17 14:39:54 ppp: [wan_link0] LCP: Down event Jan 17 14:39:54 ppp: [wan_link0] LCP: state change Stopped --> Starting Jan 17 14:39:54 ppp: [wan_link0] LCP: LayerStart Jan 17 14:39:54 ppp: [wan_link0] Link: reconnection attempt 1 in 3 seconds Jan 17 14:39:57 ppp: [wan_link0] Link: reconnection attempt 1 Jan 17 14:39:57 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:40:04 ppp: Multi-link PPP daemon for FreeBSD Jan 17 14:40:04 ppp: Jan 17 14:40:04 ppp: process 91898 started, version 5.7 (root@pfsense-22-i386-builder 13:22 18-Nov-2014) Jan 17 14:40:04 ppp: web: web is not running Jan 17 14:40:04 ppp: [wan] Bundle: Interface ng1 created Jan 17 14:40:04 ppp: [wan] IFACE: ioctl(ng1, SIOCSIFNAME): File exists Jan 17 14:40:04 ppp: mpd_wan.conf:11: Error in 'set iface name pppoe0' Jan 17 14:40:04 ppp: [wan_link0] Link: OPEN event Jan 17 14:40:04 ppp: [wan_link0] LCP: Open event Jan 17 14:40:04 ppp: [wan_link0] LCP: state change Initial --> Starting Jan 17 14:40:04 ppp: [wan_link0] LCP: LayerStart Jan 17 14:40:04 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:40:13 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:40:13 ppp: [wan_link0] Link: DOWN event Jan 17 14:40:13 ppp: [wan_link0] LCP: Down event Jan 17 14:40:13 ppp: [wan_link0] Link: reconnection attempt 1 in 4 seconds Jan 17 14:40:17 ppp: [wan_link0] Link: reconnection attempt 1 Jan 17 14:40:17 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:40:26 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:40:26 ppp: [wan_link0] Link: DOWN event Jan 17 14:40:26 ppp: [wan_link0] LCP: Down event Jan 17 14:40:26 ppp: [wan_link0] Link: reconnection attempt 2 in 2 seconds Jan 17 14:40:28 ppp: [wan_link0] Link: reconnection attempt 2 Jan 17 14:40:28 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:40:37 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:40:37 ppp: [wan_link0] Link: DOWN event Jan 17 14:40:37 ppp: [wan_link0] LCP: Down event Jan 17 14:40:37 ppp: [wan_link0] Link: reconnection attempt 3 in 2 seconds Jan 17 14:40:39 ppp: [wan_link0] Link: reconnection attempt 3 Jan 17 14:40:39 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:40:48 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:40:48 ppp: [wan_link0] Link: DOWN event Jan 17 14:40:48 ppp: [wan_link0] LCP: Down event Jan 17 14:40:48 ppp: [wan_link0] Link: reconnection attempt 4 in 3 seconds Jan 17 14:40:51 ppp: [wan_link0] Link: reconnection attempt 4 Jan 17 14:40:51 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:41:00 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:41:00 ppp: [wan_link0] Link: DOWN event Jan 17 14:41:00 ppp: [wan_link0] LCP: Down event Jan 17 14:41:00 ppp: [wan_link0] Link: reconnection attempt 5 in 1 seconds Jan 17 14:41:01 ppp: [wan_link0] Link: reconnection attempt 5 Jan 17 14:41:01 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:41:10 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:41:10 ppp: [wan_link0] Link: DOWN event Jan 17 14:41:10 ppp: [wan_link0] LCP: Down event Jan 17 14:41:10 ppp: [wan_link0] Link: reconnection attempt 6 in 1 seconds Jan 17 14:41:11 ppp: [wan_link0] Link: reconnection attempt 6 Jan 17 14:41:11 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:41:20 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:41:20 ppp: [wan_link0] Link: DOWN event Jan 17 14:41:20 ppp: [wan_link0] LCP: Down event Jan 17 14:41:20 ppp: [wan_link0] Link: reconnection attempt 7 in 3 seconds Jan 17 14:41:23 ppp: [wan_link0] Link: reconnection attempt 7 Jan 17 14:41:23 ppp: [wan_link0] PPPoE: Connecting to '' Jan 17 14:41:32 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Jan 17 14:41:32 ppp: [wan_link0] Link: DOWN event Jan 17 14:41:32 ppp: [wan_link0] LCP: Down event Jan 17 14:41:32 ppp: [wan_link0] Link: reconnection attempt 8 in 4 secondsthe part about netgraph in ppp log
Jan 17 14:40:04 ppp: [wan] IFACE: ioctl(ng1, SIOCSIFNAME): File exists Jan 17 14:40:04 ppp: mpd_wan.conf:11: Error in 'set iface name pppoe0'