WAN DNS Failure



  • Hi all,

    I'm having an issue where I lose my public WAN address. It happens about once every couple of weeks. Usually, I have to force renew, or uncheck/check the interface in the web admin to restart eu0 (WAN). This time, it happened while I was out of town, and somehow dhclient kicked back in about 5 days later. The only signs I have in the logs are this:

    Nov 22 23:56:42 pfsense dhclient[24893]: DHCPREQUEST on ue0 to 98.X.210.1 port 67
    Nov 22 23:56:42 pfsense dhclient[24893]: DHCPACK from 98.X.210.1
    Nov 22 23:56:42 pfsense dhclient: RENEW
    Nov 22 23:56:42 pfsense dhclient: Creating resolv.conf
    Nov 22 23:56:42 pfsense dhclient[24893]: bound to 98.X.210.XXX – renewal in 3600 seconds.
    Nov 22 23:56:48 pfsense dhclient[30439]: DHCPREQUEST on ue0 to 98.X.210.1 port 67
    Nov 22 23:56:48 pfsense dhclient[30439]: DHCPACK from 98.X.210.1
    Nov 22 23:56:48 pfsense dhclient: RENEW
    Nov 22 23:56:48 pfsense dhclient: Creating resolv.conf
    Nov 22 23:56:48 pfsense dhclient[30439]: bound to 98.X.210.XXX – renewal in 3600 seconds.
    Nov 23 00:00:45 pfsense check_reload_status: Linkup starting ue0
    Nov 23 00:00:45 pfsense kernel: ue0: link state changed to DOWN
    Nov 23 00:00:45 pfsense kernel: ue0: link state changed to UP
    Nov 23 00:00:45 pfsense check_reload_status: Linkup starting ue0
    Nov 23 00:00:47 pfsense php: : DEVD Ethernet attached event for wan
    Nov 23 00:00:47 pfsense php: : HOTPLUG: Configuring interface wan
    Nov 23 00:00:47 pfsense dhclient: PREINIT
    Nov 23 00:00:47 pfsense dhclient[30439]: My address (98.X.210.XXX) was deleted, dhclient exiting
    Nov 23 00:00:47 pfsense dhclient[30439]: My address (98.X.210.XXX) was deleted, dhclient exiting
    Nov 23 00:00:47 pfsense dhclient[24893]: My address (98.X.210.XXX) was deleted, dhclient exiting
    Nov 23 00:00:47 pfsense dhclient[24893]: My address (98.X.210.XXX) was deleted, dhclient exiting
    Nov 23 00:00:47 pfsense dhclient: FAIL
    Nov 23 00:00:47 pfsense dhclient: FAIL
    Nov 23 00:00:47 pfsense dhclient[25793]: connection closed
    Nov 23 00:00:47 pfsense dhclient[25793]: connection closed
    Nov 23 00:00:47 pfsense dhclient[25793]: exiting.
    Nov 23 00:00:47 pfsense dhclient[25793]: exiting.
    Nov 23 00:00:47 pfsense dhclient[18599]: connection closed
    Nov 23 00:00:47 pfsense dhclient[18599]: connection closed
    Nov 23 00:00:47 pfsense dhclient[18599]: exiting.
    Nov 23 00:00:47 pfsense dhclient[18599]: exiting.
    Nov 23 00:00:47 pfsense dhclient[62947]: DHCPREQUEST on ue0 to 255.255.255.255 port 67
    Nov 23 00:00:47 pfsense php: : DEVD Ethernet detached event for wan
    Nov 23 00:00:47 pfsense dhclient[63196]: connection closed
    Nov 23 00:00:47 pfsense dhclient[63196]: connection closed
    Nov 23 00:00:47 pfsense dhclient[63196]: exiting.
    Nov 23 00:00:47 pfsense dhclient[63196]: exiting.
    Nov 23 00:00:47 pfsense php: : The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf ue0 > /tmp/ue0_output > /tmp/
    ue0_error_output' returned exit code '15', the output was ''
    Nov 23 00:00:48 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:48 pfsense miniupnpd[43615]: ioctl(s, SIOCGIFADDR, …): Can't assign requested address
    Nov 23 00:00:48 pfsense miniupnpd[43615]: ioctl(s, SIOCGIFADDR, …): Can't assign requested address
    Nov 23 00:00:48 pfsense miniupnpd[43615]: Failed to get IP for interface ue0
    Nov 23 00:00:48 pfsense miniupnpd[43615]: Failed to get IP for interface ue0
    Nov 23 00:00:48 pfsense miniupnpd[43615]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
    Nov 23 00:00:48 pfsense miniupnpd[43615]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
    Nov 23 00:00:49 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:50 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:51 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:52 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:53 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:54 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:55 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:56 pfsense kernel: arpresolve: can't allocate llinfo for 98.X.210.1
    Nov 23 00:00:57 pfsense apinger: ALARM: GW_WAN(98.X.210.1)  *** down ***

    The next entry for dhclient wasn't until:

    Nov 27 00:59:17 pfsense dhclient: PREINIT
    ov 27 00:59:17 pfsense dhclient: EXPIRE
    Nov 27 00:59:17 pfsense dhclient: Deleting old routes
    Nov 27 00:59:17 pfsense dhclient: PREINIT
    Nov 27 00:59:17 pfsense dhclient[26098]: DHCPDISCOVER on ue0 to 255.255.255.255 port 67 interval 1
    Nov 27 00:59:17 pfsense dhclient[26098]: DHCPOFFER from 98.X.206.1
    Nov 27 00:59:17 pfsense dhclient: ARPSEND

    At which point things went smoothly. Any thoughts as to why it waited so long to renew, or why the failure occurred in the first place?

    How would you recommend I keep this from happening, or at least a once daily stop gap for if I'm out of town?

    Thanks,
    Ben