PPPoE WAN fails to reconnect after link loss
-
Hello, did you find out what was the problem ? Because I have the exact same problem with the exact same errors after upgrading to 2.4.4 .
[opt1] IFACE: Adding IPv4 address to pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): File exists
[opt1] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
Tried everything, nothing helps. The connection works on VyOS and directly on the PC.
-
@joxxxx I have yet to find a solution. For me, the (re)connection works fine on other OSes and devices as well.
Let me know you find out anything. Unfortunately I'm considering migrating to something else.
-
@vegbrasil After countless hours, I found out that the pppoe layer of pfsense 2.4.4 is bugged ! Something changed and because of that, the connection cannot be made with the above errors. Tried it also on a clean 2.4.4 install.
Re-installed 2.4.3 and all works again without any problems ! The thing is that I cannot install any packages anymore because it wants me to update to 2.4.4...
Btw, tried the dev 2.4.5 version and the bug is still the same.
I hope this will be fixed because it mus affect a lot of people...
-
Hmm, there must be something different about your connection. I have two WANs here, both are PPPoE. The only issue I saw was when the ISP(s) had an outage. However the logs showed it as very broken, nothing like what you're seeing.
If PPPoE was broken in general there would be hundreds of posts about it by now.Are you able to try a different NIC type?
Steve
-
@stephenw10 I've been using pfSense with the same FTTH PPPoE connection and two different NICs models:
- Realtek NICs (
re
): crazy bugs, completely unusable - expected, I guess; - Intel PRO/1000 NICs (
em
): Problem of the initial post: first connection is fine, re-connection never happens. If we forget this problem, everything is working great and I can max-out the connection (150 Mbps).
Upgraded and tried clean install between 2.3.5 and all 2.4.x versions.
Thanks!
- Realtek NICs (
-
Hmm, yes, this seems like it must be some other issue since you were seeing it in 2.4.3 also.
Steve
-
I'm using a quad ethernet intel pro and never got any problems since v 2.0 or something like that on vmware and the vmx3 driver.
After analysing a bit the problem, could it be that I have this problem because pfsense sees his local ip address as the FTTH gateway ?
Maybe something changed in pf 2.4.4 in the pppoe connection that forbids the use of it ? (This could explain the two errors I get). -
@joxxxx said in PPPoE WAN fails to reconnect after link loss:
I'm using a quad ethernet intel pro
I'm using the exactly same hardware and since we're having the same problem, we may have found the initial cause.
-
@stephenw10 On my side, 2.4.3 is working perfectly and reaching the 1Gbits of the fiber connection. The problem is 2.4.4 and above where it doesn't want to allocate the ip address it gets (I can see the received ip address in the logs, but the error prevents it to be used).
-
How did you get anywhere with this problem?
I'm experiencing the same issue:
Could it be the PPPoE is not receiving a gateway address and the IP address its been given is a /32 255.255.255.255 address
This is the case with mine.
I started on 2.4.3 upgraded to 2.4.4 (this causes the WAN to drop and it attempt another connection - 2.4.3 got this error and left the link up)
Feb 4 12:22:20 ppp [wan] IFACE: Adding IPv4 address to pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Destination address required
Feb 4 12:22:20 ppp [wan] 118.xxx.xxx.xxx -> 0.0.0.0
Feb 4 12:22:20 ppp [wan] IPCP: LayerUp
Feb 4 12:22:20 ppp [wan] IPCP: state change Ack-Sent --> Opened
Feb 4 12:22:20 ppp [wan] IPADDR 118.xxx.xxx.xxx -
@timboau-0 I still have the issue, running the latest stable version of pfSense.
This weekend I needed to visit a site that was offline due this reconnection problem. I'm thinking of making some sort of simple script to reboot the server if the WAN is offline for a few minutes...
Thanks!
-
This post is deleted! -
I'll add that im using APU intel nic no VM
Tested clean install of 2.3.1 & 2.4.42.3.1 has some additional information:
Feb 4 02:35:58 ppp [wan] IPCP: LayerUp
Feb 4 02:35:58 ppp [wan] 118.xxx.xxx.xxx -> 0.0.0.0
Feb 4 02:35:58 ppp [wan] IFACE: Adding IPv4 address to pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Destination address required
Feb 4 02:35:58 check_reload_status Rewriting resolv.conf
Feb 4 02:35:58 php-fpm 19680 /rc.interfaces_wan_configure: Accept router advertisements on interface igb0
Feb 4 02:35:59 check_reload_status updating dyndns wan
Feb 4 02:35:59 check_reload_status rc.newwanip starting pppoe0
Feb 4 02:35:59 ppp [wan] IFACE: Up event
Feb 4 02:35:59 ppp [wan] IFACE: Rename interface ng0 to pppoe0
Feb 4 02:36:00 php-fpm 34057 /rc.newwanip: rc.newwanip: Info: starting on pppoe0.
Feb 4 02:36:00 php-fpm 34057 /rc.newwanip: rc.newwanip: on (IP address: ) (interface: WAN[wan]) (real interface: pppoe0).
Feb 4 02:36:00 php-fpm 34057 /rc.newwanip: rc.newwanip: Failed to update wan IP, restarting...
Feb 4 02:36:00 check_reload_status Configuring interface wan
Feb 4 02:36:01 check_reload_status Reloading filter
Feb 4 02:36:01 php-fpm 16228 /interfaces.php: Creating rrd update script
Feb 4 02:36:02 xinetd 9811 Starting reconfiguration
Feb 4 02:36:02 xinetd 9811 Swapping defaults
Feb 4 02:36:02 xinetd 9811 readjusting service 6969-udp
Feb 4 02:36:02 xinetd 9811 Reconfigured: new=0 old=1 dropped=0 (services)
Feb 4 02:36:05 php-fpm 23933 /rc.interfaces_wan_configure: Accept router advertisements on interface igb0 -
/32 is valid for a PPP connection as is a gateway outside the subnet.
Obviously no gateway at all isn't though.
Steve
-
I got my config working on 2.4.3 (but I think it will break on 2.4.4) - I had a slightly different problem to the above.
With 2.4.3 I can authenticate and the interface stays up
2.4.4 I authenticate and the interface cant be configured as it doesnt have a gateway IP so it goes down and then re-connects. (repeat)I fixed my problem : (Not being supplied a gateway via PPPoE)
Configuring the interface with PPPoE (as per usual)
Then creating a NEW interface on the same port as STATIC (save)
Then open the interface again and create a gateway using that same IPI can see that if I lost authentication now on 2.4.3 I would likely loose connectivity as the PPPoE wont try again (without a reboot or port cycle)
I worry on 2.4.4 that it will fail the interface configuration due to the missing gateway then the 2nd interface wont work either.. -
Hmm, try it and see. Odd setup.
Steve
-
@timboau-0
Did someone noticed the same File Exists! error for PPPoE on 2.5.2 ? I have the same issue after the upgrade.IFACE: Adding IPv4 address to pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): File exists
-
I am also facing the same problem on 2.5.2 version. Pls Help
-
Which problem exactly are you seeing? PPPoE connection with no gateway? Can we see some logs?
Steve
-
@stephenw10 Yes sir, Sorry I am late. This is the PPP log. See first it was connected and then I disconnected the cable to pfsense to simulate a disconnection and it does not connect again. I had to reboot the box to get connected again. Thanks for helping.
Dec 6 20:16:26 ppp 89924 [wan_link0] MAGICNUM 0x754938a9
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 6 20:16:26 ppp 89924 [wan_link0] PROTOCOMP
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: SendConfigReq #2
Dec 6 20:16:26 ppp 89924 [wan_link0] MRU 1492
Dec 6 20:16:26 ppp 89924 [wan_link0] MAGICNUM 0x745b8af8
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 6 20:16:26 ppp 89924 [wan_link0] MRU 1460
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: SendConfigReq #3
Dec 6 20:16:26 ppp 89924 [wan_link0] MRU 1460
Dec 6 20:16:26 ppp 89924 [wan_link0] MAGICNUM 0x745b8af8
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 6 20:16:26 ppp 89924 [wan_link0] MRU 1460
Dec 6 20:16:26 ppp 89924 [wan_link0] MAGICNUM 0x745b8af8
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: state change Ack-Sent --> Opened
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: LayerUp
Dec 6 20:16:26 ppp 89924 [wan_link0] CHAP: rec'd CHALLENGE #1 len: 39
Dec 6 20:16:26 ppp 89924 [wan_link0] Name: "XXXXXXXXXXXX"
Dec 6 20:16:26 ppp 89924 [wan_link0] CHAP: Using authname "XXXXXXXX"
Dec 6 20:16:26 ppp 89924 [wan_link0] CHAP: sending RESPONSE #1 len: 50
Dec 6 20:16:26 ppp 89924 [wan_link0] CHAP: rec'd SUCCESS #1 len: 26
Dec 6 20:16:26 ppp 89924 [wan_link0] MESG: Authentication Success
Dec 6 20:16:26 ppp 89924 [wan_link0] LCP: authorization successful
Dec 6 20:16:26 ppp 89924 [wan_link0] Link: Matched action 'bundle "wan" ""'
Dec 6 20:16:26 ppp 89924 [wan_link0] Link: Join bundle "wan"
Dec 6 20:16:26 ppp 89924 [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Dec 6 20:16:26 ppp 89924 [wan] IPCP: Open event
Dec 6 20:16:26 ppp 89924 [wan] IPCP: state change Initial --> Starting
Dec 6 20:16:26 ppp 89924 [wan] IPCP: LayerStart
Dec 6 20:16:26 ppp 89924 [wan] IPCP: Up event
Dec 6 20:16:26 ppp 89924 [wan] IPCP: state change Starting --> Req-Sent
Dec 6 20:16:26 ppp 89924 [wan] IPCP: SendConfigReq #1
Dec 6 20:16:26 ppp 89924 [wan] IPADDR 0.0.0.0
Dec 6 20:16:26 ppp 89924 [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 6 20:16:26 ppp 89924 [wan] PRIDNS 0.0.0.0
Dec 6 20:16:26 ppp 89924 [wan] SECDNS 0.0.0.0
Dec 6 20:16:26 ppp 89924 [wan] IPCP: rec'd Configure Request #187 (Req-Sent)
Dec 6 20:16:26 ppp 89924 [wan] IPADDR IP.ADDRESS..1
Dec 6 20:16:26 ppp 89924 [wan] IP.ADDRESS..1 is OK
Dec 6 20:16:26 ppp 89924 [wan] IPCP: SendConfigAck #187
Dec 6 20:16:26 ppp 89924 [wan] IPADDR IP.ADDRESS..1
Dec 6 20:16:26 ppp 89924 [wan] IPCP: state change Req-Sent --> Ack-Sent
Dec 6 20:16:26 ppp 89924 [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 6 20:16:26 ppp 89924 [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 6 20:16:26 ppp 89924 [wan] IPCP: SendConfigReq #2
Dec 6 20:16:26 ppp 89924 [wan] IPADDR 0.0.0.0
Dec 6 20:16:26 ppp 89924 [wan] PRIDNS 0.0.0.0
Dec 6 20:16:26 ppp 89924 [wan] SECDNS 0.0.0.0
Dec 6 20:16:26 ppp 89924 [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 6 20:16:26 ppp 89924 [wan] IPADDR IP.ADDRESS..3
Dec 6 20:16:26 ppp 89924 [wan] IP.ADDRESS..3 is OK
Dec 6 20:16:26 ppp 89924 [wan] PRIDNS IP.ADDRESS..193
Dec 6 20:16:26 ppp 89924 [wan] SECDNS IP.ADDRESS..225
Dec 6 20:16:26 ppp 89924 [wan] IPCP: SendConfigReq #3
Dec 6 20:16:26 ppp 89924 [wan] IPADDR IP.ADDRESS..3
Dec 6 20:16:26 ppp 89924 [wan] PRIDNS IP.ADDRESS..193
Dec 6 20:16:26 ppp 89924 [wan] SECDNS IP.ADDRESS..225
Dec 6 20:16:26 ppp 89924 [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 6 20:16:26 ppp 89924 [wan] IPADDR IP.ADDRESS..3
Dec 6 20:16:26 ppp 89924 [wan] PRIDNS IP.ADDRESS..193
Dec 6 20:16:26 ppp 89924 [wan] SECDNS IP.ADDRESS..225
Dec 6 20:16:26 ppp 89924 [wan] IPCP: state change Ack-Sent --> Opened
Dec 6 20:16:26 ppp 89924 [wan] IPCP: LayerUp
Dec 6 20:16:26 ppp 89924 [wan] IP.ADDRESS..3 -> IP.ADDRESS..1
Dec 6 20:16:27 ppp 89924 [wan] IFACE: Up event
Dec 6 20:16:27 ppp 89924 [wan] IFACE: Rename interface ng0 to pppoe0
Dec 7 01:55:41 ppp 7531 Multi-link PPP daemon for FreeBSD
Dec 7 01:55:41 ppp 7531 process 7531 started, version 5.9
Dec 7 01:55:41 ppp 7531 web: web is not running
Dec 7 01:55:41 ppp 7531 [wan] Bundle: Interface ng0 created
Dec 7 01:55:41 ppp 7531 [wan_link0] Link: OPEN event
Dec 7 01:55:41 ppp 7531 [wan_link0] LCP: Open event
Dec 7 01:55:41 ppp 7531 [wan_link0] LCP: state change Initial --> Starting
Dec 7 01:55:41 ppp 7531 [wan_link0] LCP: LayerStart
Dec 7 01:55:41 ppp 7531 [wan_link0] PPPoE: Connecting to ''
Dec 7 01:55:44 ppp 7531 PPPoE: rec'd ACNAME "XXXXXXXXXXXXXXXXXXX"
Dec 7 01:55:44 ppp 7531 [wan_link0] PPPoE: connection successful
Dec 7 01:55:44 ppp 7531 [wan_link0] Link: UP event
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: Up event
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: state change Starting --> Req-Sent
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: SendConfigReq #1
Dec 7 01:55:44 ppp 7531 [wan_link0] PROTOCOMP
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1492
Dec 7 01:55:44 ppp 7531 [wan_link0] MAGICNUM 0x42de2289
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: rec'd Configure Request #244 (Req-Sent)
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1460
Dec 7 01:55:44 ppp 7531 [wan_link0] AUTHPROTO CHAP MD5
Dec 7 01:55:44 ppp 7531 [wan_link0] MAGICNUM 0x22726716
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: SendConfigAck #244
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1460
Dec 7 01:55:44 ppp 7531 [wan_link0] AUTHPROTO CHAP MD5
Dec 7 01:55:44 ppp 7531 [wan_link0] MAGICNUM 0x22726716
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 7 01:55:44 ppp 7531 [wan_link0] PROTOCOMP
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: SendConfigReq #2
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1492
Dec 7 01:55:44 ppp 7531 [wan_link0] MAGICNUM 0x42de2289
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1460
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: SendConfigReq #3
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1460
Dec 7 01:55:44 ppp 7531 [wan_link0] MAGICNUM 0x42de2289
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 7 01:55:44 ppp 7531 [wan_link0] MRU 1460
Dec 7 01:55:44 ppp 7531 [wan_link0] MAGICNUM 0x42de2289
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: state change Ack-Sent --> Opened
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Dec 7 01:55:44 ppp 7531 [wan_link0] LCP: LayerUp
Dec 7 01:55:44 ppp 7531 [wan_link0] CHAP: rec'd CHALLENGE #1 len: 39
Dec 7 01:55:44 ppp 7531 [wan_link0] Name: "XXXXXXXXXXX"
Dec 7 01:55:44 ppp 7531 [wan_link0] CHAP: Using authname "XXXXXXXX"
Dec 7 01:55:44 ppp 7531 [wan_link0] CHAP: sending RESPONSE #1 len: 50
Dec 7 01:55:45 ppp 7531 [wan_link0] CHAP: rec'd SUCCESS #1 len: 26
Dec 7 01:55:45 ppp 7531 [wan_link0] MESG: Authentication Success
Dec 7 01:55:45 ppp 7531 [wan_link0] LCP: authorization successful
Dec 7 01:55:45 ppp 7531 [wan_link0] Link: Matched action 'bundle "wan" ""'
Dec 7 01:55:45 ppp 7531 [wan_link0] Link: Join bundle "wan"
Dec 7 01:55:45 ppp 7531 [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Dec 7 01:55:45 ppp 7531 [wan] IPCP: Open event
Dec 7 01:55:45 ppp 7531 [wan] IPCP: state change Initial --> Starting
Dec 7 01:55:45 ppp 7531 [wan] IPCP: LayerStart
Dec 7 01:55:45 ppp 7531 [wan] IPCP: Up event
Dec 7 01:55:45 ppp 7531 [wan] IPCP: state change Starting --> Req-Sent
Dec 7 01:55:45 ppp 7531 [wan] IPCP: SendConfigReq #1
Dec 7 01:55:45 ppp 7531 [wan] IPADDR 0.0.0.0
Dec 7 01:55:45 ppp 7531 [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 7 01:55:45 ppp 7531 [wan] IPCP: rec'd Configure Request #228 (Req-Sent)
Dec 7 01:55:45 ppp 7531 [wan] IPADDR IP.ADDRESS..1
Dec 7 01:55:45 ppp 7531 [wan] IP.ADDRESS..1 is OK
Dec 7 01:55:45 ppp 7531 [wan] IPCP: SendConfigAck #228
Dec 7 01:55:45 ppp 7531 [wan] IPADDR IP.ADDRESS..1
Dec 7 01:55:45 ppp 7531 [wan] IPCP: state change Req-Sent --> Ack-Sent
Dec 7 01:55:45 ppp 7531 [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 7 01:55:45 ppp 7531 [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 7 01:55:45 ppp 7531 [wan] IPCP: SendConfigReq #2
Dec 7 01:55:45 ppp 7531 [wan] IPADDR 0.0.0.0
Dec 7 01:55:45 ppp 7531 [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 7 01:55:45 ppp 7531 [wan] IPADDR IP.ADDRESS..3
Dec 7 01:55:45 ppp 7531 [wan] IP.ADDRESS..3 is OK
Dec 7 01:55:45 ppp 7531 [wan] IPCP: SendConfigReq #3
Dec 7 01:55:45 ppp 7531 [wan] IPADDR IP.ADDRESS..3
Dec 7 01:55:45 ppp 7531 [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 7 01:55:45 ppp 7531 [wan] IPADDR IP.ADDRESS..3
Dec 7 01:55:45 ppp 7531 [wan] IPCP: state change Ack-Sent --> Opened
Dec 7 01:55:45 ppp 7531 [wan] IPCP: LayerUp
Dec 7 01:55:45 ppp 7531 [wan] IP.ADDRESS..3 -> IP.ADDRESS..1
Dec 7 01:55:45 ppp 7531 [wan] IFACE: Up event
Dec 7 01:55:45 ppp 7531 [wan] IFACE: Rename interface ng0 to pppoe0
Dec 7 02:02:04 ppp 56916 Multi-link PPP daemon for FreeBSD
Dec 7 02:02:04 ppp 56916 process 56916 started, version 5.9
Dec 7 02:02:04 ppp 7531 caught fatal signal TERM
Dec 7 02:02:04 ppp 7531 [wan] IFACE: Close event
Dec 7 02:02:04 ppp 7531 [wan] IPCP: Close event
Dec 7 02:02:04 ppp 7531 [wan] IPCP: state change Opened --> Closing
Dec 7 02:02:04 ppp 7531 [wan] IPCP: SendTerminateReq #4
Dec 7 02:02:04 ppp 7531 [wan] IPCP: LayerDown
Dec 7 02:02:04 ppp 56916 waiting for process 7531 to die...
Dec 7 02:02:04 ppp 7531 [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
Dec 7 02:02:04 ppp 7531 [wan] IFACE: Down event
Dec 7 02:02:04 ppp 7531 [wan] IFACE: Rename interface pppoe0 to pppoe0
Dec 7 02:02:04 ppp 7531 [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Dec 7 02:02:04 ppp 7531 [wan] IPCP: state change Closing --> Closed
Dec 7 02:02:04 ppp 7531 [wan] IPCP: LayerFinish
Dec 7 02:02:04 ppp 7531 [wan] Bundle: No NCPs left. Closing links...
Dec 7 02:02:04 ppp 7531 [wan] Bundle: closing link "wan_link0"...
Dec 7 02:02:04 ppp 7531 [wan_link0] LCP: rec'd Terminate Request #245 (Opened)
Dec 7 02:02:04 ppp 7531 [wan_link0] LCP: state change Opened --> Stopping
Dec 7 02:02:04 ppp 7531 [wan_link0] Link: Leave bundle "wan"
Dec 7 02:02:04 ppp 7531 [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Dec 7 02:02:04 ppp 7531 [wan] IPCP: Close event
Dec 7 02:02:04 ppp 7531 [wan] IPCP: Down event
Dec 7 02:02:04 ppp 7531 [wan] IPCP: state change Closed --> Initial
Dec 7 02:02:04 ppp 7531 [wan_link0] LCP: SendTerminateAck #4
Dec 7 02:02:04 ppp 7531 [wan_link0] LCP: LayerDown
Dec 7 02:02:04 ppp 7531 [wan_link0] Link: CLOSE event
Dec 7 02:02:04 ppp 7531 [wan_link0] LCP: Close event
Dec 7 02:02:04 ppp 7531 [wan_link0] LCP: state change Stopping --> Closing
Dec 7 02:02:05 ppp 56916 waiting for process 7531 to die...
Dec 7 02:02:06 ppp 56916 waiting for process 7531 to die...
Dec 7 02:02:06 ppp 7531 [wan_link0] LCP: state change Closing --> Closed
Dec 7 02:02:06 ppp 7531 [wan_link0] LCP: LayerFinish
Dec 7 02:02:06 ppp 7531 [wan] Bundle: Shutdown
Dec 7 02:02:07 ppp 7531 [wan_link0] Link: Shutdown
Dec 7 02:02:07 ppp 7531 process 7531 terminated
Dec 7 02:02:08 ppp 56916 web: web is not running
Dec 7 02:02:08 ppp 56916 [wan] Bundle: Interface ng0 created
Dec 7 02:02:08 ppp 56916 [wan_link0] Link: OPEN event
Dec 7 02:02:08 ppp 56916 [wan_link0] LCP: Open event
Dec 7 02:02:08 ppp 56916 [wan_link0] LCP: state change Initial --> Starting
Dec 7 02:02:08 ppp 56916 [wan_link0] LCP: LayerStart
Dec 7 02:02:08 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:02:17 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:02:17 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:02:17 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:02:17 ppp 56916 [wan_link0] Link: reconnection attempt 1 in 1 seconds
Dec 7 02:02:18 ppp 56916 [wan_link0] Link: reconnection attempt 1
Dec 7 02:02:18 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:02:27 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:02:27 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:02:27 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:02:27 ppp 56916 [wan_link0] Link: reconnection attempt 2 in 1 seconds
Dec 7 02:02:28 ppp 56916 [wan_link0] Link: reconnection attempt 2
Dec 7 02:02:28 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:02:37 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:02:37 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:02:37 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:02:37 ppp 56916 [wan_link0] Link: reconnection attempt 3 in 2 seconds
Dec 7 02:02:39 ppp 56916 [wan_link0] Link: reconnection attempt 3
Dec 7 02:02:39 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:02:48 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:02:48 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:02:48 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:02:48 ppp 56916 [wan_link0] Link: reconnection attempt 4 in 3 seconds
Dec 7 02:02:51 ppp 56916 [wan_link0] Link: reconnection attempt 4
Dec 7 02:02:51 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:03:00 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:03:00 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:03:00 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:03:00 ppp 56916 [wan_link0] Link: reconnection attempt 5 in 3 seconds
Dec 7 02:03:03 ppp 56916 [wan_link0] Link: reconnection attempt 5
Dec 7 02:03:03 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:03:12 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:03:12 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:03:12 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:03:12 ppp 56916 [wan_link0] Link: reconnection attempt 6 in 3 seconds
Dec 7 02:03:15 ppp 56916 [wan_link0] Link: reconnection attempt 6
Dec 7 02:03:15 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:03:24 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:03:24 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:03:24 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:03:24 ppp 56916 [wan_link0] Link: reconnection attempt 7 in 2 seconds
Dec 7 02:03:26 ppp 56916 [wan_link0] Link: reconnection attempt 7
Dec 7 02:03:26 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:03:35 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:03:35 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:03:35 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:03:35 ppp 56916 [wan_link0] Link: reconnection attempt 8 in 2 seconds
Dec 7 02:03:37 ppp 56916 [wan_link0] Link: reconnection attempt 8
Dec 7 02:03:37 ppp 56916 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:03:46 ppp 56916 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:03:46 ppp 56916 [wan_link0] Link: DOWN event
Dec 7 02:03:46 ppp 56916 [wan_link0] LCP: Down event
Dec 7 02:03:46 ppp 56916 [wan_link0] Link: reconnection attempt 9 in 4 seconds
Dec 7 02:03:48 ppp 42670 Multi-link PPP daemon for FreeBSD
Dec 7 02:03:48 ppp 42670 process 42670 started, version 5.9
Dec 7 02:03:48 ppp 42670 waiting for process 56916 to die...
Dec 7 02:03:48 ppp 56916 caught fatal signal TERM
Dec 7 02:03:48 ppp 56916 [wan] IFACE: Close event
Dec 7 02:03:48 ppp 56916 [wan] IPCP: Close event
Dec 7 02:03:49 ppp 42670 waiting for process 56916 to die...
Dec 7 02:03:50 ppp 56916 [wan_link0] LCP: Close event
Dec 7 02:03:50 ppp 56916 [wan_link0] LCP: state change Starting --> Initial
Dec 7 02:03:50 ppp 56916 [wan_link0] LCP: LayerFinish
Dec 7 02:03:50 ppp 56916 [wan] Bundle: Shutdown
Dec 7 02:03:50 ppp 42670 waiting for process 56916 to die...
Dec 7 02:03:50 ppp 56916 [wan_link0] Link: Shutdown
Dec 7 02:03:50 ppp 56916 process 56916 terminated
Dec 7 02:03:51 ppp 42670 web: web is not running
Dec 7 02:03:51 ppp 42670 [wan] Bundle: Interface ng0 created
Dec 7 02:03:51 ppp 42670 [wan_link0] Link: OPEN event
Dec 7 02:03:51 ppp 42670 [wan_link0] LCP: Open event
Dec 7 02:03:51 ppp 42670 [wan_link0] LCP: state change Initial --> Starting
Dec 7 02:03:51 ppp 42670 [wan_link0] LCP: LayerStart
Dec 7 02:03:51 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:04:00 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:04:00 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:04:00 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:04:00 ppp 42670 [wan_link0] Link: reconnection attempt 1 in 3 seconds
Dec 7 02:04:03 ppp 42670 [wan_link0] Link: reconnection attempt 1
Dec 7 02:04:03 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:04:13 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:04:13 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:04:13 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:04:13 ppp 42670 [wan_link0] Link: reconnection attempt 2 in 2 seconds
Dec 7 02:04:15 ppp 42670 [wan_link0] Link: reconnection attempt 2
Dec 7 02:04:15 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:04:24 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:04:24 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:04:24 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:04:24 ppp 42670 [wan_link0] Link: reconnection attempt 3 in 4 seconds
Dec 7 02:04:28 ppp 42670 [wan_link0] Link: reconnection attempt 3
Dec 7 02:04:28 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:04:37 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:04:37 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:04:37 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:04:37 ppp 42670 [wan_link0] Link: reconnection attempt 4 in 4 seconds
Dec 7 02:04:41 ppp 42670 [wan_link0] Link: reconnection attempt 4
Dec 7 02:04:41 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:04:50 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:04:50 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:04:50 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:04:50 ppp 42670 [wan_link0] Link: reconnection attempt 5 in 1 seconds
Dec 7 02:04:51 ppp 42670 [wan_link0] Link: reconnection attempt 5
Dec 7 02:04:51 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:05:00 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:05:00 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:05:00 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:05:00 ppp 42670 [wan_link0] Link: reconnection attempt 6 in 3 seconds
Dec 7 02:05:03 ppp 42670 [wan_link0] Link: reconnection attempt 6
Dec 7 02:05:03 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:05:12 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:05:12 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:05:12 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:05:12 ppp 42670 [wan_link0] Link: reconnection attempt 7 in 1 seconds
Dec 7 02:05:13 ppp 42670 [wan_link0] Link: reconnection attempt 7
Dec 7 02:05:13 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:05:22 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:05:22 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:05:22 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:05:22 ppp 42670 [wan_link0] Link: reconnection attempt 8 in 3 seconds
Dec 7 02:05:25 ppp 42670 [wan_link0] Link: reconnection attempt 8
Dec 7 02:05:25 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:05:34 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:05:34 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:05:34 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:05:34 ppp 42670 [wan_link0] Link: reconnection attempt 9 in 2 seconds
Dec 7 02:05:36 ppp 42670 [wan_link0] Link: reconnection attempt 9
Dec 7 02:05:36 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:05:45 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:05:45 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:05:45 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:05:45 ppp 42670 [wan_link0] Link: reconnection attempt 10 in 1 seconds
Dec 7 02:05:46 ppp 42670 [wan_link0] Link: reconnection attempt 10
Dec 7 02:05:46 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:05:55 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:05:55 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:05:55 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:05:55 ppp 42670 [wan_link0] Link: reconnection attempt 11 in 4 seconds
Dec 7 02:05:59 ppp 42670 [wan_link0] Link: reconnection attempt 11
Dec 7 02:05:59 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:06:08 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:06:08 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:06:08 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:06:08 ppp 42670 [wan_link0] Link: reconnection attempt 12 in 3 seconds
Dec 7 02:06:11 ppp 42670 [wan_link0] Link: reconnection attempt 12
Dec 7 02:06:11 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:06:20 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:06:20 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:06:20 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:06:20 ppp 42670 [wan_link0] Link: reconnection attempt 13 in 2 seconds
Dec 7 02:06:23 ppp 42670 [wan_link0] Link: reconnection attempt 13
Dec 7 02:06:23 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:06:32 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:06:32 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:06:32 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:06:32 ppp 42670 [wan_link0] Link: reconnection attempt 14 in 1 seconds
Dec 7 02:06:33 ppp 42670 [wan_link0] Link: reconnection attempt 14
Dec 7 02:06:33 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:06:42 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:06:42 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:06:42 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:06:42 ppp 42670 [wan_link0] Link: reconnection attempt 15 in 1 seconds
Dec 7 02:06:43 ppp 42670 [wan_link0] Link: reconnection attempt 15
Dec 7 02:06:43 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:06:52 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:06:52 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:06:52 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:06:52 ppp 42670 [wan_link0] Link: reconnection attempt 16 in 4 seconds
Dec 7 02:06:56 ppp 42670 [wan_link0] Link: reconnection attempt 16
Dec 7 02:06:56 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:07:05 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:07:05 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:07:05 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:07:05 ppp 42670 [wan_link0] Link: reconnection attempt 17 in 4 seconds
Dec 7 02:07:09 ppp 42670 [wan_link0] Link: reconnection attempt 17
Dec 7 02:07:09 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:07:18 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:07:18 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:07:18 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:07:18 ppp 42670 [wan_link0] Link: reconnection attempt 18 in 4 seconds
Dec 7 02:07:22 ppp 42670 [wan_link0] Link: reconnection attempt 18
Dec 7 02:07:22 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:07:31 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:07:31 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:07:31 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:07:31 ppp 42670 [wan_link0] Link: reconnection attempt 19 in 4 seconds
Dec 7 02:07:35 ppp 42670 [wan_link0] Link: reconnection attempt 19
Dec 7 02:07:35 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:07:44 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:07:44 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:07:44 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:07:44 ppp 42670 [wan_link0] Link: reconnection attempt 20 in 2 seconds
Dec 7 02:07:46 ppp 42670 [wan_link0] Link: reconnection attempt 20
Dec 7 02:07:46 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:07:55 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:07:55 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:07:55 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:07:55 ppp 42670 [wan_link0] Link: reconnection attempt 21 in 2 seconds
Dec 7 02:07:57 ppp 42670 [wan_link0] Link: reconnection attempt 21
Dec 7 02:07:57 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:08:06 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:08:06 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:08:06 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:08:06 ppp 42670 [wan_link0] Link: reconnection attempt 22 in 3 seconds
Dec 7 02:08:09 ppp 42670 [wan_link0] Link: reconnection attempt 22
Dec 7 02:08:09 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:08:18 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:08:18 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:08:18 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:08:18 ppp 42670 [wan_link0] Link: reconnection attempt 23 in 1 seconds
Dec 7 02:08:19 ppp 42670 [wan_link0] Link: reconnection attempt 23
Dec 7 02:08:19 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:08:28 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:08:28 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:08:28 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:08:28 ppp 42670 [wan_link0] Link: reconnection attempt 24 in 1 seconds
Dec 7 02:08:29 ppp 42670 [wan_link0] Link: reconnection attempt 24
Dec 7 02:08:29 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:08:38 ppp 42670 [wan_link0] PPPoE connection timeout after 9 seconds
Dec 7 02:08:38 ppp 42670 [wan_link0] Link: DOWN event
Dec 7 02:08:38 ppp 42670 [wan_link0] LCP: Down event
Dec 7 02:08:38 ppp 42670 [wan_link0] Link: reconnection attempt 25 in 1 seconds
Dec 7 02:08:39 ppp 42670 [wan_link0] Link: reconnection attempt 25
Dec 7 02:08:39 ppp 42670 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:09:44 ppp 7586 Multi-link PPP daemon for FreeBSD
Dec 7 02:09:44 ppp 7586 process 7586 started, version 5.9
Dec 7 02:09:44 ppp 7586 web: web is not running
Dec 7 02:09:44 ppp 7586 [wan] Bundle: Interface ng0 created
Dec 7 02:09:44 ppp 7586 [wan_link0] Link: OPEN event
Dec 7 02:09:44 ppp 7586 [wan_link0] LCP: Open event
Dec 7 02:09:44 ppp 7586 [wan_link0] LCP: state change Initial --> Starting
Dec 7 02:09:44 ppp 7586 [wan_link0] LCP: LayerStart
Dec 7 02:09:44 ppp 7586 [wan_link0] PPPoE: Connecting to ''
Dec 7 02:09:47 ppp 7586 PPPoE: rec'd ACNAME "XXXXXXXXXXX"
Dec 7 02:09:47 ppp 7586 [wan_link0] PPPoE: connection successful
Dec 7 02:09:47 ppp 7586 [wan_link0] Link: UP event
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: Up event
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: state change Starting --> Req-Sent
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: SendConfigReq #1
Dec 7 02:09:47 ppp 7586 [wan_link0] PROTOCOMP
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1492
Dec 7 02:09:47 ppp 7586 [wan_link0] MAGICNUM 0x32d558bc
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: rec'd Configure Request #174 (Req-Sent)
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1460
Dec 7 02:09:47 ppp 7586 [wan_link0] AUTHPROTO CHAP MD5
Dec 7 02:09:47 ppp 7586 [wan_link0] MAGICNUM 0x3055284a
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: SendConfigAck #174
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1460
Dec 7 02:09:47 ppp 7586 [wan_link0] AUTHPROTO CHAP MD5
Dec 7 02:09:47 ppp 7586 [wan_link0] MAGICNUM 0x3055284a
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 7 02:09:47 ppp 7586 [wan_link0] PROTOCOMP
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: SendConfigReq #2
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1492
Dec 7 02:09:47 ppp 7586 [wan_link0] MAGICNUM 0x32d558bc
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1460
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: SendConfigReq #3
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1460
Dec 7 02:09:47 ppp 7586 [wan_link0] MAGICNUM 0x32d558bc
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 7 02:09:47 ppp 7586 [wan_link0] MRU 1460
Dec 7 02:09:47 ppp 7586 [wan_link0] MAGICNUM 0x32d558bc
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: state change Ack-Sent --> Opened
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Dec 7 02:09:47 ppp 7586 [wan_link0] LCP: LayerUp
Dec 7 02:09:47 ppp 7586 [wan_link0] CHAP: rec'd CHALLENGE #1 len: 39
Dec 7 02:09:47 ppp 7586 [wan_link0] Name: "XXXXXXXXXXXX"
Dec 7 02:09:47 ppp 7586 [wan_link0] CHAP: Using authname "XXXXXXXX"
Dec 7 02:09:47 ppp 7586 [wan_link0] CHAP: sending RESPONSE #1 len: 50
Dec 7 02:09:48 ppp 7586 [wan_link0] CHAP: rec'd SUCCESS #1 len: 26
Dec 7 02:09:48 ppp 7586 [wan_link0] MESG: Authentication Success
Dec 7 02:09:48 ppp 7586 [wan_link0] LCP: authorization successful
Dec 7 02:09:48 ppp 7586 [wan_link0] Link: Matched action 'bundle "wan" ""'
Dec 7 02:09:48 ppp 7586 [wan_link0] Link: Join bundle "wan"
Dec 7 02:09:48 ppp 7586 [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Dec 7 02:09:48 ppp 7586 [wan] IPCP: Open event
Dec 7 02:09:48 ppp 7586 [wan] IPCP: state change Initial --> Starting
Dec 7 02:09:48 ppp 7586 [wan] IPCP: LayerStart
Dec 7 02:09:48 ppp 7586 [wan] IPCP: Up event
Dec 7 02:09:48 ppp 7586 [wan] IPCP: state change Starting --> Req-Sent
Dec 7 02:09:48 ppp 7586 [wan] IPCP: SendConfigReq #1
Dec 7 02:09:48 ppp 7586 [wan] IPADDR 0.0.0.0
Dec 7 02:09:48 ppp 7586 [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 7 02:09:48 ppp 7586 [wan] IPCP: rec'd Configure Request #183 (Req-Sent)
Dec 7 02:09:48 ppp 7586 [wan] IPADDR IP.ADDRESS..1
Dec 7 02:09:48 ppp 7586 [wan] IP.ADDRESS..1 is OK
Dec 7 02:09:48 ppp 7586 [wan] IPCP: SendConfigAck #183
Dec 7 02:09:48 ppp 7586 [wan] IPADDR IP.ADDRESS..1
Dec 7 02:09:48 ppp 7586 [wan] IPCP: state change Req-Sent --> Ack-Sent
Dec 7 02:09:48 ppp 7586 [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 7 02:09:48 ppp 7586 [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 7 02:09:48 ppp 7586 [wan] IPCP: SendConfigReq #2
Dec 7 02:09:48 ppp 7586 [wan] IPADDR 0.0.0.0
Dec 7 02:09:48 ppp 7586 [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 7 02:09:48 ppp 7586 [wan] IPADDR IP.ADDRESS..163
Dec 7 02:09:48 ppp 7586 [wan] IP.ADDRESS..163 is OK
Dec 7 02:09:48 ppp 7586 [wan] IPCP: SendConfigReq #3
Dec 7 02:09:48 ppp 7586 [wan] IPADDR IP.ADDRESS..163
Dec 7 02:09:48 ppp 7586 [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 7 02:09:48 ppp 7586 [wan] IPADDR IP.ADDRESS..163
Dec 7 02:09:48 ppp 7586 [wan] IPCP: state change Ack-Sent --> Opened
Dec 7 02:09:48 ppp 7586 [wan] IPCP: LayerUp
Dec 7 02:09:48 ppp 7586 [wan] IP.ADDRESS..163 -> IP.ADDRESS..1
Dec 7 02:09:48 ppp 7586 [wan] IFACE: Up event
Dec 7 02:09:48 ppp 7586 [wan] IFACE: Rename interface ng0 to pppoe0