Dec 5 18:41:04 pfSense ppp: process 9957 started, version 5.8 (nobody@pfSense_v2_4_4_amd64-pfSense_v2_4_4-job-04 23:36 4-Sep-2018) Dec 5 18:41:04 pfSense ppp: web: web is not running Dec 5 18:41:04 pfSense ppp: [opt1] Bundle: Interface ng0 created Dec 5 18:41:04 pfSense ppp: [opt1_link0] Link: OPEN event Dec 5 18:41:04 pfSense ppp: [opt1_link0] LCP: Open event Dec 5 18:41:04 pfSense ppp: [opt1_link0] LCP: state change Initial --> Starting Dec 5 18:41:04 pfSense ppp: [opt1_link0] LCP: LayerStart Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: +CPIN? Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: +CPIN: SIM PIN Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: +CGDCONT=1,"IP","websfr" Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: Detected Custom modem. Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: Dialing server at *99#... Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: ATDT*99# Dec 5 18:41:05 pfSense ppp: [opt1_link0] CHAT: The remote modem did not answer. Dec 5 18:41:05 pfSense ppp: [opt1_link0] MODEM: chat script failed Dec 5 18:41:05 pfSense ppp: [opt1_link0] Link: DOWN event Dec 5 18:41:05 pfSense ppp: [opt1_link0] LCP: Down event Dec 5 18:41:05 pfSense ppp: [opt1_link0] Link: reconnection attempt 1 in 3 seconds Dec 5 18:41:08 pfSense ppp: [opt1_link0] Link: reconnection attempt 1 Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: +CPIN? Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: +CPIN: READY Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: +CGDCONT=1,"IP","websfr" Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: Detected Custom modem. Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: Dialing server at *99#... Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: ATDT*99# Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAT: Connected at 150000000. Dec 5 18:41:09 pfSense ppp: [opt1_link0] MODEM: chat script succeeded Dec 5 18:41:09 pfSense ppp: [opt1_link0] Link: UP event Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: Up event Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: state change Starting --> Req-Sent Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: SendConfigReq #1 Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACFCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] PROTOCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACCMAP 0x000a0000 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MRU 1500 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MAGICNUM 0x5263fb11 Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: rec'd Configure Request #1 (Req-Sent) Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACFCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] PROTOCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACCMAP 0x00000000 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MRU 1500 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MAGICNUM 0x00000543 Dec 5 18:41:09 pfSense ppp: [opt1_link0] AUTHPROTO CHAP MD5 Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: SendConfigAck #1 Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACFCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] PROTOCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACCMAP 0x00000000 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MRU 1500 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MAGICNUM 0x00000543 Dec 5 18:41:09 pfSense ppp: [opt1_link0] AUTHPROTO CHAP MD5 Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: state change Req-Sent --> Ack-Sent Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACFCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] PROTOCOMP Dec 5 18:41:09 pfSense ppp: [opt1_link0] ACCMAP 0x000a0000 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MRU 1500 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MAGICNUM 0x5263fb11 Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: state change Ack-Sent --> Opened Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: auth: peer wants CHAP, I want nothing Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: LayerUp Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAP: rec'd CHALLENGE #1 len: 37 Dec 5 18:41:09 pfSense ppp: [opt1_link0] Name: "HUAWEI_CHAP_SRVR" Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAP: Using authname "user" Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAP: sending RESPONSE #1 len: 25 Dec 5 18:41:09 pfSense ppp: [opt1_link0] CHAP: rec'd SUCCESS #1 len: 13 Dec 5 18:41:09 pfSense ppp: [opt1_link0] MESG: Welcome!! Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: authorization successful Dec 5 18:41:09 pfSense ppp: [opt1_link0] Link: Matched action 'bundle "opt1" ""' Dec 5 18:41:09 pfSense ppp: [opt1_link0] Link: Join bundle "opt1" Dec 5 18:41:09 pfSense ppp: [opt1] Bundle: Status update: up 1 link, total bandwidth 150000000 bps Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: Open event Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: state change Initial --> Starting Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: LayerStart Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: Open event Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: state change Initial --> Starting Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: LayerStart Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: Up event Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: state change Starting --> Req-Sent Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: SendConfigReq #1 Dec 5 18:41:09 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:09 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:09 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:09 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: Up event Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: state change Starting --> Req-Sent Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: SendConfigReq #1 Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: rec'd Configure Request #1 (Req-Sent) Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: SendConfigAck #1 Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: state change Req-Sent --> Ack-Sent Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: rec'd Configure Nak #1 (Ack-Sent) Dec 5 18:41:09 pfSense ppp: [opt1] IPCP: SendConfigReq #2 Dec 5 18:41:09 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:09 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:09 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:09 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: rec'd Protocol Reject #2 (Opened) Dec 5 18:41:09 pfSense ppp: [opt1_link0] LCP: protocol IPV6CP was rejected Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: protocol was rejected by peer Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: state change Req-Sent --> Stopped Dec 5 18:41:09 pfSense ppp: [opt1] IPV6CP: LayerFinish Dec 5 18:41:11 pfSense ppp: [opt1] IPCP: SendConfigReq #3 Dec 5 18:41:11 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:11 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:11 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:11 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:13 pfSense ppp: [opt1] IPCP: SendConfigReq #4 Dec 5 18:41:13 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:13 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:13 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:13 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:15 pfSense ppp: [opt1] IPCP: SendConfigReq #5 Dec 5 18:41:15 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:15 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:15 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:15 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:17 pfSense ppp: [opt1] IPCP: SendConfigReq #6 Dec 5 18:41:17 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:17 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:17 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:17 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:19 pfSense ppp: [opt1] IPCP: SendConfigReq #7 Dec 5 18:41:19 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:19 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:19 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:19 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:21 pfSense ppp: [opt1] IPCP: SendConfigReq #8 Dec 5 18:41:21 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:21 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:21 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:21 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:23 pfSense ppp: [opt1] IPCP: SendConfigReq #9 Dec 5 18:41:23 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:23 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:23 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:23 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:25 pfSense ppp: [opt1] IPCP: SendConfigReq #10 Dec 5 18:41:25 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:25 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:25 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:25 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:27 pfSense ppp: [opt1] IPCP: SendConfigReq #11 Dec 5 18:41:27 pfSense ppp: [opt1] IPADDR 0.0.0.0 Dec 5 18:41:27 pfSense ppp: [opt1] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 5 18:41:27 pfSense ppp: [opt1] PRIDNS 0.0.0.0 Dec 5 18:41:27 pfSense ppp: [opt1] SECDNS 0.0.0.0 Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: parameter negotiation failed Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: state change Ack-Sent --> Stopped Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: LayerFinish Dec 5 18:41:29 pfSense ppp: [opt1] Bundle: No NCPs left. Closing links... Dec 5 18:41:29 pfSense ppp: [opt1] Bundle: closing link "opt1_link0"... Dec 5 18:41:29 pfSense ppp: [opt1_link0] Link: CLOSE event Dec 5 18:41:29 pfSense ppp: [opt1_link0] LCP: Close event Dec 5 18:41:29 pfSense ppp: [opt1_link0] LCP: state change Opened --> Closing Dec 5 18:41:29 pfSense ppp: [opt1_link0] Link: Leave bundle "opt1" Dec 5 18:41:29 pfSense ppp: [opt1] Bundle: Status update: up 0 links, total bandwidth 9600 bps Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: Close event Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: state change Stopped --> Closed Dec 5 18:41:29 pfSense ppp: [opt1] IPV6CP: Close event Dec 5 18:41:29 pfSense ppp: [opt1] IPV6CP: state change Stopped --> Closed Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: Down event Dec 5 18:41:29 pfSense ppp: [opt1] IPCP: state change Closed --> Initial Dec 5 18:41:29 pfSense ppp: [opt1] IPV6CP: Down event Dec 5 18:41:29 pfSense ppp: [opt1] IPV6CP: state change Closed --> Initial Dec 5 18:41:29 pfSense ppp: [opt1_link0] LCP: SendTerminateReq #2 Dec 5 18:41:29 pfSense ppp: [opt1_link0] LCP: LayerDown Dec 5 18:41:31 pfSense ppp: [opt1_link0] LCP: SendTerminateReq #3 Dec 5 18:41:33 pfSense ppp: [opt1_link0] LCP: state change Closing --> Closed Dec 5 18:41:33 pfSense ppp: [opt1_link0] LCP: LayerFinish Dec 5 18:41:33 pfSense ppp: [opt1_link0] Link: DOWN event Dec 5 18:41:33 pfSense ppp: [opt1_link0] LCP: Down event Dec 5 18:41:33 pfSense ppp: [opt1_link0] LCP: state change Closed --> Initial