Reverse Tunnelling



  • Hi All,

    I have a pfsense box running 2.2.3 and this is use as a backup option in case my main firewall have any hardware issue. I have got a 3G dongle which I have got it to work with the pfsense box but the problem is that the WAN IP is not publicly available.

    I would like to know how I can configure the pfsense box to connect to another production pfsense box with public IP (something like a reverse SSH)? Can we configure such that when the standby pfsense box is powered on, it will auto-connect to my production pfsense box and then I can access the remote side through that connection.

    Office 1 –--- Production FW1 <----- IPSec tunnel -----> Production FW2 ---- Office 2
                  |--- Standby FW      <----- ????? -------------->

    As IPSec to IPSec VPN tunnel requires public IP, I cannot do that for this setup.

    Any comments?



  • OpenVPN should work as it can do a client -> server setup.



  • I have tried to configure OpenVPN in client -> Server setup on my pfsense box but it doesn't work. Is there an example on how to do this on pfsense?



  • https://doc.pfsense.org/index.php/OpenVPN_Site_To_Site

    The server has to be on the end with the public IP. The client can come from behind some non-public ISP thing. I have a few of these from small offices without public IP up to the main office. It works.



  • Hi All,

    Thanks to Phil, I have setup my OpenVPN Server to Client and got it to work but I have another problem. The WAN is using a 3G dongle and after sometime, pfsense disabled the WAN interface, I think due to inactivity.

    In the case where the WAN interface is disabled by the system, how do I enable it back using command line? Will a periodic ping to the OpenVPN server let the system left the WAN interface stay enabled?

    Thanks & Regards.


  • Netgate Administrator

    OpenVPN itslef should keep the connection up via keep-alives. Gateway monitoring will also do that.

    How does the WAN fail, what is logged? Is it connected via ppp? Do you have an idle timeout set?

    Steve



  • From friday to monday, where there is no activity except my cron job to ping the OpenVPN server. I login on monday and access the webconfigurator page, check the dashboard and saw the WAN interface is disabled.

    The WAN interface is configured to use PPP connection as this is a 3G dongle. I don't know if there is an idle timeout setting.

    To bring the connection back on, I go to the WAN interface and click save and apply changes. Check the dashboard and the WAN interface is active again and the OpenVPN client is connecting to my remote server.


  • Netgate Administrator

    The WAN is actually disabled? Or just down?

    We'd need to see what is logged in the system or ppp logs to have any idea why that happened.

    The idle timeout is in the ppp settings. It's usually set to 0 (disabled) by default.

    Steve



  • On 22 May at around 03:08 AM, the connection went down and on 25 May at around 12:07 PM, I login and found the WAN interface is disabled, I went to enable it back on.

    Content of system.log when the connection broke:

    
    May 22 03:08:17 myfw01s ppp: [wan_link0] LCP: rec'd Terminate Request #10 (Opened)
    May 22 03:08:17 myfw01s ppp: [wan_link0] LCP: state change Opened --> Stopping
    May 22 03:08:17 myfw01s ppp: [wan_link0] Link: Leave bundle "wan"
    May 22 03:08:17 myfw01s ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: Close event
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: state change Opened --> Closing
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: SendTerminateReq #19
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: LayerDown
    May 22 03:08:17 myfw01s check_reload_status: Rewriting resolv.conf
    May 22 03:08:17 myfw01s ppp: [wan] IFACE: Delete route 0.0.0.0/0 10.64.64.0 failed: No such process
    May 22 03:08:17 myfw01s ppp: [wan] IFACE: Down event
    May 22 03:08:17 myfw01s ppp: [wan] IFACE: Rename interface ppp0 to ppp0
    May 22 03:08:17 myfw01s ppp: [wan] IPV6CP: Close event
    May 22 03:08:17 myfw01s ppp: [wan] IPV6CP: state change Stopped --> Closed
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: Down event
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: LayerFinish
    May 22 03:08:17 myfw01s ppp: [wan] Bundle: No NCPs left. Closing links...
    May 22 03:08:17 myfw01s ppp: [wan] IPCP: state change Closing --> Initial
    May 22 03:08:17 myfw01s ppp: [wan] IPV6CP: Down event
    May 22 03:08:17 myfw01s ppp: [wan] IPV6CP: state change Closed --> Initial
    May 22 03:08:17 myfw01s ppp: [wan_link0] LCP: SendTerminateAck #6
    May 22 03:08:17 myfw01s ppp: [wan_link0] LCP: LayerDown
    May 22 03:08:19 myfw01s ppp: [wan_link0] LCP: state change Stopping --> Stopped
    May 22 03:08:19 myfw01s ppp: [wan_link0] LCP: LayerFinish
    May 22 03:08:19 myfw01s ppp: [wan_link0] Link: DOWN event
    May 22 03:08:19 myfw01s ppp: [wan_link0] LCP: Down event
    May 22 03:08:19 myfw01s ppp: [wan_link0] LCP: state change Stopped --> Starting
    May 22 03:08:19 myfw01s ppp: [wan_link0] LCP: LayerStart
    May 22 03:08:19 myfw01s ppp: [wan_link0] Link: reconnection attempt 1 in 2 seconds
    May 22 03:08:21 myfw01s ppp: [wan_link0] Link: reconnection attempt 1
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAT: Detected Hayes compatible modem.
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAT: Dialing server at *99#...
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAT: ATDT*99#
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAT: Connected at an unknown speed.
    May 22 03:08:22 myfw01s ppp: [wan_link0] MODEM: chat script succeeded
    May 22 03:08:22 myfw01s ppp: [wan_link0] Link: UP event
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: Up event
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: state change Starting --> Req-Sent
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: SendConfigReq #7
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACFCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACCMAP 0x000a0000
    May 22 03:08:22 myfw01s ppp: [wan_link0]   MRU 1500
    May 22 03:08:22 myfw01s ppp: [wan_link0]   MAGICNUM 31e51da2
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: rec'd Configure Request #11 (Req-Sent)
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 22 03:08:22 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 22 03:08:22 myfw01s ppp: [wan_link0]   MAGICNUM 0da69c92
    May 22 03:08:22 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACFCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: SendConfigAck #11
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 22 03:08:22 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 22 03:08:22 myfw01s ppp: [wan_link0]   MAGICNUM 0da69c92
    May 22 03:08:22 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACFCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: rec'd Configure Ack #7 (Ack-Sent)
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACFCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 22 03:08:22 myfw01s ppp: [wan_link0]   ACCMAP 0x000a0000
    May 22 03:08:22 myfw01s ppp: [wan_link0]   MRU 1500
    May 22 03:08:22 myfw01s ppp: [wan_link0]   MAGICNUM 31e51da2
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: LayerUp
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: rec'd Discard Request #12 (Opened)
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 35
    May 22 03:08:22 myfw01s ppp: [wan_link0]   Name: "UMTS_CHAP_SRVR"
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAP: Using authname "user"
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 25
    May 22 03:08:22 myfw01s ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 4
    May 22 03:08:22 myfw01s ppp: [wan_link0] LCP: authorization successful
    May 22 03:08:22 myfw01s ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
    May 22 03:08:22 myfw01s ppp: [wan_link0] Link: Join bundle "wan"
    May 22 03:08:22 myfw01s ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 28800 bps
    May 22 03:08:22 myfw01s ppp: [wan] IPCP: Open event
    May 22 03:08:22 myfw01s ppp: [wan] IPCP: state change Initial --> Starting
    May 22 03:08:22 myfw01s ppp: [wan] IPCP: LayerStart
    May 22 03:08:22 myfw01s ppp: [wan] IPV6CP: Open event
    May 22 03:08:22 myfw01s ppp: [wan] IPV6CP: state change Initial --> Starting
    May 22 03:08:22 myfw01s ppp: [wan] IPV6CP: LayerStart
    May 22 03:08:22 myfw01s ppp: [wan] IPCP: Up event
    May 22 03:08:22 myfw01s ppp: [wan] IPCP: state change Starting --> Req-Sent
    May 22 03:08:22 myfw01s ppp: [wan] IPCP: SendConfigReq #20
    May 22 03:08:22 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:22 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:22 myfw01s ppp: [wan]   PRIDNS 0.0.0.0
    May 22 03:08:22 myfw01s ppp: [wan]   SECDNS 0.0.0.0
    May 22 03:08:22 myfw01s ppp: [wan] IPV6CP: Up event
    May 22 03:08:22 myfw01s ppp: [wan] IPV6CP: state change Starting --> Req-Sent
    May 22 03:08:22 myfw01s ppp: [wan] IPV6CP: SendConfigReq #31
    May 22 03:08:23 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #20 (Req-Sent)
    May 22 03:08:23 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:23 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:23 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:23 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:23 myfw01s ppp: [wan] IPCP: SendConfigReq #21
    May 22 03:08:23 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:23 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:23 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:23 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:24 myfw01s ppp: [wan] IPV6CP: SendConfigReq #32
    May 22 03:08:24 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #21 (Req-Sent)
    May 22 03:08:24 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:24 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:24 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:24 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:24 myfw01s ppp: [wan] IPCP: SendConfigReq #22
    May 22 03:08:24 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:24 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:24 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:24 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:25 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #22 (Req-Sent)
    May 22 03:08:25 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:25 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:25 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:25 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:25 myfw01s ppp: [wan] IPCP: SendConfigReq #23
    May 22 03:08:25 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:25 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:25 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:25 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:26 myfw01s ppp: [wan] IPV6CP: SendConfigReq #33
    May 22 03:08:26 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #23 (Req-Sent)
    May 22 03:08:26 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:26 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:26 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:26 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:26 myfw01s ppp: [wan] IPCP: SendConfigReq #24
    May 22 03:08:26 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:26 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:26 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:26 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:27 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #24 (Req-Sent)
    May 22 03:08:27 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:27 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:27 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:27 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:27 myfw01s ppp: [wan] IPCP: SendConfigReq #25
    May 22 03:08:27 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:27 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:27 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:27 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:28 myfw01s ppp: [wan] IPV6CP: SendConfigReq #34
    May 22 03:08:28 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #25 (Req-Sent)
    May 22 03:08:28 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:28 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:28 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:28 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:28 myfw01s ppp: [wan] IPCP: SendConfigReq #26
    May 22 03:08:28 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:28 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:28 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:28 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:29 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #26 (Req-Sent)
    May 22 03:08:29 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:29 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:29 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:29 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:29 myfw01s ppp: [wan] IPCP: SendConfigReq #27
    May 22 03:08:29 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:29 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:29 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:29 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:30 myfw01s ppp: [wan] IPV6CP: SendConfigReq #35
    May 22 03:08:30 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #27 (Req-Sent)
    May 22 03:08:30 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:30 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:30 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:30 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:30 myfw01s ppp: [wan] IPCP: SendConfigReq #28
    May 22 03:08:30 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:30 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:30 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:30 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:31 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #28 (Req-Sent)
    May 22 03:08:31 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:31 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:31 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:31 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:31 myfw01s ppp: [wan] IPCP: SendConfigReq #29
    May 22 03:08:31 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:31 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:31 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:31 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:32 myfw01s ppp: [wan] IPV6CP: SendConfigReq #36
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #29 (Req-Sent)
    May 22 03:08:32 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:32 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:32 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:32 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: not converging
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: parameter negotiation failed
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: state change Req-Sent --> Stopped
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: LayerFinish
    May 22 03:08:34 myfw01s ppp: [wan] IPV6CP: SendConfigReq #37
    May 22 03:08:36 myfw01s ppp: [wan] IPV6CP: SendConfigReq #38
    May 22 03:08:38 myfw01s ppp: [wan] IPV6CP: SendConfigReq #39
    May 22 03:08:40 myfw01s ppp: [wan] IPV6CP: SendConfigReq #40
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: parameter negotiation failed
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: LayerFinish
    May 22 03:08:42 myfw01s ppp: [wan] Bundle: No NCPs left. Closing links...
    May 22 03:08:42 myfw01s ppp: [wan] Bundle: closing link "wan_link0"...
    May 22 03:08:42 myfw01s ppp: [wan_link0] Link: CLOSE event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: Close event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: state change Opened --> Closing
    May 22 03:08:42 myfw01s ppp: [wan_link0] Link: Leave bundle "wan"
    May 22 03:08:42 myfw01s ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: Close event
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: state change Stopped --> Closed
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: Close event
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: state change Stopped --> Closed
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: Down event
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: state change Closed --> Initial
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: Down event
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: state change Closed --> Initial
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: SendTerminateReq #8
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: LayerDown
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: rec'd Terminate Ack #8 (Closing)
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: state change Closing --> Closed
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: LayerFinish
    May 22 03:08:42 myfw01s ppp: [wan_link0] Link: DOWN event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: Down event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: state change Closed --> Initial
    May 22 03:09:14 myfw01s kernel: ovpnc1: link state changed to DOWN
    May 22 03:09:14 myfw01s check_reload_status: Reloading filter
    May 22 03:09:15 myfw01s php-fpm[23463]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan).
    May 22 03:09:15 myfw01s php-fpm[23463]: /rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan).
    May 23 01:01:00 myfw01s php: rc.dyndns.update: DynDns: updatedns() starting
    May 23 01:01:00 myfw01s php: rc.dyndns.update: DynDNS (myfw01s.mooo.com): running get_failover_interface for wan. found ppp0
    May 23 01:01:00 myfw01s php: rc.dyndns.update: DynDNS (myfw01s.mooo.com) There was an error trying to determine the public IP for interface - wan(ppp0). Probably interface is not a WAN interface.
    May 24 01:01:00 myfw01s php: rc.dyndns.update: DynDns: updatedns() starting
    May 24 01:01:00 myfw01s php: rc.dyndns.update: DynDNS (myfw01s.mooo.com): running get_failover_interface for wan. found ppp0
    May 24 01:01:00 myfw01s php: rc.dyndns.update: DynDNS (myfw01s.mooo.com) There was an error trying to determine the public IP for interface - wan(ppp0). Probably interface is not a WAN interface.
    May 25 01:01:00 myfw01s php: rc.dyndns.update: DynDns: updatedns() starting
    May 25 01:01:00 myfw01s php: rc.dyndns.update: DynDNS (myfw01s.mooo.com): running get_failover_interface for wan. found ppp0
    May 25 01:01:00 myfw01s php: rc.dyndns.update: DynDNS (myfw01s.mooo.com) There was an error trying to determine the public IP for interface - wan(ppp0). Probably interface is not a WAN interface.
    May 25 12:07:37 myfw01s php-fpm[23463]: /index.php: Successful login for user 'lawrence' from: 10.60.3.1
    May 25 12:07:37 myfw01s php-fpm[23463]: /index.php: Successful login for user 'lawrence' from: 10.60.3.1
    May 25 12:08:13 myfw01s check_reload_status: Syncing firewall
    May 25 12:08:15 myfw01s ppp: caught fatal signal TERM
    May 25 12:08:15 myfw01s ppp: [wan] IFACE: Close event
    May 25 12:08:15 myfw01s ppp: [wan] IPCP: Close event
    May 25 12:08:15 myfw01s ppp: [wan] IPV6CP: Close event
    May 25 12:08:17 myfw01s ppp: [wan] Bundle: Shutdown
    May 25 12:08:17 myfw01s ppp: [wan_link0] Link: Shutdown
    May 25 12:08:17 myfw01s ppp: process 6163 terminated
    May 25 12:08:17 myfw01s ppp: Multi-link PPP daemon for FreeBSD
    May 25 12:08:17 myfw01s ppp:
    May 25 12:08:17 myfw01s ppp: process 12576 started, version 5.7 (root@pfSense_RELENG_2_2_amd64_amd64-pfSense_RELENG_2_2-job-01 00:07  9-Jun-2015)
    May 25 12:08:17 myfw01s ppp: web: web is not running
    May 25 12:08:17 myfw01s ppp: [wan] Bundle: Interface ng0 created
    May 25 12:08:17 myfw01s ppp: [wan_link0] Link: OPEN event
    May 25 12:08:17 myfw01s kernel: ng0: changing name to 'ppp0'
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: Open event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: state change Initial --> Starting
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: LayerStart
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: +CGDCONT=1,"IP","celcom3g"
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: Detected Hayes compatible modem.
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: Dialing server at *99#...
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: ATDT*99#
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: Connected at an unknown speed.
    May 25 12:08:17 myfw01s ppp: [wan_link0] MODEM: chat script succeeded
    May 25 12:08:17 myfw01s ppp: [wan_link0] Link: UP event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: Up event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: state change Starting --> Req-Sent
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: SendConfigReq #1
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACFCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACCMAP 0x000a0000
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MRU 1500
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MAGICNUM 4c164a60
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: rec'd Configure Request #13 (Req-Sent)
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 25 12:08:17 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MAGICNUM 1f061408
    May 25 12:08:17 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACFCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: SendConfigAck #13
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 25 12:08:17 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MAGICNUM 1f061408
    May 25 12:08:17 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACFCOMP
    
    

    Content of ppp.log when the connection broke:

    
    May 22 03:08:31 myfw01s ppp: [wan] IPCP: SendConfigReq #29
    May 22 03:08:31 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 22 03:08:31 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 22 03:08:31 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:31 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:32 myfw01s ppp: [wan] IPV6CP: SendConfigReq #36
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #29 (Req-Sent)
    May 22 03:08:32 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 22 03:08:32 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 22 03:08:32 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 22 03:08:32 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: not converging
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: parameter negotiation failed
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: state change Req-Sent --> Stopped
    May 22 03:08:32 myfw01s ppp: [wan] IPCP: LayerFinish
    May 22 03:08:34 myfw01s ppp: [wan] IPV6CP: SendConfigReq #37
    May 22 03:08:36 myfw01s ppp: [wan] IPV6CP: SendConfigReq #38
    May 22 03:08:38 myfw01s ppp: [wan] IPV6CP: SendConfigReq #39
    May 22 03:08:40 myfw01s ppp: [wan] IPV6CP: SendConfigReq #40
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: parameter negotiation failed
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: LayerFinish
    May 22 03:08:42 myfw01s ppp: [wan] Bundle: No NCPs left. Closing links...
    May 22 03:08:42 myfw01s ppp: [wan] Bundle: closing link "wan_link0"...
    May 22 03:08:42 myfw01s ppp: [wan_link0] Link: CLOSE event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: Close event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: state change Opened --> Closing
    May 22 03:08:42 myfw01s ppp: [wan_link0] Link: Leave bundle "wan"
    May 22 03:08:42 myfw01s ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: Close event
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: state change Stopped --> Closed
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: Close event
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: state change Stopped --> Closed
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: Down event
    May 22 03:08:42 myfw01s ppp: [wan] IPCP: state change Closed --> Initial
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: Down event
    May 22 03:08:42 myfw01s ppp: [wan] IPV6CP: state change Closed --> Initial
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: SendTerminateReq #8
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: LayerDown
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: rec'd Terminate Ack #8 (Closing)
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: state change Closing --> Closed
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: LayerFinish
    May 22 03:08:42 myfw01s ppp: [wan_link0] Link: DOWN event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: Down event
    May 22 03:08:42 myfw01s ppp: [wan_link0] LCP: state change Closed --> Initial
    May 25 12:08:15 myfw01s ppp: caught fatal signal TERM
    May 25 12:08:15 myfw01s ppp: [wan] IFACE: Close event
    May 25 12:08:15 myfw01s ppp: [wan] IPCP: Close event
    May 25 12:08:15 myfw01s ppp: [wan] IPV6CP: Close event
    May 25 12:08:17 myfw01s ppp: [wan] Bundle: Shutdown
    May 25 12:08:17 myfw01s ppp: [wan_link0] Link: Shutdown
    May 25 12:08:17 myfw01s ppp: process 6163 terminated
    May 25 12:08:17 myfw01s ppp: Multi-link PPP daemon for FreeBSD
    May 25 12:08:17 myfw01s ppp:
    May 25 12:08:17 myfw01s ppp: process 12576 started, version 5.7 (root@pfSense_RELENG_2_2_amd64_amd64-pfSense_RELENG_2_2-job-01 00:07  9-Jun-2015)
    May 25 12:08:17 myfw01s ppp: web: web is not running
    May 25 12:08:17 myfw01s ppp: [wan] Bundle: Interface ng0 created
    May 25 12:08:17 myfw01s ppp: [wan_link0] Link: OPEN event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: Open event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: state change Initial --> Starting
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: LayerStart
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: +CGDCONT=1,"IP","celcom3g"
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: Detected Hayes compatible modem.
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: Dialing server at *99#...
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: ATDT*99#
    May 25 12:08:17 myfw01s ppp: [wan_link0] CHAT: Connected at an unknown speed.
    May 25 12:08:17 myfw01s ppp: [wan_link0] MODEM: chat script succeeded
    May 25 12:08:17 myfw01s ppp: [wan_link0] Link: UP event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: Up event
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: state change Starting --> Req-Sent
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: SendConfigReq #1
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACFCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACCMAP 0x000a0000
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MRU 1500
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MAGICNUM 4c164a60
    May 25 12:08:17 myfw01s ppp: [wan_link0] LCP: rec'd Configure Request #13 (Req-Sent)
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 25 12:08:17 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 25 12:08:17 myfw01s ppp: [wan_link0]   MAGICNUM 1f061408
    May 25 12:08:17 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 25 12:08:17 myfw01s ppp: [wan_link0]   ACFCOMP
    
    


  • First log fragment, first line :

    May 22 03:08:17 myfw01s ppp: [wan_link0] LCP: rec'd Terminate Request #10 (Opened)
    

    Your PPP connection received a "Terminate Request".
    So, the connection was being terminated properly, to be rebuild right away.
    But :
    When reconnecting, you have a lot of

    May 22 03:08:23 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #20 (Req-Sent)
    

    What I make of it : the other side doesn't (or can't) give you an IPv4 WAN IP (two DNS's, yes, but no WAN IP).

    There is a mismatch between send and received # config number (like 36 send and 29 received). The 3G connection went bad ?


  • Netgate Administrator

    Yeah, it looks like the provider does not send an IP address for the client to use for some reason.

    Hard to say why it would actually be disabled though. Are you sure it is? It could just appear that way. Re-saving the interface kicks in a whole bunch of stuff.

    Steve



  • I login today and found the WAN interface is offline and this is what is shown on the console:

    
    *** Welcome to pfSense 2.2.3-RELEASE-nanobsd (amd64) on myfw01s ***
    
     WAN (wan)       -> ppp0       ->
     LAN (lan)       -> em1        -> v4: 10.60.3.10/16
     0) Logout (SSH only)                  9) pfTop
     1) Assign Interfaces                 10) Filter Logs
     2) Set interface(s) IP address       11) Restart webConfigurator
     3) Reset webConfigurator password    12) pfSense Developer Shell
     4) Reset to factory defaults         13) Upgrade from console
     5) Reboot system                     14) Disable Secure Shell (sshd)
     6) Halt system                       15) Restore recent configuration
     7) Ping host                         16) Restart PHP-FPM
     8) Shell
    
    Enter an option:
    
    

    Looking at the system log, it does try to redial the connection but somehow it is not working to bring the WAN interface up.

    
    May 31 14:18:05 myfw01s ppp: [wan_link0] LCP: rec'd Terminate Request #29 (Opened)
    May 31 14:18:05 myfw01s ppp: [wan_link0] LCP: state change Opened --> Stopping
    May 31 14:18:05 myfw01s ppp: [wan_link0] Link: Leave bundle "wan"
    May 31 14:18:05 myfw01s ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: Close event
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: state change Opened --> Closing
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: SendTerminateReq #17
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: LayerDown
    May 31 14:18:05 myfw01s check_reload_status: Rewriting resolv.conf
    May 31 14:18:05 myfw01s ppp: [wan] IFACE: Delete route 0.0.0.0/0 10.64.64.0 failed: No such process
    May 31 14:18:05 myfw01s ppp: [wan] IFACE: Down event
    May 31 14:18:05 myfw01s ppp: [wan] IFACE: Rename interface ppp0 to ppp0
    May 31 14:18:05 myfw01s ppp: [wan] IPV6CP: Close event
    May 31 14:18:05 myfw01s ppp: [wan] IPV6CP: state change Stopped --> Closed
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: Down event
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: LayerFinish
    May 31 14:18:05 myfw01s ppp: [wan] Bundle: No NCPs left. Closing links...
    May 31 14:18:05 myfw01s ppp: [wan] IPCP: state change Closing --> Initial
    May 31 14:18:05 myfw01s ppp: [wan] IPV6CP: Down event
    May 31 14:18:05 myfw01s ppp: [wan] IPV6CP: state change Closed --> Initial
    May 31 14:18:05 myfw01s ppp: [wan_link0] LCP: SendTerminateAck #4
    May 31 14:18:05 myfw01s ppp: [wan_link0] LCP: LayerDown
    May 31 14:18:07 myfw01s ppp: [wan_link0] LCP: state change Stopping --> Stopped
    May 31 14:18:07 myfw01s ppp: [wan_link0] LCP: LayerFinish
    May 31 14:18:08 myfw01s ppp: [wan_link0] Link: DOWN event
    May 31 14:18:08 myfw01s ppp: [wan_link0] LCP: Down event
    May 31 14:18:08 myfw01s ppp: [wan_link0] LCP: state change Stopped --> Starting
    May 31 14:18:08 myfw01s ppp: [wan_link0] LCP: LayerStart
    May 31 14:18:08 myfw01s ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds
    May 31 14:18:09 myfw01s ppp: [wan_link0] Link: reconnection attempt 1
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAT: Detected Hayes compatible modem.
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAT: Dialing server at *99#...
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAT: ATDT*99#
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAT: Connected at an unknown speed.
    May 31 14:18:11 myfw01s ppp: [wan_link0] MODEM: chat script succeeded
    May 31 14:18:11 myfw01s ppp: [wan_link0] Link: UP event
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: Up event
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: state change Starting --> Req-Sent
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: SendConfigReq #5
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACFCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACCMAP 0x000a0000
    May 31 14:18:11 myfw01s ppp: [wan_link0]   MRU 1500
    May 31 14:18:11 myfw01s ppp: [wan_link0]   MAGICNUM a6abc118
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: rec'd Configure Request #30 (Req-Sent)
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 31 14:18:11 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 31 14:18:11 myfw01s ppp: [wan_link0]   MAGICNUM 3e650d54
    May 31 14:18:11 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACFCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: SendConfigAck #30
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACCMAP 0x00000000
    May 31 14:18:11 myfw01s ppp: [wan_link0]   AUTHPROTO CHAP MD5
    May 31 14:18:11 myfw01s ppp: [wan_link0]   MAGICNUM 3e650d54
    May 31 14:18:11 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACFCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: rec'd Configure Ack #5 (Ack-Sent)
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACFCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0]   PROTOCOMP
    May 31 14:18:11 myfw01s ppp: [wan_link0]   ACCMAP 0x000a0000
    May 31 14:18:11 myfw01s ppp: [wan_link0]   MRU 1500
    May 31 14:18:11 myfw01s ppp: [wan_link0]   MAGICNUM a6abc118
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: LayerUp
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: rec'd Discard Request #31 (Opened)
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 35
    May 31 14:18:11 myfw01s ppp: [wan_link0]   Name: "UMTS_CHAP_SRVR"
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAP: Using authname "user"
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 25
    May 31 14:18:11 myfw01s ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 4
    May 31 14:18:11 myfw01s ppp: [wan_link0] LCP: authorization successful
    May 31 14:18:11 myfw01s ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
    May 31 14:18:11 myfw01s ppp: [wan_link0] Link: Join bundle "wan"
    May 31 14:18:11 myfw01s ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 28800 bps
    May 31 14:18:11 myfw01s ppp: [wan] IPCP: Open event
    May 31 14:18:11 myfw01s ppp: [wan] IPCP: state change Initial --> Starting
    May 31 14:18:11 myfw01s ppp: [wan] IPCP: LayerStart
    May 31 14:18:11 myfw01s ppp: [wan] IPV6CP: Open event
    May 31 14:18:11 myfw01s ppp: [wan] IPV6CP: state change Initial --> Starting
    May 31 14:18:11 myfw01s ppp: [wan] IPV6CP: LayerStart
    May 31 14:18:11 myfw01s ppp: [wan] IPCP: Up event
    May 31 14:18:11 myfw01s ppp: [wan] IPCP: state change Starting --> Req-Sent
    May 31 14:18:11 myfw01s ppp: [wan] IPCP: SendConfigReq #18
    May 31 14:18:11 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:11 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:11 myfw01s ppp: [wan]   PRIDNS 0.0.0.0
    May 31 14:18:11 myfw01s ppp: [wan]   SECDNS 0.0.0.0
    May 31 14:18:11 myfw01s ppp: [wan] IPV6CP: Up event
    May 31 14:18:11 myfw01s ppp: [wan] IPV6CP: state change Starting --> Req-Sent
    May 31 14:18:11 myfw01s ppp: [wan] IPV6CP: SendConfigReq #21
    May 31 14:18:12 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #18 (Req-Sent)
    May 31 14:18:12 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:12 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:12 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:12 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:12 myfw01s ppp: [wan] IPCP: SendConfigReq #19
    May 31 14:18:12 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:12 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:12 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:12 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:13 myfw01s ppp: [wan] IPV6CP: SendConfigReq #22
    May 31 14:18:13 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #19 (Req-Sent)
    May 31 14:18:13 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:13 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:13 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:13 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:13 myfw01s ppp: [wan] IPCP: SendConfigReq #20
    May 31 14:18:13 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:13 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:13 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:13 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:14 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #20 (Req-Sent)
    May 31 14:18:14 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:14 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:14 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:14 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:14 myfw01s ppp: [wan] IPCP: SendConfigReq #21
    May 31 14:18:14 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:14 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:14 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:14 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:15 myfw01s ppp: [wan] IPV6CP: SendConfigReq #23
    May 31 14:18:15 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #21 (Req-Sent)
    May 31 14:18:15 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:15 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:15 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:15 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:15 myfw01s ppp: [wan] IPCP: SendConfigReq #22
    May 31 14:18:15 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:15 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:15 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:15 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:16 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #22 (Req-Sent)
    May 31 14:18:16 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:16 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:16 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:16 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:16 myfw01s ppp: [wan] IPCP: SendConfigReq #23
    May 31 14:18:16 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:16 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:16 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:16 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:17 myfw01s ppp: [wan] IPV6CP: SendConfigReq #24
    May 31 14:18:17 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #23 (Req-Sent)
    May 31 14:18:17 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:17 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:17 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:17 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:17 myfw01s ppp: [wan] IPCP: SendConfigReq #24
    May 31 14:18:17 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:17 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:17 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:17 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:18 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #24 (Req-Sent)
    May 31 14:18:18 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:18 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:18 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:18 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:18 myfw01s ppp: [wan] IPCP: SendConfigReq #25
    May 31 14:18:18 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:18 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:18 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:18 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:19 myfw01s ppp: [wan] IPV6CP: SendConfigReq #25
    May 31 14:18:19 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #25 (Req-Sent)
    May 31 14:18:19 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:19 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:19 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:19 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:19 myfw01s ppp: [wan] IPCP: SendConfigReq #26
    May 31 14:18:19 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:19 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:19 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:19 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:20 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #26 (Req-Sent)
    May 31 14:18:20 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:20 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:20 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:20 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:20 myfw01s ppp: [wan] IPCP: SendConfigReq #27
    May 31 14:18:20 myfw01s ppp: [wan]   IPADDR 0.0.0.0
    May 31 14:18:20 myfw01s ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    May 31 14:18:20 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:20 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:21 myfw01s ppp: [wan] IPV6CP: SendConfigReq #26
    May 31 14:18:21 myfw01s ppp: [wan] IPCP: rec'd Configure Nak #27 (Req-Sent)
    May 31 14:18:21 myfw01s ppp: [wan]   PRIDNS 10.11.12.13
    May 31 14:18:21 myfw01s ppp: [wan]   SECDNS 10.11.12.14
    May 31 14:18:21 myfw01s ppp: [wan]   PRINBNS 10.11.12.13
    May 31 14:18:21 myfw01s ppp: [wan]   SECNBNS 10.11.12.14
    May 31 14:18:21 myfw01s ppp: [wan] IPCP: not converging
    May 31 14:18:21 myfw01s ppp: [wan] IPCP: parameter negotiation failed
    May 31 14:18:21 myfw01s ppp: [wan] IPCP: state change Req-Sent --> Stopped
    May 31 14:18:21 myfw01s ppp: [wan] IPCP: LayerFinish
    May 31 14:18:23 myfw01s ppp: [wan] IPV6CP: SendConfigReq #27
    May 31 14:18:25 myfw01s ppp: [wan] IPV6CP: SendConfigReq #28
    May 31 14:18:27 myfw01s ppp: [wan] IPV6CP: SendConfigReq #29
    May 31 14:18:29 myfw01s ppp: [wan] IPV6CP: SendConfigReq #30
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: parameter negotiation failed
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: LayerFinish
    May 31 14:18:31 myfw01s ppp: [wan] Bundle: No NCPs left. Closing links...
    May 31 14:18:31 myfw01s ppp: [wan] Bundle: closing link "wan_link0"...
    May 31 14:18:31 myfw01s ppp: [wan_link0] Link: CLOSE event
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: Close event
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: state change Opened --> Closing
    May 31 14:18:31 myfw01s ppp: [wan_link0] Link: Leave bundle "wan"
    May 31 14:18:31 myfw01s ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    May 31 14:18:31 myfw01s ppp: [wan] IPCP: Close event
    May 31 14:18:31 myfw01s ppp: [wan] IPCP: state change Stopped --> Closed
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: Close event
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: state change Stopped --> Closed
    May 31 14:18:31 myfw01s ppp: [wan] IPCP: Down event
    May 31 14:18:31 myfw01s ppp: [wan] IPCP: state change Closed --> Initial
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: Down event
    May 31 14:18:31 myfw01s ppp: [wan] IPV6CP: state change Closed --> Initial
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: SendTerminateReq #6
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: LayerDown
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: rec'd Terminate Ack #6 (Closing)
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: state change Closing --> Closed
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: LayerFinish
    May 31 14:18:31 myfw01s ppp: [wan_link0] Link: DOWN event
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: Down event
    May 31 14:18:31 myfw01s ppp: [wan_link0] LCP: state change Closed --> Initial
    May 31 14:19:06 myfw01s kernel: ovpnc1: link state changed to DOWN
    May 31 14:19:06 myfw01s check_reload_status: Reloading filter
    
    

  • Netgate Administrator

    Massively late reply…...

    Still looks like they just don't send you an IP address so it just keeps asking.

    No clue what that could be be.  :-\

    Steve


Log in to reply