Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Problem with dhclient restarting #4017

Closed
iwex opened this issue Apr 4, 2020 · 4 comments
Closed

Problem with dhclient restarting #4017

iwex opened this issue Apr 4, 2020 · 4 comments
Labels
help wanted Contributor missing / timeout support Community support

Comments

@iwex
Copy link

iwex commented Apr 4, 2020

Hello! Sometimes something strange happening on opnsesne. WAN goes to defunct state. As i see - main problem is that The command '/sbin/dhclient -c '/var/etc/dhclient_wan.conf' -p '/var/run/dhclient.re1.pid' 're1'' returned exit code '1', the output was 'dhclient already running, pid: 71216. exiting.'

Relevant log files
If applicable, information from log files supporting your claim.

Apr  4 02:33:12 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:34:14 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:35:10 opnsense kernel: re1: link state changed to DOWN
Apr  4 02:35:11 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet detached event for wan 
Apr  4 02:35:11 opnsense dhclient[32217]: connection closed 
Apr  4 02:35:11 opnsense dhclient[32217]: exiting. 
Apr  4 02:35:11 opnsense opnsense: /usr/local/etc/rc.linkup: Clearing states for stale wan route on re1 
Apr  4 02:35:13 opnsense kernel: re1: link state changed to UP
Apr  4 02:35:14 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet attached event for wan 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.linkup: HOTPLUG: Configuring interface wan 
Apr  4 02:35:14 opnsense dhclient: Starting delete_old_states() 
Apr  4 02:35:14 opnsense dhclient: Comparing IPs: Old: yyy.yyy.yyy.yyy New:  
Apr  4 02:35:14 opnsense dhclient: Removing states from old IP 'yyy.yyy.yyy.yyy' (new IP '') 
Apr  4 02:35:14 opnsense dhclient[86233]: DHCPREQUEST on re1 to 255.255.255.255 port 67 
Apr  4 02:35:14 opnsense dhclient[86233]: DHCPACK from 10.244.7.254 
Apr  4 02:35:14 opnsense dhclient: Starting delete_old_states() 
Apr  4 02:35:14 opnsense dhclient: Comparing IPs: Old: yyy.yyy.yyy.yyy New: yyy.yyy.yyy.yyy 
Apr  4 02:35:14 opnsense dhclient: New IP Address (re1): yyy.yyy.yyy.yyy 
Apr  4 02:35:14 opnsense dhclient: New Subnet Mask (re1): 255.255.240.0 
Apr  4 02:35:14 opnsense dhclient: New Broadcast Address (re1): zzz.zzz.zzz.zzz 
Apr  4 02:35:14 opnsense dhclient: New Routers (re1): xxx.xxx.xxx.1 
Apr  4 02:35:14 opnsense dhclient: route add default xxx.xxx.xxx.1 
Apr  4 02:35:14 opnsense dhclient: Creating resolv.conf 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 're1' 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: On (IP address: yyy.yyy.yyy.yyy) (interface: WAN[wan]) (real interface: re1). 
Apr  4 02:35:14 opnsense opnsense: plugins_configure hosts () 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'wan' 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to wan 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to xxx.xxx.xxx.1 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'xxx.xxx.xxx.1' 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: skipping IPv6 default route 
Apr  4 02:35:14 opnsense opnsense: plugins_configure monitor () 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: Removing static route for monitor 8.8.8.8 via xxx.xxx.xxx.1 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: Adding static route for monitor 8.8.8.8 via xxx.xxx.xxx.1 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_1 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_2 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_3 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_4 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_5 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_6 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_7 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_7_VPNV4 monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:14 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:15 opnsense opnsense: plugins_configure vpn (,wan) 
Apr  4 02:35:15 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:15 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:15 opnsense opnsense: /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface WAN. 
Apr  4 02:35:15 opnsense kernel: ovpns4: link state changed to DOWN
Apr  4 02:35:16 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:35:16 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:16 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:16 opnsense kernel: ovpns4: link state changed to UP
Apr  4 02:35:16 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN server 4 instance started on PID 91036. 
Apr  4 02:35:16 opnsense opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 'ovpns4' 
Apr  4 02:35:16 opnsense opnsense: /usr/local/etc/rc.newwanip: Interface '' is disabled or empty, nothing to do. 
Apr  4 02:35:17 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:17 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:17 opnsense kernel: ovpnc7: link state changed to DOWN
Apr  4 02:35:18 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:18 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:18 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN client 7 instance started on PID 18678. 
Apr  4 02:35:19 opnsense kernel: ovpnc7: link state changed to UP
Apr  4 02:35:19 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:19 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:19 opnsense kernel: ovpnc3: link state changed to DOWN
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 'ovpnc7' 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: On (IP address: 192.168.43.6) (interface: VPN_7[opt8]) (real interface: ovpnc7). 
Apr  4 02:35:19 opnsense opnsense: plugins_configure hosts () 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt8' 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to wan 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: skipping IPv4 default route 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: skipping IPv6 default route 
Apr  4 02:35:19 opnsense opnsense: plugins_configure monitor () 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: Removing static route for monitor 8.8.8.8 via xxx.xxx.xxx.1 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: Adding static route for monitor 8.8.8.8 via xxx.xxx.xxx.1 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_1 monitor address is empty, skipping. 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_2 monitor address is empty, skipping. 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_3 monitor address is empty, skipping. 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_4 monitor address is empty, skipping. 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_5 monitor address is empty, skipping. 
Apr  4 02:35:19 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_6 monitor address is empty, skipping. 
Apr  4 02:35:20 opnsense opnsense: plugins_configure vpn (,opt8) 
Apr  4 02:35:20 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:20 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:20 opnsense opnsense: /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface VPN_7. 
Apr  4 02:35:20 opnsense opnsense: plugins_configure newwanip (,opt8) 
Apr  4 02:35:21 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:21 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:21 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN client 3 instance started on PID 70946. 
Apr  4 02:35:22 opnsense kernel: re1: link state changed to DOWN
Apr  4 02:35:22 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:22 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:22 opnsense kernel: ovpnc1: link state changed to DOWN
Apr  4 02:35:23 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:23 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:23 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN client 1 instance started on PID 21978. 
Apr  4 02:35:24 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:24 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:24 opnsense kernel: ovpnc2: link state changed to DOWN
Apr  4 02:35:25 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:25 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:25 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN client 2 instance started on PID 61599. 
Apr  4 02:35:26 opnsense opnsense: plugins_configure newwanip (,wan) 
Apr  4 02:35:26 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:26 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:26 opnsense dhclient[86233]: bound to yyy.yyy.yyy.yyy -- renewal in 43200 seconds. 
Apr  4 02:35:26 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: entering configure using 'wan' 
Apr  4 02:35:26 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv4 default gateway set to wan 
Apr  4 02:35:26 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: setting IPv4 default route to xxx.xxx.xxx.1 
Apr  4 02:35:26 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: keeping current default gateway 'xxx.xxx.xxx.1' 
Apr  4 02:35:26 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:35:26 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv6 default route 
Apr  4 02:35:26 opnsense opnsense: plugins_configure ipsec (,wan) 
Apr  4 02:35:26 opnsense opnsense: plugins_configure dhcp () 
Apr  4 02:35:26 opnsense opnsense: plugins_configure dns () 
Apr  4 02:35:27 opnsense kernel: arp: 192.168.8.1 moved from 38:3f:10:06:24:0c to 38:3f:10:05:47:4a on bridge0
Apr  4 02:35:33 opnsense kernel: re1: link state changed to UP
Apr  4 02:35:34 opnsense kernel: re1: link state changed to DOWN
Apr  4 02:35:45 opnsense kernel: re1: link state changed to UP
Apr  4 02:35:45 opnsense dhclient[71216]: DHCPREQUEST on re1 to 255.255.255.255 port 67 
Apr  4 02:35:45 opnsense dhclient[71216]: DHCPACK from 10.244.7.254 
Apr  4 02:35:45 opnsense dhclient: Starting delete_old_states() 
Apr  4 02:35:45 opnsense dhclient: Comparing IPs: Old: yyy.yyy.yyy.yyy New: yyy.yyy.yyy.yyy 
Apr  4 02:35:45 opnsense dhclient: Comparing Routers: Old: xxx.xxx.xxx.1 New: xxx.xxx.xxx.1 
Apr  4 02:35:45 opnsense dhclient: New IP Address (re1): yyy.yyy.yyy.yyy 
Apr  4 02:35:45 opnsense dhclient: New Subnet Mask (re1): 255.255.240.0 
Apr  4 02:35:45 opnsense dhclient: New Broadcast Address (re1): zzz.zzz.zzz.zzz 
Apr  4 02:35:45 opnsense dhclient: New Routers (re1): xxx.xxx.xxx.1 
Apr  4 02:35:45 opnsense dhclient: route add default xxx.xxx.xxx.1 
Apr  4 02:35:45 opnsense dhclient: Creating resolv.conf 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 're1' 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: On (IP address: yyy.yyy.yyy.yyy) (interface: WAN[wan]) (real interface: re1). 
Apr  4 02:35:45 opnsense opnsense: plugins_configure hosts () 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'wan' 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to wan 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to xxx.xxx.xxx.1 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'xxx.xxx.xxx.1' 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: ROUTING: skipping IPv6 default route 
Apr  4 02:35:45 opnsense opnsense: plugins_configure monitor () 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: Removing static route for monitor 8.8.8.8 via xxx.xxx.xxx.1 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: Adding static route for monitor 8.8.8.8 via xxx.xxx.xxx.1 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_1 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_2 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_3 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_4 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_5 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_6 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_7 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_7_VPNV4 monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:45 opnsense opnsense: /usr/local/etc/rc.newwanip: The VPN_X monitor address is empty, skipping. 
Apr  4 02:35:46 opnsense opnsense: plugins_configure vpn (,wan) 
Apr  4 02:35:46 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:46 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:46 opnsense opnsense: /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface WAN. 
Apr  4 02:35:46 opnsense kernel: ovpns4: link state changed to DOWN
Apr  4 02:35:46 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet detached event for wan 
Apr  4 02:35:46 opnsense opnsense: /usr/local/etc/rc.linkup: Clearing states for stale wan route on re1 
Apr  4 02:35:46 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet attached event for wan 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: HOTPLUG: Configuring interface wan 
Apr  4 02:35:47 opnsense dhclient[39805]: dhclient already running, pid: 71216. 
Apr  4 02:35:47 opnsense dhclient[39805]: exiting. 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: The command '/sbin/dhclient -c '/var/etc/dhclient_wan.conf' -p '/var/run/dhclient.re1.pid' 're1'' returned exit code '1', the output was 'dhclient already running, pid: 71216. exiting.' 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: entering configure using 'wan' 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv4 default gateway set to opt4 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv4 default route 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv6 default route 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: Static Routes: Gateway IP could not be found for 0.0.0.0/1 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.linkup: Static Routes: Gateway IP could not be found for 195.123.195.37/32 
Apr  4 02:35:47 opnsense opnsense: plugins_configure ipsec (,wan) 
Apr  4 02:35:47 opnsense opnsense: plugins_configure dhcp () 
Apr  4 02:35:47 opnsense opnsense: plugins_configure dns () 
Apr  4 02:35:47 opnsense kernel: pflog0: promiscuous mode disabled
Apr  4 02:35:47 opnsense kernel: pflog0: promiscuous mode enabled
Apr  4 02:35:47 opnsense kernel: ovpns4: link state changed to UP
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN server 4 instance started on PID 2953. 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 'ovpns4' 
Apr  4 02:35:47 opnsense opnsense: /usr/local/etc/rc.newwanip: Interface '' is disabled or empty, nothing to do. 
Apr  4 02:35:47 opnsense kernel: ovpns4: link state changed to DOWN
Apr  4 02:35:47 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:48 opnsense kernel: ovpnc7: link state changed to DOWN
Apr  4 02:35:48 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:49 opnsense kernel: arp: 192.168.8.1 moved from 38:3f:10:05:47:4a to 38:3f:10:06:24:0c on bridge0
Apr  4 02:35:49 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:49 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:52 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:53 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:35:57 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:36:01 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:36:05 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:36:09 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:36:13 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:36:17 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:36:17 opnsense kernel: arpresolve: can't allocate llinfo for xxx.xxx.xxx.1 on re1
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet detached event for wan 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet attached event for wan 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: HOTPLUG: Configuring interface wan 
Apr  4 02:36:39 opnsense dhclient[23185]: dhclient already running, pid: 71216. 
Apr  4 02:36:39 opnsense dhclient[23185]: exiting. 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: The command '/sbin/dhclient -c '/var/etc/dhclient_wan.conf' -p '/var/run/dhclient.re1.pid' 're1'' returned exit code '1', the output was 'dhclient already running, pid: 71216. exiting.' 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: entering configure using 'wan' 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv4 default gateway set to opt4 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv4 default route 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv6 default route 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: Static Routes: Gateway IP could not be found for 0.0.0.0/1 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.linkup: Static Routes: Gateway IP could not be found for 195.123.195.37/32 
Apr  4 02:36:39 opnsense opnsense: plugins_configure ipsec (,wan) 
Apr  4 02:36:39 opnsense opnsense: plugins_configure dhcp () 
Apr  4 02:36:39 opnsense opnsense: plugins_configure dns () 
Apr  4 02:36:39 opnsense opnsense: /usr/local/etc/rc.newwanip: OpenVPN client 7 instance started on PID 86403. 
Apr  4 02:36:40 opnsense kernel: arp: 192.168.8.1 moved from 38:3f:10:06:24:0c to 38:3f:10:05:47:4a on bridge0
Apr  4 02:37:19 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:38:21 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:39:23 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:40:25 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:41:26 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:42:28 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:43:30 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:44:32 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:46:35 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:47:37 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:48:32 opnsense kernel: re1: link state changed to DOWN
Apr  4 02:48:32 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet detached event for wan 
Apr  4 02:48:35 opnsense kernel: re1: link state changed to UP
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: DEVD Ethernet attached event for wan 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: HOTPLUG: Configuring interface wan 
Apr  4 02:48:35 opnsense dhclient[13720]: dhclient already running, pid: 71216. 
Apr  4 02:48:35 opnsense dhclient[13720]: exiting. 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: The command '/sbin/dhclient -c '/var/etc/dhclient_wan.conf' -p '/var/run/dhclient.re1.pid' 're1'' returned exit code '1', the output was 'dhclient already running, pid: 71216. exiting.' 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: entering configure using 'wan' 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv4 default gateway set to opt4 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv4 default route 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: IPv6 default gateway set to opt5 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: ROUTING: skipping IPv6 default route 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: Static Routes: Gateway IP could not be found for 0.0.0.0/1 
Apr  4 02:48:35 opnsense opnsense: /usr/local/etc/rc.linkup: Static Routes: Gateway IP could not be found for 195.123.195.37/32 
Apr  4 02:48:35 opnsense opnsense: plugins_configure ipsec (,wan) 
Apr  4 02:48:35 opnsense opnsense: plugins_configure dhcp () 
Apr  4 02:48:35 opnsense opnsense: plugins_configure dns () 
Apr  4 02:48:39 opnsense kernel: arp: 192.168.8.1 moved from 38:3f:10:06:24:0c to 38:3f:10:05:47:4a on bridge0
Apr  4 02:48:39 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:49:41 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)
Apr  4 02:50:43 opnsense kernel: sonewconn: pcb 0xfffff801e66d1740: Listen queue overflow: 151 already in queue awaiting acceptance (8 occurrences)

Environment
Software version used and hardware type if relevant.
e.g.:

OPNsense 19.7.10-amd64
FreeBSD 11.2-RELEASE-p16-HBSD
OpenSSL 1.0.2u 20 Dec 2019

@AdSchellevis AdSchellevis added the support Community support label Apr 4, 2020
@fichtner
Copy link
Member

fichtner commented Apr 5, 2020

There might be a race involved, between:

killbypid("/var/run/dhclient.{$wanif}.pid", 'TERM', true);

and

mwexecf('/sbin/dhclient -c %s -p %s %s', array(
"/var/etc/dhclient_{$interface}.conf",
"/var/run/dhclient.{$wanif}.pid",
$wanif
));

In the same function... if dhclient does not respond to SIGTERM it is likely stuck in the system forever.

If you can, please re-evaluate on 20.1 as 19.7 is no longer maintained.

@dupsatou
Copy link

I'm running a newer version and still having this issue as well:
OPNsense 20.7.1-amd64
FreeBSD 12.1-RELEASE-p8-HBSD
OpenSSL 1.1.1g 21 Apr 2020

My WAN connection will work fine for days with a DHCP renewal every 900 seconds from my ISP (Frontier FIOS). However, all of a sudden the WAN stops working and I see the exact same series of events seen above.

@AdSchellevis
Copy link
Member

This issue has been automatically timed-out (after 180 days of inactivity).

For more information about the policies for this repository,
please read https://github.com/opnsense/core/blob/master/CONTRIBUTING.md for further details.

If someone wants to step up and work on this issue,
just let us know, so we can reopen the issue and assign an owner to it.

@AdSchellevis AdSchellevis added the help wanted Contributor missing / timeout label Nov 5, 2020
@dupsatou
Copy link

dupsatou commented Nov 5, 2020

Wanted to add, after digging in more and troubleshooting I do not believe my issue on 20.7.1 was related to this old one so this may be better now. Mine appeared to be related to the way my ISP handled DHCP renew requests going to the gateway and seems to be fixed so far based on what I did here: https://forum.opnsense.org/index.php?topic=19143.0

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
help wanted Contributor missing / timeout support Community support
Development

No branches or pull requests

4 participants