Netgate m1n1wall

Author Topic: PPPoE reconenction fix - mpd fix ($100)  (Read 44978 times)

0 Members and 1 Guest are viewing this topic.

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
PPPoE reconenction fix - mpd fix ($100)
« on: September 18, 2011, 02:48:29 am »
all the pfsense nanobsd v2+ have one pppoe reconnection issue related to the newer mpd being used and i desperately need a fix for it, this has been talked over and over again and again but most of the ppl out there dont have this issue but there r a few including me who face this.

mpd, pppoe etc logs have been provided but hasn't yet yielded anything and mostly the culprit is mpd itself coz the older pfsense and older mpd used to work flawless and used to reconnect perfectly even with cable unplug or isp router fail etc.

the issue is as follows:
on pfsense reboot, pppoe connects fine and everything working. once u unplug the cable between the isp router and pfsense then connection drops but as soon as replug it, it resumes fine, myb because the isp modem still remains in its configured state. now if u reset or switch off the isp modem then again pppoe connection drops but as soon as the isp modem is up again, pppoe goes in a infinite reconnection loop and wont reconnect till u reboot pfsense, what i think is when the isp modem is rebooted, it keeps its network port is some sort of state which just keeps pppoe in the reconnection loop or there is some if and else in mpd coding which detects different states and accordingly attempts a reconenct which while rebooting is different and while reconnection is different.

more ppl as me might almost contribute to this but bear in mind, the logs dont yield much so some more advanced debug method is required, i can provide access to my PC and box using dialup connection which some1 can login into it and try to see what happens behind the scene with mpd when such a scenario is recreated

Offline ermal

  • Administrator
  • Hero Member
  • *****
  • Posts: 3363
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #1 on: September 18, 2011, 02:29:29 pm »
I would accept to diagnose this with your bounty but need access to your system and you on place to be able to reproduce this :)

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #2 on: September 18, 2011, 11:38:33 pm »
one thing i forgot to mention is my dialup connection etc also run on that fibre optic modem provided by the isp/telephone company so even if i connect using dialup and replicate the issue by turning off the isp modem then my dialup connection would also die and u would loose connection.
let me try to find some other way to be connected to the internet and still be able to give ua ccess and replicate the issue.

access to my pc and the firewall i can provide using radmin or teamviewer etc

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #3 on: September 19, 2011, 01:20:31 am »
cracking neighbours wifi password to remain connected to the internet while replicating issue, ill message back soon once im ready

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #4 on: September 19, 2011, 03:03:58 am »
im ready now, how do i contact u and provide access to my PC?

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #5 on: September 21, 2011, 08:22:45 am »
can we do it now?

Offline ermal

  • Administrator
  • Hero Member
  • *****
  • Posts: 3363
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #6 on: September 21, 2011, 02:48:21 pm »
Quote

diff --git a/etc/inc/interfaces.inc b/etc/inc/interfaces.inc
index 679e347..ce9156a 100644
--- a/etc/inc/interfaces.inc
+++ b/etc/inc/interfaces.inc
@@ -1612,6 +1612,14 @@ EOD;
                        setup_pppoe_reset_file($ppp['if']);
        }
 
+       /* sleep until interface is up - or 30 seconds, whichever comes first */
+       for ($count = 0; $count < 30; $count++) {
+               if(file_exists("{$g['tmp_path']}/wanup")) {
+                       break;
+               }
+               sleep(1);
+       }
+
        return 1;
 }
 

Can you please test these patch on your systems?

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #7 on: September 22, 2011, 05:06:22 am »
here r some of the results, below logs r from ppp

unplug cable and reconnect after 30seconds, wan ip changes on reconnect and auto reconeccts fine

Code: [Select]
Sep 22 13:02:52 ppp: [wan_link0] Link: DOWN event
Sep 22 13:02:52 ppp: [wan_link0] LCP: Down event
Sep 22 13:02:52 ppp: [wan_link0] Link: reconnection attempt 12 in 4 seconds
Sep 22 13:02:56 ppp: [wan_link0] Link: reconnection attempt 12
Sep 22 13:02:56 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:02:56 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:03:03 ppp: Multi-link PPP daemon for FreeBSD
Sep 22 13:03:03 ppp:
Sep 22 13:03:03 ppp: process 39410 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 13:56 11-Aug-2011)
Sep 22 13:03:03 ppp: caught fatal signal term
Sep 22 13:03:03 ppp: [wan] IFACE: Close event
Sep 22 13:03:03 ppp: [wan] IPCP: Close event
Sep 22 13:03:03 ppp: waiting for process 8830 to die...
Sep 22 13:03:05 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:03:05 ppp: [wan_link0] Link: DOWN event
Sep 22 13:03:05 ppp: [wan_link0] Link: giving up after 12 reconnection attempts
Sep 22 13:03:05 ppp: [wan_link0] LCP: Close event
Sep 22 13:03:05 ppp: [wan_link0] LCP: state change Starting --> Initial
Sep 22 13:03:05 ppp: [wan_link0] LCP: LayerFinish
Sep 22 13:03:05 ppp: [wan_link0] LCP: Down event
Sep 22 13:03:05 ppp: [wan] Bundle: Shutdown
Sep 22 13:03:05 ppp: [wan_link0] Link: Shutdown
Sep 22 13:03:05 ppp: process 8830 terminated
Sep 22 13:03:05 ppp: waiting for process 8830 to die...
Sep 22 13:03:05 ppp: web: web is not running
Sep 22 13:03:05 ppp: [wan] Bundle: Interface ng0 created
Sep 22 13:03:05 ppp: [wan_link0] Link: OPEN event
Sep 22 13:03:05 ppp: [wan_link0] LCP: Open event
Sep 22 13:03:05 ppp: [wan_link0] LCP: state change Initial --> Starting
Sep 22 13:03:05 ppp: [wan_link0] LCP: LayerStart
Sep 22 13:03:05 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:03:06 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:03:15 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:03:15 ppp: [wan_link0] Link: DOWN event
Sep 22 13:03:15 ppp: [wan_link0] LCP: Down event
Sep 22 13:03:15 ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds
Sep 22 13:03:16 ppp: [wan_link0] Link: reconnection attempt 1
Sep 22 13:03:16 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:03:16 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:03:25 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:03:25 ppp: [wan_link0] Link: DOWN event
Sep 22 13:03:25 ppp: [wan_link0] LCP: Down event
Sep 22 13:03:25 ppp: [wan_link0] Link: reconnection attempt 2 in 3 seconds
Sep 22 13:03:28 ppp: [wan_link0] Link: reconnection attempt 2
Sep 22 13:03:28 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:03:28 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:03:37 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:03:37 ppp: [wan_link0] Link: DOWN event
Sep 22 13:03:37 ppp: [wan_link0] LCP: Down event
Sep 22 13:03:37 ppp: [wan_link0] Link: reconnection attempt 3 in 3 seconds
....
Sep 22 13:06:52 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:01 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:07:01 ppp: [wan_link0] Link: DOWN event
Sep 22 13:07:01 ppp: [wan_link0] LCP: Down event
Sep 22 13:07:01 ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds
Sep 22 13:07:03 ppp: [wan_link0] Link: reconnection attempt 4
Sep 22 13:07:03 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:12 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:07:12 ppp: [wan_link0] Link: DOWN event
Sep 22 13:07:12 ppp: [wan_link0] LCP: Down event
Sep 22 13:07:12 ppp: [wan_link0] Link: reconnection attempt 5 in 1 seconds
Sep 22 13:07:13 ppp: [wan_link0] Link: reconnection attempt 5
Sep 22 13:07:13 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:22 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:07:22 ppp: [wan_link0] Link: DOWN event
Sep 22 13:07:22 ppp: [wan_link0] LCP: Down event
Sep 22 13:07:22 ppp: [wan_link0] Link: reconnection attempt 6 in 1 seconds
Sep 22 13:07:23 ppp: [wan_link0] Link: reconnection attempt 6
Sep 22 13:07:23 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:32 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:07:32 ppp: [wan_link0] Link: DOWN event
Sep 22 13:07:32 ppp: [wan_link0] LCP: Down event
Sep 22 13:07:32 ppp: [wan_link0] Link: reconnection attempt 7 in 1 seconds
Sep 22 13:07:33 ppp: [wan_link0] Link: reconnection attempt 7
Sep 22 13:07:33 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:42 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:07:42 ppp: [wan_link0] Link: DOWN event
Sep 22 13:07:42 ppp: [wan_link0] LCP: Down event
Sep 22 13:07:42 ppp: [wan_link0] Link: reconnection attempt 8 in 4 seconds
Sep 22 13:07:46 ppp: [wan_link0] Link: reconnection attempt 8
Sep 22 13:07:46 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:46 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:07:55 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:07:55 ppp: [wan_link0] Link: DOWN event
Sep 22 13:07:55 ppp: [wan_link0] LCP: Down event
Sep 22 13:07:55 ppp: [wan_link0] Link: reconnection attempt 9 in 1 seconds
Sep 22 13:07:56 ppp: [wan_link0] Link: reconnection attempt 9
Sep 22 13:07:56 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:07:56 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:08:05 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:08:05 ppp: [wan_link0] Link: DOWN event
Sep 22 13:08:05 ppp: [wan_link0] LCP: Down event
Sep 22 13:08:05 ppp: [wan_link0] Link: reconnection attempt 10 in 2 seconds
Sep 22 13:08:07 ppp: [wan_link0] Link: reconnection attempt 10
Sep 22 13:08:07 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:08:07 ppp: PPPoE: rec'd ACNAME "WE1"
Sep 22 13:08:53 ppp: [wan_link0] PPPoE: connection successful
Sep 22 13:08:53 ppp: [wan_link0] Link: UP event
Sep 22 13:08:53 ppp: [wan_link0] LCP: Up event
Sep 22 13:08:53 ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Sep 22 13:08:53 ppp: [wan_link0] LCP: SendConfigReq #4
Sep 22 13:08:53 ppp: [wan_link0] PROTOCOMP
Sep 22 13:08:53 ppp: [wan_link0] MRU 1492
Sep 22 13:08:53 ppp: [wan_link0] MAGICNUM 9ecd1a58
Sep 22 13:08:53 ppp: [wan_link0] LCP: rec'd Configure Request #88 (Req-Sent)
Sep 22 13:08:53 ppp: [wan_link0] MRU 1492
Sep 22 13:08:53 ppp: [wan_link0] AUTHPROTO PAP
Sep 22 13:08:53 ppp: [wan_link0] MAGICNUM 6fd061fb
Sep 22 13:08:53 ppp: [wan_link0] LCP: SendConfigAck #88
Sep 22 13:08:53 ppp: [wan_link0] MRU 1492
Sep 22 13:08:53 ppp: [wan_link0] AUTHPROTO PAP
Sep 22 13:08:53 ppp: [wan_link0] MAGICNUM 6fd061fb
Sep 22 13:08:53 ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Sep 22 13:08:53 ppp: [wan_link0] LCP: rec'd Configure Ack #4 (Ack-Sent)
Sep 22 13:08:53 ppp: [wan_link0] PROTOCOMP
Sep 22 13:08:53 ppp: [wan_link0] MRU 1492
Sep 22 13:08:53 ppp: [wan_link0] MAGICNUM 9ecd1a58
Sep 22 13:08:53 ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Sep 22 13:08:53 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Sep 22 13:08:53 ppp: [wan_link0] PAP: using authname "xbipin"
Sep 22 13:08:53 ppp: [wan_link0] PAP: sending REQUEST #1 len: 20
Sep 22 13:08:53 ppp: [wan_link0] LCP: LayerUp
Sep 22 13:08:55 ppp: [wan_link0] PAP: using authname "xbipin"
Sep 22 13:08:55 ppp: [wan_link0] PAP: sending REQUEST #2 len: 20
Sep 22 13:08:59 ppp: [wan_link0] PAP: using authname "xbipin"
Sep 22 13:08:59 ppp: [wan_link0] PAP: sending REQUEST #4 len: 20
Sep 22 13:09:01 ppp: [wan_link0] PAP: rec'd ACK #4 len: 5
Sep 22 13:09:01 ppp: [wan_link0] LCP: authorization successful
Sep 22 13:09:01 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 22 13:09:01 ppp: [wan_link0] Link: Join bundle "wan"
Sep 22 13:09:01 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 22 13:09:01 ppp: [wan] IPCP: Open event
Sep 22 13:09:01 ppp: [wan] IPCP: state change Initial --> Starting
Sep 22 13:09:01 ppp: [wan] IPCP: LayerStart
Sep 22 13:09:01 ppp: [wan] IPCP: Up event
Sep 22 13:09:01 ppp: [wan] IPCP: state change Starting --> Req-Sent
Sep 22 13:09:01 ppp: [wan] IPCP: SendConfigReq #4
Sep 22 13:09:01 ppp: [wan] IPADDR 0.0.0.0
Sep 22 13:09:01 ppp: [wan] IPCP: rec'd Configure Nak #4 (Req-Sent)
Sep 22 13:09:01 ppp: [wan] IPADDR 92.99.131.139
Sep 22 13:09:01 ppp: [wan] 92.99.131.139 is OK
Sep 22 13:09:01 ppp: [wan] IPCP: SendConfigReq #5
Sep 22 13:09:01 ppp: [wan] IPADDR 92.99.131.139
Sep 22 13:09:01 ppp: [wan] IPCP: rec'd Configure Ack #5 (Req-Sent)
Sep 22 13:09:01 ppp: [wan] IPADDR 92.99.131.139
Sep 22 13:09:01 ppp: [wan] IPCP: state change Req-Sent --> Ack-Rcvd
Sep 22 13:09:01 ppp: [wan] IPCP: rec'd Configure Request #210 (Ack-Rcvd)
Sep 22 13:09:01 ppp: [wan] IPADDR 195.229.252.27
Sep 22 13:09:01 ppp: [wan] 195.229.252.27 is OK
Sep 22 13:09:01 ppp: [wan] IPCP: SendConfigAck #210
Sep 22 13:09:01 ppp: [wan] IPADDR 195.229.252.27
Sep 22 13:09:01 ppp: [wan] IPCP: state change Ack-Rcvd --> Opened
Sep 22 13:09:01 ppp: [wan] IPCP: LayerUp
Sep 22 13:09:01 ppp: [wan] 92.99.131.139 -> 195.229.252.27
Sep 22 13:09:01 ppp: [wan] IFACE: Up event


unplug cable and reconnect in less than 30seconds, wan ip remains same at first then later changes and reconnects fine

Code: [Select]
Sep 22 13:20:35 ppp: [wan_link0] LCP: no reply to 1 echo request(s)
Sep 22 13:21:45 ppp: [wan_link0] LCP: no reply to 1 echo request(s)
Sep 22 13:21:45 ppp: [wan_link0] LCP: no reply to 2 echo request(s)
Sep 22 13:21:55 ppp: [wan_link0] LCP: no reply to 3 echo request(s)
...
Sep 22 13:48:20 ppp: [wan_link0] PROTOCOMP
Sep 22 13:48:20 ppp: [wan_link0] MRU 1492
Sep 22 13:48:20 ppp: [wan_link0] MAGICNUM a59b33b8
Sep 22 13:48:21 ppp: [wan_link0] LCP: rec'd Configure Request #67 (Req-Sent)
Sep 22 13:48:21 ppp: [wan_link0] MRU 1492
Sep 22 13:48:21 ppp: [wan_link0] AUTHPROTO PAP
Sep 22 13:48:21 ppp: [wan_link0] MAGICNUM 35150c1e
Sep 22 13:48:21 ppp: [wan_link0] LCP: SendConfigAck #67
Sep 22 13:48:21 ppp: [wan_link0] MRU 1492
Sep 22 13:48:21 ppp: [wan_link0] AUTHPROTO PAP
Sep 22 13:48:21 ppp: [wan_link0] MAGICNUM 35150c1e
Sep 22 13:48:21 ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Sep 22 13:48:21 ppp: [wan_link0] LCP: rec'd Configure Ack #7 (Ack-Sent)
Sep 22 13:48:21 ppp: [wan_link0] PROTOCOMP
Sep 22 13:48:21 ppp: [wan_link0] MRU 1492
Sep 22 13:48:21 ppp: [wan_link0] MAGICNUM a59b33b8
Sep 22 13:48:21 ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Sep 22 13:48:21 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Sep 22 13:48:21 ppp: [wan_link0] PAP: using authname "xbipin"
Sep 22 13:48:21 ppp: [wan_link0] PAP: sending REQUEST #1 len: 20
Sep 22 13:48:21 ppp: [wan_link0] LCP: LayerUp
Sep 22 13:48:21 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Sep 22 13:48:21 ppp: [wan_link0] LCP: authorization successful
Sep 22 13:48:21 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 22 13:48:21 ppp: [wan_link0] Link: Join bundle "wan"
Sep 22 13:48:21 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 22 13:48:21 ppp: [wan] IPCP: Open event
Sep 22 13:48:21 ppp: [wan] IPCP: state change Initial --> Starting
Sep 22 13:48:21 ppp: [wan] IPCP: LayerStart
Sep 22 13:48:21 ppp: [wan] IPCP: Up event
Sep 22 13:48:21 ppp: [wan] IPCP: state change Starting --> Req-Sent
Sep 22 13:48:21 ppp: [wan] IPCP: SendConfigReq #7
Sep 22 13:48:21 ppp: [wan] IPADDR 0.0.0.0
Sep 22 13:48:21 ppp: [wan] IPCP: rec'd Configure Nak #7 (Req-Sent)
Sep 22 13:48:21 ppp: [wan] IPADDR 92.99.205.85
Sep 22 13:48:21 ppp: [wan] 92.99.205.85 is OK
Sep 22 13:48:21 ppp: [wan] IPCP: SendConfigReq #8
Sep 22 13:48:21 ppp: [wan] IPADDR 92.99.205.85
Sep 22 13:48:21 ppp: [wan] IPCP: rec'd Configure Ack #8 (Req-Sent)
Sep 22 13:48:21 ppp: [wan] IPADDR 92.99.205.85
Sep 22 13:48:21 ppp: [wan] IPCP: state change Req-Sent --> Ack-Rcvd
Sep 22 13:48:21 ppp: [wan] IPCP: rec'd Configure Request #245 (Ack-Rcvd)
Sep 22 13:48:21 ppp: [wan] IPADDR 195.229.252.27
Sep 22 13:48:21 ppp: [wan] 195.229.252.27 is OK
Sep 22 13:48:21 ppp: [wan] IPCP: SendConfigAck #245
Sep 22 13:48:21 ppp: [wan] IPADDR 195.229.252.27
Sep 22 13:48:21 ppp: [wan] IPCP: state change Ack-Rcvd --> Opened
Sep 22 13:48:21 ppp: [wan] IPCP: LayerUp
Sep 22 13:48:21 ppp: [wan] 92.99.205.85 -> 195.229.252.27
Sep 22 13:48:21 ppp: [wan] IFACE: Up event

fibre optic modem from isp reset or power plug pulled and then after some time repowered but pf doesnt reconnect, log below

Code: [Select]
Sep 22 13:52:31 ppp: [wan_link0] LCP: no reply to 1 echo request(s)
Sep 22 13:52:41 ppp: [wan_link0] LCP: no reply to 2 echo request(s)
Sep 22 13:52:51 ppp: [wan_link0] LCP: no reply to 3 echo request(s)
Sep 22 13:53:01 ppp: [wan_link0] LCP: no reply to 4 echo request(s)
Sep 22 13:53:11 ppp: [wan_link0] LCP: no reply to 5 echo request(s)
Sep 22 13:53:11 ppp: [wan_link0] LCP: peer not responding to echo requests
Sep 22 13:53:11 ppp: [wan_link0] LCP: state change Opened --> Stopping
Sep 22 13:53:11 ppp: [wan_link0] Link: Leave bundle "wan"
Sep 22 13:53:11 ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Sep 22 13:53:11 ppp: [wan] IPCP: Close event
Sep 22 13:53:11 ppp: [wan] IPCP: state change Opened --> Closing
Sep 22 13:53:11 ppp: [wan] IPCP: SendTerminateReq #9
Sep 22 13:53:11 ppp: [wan] IPCP: LayerDown
Sep 22 13:53:11 ppp: [wan] IFACE: Down event
Sep 22 13:53:11 ppp: [wan] IPCP: Down event
Sep 22 13:53:11 ppp: [wan] IPCP: LayerFinish
Sep 22 13:53:11 ppp: [wan] Bundle: No NCPs left. Closing links...
Sep 22 13:53:11 ppp: [wan] IPCP: state change Closing --> Initial
Sep 22 13:53:11 ppp: [wan_link0] LCP: SendTerminateReq #8
Sep 22 13:53:11 ppp: [wan_link0] LCP: LayerDown
Sep 22 13:53:13 ppp: [wan_link0] LCP: SendTerminateReq #9
Sep 22 13:53:15 ppp: [wan_link0] LCP: state change Stopping --> Stopped
Sep 22 13:53:15 ppp: [wan_link0] LCP: LayerFinish
Sep 22 13:53:15 ppp: [wan_link0] PPPoE: connection closed
Sep 22 13:53:15 ppp: [wan_link0] Link: DOWN event
Sep 22 13:53:15 ppp: [wan_link0] LCP: Down event
Sep 22 13:53:15 ppp: [wan_link0] LCP: state change Stopped --> Starting
Sep 22 13:53:15 ppp: [wan_link0] LCP: LayerStart
Sep 22 13:53:15 ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds
Sep 22 13:53:16 ppp: [wan_link0] Link: reconnection attempt 1
Sep 22 13:53:16 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:53:25 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:53:25 ppp: [wan_link0] Link: DOWN event
Sep 22 13:53:25 ppp: [wan_link0] LCP: Down event
Sep 22 13:53:25 ppp: [wan_link0] Link: reconnection attempt 2 in 2 seconds
Sep 22 13:53:27 ppp: [wan_link0] Link: reconnection attempt 2
Sep 22 13:53:27 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:53:36 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:53:36 ppp: [wan_link0] Link: DOWN event
Sep 22 13:53:36 ppp: [wan_link0] LCP: Down event
Sep 22 13:53:36 ppp: [wan_link0] Link: reconnection attempt 3 in 3 seconds
Sep 22 13:53:39 ppp: [wan_link0] Link: reconnection attempt 3
Sep 22 13:53:39 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:53:48 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:53:48 ppp: [wan_link0] Link: DOWN event
Sep 22 13:53:48 ppp: [wan_link0] LCP: Down event
Sep 22 13:53:48 ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds
Sep 22 13:53:50 ppp: [wan_link0] Link: reconnection attempt 4
Sep 22 13:53:50 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:53:59 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:53:59 ppp: [wan_link0] Link: DOWN event
Sep 22 13:53:59 ppp: [wan_link0] LCP: Down event
Sep 22 13:53:59 ppp: [wan_link0] Link: reconnection attempt 5 in 1 seconds
Sep 22 13:54:00 ppp: [wan_link0] Link: reconnection attempt 5
Sep 22 13:54:00 ppp: [wan_link0] PPPoE: Connecting to ''
Sep 22 13:54:09 ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Sep 22 13:54:09 ppp: [wan_link0] Link: DOWN event
Sep 22 13:54:09 ppp: [wan_link0] LCP: Down event
Sep 22 13:54:09 ppp: [wan_link0] Link: reconnection attempt 6 in 3 seconds
Sep 22 13:54:12 ppp: [wan_link0] Link: reconnection attempt 6
Sep 22 13:54:12 ppp: [wan_link0] PPPoE: Connecting to ''

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #8 on: September 22, 2011, 05:07:35 am »
in summary, resetting isp modem doesnt make pppoe reconnect, problem still persists

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #9 on: September 26, 2011, 11:54:14 am »
any luck?

Offline wallabybob

  • Hero Member
  • *****
  • Posts: 5262
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #10 on: September 27, 2011, 02:40:57 am »
The logs displayed a couple of replies back suggest the remote PPP is not responding. Since this seems to be fairly easily reproduced I suggests you make it happen while you have a packet trace running so you can see if there is response from the ISP. Is there any response from the ISP's end? If not, call their technical support to report the problem.


Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #11 on: September 27, 2011, 03:53:59 am »
do i run the below command with itnerface as pppoe0 or vr1 ?

tcpdump -i $interface -s 0 -vvvX -w /tmp/pcap.capture

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #12 on: September 27, 2011, 04:10:10 am »
command run with interface as pppoe0 and replicated a isp modem reset and complete endless loop for a while for pf reconnection attempt
file name - pcap.capture1

command run with interface as vr1 and replicated a isp modem reset and complete endless loop for a while for pf reconnection attempt
file name - pcap.capture2

the cable from the isp modem to pf if i put in my PC then the LAN status always shows limited connectivity and no IP from DHCP untill a pppoe connection is dialed out

all standard routers such as dlink, aztec etc sold in this region work fine when u replicate the same situation so i wonder how do they reconnect fine even if u reset or unplug and replug the isp modem
« Last Edit: September 27, 2011, 04:12:49 am by xbipin »

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #13 on: September 27, 2011, 04:37:22 am »
the strange thing is, suppose i remove the wan cable going from my isp modem to pfsense and reboot pfsense then once its done it doesnt have any internet connection and no wan ip but as soon as i plug the cable from my isp modem to pfsense, in just one attempt it connects and remains connected.

so i guess the issue is mpd would need the vr1 used for wan to do down and then replugging it would get it up and then mpd would dial out perfectly fine but if the isp modem was just reset then the port has the power on so i guess it doesn't go down completely and then reconnection attempt keeps failing. i mayb wrong on the above but at least on a pfsense reboot everything works fine and even on a cable unplug and replug also works fine but on a modem reset only it doesn't work or mayb if the isp modem hanged or dropped the connection then also it would connect but i havent been able to do that.

Offline xbipin

  • Hero Member
  • *****
  • Posts: 1220
  • Karma: +0/-0
    • View Profile
Re: PPPoE reconenction fix - mpd fix ($100)
« Reply #14 on: September 29, 2011, 03:25:22 am »
can anything be done to solve this?