Project

General

Profile

Actions

Bug #5948

closed

WAN with DHCPv6 gets stuck in a loop reconfiguring

Added by Jim Pingle over 8 years ago. Updated over 8 years ago.

Status:
Resolved
Priority:
High
Category:
DHCP (IPv6)
Target version:
Start date:
03/03/2016
Due date:
% Done:

0%

Estimated time:
Plus Target Version:
Release Notes:
Affected Version:
2.3
Affected Architecture:
All

Description

I can reproduce this on three boxes, seems to be specific to DHCPv6. In my case it's pulling PD as well.

Log is full of repeats of the same block over and over, here are a couple:

Mar 3 12:46:49     php-fpm     84731     /rc.newwanipv6: ROUTING: setting default route to 198.51.100.1
Mar 3 12:46:49     php-fpm     84731     /rc.newwanipv6: ROUTING: setting IPv6 default route to fe80::290:bff:fe37:a324%vmx0
Mar 3 12:46:49     check_reload_status         Reloading filter
Mar 3 12:46:49     php-fpm     99791     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:49     php-fpm     99791     /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan].
Mar 3 12:46:49     php-fpm     70561     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:49     php-fpm     70561     /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan].
Mar 3 12:46:50     php-fpm     99791     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:50     php-fpm     99791     /rc.newwanipv6: rc.newwanipv6: on (IP address: 2001:db8::ffff:87fe) (interface: wan) (real interface: vmx0).
Mar 3 12:46:50     xinetd     22214     Starting reconfiguration
Mar 3 12:46:50     xinetd     22214     Swapping defaults
Mar 3 12:46:50     xinetd     22214     readjusting service 6969-udp
Mar 3 12:46:50     xinetd     22214     Reconfigured: new=0 old=1 dropped=0 (services)
Mar 3 12:46:51     php-fpm     99791     /rc.newwanipv6: ROUTING: setting default route to 198.51.100.1
Mar 3 12:46:51     php-fpm     99791     /rc.newwanipv6: ROUTING: setting IPv6 default route to fe80::290:bff:fe37:a324%vmx0
Mar 3 12:46:51     check_reload_status         Reloading filter
Mar 3 12:46:51     php-fpm     70561     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:51     php-fpm     70561     /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan].
Mar 3 12:46:51     php-fpm     84731     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:51     php-fpm     84731     /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan].
Mar 3 12:46:53     xinetd     22214     Starting reconfiguration
Mar 3 12:46:53     xinetd     22214     Swapping defaults
Mar 3 12:46:53     xinetd     22214     readjusting service 6969-udp
Mar 3 12:46:53     xinetd     22214     Reconfigured: new=0 old=1 dropped=0 (services)
Mar 3 12:46:53     php-fpm     84731     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:53     php-fpm     84731     /rc.newwanipv6: rc.newwanipv6: on (IP address: 2001:db8::ffff:87fe) (interface: wan) (real interface: vmx0).
Mar 3 12:46:54     php-fpm     84731     /rc.newwanipv6: ROUTING: setting default route to 198.51.100.1
Mar 3 12:46:54     php-fpm     84731     /rc.newwanipv6: ROUTING: setting IPv6 default route to fe80::290:bff:fe37:a324%vmx0
Mar 3 12:46:54     check_reload_status         Reloading filter
Mar 3 12:46:54     php-fpm     70561     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:54     php-fpm     70561     /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan].
Mar 3 12:46:54     php-fpm     99791     /rc.newwanipv6: rc.newwanipv6: Info: starting on vmx0.
Mar 3 12:46:54     php-fpm     99791     /rc.newwanipv6: rc.newwanipv6: No IPv6 address found for interface WAN [wan].
Mar 3 12:46:55     xinetd     22214     Starting reconfiguration
Mar 3 12:46:55     xinetd     22214     Swapping defaults
Mar 3 12:46:55     xinetd     22214     readjusting service 6969-udp
Mar 3 12:46:55     xinetd     22214     Reconfigured: new=0 old=1 dropped=0 (services) 

dpinger is also being restarted, as are radvd, dnsmasq/unbound, and so on.

DHCP log from a couple restarts:

Mar 3 12:49:34     dhcp6c     41883     restarting
Mar 3 12:49:34     dhcp6c     41883     status code: success
Mar 3 12:49:34     dhcp6c     41883     status code: success
Mar 3 12:49:36     dhcp6c     41883     T1(2250) and/or T2(3600) is locally determined
Mar 3 12:49:36     dhcp6c     41883     T1(2250) and/or T2(3600) is locally determined
Mar 3 12:49:37     dhcpd         Internet Systems Consortium DHCP Server 4.3.3-P1
Mar 3 12:49:37     dhcpd         Copyright 2004-2016 Internet Systems Consortium.
Mar 3 12:49:37     dhcpd         All rights reserved.
Mar 3 12:49:37     dhcpd         For info, please visit https://www.isc.org/software/dhcp/
Mar 3 12:49:37     dhcpd         Config file: /etc/dhcpdv6.conf
Mar 3 12:49:37     dhcpd         Database file: /var/db/dhcpd6.leases
Mar 3 12:49:37     dhcpd         PID file: /var/run/dhcpdv6.pid
Mar 3 12:49:37     dhcpd         Internet Systems Consortium DHCP Server 4.3.3-P1
Mar 3 12:49:37     dhcpd         Copyright 2004-2016 Internet Systems Consortium.
Mar 3 12:49:37     dhcpd         All rights reserved.
Mar 3 12:49:37     dhcpd         For info, please visit https://www.isc.org/software/dhcp/
Mar 3 12:49:37     dhcpd         Wrote 0 deleted host decls to leases file.
Mar 3 12:49:37     dhcpd         Wrote 0 new dynamic host decls to leases file.
Mar 3 12:49:37     dhcpd         Wrote 2 NA, 0 TA, 0 PD leases to lease file.
Mar 3 12:49:37     dhcpd         Bound to *:547
Mar 3 12:49:37     dhcpd         Listening on Socket/5/vmx1/2001:db8:1:ef00::/64
Mar 3 12:49:37     dhcpd         Sending on Socket/5/vmx1/2001:db8:1:ef00::/64
Mar 3 12:49:37     dhcpd         Server starting service.
Mar 3 12:49:37     dhcp6c     41883     restarting
Mar 3 12:49:37     dhcp6c     41883     status code: success
Mar 3 12:49:37     dhcp6c     41883     status code: success
Mar 3 12:49:38     dhcp6c     41883     T1(2250) and/or T2(3600) is locally determined
Mar 3 12:49:38     dhcp6c     41883     T1(2250) and/or T2(3600) is locally determined
Mar 3 12:49:40     dhcpd         Internet Systems Consortium DHCP Server 4.3.3-P1
Mar 3 12:49:40     dhcpd         Copyright 2004-2016 Internet Systems Consortium.
Mar 3 12:49:40     dhcpd         All rights reserved.
Mar 3 12:49:40     dhcpd         For info, please visit https://www.isc.org/software/dhcp/
Mar 3 12:49:40     dhcpd         Config file: /etc/dhcpdv6.conf
Mar 3 12:49:40     dhcpd         Database file: /var/db/dhcpd6.leases
Mar 3 12:49:40     dhcpd         PID file: /var/run/dhcpdv6.pid
Mar 3 12:49:40     dhcpd         Internet Systems Consortium DHCP Server 4.3.3-P1
Mar 3 12:49:40     dhcpd         Copyright 2004-2016 Internet Systems Consortium.
Mar 3 12:49:40     dhcpd         All rights reserved.
Mar 3 12:49:40     dhcpd         For info, please visit https://www.isc.org/software/dhcp/
Mar 3 12:49:40     dhcpd         Wrote 0 deleted host decls to leases file.
Mar 3 12:49:40     dhcpd         Wrote 0 new dynamic host decls to leases file.
Mar 3 12:49:40     dhcpd         Wrote 2 NA, 0 TA, 0 PD leases to lease file.
Mar 3 12:49:40     dhcpd         Bound to *:547
Mar 3 12:49:40     dhcpd         Listening on Socket/5/vmx1/2001:db8:1:ef00::/64
Mar 3 12:49:40     dhcpd         Sending on Socket/5/vmx1/2001:db8:1:ef00::/64
Mar 3 12:49:40     dhcpd         Server starting service.
Mar 3 12:49:40     dhcp6c     41883     restarting
Mar 3 12:49:40     dhcp6c     41883     status code: success
Mar 3 12:49:40     dhcp6c     41883     status code: success
Mar 3 12:49:42     dhcp6c     41883     T1(2250) and/or T2(3600) is locally determined
Mar 3 12:49:42     dhcp6c     41883     T1(2250) and/or T2(3600) is locally determined
Mar 3 12:49:43     dhcpd         Internet Systems Consortium DHCP Server 4.3.3-P1
Mar 3 12:49:43     dhcpd         Copyright 2004-2016 Internet Systems Consortium.
Mar 3 12:49:43     dhcpd         All rights reserved.
Mar 3 12:49:43     dhcpd         For info, please visit https://www.isc.org/software/dhcp/
Mar 3 12:49:43     dhcpd         Config file: /etc/dhcpdv6.conf
Mar 3 12:49:43     dhcpd         Database file: /var/db/dhcpd6.leases
Mar 3 12:49:43     dhcpd         PID file: /var/run/dhcpdv6.pid
Mar 3 12:49:43     dhcpd         Internet Systems Consortium DHCP Server 4.3.3-P1
Mar 3 12:49:43     dhcpd         Copyright 2004-2016 Internet Systems Consortium.
Mar 3 12:49:43     dhcpd         All rights reserved.
Mar 3 12:49:43     dhcpd         For info, please visit https://www.isc.org/software/dhcp/
Mar 3 12:49:43     dhcpd         Wrote 0 deleted host decls to leases file.
Mar 3 12:49:43     dhcpd         Wrote 0 new dynamic host decls to leases file.
Mar 3 12:49:43     dhcpd         Wrote 2 NA, 0 TA, 0 PD leases to lease file.
Mar 3 12:49:43     dhcpd         Bound to *:547
Mar 3 12:49:43     dhcpd         Listening on Socket/5/vmx1/2001:db8:1:ef00::/64
Mar 3 12:49:43     dhcpd         Sending on Socket/5/vmx1/2001:db8:1:ef00::/64
Mar 3 12:49:43     dhcpd         Server starting service.
Mar 3 12:49:43     dhcp6c     41883     restarting 

Seems like it might possibly be related to some work done on DHCPv6 recently. Static IPv6 is unaffected.

Actions #1

Updated by Chris Buechler over 8 years ago

  • Assignee set to Chris Buechler

I was looking at this earlier.

Actions #2

Updated by Chris Buechler over 8 years ago

  • Status changed from Confirmed to Resolved

this is fixed, re-opened #5945 after reverting that change.

Actions

Also available in: Atom PDF