Automatic connection does not work
-
Re: PFSense 2.4.5-p1 L2TP server ip resolve from FQDN during boot issue
After updating to patch 2.7.0, I ran into the same problem. Previously I used patch 2.6.0, my configuration worked correctly.
The patch that is specified in the branch cannot be applied correctly.
Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: rc.newwanip: Info: starting on em0. Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: rc.newwanip: on (IP address: 10.46.116.110) (interface: WAN[wan]) (real interface: em0). Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: Gateway, switch to: Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: Could not get a PPTP/L2TP Remote IP address from 10.46.116.110 for l2tp.internet.beeline.kz in interfaces_ppps_configure. Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: The command '/sbin/ifconfig 'l2tp0' description 'BEELINE'' returned exit code '1', the output was 'ifconfig: interface l2tp0 does not exist' Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: Gateway, switch to: Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: Gateway, NONE AVAILABLE Aug 18 09:19:27 pfSense php-fpm[383]: /rc.newwanip: route_add_or_change: Invalid gateway and/or network interface l2tp0
-
That patch on that thread was merged two years ago. I was in 2.6 and is in 2.7. You should have had to apply a patch in 2.6. What were you actually applying?
Steve
-
@stephenw10 At the moment I have a clean installation. I don't have any patches installed.
Before the last reboot, I added the name and addresses to DNS Resolver. And now my l2tp connection is triggered automatically on reboot, as before.
But I still see the error Could not get a PPTP/L2TP Remote IP address from 10.46.116.110 for l2tp.internet.beeline.kz in interfaces_ppps_configure.
||```
Aug 18 17:10:13 pfSense check_reload_status[421]: Linkup starting re0
Aug 18 17:10:13 pfSense check_reload_status[421]: Linkup starting re0.10
Aug 18 17:10:14 pfSense php-fpm[382]: /rc.linkup: Ignoring link event during boot sequence.
Aug 18 17:10:14 pfSense php-fpm[383]: /rc.linkup: Ignoring link event during boot sequence.
Aug 18 17:10:14 pfSense kernel: [fib_algo] inet.0 (bsearch4#24) rebuild_fd_flm: switching algo to radix4_lockless
Aug 18 17:10:14 pfSense check_reload_status[421]: rc.newwanip starting em0
Aug 18 17:10:14 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:14 pfSense php-cgi[444]: rc.bootup: Could not get a PPTP/L2TP Remote IP address from 10.46.116.110 for l2tp.internet.beeline.kz in interfaces_ppps_configure.
Aug 18 17:10:14 pfSense php-cgi[444]: rc.bootup: The command '/sbin/ifconfig 'l2tp0' description 'BEELINE'' returned exit code '1', the output was 'ifconfig: interface l2tp0 does not exist'
Aug 18 17:10:14 pfSense kernel: done.
Aug 18 17:10:14 pfSense kernel: done.
Aug 18 17:10:14 pfSense kernel: tun1: changing name to 'ovpnc1'
Aug 18 17:10:14 pfSense php-cgi[444]: rc.bootup: Resyncing OpenVPN instances.
Aug 18 17:10:14 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: The command '/sbin/route -q delete 172.16.26.50' returned exit code '1', the output was 'route: route has not been found'
Aug 18 17:10:15 pfSense kernel: ovpnc1: link state changed to UP
Aug 18 17:10:15 pfSense kernel: tun2: changing name to 'ovpnc2'
Aug 18 17:10:15 pfSense kernel: ovpnc2: link state changed to UP
Aug 18 17:10:15 pfSense kernel: tun3: changing name to 'ovpnc3'
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: The command '/sbin/route -q delete 172.16.26.34' returned exit code '1', the output was 'route: route has not been found'
Aug 18 17:10:15 pfSense kernel: ovpnc3: link state changed to UP
Aug 18 17:10:15 pfSense check_reload_status[421]: rc.newwanip starting ovpnc1
Aug 18 17:10:15 pfSense check_reload_status[421]: rc.newwanip starting ovpnc2
Aug 18 17:10:15 pfSense check_reload_status[421]: rc.newwanip starting ovpnc3
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: The command '/sbin/route -q delete 172.16.26.66' returned exit code '1', the output was 'route: route has not been found'
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:15 pfSense kernel: pflog0: promiscuous mode enabled
Aug 18 17:10:15 pfSense kernel: .
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: [squid] Installed but disabled. Not installing 'nat' rules.
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: [squid] Installed but disabled. Not installing 'pfearly' rules.
Aug 18 17:10:15 pfSense kernel: .
Aug 18 17:10:15 pfSense kernel: ..
Aug 18 17:10:15 pfSense php-cgi[444]: rc.bootup: [squid] Installed but disabled. Not installing 'filter' rules.
Aug 18 17:10:15 pfSense php-fpm[383]: /rc.newwanip: rc.newwanip: Info: starting on em0.
Aug 18 17:10:15 pfSense php-fpm[383]: /rc.newwanip: rc.newwanip: on (IP address: 10.46.116.110) (interface: WAN[wan]) (real interface: em0).
Aug 18 17:10:15 pfSense php-fpm[383]: /rc.newwanip: Gateway, switch to:
Aug 18 17:10:16 pfSense kernel: .done.
Aug 18 17:10:16 pfSense ppp[17894]: Multi-link PPP daemon for FreeBSD
Aug 18 17:10:16 pfSense kernel:
Aug 18 17:10:16 pfSense kernel: ng0: changing name to 'l2tp0'
Aug 18 17:10:16 pfSense php-cgi[444]: rc.bootup: dpinger: status socket /var/run/dpinger_FILIAL_VPN_VPNV4~172.16.26.34~172.16.26.33.sock not found
Aug 18 17:10:16 pfSense php-cgi[444]: rc.bootup: dpinger: No dpinger session running for gateway MAIN_VPN_VPNV4
Aug 18 17:10:16 pfSense php-cgi[444]: rc.bootup: dpinger: No dpinger session running for gateway VSHEP_VPN_VPNV4
Aug 18 17:10:16 pfSense php-cgi[444]: rc.bootup: dpinger: status socket /var/run/dpinger_WAN_DHCP~10.46.116.110~10.46.116.1.sock not found
Aug 18 17:10:16 pfSense php-cgi[444]: rc.bootup: Gateway, switch to:
Aug 18 17:10:16 pfSense php-cgi[444]: rc.bootup: Gateway, NONE AVAILABLE
Aug 18 17:10:16 pfSense php-fpm[24183]: /rc.newwanip: rc.newwanip: Info: starting on ovpnc1.
Aug 18 17:10:16 pfSense php-fpm[24183]: /rc.newwanip: rc.newwanip: on (IP address: 172.16.26.50) (interface: MAIN_VPN[opt1]) (real interface: ovpnc1).
Aug 18 17:10:16 pfSense php-fpm[382]: /rc.newwanip: rc.newwanip: Info: starting on ovpnc2.
Aug 18 17:10:16 pfSense php-fpm[382]: /rc.newwanip: rc.newwanip: on (IP address: 172.16.26.34) (interface: FILIAL_VPN[opt2]) (real interface: ovpnc2).
Aug 18 17:10:16 pfSense php-fpm[382]: /rc.newwanip: rc.newwanip: Info: starting on ovpnc3.
Aug 18 17:10:16 pfSense php-fpm[382]: /rc.newwanip: rc.newwanip: on (IP address: 172.16.26.66) (interface: VSHEP_VPN[opt4]) (real interface: ovpnc3).
Aug 18 17:10:16 pfSense ppp[17894]:
Aug 18 17:10:16 pfSense ppp[17894]: process 17894 started, version 5.9
Aug 18 17:10:16 pfSense ppp[17894]: web: web is not running
Aug 18 17:10:16 pfSense ppp[17894]: [opt3] Bundle: Interface ng0 created
Aug 18 17:10:16 pfSense ppp[17894]: [opt3_link0] Link: OPEN event
Aug 18 17:10:16 pfSense ppp[17894]: [opt3_link0] LCP: Open event
Aug 18 17:10:16 pfSense ppp[17894]: [opt3_link0] LCP: state change Initial --> Starting
Aug 18 17:10:16 pfSense ppp[17894]: [opt3_link0] LCP: LayerStart
Aug 18 17:10:16 pfSense ppp[17894]: L2TP: Initiating control connection 0x3834cd307310 10.46.116.110 0 <-> 80.241.35.5 1701
Aug 18 17:10:18 pfSense php-cgi[444]: rc.bootup: Gateway, switch to: WAN_DHCP
Aug 18 17:10:18 pfSense php-cgi[444]: rc.bootup: Default gateway setting Interface WAN_DHCP Gateway as default.
Aug 18 17:10:18 pfSense rc.gateway_alarm[73843]: >>> Gateway alarm: VSHEP_VPN_VPNV4 (Addr:172.16.26.65 Alarm:1 RTT:0ms RTTsd:0ms Loss:100%)
Aug 18 17:10:18 pfSense rc.gateway_alarm[72372]: >>> Gateway alarm: MAIN_VPN_VPNV4 (Addr:172.16.26.49 Alarm:1 RTT:0ms RTTsd:0ms Loss:100%)
Aug 18 17:10:18 pfSense rc.gateway_alarm[73915]: >>> Gateway alarm: FILIAL_VPN_VPNV4 (Addr:172.16.26.33 Alarm:1 RTT:0ms RTTsd:0ms Loss:100%)
Aug 18 17:10:18 pfSense check_reload_status[421]: updating dyndns FILIAL_VPN_VPNV4
Aug 18 17:10:18 pfSense check_reload_status[421]: updating dyndns MAIN_VPN_VPNV4
Aug 18 17:10:18 pfSense check_reload_status[421]: Restarting IPsec tunnels
Aug 18 17:10:18 pfSense check_reload_status[421]: Restarting IPsec tunnels
Aug 18 17:10:18 pfSense check_reload_status[421]: Restarting OpenVPN tunnels/interfaces
Aug 18 17:10:18 pfSense check_reload_status[421]: Reloading filter
Aug 18 17:10:18 pfSense check_reload_status[421]: Restarting OpenVPN tunnels/interfaces
Aug 18 17:10:18 pfSense check_reload_status[421]: Reloading filter
Aug 18 17:10:18 pfSense kernel: done.
Aug 18 17:10:18 pfSense php-cgi[444]: rc.bootup: sync unbound done.
Aug 18 17:10:19 pfSense ppp[17894]: L2TP: Control connection 0x3834cd307310 10.46.116.110 42046 <-> 80.241.35.5 1701 connected
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] L2TP: Incoming call #1830000 via control connection 0x3834cd307310 initiated
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] L2TP: Call #1830000 connected
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] Link: UP event
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] LCP: Up event
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] LCP: state change Starting --> Req-Sent
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] LCP: SendConfigReq #1
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] ACFCOMP
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] PROTOCOMP
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] MRU 1500
Aug 18 17:10:19 pfSense ppp[17894]: [opt3_link0] MAGICNUM 0x3b169812
Aug 18 17:10:19 pfSense php-fpm[383]: /rc.newwanip: Gateway, NONE AVAILABLE
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MRU 1460
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] AUTHPROTO PAP
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MAGICNUM 0x1b7422d4
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: SendConfigAck #1
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MRU 1460
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] AUTHPROTO PAP
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MAGICNUM 0x1b7422d4
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: state change Req-Sent --> Ack-Sent
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: SendConfigReq #2
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] ACFCOMP
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] PROTOCOMP
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MRU 1500
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MAGICNUM 0x3b169812
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] ACFCOMP
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] PROTOCOMP
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MRU 1500
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] MAGICNUM 0x3b169812
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: state change Ack-Sent --> Opened
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: auth: peer wants PAP, I want nothing
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] PAP: using authname "0013594668"
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] PAP: sending REQUEST #1 len: 24
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: LayerUp
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] PAP: rec'd ACK #1 len: 5
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] LCP: authorization successful
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] Link: Matched action 'bundle "opt3" ""'
Aug 18 17:10:21 pfSense ppp[17894]: [opt3_link0] Link: Join bundle "opt3"
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: Open event
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: state change Initial --> Starting
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: LayerStart
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: Up event
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: state change Starting --> Req-Sent
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: SendConfigReq #1
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 0.0.0.0
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] PRIDNS 0.0.0.0
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] SECDNS 0.0.0.0
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 80.241.35.5
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] 80.241.35.5 is OK
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: SendConfigAck #1
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 80.241.35.5
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: state change Req-Sent --> Ack-Sent
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: SendConfigReq #2
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 0.0.0.0
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] PRIDNS 0.0.0.0
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] SECDNS 0.0.0.0
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 10.211.35.87
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] 10.211.35.87 is OK
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] PRIDNS 5.34.34.5
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] SECDNS 37.99.99.37
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: SendConfigReq #3
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 10.211.35.87
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] PRIDNS 5.34.34.5
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] SECDNS 37.99.99.37
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPADDR 10.211.35.87
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] PRIDNS 5.34.34.5
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] SECDNS 37.99.99.37
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: state change Ack-Sent --> Opened
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] IPCP: LayerUp
Aug 18 17:10:21 pfSense ppp[17894]: [opt3] 10.211.35.87 -> 80.241.35.5
Aug 18 17:10:21 pfSense check_reload_status[421]: Rewriting resolv.conf -
Hmm, so it tries to connect via em0 before it has an IP and fails. Then retries successfully?