PPPoE Disconnects
-
Hello Everyone
I'm trying to get to the bottom of some PPPoE micro disconnects. The internet connection is a PPPoE GPON. The log is below. I've got probably 30-40 pfSense out there and I have two connections that are experiencing this issue. The hardware is one of those cheap machines from Alibaba (One is an Intel J1900, the other is an Intel N3100). The boxes themselves are fine. I verified these by testing them on our office connection which is also by the same ISP and it works just fine.
So to summarise, I have tried
- New pfSense hardware (I've been through 4 different physical boxes)
- Various version of pfSense (2.3.2, 2.3.3, 2.3.3_1)
- Even tried changing patch cables.
I told the ISP about this but they maintain nothing is wrong at their end… and they're not being too helpful with diagnosis because they say pfSense is an "unsupported" configuration (They would rather you use their terrible Huawei modem).
You can see the log below. I replaced the pfSense system at 10:37 AM. Then, at 14:08, 14:20, 14:27, 14:29 and 14:30 there are micro disconnects (PPPoE drops for a few minutes then comes back).
I would REALLY appreciate some assistance in diagnosing this!
Apr 5 10:37:32 ppp Multi-link PPP daemon for FreeBSD Apr 5 10:37:32 ppp process 7141 started, version 5.8 (root@pfSense_v2_3_3_amd64-pfSense_v2_3_3-job-16 03:21 11-Feb-2017) Apr 5 10:37:32 ppp web: web is not running Apr 5 10:37:32 ppp [opt2] Bundle: Interface ng0 created Apr 5 10:37:32 ppp [opt2_link0] Link: OPEN event Apr 5 10:37:32 ppp [opt2_link0] LCP: Open event Apr 5 10:37:32 ppp [opt2_link0] LCP: state change Initial --> Starting Apr 5 10:37:32 ppp [opt2_link0] LCP: LayerStart Apr 5 10:37:32 ppp [opt2_link0] PPPoE: Connecting to '' Apr 5 10:37:41 ppp [opt2_link0] PPPoE connection timeout after 9 seconds Apr 5 10:37:41 ppp [opt2_link0] Link: DOWN event Apr 5 10:37:41 ppp [opt2_link0] LCP: Down event Apr 5 10:37:41 ppp [opt2_link0] Link: reconnection attempt 1 in 3 seconds Apr 5 10:37:44 ppp [opt2_link0] Link: reconnection attempt 1 Apr 5 10:37:44 ppp [opt2_link0] PPPoE: Connecting to '' Apr 5 10:37:44 ppp PPPoE: rec'd ACNAME "Air-PER-01" Apr 5 10:37:44 ppp [opt2_link0] PPPoE: connection successful Apr 5 10:37:44 ppp [opt2_link0] Link: UP event Apr 5 10:37:44 ppp [opt2_link0] LCP: Up event Apr 5 10:37:44 ppp [opt2_link0] LCP: state change Starting --> Req-Sent Apr 5 10:37:44 ppp [opt2_link0] LCP: SendConfigReq #1 Apr 5 10:37:44 ppp [opt2_link0] PROTOCOMP Apr 5 10:37:44 ppp [opt2_link0] MRU 1492 Apr 5 10:37:44 ppp [opt2_link0] MAGICNUM 0x069f7cf3 Apr 5 10:37:44 ppp [opt2_link0] LCP: rec'd Configure Request #1 (Req-Sent) Apr 5 10:37:44 ppp [opt2_link0] MRU 1492 Apr 5 10:37:44 ppp [opt2_link0] AUTHPROTO PAP Apr 5 10:37:44 ppp [opt2_link0] MAGICNUM 0x454e5b97 Apr 5 10:37:44 ppp [opt2_link0] LCP: SendConfigAck #1 Apr 5 10:37:44 ppp [opt2_link0] MRU 1492 Apr 5 10:37:44 ppp [opt2_link0] AUTHPROTO PAP Apr 5 10:37:44 ppp [opt2_link0] MAGICNUM 0x454e5b97 Apr 5 10:37:44 ppp [opt2_link0] LCP: state change Req-Sent --> Ack-Sent Apr 5 10:37:44 ppp [opt2_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 10:37:44 ppp [opt2_link0] PROTOCOMP Apr 5 10:37:44 ppp [opt2_link0] MRU 1492 Apr 5 10:37:44 ppp [opt2_link0] MAGICNUM 0x069f7cf3 Apr 5 10:37:44 ppp [opt2_link0] LCP: state change Ack-Sent --> Opened Apr 5 10:37:44 ppp [opt2_link0] LCP: auth: peer wants PAP, I want nothing Apr 5 10:37:44 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 10:37:44 ppp [opt2_link0] PAP: sending REQUEST #1 len: 37 Apr 5 10:37:44 ppp [opt2_link0] LCP: LayerUp Apr 5 10:37:46 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 10:37:46 ppp [opt2_link0] PAP: sending REQUEST #2 len: 37 Apr 5 10:37:47 ppp [opt2_link0] PAP: rec'd ACK #2 len: 5 Apr 5 10:37:47 ppp [opt2_link0] LCP: authorization successful Apr 5 10:37:47 ppp [opt2_link0] Link: Matched action 'bundle "opt2" ""' Apr 5 10:37:47 ppp [opt2_link0] Link: Join bundle "opt2" Apr 5 10:37:47 ppp [opt2] Bundle: Status update: up 1 link, total bandwidth 64000 bps Apr 5 10:37:47 ppp [opt2] IPCP: Open event Apr 5 10:37:47 ppp [opt2] IPCP: state change Initial --> Starting Apr 5 10:37:47 ppp [opt2] IPCP: LayerStart Apr 5 10:37:47 ppp [opt2] IPV6CP: Open event Apr 5 10:37:47 ppp [opt2] IPV6CP: state change Initial --> Starting Apr 5 10:37:47 ppp [opt2] IPV6CP: LayerStart Apr 5 10:37:47 ppp [opt2] IPCP: Up event Apr 5 10:37:47 ppp [opt2] IPCP: state change Starting --> Req-Sent Apr 5 10:37:47 ppp [opt2] IPCP: SendConfigReq #1 Apr 5 10:37:47 ppp [opt2] IPADDR 0.0.0.0 Apr 5 10:37:47 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 10:37:47 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 10:37:47 ppp [opt2] SECDNS 0.0.0.0 Apr 5 10:37:47 ppp [opt2] IPV6CP: Up event Apr 5 10:37:47 ppp [opt2] IPV6CP: state change Starting --> Req-Sent Apr 5 10:37:47 ppp [opt2] IPV6CP: SendConfigReq #1 Apr 5 10:37:47 ppp [opt2] IPCP: rec'd Configure Request #1 (Req-Sent) Apr 5 10:37:47 ppp [opt2] IPADDR 210.48.12.66 Apr 5 10:37:47 ppp [opt2] 210.48.12.66 is OK Apr 5 10:37:47 ppp [opt2] IPCP: SendConfigAck #1 Apr 5 10:37:47 ppp [opt2] IPADDR 210.48.12.66 Apr 5 10:37:47 ppp [opt2] IPCP: state change Req-Sent --> Ack-Sent Apr 5 10:37:47 ppp [opt2] IPV6CP: rec'd Configure Request #1 (Req-Sent) Apr 5 10:37:47 ppp [opt2] IPV6CP: SendConfigAck #1 Apr 5 10:37:47 ppp [opt2] IPV6CP: state change Req-Sent --> Ack-Sent Apr 5 10:37:47 ppp [opt2] IPCP: rec'd Configure Reject #1 (Ack-Sent) Apr 5 10:37:47 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 10:37:47 ppp [opt2] IPCP: SendConfigReq #2 Apr 5 10:37:47 ppp [opt2] IPADDR 0.0.0.0 Apr 5 10:37:47 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 10:37:47 ppp [opt2] SECDNS 0.0.0.0 Apr 5 10:37:47 ppp [opt2] IPV6CP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 10:37:47 ppp [opt2] IPV6CP: state change Ack-Sent --> Opened Apr 5 10:37:47 ppp [opt2] IPV6CP: LayerUp Apr 5 10:37:47 ppp [opt2] 0230:18ff:fe0a:ebf4 -> ba38:61ff:fef5:df00 Apr 5 10:37:48 ppp [opt2] IFACE: Up event Apr 5 10:37:48 ppp [opt2] IFACE: Rename interface ng0 to pppoe1 Apr 5 10:37:48 ppp [opt2] IPCP: rec'd Configure Nak #2 (Ack-Sent) Apr 5 10:37:48 ppp [opt2] IPADDR 125.126.0.42 Apr 5 10:37:48 ppp [opt2] 125.126.0.42 is OK Apr 5 10:37:48 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 10:37:48 ppp [opt2] SECDNS 125.126.66.1 Apr 5 10:37:48 ppp [opt2] IPCP: SendConfigReq #3 Apr 5 10:37:48 ppp [opt2] IPADDR 125.126.0.42 Apr 5 10:37:48 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 10:37:48 ppp [opt2] SECDNS 125.126.66.1 Apr 5 10:37:48 ppp [opt2] IPCP: rec'd Configure Ack #3 (Ack-Sent) Apr 5 10:37:48 ppp [opt2] IPADDR 125.126.0.42 Apr 5 10:37:48 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 10:37:48 ppp [opt2] SECDNS 125.126.66.1 Apr 5 10:37:48 ppp [opt2] IPCP: state change Ack-Sent --> Opened Apr 5 10:37:48 ppp [opt2] IPCP: LayerUp Apr 5 10:37:48 ppp [opt2] 125.126.0.42 -> 210.48.12.66 Apr 5 14:08:06 ppp Multi-link PPP daemon for FreeBSD Apr 5 14:08:06 ppp process 53404 started, version 5.8 (root@pfSense_v2_3_3_amd64-pfSense_v2_3_3-job-16 03:21 11-Feb-2017) Apr 5 14:08:06 ppp waiting for process 7141 to die... Apr 5 14:08:06 ppp caught fatal signal TERM Apr 5 14:08:06 ppp [opt2] IFACE: Close event Apr 5 14:08:06 ppp [opt2] IPCP: Close event Apr 5 14:08:06 ppp [opt2] IPCP: state change Opened --> Closing Apr 5 14:08:06 ppp [opt2] IPCP: SendTerminateReq #4 Apr 5 14:08:06 ppp [opt2] IPCP: LayerDown Apr 5 14:08:06 ppp [opt2] IFACE: Delete route 0.0.0.0/0 210.48.12.66 failed: No such process Apr 5 14:08:06 ppp [opt2] IFACE: Removing IPv4 address from pppoe1 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Apr 5 14:08:06 ppp [opt2] IPV6CP: Close event Apr 5 14:08:06 ppp [opt2] IPV6CP: state change Opened --> Closing Apr 5 14:08:06 ppp [opt2] IPV6CP: SendTerminateReq #2 Apr 5 14:08:06 ppp [opt2] IPV6CP: LayerDown Apr 5 14:08:07 ppp [opt2] IFACE: Down event Apr 5 14:08:07 ppp [opt2] IFACE: Rename interface pppoe1 to pppoe1 Apr 5 14:08:07 ppp waiting for process 7141 to die... Apr 5 14:08:08 ppp waiting for process 7141 to die... Apr 5 14:08:09 ppp [opt2] Bundle: Shutdown Apr 5 14:08:09 ppp [opt2_link0] Link: Shutdown Apr 5 14:08:09 ppp process 7141 terminated Apr 5 14:08:09 ppp web: web is not running Apr 5 14:08:09 ppp [opt2] Bundle: Interface ng0 created Apr 5 14:08:09 ppp [opt2_link0] Link: OPEN event Apr 5 14:08:09 ppp [opt2_link0] LCP: Open event Apr 5 14:08:09 ppp [opt2_link0] LCP: state change Initial --> Starting Apr 5 14:08:09 ppp [opt2_link0] LCP: LayerStart Apr 5 14:08:09 ppp [opt2_link0] PPPoE: Connecting to '' Apr 5 14:08:09 ppp PPPoE: rec'd ACNAME "Air-PER-01" Apr 5 14:08:09 ppp [opt2_link0] PPPoE: connection successful Apr 5 14:08:09 ppp [opt2_link0] Link: UP event Apr 5 14:08:09 ppp [opt2_link0] LCP: Up event Apr 5 14:08:09 ppp [opt2_link0] LCP: state change Starting --> Req-Sent Apr 5 14:08:09 ppp [opt2_link0] LCP: SendConfigReq #1 Apr 5 14:08:09 ppp [opt2_link0] PROTOCOMP Apr 5 14:08:09 ppp [opt2_link0] MRU 1492 Apr 5 14:08:09 ppp [opt2_link0] MAGICNUM 0xd077174c Apr 5 14:08:09 ppp [opt2_link0] LCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:08:09 ppp [opt2_link0] MRU 1492 Apr 5 14:08:09 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:08:09 ppp [opt2_link0] MAGICNUM 0x460f0af7 Apr 5 14:08:09 ppp [opt2_link0] LCP: SendConfigAck #1 Apr 5 14:08:09 ppp [opt2_link0] MRU 1492 Apr 5 14:08:09 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:08:09 ppp [opt2_link0] MAGICNUM 0x460f0af7 Apr 5 14:08:09 ppp [opt2_link0] LCP: state change Req-Sent --> Ack-Sent Apr 5 14:08:09 ppp [opt2_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:08:09 ppp [opt2_link0] PROTOCOMP Apr 5 14:08:09 ppp [opt2_link0] MRU 1492 Apr 5 14:08:09 ppp [opt2_link0] MAGICNUM 0xd077174c Apr 5 14:08:09 ppp [opt2_link0] LCP: state change Ack-Sent --> Opened Apr 5 14:08:09 ppp [opt2_link0] LCP: auth: peer wants PAP, I want nothing Apr 5 14:08:09 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:08:09 ppp [opt2_link0] PAP: sending REQUEST #1 len: 37 Apr 5 14:08:09 ppp [opt2_link0] LCP: LayerUp Apr 5 14:08:11 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:08:11 ppp [opt2_link0] PAP: sending REQUEST #2 len: 37 Apr 5 14:08:12 ppp [opt2_link0] PAP: rec'd ACK #2 len: 5 Apr 5 14:08:12 ppp [opt2_link0] LCP: authorization successful Apr 5 14:08:12 ppp [opt2_link0] Link: Matched action 'bundle "opt2" ""' Apr 5 14:08:12 ppp [opt2_link0] Link: Join bundle "opt2" Apr 5 14:08:12 ppp [opt2] Bundle: Status update: up 1 link, total bandwidth 64000 bps Apr 5 14:08:12 ppp [opt2] IPCP: Open event Apr 5 14:08:12 ppp [opt2] IPCP: state change Initial --> Starting Apr 5 14:08:12 ppp [opt2] IPCP: LayerStart Apr 5 14:08:12 ppp [opt2] IPV6CP: Open event Apr 5 14:08:12 ppp [opt2] IPV6CP: state change Initial --> Starting Apr 5 14:08:12 ppp [opt2] IPV6CP: LayerStart Apr 5 14:08:12 ppp [opt2] IPCP: Up event Apr 5 14:08:12 ppp [opt2] IPCP: state change Starting --> Req-Sent Apr 5 14:08:12 ppp [opt2] IPCP: SendConfigReq #1 Apr 5 14:08:12 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:08:12 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:08:12 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:08:12 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:08:12 ppp [opt2] IPV6CP: Up event Apr 5 14:08:12 ppp [opt2] IPV6CP: state change Starting --> Req-Sent Apr 5 14:08:12 ppp [opt2] IPV6CP: SendConfigReq #1 Apr 5 14:08:12 ppp [opt2] IPCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:08:12 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:08:12 ppp [opt2] 210.48.12.66 is OK Apr 5 14:08:12 ppp [opt2] IPCP: SendConfigAck #1 Apr 5 14:08:12 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:08:12 ppp [opt2] IPCP: state change Req-Sent --> Ack-Sent Apr 5 14:08:12 ppp [opt2] IPV6CP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:08:12 ppp [opt2] IPV6CP: SendConfigAck #1 Apr 5 14:08:12 ppp [opt2] IPV6CP: state change Req-Sent --> Ack-Sent Apr 5 14:08:12 ppp [opt2] IPCP: rec'd Configure Reject #1 (Ack-Sent) Apr 5 14:08:12 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:08:12 ppp [opt2] IPCP: SendConfigReq #2 Apr 5 14:08:12 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:08:12 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:08:12 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:08:12 ppp [opt2] IPV6CP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:08:12 ppp [opt2] IPV6CP: state change Ack-Sent --> Opened Apr 5 14:08:12 ppp [opt2] IPV6CP: LayerUp Apr 5 14:08:12 ppp [opt2] 0230:18ff:fe0a:ebf4 -> ba38:61ff:fef5:df00 Apr 5 14:08:13 ppp [opt2] IFACE: Up event Apr 5 14:08:13 ppp [opt2] IFACE: Rename interface ng0 to pppoe1 Apr 5 14:08:13 ppp [opt2] IPCP: rec'd Configure Nak #2 (Ack-Sent) Apr 5 14:08:13 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:08:13 ppp [opt2] 125.126.0.42 is OK Apr 5 14:08:13 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:08:13 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:08:13 ppp [opt2] IPCP: SendConfigReq #3 Apr 5 14:08:13 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:08:13 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:08:13 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:08:13 ppp [opt2] IPCP: rec'd Configure Ack #3 (Ack-Sent) Apr 5 14:08:13 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:08:13 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:08:13 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:08:13 ppp [opt2] IPCP: state change Ack-Sent --> Opened Apr 5 14:08:13 ppp [opt2] IPCP: LayerUp Apr 5 14:08:13 ppp [opt2] 125.126.0.42 -> 210.48.12.66 Apr 5 14:20:24 ppp Multi-link PPP daemon for FreeBSD Apr 5 14:20:24 ppp process 91047 started, version 5.8 (root@pfSense_v2_3_3_amd64-pfSense_v2_3_3-job-16 03:21 11-Feb-2017) Apr 5 14:20:24 ppp waiting for process 53404 to die... Apr 5 14:20:24 ppp caught fatal signal TERM Apr 5 14:20:24 ppp [opt2] IFACE: Close event Apr 5 14:20:24 ppp [opt2] IPCP: Close event Apr 5 14:20:24 ppp [opt2] IPCP: state change Opened --> Closing Apr 5 14:20:24 ppp [opt2] IPCP: SendTerminateReq #4 Apr 5 14:20:24 ppp [opt2] IPCP: LayerDown Apr 5 14:20:24 ppp [opt2] IFACE: Delete route 0.0.0.0/0 210.48.12.66 failed: No such process Apr 5 14:20:24 ppp [opt2] IFACE: Removing IPv4 address from pppoe1 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Apr 5 14:20:24 ppp [opt2] IPV6CP: Close event Apr 5 14:20:24 ppp [opt2] IPV6CP: state change Opened --> Closing Apr 5 14:20:24 ppp [opt2] IPV6CP: SendTerminateReq #2 Apr 5 14:20:24 ppp [opt2] IPV6CP: LayerDown Apr 5 14:20:25 ppp [opt2] IFACE: Down event Apr 5 14:20:25 ppp [opt2] IFACE: Rename interface pppoe1 to pppoe1 Apr 5 14:20:25 ppp waiting for process 53404 to die... Apr 5 14:20:26 ppp waiting for process 53404 to die... Apr 5 14:20:27 ppp [opt2] Bundle: Shutdown Apr 5 14:20:27 ppp [opt2_link0] Link: Shutdown Apr 5 14:20:27 ppp process 53404 terminated Apr 5 14:20:27 ppp web: web is not running Apr 5 14:20:27 ppp [opt2] Bundle: Interface ng0 created Apr 5 14:20:27 ppp [opt2_link0] Link: OPEN event Apr 5 14:20:27 ppp [opt2_link0] LCP: Open event Apr 5 14:20:27 ppp [opt2_link0] LCP: state change Initial --> Starting Apr 5 14:20:27 ppp [opt2_link0] LCP: LayerStart Apr 5 14:20:27 ppp [opt2_link0] PPPoE: Connecting to '' Apr 5 14:20:29 ppp PPPoE: rec'd ACNAME "Air-PER-01" Apr 5 14:20:29 ppp [opt2_link0] PPPoE: connection successful Apr 5 14:20:29 ppp [opt2_link0] Link: UP event Apr 5 14:20:29 ppp [opt2_link0] LCP: Up event Apr 5 14:20:29 ppp [opt2_link0] LCP: state change Starting --> Req-Sent Apr 5 14:20:29 ppp [opt2_link0] LCP: SendConfigReq #1 Apr 5 14:20:29 ppp [opt2_link0] PROTOCOMP Apr 5 14:20:29 ppp [opt2_link0] MRU 1492 Apr 5 14:20:29 ppp [opt2_link0] MAGICNUM 0xce052cee Apr 5 14:20:29 ppp [opt2_link0] LCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:20:29 ppp [opt2_link0] MRU 1492 Apr 5 14:20:29 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:20:29 ppp [opt2_link0] MAGICNUM 0x461a5726 Apr 5 14:20:29 ppp [opt2_link0] LCP: SendConfigAck #1 Apr 5 14:20:29 ppp [opt2_link0] MRU 1492 Apr 5 14:20:29 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:20:29 ppp [opt2_link0] MAGICNUM 0x461a5726 Apr 5 14:20:29 ppp [opt2_link0] LCP: state change Req-Sent --> Ack-Sent Apr 5 14:20:29 ppp [opt2_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:20:29 ppp [opt2_link0] PROTOCOMP Apr 5 14:20:29 ppp [opt2_link0] MRU 1492 Apr 5 14:20:29 ppp [opt2_link0] MAGICNUM 0xce052cee Apr 5 14:20:29 ppp [opt2_link0] LCP: state change Ack-Sent --> Opened Apr 5 14:20:29 ppp [opt2_link0] LCP: auth: peer wants PAP, I want nothing Apr 5 14:20:29 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:20:29 ppp [opt2_link0] PAP: sending REQUEST #1 len: 37 Apr 5 14:20:29 ppp [opt2_link0] LCP: LayerUp Apr 5 14:20:31 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:20:31 ppp [opt2_link0] PAP: sending REQUEST #2 len: 37 Apr 5 14:20:32 ppp [opt2_link0] PAP: rec'd ACK #2 len: 5 Apr 5 14:20:32 ppp [opt2_link0] LCP: authorization successful Apr 5 14:20:32 ppp [opt2_link0] Link: Matched action 'bundle "opt2" ""' Apr 5 14:20:32 ppp [opt2_link0] Link: Join bundle "opt2" Apr 5 14:20:32 ppp [opt2] Bundle: Status update: up 1 link, total bandwidth 64000 bps Apr 5 14:20:32 ppp [opt2] IPCP: Open event Apr 5 14:20:32 ppp [opt2] IPCP: state change Initial --> Starting Apr 5 14:20:32 ppp [opt2] IPCP: LayerStart Apr 5 14:20:32 ppp [opt2] IPV6CP: Open event Apr 5 14:20:32 ppp [opt2] IPV6CP: state change Initial --> Starting Apr 5 14:20:32 ppp [opt2] IPV6CP: LayerStart Apr 5 14:20:32 ppp [opt2] IPCP: Up event Apr 5 14:20:32 ppp [opt2] IPCP: state change Starting --> Req-Sent Apr 5 14:20:32 ppp [opt2] IPCP: SendConfigReq #1 Apr 5 14:20:32 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:20:32 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:20:32 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:20:32 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:20:32 ppp [opt2] IPV6CP: Up event Apr 5 14:20:32 ppp [opt2] IPV6CP: state change Starting --> Req-Sent Apr 5 14:20:32 ppp [opt2] IPV6CP: SendConfigReq #1 Apr 5 14:20:32 ppp [opt2] IPCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:20:32 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:20:32 ppp [opt2] 210.48.12.66 is OK Apr 5 14:20:32 ppp [opt2] IPCP: SendConfigAck #1 Apr 5 14:20:32 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:20:32 ppp [opt2] IPCP: state change Req-Sent --> Ack-Sent Apr 5 14:20:32 ppp [opt2] IPV6CP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:20:32 ppp [opt2] IPV6CP: SendConfigAck #1 Apr 5 14:20:32 ppp [opt2] IPV6CP: state change Req-Sent --> Ack-Sent Apr 5 14:20:32 ppp [opt2] IPCP: rec'd Configure Reject #1 (Ack-Sent) Apr 5 14:20:32 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:20:32 ppp [opt2] IPCP: SendConfigReq #2 Apr 5 14:20:32 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:20:32 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:20:32 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:20:32 ppp [opt2] IPV6CP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:20:32 ppp [opt2] IPV6CP: state change Ack-Sent --> Opened Apr 5 14:20:32 ppp [opt2] IPV6CP: LayerUp Apr 5 14:20:32 ppp [opt2] 0230:18ff:fe0a:ebf4 -> ba38:61ff:fef5:df00 Apr 5 14:20:33 ppp [opt2] IFACE: Up event Apr 5 14:20:33 ppp [opt2] IFACE: Rename interface ng0 to pppoe1 Apr 5 14:20:33 ppp [opt2] IPCP: rec'd Configure Nak #2 (Ack-Sent) Apr 5 14:20:33 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:20:33 ppp [opt2] 125.126.0.42 is OK Apr 5 14:20:33 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:20:33 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:20:33 ppp [opt2] IPCP: SendConfigReq #3 Apr 5 14:20:33 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:20:33 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:20:33 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:20:33 ppp [opt2] IPCP: rec'd Configure Ack #3 (Ack-Sent) Apr 5 14:20:33 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:20:33 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:20:33 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:20:33 ppp [opt2] IPCP: state change Ack-Sent --> Opened Apr 5 14:20:33 ppp [opt2] IPCP: LayerUp Apr 5 14:20:33 ppp [opt2] 125.126.0.42 -> 210.48.12.66 Apr 5 14:27:13 ppp Multi-link PPP daemon for FreeBSD Apr 5 14:27:13 ppp process 54406 started, version 5.8 (root@pfSense_v2_3_3_amd64-pfSense_v2_3_3-job-16 03:21 11-Feb-2017) Apr 5 14:27:13 ppp waiting for process 91047 to die... Apr 5 14:27:13 ppp caught fatal signal TERM Apr 5 14:27:13 ppp [opt2] IFACE: Close event Apr 5 14:27:13 ppp [opt2] IPCP: Close event Apr 5 14:27:13 ppp [opt2] IPCP: state change Opened --> Closing Apr 5 14:27:13 ppp [opt2] IPCP: SendTerminateReq #4 Apr 5 14:27:13 ppp [opt2] IPCP: LayerDown Apr 5 14:27:13 ppp [opt2] IFACE: Delete route 0.0.0.0/0 210.48.12.66 failed: No such process Apr 5 14:27:13 ppp [opt2] IFACE: Removing IPv4 address from pppoe1 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Apr 5 14:27:13 ppp [opt2] IPV6CP: Close event Apr 5 14:27:13 ppp [opt2] IPV6CP: state change Opened --> Closing Apr 5 14:27:13 ppp [opt2] IPV6CP: SendTerminateReq #2 Apr 5 14:27:13 ppp [opt2] IPV6CP: LayerDown Apr 5 14:27:14 ppp [opt2] IFACE: Down event Apr 5 14:27:14 ppp [opt2] IFACE: Rename interface pppoe1 to pppoe1 Apr 5 14:27:14 ppp waiting for process 91047 to die... Apr 5 14:27:15 ppp waiting for process 91047 to die... Apr 5 14:27:16 ppp [opt2] Bundle: Shutdown Apr 5 14:27:16 ppp [opt2_link0] Link: Shutdown Apr 5 14:27:16 ppp process 91047 terminated Apr 5 14:27:16 ppp web: web is not running Apr 5 14:27:16 ppp [opt2] Bundle: Interface ng0 created Apr 5 14:27:16 ppp [opt2_link0] Link: OPEN event Apr 5 14:27:16 ppp [opt2_link0] LCP: Open event Apr 5 14:27:16 ppp [opt2_link0] LCP: state change Initial --> Starting Apr 5 14:27:16 ppp [opt2_link0] LCP: LayerStart Apr 5 14:27:16 ppp [opt2_link0] PPPoE: Connecting to '' Apr 5 14:27:18 ppp PPPoE: rec'd ACNAME "Air-PER-01" Apr 5 14:27:18 ppp [opt2_link0] PPPoE: connection successful Apr 5 14:27:18 ppp [opt2_link0] Link: UP event Apr 5 14:27:18 ppp [opt2_link0] LCP: Up event Apr 5 14:27:18 ppp [opt2_link0] LCP: state change Starting --> Req-Sent Apr 5 14:27:18 ppp [opt2_link0] LCP: SendConfigReq #1 Apr 5 14:27:18 ppp [opt2_link0] PROTOCOMP Apr 5 14:27:18 ppp [opt2_link0] MRU 1492 Apr 5 14:27:18 ppp [opt2_link0] MAGICNUM 0x9a56af6e Apr 5 14:27:18 ppp [opt2_link0] LCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:27:18 ppp [opt2_link0] MRU 1492 Apr 5 14:27:18 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:27:18 ppp [opt2_link0] MAGICNUM 0x46209609 Apr 5 14:27:18 ppp [opt2_link0] LCP: SendConfigAck #1 Apr 5 14:27:18 ppp [opt2_link0] MRU 1492 Apr 5 14:27:18 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:27:18 ppp [opt2_link0] MAGICNUM 0x46209609 Apr 5 14:27:18 ppp [opt2_link0] LCP: state change Req-Sent --> Ack-Sent Apr 5 14:27:18 ppp [opt2_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:27:18 ppp [opt2_link0] PROTOCOMP Apr 5 14:27:18 ppp [opt2_link0] MRU 1492 Apr 5 14:27:18 ppp [opt2_link0] MAGICNUM 0x9a56af6e Apr 5 14:27:18 ppp [opt2_link0] LCP: state change Ack-Sent --> Opened Apr 5 14:27:18 ppp [opt2_link0] LCP: auth: peer wants PAP, I want nothing Apr 5 14:27:18 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:27:18 ppp [opt2_link0] PAP: sending REQUEST #1 len: 37 Apr 5 14:27:18 ppp [opt2_link0] LCP: LayerUp Apr 5 14:27:20 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:27:20 ppp [opt2_link0] PAP: sending REQUEST #2 len: 37 Apr 5 14:27:21 ppp [opt2_link0] PAP: rec'd ACK #2 len: 5 Apr 5 14:27:21 ppp [opt2_link0] LCP: authorization successful Apr 5 14:27:21 ppp [opt2_link0] Link: Matched action 'bundle "opt2" ""' Apr 5 14:27:21 ppp [opt2_link0] Link: Join bundle "opt2" Apr 5 14:27:21 ppp [opt2] Bundle: Status update: up 1 link, total bandwidth 64000 bps Apr 5 14:27:21 ppp [opt2] IPCP: Open event Apr 5 14:27:21 ppp [opt2] IPCP: state change Initial --> Starting Apr 5 14:27:21 ppp [opt2] IPCP: LayerStart Apr 5 14:27:21 ppp [opt2] IPV6CP: Open event Apr 5 14:27:21 ppp [opt2] IPV6CP: state change Initial --> Starting Apr 5 14:27:21 ppp [opt2] IPV6CP: LayerStart Apr 5 14:27:21 ppp [opt2] IPCP: Up event Apr 5 14:27:21 ppp [opt2] IPCP: state change Starting --> Req-Sent Apr 5 14:27:21 ppp [opt2] IPCP: SendConfigReq #1 Apr 5 14:27:21 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:27:21 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:27:21 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:27:21 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:27:21 ppp [opt2] IPV6CP: Up event Apr 5 14:27:21 ppp [opt2] IPV6CP: state change Starting --> Req-Sent Apr 5 14:27:21 ppp [opt2] IPV6CP: SendConfigReq #1 Apr 5 14:27:21 ppp [opt2] IPCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:27:21 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:27:21 ppp [opt2] 210.48.12.66 is OK Apr 5 14:27:21 ppp [opt2] IPCP: SendConfigAck #1 Apr 5 14:27:21 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:27:21 ppp [opt2] IPCP: state change Req-Sent --> Ack-Sent Apr 5 14:27:21 ppp [opt2] IPV6CP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:27:21 ppp [opt2] IPV6CP: SendConfigAck #1 Apr 5 14:27:21 ppp [opt2] IPV6CP: state change Req-Sent --> Ack-Sent Apr 5 14:27:21 ppp [opt2] IPCP: rec'd Configure Reject #1 (Ack-Sent) Apr 5 14:27:21 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:27:21 ppp [opt2] IPCP: SendConfigReq #2 Apr 5 14:27:21 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:27:21 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:27:21 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:27:21 ppp [opt2] IPV6CP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:27:21 ppp [opt2] IPV6CP: state change Ack-Sent --> Opened Apr 5 14:27:21 ppp [opt2] IPV6CP: LayerUp Apr 5 14:27:21 ppp [opt2] 0230:18ff:fe0a:ebf4 -> ba38:61ff:fef5:df00 Apr 5 14:27:22 ppp [opt2] IFACE: Up event Apr 5 14:27:22 ppp [opt2] IFACE: Rename interface ng0 to pppoe1 Apr 5 14:27:22 ppp [opt2] IPCP: rec'd Configure Nak #2 (Ack-Sent) Apr 5 14:27:22 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:27:22 ppp [opt2] 125.126.0.42 is OK Apr 5 14:27:22 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:27:22 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:27:22 ppp [opt2] IPCP: SendConfigReq #3 Apr 5 14:27:22 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:27:22 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:27:22 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:27:22 ppp [opt2] IPCP: rec'd Configure Ack #3 (Ack-Sent) Apr 5 14:27:22 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:27:22 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:27:22 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:27:22 ppp [opt2] IPCP: state change Ack-Sent --> Opened Apr 5 14:27:22 ppp [opt2] IPCP: LayerUp Apr 5 14:27:22 ppp [opt2] 125.126.0.42 -> 210.48.12.66 Apr 5 14:29:57 ppp Multi-link PPP daemon for FreeBSD Apr 5 14:29:57 ppp process 4959 started, version 5.8 (root@pfSense_v2_3_3_amd64-pfSense_v2_3_3-job-16 03:21 11-Feb-2017) Apr 5 14:29:57 ppp waiting for process 54406 to die... Apr 5 14:29:57 ppp caught fatal signal TERM Apr 5 14:29:57 ppp [opt2] IFACE: Close event Apr 5 14:29:57 ppp [opt2] IPCP: Close event Apr 5 14:29:57 ppp [opt2] IPCP: state change Opened --> Closing Apr 5 14:29:57 ppp [opt2] IPCP: SendTerminateReq #4 Apr 5 14:29:57 ppp [opt2] IPCP: LayerDown Apr 5 14:29:57 ppp [opt2] IFACE: Delete route 0.0.0.0/0 210.48.12.66 failed: No such process Apr 5 14:29:57 ppp [opt2] IFACE: Removing IPv4 address from pppoe1 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Apr 5 14:29:57 ppp [opt2] IPV6CP: Close event Apr 5 14:29:57 ppp [opt2] IPV6CP: state change Opened --> Closing Apr 5 14:29:57 ppp [opt2] IPV6CP: SendTerminateReq #2 Apr 5 14:29:57 ppp [opt2] IPV6CP: LayerDown Apr 5 14:29:58 ppp [opt2] IFACE: Down event Apr 5 14:29:58 ppp [opt2] IFACE: Rename interface pppoe1 to pppoe1 Apr 5 14:29:58 ppp waiting for process 54406 to die... Apr 5 14:29:59 ppp waiting for process 54406 to die... Apr 5 14:30:00 ppp [opt2] Bundle: Shutdown Apr 5 14:30:00 ppp [opt2_link0] Link: Shutdown Apr 5 14:30:00 ppp process 54406 terminated Apr 5 14:30:00 ppp web: web is not running Apr 5 14:30:00 ppp [opt2] Bundle: Interface ng0 created Apr 5 14:30:00 ppp [opt2_link0] Link: OPEN event Apr 5 14:30:00 ppp [opt2_link0] LCP: Open event Apr 5 14:30:00 ppp [opt2_link0] LCP: state change Initial --> Starting Apr 5 14:30:00 ppp [opt2_link0] LCP: LayerStart Apr 5 14:30:00 ppp [opt2_link0] PPPoE: Connecting to '' Apr 5 14:30:00 ppp PPPoE: rec'd ACNAME "Air-PER-01" Apr 5 14:30:00 ppp [opt2_link0] PPPoE: connection successful Apr 5 14:30:00 ppp [opt2_link0] Link: UP event Apr 5 14:30:00 ppp [opt2_link0] LCP: Up event Apr 5 14:30:00 ppp [opt2_link0] LCP: state change Starting --> Req-Sent Apr 5 14:30:00 ppp [opt2_link0] LCP: SendConfigReq #1 Apr 5 14:30:00 ppp [opt2_link0] PROTOCOMP Apr 5 14:30:00 ppp [opt2_link0] MRU 1492 Apr 5 14:30:00 ppp [opt2_link0] MAGICNUM 0xce2655ef Apr 5 14:30:00 ppp [opt2_link0] LCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:30:00 ppp [opt2_link0] MRU 1492 Apr 5 14:30:00 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:30:00 ppp [opt2_link0] MAGICNUM 0x46230f4a Apr 5 14:30:00 ppp [opt2_link0] LCP: SendConfigAck #1 Apr 5 14:30:00 ppp [opt2_link0] MRU 1492 Apr 5 14:30:00 ppp [opt2_link0] AUTHPROTO PAP Apr 5 14:30:00 ppp [opt2_link0] MAGICNUM 0x46230f4a Apr 5 14:30:00 ppp [opt2_link0] LCP: state change Req-Sent --> Ack-Sent Apr 5 14:30:00 ppp [opt2_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:30:00 ppp [opt2_link0] PROTOCOMP Apr 5 14:30:00 ppp [opt2_link0] MRU 1492 Apr 5 14:30:00 ppp [opt2_link0] MAGICNUM 0xce2655ef Apr 5 14:30:00 ppp [opt2_link0] LCP: state change Ack-Sent --> Opened Apr 5 14:30:00 ppp [opt2_link0] LCP: auth: peer wants PAP, I want nothing Apr 5 14:30:00 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:30:00 ppp [opt2_link0] PAP: sending REQUEST #1 len: 37 Apr 5 14:30:00 ppp [opt2_link0] LCP: LayerUp Apr 5 14:30:02 ppp [opt2_link0] PAP: using authname "pppoeuser@theisp.net" Apr 5 14:30:02 ppp [opt2_link0] PAP: sending REQUEST #2 len: 37 Apr 5 14:30:03 ppp [opt2_link0] PAP: rec'd ACK #2 len: 5 Apr 5 14:30:03 ppp [opt2_link0] LCP: authorization successful Apr 5 14:30:03 ppp [opt2_link0] Link: Matched action 'bundle "opt2" ""' Apr 5 14:30:03 ppp [opt2_link0] Link: Join bundle "opt2" Apr 5 14:30:03 ppp [opt2] Bundle: Status update: up 1 link, total bandwidth 64000 bps Apr 5 14:30:03 ppp [opt2] IPCP: Open event Apr 5 14:30:03 ppp [opt2] IPCP: state change Initial --> Starting Apr 5 14:30:03 ppp [opt2] IPCP: LayerStart Apr 5 14:30:03 ppp [opt2] IPV6CP: Open event Apr 5 14:30:03 ppp [opt2] IPV6CP: state change Initial --> Starting Apr 5 14:30:03 ppp [opt2] IPV6CP: LayerStart Apr 5 14:30:03 ppp [opt2] IPCP: Up event Apr 5 14:30:03 ppp [opt2] IPCP: state change Starting --> Req-Sent Apr 5 14:30:03 ppp [opt2] IPCP: SendConfigReq #1 Apr 5 14:30:03 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:30:03 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:30:03 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:30:03 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:30:03 ppp [opt2] IPV6CP: Up event Apr 5 14:30:03 ppp [opt2] IPV6CP: state change Starting --> Req-Sent Apr 5 14:30:03 ppp [opt2] IPV6CP: SendConfigReq #1 Apr 5 14:30:03 ppp [opt2] IPCP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:30:03 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:30:03 ppp [opt2] 210.48.12.66 is OK Apr 5 14:30:03 ppp [opt2] IPCP: SendConfigAck #1 Apr 5 14:30:03 ppp [opt2] IPADDR 210.48.12.66 Apr 5 14:30:03 ppp [opt2] IPCP: state change Req-Sent --> Ack-Sent Apr 5 14:30:03 ppp [opt2] IPV6CP: rec'd Configure Request #1 (Req-Sent) Apr 5 14:30:03 ppp [opt2] IPV6CP: SendConfigAck #1 Apr 5 14:30:03 ppp [opt2] IPV6CP: state change Req-Sent --> Ack-Sent Apr 5 14:30:03 ppp [opt2] IPCP: rec'd Configure Reject #1 (Ack-Sent) Apr 5 14:30:03 ppp [opt2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Apr 5 14:30:03 ppp [opt2] IPCP: SendConfigReq #2 Apr 5 14:30:03 ppp [opt2] IPADDR 0.0.0.0 Apr 5 14:30:03 ppp [opt2] PRIDNS 0.0.0.0 Apr 5 14:30:03 ppp [opt2] SECDNS 0.0.0.0 Apr 5 14:30:03 ppp [opt2] IPV6CP: rec'd Configure Ack #1 (Ack-Sent) Apr 5 14:30:03 ppp [opt2] IPV6CP: state change Ack-Sent --> Opened Apr 5 14:30:03 ppp [opt2] IPV6CP: LayerUp Apr 5 14:30:03 ppp [opt2] 0230:18ff:fe0a:ebf4 -> ba38:61ff:fef5:df00 Apr 5 14:30:04 ppp [opt2] IFACE: Up event Apr 5 14:30:04 ppp [opt2] IFACE: Rename interface ng0 to pppoe1 Apr 5 14:30:04 ppp [opt2] IPCP: rec'd Configure Nak #2 (Ack-Sent) Apr 5 14:30:04 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:30:04 ppp [opt2] 125.126.0.42 is OK Apr 5 14:30:04 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:30:04 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:30:04 ppp [opt2] IPCP: SendConfigReq #3 Apr 5 14:30:04 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:30:04 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:30:04 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:30:04 ppp [opt2] IPCP: rec'd Configure Ack #3 (Ack-Sent) Apr 5 14:30:04 ppp [opt2] IPADDR 125.126.0.42 Apr 5 14:30:04 ppp [opt2] PRIDNS 125.126.65.1 Apr 5 14:30:04 ppp [opt2] SECDNS 125.126.66.1 Apr 5 14:30:04 ppp [opt2] IPCP: state change Ack-Sent --> Opened Apr 5 14:30:04 ppp [opt2] IPCP: LayerUp Apr 5 14:30:04 ppp [opt2] 125.126.0.42 -> 210.48.12.66