[2.3.x] DPINGER WAN_PPPoE sendto error: 65 (no WAN access)
-
UPDATE: backup my config from pfSense 2.2.6, then start a fresh installation on the same hardware of 2.3.1. After that, i restore config and restart pfSense. After reboot, WAN PPPoE was down, so i tried to disconnect/connect WAN interface. After 5 attempts, WAN PPPoE interface goes up. :D
But there is a problem: if i reboot pfSense, WAN PPPoE interface doesn't start automatically, and i need to disconnect/reconnet WAN interface al least 4/5 times. Instead, if WAN interface has fixed or static IP (no PPPoe), everything works fine.
-
dpinger logging that is a symptom of the problem, which seems to be that your PPPoE isn't connecting. What do the PPP logs show when it's not working?
-
Hi CMB,
thanks for your reply. :)Here is PPP logs:
May 23 22:11:31 ppp [wan] IPV6CP: LayerFinish May 23 22:11:31 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:11:28 ppp [wan] IPV6CP: LayerDown May 23 22:11:28 ppp [wan] IPV6CP: SendTerminateAck #6 May 23 22:11:28 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:11:28 ppp [wan] IPV6CP: rec'd Terminate Request #212 (Opened) May 23 22:11:28 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:11:28 ppp [wan] IPV6CP: LayerUp May 23 22:11:28 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:11:28 ppp [wan] IPV6CP: SendConfigAck #211 May 23 22:11:28 ppp [wan] IPV6CP: rec'd Configure Request #211 (Ack-Rcvd) May 23 22:11:28 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:11:28 ppp [wan] IPV6CP: rec'd Configure Ack #5 (Req-Sent) May 23 22:11:28 ppp [wan] IPV6CP: SendConfigReq #5 May 23 22:11:26 ppp [wan] IPV6CP: SendTerminateAck #4 May 23 22:11:26 ppp [wan] IPV6CP: state change Ack-Sent --> Req-Sent May 23 22:11:26 ppp [wan] IPV6CP: rec'd Terminate Request #1 (Ack-Sent) May 23 22:11:26 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Sent May 23 22:11:26 ppp [wan] IPV6CP: SendConfigAck #0 May 23 22:11:26 ppp [wan] IPV6CP: rec'd Configure Request #0 (Req-Sent) May 23 22:11:26 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:11:26 ppp [wan] IFACE: Up event May 23 22:11:26 ppp [wan] 87.21.211.177 -> 192.168.100.1 May 23 22:11:26 ppp [wan] IPCP: LayerUp May 23 22:11:26 ppp [wan] IPCP: state change Ack-Sent --> Opened May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177 May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) May 23 22:11:26 ppp [wan] IPCP: state change Req-Sent --> Ack-Sent May 23 22:11:26 ppp [wan] IPADDR 192.168.100.1 May 23 22:11:26 ppp [wan] IPCP: SendConfigAck #181 May 23 22:11:26 ppp [wan] 192.168.100.1 is OK May 23 22:11:26 ppp [wan] IPADDR 192.168.100.1 May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Request #181 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: SendTerminateAck #3 May 23 22:11:26 ppp [wan] IPV6CP: rec'd Terminate Request #255 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:11:26 ppp [wan] IPV6CP: state change Ack-Rcvd --> Req-Sent May 23 22:11:26 ppp [wan] IPV6CP: rec'd Terminate Request #254 (Ack-Rcvd) May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177 May 23 22:11:26 ppp [wan] IPCP: SendConfigReq #3 May 23 22:11:26 ppp [wan] 87.21.211.177 is OK May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177 May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:11:26 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:11:26 ppp [wan] IPADDR 0.0.0.0 May 23 22:11:26 ppp [wan] IPCP: SendConfigReq #2 May 23 22:11:26 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:11:26 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:11:26 ppp [wan] IPV6CP: Up event May 23 22:11:26 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:11:26 ppp [wan] IPADDR 0.0.0.0 May 23 22:11:26 ppp [wan] IPCP: SendConfigReq #1 May 23 22:11:26 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:11:26 ppp [wan] IPCP: Up event May 23 22:11:26 ppp [wan] IPV6CP: LayerStart May 23 22:11:26 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:11:26 ppp [wan] IPV6CP: Open event May 23 22:11:26 ppp [wan] IPCP: LayerStart May 23 22:11:26 ppp [wan] IPCP: state change Initial --> Starting May 23 22:11:26 ppp [wan] IPCP: Open event May 23 22:11:26 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:11:26 ppp [wan_link0] Link: Join bundle "wan" May 23 22:11:26 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:11:26 ppp [wan_link0] LCP: authorization successful May 23 22:11:26 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:11:26 ppp [wan_link0] LCP: LayerUp May 23 22:11:26 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:11:26 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:11:26 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:11:26 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:11:26 ppp [wan_link0] MAGICNUM 0xcef11f0e May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] PROTOCOMP May 23 22:11:26 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:11:26 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:11:26 ppp [wan_link0] MAGICNUM 0x536a27fa May 23 22:11:26 ppp [wan_link0] AUTHPROTO PAP May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] LCP: SendConfigAck #56 May 23 22:11:26 ppp [wan_link0] MAGICNUM 0x536a27fa May 23 22:11:26 ppp [wan_link0] AUTHPROTO PAP May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] LCP: rec'd Configure Request #56 (Req-Sent) May 23 22:11:26 ppp [wan_link0] MAGICNUM 0xcef11f0e May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] PROTOCOMP May 23 22:11:26 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:11:26 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:11:26 ppp [wan_link0] LCP: Up event May 23 22:11:26 ppp [wan_link0] Link: UP event May 23 22:11:26 ppp [wan_link0] PPPoE: connection successful May 23 22:11:26 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:11:25 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:11:25 ppp [wan_link0] LCP: LayerStart May 23 22:11:25 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:11:25 ppp [wan_link0] LCP: Open event May 23 22:11:25 ppp [wan_link0] Link: OPEN event May 23 22:11:25 ppp [wan] Bundle: Interface ng0 created May 23 22:11:25 ppp web: web is not running May 23 22:11:25 ppp process 84326 terminated May 23 22:11:25 ppp [wan_link0] Link: Shutdown May 23 22:11:25 ppp [wan] Bundle: Shutdown May 23 22:11:24 ppp waiting for process 84326 to die... May 23 22:11:24 ppp process 12005 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:11:24 ppp Multi-link PPP daemon for FreeBSD May 23 22:11:23 ppp [wan_link0] LCP: Close event May 23 22:11:23 ppp [wan_link0] Link: CLOSE event May 23 22:11:23 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:11:23 ppp [wan_link0] LCP: LayerFinish May 23 22:11:23 ppp [wan_link0] LCP: Down event May 23 22:11:23 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:11:23 ppp [wan_link0] LCP: Close event May 23 22:11:23 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:11:23 ppp [wan_link0] Link: DOWN event May 23 22:11:23 ppp [wan_link0] PPPoE: connection closed May 23 22:11:23 ppp [wan_link0] LCP: LayerDown May 23 22:11:23 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:11:23 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:11:23 ppp [wan] IPV6CP: Down event May 23 22:11:23 ppp [wan] IPCP: state change Closed --> Initial May 23 22:11:23 ppp [wan] IPCP: Down event May 23 22:11:23 ppp [wan] IPV6CP: Close event May 23 22:11:23 ppp [wan] IPCP: Close event May 23 22:11:23 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:11:23 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:11:23 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:11:23 ppp [wan_link0] LCP: rec'd Terminate Request #24 (Opened) May 23 22:11:23 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:11:23 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:11:23 ppp [wan] IPCP: LayerFinish May 23 22:11:23 ppp [wan] IPCP: state change Closing --> Closed May 23 22:11:23 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:11:23 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:11:23 ppp [wan] IPV6CP: Close event May 23 22:11:23 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:11:23 ppp [wan] IFACE: Down event May 23 22:11:22 ppp [wan] IPCP: LayerDown May 23 22:11:22 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:11:22 ppp [wan] IPCP: state change Opened --> Closing May 23 22:11:22 ppp [wan] IPCP: Close event May 23 22:11:22 ppp [wan] IFACE: Close event May 23 22:11:22 ppp caught fatal signal TERM May 23 22:09:14 ppp [wan] IPV6CP: LayerFinish May 23 22:09:14 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:09:12 ppp [wan] IPV6CP: LayerDown May 23 22:09:12 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:09:12 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:09:12 ppp [wan] IPV6CP: rec'd Terminate Request #184 (Opened) May 23 22:09:12 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:09:12 ppp [wan] IPV6CP: LayerUp May 23 22:09:12 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:09:12 ppp [wan] IPV6CP: SendConfigAck #183 May 23 22:09:12 ppp [wan] IPV6CP: rec'd Configure Request #183 (Ack-Rcvd) May 23 22:09:12 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:09:12 ppp [wan] IFACE: Up event May 23 22:09:12 ppp [wan] 82.53.152.239 -> 192.168.100.1 May 23 22:09:12 ppp [wan] IPCP: LayerUp May 23 22:09:12 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:09:12 ppp [wan] IPADDR 192.168.100.1 May 23 22:09:12 ppp [wan] IPCP: SendConfigAck #247 May 23 22:09:12 ppp [wan] 192.168.100.1 is OK May 23 22:09:12 ppp [wan] IPADDR 192.168.100.1 May 23 22:09:12 ppp [wan] IPCP: rec'd Configure Request #247 (Ack-Rcvd) May 23 22:09:12 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:09:12 ppp [wan] IPADDR 82.53.152.239 May 23 22:09:12 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:09:12 ppp [wan] IPADDR 82.53.152.239 May 23 22:09:12 ppp [wan] IPCP: SendConfigReq #3 May 23 22:09:12 ppp [wan] 82.53.152.239 is OK May 23 22:09:12 ppp [wan] IPADDR 82.53.152.239 May 23 22:09:12 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:09:11 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:09:11 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:09:11 ppp [wan] IPADDR 0.0.0.0 May 23 22:09:11 ppp [wan] IPCP: SendConfigReq #2 May 23 22:09:11 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:09:11 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:09:11 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:09:11 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:09:11 ppp [wan] IPV6CP: Up event May 23 22:09:11 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:09:11 ppp [wan] IPADDR 0.0.0.0 May 23 22:09:11 ppp [wan] IPCP: SendConfigReq #1 May 23 22:09:11 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:09:11 ppp [wan] IPCP: Up event May 23 22:09:11 ppp [wan] IPV6CP: LayerStart May 23 22:09:11 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:09:11 ppp [wan] IPV6CP: Open event May 23 22:09:11 ppp [wan] IPCP: LayerStart May 23 22:09:11 ppp [wan] IPCP: state change Initial --> Starting May 23 22:09:11 ppp [wan] IPCP: Open event May 23 22:09:11 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:09:11 ppp [wan_link0] Link: Join bundle "wan" May 23 22:09:11 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:09:11 ppp [wan_link0] LCP: authorization successful May 23 22:09:11 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:09:11 ppp [wan_link0] LCP: LayerUp May 23 22:09:11 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:09:11 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:09:11 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:09:11 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x79b7508e May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] PROTOCOMP May 23 22:09:11 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:09:11 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x3406c6c8 May 23 22:09:11 ppp [wan_link0] AUTHPROTO PAP May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] LCP: SendConfigAck #23 May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x3406c6c8 May 23 22:09:11 ppp [wan_link0] AUTHPROTO PAP May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] LCP: rec'd Configure Request #23 (Req-Sent) May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x79b7508e May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] PROTOCOMP May 23 22:09:11 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:09:11 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:09:11 ppp [wan_link0] LCP: Up event May 23 22:09:11 ppp [wan_link0] Link: UP event May 23 22:09:11 ppp [wan_link0] PPPoE: connection successful May 23 22:09:11 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:09:11 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:09:11 ppp [wan_link0] LCP: LayerStart May 23 22:09:11 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:09:11 ppp [wan_link0] LCP: Open event May 23 22:09:11 ppp [wan_link0] Link: OPEN event May 23 22:09:11 ppp [wan] Bundle: Interface ng0 created May 23 22:09:11 ppp web: web is not running May 23 22:09:10 ppp process 91397 terminated May 23 22:09:10 ppp [wan_link0] Link: Shutdown May 23 22:09:10 ppp [wan] Bundle: Shutdown May 23 22:09:10 ppp waiting for process 91397 to die... May 23 22:09:10 ppp process 84326 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:09:10 ppp Multi-link PPP daemon for FreeBSD May 23 22:09:08 ppp [wan_link0] LCP: Close event May 23 22:09:08 ppp [wan_link0] Link: CLOSE event May 23 22:09:08 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:09:08 ppp [wan_link0] LCP: LayerFinish May 23 22:09:08 ppp [wan_link0] LCP: Down event May 23 22:09:08 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:09:08 ppp [wan_link0] LCP: Close event May 23 22:09:08 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:09:08 ppp [wan_link0] Link: DOWN event May 23 22:09:08 ppp [wan_link0] PPPoE: connection closed May 23 22:09:08 ppp [wan_link0] LCP: LayerDown May 23 22:09:08 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:09:08 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:09:08 ppp [wan] IPV6CP: Down event May 23 22:09:08 ppp [wan] IPCP: state change Closed --> Initial May 23 22:09:08 ppp [wan] IPCP: Down event May 23 22:09:08 ppp [wan] IPV6CP: Close event May 23 22:09:08 ppp [wan] IPCP: Close event May 23 22:09:08 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:09:08 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:09:08 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:09:08 ppp [wan_link0] LCP: rec'd Terminate Request #148 (Opened) May 23 22:09:08 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:09:08 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:09:08 ppp [wan] IPCP: LayerFinish May 23 22:09:08 ppp [wan] IPCP: state change Closing --> Closed May 23 22:09:08 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:09:08 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:09:08 ppp [wan] IPV6CP: Close event May 23 22:09:08 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:09:08 ppp [wan] IFACE: Down event May 23 22:09:08 ppp [wan] IPCP: LayerDown May 23 22:09:08 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:09:08 ppp [wan] IPCP: state change Opened --> Closing May 23 22:09:08 ppp [wan] IPCP: Close event May 23 22:09:08 ppp [wan] IFACE: Close event May 23 22:09:08 ppp caught fatal signal TERM May 23 22:07:06 ppp [wan] IPV6CP: LayerFinish May 23 22:07:06 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:07:04 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:07:04 ppp [wan] IFACE: Up event May 23 22:07:04 ppp [wan] 87.1.223.81 -> 192.168.100.1 May 23 22:07:04 ppp [wan] IPCP: LayerUp May 23 22:07:04 ppp [wan] IPCP: state change Ack-Sent --> Opened May 23 22:07:04 ppp [wan] IPADDR 87.1.223.81 May 23 22:07:04 ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) May 23 22:07:04 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:07:04 ppp [wan] IFACE: Down event May 23 22:07:03 ppp [wan] IPV6CP: LayerDown May 23 22:07:03 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:07:03 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:07:03 ppp [wan] IPV6CP: rec'd Terminate Request #193 (Opened) May 23 22:07:03 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:07:03 ppp [wan] IFACE: Up event May 23 22:07:03 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:07:03 ppp [wan] IPV6CP: LayerUp May 23 22:07:03 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:07:03 ppp [wan] IPV6CP: SendConfigAck #192 May 23 22:07:03 ppp [wan] IPV6CP: rec'd Configure Request #192 (Ack-Rcvd) May 23 22:07:03 ppp [wan] IPADDR 87.1.223.81 May 23 22:07:03 ppp [wan] IPCP: SendConfigReq #3 May 23 22:07:03 ppp [wan] 87.1.223.81 is OK May 23 22:07:03 ppp [wan] IPADDR 87.1.223.81 May 23 22:07:03 ppp [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent) May 23 22:07:03 ppp [wan] IPCP: state change Req-Sent --> Ack-Sent May 23 22:07:03 ppp [wan] IPADDR 192.168.100.1 May 23 22:07:03 ppp [wan] IPCP: SendConfigAck #172 May 23 22:07:03 ppp [wan] 192.168.100.1 is OK May 23 22:07:03 ppp [wan] IPADDR 192.168.100.1 May 23 22:07:03 ppp [wan] IPCP: rec'd Configure Request #172 (Req-Sent) May 23 22:07:03 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:07:03 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:07:03 ppp [wan] IPADDR 0.0.0.0 May 23 22:07:03 ppp [wan] IPCP: SendConfigReq #2 May 23 22:07:03 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:07:03 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:07:03 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:07:03 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:07:03 ppp [wan] IPV6CP: Up event May 23 22:07:03 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:07:03 ppp [wan] IPADDR 0.0.0.0 May 23 22:07:03 ppp [wan] IPCP: SendConfigReq #1 May 23 22:07:03 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:07:03 ppp [wan] IPCP: Up event May 23 22:07:03 ppp [wan] IPV6CP: LayerStart May 23 22:07:03 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:07:03 ppp [wan] IPV6CP: Open event May 23 22:07:03 ppp [wan] IPCP: LayerStart May 23 22:07:03 ppp [wan] IPCP: state change Initial --> Starting May 23 22:07:03 ppp [wan] IPCP: Open event May 23 22:07:03 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:07:03 ppp [wan_link0] Link: Join bundle "wan" May 23 22:07:03 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:07:03 ppp [wan_link0] LCP: authorization successful May 23 22:07:03 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:07:03 ppp [wan_link0] LCP: LayerUp May 23 22:07:03 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:07:03 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:07:03 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:07:03 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x5cce6d32 May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] PROTOCOMP May 23 22:07:03 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:07:03 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x30b57178 May 23 22:07:03 ppp [wan_link0] AUTHPROTO PAP May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] LCP: SendConfigAck #147 May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x30b57178 May 23 22:07:03 ppp [wan_link0] AUTHPROTO PAP May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] LCP: rec'd Configure Request #147 (Req-Sent) May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x5cce6d32 May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] PROTOCOMP May 23 22:07:03 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:07:03 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:07:03 ppp [wan_link0] LCP: Up event May 23 22:07:03 ppp [wan_link0] Link: UP event May 23 22:07:03 ppp [wan_link0] PPPoE: connection successful May 23 22:07:03 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:07:03 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:07:03 ppp [wan_link0] LCP: LayerStart May 23 22:07:03 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:07:03 ppp [wan_link0] LCP: Open event May 23 22:07:03 ppp [wan_link0] Link: OPEN event May 23 22:07:03 ppp [wan] Bundle: Interface ng0 created May 23 22:07:03 ppp web: web is not running May 23 22:07:03 ppp process 91397 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:07:03 ppp Multi-link PPP daemon for FreeBSD May 23 22:06:49 ppp process 8514 terminated May 23 22:06:49 ppp [wan_link0] Link: Shutdown May 23 22:06:49 ppp [wan] Bundle: Shutdown May 23 22:06:47 ppp [wan_link0] LCP: Close event May 23 22:06:47 ppp [wan_link0] Link: CLOSE event May 23 22:06:47 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:06:47 ppp [wan_link0] LCP: LayerFinish May 23 22:06:47 ppp [wan_link0] LCP: Down event May 23 22:06:47 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:06:47 ppp [wan_link0] LCP: Close event May 23 22:06:47 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:06:47 ppp [wan_link0] Link: DOWN event May 23 22:06:47 ppp [wan_link0] PPPoE: connection closed May 23 22:06:47 ppp [wan_link0] LCP: LayerDown May 23 22:06:47 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:06:47 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:06:47 ppp [wan] IPV6CP: Down event May 23 22:06:47 ppp [wan] IPCP: state change Closed --> Initial May 23 22:06:47 ppp [wan] IPCP: Down event May 23 22:06:47 ppp [wan] IPV6CP: Close event May 23 22:06:47 ppp [wan] IPCP: Close event May 23 22:06:47 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:06:47 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:06:47 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:06:47 ppp [wan_link0] LCP: rec'd Terminate Request #204 (Opened) May 23 22:06:47 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:06:47 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:06:47 ppp [wan] IPCP: LayerFinish May 23 22:06:47 ppp [wan] IPCP: state change Closing --> Closed May 23 22:06:47 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:06:47 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:06:47 ppp [wan] IPV6CP: Close event May 23 22:06:47 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:06:47 ppp [wan] IFACE: Down event May 23 22:06:46 ppp [wan] IPCP: LayerDown May 23 22:06:46 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:06:46 ppp [wan] IPCP: state change Opened --> Closing May 23 22:06:46 ppp [wan] IPCP: Close event May 23 22:06:46 ppp [wan] IFACE: Close event May 23 22:06:46 ppp caught fatal signal TERM May 23 22:05:05 ppp [wan] IPV6CP: LayerFinish May 23 22:05:05 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:05:03 ppp [wan] IPV6CP: LayerDown May 23 22:05:03 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:05:03 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:05:03 ppp [wan] IPV6CP: rec'd Terminate Request #82 (Opened) May 23 22:05:02 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:05:02 ppp [wan] IPV6CP: LayerUp May 23 22:05:02 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:05:02 ppp [wan] IPV6CP: SendConfigAck #81 May 23 22:05:02 ppp [wan] IPV6CP: rec'd Configure Request #81 (Ack-Rcvd) May 23 22:05:02 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:05:02 ppp [wan] IFACE: Up event May 23 22:05:02 ppp [wan] 82.51.149.195 -> 192.168.100.1 May 23 22:05:02 ppp [wan] IPCP: LayerUp May 23 22:05:02 ppp [wan] IPCP: state change Ack-Sent --> Opened May 23 22:05:02 ppp [wan] IPADDR 82.51.149.195 May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) May 23 22:05:02 ppp [wan] IPCP: state change Req-Sent --> Ack-Sent May 23 22:05:02 ppp [wan] IPADDR 192.168.100.1 May 23 22:05:02 ppp [wan] IPCP: SendConfigAck #15 May 23 22:05:02 ppp [wan] 192.168.100.1 is OK May 23 22:05:02 ppp [wan] IPADDR 192.168.100.1 May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Request #15 (Req-Sent) May 23 22:05:02 ppp [wan] IPADDR 82.51.149.195 May 23 22:05:02 ppp [wan] IPCP: SendConfigReq #3 May 23 22:05:02 ppp [wan] 82.51.149.195 is OK May 23 22:05:02 ppp [wan] IPADDR 82.51.149.195 May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:05:02 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:05:02 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:05:02 ppp [wan] IPADDR 0.0.0.0 May 23 22:05:02 ppp [wan] IPCP: SendConfigReq #2 May 23 22:05:02 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:05:02 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:05:02 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:05:02 ppp [wan] IPV6CP: Up event May 23 22:05:02 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:05:02 ppp [wan] IPADDR 0.0.0.0 May 23 22:05:02 ppp [wan] IPCP: SendConfigReq #1 May 23 22:05:02 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:05:02 ppp [wan] IPCP: Up event May 23 22:05:02 ppp [wan] IPV6CP: LayerStart May 23 22:05:02 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:05:02 ppp [wan] IPV6CP: Open event May 23 22:05:02 ppp [wan] IPCP: LayerStart May 23 22:05:02 ppp [wan] IPCP: state change Initial --> Starting May 23 22:05:02 ppp [wan] IPCP: Open event May 23 22:05:02 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:05:02 ppp [wan_link0] Link: Join bundle "wan" May 23 22:05:02 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:05:02 ppp [wan_link0] LCP: authorization successful May 23 22:05:02 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:05:02 ppp [wan_link0] LCP: LayerUp May 23 22:05:02 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:05:02 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:05:02 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:05:02 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x57f267c0 May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] PROTOCOMP May 23 22:05:02 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:05:02 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x2e5d1f70 May 23 22:05:02 ppp [wan_link0] AUTHPROTO PAP May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] LCP: SendConfigAck #203 May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x2e5d1f70 May 23 22:05:02 ppp [wan_link0] AUTHPROTO PAP May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] LCP: rec'd Configure Request #203 (Req-Sent) May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x57f267c0 May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] PROTOCOMP May 23 22:05:02 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:05:02 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:05:02 ppp [wan_link0] LCP: Up event May 23 22:05:02 ppp [wan_link0] Link: UP event May 23 22:05:02 ppp [wan_link0] PPPoE: connection successful May 23 22:05:02 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:05:02 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:05:02 ppp [wan_link0] LCP: LayerStart May 23 22:05:02 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:05:02 ppp [wan_link0] LCP: Open event May 23 22:05:02 ppp [wan_link0] Link: OPEN event May 23 22:05:02 ppp [wan] Bundle: Interface ng0 created May 23 22:05:02 ppp web: web is not running May 23 22:05:02 ppp process 8514 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:05:02 ppp Multi-link PPP daemon for FreeBSD May 23 22:04:54 ppp process 67736 terminated May 23 22:04:54 ppp [wan_link0] Link: Shutdown May 23 22:04:54 ppp [wan] Bundle: Shutdown May 23 22:04:52 ppp [wan_link0] LCP: Close event May 23 22:04:52 ppp [wan_link0] Link: CLOSE event May 23 22:04:52 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:04:52 ppp [wan_link0] LCP: LayerFinish May 23 22:04:52 ppp [wan_link0] LCP: Down event May 23 22:04:52 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:04:52 ppp [wan_link0] LCP: Close event May 23 22:04:52 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:04:52 ppp [wan_link0] Link: DOWN event May 23 22:04:52 ppp [wan_link0] PPPoE: connection closed May 23 22:04:52 ppp [wan_link0] LCP: LayerDown May 23 22:04:52 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:04:52 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:04:52 ppp [wan] IPV6CP: Down event May 23 22:04:52 ppp [wan] IPCP: state change Closed --> Initial May 23 22:04:52 ppp [wan] IPCP: Down event May 23 22:04:52 ppp [wan] IPV6CP: Close event May 23 22:04:52 ppp [wan] IPCP: Close event May 23 22:04:52 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:04:52 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:04:52 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:04:52 ppp [wan_link0] LCP: rec'd Terminate Request #148 (Opened) May 23 22:04:52 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:04:52 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:04:52 ppp [wan] IPCP: LayerFinish May 23 22:04:52 ppp [wan] IPCP: state change Closing --> Closed May 23 22:04:52 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:04:52 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:04:52 ppp [wan] IPV6CP: Close event May 23 22:04:52 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:04:52 ppp [wan] IFACE: Down event May 23 22:04:52 ppp [wan] IPCP: LayerDown May 23 22:04:52 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:04:52 ppp [wan] IPCP: state change Opened --> Closing May 23 22:04:52 ppp [wan] IPCP: Close event May 23 22:04:52 ppp [wan] IFACE: Close event May 23 22:04:52 ppp caught fatal signal TERM May 23 22:04:18 ppp [wan] IPV6CP: LayerFinish May 23 22:04:18 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:04:15 ppp [wan] IPV6CP: LayerDown May 23 22:04:15 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:04:15 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:04:15 ppp [wan] IPV6CP: rec'd Terminate Request #174 (Opened) May 23 22:04:15 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:04:15 ppp [wan] IPV6CP: LayerUp May 23 22:04:15 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:04:15 ppp [wan] IPV6CP: SendConfigAck #173 May 23 22:04:15 ppp [wan] IPV6CP: rec'd Configure Request #173 (Ack-Rcvd) May 23 22:04:15 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:04:15 ppp [wan] IFACE: Up event May 23 22:04:15 ppp [wan] 82.51.146.47 -> 192.168.100.1 May 23 22:04:15 ppp [wan] IPCP: LayerUp May 23 22:04:15 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:04:15 ppp [wan] IPADDR 192.168.100.1 May 23 22:04:15 ppp [wan] IPCP: SendConfigAck #100 May 23 22:04:15 ppp [wan] 192.168.100.1 is OK May 23 22:04:15 ppp [wan] IPADDR 192.168.100.1 May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Request #100 (Ack-Rcvd) May 23 22:04:15 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:04:15 ppp [wan] IPADDR 82.51.146.47 May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:04:15 ppp [wan] IPADDR 82.51.146.47 May 23 22:04:15 ppp [wan] IPCP: SendConfigReq #3 May 23 22:04:15 ppp [wan] 82.51.146.47 is OK May 23 22:04:15 ppp [wan] IPADDR 82.51.146.47 May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:04:15 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:04:15 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:04:15 ppp [wan] IPADDR 0.0.0.0 May 23 22:04:15 ppp [wan] IPCP: SendConfigReq #2 May 23 22:04:15 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:04:15 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:04:15 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:04:15 ppp [wan] IPV6CP: Up event May 23 22:04:15 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:04:15 ppp [wan] IPADDR 0.0.0.0 May 23 22:04:15 ppp [wan] IPCP: SendConfigReq #1 May 23 22:04:15 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:04:15 ppp [wan] IPCP: Up event May 23 22:04:15 ppp [wan] IPV6CP: LayerStart May 23 22:04:15 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:04:15 ppp [wan] IPV6CP: Open event May 23 22:04:15 ppp [wan] IPCP: LayerStart May 23 22:04:15 ppp [wan] IPCP: state change Initial --> Starting May 23 22:04:15 ppp [wan] IPCP: Open event May 23 22:04:15 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:04:15 ppp [wan_link0] Link: Join bundle "wan" May 23 22:04:15 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:04:15 ppp [wan_link0] LCP: authorization successful May 23 22:04:15 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:04:15 ppp [wan_link0] LCP: LayerUp May 23 22:04:15 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:04:15 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:04:15 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:04:15 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7a955848 May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] PROTOCOMP May 23 22:04:15 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:04:15 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7340e4e0 May 23 22:04:15 ppp [wan_link0] AUTHPROTO PAP May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] LCP: SendConfigAck #147 May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7340e4e0 May 23 22:04:15 ppp [wan_link0] AUTHPROTO PAP May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] LCP: rec'd Configure Request #147 (Req-Sent) May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7a955848 May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] PROTOCOMP May 23 22:04:15 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:04:15 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:04:15 ppp [wan_link0] LCP: Up event May 23 22:04:15 ppp [wan_link0] Link: UP event May 23 22:04:15 ppp [wan_link0] PPPoE: connection successful May 23 22:04:15 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:04:15 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:04:15 ppp [wan_link0] LCP: LayerStart May 23 22:04:15 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:04:15 ppp [wan_link0] LCP: Open event May 23 22:04:15 ppp [wan_link0] Link: OPEN event May 23 22:04:15 ppp [wan] Bundle: Interface ng0 created May 23 22:04:15 ppp web: web is not running May 23 22:04:15 ppp process 67736 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:04:15 ppp Multi-link PPP daemon for FreeBSD May 23 22:04:13 ppp process 19089 terminated May 23 22:04:13 ppp [wan_link0] Link: Shutdown May 23 22:04:13 ppp [wan] Bundle: Shutdown May 23 22:04:11 ppp [wan_link0] LCP: Close event May 23 22:04:11 ppp [wan_link0] Link: CLOSE event May 23 22:04:11 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:04:11 ppp [wan_link0] LCP: LayerFinish May 23 22:04:11 ppp [wan_link0] LCP: Down event May 23 22:04:11 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:04:11 ppp [wan_link0] LCP: Close event May 23 22:04:11 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:04:11 ppp [wan_link0] Link: DOWN event May 23 22:04:11 ppp [wan_link0] PPPoE: connection closed May 23 22:04:11 ppp [wan_link0] LCP: LayerDown May 23 22:04:11 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:04:11 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:04:11 ppp [wan] IPV6CP: Down event May 23 22:04:11 ppp [wan] IPCP: state change Closed --> Initial May 23 22:04:11 ppp [wan] IPCP: Down event May 23 22:04:11 ppp [wan] IPV6CP: Close event May 23 22:04:11 ppp [wan] IPCP: Close event May 23 22:04:11 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:04:11 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:04:11 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:04:11 ppp [wan_link0] LCP: rec'd Terminate Request #165 (Opened) May 23 22:04:11 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:04:11 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:04:11 ppp [wan] IPCP: LayerFinish May 23 22:04:11 ppp [wan] IPCP: state change Closing --> Closed May 23 22:04:11 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:04:11 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:04:11 ppp [wan] IPV6CP: Close event May 23 22:04:11 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:04:11 ppp [wan] IFACE: Down event May 23 22:04:11 ppp [wan] IPCP: LayerDown May 23 22:04:11 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:04:11 ppp [wan] IPCP: state change Opened --> Closing May 23 22:04:11 ppp [wan] IPCP: Close event May 23 22:04:11 ppp [wan] IFACE: Close event May 23 22:04:11 ppp caught fatal signal TERM May 23 22:02:10 ppp [wan] IPV6CP: LayerFinish May 23 22:02:10 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:02:08 ppp [wan] IPV6CP: LayerDown May 23 22:02:08 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:02:08 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:02:08 ppp [wan] IPV6CP: rec'd Terminate Request #223 (Opened) May 23 22:02:08 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:02:08 ppp [wan] IPV6CP: LayerUp May 23 22:02:08 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:02:08 ppp [wan] IPV6CP: SendConfigAck #222 May 23 22:02:08 ppp [wan] IPV6CP: rec'd Configure Request #222 (Ack-Rcvd) May 23 22:02:08 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:02:08 ppp [wan] IFACE: Up event May 23 22:02:08 ppp [wan] 87.6.212.160 -> 192.168.100.1 May 23 22:02:08 ppp [wan] IPCP: LayerUp May 23 22:02:08 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:02:08 ppp [wan] IPADDR 192.168.100.1 May 23 22:02:08 ppp [wan] IPCP: SendConfigAck #157 May 23 22:02:08 ppp [wan] 192.168.100.1 is OK May 23 22:02:08 ppp [wan] IPADDR 192.168.100.1 May 23 22:02:08 ppp [wan] IPCP: rec'd Configure Request #157 (Ack-Rcvd) May 23 22:02:07 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:02:07 ppp [wan] IPADDR 87.6.212.160 May 23 22:02:07 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:02:07 ppp [wan] IPADDR 87.6.212.160 May 23 22:02:07 ppp [wan] IPCP: SendConfigReq #3 May 23 22:02:07 ppp [wan] 87.6.212.160 is OK May 23 22:02:07 ppp [wan] IPADDR 87.6.212.160 May 23 22:02:07 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:02:07 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:02:07 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:02:07 ppp [wan] IPADDR 0.0.0.0 May 23 22:02:07 ppp [wan] IPCP: SendConfigReq #2 May 23 22:02:07 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:02:07 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:02:07 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:02:07 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:02:07 ppp [wan] IPV6CP: Up event May 23 22:02:07 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:02:07 ppp [wan] IPADDR 0.0.0.0 May 23 22:02:07 ppp [wan] IPCP: SendConfigReq #1 May 23 22:02:07 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:02:07 ppp [wan] IPCP: Up event May 23 22:02:07 ppp [wan] IPV6CP: LayerStart May 23 22:02:07 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:02:07 ppp [wan] IPV6CP: Open event May 23 22:02:07 ppp [wan] IPCP: LayerStart May 23 22:02:07 ppp [wan] IPCP: state change Initial --> Starting May 23 22:02:07 ppp [wan] IPCP: Open event May 23 22:02:07 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:02:07 ppp [wan_link0] Link: Join bundle "wan" May 23 22:02:07 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:02:07 ppp [wan_link0] LCP: authorization successful May 23 22:02:07 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:02:07 ppp [wan_link0] LCP: LayerUp May 23 22:02:07 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:02:07 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:02:07 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:02:07 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x548d3a6b May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] PROTOCOMP May 23 22:02:07 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:02:07 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x19dc65f6 May 23 22:02:07 ppp [wan_link0] AUTHPROTO PAP May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] LCP: SendConfigAck #164 May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x19dc65f6 May 23 22:02:07 ppp [wan_link0] AUTHPROTO PAP May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] LCP: rec'd Configure Request #164 (Req-Sent) May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x548d3a6b May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] PROTOCOMP May 23 22:02:07 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:02:07 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:02:07 ppp [wan_link0] LCP: Up event May 23 22:02:07 ppp [wan_link0] Link: UP event May 23 22:02:07 ppp [wan_link0] PPPoE: connection successful May 23 22:02:07 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:02:06 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:02:06 ppp [wan_link0] LCP: LayerStart May 23 22:02:06 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:02:06 ppp [wan_link0] LCP: Open event May 23 22:02:06 ppp [wan_link0] Link: OPEN event May 23 22:02:06 ppp [wan] Bundle: Interface ng0 created May 23 22:02:06 ppp web: web is not running May 23 22:02:06 ppp process 19089 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:02:06 ppp Multi-link PPP daemon for FreeBSD May 23 22:02:02 ppp process 12700 terminated May 23 22:02:02 ppp [wan_link0] Link: Shutdown May 23 22:02:02 ppp [wan] Bundle: Shutdown May 23 22:02:00 ppp [wan_link0] LCP: Close event May 23 22:02:00 ppp [wan_link0] Link: CLOSE event May 23 22:02:00 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:02:00 ppp [wan_link0] LCP: LayerFinish May 23 22:02:00 ppp [wan_link0] LCP: Down event May 23 22:02:00 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:02:00 ppp [wan_link0] LCP: Close event May 23 22:02:00 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:02:00 ppp [wan_link0] Link: DOWN event May 23 22:02:00 ppp [wan_link0] PPPoE: connection closed May 23 22:02:00 ppp [wan_link0] LCP: LayerDown May 23 22:02:00 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:02:00 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:02:00 ppp [wan] IPV6CP: Down event May 23 22:02:00 ppp [wan] IPCP: state change Closed --> Initial May 23 22:02:00 ppp [wan] IPCP: Down event May 23 22:02:00 ppp [wan] IPV6CP: Close event May 23 22:02:00 ppp [wan] IPCP: Close event May 23 22:02:00 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:02:00 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:02:00 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:02:00 ppp [wan_link0] LCP: rec'd Terminate Request #74 (Opened) May 23 22:02:00 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:02:00 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:02:00 ppp [wan] IPCP: LayerFinish May 23 22:02:00 ppp [wan] IPCP: state change Closing --> Closed May 23 22:02:00 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:02:00 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:02:00 ppp [wan] IPV6CP: Close event May 23 22:02:00 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:02:00 ppp [wan] IFACE: Down event May 23 22:02:00 ppp [wan] IPCP: LayerDown May 23 22:02:00 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:02:00 ppp [wan] IPCP: state change Opened --> Closing May 23 22:02:00 ppp [wan] IPCP: Close event May 23 22:02:00 ppp [wan] IFACE: Close event May 23 22:02:00 ppp caught fatal signal TERM May 23 22:01:17 ppp [wan] IPV6CP: LayerFinish May 23 22:01:17 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:01:14 ppp [wan] IPV6CP: LayerDown May 23 22:01:14 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:01:14 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:01:14 ppp [wan] IPV6CP: rec'd Terminate Request #96 (Opened) May 23 22:01:14 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:01:14 ppp [wan] IPV6CP: LayerUp May 23 22:01:14 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:01:14 ppp [wan] IPV6CP: SendConfigAck #95 May 23 22:01:14 ppp [wan] IPV6CP: rec'd Configure Request #95 (Ack-Rcvd) May 23 22:01:14 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:01:14 ppp [wan] IFACE: Up event May 23 22:01:14 ppp [wan] 82.57.155.135 -> 192.168.100.1 May 23 22:01:14 ppp [wan] IPCP: LayerUp May 23 22:01:14 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:01:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:01:14 ppp [wan] IPCP: SendConfigAck #126 May 23 22:01:14 ppp [wan] 192.168.100.1 is OK May 23 22:01:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Request #126 (Ack-Rcvd) May 23 22:01:14 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:01:14 ppp [wan] IPADDR 82.57.155.135 May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:01:14 ppp [wan] IPADDR 82.57.155.135 May 23 22:01:14 ppp [wan] IPCP: SendConfigReq #3 May 23 22:01:14 ppp [wan] 82.57.155.135 is OK May 23 22:01:14 ppp [wan] IPADDR 82.57.155.135 May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:01:14 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:01:14 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:01:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:01:14 ppp [wan] IPCP: SendConfigReq #2 May 23 22:01:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:01:14 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:01:14 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:01:14 ppp [wan] IPV6CP: Up event May 23 22:01:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:01:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:01:14 ppp [wan] IPCP: SendConfigReq #1 May 23 22:01:14 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:01:14 ppp [wan] IPCP: Up event May 23 22:01:14 ppp [wan] IPV6CP: LayerStart May 23 22:01:14 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:01:14 ppp [wan] IPV6CP: Open event May 23 22:01:14 ppp [wan] IPCP: LayerStart May 23 22:01:14 ppp [wan] IPCP: state change Initial --> Starting May 23 22:01:14 ppp [wan] IPCP: Open event May 23 22:01:14 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:01:14 ppp [wan_link0] Link: Join bundle "wan" May 23 22:01:14 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:01:14 ppp [wan_link0] LCP: authorization successful May 23 22:01:14 ppp [wan_link0] MESG: REASON021 - TK MISSING: NOT IN DSL-LINE May 23 22:01:14 ppp [wan_link0] PAP: rec'd ACK #1 len: 44 May 23 22:01:14 ppp [wan_link0] LCP: LayerUp May 23 22:01:14 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:01:14 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:01:14 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:01:14 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:01:14 ppp [wan_link0] MAGICNUM 0xb8e86e4c May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] PROTOCOMP May 23 22:01:14 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:01:14 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:01:14 ppp [wan_link0] MAGICNUM 0x5e908b8b May 23 22:01:14 ppp [wan_link0] AUTHPROTO PAP May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] LCP: SendConfigAck #73 May 23 22:01:14 ppp [wan_link0] MAGICNUM 0x5e908b8b May 23 22:01:14 ppp [wan_link0] AUTHPROTO PAP May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] LCP: rec'd Configure Request #73 (Req-Sent) May 23 22:01:14 ppp [wan_link0] MAGICNUM 0xb8e86e4c May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] PROTOCOMP May 23 22:01:14 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:01:14 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:01:14 ppp [wan_link0] LCP: Up event May 23 22:01:14 ppp [wan_link0] Link: UP event May 23 22:01:14 ppp [wan_link0] PPPoE: connection successful May 23 22:01:14 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:01:14 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:01:14 ppp [wan_link0] LCP: LayerStart May 23 22:01:14 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:01:14 ppp [wan_link0] LCP: Open event May 23 22:01:14 ppp [wan_link0] Link: OPEN event May 23 22:01:14 ppp [wan] Bundle: Interface ng0 created May 23 22:01:14 ppp web: web is not running May 23 22:01:14 ppp process 12700 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:01:14 ppp Multi-link PPP daemon for FreeBSD May 23 22:01:11 ppp process 29992 terminated May 23 22:01:11 ppp [wan_link0] Link: Shutdown May 23 22:01:11 ppp [wan] Bundle: Shutdown May 23 22:01:09 ppp [wan_link0] LCP: Close event May 23 22:01:09 ppp [wan_link0] Link: CLOSE event May 23 22:01:09 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:01:09 ppp [wan_link0] LCP: LayerFinish May 23 22:01:09 ppp [wan_link0] LCP: Down event May 23 22:01:09 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:01:09 ppp [wan_link0] LCP: Close event May 23 22:01:09 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:01:09 ppp [wan_link0] Link: DOWN event May 23 22:01:09 ppp [wan_link0] PPPoE: connection closed May 23 22:01:09 ppp [wan_link0] LCP: LayerDown May 23 22:01:09 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:01:09 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:01:09 ppp [wan] IPV6CP: Down event May 23 22:01:09 ppp [wan] IPCP: state change Closed --> Initial May 23 22:01:09 ppp [wan] IPCP: Down event May 23 22:01:09 ppp [wan] IPV6CP: Close event May 23 22:01:09 ppp [wan] IPCP: Close event May 23 22:01:09 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:01:09 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:01:09 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:01:09 ppp [wan_link0] LCP: rec'd Terminate Request #19 (Opened) May 23 22:01:09 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:01:09 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:01:09 ppp [wan] IPCP: LayerFinish May 23 22:01:09 ppp [wan] IPCP: state change Closing --> Closed May 23 22:01:09 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:01:09 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:01:09 ppp [wan] IPV6CP: Close event May 23 22:01:09 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:01:09 ppp [wan] IFACE: Down event May 23 22:01:09 ppp [wan] IPCP: LayerDown May 23 22:01:09 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:01:09 ppp [wan] IPCP: state change Opened --> Closing May 23 22:01:09 ppp [wan] IPCP: Close event May 23 22:01:09 ppp [wan] IFACE: Close event May 23 22:01:09 ppp caught fatal signal TERM May 23 22:00:17 ppp [wan] IPV6CP: LayerFinish May 23 22:00:17 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:00:14 ppp [wan] IPV6CP: LayerDown May 23 22:00:14 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:00:14 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:00:14 ppp [wan] IPV6CP: rec'd Terminate Request #140 (Opened) May 23 22:00:14 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:00:14 ppp [wan] IPV6CP: LayerUp May 23 22:00:14 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:00:14 ppp [wan] IPV6CP: SendConfigAck #139 May 23 22:00:14 ppp [wan] IPV6CP: rec'd Configure Request #139 (Ack-Rcvd) May 23 22:00:14 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:00:14 ppp [wan] IFACE: Up event May 23 22:00:14 ppp [wan] 79.18.210.145 -> 192.168.100.1 May 23 22:00:14 ppp [wan] IPCP: LayerUp May 23 22:00:14 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:00:14 ppp [wan] IPCP: SendConfigAck #11 May 23 22:00:14 ppp [wan] 192.168.100.1 is OK May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Request #11 (Ack-Rcvd) May 23 22:00:14 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:00:14 ppp [wan] IPADDR 79.18.210.145 May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:00:14 ppp [wan] IPADDR 79.18.210.145 May 23 22:00:14 ppp [wan] IPCP: SendConfigReq #3 May 23 22:00:14 ppp [wan] 79.18.210.145 is OK May 23 22:00:14 ppp [wan] IPADDR 79.18.210.145 May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:00:14 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:00:14 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:00:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:00:14 ppp [wan] IPCP: SendConfigReq #2 May 23 22:00:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:00:14 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:00:14 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:00:14 ppp [wan] IPV6CP: Up event May 23 22:00:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:00:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:00:14 ppp [wan] IPCP: SendConfigReq #1 May 23 22:00:14 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:00:14 ppp [wan] IPCP: Up event May 23 22:00:14 ppp [wan] IPV6CP: LayerStart May 23 22:00:14 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:00:14 ppp [wan] IPV6CP: Open event May 23 22:00:14 ppp [wan] IPCP: LayerStart May 23 22:00:14 ppp [wan] IPCP: state change Initial --> Starting May 23 22:00:14 ppp [wan] IPCP: Open event May 23 22:00:14 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:00:14 ppp [wan_link0] Link: Join bundle "wan" May 23 22:00:14 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:00:14 ppp [wan_link0] LCP: authorization successful May 23 22:00:14 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:00:14 ppp [wan_link0] LCP: LayerUp May 23 22:00:14 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:00:14 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:00:14 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:00:14 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:00:14 ppp [wan_link0] MAGICNUM 0x668d5328 May 23 22:00:14 ppp [wan_link0] MRU 1492 May 23 22:00:14 ppp [wan_link0] PROTOCOMP May 23 22:00:14 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:00:14 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:00:14 ppp [wan_link0] MAGICNUM 0x534f4cbb May 23 22:00:14 ppp [wan_link0] AUTHPROTO PAP May 23 22:00:14 ppp [wan_link0] MRU 1492 May 23 22:00:14 ppp [wan_link0] LCP: SendConfigAck #18 May 23 22:00:14 ppp [wan_link0] MAGICNUM 0x534f4cbb -
Hi CMB,
thanks for your reply. :)Here is PPP logs:
May 23 22:11:31 ppp [wan] IPV6CP: LayerFinish May 23 22:11:31 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:11:28 ppp [wan] IPV6CP: LayerDown May 23 22:11:28 ppp [wan] IPV6CP: SendTerminateAck #6 May 23 22:11:28 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:11:28 ppp [wan] IPV6CP: rec'd Terminate Request #212 (Opened) May 23 22:11:28 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:11:28 ppp [wan] IPV6CP: LayerUp May 23 22:11:28 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:11:28 ppp [wan] IPV6CP: SendConfigAck #211 May 23 22:11:28 ppp [wan] IPV6CP: rec'd Configure Request #211 (Ack-Rcvd) May 23 22:11:28 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:11:28 ppp [wan] IPV6CP: rec'd Configure Ack #5 (Req-Sent) May 23 22:11:28 ppp [wan] IPV6CP: SendConfigReq #5 May 23 22:11:26 ppp [wan] IPV6CP: SendTerminateAck #4 May 23 22:11:26 ppp [wan] IPV6CP: state change Ack-Sent --> Req-Sent May 23 22:11:26 ppp [wan] IPV6CP: rec'd Terminate Request #1 (Ack-Sent) May 23 22:11:26 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Sent May 23 22:11:26 ppp [wan] IPV6CP: SendConfigAck #0 May 23 22:11:26 ppp [wan] IPV6CP: rec'd Configure Request #0 (Req-Sent) May 23 22:11:26 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:11:26 ppp [wan] IFACE: Up event May 23 22:11:26 ppp [wan] 87.21.211.177 -> 192.168.100.1 May 23 22:11:26 ppp [wan] IPCP: LayerUp May 23 22:11:26 ppp [wan] IPCP: state change Ack-Sent --> Opened May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177 May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) May 23 22:11:26 ppp [wan] IPCP: state change Req-Sent --> Ack-Sent May 23 22:11:26 ppp [wan] IPADDR 192.168.100.1 May 23 22:11:26 ppp [wan] IPCP: SendConfigAck #181 May 23 22:11:26 ppp [wan] 192.168.100.1 is OK May 23 22:11:26 ppp [wan] IPADDR 192.168.100.1 May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Request #181 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: SendTerminateAck #3 May 23 22:11:26 ppp [wan] IPV6CP: rec'd Terminate Request #255 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:11:26 ppp [wan] IPV6CP: state change Ack-Rcvd --> Req-Sent May 23 22:11:26 ppp [wan] IPV6CP: rec'd Terminate Request #254 (Ack-Rcvd) May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177 May 23 22:11:26 ppp [wan] IPCP: SendConfigReq #3 May 23 22:11:26 ppp [wan] 87.21.211.177 is OK May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177 May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:11:26 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:11:26 ppp [wan] IPADDR 0.0.0.0 May 23 22:11:26 ppp [wan] IPCP: SendConfigReq #2 May 23 22:11:26 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:11:26 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:11:26 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:11:26 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:11:26 ppp [wan] IPV6CP: Up event May 23 22:11:26 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:11:26 ppp [wan] IPADDR 0.0.0.0 May 23 22:11:26 ppp [wan] IPCP: SendConfigReq #1 May 23 22:11:26 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:11:26 ppp [wan] IPCP: Up event May 23 22:11:26 ppp [wan] IPV6CP: LayerStart May 23 22:11:26 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:11:26 ppp [wan] IPV6CP: Open event May 23 22:11:26 ppp [wan] IPCP: LayerStart May 23 22:11:26 ppp [wan] IPCP: state change Initial --> Starting May 23 22:11:26 ppp [wan] IPCP: Open event May 23 22:11:26 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:11:26 ppp [wan_link0] Link: Join bundle "wan" May 23 22:11:26 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:11:26 ppp [wan_link0] LCP: authorization successful May 23 22:11:26 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:11:26 ppp [wan_link0] LCP: LayerUp May 23 22:11:26 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:11:26 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:11:26 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:11:26 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:11:26 ppp [wan_link0] MAGICNUM 0xcef11f0e May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] PROTOCOMP May 23 22:11:26 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:11:26 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:11:26 ppp [wan_link0] MAGICNUM 0x536a27fa May 23 22:11:26 ppp [wan_link0] AUTHPROTO PAP May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] LCP: SendConfigAck #56 May 23 22:11:26 ppp [wan_link0] MAGICNUM 0x536a27fa May 23 22:11:26 ppp [wan_link0] AUTHPROTO PAP May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] LCP: rec'd Configure Request #56 (Req-Sent) May 23 22:11:26 ppp [wan_link0] MAGICNUM 0xcef11f0e May 23 22:11:26 ppp [wan_link0] MRU 1492 May 23 22:11:26 ppp [wan_link0] PROTOCOMP May 23 22:11:26 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:11:26 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:11:26 ppp [wan_link0] LCP: Up event May 23 22:11:26 ppp [wan_link0] Link: UP event May 23 22:11:26 ppp [wan_link0] PPPoE: connection successful May 23 22:11:26 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:11:25 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:11:25 ppp [wan_link0] LCP: LayerStart May 23 22:11:25 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:11:25 ppp [wan_link0] LCP: Open event May 23 22:11:25 ppp [wan_link0] Link: OPEN event May 23 22:11:25 ppp [wan] Bundle: Interface ng0 created May 23 22:11:25 ppp web: web is not running May 23 22:11:25 ppp process 84326 terminated May 23 22:11:25 ppp [wan_link0] Link: Shutdown May 23 22:11:25 ppp [wan] Bundle: Shutdown May 23 22:11:24 ppp waiting for process 84326 to die... May 23 22:11:24 ppp process 12005 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:11:24 ppp Multi-link PPP daemon for FreeBSD May 23 22:11:23 ppp [wan_link0] LCP: Close event May 23 22:11:23 ppp [wan_link0] Link: CLOSE event May 23 22:11:23 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:11:23 ppp [wan_link0] LCP: LayerFinish May 23 22:11:23 ppp [wan_link0] LCP: Down event May 23 22:11:23 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:11:23 ppp [wan_link0] LCP: Close event May 23 22:11:23 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:11:23 ppp [wan_link0] Link: DOWN event May 23 22:11:23 ppp [wan_link0] PPPoE: connection closed May 23 22:11:23 ppp [wan_link0] LCP: LayerDown May 23 22:11:23 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:11:23 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:11:23 ppp [wan] IPV6CP: Down event May 23 22:11:23 ppp [wan] IPCP: state change Closed --> Initial May 23 22:11:23 ppp [wan] IPCP: Down event May 23 22:11:23 ppp [wan] IPV6CP: Close event May 23 22:11:23 ppp [wan] IPCP: Close event May 23 22:11:23 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:11:23 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:11:23 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:11:23 ppp [wan_link0] LCP: rec'd Terminate Request #24 (Opened) May 23 22:11:23 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:11:23 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:11:23 ppp [wan] IPCP: LayerFinish May 23 22:11:23 ppp [wan] IPCP: state change Closing --> Closed May 23 22:11:23 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:11:23 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:11:23 ppp [wan] IPV6CP: Close event May 23 22:11:23 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:11:23 ppp [wan] IFACE: Down event May 23 22:11:22 ppp [wan] IPCP: LayerDown May 23 22:11:22 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:11:22 ppp [wan] IPCP: state change Opened --> Closing May 23 22:11:22 ppp [wan] IPCP: Close event May 23 22:11:22 ppp [wan] IFACE: Close event May 23 22:11:22 ppp caught fatal signal TERM May 23 22:09:14 ppp [wan] IPV6CP: LayerFinish May 23 22:09:14 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:09:12 ppp [wan] IPV6CP: LayerDown May 23 22:09:12 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:09:12 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:09:12 ppp [wan] IPV6CP: rec'd Terminate Request #184 (Opened) May 23 22:09:12 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:09:12 ppp [wan] IPV6CP: LayerUp May 23 22:09:12 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:09:12 ppp [wan] IPV6CP: SendConfigAck #183 May 23 22:09:12 ppp [wan] IPV6CP: rec'd Configure Request #183 (Ack-Rcvd) May 23 22:09:12 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:09:12 ppp [wan] IFACE: Up event May 23 22:09:12 ppp [wan] 82.53.152.239 -> 192.168.100.1 May 23 22:09:12 ppp [wan] IPCP: LayerUp May 23 22:09:12 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:09:12 ppp [wan] IPADDR 192.168.100.1 May 23 22:09:12 ppp [wan] IPCP: SendConfigAck #247 May 23 22:09:12 ppp [wan] 192.168.100.1 is OK May 23 22:09:12 ppp [wan] IPADDR 192.168.100.1 May 23 22:09:12 ppp [wan] IPCP: rec'd Configure Request #247 (Ack-Rcvd) May 23 22:09:12 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:09:12 ppp [wan] IPADDR 82.53.152.239 May 23 22:09:12 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:09:12 ppp [wan] IPADDR 82.53.152.239 May 23 22:09:12 ppp [wan] IPCP: SendConfigReq #3 May 23 22:09:12 ppp [wan] 82.53.152.239 is OK May 23 22:09:12 ppp [wan] IPADDR 82.53.152.239 May 23 22:09:12 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:09:11 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:09:11 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:09:11 ppp [wan] IPADDR 0.0.0.0 May 23 22:09:11 ppp [wan] IPCP: SendConfigReq #2 May 23 22:09:11 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:09:11 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:09:11 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:09:11 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:09:11 ppp [wan] IPV6CP: Up event May 23 22:09:11 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:09:11 ppp [wan] IPADDR 0.0.0.0 May 23 22:09:11 ppp [wan] IPCP: SendConfigReq #1 May 23 22:09:11 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:09:11 ppp [wan] IPCP: Up event May 23 22:09:11 ppp [wan] IPV6CP: LayerStart May 23 22:09:11 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:09:11 ppp [wan] IPV6CP: Open event May 23 22:09:11 ppp [wan] IPCP: LayerStart May 23 22:09:11 ppp [wan] IPCP: state change Initial --> Starting May 23 22:09:11 ppp [wan] IPCP: Open event May 23 22:09:11 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:09:11 ppp [wan_link0] Link: Join bundle "wan" May 23 22:09:11 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:09:11 ppp [wan_link0] LCP: authorization successful May 23 22:09:11 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:09:11 ppp [wan_link0] LCP: LayerUp May 23 22:09:11 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:09:11 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:09:11 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:09:11 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x79b7508e May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] PROTOCOMP May 23 22:09:11 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:09:11 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x3406c6c8 May 23 22:09:11 ppp [wan_link0] AUTHPROTO PAP May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] LCP: SendConfigAck #23 May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x3406c6c8 May 23 22:09:11 ppp [wan_link0] AUTHPROTO PAP May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] LCP: rec'd Configure Request #23 (Req-Sent) May 23 22:09:11 ppp [wan_link0] MAGICNUM 0x79b7508e May 23 22:09:11 ppp [wan_link0] MRU 1492 May 23 22:09:11 ppp [wan_link0] PROTOCOMP May 23 22:09:11 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:09:11 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:09:11 ppp [wan_link0] LCP: Up event May 23 22:09:11 ppp [wan_link0] Link: UP event May 23 22:09:11 ppp [wan_link0] PPPoE: connection successful May 23 22:09:11 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:09:11 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:09:11 ppp [wan_link0] LCP: LayerStart May 23 22:09:11 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:09:11 ppp [wan_link0] LCP: Open event May 23 22:09:11 ppp [wan_link0] Link: OPEN event May 23 22:09:11 ppp [wan] Bundle: Interface ng0 created May 23 22:09:11 ppp web: web is not running May 23 22:09:10 ppp process 91397 terminated May 23 22:09:10 ppp [wan_link0] Link: Shutdown May 23 22:09:10 ppp [wan] Bundle: Shutdown May 23 22:09:10 ppp waiting for process 91397 to die... May 23 22:09:10 ppp process 84326 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:09:10 ppp Multi-link PPP daemon for FreeBSD May 23 22:09:08 ppp [wan_link0] LCP: Close event May 23 22:09:08 ppp [wan_link0] Link: CLOSE event May 23 22:09:08 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:09:08 ppp [wan_link0] LCP: LayerFinish May 23 22:09:08 ppp [wan_link0] LCP: Down event May 23 22:09:08 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:09:08 ppp [wan_link0] LCP: Close event May 23 22:09:08 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:09:08 ppp [wan_link0] Link: DOWN event May 23 22:09:08 ppp [wan_link0] PPPoE: connection closed May 23 22:09:08 ppp [wan_link0] LCP: LayerDown May 23 22:09:08 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:09:08 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:09:08 ppp [wan] IPV6CP: Down event May 23 22:09:08 ppp [wan] IPCP: state change Closed --> Initial May 23 22:09:08 ppp [wan] IPCP: Down event May 23 22:09:08 ppp [wan] IPV6CP: Close event May 23 22:09:08 ppp [wan] IPCP: Close event May 23 22:09:08 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:09:08 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:09:08 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:09:08 ppp [wan_link0] LCP: rec'd Terminate Request #148 (Opened) May 23 22:09:08 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:09:08 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:09:08 ppp [wan] IPCP: LayerFinish May 23 22:09:08 ppp [wan] IPCP: state change Closing --> Closed May 23 22:09:08 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:09:08 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:09:08 ppp [wan] IPV6CP: Close event May 23 22:09:08 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:09:08 ppp [wan] IFACE: Down event May 23 22:09:08 ppp [wan] IPCP: LayerDown May 23 22:09:08 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:09:08 ppp [wan] IPCP: state change Opened --> Closing May 23 22:09:08 ppp [wan] IPCP: Close event May 23 22:09:08 ppp [wan] IFACE: Close event May 23 22:09:08 ppp caught fatal signal TERM May 23 22:07:06 ppp [wan] IPV6CP: LayerFinish May 23 22:07:06 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:07:04 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:07:04 ppp [wan] IFACE: Up event May 23 22:07:04 ppp [wan] 87.1.223.81 -> 192.168.100.1 May 23 22:07:04 ppp [wan] IPCP: LayerUp May 23 22:07:04 ppp [wan] IPCP: state change Ack-Sent --> Opened May 23 22:07:04 ppp [wan] IPADDR 87.1.223.81 May 23 22:07:04 ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) May 23 22:07:04 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:07:04 ppp [wan] IFACE: Down event May 23 22:07:03 ppp [wan] IPV6CP: LayerDown May 23 22:07:03 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:07:03 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:07:03 ppp [wan] IPV6CP: rec'd Terminate Request #193 (Opened) May 23 22:07:03 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:07:03 ppp [wan] IFACE: Up event May 23 22:07:03 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:07:03 ppp [wan] IPV6CP: LayerUp May 23 22:07:03 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:07:03 ppp [wan] IPV6CP: SendConfigAck #192 May 23 22:07:03 ppp [wan] IPV6CP: rec'd Configure Request #192 (Ack-Rcvd) May 23 22:07:03 ppp [wan] IPADDR 87.1.223.81 May 23 22:07:03 ppp [wan] IPCP: SendConfigReq #3 May 23 22:07:03 ppp [wan] 87.1.223.81 is OK May 23 22:07:03 ppp [wan] IPADDR 87.1.223.81 May 23 22:07:03 ppp [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent) May 23 22:07:03 ppp [wan] IPCP: state change Req-Sent --> Ack-Sent May 23 22:07:03 ppp [wan] IPADDR 192.168.100.1 May 23 22:07:03 ppp [wan] IPCP: SendConfigAck #172 May 23 22:07:03 ppp [wan] 192.168.100.1 is OK May 23 22:07:03 ppp [wan] IPADDR 192.168.100.1 May 23 22:07:03 ppp [wan] IPCP: rec'd Configure Request #172 (Req-Sent) May 23 22:07:03 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:07:03 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:07:03 ppp [wan] IPADDR 0.0.0.0 May 23 22:07:03 ppp [wan] IPCP: SendConfigReq #2 May 23 22:07:03 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:07:03 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:07:03 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:07:03 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:07:03 ppp [wan] IPV6CP: Up event May 23 22:07:03 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:07:03 ppp [wan] IPADDR 0.0.0.0 May 23 22:07:03 ppp [wan] IPCP: SendConfigReq #1 May 23 22:07:03 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:07:03 ppp [wan] IPCP: Up event May 23 22:07:03 ppp [wan] IPV6CP: LayerStart May 23 22:07:03 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:07:03 ppp [wan] IPV6CP: Open event May 23 22:07:03 ppp [wan] IPCP: LayerStart May 23 22:07:03 ppp [wan] IPCP: state change Initial --> Starting May 23 22:07:03 ppp [wan] IPCP: Open event May 23 22:07:03 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:07:03 ppp [wan_link0] Link: Join bundle "wan" May 23 22:07:03 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:07:03 ppp [wan_link0] LCP: authorization successful May 23 22:07:03 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:07:03 ppp [wan_link0] LCP: LayerUp May 23 22:07:03 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:07:03 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:07:03 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:07:03 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x5cce6d32 May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] PROTOCOMP May 23 22:07:03 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:07:03 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x30b57178 May 23 22:07:03 ppp [wan_link0] AUTHPROTO PAP May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] LCP: SendConfigAck #147 May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x30b57178 May 23 22:07:03 ppp [wan_link0] AUTHPROTO PAP May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] LCP: rec'd Configure Request #147 (Req-Sent) May 23 22:07:03 ppp [wan_link0] MAGICNUM 0x5cce6d32 May 23 22:07:03 ppp [wan_link0] MRU 1492 May 23 22:07:03 ppp [wan_link0] PROTOCOMP May 23 22:07:03 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:07:03 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:07:03 ppp [wan_link0] LCP: Up event May 23 22:07:03 ppp [wan_link0] Link: UP event May 23 22:07:03 ppp [wan_link0] PPPoE: connection successful May 23 22:07:03 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:07:03 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:07:03 ppp [wan_link0] LCP: LayerStart May 23 22:07:03 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:07:03 ppp [wan_link0] LCP: Open event May 23 22:07:03 ppp [wan_link0] Link: OPEN event May 23 22:07:03 ppp [wan] Bundle: Interface ng0 created May 23 22:07:03 ppp web: web is not running May 23 22:07:03 ppp process 91397 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:07:03 ppp Multi-link PPP daemon for FreeBSD May 23 22:06:49 ppp process 8514 terminated May 23 22:06:49 ppp [wan_link0] Link: Shutdown May 23 22:06:49 ppp [wan] Bundle: Shutdown May 23 22:06:47 ppp [wan_link0] LCP: Close event May 23 22:06:47 ppp [wan_link0] Link: CLOSE event May 23 22:06:47 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:06:47 ppp [wan_link0] LCP: LayerFinish May 23 22:06:47 ppp [wan_link0] LCP: Down event May 23 22:06:47 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:06:47 ppp [wan_link0] LCP: Close event May 23 22:06:47 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:06:47 ppp [wan_link0] Link: DOWN event May 23 22:06:47 ppp [wan_link0] PPPoE: connection closed May 23 22:06:47 ppp [wan_link0] LCP: LayerDown May 23 22:06:47 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:06:47 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:06:47 ppp [wan] IPV6CP: Down event May 23 22:06:47 ppp [wan] IPCP: state change Closed --> Initial May 23 22:06:47 ppp [wan] IPCP: Down event May 23 22:06:47 ppp [wan] IPV6CP: Close event May 23 22:06:47 ppp [wan] IPCP: Close event May 23 22:06:47 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:06:47 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:06:47 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:06:47 ppp [wan_link0] LCP: rec'd Terminate Request #204 (Opened) May 23 22:06:47 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:06:47 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:06:47 ppp [wan] IPCP: LayerFinish May 23 22:06:47 ppp [wan] IPCP: state change Closing --> Closed May 23 22:06:47 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:06:47 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:06:47 ppp [wan] IPV6CP: Close event May 23 22:06:47 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:06:47 ppp [wan] IFACE: Down event May 23 22:06:46 ppp [wan] IPCP: LayerDown May 23 22:06:46 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:06:46 ppp [wan] IPCP: state change Opened --> Closing May 23 22:06:46 ppp [wan] IPCP: Close event May 23 22:06:46 ppp [wan] IFACE: Close event May 23 22:06:46 ppp caught fatal signal TERM May 23 22:05:05 ppp [wan] IPV6CP: LayerFinish May 23 22:05:05 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:05:03 ppp [wan] IPV6CP: LayerDown May 23 22:05:03 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:05:03 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:05:03 ppp [wan] IPV6CP: rec'd Terminate Request #82 (Opened) May 23 22:05:02 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:05:02 ppp [wan] IPV6CP: LayerUp May 23 22:05:02 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:05:02 ppp [wan] IPV6CP: SendConfigAck #81 May 23 22:05:02 ppp [wan] IPV6CP: rec'd Configure Request #81 (Ack-Rcvd) May 23 22:05:02 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:05:02 ppp [wan] IFACE: Up event May 23 22:05:02 ppp [wan] 82.51.149.195 -> 192.168.100.1 May 23 22:05:02 ppp [wan] IPCP: LayerUp May 23 22:05:02 ppp [wan] IPCP: state change Ack-Sent --> Opened May 23 22:05:02 ppp [wan] IPADDR 82.51.149.195 May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) May 23 22:05:02 ppp [wan] IPCP: state change Req-Sent --> Ack-Sent May 23 22:05:02 ppp [wan] IPADDR 192.168.100.1 May 23 22:05:02 ppp [wan] IPCP: SendConfigAck #15 May 23 22:05:02 ppp [wan] 192.168.100.1 is OK May 23 22:05:02 ppp [wan] IPADDR 192.168.100.1 May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Request #15 (Req-Sent) May 23 22:05:02 ppp [wan] IPADDR 82.51.149.195 May 23 22:05:02 ppp [wan] IPCP: SendConfigReq #3 May 23 22:05:02 ppp [wan] 82.51.149.195 is OK May 23 22:05:02 ppp [wan] IPADDR 82.51.149.195 May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:05:02 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:05:02 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:05:02 ppp [wan] IPADDR 0.0.0.0 May 23 22:05:02 ppp [wan] IPCP: SendConfigReq #2 May 23 22:05:02 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:05:02 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:05:02 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:05:02 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:05:02 ppp [wan] IPV6CP: Up event May 23 22:05:02 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:05:02 ppp [wan] IPADDR 0.0.0.0 May 23 22:05:02 ppp [wan] IPCP: SendConfigReq #1 May 23 22:05:02 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:05:02 ppp [wan] IPCP: Up event May 23 22:05:02 ppp [wan] IPV6CP: LayerStart May 23 22:05:02 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:05:02 ppp [wan] IPV6CP: Open event May 23 22:05:02 ppp [wan] IPCP: LayerStart May 23 22:05:02 ppp [wan] IPCP: state change Initial --> Starting May 23 22:05:02 ppp [wan] IPCP: Open event May 23 22:05:02 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:05:02 ppp [wan_link0] Link: Join bundle "wan" May 23 22:05:02 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:05:02 ppp [wan_link0] LCP: authorization successful May 23 22:05:02 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:05:02 ppp [wan_link0] LCP: LayerUp May 23 22:05:02 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:05:02 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:05:02 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:05:02 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x57f267c0 May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] PROTOCOMP May 23 22:05:02 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:05:02 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x2e5d1f70 May 23 22:05:02 ppp [wan_link0] AUTHPROTO PAP May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] LCP: SendConfigAck #203 May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x2e5d1f70 May 23 22:05:02 ppp [wan_link0] AUTHPROTO PAP May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] LCP: rec'd Configure Request #203 (Req-Sent) May 23 22:05:02 ppp [wan_link0] MAGICNUM 0x57f267c0 May 23 22:05:02 ppp [wan_link0] MRU 1492 May 23 22:05:02 ppp [wan_link0] PROTOCOMP May 23 22:05:02 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:05:02 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:05:02 ppp [wan_link0] LCP: Up event May 23 22:05:02 ppp [wan_link0] Link: UP event May 23 22:05:02 ppp [wan_link0] PPPoE: connection successful May 23 22:05:02 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:05:02 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:05:02 ppp [wan_link0] LCP: LayerStart May 23 22:05:02 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:05:02 ppp [wan_link0] LCP: Open event May 23 22:05:02 ppp [wan_link0] Link: OPEN event May 23 22:05:02 ppp [wan] Bundle: Interface ng0 created May 23 22:05:02 ppp web: web is not running May 23 22:05:02 ppp process 8514 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:05:02 ppp Multi-link PPP daemon for FreeBSD May 23 22:04:54 ppp process 67736 terminated May 23 22:04:54 ppp [wan_link0] Link: Shutdown May 23 22:04:54 ppp [wan] Bundle: Shutdown May 23 22:04:52 ppp [wan_link0] LCP: Close event May 23 22:04:52 ppp [wan_link0] Link: CLOSE event May 23 22:04:52 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:04:52 ppp [wan_link0] LCP: LayerFinish May 23 22:04:52 ppp [wan_link0] LCP: Down event May 23 22:04:52 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:04:52 ppp [wan_link0] LCP: Close event May 23 22:04:52 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:04:52 ppp [wan_link0] Link: DOWN event May 23 22:04:52 ppp [wan_link0] PPPoE: connection closed May 23 22:04:52 ppp [wan_link0] LCP: LayerDown May 23 22:04:52 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:04:52 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:04:52 ppp [wan] IPV6CP: Down event May 23 22:04:52 ppp [wan] IPCP: state change Closed --> Initial May 23 22:04:52 ppp [wan] IPCP: Down event May 23 22:04:52 ppp [wan] IPV6CP: Close event May 23 22:04:52 ppp [wan] IPCP: Close event May 23 22:04:52 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:04:52 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:04:52 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:04:52 ppp [wan_link0] LCP: rec'd Terminate Request #148 (Opened) May 23 22:04:52 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:04:52 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:04:52 ppp [wan] IPCP: LayerFinish May 23 22:04:52 ppp [wan] IPCP: state change Closing --> Closed May 23 22:04:52 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:04:52 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:04:52 ppp [wan] IPV6CP: Close event May 23 22:04:52 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:04:52 ppp [wan] IFACE: Down event May 23 22:04:52 ppp [wan] IPCP: LayerDown May 23 22:04:52 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:04:52 ppp [wan] IPCP: state change Opened --> Closing May 23 22:04:52 ppp [wan] IPCP: Close event May 23 22:04:52 ppp [wan] IFACE: Close event May 23 22:04:52 ppp caught fatal signal TERM May 23 22:04:18 ppp [wan] IPV6CP: LayerFinish May 23 22:04:18 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:04:15 ppp [wan] IPV6CP: LayerDown May 23 22:04:15 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:04:15 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:04:15 ppp [wan] IPV6CP: rec'd Terminate Request #174 (Opened) May 23 22:04:15 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:04:15 ppp [wan] IPV6CP: LayerUp May 23 22:04:15 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:04:15 ppp [wan] IPV6CP: SendConfigAck #173 May 23 22:04:15 ppp [wan] IPV6CP: rec'd Configure Request #173 (Ack-Rcvd) May 23 22:04:15 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:04:15 ppp [wan] IFACE: Up event May 23 22:04:15 ppp [wan] 82.51.146.47 -> 192.168.100.1 May 23 22:04:15 ppp [wan] IPCP: LayerUp May 23 22:04:15 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:04:15 ppp [wan] IPADDR 192.168.100.1 May 23 22:04:15 ppp [wan] IPCP: SendConfigAck #100 May 23 22:04:15 ppp [wan] 192.168.100.1 is OK May 23 22:04:15 ppp [wan] IPADDR 192.168.100.1 May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Request #100 (Ack-Rcvd) May 23 22:04:15 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:04:15 ppp [wan] IPADDR 82.51.146.47 May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:04:15 ppp [wan] IPADDR 82.51.146.47 May 23 22:04:15 ppp [wan] IPCP: SendConfigReq #3 May 23 22:04:15 ppp [wan] 82.51.146.47 is OK May 23 22:04:15 ppp [wan] IPADDR 82.51.146.47 May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:04:15 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:04:15 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:04:15 ppp [wan] IPADDR 0.0.0.0 May 23 22:04:15 ppp [wan] IPCP: SendConfigReq #2 May 23 22:04:15 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:04:15 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:04:15 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:04:15 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:04:15 ppp [wan] IPV6CP: Up event May 23 22:04:15 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:04:15 ppp [wan] IPADDR 0.0.0.0 May 23 22:04:15 ppp [wan] IPCP: SendConfigReq #1 May 23 22:04:15 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:04:15 ppp [wan] IPCP: Up event May 23 22:04:15 ppp [wan] IPV6CP: LayerStart May 23 22:04:15 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:04:15 ppp [wan] IPV6CP: Open event May 23 22:04:15 ppp [wan] IPCP: LayerStart May 23 22:04:15 ppp [wan] IPCP: state change Initial --> Starting May 23 22:04:15 ppp [wan] IPCP: Open event May 23 22:04:15 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:04:15 ppp [wan_link0] Link: Join bundle "wan" May 23 22:04:15 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:04:15 ppp [wan_link0] LCP: authorization successful May 23 22:04:15 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:04:15 ppp [wan_link0] LCP: LayerUp May 23 22:04:15 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:04:15 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:04:15 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:04:15 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7a955848 May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] PROTOCOMP May 23 22:04:15 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:04:15 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7340e4e0 May 23 22:04:15 ppp [wan_link0] AUTHPROTO PAP May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] LCP: SendConfigAck #147 May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7340e4e0 May 23 22:04:15 ppp [wan_link0] AUTHPROTO PAP May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] LCP: rec'd Configure Request #147 (Req-Sent) May 23 22:04:15 ppp [wan_link0] MAGICNUM 0x7a955848 May 23 22:04:15 ppp [wan_link0] MRU 1492 May 23 22:04:15 ppp [wan_link0] PROTOCOMP May 23 22:04:15 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:04:15 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:04:15 ppp [wan_link0] LCP: Up event May 23 22:04:15 ppp [wan_link0] Link: UP event May 23 22:04:15 ppp [wan_link0] PPPoE: connection successful May 23 22:04:15 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:04:15 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:04:15 ppp [wan_link0] LCP: LayerStart May 23 22:04:15 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:04:15 ppp [wan_link0] LCP: Open event May 23 22:04:15 ppp [wan_link0] Link: OPEN event May 23 22:04:15 ppp [wan] Bundle: Interface ng0 created May 23 22:04:15 ppp web: web is not running May 23 22:04:15 ppp process 67736 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:04:15 ppp Multi-link PPP daemon for FreeBSD May 23 22:04:13 ppp process 19089 terminated May 23 22:04:13 ppp [wan_link0] Link: Shutdown May 23 22:04:13 ppp [wan] Bundle: Shutdown May 23 22:04:11 ppp [wan_link0] LCP: Close event May 23 22:04:11 ppp [wan_link0] Link: CLOSE event May 23 22:04:11 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:04:11 ppp [wan_link0] LCP: LayerFinish May 23 22:04:11 ppp [wan_link0] LCP: Down event May 23 22:04:11 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:04:11 ppp [wan_link0] LCP: Close event May 23 22:04:11 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:04:11 ppp [wan_link0] Link: DOWN event May 23 22:04:11 ppp [wan_link0] PPPoE: connection closed May 23 22:04:11 ppp [wan_link0] LCP: LayerDown May 23 22:04:11 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:04:11 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:04:11 ppp [wan] IPV6CP: Down event May 23 22:04:11 ppp [wan] IPCP: state change Closed --> Initial May 23 22:04:11 ppp [wan] IPCP: Down event May 23 22:04:11 ppp [wan] IPV6CP: Close event May 23 22:04:11 ppp [wan] IPCP: Close event May 23 22:04:11 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:04:11 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:04:11 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:04:11 ppp [wan_link0] LCP: rec'd Terminate Request #165 (Opened) May 23 22:04:11 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:04:11 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:04:11 ppp [wan] IPCP: LayerFinish May 23 22:04:11 ppp [wan] IPCP: state change Closing --> Closed May 23 22:04:11 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:04:11 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:04:11 ppp [wan] IPV6CP: Close event May 23 22:04:11 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:04:11 ppp [wan] IFACE: Down event May 23 22:04:11 ppp [wan] IPCP: LayerDown May 23 22:04:11 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:04:11 ppp [wan] IPCP: state change Opened --> Closing May 23 22:04:11 ppp [wan] IPCP: Close event May 23 22:04:11 ppp [wan] IFACE: Close event May 23 22:04:11 ppp caught fatal signal TERM May 23 22:02:10 ppp [wan] IPV6CP: LayerFinish May 23 22:02:10 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:02:08 ppp [wan] IPV6CP: LayerDown May 23 22:02:08 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:02:08 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:02:08 ppp [wan] IPV6CP: rec'd Terminate Request #223 (Opened) May 23 22:02:08 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:02:08 ppp [wan] IPV6CP: LayerUp May 23 22:02:08 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:02:08 ppp [wan] IPV6CP: SendConfigAck #222 May 23 22:02:08 ppp [wan] IPV6CP: rec'd Configure Request #222 (Ack-Rcvd) May 23 22:02:08 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:02:08 ppp [wan] IFACE: Up event May 23 22:02:08 ppp [wan] 87.6.212.160 -> 192.168.100.1 May 23 22:02:08 ppp [wan] IPCP: LayerUp May 23 22:02:08 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:02:08 ppp [wan] IPADDR 192.168.100.1 May 23 22:02:08 ppp [wan] IPCP: SendConfigAck #157 May 23 22:02:08 ppp [wan] 192.168.100.1 is OK May 23 22:02:08 ppp [wan] IPADDR 192.168.100.1 May 23 22:02:08 ppp [wan] IPCP: rec'd Configure Request #157 (Ack-Rcvd) May 23 22:02:07 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:02:07 ppp [wan] IPADDR 87.6.212.160 May 23 22:02:07 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:02:07 ppp [wan] IPADDR 87.6.212.160 May 23 22:02:07 ppp [wan] IPCP: SendConfigReq #3 May 23 22:02:07 ppp [wan] 87.6.212.160 is OK May 23 22:02:07 ppp [wan] IPADDR 87.6.212.160 May 23 22:02:07 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:02:07 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:02:07 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:02:07 ppp [wan] IPADDR 0.0.0.0 May 23 22:02:07 ppp [wan] IPCP: SendConfigReq #2 May 23 22:02:07 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:02:07 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:02:07 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:02:07 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:02:07 ppp [wan] IPV6CP: Up event May 23 22:02:07 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:02:07 ppp [wan] IPADDR 0.0.0.0 May 23 22:02:07 ppp [wan] IPCP: SendConfigReq #1 May 23 22:02:07 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:02:07 ppp [wan] IPCP: Up event May 23 22:02:07 ppp [wan] IPV6CP: LayerStart May 23 22:02:07 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:02:07 ppp [wan] IPV6CP: Open event May 23 22:02:07 ppp [wan] IPCP: LayerStart May 23 22:02:07 ppp [wan] IPCP: state change Initial --> Starting May 23 22:02:07 ppp [wan] IPCP: Open event May 23 22:02:07 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:02:07 ppp [wan_link0] Link: Join bundle "wan" May 23 22:02:07 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:02:07 ppp [wan_link0] LCP: authorization successful May 23 22:02:07 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:02:07 ppp [wan_link0] LCP: LayerUp May 23 22:02:07 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:02:07 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:02:07 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:02:07 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x548d3a6b May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] PROTOCOMP May 23 22:02:07 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:02:07 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x19dc65f6 May 23 22:02:07 ppp [wan_link0] AUTHPROTO PAP May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] LCP: SendConfigAck #164 May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x19dc65f6 May 23 22:02:07 ppp [wan_link0] AUTHPROTO PAP May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] LCP: rec'd Configure Request #164 (Req-Sent) May 23 22:02:07 ppp [wan_link0] MAGICNUM 0x548d3a6b May 23 22:02:07 ppp [wan_link0] MRU 1492 May 23 22:02:07 ppp [wan_link0] PROTOCOMP May 23 22:02:07 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:02:07 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:02:07 ppp [wan_link0] LCP: Up event May 23 22:02:07 ppp [wan_link0] Link: UP event May 23 22:02:07 ppp [wan_link0] PPPoE: connection successful May 23 22:02:07 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:02:06 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:02:06 ppp [wan_link0] LCP: LayerStart May 23 22:02:06 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:02:06 ppp [wan_link0] LCP: Open event May 23 22:02:06 ppp [wan_link0] Link: OPEN event May 23 22:02:06 ppp [wan] Bundle: Interface ng0 created May 23 22:02:06 ppp web: web is not running May 23 22:02:06 ppp process 19089 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:02:06 ppp Multi-link PPP daemon for FreeBSD May 23 22:02:02 ppp process 12700 terminated May 23 22:02:02 ppp [wan_link0] Link: Shutdown May 23 22:02:02 ppp [wan] Bundle: Shutdown May 23 22:02:00 ppp [wan_link0] LCP: Close event May 23 22:02:00 ppp [wan_link0] Link: CLOSE event May 23 22:02:00 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:02:00 ppp [wan_link0] LCP: LayerFinish May 23 22:02:00 ppp [wan_link0] LCP: Down event May 23 22:02:00 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:02:00 ppp [wan_link0] LCP: Close event May 23 22:02:00 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:02:00 ppp [wan_link0] Link: DOWN event May 23 22:02:00 ppp [wan_link0] PPPoE: connection closed May 23 22:02:00 ppp [wan_link0] LCP: LayerDown May 23 22:02:00 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:02:00 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:02:00 ppp [wan] IPV6CP: Down event May 23 22:02:00 ppp [wan] IPCP: state change Closed --> Initial May 23 22:02:00 ppp [wan] IPCP: Down event May 23 22:02:00 ppp [wan] IPV6CP: Close event May 23 22:02:00 ppp [wan] IPCP: Close event May 23 22:02:00 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:02:00 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:02:00 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:02:00 ppp [wan_link0] LCP: rec'd Terminate Request #74 (Opened) May 23 22:02:00 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:02:00 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:02:00 ppp [wan] IPCP: LayerFinish May 23 22:02:00 ppp [wan] IPCP: state change Closing --> Closed May 23 22:02:00 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:02:00 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:02:00 ppp [wan] IPV6CP: Close event May 23 22:02:00 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:02:00 ppp [wan] IFACE: Down event May 23 22:02:00 ppp [wan] IPCP: LayerDown May 23 22:02:00 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:02:00 ppp [wan] IPCP: state change Opened --> Closing May 23 22:02:00 ppp [wan] IPCP: Close event May 23 22:02:00 ppp [wan] IFACE: Close event May 23 22:02:00 ppp caught fatal signal TERM May 23 22:01:17 ppp [wan] IPV6CP: LayerFinish May 23 22:01:17 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:01:14 ppp [wan] IPV6CP: LayerDown May 23 22:01:14 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:01:14 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:01:14 ppp [wan] IPV6CP: rec'd Terminate Request #96 (Opened) May 23 22:01:14 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:01:14 ppp [wan] IPV6CP: LayerUp May 23 22:01:14 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:01:14 ppp [wan] IPV6CP: SendConfigAck #95 May 23 22:01:14 ppp [wan] IPV6CP: rec'd Configure Request #95 (Ack-Rcvd) May 23 22:01:14 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:01:14 ppp [wan] IFACE: Up event May 23 22:01:14 ppp [wan] 82.57.155.135 -> 192.168.100.1 May 23 22:01:14 ppp [wan] IPCP: LayerUp May 23 22:01:14 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:01:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:01:14 ppp [wan] IPCP: SendConfigAck #126 May 23 22:01:14 ppp [wan] 192.168.100.1 is OK May 23 22:01:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Request #126 (Ack-Rcvd) May 23 22:01:14 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:01:14 ppp [wan] IPADDR 82.57.155.135 May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:01:14 ppp [wan] IPADDR 82.57.155.135 May 23 22:01:14 ppp [wan] IPCP: SendConfigReq #3 May 23 22:01:14 ppp [wan] 82.57.155.135 is OK May 23 22:01:14 ppp [wan] IPADDR 82.57.155.135 May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:01:14 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:01:14 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:01:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:01:14 ppp [wan] IPCP: SendConfigReq #2 May 23 22:01:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:01:14 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:01:14 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:01:14 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:01:14 ppp [wan] IPV6CP: Up event May 23 22:01:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:01:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:01:14 ppp [wan] IPCP: SendConfigReq #1 May 23 22:01:14 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:01:14 ppp [wan] IPCP: Up event May 23 22:01:14 ppp [wan] IPV6CP: LayerStart May 23 22:01:14 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:01:14 ppp [wan] IPV6CP: Open event May 23 22:01:14 ppp [wan] IPCP: LayerStart May 23 22:01:14 ppp [wan] IPCP: state change Initial --> Starting May 23 22:01:14 ppp [wan] IPCP: Open event May 23 22:01:14 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:01:14 ppp [wan_link0] Link: Join bundle "wan" May 23 22:01:14 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:01:14 ppp [wan_link0] LCP: authorization successful May 23 22:01:14 ppp [wan_link0] MESG: REASON021 - TK MISSING: NOT IN DSL-LINE May 23 22:01:14 ppp [wan_link0] PAP: rec'd ACK #1 len: 44 May 23 22:01:14 ppp [wan_link0] LCP: LayerUp May 23 22:01:14 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:01:14 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:01:14 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:01:14 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:01:14 ppp [wan_link0] MAGICNUM 0xb8e86e4c May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] PROTOCOMP May 23 22:01:14 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:01:14 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:01:14 ppp [wan_link0] MAGICNUM 0x5e908b8b May 23 22:01:14 ppp [wan_link0] AUTHPROTO PAP May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] LCP: SendConfigAck #73 May 23 22:01:14 ppp [wan_link0] MAGICNUM 0x5e908b8b May 23 22:01:14 ppp [wan_link0] AUTHPROTO PAP May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] LCP: rec'd Configure Request #73 (Req-Sent) May 23 22:01:14 ppp [wan_link0] MAGICNUM 0xb8e86e4c May 23 22:01:14 ppp [wan_link0] MRU 1492 May 23 22:01:14 ppp [wan_link0] PROTOCOMP May 23 22:01:14 ppp [wan_link0] LCP: SendConfigReq #1 May 23 22:01:14 ppp [wan_link0] LCP: state change Starting --> Req-Sent May 23 22:01:14 ppp [wan_link0] LCP: Up event May 23 22:01:14 ppp [wan_link0] Link: UP event May 23 22:01:14 ppp [wan_link0] PPPoE: connection successful May 23 22:01:14 ppp PPPoE: rec'd ACNAME "r-rm900" May 23 22:01:14 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' May 23 22:01:14 ppp [wan_link0] LCP: LayerStart May 23 22:01:14 ppp [wan_link0] LCP: state change Initial --> Starting May 23 22:01:14 ppp [wan_link0] LCP: Open event May 23 22:01:14 ppp [wan_link0] Link: OPEN event May 23 22:01:14 ppp [wan] Bundle: Interface ng0 created May 23 22:01:14 ppp web: web is not running May 23 22:01:14 ppp process 12700 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) May 23 22:01:14 ppp Multi-link PPP daemon for FreeBSD May 23 22:01:11 ppp process 29992 terminated May 23 22:01:11 ppp [wan_link0] Link: Shutdown May 23 22:01:11 ppp [wan] Bundle: Shutdown May 23 22:01:09 ppp [wan_link0] LCP: Close event May 23 22:01:09 ppp [wan_link0] Link: CLOSE event May 23 22:01:09 ppp [wan_link0] LCP: state change Closing --> Initial May 23 22:01:09 ppp [wan_link0] LCP: LayerFinish May 23 22:01:09 ppp [wan_link0] LCP: Down event May 23 22:01:09 ppp [wan_link0] LCP: state change Stopping --> Closing May 23 22:01:09 ppp [wan_link0] LCP: Close event May 23 22:01:09 ppp [wan_link0] Link: giving up after 0 reconnection attempts May 23 22:01:09 ppp [wan_link0] Link: DOWN event May 23 22:01:09 ppp [wan_link0] PPPoE: connection closed May 23 22:01:09 ppp [wan_link0] LCP: LayerDown May 23 22:01:09 ppp [wan_link0] LCP: SendTerminateAck #2 May 23 22:01:09 ppp [wan] IPV6CP: state change Closed --> Initial May 23 22:01:09 ppp [wan] IPV6CP: Down event May 23 22:01:09 ppp [wan] IPCP: state change Closed --> Initial May 23 22:01:09 ppp [wan] IPCP: Down event May 23 22:01:09 ppp [wan] IPV6CP: Close event May 23 22:01:09 ppp [wan] IPCP: Close event May 23 22:01:09 ppp [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps May 23 22:01:09 ppp [wan_link0] Link: Leave bundle "wan" May 23 22:01:09 ppp [wan_link0] LCP: state change Opened --> Stopping May 23 22:01:09 ppp [wan_link0] LCP: rec'd Terminate Request #19 (Opened) May 23 22:01:09 ppp [wan] Bundle: closing link "wan_link0"... May 23 22:01:09 ppp [wan] Bundle: No NCPs left. Closing links... May 23 22:01:09 ppp [wan] IPCP: LayerFinish May 23 22:01:09 ppp [wan] IPCP: state change Closing --> Closed May 23 22:01:09 ppp [wan] IPCP: rec'd Terminate Ack #4 (Closing) May 23 22:01:09 ppp [wan] IPV6CP: state change Stopped --> Closed May 23 22:01:09 ppp [wan] IPV6CP: Close event May 23 22:01:09 ppp [wan] IFACE: Rename interface pppoe1 to pppoe1 May 23 22:01:09 ppp [wan] IFACE: Down event May 23 22:01:09 ppp [wan] IPCP: LayerDown May 23 22:01:09 ppp [wan] IPCP: SendTerminateReq #4 May 23 22:01:09 ppp [wan] IPCP: state change Opened --> Closing May 23 22:01:09 ppp [wan] IPCP: Close event May 23 22:01:09 ppp [wan] IFACE: Close event May 23 22:01:09 ppp caught fatal signal TERM May 23 22:00:17 ppp [wan] IPV6CP: LayerFinish May 23 22:00:17 ppp [wan] IPV6CP: state change Stopping --> Stopped May 23 22:00:14 ppp [wan] IPV6CP: LayerDown May 23 22:00:14 ppp [wan] IPV6CP: SendTerminateAck #2 May 23 22:00:14 ppp [wan] IPV6CP: state change Opened --> Stopping May 23 22:00:14 ppp [wan] IPV6CP: rec'd Terminate Request #140 (Opened) May 23 22:00:14 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 May 23 22:00:14 ppp [wan] IPV6CP: LayerUp May 23 22:00:14 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened May 23 22:00:14 ppp [wan] IPV6CP: SendConfigAck #139 May 23 22:00:14 ppp [wan] IPV6CP: rec'd Configure Request #139 (Ack-Rcvd) May 23 22:00:14 ppp [wan] IFACE: Rename interface ng0 to pppoe1 May 23 22:00:14 ppp [wan] IFACE: Up event May 23 22:00:14 ppp [wan] 79.18.210.145 -> 192.168.100.1 May 23 22:00:14 ppp [wan] IPCP: LayerUp May 23 22:00:14 ppp [wan] IPCP: state change Ack-Rcvd --> Opened May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:00:14 ppp [wan] IPCP: SendConfigAck #11 May 23 22:00:14 ppp [wan] 192.168.100.1 is OK May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1 May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Request #11 (Ack-Rcvd) May 23 22:00:14 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd May 23 22:00:14 ppp [wan] IPADDR 79.18.210.145 May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) May 23 22:00:14 ppp [wan] IPADDR 79.18.210.145 May 23 22:00:14 ppp [wan] IPCP: SendConfigReq #3 May 23 22:00:14 ppp [wan] 79.18.210.145 is OK May 23 22:00:14 ppp [wan] IPADDR 79.18.210.145 May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) May 23 22:00:14 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd May 23 22:00:14 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) May 23 22:00:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:00:14 ppp [wan] IPCP: SendConfigReq #2 May 23 22:00:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:00:14 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) May 23 22:00:14 ppp [wan] IPV6CP: SendConfigReq #1 May 23 22:00:14 ppp [wan] IPV6CP: state change Starting --> Req-Sent May 23 22:00:14 ppp [wan] IPV6CP: Up event May 23 22:00:14 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid May 23 22:00:14 ppp [wan] IPADDR 0.0.0.0 May 23 22:00:14 ppp [wan] IPCP: SendConfigReq #1 May 23 22:00:14 ppp [wan] IPCP: state change Starting --> Req-Sent May 23 22:00:14 ppp [wan] IPCP: Up event May 23 22:00:14 ppp [wan] IPV6CP: LayerStart May 23 22:00:14 ppp [wan] IPV6CP: state change Initial --> Starting May 23 22:00:14 ppp [wan] IPV6CP: Open event May 23 22:00:14 ppp [wan] IPCP: LayerStart May 23 22:00:14 ppp [wan] IPCP: state change Initial --> Starting May 23 22:00:14 ppp [wan] IPCP: Open event May 23 22:00:14 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps May 23 22:00:14 ppp [wan_link0] Link: Join bundle "wan" May 23 22:00:14 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' May 23 22:00:14 ppp [wan_link0] LCP: authorization successful May 23 22:00:14 ppp [wan_link0] PAP: rec'd ACK #1 len: 5 May 23 22:00:14 ppp [wan_link0] LCP: LayerUp May 23 22:00:14 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 May 23 22:00:14 ppp [wan_link0] PAP: using authname "aliceadsl" May 23 22:00:14 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing May 23 22:00:14 ppp [wan_link0] LCP: state change Ack-Sent --> Opened May 23 22:00:14 ppp [wan_link0] MAGICNUM 0x668d5328 May 23 22:00:14 ppp [wan_link0] MRU 1492 May 23 22:00:14 ppp [wan_link0] PROTOCOMP May 23 22:00:14 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) May 23 22:00:14 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent May 23 22:00:14 ppp [wan_link0] MAGICNUM 0x534f4cbb May 23 22:00:14 ppp [wan_link0] AUTHPROTO PAP May 23 22:00:14 ppp [wan_link0] MRU 1492 May 23 22:00:14 ppp [wan_link0] LCP: SendConfigAck #18 May 23 22:00:14 ppp [wan_link0] MAGICNUM 0x534f4cbbThis seems ok (in the right order) :
May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177
May 23 22:11:26 ppp [wan] 87.21.211.177 is OK
You have your IPv4.
You're up.But then (in the right order) :
May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1
May 23 22:00:14 ppp [wan] 192.168.100.1 is OK
May 23 22:00:14 ppp [wan] IPCP: state change Ack-Rcvd –> Opened
May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1
May 23 22:00:14 ppp [wan] IPCP: SendConfigAck #11
May 23 22:00:14 ppp [wan] IPCP: LayerUp
May 23 22:00:14 ppp [wan] 79.18.210.145 -> 192.168.100.1
May 23 22:00:14 ppp [wan] IFACE: Up eventWhere does this local IP "192.168.100.1" coming from ?
Then the IPv6 starts to kick in.
Btw : aliceadsl (France) supports IPv6 ?
If so, deactivate IPv6, and try to get a good working IPv4 first. -
Same here. After upgrading there was no connection to the internet although ppp had a public ip address.
I have to disconnect and reconnect manually. This problem exists since 2.3.1 and is in most times reproducible after reboot.
Already made a clean install and restored config.
Using a vigor 130 for German adsl telekom. -
This seems ok (in the right order) :
May 23 22:11:26 ppp [wan] IPADDR 87.21.211.177
May 23 22:11:26 ppp [wan] 87.21.211.177 is OK
You have your IPv4.
You're up.But then (in the right order) :
May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1
May 23 22:00:14 ppp [wan] 192.168.100.1 is OK
May 23 22:00:14 ppp [wan] IPCP: state change Ack-Rcvd –> Opened
May 23 22:00:14 ppp [wan] IPADDR 192.168.100.1
May 23 22:00:14 ppp [wan] IPCP: SendConfigAck #11
May 23 22:00:14 ppp [wan] IPCP: LayerUp
May 23 22:00:14 ppp [wan] 79.18.210.145 -> 192.168.100.1
May 23 22:00:14 ppp [wan] IFACE: Up eventWhere does this local IP "192.168.100.1" coming from ?
Then the IPv6 starts to kick in.
Btw : aliceadsl (France) supports IPv6 ?
If so, deactivate IPv6, and try to get a good working IPv4 first.192.168.100.1 is the modem. I have a IPv6 tunnel with Hurricane Electric. I try only IPv4 with a fresh install, same problem with PPPoe. AliceADSL is by TIM Italy (VDSL 100Mb/s).
-
Same here. After upgrading there was no connection to the internet although ppp had a public ip address.
I have to disconnect and reconnect manually. This problem exists since 2.3.1 and is in most times reproducible after reboot.
Already made a clean install and restored config.
Using a vigor 130 for German adsl telekom.Yes, the same happens to my config. But i have problem since 2.3.0 and then 2.3.1. If i disconnect/reconnect 4/5 times, then WAN PPPoe goes up with internet connection.
-
Yesterday i tried another PC with 2 Reltek interface and pfSense 2.3.2 (0528). Same PPPoe on WAN (Telecom Fibra 100Mb/s), same PPPoE error: WAN get dynamic IP from ISP, gateway monitor show WAN gateway down, no internet connection. :-[
Instead, the other firewall with 2.3.1 crashed after running for 3 days. This time disconnect/connect WAN interface doesn't solve problem. Too much problems for now with 2.3.x with PPPoE, rollback on 2.2.6.
There's is any news from pfSense team about this issue?
-
New test with 2.2.6, 2.3.1 and 2.3.2 (snapshot 05312016) and two different hardware (my ITX firewall and Alix 2D). I tried with two different italian ISP with PPPoE connection and modem in bridge mode.
With 2.2.6 pfSense works without any kind of problem: WAN public IP is assigned fron ISP, on gateway widget PPPoE connection on WAN is green. Internet and outboud traffic works normally.
With 2.3.1 and 2.3.2 both ITX and Alix get IP address from ISP, but gateway widget show PPPoE WAN down. No internet outbound traffic and outbound PING. Disconnect and reconnect WAN interface works only some times.
I don't know what other kind of test i can do… I like very much new 2.3 pfSense, but i cannot install on my system. :'(
-
Ran into the same problem yesterday when I did the 2.3.2 upgrade.
It gets a ip on the wan connection but the gateway itself is down.Connected to it remotely using openvpn worked fine….
Ended up reverting to a previous 2.2.6 snapshot and it worked straight away.Also tried doing a fresh install of 2.3.2 same problem appeared same as OP
Ps on Uk BT ppoe
-
Yes, it seems the same error. I'd like to know if pfSense Team can open a bug about this issue.
-
Same problem here.
Alix motherboard and bridged modem, all ok with 2.3.0 but after upgrading to 2.3.1 no more internet access.
If I go to Status->Interfaces and I do a disconnect and the a connect internet is ok.It's surely a bug within version 2.3.1 and hope it will be solved soon.
-
I have been poking around this on the most weird ways using two pfSense installs running on VMware. One being the server and another the client. The only way I found to make it work was to do the following.
-
Edit WAN_PPPOE gateway using 'System > Routing > Gateways > Edit'
1.1) Check 'Use non-local gateway' on advanced options
1.2) Save & Apply -
Edit WAN using 'Interfaces > WAN'
2.1) Add the WAN interface MAC address manually
2.2) Save & Apply
Then after a system reboot, everything magically worked.
-
-
Hi Jeorge, thank you for your post. I'll try tomorrow and let you know if it works. :)
EDIT: try your config. No luck, same error. :(
-
New try, same PPPoE config, v 2.3.1 and 2.3.2 last snapshot. Same error on WAN PPPoE if, gateway down, no internet, no ping. Here is PPP log:
Time Process PID Message Jun 9 18:33:43 ppp [wan] IPV6CP: LayerFinish Jun 9 18:33:43 ppp [wan] IPV6CP: state change Stopping --> Stopped Jun 9 18:33:40 ppp [wan] IPV6CP: LayerDown Jun 9 18:33:40 ppp [wan] IPV6CP: SendTerminateAck #2 Jun 9 18:33:40 ppp [wan] IPV6CP: state change Opened --> Stopping Jun 9 18:33:40 ppp [wan] IPV6CP: rec'd Terminate Request #42 (Opened) Jun 9 18:33:40 ppp [wan] 76d4:35ff:fe8a:b19c -> 02a0:a50f:fc86:8004 Jun 9 18:33:40 ppp [wan] IPV6CP: LayerUp Jun 9 18:33:40 ppp [wan] IPV6CP: state change Ack-Rcvd --> Opened Jun 9 18:33:40 ppp [wan] IPV6CP: SendConfigAck #41 Jun 9 18:33:40 ppp [wan] IPV6CP: rec'd Configure Request #41 (Ack-Rcvd) Jun 9 18:33:40 ppp [wan] IFACE: Rename interface ng0 to pppoe1 Jun 9 18:33:40 ppp [wan] IFACE: Up event Jun 9 18:33:40 ppp [wan] 79.36.219.229 -> 192.168.100.1 Jun 9 18:33:40 ppp [wan] IPCP: LayerUp Jun 9 18:33:40 ppp [wan] IPCP: state change Ack-Rcvd --> Opened Jun 9 18:33:40 ppp [wan] IPADDR 192.168.100.1 Jun 9 18:33:40 ppp [wan] IPCP: SendConfigAck #112 Jun 9 18:33:40 ppp [wan] 192.168.100.1 is OK Jun 9 18:33:40 ppp [wan] IPADDR 192.168.100.1 Jun 9 18:33:40 ppp [wan] IPCP: rec'd Configure Request #112 (Ack-Rcvd) Jun 9 18:33:40 ppp [wan] IPCP: state change Req-Sent --> Ack-Rcvd Jun 9 18:33:40 ppp [wan] IPADDR 79.36.219.229 Jun 9 18:33:40 ppp [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) Jun 9 18:33:40 ppp [wan] IPADDR 79.36.219.229 Jun 9 18:33:40 ppp [wan] IPCP: SendConfigReq #3 Jun 9 18:33:40 ppp [wan] 79.36.219.229 is OK Jun 9 18:33:40 ppp [wan] IPADDR 79.36.219.229 Jun 9 18:33:40 ppp [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) Jun 9 18:33:40 ppp [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd Jun 9 18:33:40 ppp [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) Jun 9 18:33:40 ppp [wan] IPADDR 0.0.0.0 Jun 9 18:33:40 ppp [wan] IPCP: SendConfigReq #2 Jun 9 18:33:40 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Jun 9 18:33:40 ppp [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) Jun 9 18:33:40 ppp [wan] IPV6CP: SendConfigReq #1 Jun 9 18:33:40 ppp [wan] IPV6CP: state change Starting --> Req-Sent Jun 9 18:33:40 ppp [wan] IPV6CP: Up event Jun 9 18:33:40 ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Jun 9 18:33:40 ppp [wan] IPADDR 0.0.0.0 Jun 9 18:33:40 ppp [wan] IPCP: SendConfigReq #1 Jun 9 18:33:40 ppp [wan] IPCP: state change Starting --> Req-Sent Jun 9 18:33:40 ppp [wan] IPCP: Up event Jun 9 18:33:40 ppp [wan] IPV6CP: LayerStart Jun 9 18:33:40 ppp [wan] IPV6CP: state change Initial --> Starting Jun 9 18:33:40 ppp [wan] IPV6CP: Open event Jun 9 18:33:40 ppp [wan] IPCP: LayerStart Jun 9 18:33:40 ppp [wan] IPCP: state change Initial --> Starting Jun 9 18:33:40 ppp [wan] IPCP: Open event Jun 9 18:33:40 ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps Jun 9 18:33:40 ppp [wan_link0] Link: Join bundle "wan" Jun 9 18:33:40 ppp [wan_link0] Link: Matched action 'bundle "wan" ""' Jun 9 18:33:40 ppp [wan_link0] LCP: authorization successful Jun 9 18:33:40 ppp [wan_link0] MESG: REASON021 - TK MISSING: NOT IN DSL-LINE Jun 9 18:33:40 ppp [wan_link0] PAP: rec'd ACK #1 len: 44 Jun 9 18:33:40 ppp [wan_link0] LCP: LayerUp Jun 9 18:33:40 ppp [wan_link0] PAP: sending REQUEST #1 len: 24 Jun 9 18:33:40 ppp [wan_link0] PAP: using authname "aliceadsl" Jun 9 18:33:40 ppp [wan_link0] LCP: auth: peer wants PAP, I want nothing Jun 9 18:33:40 ppp [wan_link0] LCP: state change Ack-Sent --> Opened Jun 9 18:33:40 ppp [wan_link0] MAGICNUM 0x70f5e750 Jun 9 18:33:40 ppp [wan_link0] MRU 1492 Jun 9 18:33:40 ppp [wan_link0] PROTOCOMP Jun 9 18:33:40 ppp [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Jun 9 18:33:40 ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent Jun 9 18:33:40 ppp [wan_link0] MAGICNUM 0x030439d9 Jun 9 18:33:40 ppp [wan_link0] AUTHPROTO PAP Jun 9 18:33:40 ppp [wan_link0] MRU 1492 Jun 9 18:33:40 ppp [wan_link0] LCP: SendConfigAck #213 Jun 9 18:33:40 ppp [wan_link0] MAGICNUM 0x030439d9 Jun 9 18:33:40 ppp [wan_link0] AUTHPROTO PAP Jun 9 18:33:40 ppp [wan_link0] MRU 1492 Jun 9 18:33:40 ppp [wan_link0] LCP: rec'd Configure Request #213 (Req-Sent) Jun 9 18:33:40 ppp [wan_link0] MAGICNUM 0x70f5e750 Jun 9 18:33:40 ppp [wan_link0] MRU 1492 Jun 9 18:33:40 ppp [wan_link0] PROTOCOMP Jun 9 18:33:40 ppp [wan_link0] LCP: SendConfigReq #1 Jun 9 18:33:40 ppp [wan_link0] LCP: state change Starting --> Req-Sent Jun 9 18:33:40 ppp [wan_link0] LCP: Up event Jun 9 18:33:40 ppp [wan_link0] Link: UP event Jun 9 18:33:40 ppp [wan_link0] PPPoE: connection successful Jun 9 18:33:40 ppp PPPoE: rec'd ACNAME "r-rm900" Jun 9 18:33:40 ppp [wan_link0] PPPoE: Connecting to 'TelecomFibra' Jun 9 18:33:40 ppp [wan_link0] LCP: LayerStart Jun 9 18:33:40 ppp [wan_link0] LCP: state change Initial --> Starting Jun 9 18:33:40 ppp [wan_link0] LCP: Open event Jun 9 18:33:40 ppp [wan_link0] Link: OPEN event Jun 9 18:33:40 ppp [wan] Bundle: Interface ng0 created Jun 9 18:33:40 ppp web: web is not running Jun 9 18:33:40 ppp process 5265 started, version 5.8 (root@pfSense_v2_3_1_amd64-pfSense_v2_3_1-job-13 19:20 16-May-2016) Jun 9 18:33:40 ppp Multi-link PPP daemon for FreeBSDNo problem with 2.2.6.
It would be good if pfSense Team let us knbow if they had replicate this issue. I'm trying and trying and trying 2.3.1 and 2.3.2 snapshot, doing test, restore many times my 2.2.6 config without any reply about this issue from team. :(
-
Again :
May 23 22:00:14 ppp [wan] 79.18.210.145 -> 192.168.100.1
Where does this local IP "192.168.100.1" coming from ?
Then the IPv6 starts to kick in.
Btw : aliceadsl (France) supports IPv6 ?
If so, deactivate IPv6, and try to get a good working IPv4 first.192.168.100.1 is the modem. I have a IPv6 tunnel with Hurricane Electric.
WHAT is your modem's IP doing in the 'ppp' log ?
I'm using a ADSL modem mode bridge (so pppoe) for years now.
True, I declared a 'fake' interface so I could connect to the web interface on this modem (on 10.0.0.138 for me)But the modem is just 'tunneling' and the ppp connection shouldn't mention the modem IP at all (I never saw that in my connections logs).
I'm usinh HE.net also, and pfSense is my tunnels (the HE.net) end point. This has nothing to do with the modem, which just converts ADSL signals to "ethernet" RJ45 (to the pfSense box).
About pinger (Monitoring) : it will default to a chosen IP address, often being your gateay upstream - some IP used by your ISP. NOT all of them do reply on ping. In case of doubt, just shut pinger down - its probably just just another issue.
With 2.3.2 snapshot and fresh install i get PPPoE working,
Good !
Keep that version ;)edit :
My 'ppp' log.
You won't find ANY local non routable IP's here …2016-06-08 12:53:11 Daemon.Info 192.168.1.1 Jun 8 12:53:14 ppp: [wan_link0] Link: reconnection attempt 9 in 4 seconds 2016-06-08 12:53:15 User.Notice 192.168.1.1 Jun 8 12:53:18 check_reload_status: Restarting ipsec tunnels 2016-06-08 12:53:15 Daemon.Info 192.168.1.1 Jun 8 12:53:18 ppp: [wan_link0] Link: reconnection attempt 9 2016-06-08 12:53:15 Daemon.Info 192.168.1.1 Jun 8 12:53:18 ppp: [wan_link0] PPPoE: Connecting to '' 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: PPPoE: rec'd ACNAME "BSBOR653-H104L0413L00VZ" 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] PPPoE: connection successful 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] Link: UP event 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: Up event 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: state change Starting --> Req-Sent 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: SendConfigReq #5 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] PROTOCOMP 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MRU 1492 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MAGICNUM 0x3128d37e 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent) 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MRU 1492 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] AUTHPROTO CHAP MD5 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MAGICNUM 0x266e15b2 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: SendConfigAck #2 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MRU 1492 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] AUTHPROTO CHAP MD5 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MAGICNUM 0x266e15b2 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: rec'd Configure Reject #5 (Ack-Sent) 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] PROTOCOMP 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: SendConfigReq #6 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MRU 1492 2016-06-08 12:53:17 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MAGICNUM 0x3128d37e 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: rec'd Configure Ack #6 (Ack-Sent) 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MRU 1492 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MAGICNUM 0x3128d37e 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: state change Ack-Sent --> Opened 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 29 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] Name: "BSTOL653" 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] CHAP: Using authname "fti/kp4jhgj" 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 32 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 43 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] MESG: CHAP authentication success, unit 20212 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: authorization successful 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""' 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] Link: Join bundle "wan" 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: Open event 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: state change Initial --> Starting 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: LayerStart 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: Open event 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: state change Initial --> Starting 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: LayerStart 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: Up event 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: state change Starting --> Req-Sent 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: SendConfigReq #5 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 0.0.0.0 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] PRIDNS 0.0.0.0 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] SECDNS 0.0.0.0 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: Up event 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: state change Starting --> Req-Sent 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: SendConfigReq #2 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 avahi-daemon[89303]: Withdrawing workstation service for pppoe0. 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: rec'd Configure Request #226 (Req-Sent) 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 193.253.160.3 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] 193.253.160.3 is OK 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: SendConfigAck #226 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 193.253.160.3 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: rec'd Configure Reject #5 (Ack-Sent) 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: SendConfigReq #6 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 0.0.0.0 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] PRIDNS 0.0.0.0 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] SECDNS 0.0.0.0 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan_link0] LCP: protocol IPV6CP was rejected 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: protocol was rejected by peer 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: state change Req-Sent --> Stopped 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPV6CP: LayerFinish 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: rec'd Configure Nak #6 (Ack-Sent) 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 109.214.60.150 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] 109.214.60.150 is OK 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] PRIDNS 80.10.246.132 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] SECDNS 81.253.149.2 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: SendConfigReq #7 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 109.214.60.150 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] PRIDNS 80.10.246.132 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] SECDNS 81.253.149.2 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: rec'd Configure Ack #7 (Ack-Sent) 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPADDR 109.214.60.150 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] PRIDNS 80.10.246.132 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] SECDNS 81.253.149.2 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: state change Ack-Sent --> Opened 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] IPCP: LayerUp 2016-06-08 12:53:18 Daemon.Info 192.168.1.1 Jun 8 12:53:20 ppp: [wan] 109.214.60.150 -> 193.253.160.3 2016-06-08 12:53:18 User.Notice 192.168.1.1 Jun 8 12:53:20 check_reload_status: Rewriting resolv.conf 2016-06-08 12:53:19 User.Notice 192.168.1.1 Jun 8 12:53:21 check_reload_status: rc.newwanip starting pppoe0 2016-06-08 12:53:19 Daemon.Info 192.168.1.1 Jun 8 12:53:21 ppp: [wan] IFACE: Up event 2016-06-08 12:53:19 Daemon.Info 192.168.1.1 Jun 8 12:53:21 ppp: [wan] IFACE: Rename interface ng0 to pppoe0afterwards, the GIF interface gets upped, HE.net (ipv6) kicks in, etc.
-
Same here. After upgrading there was no connection to the internet although ppp had a public ip address.
I have to disconnect and reconnect manually. This problem exists since 2.3.1 and is in most times reproducible after reboot.
Already made a clean install and restored config.
Using a vigor 130 for German adsl telekom.I confirm the same problem with 2.3.1 and Telecom Italia as provider. My warkround was to disable the 'fake interface' that i created to access the modem. Since than the connection is stable as never before!
Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.