WAN IP gets renewed every 20, 40 minutes; sometimes periodic
-
Dear folks,
After some days of configuring my pfSense box, finally my new machine is now running and serving my home network :)
Thanks for this piece of software!
However, yesterday I noticed that my WAN IP address gets renewed very often. I think there is some certain pattern behind this renewal proccess. Here are some of the log entries:
Oct 4 05:42:24 myrouter php-fpm[94729]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 06:22:14 myrouter php-fpm[69347]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 06:22:26 myrouter php-fpm[26752]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 06:41:55 myrouter php-fpm[34159]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 06:42:10 myrouter php-fpm[34159]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 07:10:21 myrouter php-fpm[75321]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 07:10:36 myrouter php-fpm[56360]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 07:30:17 myrouter php-fpm[84123]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX Oct 4 07:30:29 myrouter php-fpm[62175]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 09:02:22 myrouter php-fpm[48626]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 09:02:33 myrouter php-fpm[39048]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 09:22:28 myrouter php-fpm[53915]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 09:22:47 myrouter php-fpm[55204]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 10:02:24 myrouter php-fpm[8283]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX.. Oct 4 10:02:36 myrouter php-fpm[89764]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 10:22:30 myrouter php-fpm[3786]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 10:22:42 myrouter php-fpm[80581]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 11:06:28 myrouter php-fpm[56010]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX. Oct 4 11:06:48 myrouter php-fpm[87495]: /rc.newwanip: IP has changed, killing states on former IP XXX.XXX.XXX.XXX.You can see that the ip address gets renewed at times xx:42 or xx:22 more often than at other times.
The WAN IP interface is right now an usb ethernet adapter. I know they are not recommended, but I'm still not sure, if there might be also other reasons behind this renewal proccess?
The cron based reset is configured as Reset at each day ("0 0 * * *").
Any help would be highly appreciated! :)
Thanks for reading!
Cheers!
-
Your ISP has a DHCP lease time of 20 minutes, I'm guessing.
-
Thanks for your posting.
The ISP renews the IP address every 24 hours. I used exactly the same modem with a different router a few days ago and could rely on a stable internet connection which gets resetted only once a day (normally during night time).
However, since I shifted this setup and replaced the old router by pfsense, the ip address gets renewed multiple times a day. Sometimes this leads to VoIP talks getting interrupted… That's not so nice...
I still belief there might be some sort of proccess which performs this reset?
Somewhere I came accross the point "gateway monitoring"... Is there any link between this and my ip resets?
-
Yeah on your monitoring make sure you turn off the kill states option when monitor thinks the link is down because of high latency in the pings.
-
Hey,
thanks so far! I disabled Gateway Monitoring but it didn't solve this problem…
I'm using USB->Ethernet Adapter ASIX AX88178. Do you think the problem is related to this usb adapter or are there any ways to solve this issue?
-
Hey,
since the automatic disconnect happens now multiple times per hour, I'm getting nuts!
I attached a log file of such a complete reconnect. It is pretty long and pfsense tries multiple reconnects, until the link gets established!
Oct 10 12:48:34 myrouter check_reload_status: Linkup starting ue0 Oct 10 12:48:34 myrouter kernel: ue0: link state changed to DOWN Oct 10 12:48:34 myrouter kernel: ue0: link state changed to UP Oct 10 12:48:34 myrouter check_reload_status: Linkup starting ue0 Oct 10 12:48:34 myrouter kernel: ue0: link state changed to DOWN Oct 10 12:48:34 myrouter kernel: ue0: link state changed to UP Oct 10 12:48:34 myrouter check_reload_status: Linkup starting ue0 Oct 10 12:48:34 myrouter kernel: ue0: link state changed to DOWN Oct 10 12:48:34 myrouter kernel: ue0: link state changed to UP Oct 10 12:48:34 myrouter check_reload_status: Linkup starting ue0 Oct 10 12:48:34 myrouter check_reload_status: Linkup starting ue0 Oct 10 12:48:34 myrouter check_reload_status: Linkup starting ue0 Oct 10 12:48:35 myrouter check_reload_status: Reloading filter Oct 10 12:48:35 myrouter ppp: Multi-link PPP daemon for FreeBSD Oct 10 12:48:35 myrouter ppp: Oct 10 12:48:35 myrouter ppp: process 32686 started, version 5.8 (root@pfSense_v2_3_2_amd64-pfSense_v2_3_2-job-04 16:03 19-Jul-2016) Oct 10 12:48:35 myrouter ppp: waiting for process 17987 to die... Oct 10 12:48:35 myrouter ppp: caught fatal signal TERM Oct 10 12:48:35 myrouter ppp: [wan] IFACE: Close event Oct 10 12:48:35 myrouter ppp: [wan] IPCP: Close event Oct 10 12:48:35 myrouter ppp: [wan] IPCP: state change Opened --> Closing Oct 10 12:48:35 myrouter ppp: [wan] IPCP: SendTerminateReq #4 Oct 10 12:48:35 myrouter ppp: [wan] IPCP: LayerDown Oct 10 12:48:35 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:48:35 myrouter ppp: [wan] IFACE: Delete route 0.0.0.0/0 217.0.118.150 failed: No such process Oct 10 12:48:35 myrouter ppp: [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Oct 10 12:48:35 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:48:35 myrouter ppp: [wan] IPV6CP: state change Opened --> Closing Oct 10 12:48:35 myrouter ppp: [wan] IPV6CP: SendTerminateReq #2 Oct 10 12:48:35 myrouter ppp: [wan] IPV6CP: LayerDown Oct 10 12:48:36 myrouter ppp: waiting for process 17987 to die... Oct 10 12:48:36 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:48:36 myrouter ppp: [wan] IFACE: Down event Oct 10 12:48:36 myrouter ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0 Oct 10 12:48:36 myrouter ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing) Oct 10 12:48:36 myrouter ppp: [wan] IPCP: state change Closing --> Closed Oct 10 12:48:36 myrouter ppp: [wan] IPCP: LayerFinish Oct 10 12:48:36 myrouter ppp: [wan] IPV6CP: rec'd Terminate Ack #2 (Closing) Oct 10 12:48:36 myrouter ppp: [wan] IPV6CP: state change Closing --> Closed Oct 10 12:48:36 myrouter ppp: [wan] IPV6CP: LayerFinish Oct 10 12:48:36 myrouter ppp: [wan] Bundle: No NCPs left. Closing links... Oct 10 12:48:36 myrouter ppp: [wan] Bundle: closing link "wan_link0"... Oct 10 12:48:36 myrouter ppp: [wan_link0] Link: CLOSE event Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: Close event Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: state change Opened --> Closing Oct 10 12:48:36 myrouter ppp: [wan_link0] Link: Leave bundle "wan" Oct 10 12:48:36 myrouter ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps Oct 10 12:48:36 myrouter ppp: [wan] IPCP: Close event Oct 10 12:48:36 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:48:36 myrouter ppp: [wan] IPCP: Down event Oct 10 12:48:36 myrouter ppp: [wan] IPCP: state change Closed --> Initial Oct 10 12:48:36 myrouter ppp: [wan] IPV6CP: Down event Oct 10 12:48:36 myrouter ppp: [wan] IPV6CP: state change Closed --> Initial Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: SendTerminateReq #2 Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: LayerDown Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing) Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: state change Closing --> Closed Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: LayerFinish Oct 10 12:48:36 myrouter ppp: [wan_link0] Link: DOWN event Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: Down event Oct 10 12:48:36 myrouter ppp: [wan_link0] LCP: state change Closed --> Initial Oct 10 12:48:37 myrouter ppp: waiting for process 17987 to die... Oct 10 12:48:38 myrouter ppp: waiting for process 17987 to die... Oct 10 12:48:38 myrouter ppp: [wan] Bundle: Shutdown Oct 10 12:48:38 myrouter ppp: [wan_link0] Link: Shutdown Oct 10 12:48:38 myrouter ppp: process 17987 terminated Oct 10 12:48:39 myrouter ppp: web: web is not running Oct 10 12:48:39 myrouter ppp: [wan] Bundle: Interface ng0 created Oct 10 12:48:39 myrouter ppp: [wan_link0] Link: OPEN event Oct 10 12:48:39 myrouter kernel: ng0: changing name to 'pppoe0' Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: Open event Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: state change Initial --> Starting Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: LayerStart Oct 10 12:48:39 myrouter ppp: [wan_link0] PPPoE: Connecting to '' Oct 10 12:48:39 myrouter ppp: PPPoE: rec'd ACNAME "AUGX45-erx" Oct 10 12:48:39 myrouter ppp: [wan_link0] PPPoE: connection successful Oct 10 12:48:39 myrouter ppp: [wan_link0] Link: UP event Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: Up event Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: state change Starting --> Req-Sent Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: SendConfigReq #1 Oct 10 12:48:39 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:48:39 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:39 myrouter ppp: [wan_link0] MAGICNUM 0xe985658e Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: rec'd Configure Request #36 (Req-Sent) Oct 10 12:48:39 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:39 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:48:39 myrouter ppp: [wan_link0] MAGICNUM 0x4a26b4f9 Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: SendConfigAck #36 Oct 10 12:48:39 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:39 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:48:39 myrouter ppp: [wan_link0] MAGICNUM 0x4a26b4f9 Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Oct 10 12:48:39 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:48:39 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:39 myrouter ppp: [wan_link0] MAGICNUM 0xe985658e Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: state change Ack-Sent --> Opened Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing Oct 10 12:48:39 myrouter ppp: [wan_link0] PAP: using authname "MYLOGIN" Oct 10 12:48:39 myrouter ppp: [wan_link0] PAP: sending REQUEST #1 len: 54 Oct 10 12:48:39 myrouter ppp: [wan_link0] LCP: LayerUp Oct 10 12:48:40 myrouter ppp: [wan_link0] PAP: rec'd ACK #1 len: 5 Oct 10 12:48:40 myrouter ppp: [wan_link0] LCP: authorization successful Oct 10 12:48:40 myrouter ppp: [wan_link0] Link: Matched action 'bundle "wan" ""' Oct 10 12:48:40 myrouter ppp: [wan_link0] Link: Join bundle "wan" Oct 10 12:48:40 myrouter ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps Oct 10 12:48:40 myrouter ppp: [wan] IPCP: Open event Oct 10 12:48:40 myrouter ppp: [wan] IPCP: state change Initial --> Starting Oct 10 12:48:40 myrouter ppp: [wan] IPCP: LayerStart Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: Open event Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: state change Initial --> Starting Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: LayerStart Oct 10 12:48:40 myrouter ppp: [wan] IPCP: Up event Oct 10 12:48:40 myrouter ppp: [wan] IPCP: state change Starting --> Req-Sent Oct 10 12:48:40 myrouter ppp: [wan] IPCP: SendConfigReq #1 Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 0.0.0.0 Oct 10 12:48:40 myrouter ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 10 12:48:40 myrouter ppp: [wan] PRIDNS 0.0.0.0 Oct 10 12:48:40 myrouter ppp: [wan] SECDNS 0.0.0.0 Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: Up event Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: state change Starting --> Req-Sent Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: SendConfigReq #1 Oct 10 12:48:40 myrouter ppp: [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) Oct 10 12:48:40 myrouter ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 10 12:48:40 myrouter ppp: [wan] IPCP: SendConfigReq #2 Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 0.0.0.0 Oct 10 12:48:40 myrouter ppp: [wan] PRIDNS 0.0.0.0 Oct 10 12:48:40 myrouter ppp: [wan] SECDNS 0.0.0.0 Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) Oct 10 12:48:40 myrouter ppp: [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd Oct 10 12:48:40 myrouter ppp: [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 79.206.151.72 Oct 10 12:48:40 myrouter ppp: [wan] 79.206.151.72 is OK Oct 10 12:48:40 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:48:40 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:48:40 myrouter ppp: [wan] IPCP: SendConfigReq #3 Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 79.206.151.72 Oct 10 12:48:40 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:48:40 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:48:40 myrouter ppp: [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 79.206.151.72 Oct 10 12:48:40 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:48:40 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:48:40 myrouter ppp: [wan] IPCP: state change Req-Sent --> Ack-Rcvd Oct 10 12:48:40 myrouter ppp: [wan] IPCP: rec'd Configure Request #32 (Ack-Rcvd) Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 217.0.118.150 Oct 10 12:48:40 myrouter ppp: [wan] 217.0.118.150 is OK Oct 10 12:48:40 myrouter ppp: [wan] IPCP: SendConfigAck #32 Oct 10 12:48:40 myrouter ppp: [wan] IPADDR 217.0.118.150 Oct 10 12:48:40 myrouter ppp: [wan] IPCP: state change Ack-Rcvd --> Opened Oct 10 12:48:40 myrouter ppp: [wan] IPCP: LayerUp Oct 10 12:48:40 myrouter ppp: [wan] 79.206.151.72 -> 217.0.118.150 Oct 10 12:48:40 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:48:41 myrouter check_reload_status: rc.newwanip starting pppoe0 Oct 10 12:48:41 myrouter ppp: [wan] IFACE: Up event Oct 10 12:48:41 myrouter ppp: [wan] IFACE: Rename interface ng0 to pppoe0 Oct 10 12:48:41 myrouter ppp: [wan] IPV6CP: rec'd Configure Request #211 (Ack-Rcvd) Oct 10 12:48:41 myrouter ppp: [wan] IPV6CP: SendConfigAck #211 Oct 10 12:48:41 myrouter ppp: [wan] IPV6CP: state change Ack-Rcvd --> Opened Oct 10 12:48:41 myrouter ppp: [wan] IPV6CP: LayerUp Oct 10 12:48:41 myrouter ppp: [wan] 92fb:a6ff:fe2a:70af -> 0090:1a00:01a1:0cd7 Oct 10 12:48:42 myrouter kernel: nd6_dad_timer: called with non-tentative address fe80:8::92fb:a6ff:fe2a:70af(pppoe0) Oct 10 12:48:43 myrouter check_reload_status: rc.newwanipv6 starting pppoe0 Oct 10 12:48:45 myrouter php-fpm[64794]: /rc.linkup: Accept router advertisements on interface ue0 Oct 10 12:48:45 myrouter php-fpm[64794]: /rc.linkup: ROUTING: setting default route to 217.0.118.150 Oct 10 12:48:45 myrouter php-fpm[64794]: /rc.linkup: ROUTING: setting IPv6 default route to fe80::90:1a00:1a1:cd7%pppoe0 Oct 10 12:48:45 myrouter check_reload_status: Restarting ipsec tunnels Oct 10 12:48:48 myrouter php-fpm[64794]: /rc.linkup: The command '/usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpd.conf -pf /var/run/dhcpd.pid nfe0' returned exit code '1', the output was 'Internet Systems Consortium DHCP Server 4.3.4 Copyright 2004-2016 Internet Systems Consortium. All rights reserved. For info, please visit https://www.isc.org/software/dhcp/ Config file: /etc/dhcpd.conf Database file: /var/db/dhcpd.leases PID file: /var/run/dhcpd.pid Wrote 18 leases to leases file. Listening on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Sending on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Can't bind to dhcp address: Address already in use Please make sure there is no other dhcp server running and that there's no entry for dhcp or bootp in /etc/inetd.conf. Also make sure you are not running HP JetAdmin software, which includes a bootp server. If you think you have received this message due to a bug rather than a configuration issue please read the section on submitting bugs on ei Oct 10 12:48:49 myrouter check_reload_status: updating dyndns wan Oct 10 12:48:49 myrouter check_reload_status: Reloading filter Oct 10 12:48:49 myrouter check_reload_status: Reloading filter Oct 10 12:48:49 myrouter ppp: Multi-link PPP daemon for FreeBSD Oct 10 12:48:49 myrouter ppp: Oct 10 12:48:49 myrouter ppp: process 52922 started, version 5.8 (root@pfSense_v2_3_2_amd64-pfSense_v2_3_2-job-04 16:03 19-Jul-2016) Oct 10 12:48:49 myrouter ppp: waiting for process 32686 to die... Oct 10 12:48:49 myrouter ppp: caught fatal signal TERM Oct 10 12:48:49 myrouter ppp: [wan] IFACE: Close event Oct 10 12:48:49 myrouter ppp: [wan] IPCP: Close event Oct 10 12:48:49 myrouter ppp: [wan] IPCP: state change Opened --> Closing Oct 10 12:48:49 myrouter ppp: [wan] IPCP: SendTerminateReq #4 Oct 10 12:48:49 myrouter ppp: [wan] IPCP: LayerDown Oct 10 12:48:50 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:48:50 myrouter xinetd[17684]: Swapping defaults Oct 10 12:48:50 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:48:50 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:48:50 myrouter php-fpm[33552]: /rc.newwanip: rc.newwanip: Info: starting on pppoe0. Oct 10 12:48:50 myrouter php-fpm[33552]: /rc.newwanip: rc.newwanip: on (IP address: ) (interface: WAN[wan]) (real interface: pppoe0). Oct 10 12:48:50 myrouter php-fpm[33552]: /rc.newwanip: rc.newwanip: Failed to update wan IP, restarting... Oct 10 12:48:50 myrouter check_reload_status: Configuring interface wan Oct 10 12:48:50 myrouter php-fpm[33552]: /rc.newwanipv6: rc.newwanipv6: Info: starting on pppoe0. Oct 10 12:48:50 myrouter php-fpm[33552]: /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan]. Oct 10 12:48:50 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:48:50 myrouter ppp: [wan] IFACE: Delete route 0.0.0.0/0 217.0.118.150 failed: No such process Oct 10 12:48:50 myrouter ppp: [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Oct 10 12:48:50 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:48:50 myrouter ppp: [wan] IPV6CP: state change Opened --> Closing Oct 10 12:48:50 myrouter ppp: [wan] IPV6CP: SendTerminateReq #2 Oct 10 12:48:50 myrouter ppp: [wan] IPV6CP: LayerDown Oct 10 12:48:50 myrouter ppp: waiting for process 32686 to die... Oct 10 12:48:51 myrouter php-fpm[33552]: /rc.dyndns.update: Dynamic DNS (DYNDNSHOSTNAME) There was an error trying to determine the public IP for interface - wan (pppoe0 ). Oct 10 12:48:51 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:48:51 myrouter ppp: [wan] IFACE: Down event Oct 10 12:48:51 myrouter ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0 Oct 10 12:48:51 myrouter ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing) Oct 10 12:48:51 myrouter ppp: [wan] IPCP: state change Closing --> Closed Oct 10 12:48:51 myrouter ppp: [wan] IPCP: LayerFinish Oct 10 12:48:51 myrouter ppp: [wan] IPV6CP: rec'd Terminate Ack #2 (Closing) Oct 10 12:48:51 myrouter ppp: [wan] IPV6CP: state change Closing --> Closed Oct 10 12:48:51 myrouter ppp: [wan] IPV6CP: LayerFinish Oct 10 12:48:51 myrouter ppp: [wan] Bundle: No NCPs left. Closing links... Oct 10 12:48:51 myrouter ppp: [wan] Bundle: closing link "wan_link0"... Oct 10 12:48:51 myrouter ppp: [wan_link0] Link: CLOSE event Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: Close event Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: state change Opened --> Closing Oct 10 12:48:51 myrouter ppp: [wan_link0] Link: Leave bundle "wan" Oct 10 12:48:51 myrouter ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps Oct 10 12:48:51 myrouter ppp: [wan] IPCP: Close event Oct 10 12:48:51 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:48:51 myrouter ppp: [wan] IPCP: Down event Oct 10 12:48:51 myrouter ppp: [wan] IPCP: state change Closed --> Initial Oct 10 12:48:51 myrouter ppp: [wan] IPV6CP: Down event Oct 10 12:48:51 myrouter ppp: [wan] IPV6CP: state change Closed --> Initial Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: SendTerminateReq #2 Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: LayerDown Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing) Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: state change Closing --> Closed Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: LayerFinish Oct 10 12:48:51 myrouter ppp: [wan_link0] Link: DOWN event Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: Down event Oct 10 12:48:51 myrouter ppp: [wan_link0] LCP: state change Closed --> Initial Oct 10 12:48:51 myrouter ppp: waiting for process 32686 to die... Oct 10 12:48:52 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:48:52 myrouter xinetd[17684]: Swapping defaults Oct 10 12:48:52 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:48:52 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:48:52 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:48:52 myrouter xinetd[17684]: Swapping defaults Oct 10 12:48:52 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:48:52 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:48:52 myrouter ppp: Multi-link PPP daemon for FreeBSD Oct 10 12:48:52 myrouter ppp: Oct 10 12:48:52 myrouter ppp: process 63331 started, version 5.8 (root@pfSense_v2_3_2_amd64-pfSense_v2_3_2-job-04 16:03 19-Jul-2016) Oct 10 12:48:52 myrouter ppp: waiting for process 32686 to die... Oct 10 12:48:52 myrouter ppp: waiting for process 32686 to die... Oct 10 12:48:53 myrouter ppp: [wan] Bundle: Shutdown Oct 10 12:48:53 myrouter ppp: [wan_link0] Link: Shutdown Oct 10 12:48:53 myrouter ppp: process 32686 terminated Oct 10 12:48:53 myrouter rtsold[44679]: <rtsock_input_ifannounce> interface pppoe0 removed Oct 10 12:48:53 myrouter ppp: web: web is not running Oct 10 12:48:53 myrouter ppp: [wan] Bundle: Interface ng0 created Oct 10 12:48:53 myrouter rtsold[44679]: <rtsock_input_ifannounce> interface ng0 removed Oct 10 12:48:53 myrouter kernel: ng0: changing name to 'pppoe0' Oct 10 12:48:53 myrouter ppp: [wan_link0] Link: OPEN event Oct 10 12:48:53 myrouter ppp: [wan_link0] LCP: Open event Oct 10 12:48:53 myrouter ppp: [wan_link0] LCP: state change Initial --> Starting Oct 10 12:48:53 myrouter ppp: [wan_link0] LCP: LayerStart Oct 10 12:48:53 myrouter ppp: [wan_link0] PPPoE: Connecting to '' Oct 10 12:48:53 myrouter ppp: waiting for process 63331 to die... Oct 10 12:48:53 myrouter ppp: caught fatal signal TERM Oct 10 12:48:53 myrouter ppp: [wan] IFACE: Close event Oct 10 12:48:53 myrouter ppp: [wan] IPCP: Close event Oct 10 12:48:53 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:48:54 myrouter ppp: PPPoE: rec'd ACNAME "AUGX45-erx" Oct 10 12:48:54 myrouter ppp: [wan_link0] PPPoE: connection successful Oct 10 12:48:54 myrouter ppp: [wan_link0] Link: UP event Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: Up event Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Starting --> Req-Sent Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: SendConfigReq #1 Oct 10 12:48:54 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:48:54 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:54 myrouter ppp: [wan_link0] MAGICNUM 0xd7b973b9 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: rec'd Configure Request #167 (Req-Sent) Oct 10 12:48:54 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:54 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:48:54 myrouter ppp: [wan_link0] MAGICNUM 0x70c71598 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: SendConfigAck #167 Oct 10 12:48:54 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:54 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:48:54 myrouter ppp: [wan_link0] MAGICNUM 0x70c71598 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Oct 10 12:48:54 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:48:54 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:54 myrouter ppp: [wan_link0] MAGICNUM 0xd7b973b9 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Ack-Sent --> Opened Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing Oct 10 12:48:54 myrouter ppp: [wan_link0] PAP: using authname "MYLOGIN" Oct 10 12:48:54 myrouter ppp: [wan_link0] PAP: sending REQUEST #1 len: 54 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: LayerUp Oct 10 12:48:54 myrouter ppp: [wan_link0] PAP: rec'd ACK #1 len: 5 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: authorization successful Oct 10 12:48:54 myrouter ppp: Shutdown sequence in progress, BundJoin() denied Oct 10 12:48:54 myrouter ppp: [wan_link0] link did not validate in bundle Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: parameter negotiation failed Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Opened --> Stopping Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: SendTerminateReq #2 Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: LayerDown Oct 10 12:48:54 myrouter ppp: waiting for process 63331 to die... Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Stopping) Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Stopping --> Stopped Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: LayerFinish Oct 10 12:48:54 myrouter ppp: [wan_link0] PPPoE: connection closed Oct 10 12:48:54 myrouter ppp: [wan_link0] Link: DOWN event Oct 10 12:48:54 myrouter ppp: [wan_link0] Link: giving up after 0 reconnection attempts Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: Close event Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Stopped --> Closed Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: Down event Oct 10 12:48:54 myrouter ppp: [wan_link0] LCP: state change Closed --> Initial Oct 10 12:48:55 myrouter ppp: waiting for process 63331 to die... Oct 10 12:48:55 myrouter ppp: [wan] Bundle: Shutdown Oct 10 12:48:55 myrouter ppp: [wan_link0] Link: Shutdown Oct 10 12:48:55 myrouter ppp: process 63331 terminated Oct 10 12:48:56 myrouter ppp: web: web is not running Oct 10 12:48:56 myrouter ppp: [wan] Bundle: Interface ng0 created Oct 10 12:48:56 myrouter ppp: [wan_link0] Link: OPEN event Oct 10 12:48:56 myrouter kernel: ng0: changing name to 'pppoe0' Oct 10 12:48:56 myrouter ppp: [wan_link0] LCP: Open event Oct 10 12:48:56 myrouter ppp: [wan_link0] LCP: state change Initial --> Starting Oct 10 12:48:56 myrouter ppp: [wan_link0] LCP: LayerStart Oct 10 12:48:56 myrouter ppp: [wan_link0] PPPoE: Connecting to '' Oct 10 12:48:59 myrouter ppp: PPPoE: rec'd ACNAME "AUGX45-erx" Oct 10 12:48:59 myrouter ppp: [wan_link0] PPPoE: connection successful Oct 10 12:48:59 myrouter ppp: [wan_link0] Link: UP event Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: Up event Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: state change Starting --> Req-Sent Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: SendConfigReq #1 Oct 10 12:48:59 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:48:59 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:59 myrouter ppp: [wan_link0] MAGICNUM 0x839c90a2 Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: rec'd Configure Request #176 (Req-Sent) Oct 10 12:48:59 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:59 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:48:59 myrouter ppp: [wan_link0] MAGICNUM 0x125db532 Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: SendConfigAck #176 Oct 10 12:48:59 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:59 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:48:59 myrouter ppp: [wan_link0] MAGICNUM 0x125db532 Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Oct 10 12:48:59 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:48:59 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:48:59 myrouter ppp: [wan_link0] MAGICNUM 0x839c90a2 Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: state change Ack-Sent --> Opened Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing Oct 10 12:48:59 myrouter ppp: [wan_link0] PAP: using authname "MYLOGIN" Oct 10 12:48:59 myrouter ppp: [wan_link0] PAP: sending REQUEST #1 len: 54 Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: LayerUp Oct 10 12:48:59 myrouter ppp: [wan_link0] PAP: rec'd ACK #1 len: 5 Oct 10 12:48:59 myrouter ppp: [wan_link0] LCP: authorization successful Oct 10 12:48:59 myrouter ppp: [wan_link0] Link: Matched action 'bundle "wan" ""' Oct 10 12:48:59 myrouter ppp: [wan_link0] Link: Join bundle "wan" Oct 10 12:48:59 myrouter ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps Oct 10 12:48:59 myrouter ppp: [wan] IPCP: Open event Oct 10 12:48:59 myrouter ppp: [wan] IPCP: state change Initial --> Starting Oct 10 12:48:59 myrouter ppp: [wan] IPCP: LayerStart Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: Open event Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: state change Initial --> Starting Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: LayerStart Oct 10 12:48:59 myrouter ppp: [wan] IPCP: Up event Oct 10 12:48:59 myrouter ppp: [wan] IPCP: state change Starting --> Req-Sent Oct 10 12:48:59 myrouter ppp: [wan] IPCP: SendConfigReq #1 Oct 10 12:48:59 myrouter ppp: [wan] IPADDR 0.0.0.0 Oct 10 12:48:59 myrouter ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 10 12:48:59 myrouter ppp: [wan] PRIDNS 0.0.0.0 Oct 10 12:48:59 myrouter ppp: [wan] SECDNS 0.0.0.0 Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: Up event Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: state change Starting --> Req-Sent Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: SendConfigReq #1 Oct 10 12:48:59 myrouter ppp: [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) Oct 10 12:48:59 myrouter ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 10 12:48:59 myrouter ppp: [wan] IPCP: SendConfigReq #2 Oct 10 12:48:59 myrouter ppp: [wan] IPADDR 0.0.0.0 Oct 10 12:48:59 myrouter ppp: [wan] PRIDNS 0.0.0.0 Oct 10 12:48:59 myrouter ppp: [wan] SECDNS 0.0.0.0 Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) Oct 10 12:48:59 myrouter ppp: [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd Oct 10 12:48:59 myrouter ppp: [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) Oct 10 12:48:59 myrouter ppp: [wan] IPADDR 79.206.136.136 Oct 10 12:48:59 myrouter ppp: [wan] 79.206.136.136 is OK Oct 10 12:48:59 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:48:59 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:48:59 myrouter ppp: [wan] IPCP: SendConfigReq #3 Oct 10 12:48:59 myrouter ppp: [wan] IPADDR 79.206.136.136 Oct 10 12:48:59 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:48:59 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:49:00 myrouter php-fpm[36228]: /rc.linkup: Accept router advertisements on interface ue0 Oct 10 12:49:00 myrouter php-fpm[36228]: /rc.linkup: ROUTING: setting IPv6 default route to fe80::90:1a00:1a1:cd7%pppoe0 Oct 10 12:49:00 myrouter php-fpm[36228]: /rc.linkup: The command '/sbin/route change -inet6 default 'fe80::90:1a00:1a1:cd7%pppoe0'' returned exit code '1', the output was 'route: writing to routing socket: No such process route: writing to routing socket: Network is unreachable change net default: gateway fe80::90:1a00:1a1:cd7%pppoe0 fib 0: Network is unreachable' Oct 10 12:49:00 myrouter kernel: sa6_recoverscope: embedded scope mismatch: fe80:8::b074:2c63%-2048\. sin6_scope_id was overridden.sa6_recoverscope: embedded scope mismatch: fe80:8::b074:2c63%-2048\. sin6_scope_id was overridden. Oct 10 12:49:00 myrouter check_reload_status: Restarting ipsec tunnels Oct 10 12:49:00 myrouter ppp: [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) Oct 10 12:49:00 myrouter ppp: [wan] IPADDR 79.206.136.136 Oct 10 12:49:00 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:49:00 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:49:00 myrouter ppp: [wan] IPCP: state change Req-Sent --> Ack-Rcvd Oct 10 12:49:00 myrouter ppp: [wan] IPCP: rec'd Configure Request #236 (Ack-Rcvd) Oct 10 12:49:00 myrouter ppp: [wan] IPADDR 217.0.118.150 Oct 10 12:49:00 myrouter ppp: [wan] 217.0.118.150 is OK Oct 10 12:49:00 myrouter ppp: [wan] IPCP: SendConfigAck #236 Oct 10 12:49:00 myrouter ppp: [wan] IPADDR 217.0.118.150 Oct 10 12:49:00 myrouter ppp: [wan] IPCP: state change Ack-Rcvd --> Opened Oct 10 12:49:00 myrouter ppp: [wan] IPCP: LayerUp Oct 10 12:49:00 myrouter ppp: [wan] 79.206.136.136 -> 217.0.118.150 Oct 10 12:49:00 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:49:01 myrouter check_reload_status: rc.newwanip starting pppoe0 Oct 10 12:49:01 myrouter ppp: [wan] IFACE: Up event Oct 10 12:49:01 myrouter ppp: [wan] IFACE: Rename interface ng0 to pppoe0 Oct 10 12:49:01 myrouter ppp: [wan] IPV6CP: rec'd Configure Request #183 (Ack-Rcvd) Oct 10 12:49:01 myrouter ppp: [wan] IPV6CP: SendConfigAck #183 Oct 10 12:49:01 myrouter ppp: [wan] IPV6CP: state change Ack-Rcvd --> Opened Oct 10 12:49:01 myrouter ppp: [wan] IPV6CP: LayerUp Oct 10 12:49:01 myrouter ppp: [wan] 92fb:a6ff:fe2a:70af -> 0090:1a00:01a1:0cd7 Oct 10 12:49:02 myrouter check_reload_status: rc.newwanipv6 starting pppoe0 Oct 10 12:49:02 myrouter php-fpm[33552]: /rc.interfaces_wan_configure: Accept router advertisements on interface ue0 Oct 10 12:49:03 myrouter php-fpm[33552]: /rc.newwanip: rc.newwanip: Info: starting on pppoe0. Oct 10 12:49:03 myrouter php-fpm[33552]: /rc.newwanip: rc.newwanip: on (IP address: 79.206.136.136) (interface: WAN[wan]) (real interface: pppoe0). Oct 10 12:49:03 myrouter php-fpm[33552]: /rc.newwanip: IP has changed, killing states on former IP 79.206.146.48. Oct 10 12:49:03 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:49:03 myrouter xinetd[17684]: Swapping defaults Oct 10 12:49:03 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:49:03 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:49:03 myrouter php-fpm[33552]: /rc.newwanip: ROUTING: setting default route to 217.0.118.150 Oct 10 12:49:03 myrouter php-fpm[33552]: /rc.newwanip: ROUTING: setting IPv6 default route to fe80::90:1a00:1a1:cd7%pppoe0 Oct 10 12:49:03 myrouter php-fpm[36228]: /rc.linkup: The command '/usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpd.conf -pf /var/run/dhcpd.pid nfe0' returned exit code '1', the output was 'Internet Systems Consortium DHCP Server 4.3.4 Copyright 2004-2016 Internet Systems Consortium. All rights reserved. For info, please visit https://www.isc.org/software/dhcp/ Config file: /etc/dhcpd.conf Database file: /var/db/dhcpd.leases PID file: /var/run/dhcpd.pid Wrote 18 leases to leases file. Listening on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Sending on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Can't bind to dhcp address: Address already in use Please make sure there is no other dhcp server running and that there's no entry for dhcp or bootp in /etc/inetd.conf. Also make sure you are not running HP JetAdmin software, which includes a bootp server. If you think you have received this message due to a bug rather than a configuration issue please read the section on submitting bugs on ei Oct 10 12:49:04 myrouter check_reload_status: updating dyndns wan Oct 10 12:49:04 myrouter check_reload_status: Reloading filter Oct 10 12:49:04 myrouter check_reload_status: Reloading filter Oct 10 12:49:04 myrouter php-fpm[37210]: /rc.newwanipv6: rc.newwanipv6: Info: starting on pppoe0. Oct 10 12:49:04 myrouter php-fpm[37210]: /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan]. Oct 10 12:49:04 myrouter ppp: Multi-link PPP daemon for FreeBSD Oct 10 12:49:04 myrouter ppp: Oct 10 12:49:04 myrouter ppp: process 99020 started, version 5.8 (root@pfSense_v2_3_2_amd64-pfSense_v2_3_2-job-04 16:03 19-Jul-2016) Oct 10 12:49:04 myrouter ppp: waiting for process 52922 to die... Oct 10 12:49:04 myrouter ppp: caught fatal signal TERM Oct 10 12:49:04 myrouter ppp: [wan] IFACE: Close event Oct 10 12:49:04 myrouter ppp: [wan] IPCP: Close event Oct 10 12:49:04 myrouter ppp: [wan] IPCP: state change Opened --> Closing Oct 10 12:49:04 myrouter ppp: [wan] IPCP: SendTerminateReq #4 Oct 10 12:49:04 myrouter ppp: [wan] IPCP: LayerDown Oct 10 12:49:05 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:49:05 myrouter ppp: [wan] IFACE: Delete route 0.0.0.0/0 217.0.118.150 failed: No such process Oct 10 12:49:05 myrouter ppp: [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address Oct 10 12:49:05 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:49:05 myrouter ppp: [wan] IPV6CP: state change Opened --> Closing Oct 10 12:49:05 myrouter ppp: [wan] IPV6CP: SendTerminateReq #2 Oct 10 12:49:05 myrouter ppp: [wan] IPV6CP: LayerDown Oct 10 12:49:05 myrouter ppp: waiting for process 52922 to die... Oct 10 12:49:05 myrouter php-fpm[37210]: /rc.dyndns.update: Dynamic DNS (DYNDNSHOSTNAME) There was an error trying to determine the public IP for interface - wan (pppoe0 ). Oct 10 12:49:06 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:49:06 myrouter ppp: [wan] IFACE: Down event Oct 10 12:49:06 myrouter ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0 Oct 10 12:49:06 myrouter ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing) Oct 10 12:49:06 myrouter ppp: [wan] IPCP: state change Closing --> Closed Oct 10 12:49:06 myrouter ppp: [wan] IPCP: LayerFinish Oct 10 12:49:06 myrouter ppp: [wan] IPV6CP: rec'd Terminate Ack #2 (Closing) Oct 10 12:49:06 myrouter ppp: [wan] IPV6CP: state change Closing --> Closed Oct 10 12:49:06 myrouter ppp: [wan] IPV6CP: LayerFinish Oct 10 12:49:06 myrouter ppp: [wan] Bundle: No NCPs left. Closing links... Oct 10 12:49:06 myrouter ppp: [wan] Bundle: closing link "wan_link0"... Oct 10 12:49:06 myrouter ppp: [wan_link0] Link: CLOSE event Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: Close event Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: state change Opened --> Closing Oct 10 12:49:06 myrouter ppp: [wan_link0] Link: Leave bundle "wan" Oct 10 12:49:06 myrouter ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps Oct 10 12:49:06 myrouter ppp: [wan] IPCP: Close event Oct 10 12:49:06 myrouter ppp: [wan] IPV6CP: Close event Oct 10 12:49:06 myrouter ppp: [wan] IPCP: Down event Oct 10 12:49:06 myrouter ppp: [wan] IPCP: state change Closed --> Initial Oct 10 12:49:06 myrouter ppp: [wan] IPV6CP: Down event Oct 10 12:49:06 myrouter ppp: [wan] IPV6CP: state change Closed --> Initial Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: SendTerminateReq #2 Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: LayerDown Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing) Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: state change Closing --> Closed Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: LayerFinish Oct 10 12:49:06 myrouter ppp: [wan_link0] Link: DOWN event Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: Down event Oct 10 12:49:06 myrouter ppp: [wan_link0] LCP: state change Closed --> Initial Oct 10 12:49:06 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:49:06 myrouter xinetd[17684]: Swapping defaults Oct 10 12:49:06 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:49:06 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:49:06 myrouter ppp: waiting for process 52922 to die... Oct 10 12:49:06 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:49:06 myrouter xinetd[17684]: Swapping defaults Oct 10 12:49:06 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:49:06 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:49:07 myrouter ppp: waiting for process 52922 to die... Oct 10 12:49:07 myrouter php-fpm[33552]: /rc.newwanip: The command '/usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpd.conf -pf /var/run/dhcpd.pid nfe0' returned exit code '1', the output was 'Internet Systems Consortium DHCP Server 4.3.4 Copyright 2004-2016 Internet Systems Consortium. All rights reserved. For info, please visit https://www.isc.org/software/dhcp/ Config file: /etc/dhcpd.conf Database file: /var/db/dhcpd.leases PID file: /var/run/dhcpd.pid Wrote 18 leases to leases file. Listening on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Sending on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Can't bind to dhcp address: Address already in use Please make sure there is no other dhcp server running and that there's no entry for dhcp or bootp in /etc/inetd.conf. Also make sure you are not running HP JetAdmin software, which includes a bootp server. If you think you have received this message due to a bug rather than a configuration issue please read the section on submitting bugs on Oct 10 12:49:08 myrouter ppp: [wan] Bundle: Shutdown Oct 10 12:49:08 myrouter ppp: [wan_link0] Link: Shutdown Oct 10 12:49:08 myrouter ppp: process 52922 terminated Oct 10 12:49:08 myrouter rtsold[93394]: <rtsock_input_ifannounce> interface pppoe0 removed Oct 10 12:49:08 myrouter rtsold[78882]: <rtsock_input_ifannounce> interface pppoe0 removed Oct 10 12:49:08 myrouter ppp: web: web is not running Oct 10 12:49:08 myrouter ppp: [wan] Bundle: Interface ng0 created Oct 10 12:49:08 myrouter rtsold[93394]: <rtsock_input_ifannounce> interface ng0 removed Oct 10 12:49:08 myrouter kernel: Oct 10 12:49:08 myrouter kernel: ng0: changing name to 'pppoe0' Oct 10 12:49:08 myrouter rtsold[78882]: <rtsock_input_ifannounce> interface ng0 removed Oct 10 12:49:08 myrouter ppp: [wan_link0] Link: OPEN event Oct 10 12:49:08 myrouter ppp: [wan_link0] LCP: Open event Oct 10 12:49:08 myrouter ppp: [wan_link0] LCP: state change Initial --> Starting Oct 10 12:49:08 myrouter ppp: [wan_link0] LCP: LayerStart Oct 10 12:49:08 myrouter ppp: [wan_link0] PPPoE: Connecting to '' Oct 10 12:49:09 myrouter php-fpm[33552]: /rc.newwanip: Curl error occurred: Could not resolve host: members.dyndns.org Oct 10 12:49:10 myrouter php-fpm[33552]: /rc.newwanip: Resyncing OpenVPN instances for interface WAN. Oct 10 12:49:10 myrouter php-fpm[33552]: /rc.newwanip: Creating rrd update script Oct 10 12:49:10 myrouter ppp: PPPoE: rec'd ACNAME "AUGX45-erx" Oct 10 12:49:11 myrouter ppp: [wan_link0] PPPoE: connection successful Oct 10 12:49:11 myrouter ppp: [wan_link0] Link: UP event Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: Up event Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: state change Starting --> Req-Sent Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: SendConfigReq #1 Oct 10 12:49:11 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:49:11 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:49:11 myrouter ppp: [wan_link0] MAGICNUM 0xc235ec98 Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: rec'd Configure Request #141 (Req-Sent) Oct 10 12:49:11 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:49:11 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:49:11 myrouter ppp: [wan_link0] MAGICNUM 0x58d08255 Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: SendConfigAck #141 Oct 10 12:49:11 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:49:11 myrouter ppp: [wan_link0] AUTHPROTO PAP Oct 10 12:49:11 myrouter ppp: [wan_link0] MAGICNUM 0x58d08255 Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent) Oct 10 12:49:11 myrouter ppp: [wan_link0] PROTOCOMP Oct 10 12:49:11 myrouter ppp: [wan_link0] MRU 1492 Oct 10 12:49:11 myrouter ppp: [wan_link0] MAGICNUM 0xc235ec98 Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: state change Ack-Sent --> Opened Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing Oct 10 12:49:11 myrouter ppp: [wan_link0] PAP: using authname "MYLOGIN" Oct 10 12:49:11 myrouter ppp: [wan_link0] PAP: sending REQUEST #1 len: 54 Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: LayerUp Oct 10 12:49:11 myrouter ppp: [wan_link0] PAP: rec'd ACK #1 len: 5 Oct 10 12:49:11 myrouter ppp: [wan_link0] LCP: authorization successful Oct 10 12:49:11 myrouter ppp: [wan_link0] Link: Matched action 'bundle "wan" ""' Oct 10 12:49:11 myrouter ppp: [wan_link0] Link: Join bundle "wan" Oct 10 12:49:11 myrouter ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps Oct 10 12:49:11 myrouter ppp: [wan] IPCP: Open event Oct 10 12:49:11 myrouter ppp: [wan] IPCP: state change Initial --> Starting Oct 10 12:49:11 myrouter ppp: [wan] IPCP: LayerStart Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: Open event Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: state change Initial --> Starting Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: LayerStart Oct 10 12:49:11 myrouter ppp: [wan] IPCP: Up event Oct 10 12:49:11 myrouter ppp: [wan] IPCP: state change Starting --> Req-Sent Oct 10 12:49:11 myrouter ppp: [wan] IPCP: SendConfigReq #1 Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 0.0.0.0 Oct 10 12:49:11 myrouter ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 10 12:49:11 myrouter ppp: [wan] PRIDNS 0.0.0.0 Oct 10 12:49:11 myrouter ppp: [wan] SECDNS 0.0.0.0 Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: Up event Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: state change Starting --> Req-Sent Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: SendConfigReq #1 Oct 10 12:49:11 myrouter ppp: [wan] IPCP: rec'd Configure Reject #1 (Req-Sent) Oct 10 12:49:11 myrouter ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 10 12:49:11 myrouter ppp: [wan] IPCP: SendConfigReq #2 Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 0.0.0.0 Oct 10 12:49:11 myrouter ppp: [wan] PRIDNS 0.0.0.0 Oct 10 12:49:11 myrouter ppp: [wan] SECDNS 0.0.0.0 Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: rec'd Configure Ack #1 (Req-Sent) Oct 10 12:49:11 myrouter ppp: [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd Oct 10 12:49:11 myrouter ppp: [wan] IPCP: rec'd Configure Nak #2 (Req-Sent) Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 79.206.151.115 Oct 10 12:49:11 myrouter ppp: [wan] 79.206.151.115 is OK Oct 10 12:49:11 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:49:11 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:49:11 myrouter ppp: [wan] IPCP: SendConfigReq #3 Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 79.206.151.115 Oct 10 12:49:11 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:49:11 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:49:11 myrouter ppp: [wan] IPCP: rec'd Configure Ack #3 (Req-Sent) Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 79.206.151.115 Oct 10 12:49:11 myrouter ppp: [wan] PRIDNS 217.0.43.145 Oct 10 12:49:11 myrouter ppp: [wan] SECDNS 217.0.43.129 Oct 10 12:49:11 myrouter ppp: [wan] IPCP: state change Req-Sent --> Ack-Rcvd Oct 10 12:49:11 myrouter ppp: [wan] IPCP: rec'd Configure Request #5 (Ack-Rcvd) Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 217.0.118.150 Oct 10 12:49:11 myrouter ppp: [wan] 217.0.118.150 is OK Oct 10 12:49:11 myrouter ppp: [wan] IPCP: SendConfigAck #5 Oct 10 12:49:11 myrouter ppp: [wan] IPADDR 217.0.118.150 Oct 10 12:49:11 myrouter ppp: [wan] IPCP: state change Ack-Rcvd --> Opened Oct 10 12:49:11 myrouter ppp: [wan] IPCP: LayerUp Oct 10 12:49:11 myrouter ppp: [wan] 79.206.151.115 -> 217.0.118.150 Oct 10 12:49:11 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:49:12 myrouter php-fpm[33552]: /rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 79.206.146.48 -> 79.206.136.136 - Restarting packages. Oct 10 12:49:12 myrouter check_reload_status: Starting packages Oct 10 12:49:12 myrouter check_reload_status: rc.newwanip starting pppoe0 Oct 10 12:49:12 myrouter ppp: [wan] IFACE: Up event Oct 10 12:49:12 myrouter ppp: [wan] IFACE: Rename interface ng0 to pppoe0 Oct 10 12:49:12 myrouter ppp: [wan] IPV6CP: rec'd Configure Request #43 (Ack-Rcvd) Oct 10 12:49:12 myrouter ppp: [wan] IPV6CP: SendConfigAck #43 Oct 10 12:49:12 myrouter ppp: [wan] IPV6CP: state change Ack-Rcvd --> Opened Oct 10 12:49:12 myrouter ppp: [wan] IPV6CP: LayerUp Oct 10 12:49:12 myrouter ppp: [wan] 92fb:a6ff:fe2a:70af -> 0090:1a00:01a1:0cd7 Oct 10 12:49:13 myrouter php-fpm[979]: /rc.start_packages: Restarting/Starting all packages. Oct 10 12:49:13 myrouter check_reload_status: Rewriting resolv.conf Oct 10 12:49:13 myrouter php-fpm[979]: /rc.newwanip: rc.newwanip: Info: starting on pppoe0. Oct 10 12:49:13 myrouter php-fpm[979]: /rc.newwanip: rc.newwanip: on (IP address: 79.206.151.115) (interface: WAN[wan]) (real interface: pppoe0). Oct 10 12:49:13 myrouter php-fpm[979]: /rc.newwanip: IP has changed, killing states on former IP 79.206.136.136. Oct 10 12:49:14 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:49:14 myrouter xinetd[17684]: Swapping defaults Oct 10 12:49:14 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:49:14 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:49:14 myrouter php-fpm[979]: /rc.newwanip: ROUTING: setting default route to 217.0.118.150 Oct 10 12:49:14 myrouter php-fpm[979]: /rc.newwanip: ROUTING: setting IPv6 default route to fe80::90:1a00:1a1:cd7%pppoe0 Oct 10 12:49:14 myrouter check_reload_status: rc.newwanipv6 starting pppoe0 Oct 10 12:49:14 myrouter php-fpm[64794]: /rc.linkup: Accept router advertisements on interface ue0 Oct 10 12:49:14 myrouter php-fpm[64794]: /rc.linkup: ROUTING: setting default route to 217.0.118.150 Oct 10 12:49:14 myrouter php-fpm[64794]: /rc.linkup: ROUTING: setting IPv6 default route to fe80::90:1a00:1a1:cd7%pppoe0 Oct 10 12:49:14 myrouter check_reload_status: Restarting ipsec tunnels Oct 10 12:49:15 myrouter php-fpm[34331]: /rc.newwanipv6: rc.newwanipv6: Info: starting on pppoe0. Oct 10 12:49:15 myrouter php-fpm[34331]: /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan]. Oct 10 12:49:20 myrouter php-fpm[979]: /rc.newwanip: The command '/usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpd.conf -pf /var/run/dhcpd.pid nfe0' returned exit code '1', the output was 'Internet Systems Consortium DHCP Server 4.3.4 Copyright 2004-2016 Internet Systems Consortium. All rights reserved. For info, please visit https://www.isc.org/software/dhcp/ Config file: /etc/dhcpd.conf Database file: /var/db/dhcpd.leases PID file: /var/run/dhcpd.pid Wrote 18 leases to leases file. Listening on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Sending on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Can't bind to dhcp address: Address already in use Please make sure there is no other dhcp server running and that there's no entry for dhcp or bootp in /etc/inetd.conf. Also make sure you are not running HP JetAdmin software, which includes a bootp server. If you think you have received this message due to a bug rather than a configuration issue please read the section on submitting bugs on ei Oct 10 12:49:21 myrouter php-fpm[64794]: /rc.linkup: The command '/usr/local/sbin/unbound -c /var/unbound/unbound.conf' returned exit code '1', the output was '[1476096561] unbound[44823:0] error: bind: address already in use [1476096561] unbound[44823:0] fatal error: could not open ports' Oct 10 12:49:21 myrouter dhcpleases: kqueue error: unkown Oct 10 12:49:22 myrouter php-fpm[64794]: /rc.linkup: The command '/usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpd.conf -pf /var/run/dhcpd.pid nfe0' returned exit code '1', the output was 'Internet Systems Consortium DHCP Server 4.3.4 Copyright 2004-2016 Internet Systems Consortium. All rights reserved. For info, please visit https://www.isc.org/software/dhcp/ Config file: /etc/dhcpd.conf Database file: /var/db/dhcpd.leases PID file: /var/run/dhcpd.pid Wrote 18 leases to leases file. Listening on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Sending on BPF/nfe0/90:fb:a6:2a:70:af/10.1.100.0/24 Can't bind to dhcp address: Address already in use Please make sure there is no other dhcp server running and that there's no entry for dhcp or bootp in /etc/inetd.conf. Also make sure you are not running HP JetAdmin software, which includes a bootp server. If you think you have received this message due to a bug rather than a configuration issue please read the section on submitting bugs on ei Oct 10 12:49:23 myrouter check_reload_status: updating dyndns wan Oct 10 12:49:23 myrouter check_reload_status: Reloading filter Oct 10 12:49:25 myrouter xinetd[17684]: Starting reconfiguration Oct 10 12:49:25 myrouter xinetd[17684]: Swapping defaults Oct 10 12:49:25 myrouter xinetd[17684]: readjusting service 6969-udp Oct 10 12:49:25 myrouter xinetd[17684]: Reconfigured: new=0 old=1 dropped=0 (services) Oct 10 12:49:27 myrouter php-fpm[979]: /rc.newwanip: phpDynDNS: updating cache file /conf/dyndns_wandyndns'DYNDNSHOSTNAME'0.cache: 79.206.151.115 Oct 10 12:49:27 myrouter php-fpm[979]: /rc.newwanip: phpDynDNS (DYNDNSHOSTNAME): (Success) IP Address Changed Successfully! (79.206.151.115) Oct 10 12:49:27 myrouter php-fpm[34331]: /rc.dyndns.update: phpDynDNS (DYNDNSHOSTNAME): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry. Oct 10 12:49:28 myrouter php-fpm[979]: /rc.newwanip: Resyncing OpenVPN instances for interface WAN. Oct 10 12:49:28 myrouter php-fpm[979]: /rc.newwanip: Creating rrd update script Oct 10 12:49:30 myrouter php-fpm[979]: /rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 79.206.136.136 -> 79.206.151.115 - Restarting packages. Oct 10 12:49:30 myrouter check_reload_status: Starting packages Oct 10 12:49:31 myrouter php-fpm[49981]: /rc.start_packages: Restarting/Starting all packages.</rtsock_input_ifannounce></rtsock_input_ifannounce></rtsock_input_ifannounce></rtsock_input_ifannounce></rtsock_input_ifannounce></rtsock_input_ifannounce>In case there is any help out there, I would be really thankful!
-
My guess is that the USB NIC is the main suspect, I've yet to see one that works well on FreeBSD.
Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.