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
    

Log in to reply