IPv6 routing stopping
-
Need help to identify why IPv6 routing stopping after 3+ hours.
Dedicated hardware with 4 ethernet's, all intel - wan, 3 separate lan's
Provider Comcast.
pfSense version:
2.4.4-RELEASE (amd64)
built on Thu Sep 20 09:03:12 EDT 2018
FreeBSD 11.2-RELEASE-p3After reboot everything working, after some hours anything from any internal lan's stop passing out ipv6 (including pings in diagnostics if i select any source not wan).
Logs for dhcpv6 also stops with nothing unusual. Interface page shows that all interfaces have IPv6 assigned.
processes:[2.4.4-RELEASE][admin@pfsense.domain]/root: ps -aux | grep dhcp root 311 0.0 0.0 4144 1964 - Is 16:54 0:00.00 /usr/local/sbin/dhcpleases6 -c /usr/local/bin/php-cgi -f /usr/local/sbin/prefixes.php|/bin/sh -l /var/dhcpd/var/db/dhc _dhcp 13208 0.0 0.0 6448 2408 - SCs 13:33 0:01.51 dhclient: em2 (dhclient) root 33415 0.0 0.0 6392 2544 - Ss 13:33 0:03.62 /usr/sbin/syslogd -s -c -c -l /var/dhcpd/var/run/log -P /var/run/syslog.pid -f /etc/syslog.conf root 54488 0.0 0.0 6340 2268 - Is 13:33 0:25.02 /usr/local/sbin/dhcp6c -d -c /var/etc/dhcp6c_wan.conf -p /var/run/dhcp6c_em2.pid em2 root 56238 0.0 0.0 6196 2216 - Is 16:54 0:00.19 /usr/local/sbin/dhcpleases -l /var/dhcpd/var/db/dhcpd.leases -d housedomain -p /var/run/unbound.pid -u /var/unbound/dh dhcpd 86038 0.0 0.1 12576 7892 - Ss 16:53 0:02.10 /usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpd.conf -pf /var/run/dhcpd.pid em3 em1 e dhcpd 99803 0.0 0.1 10656 5484 - Ss 16:54 0:01.81 /usr/local/sbin/dhcpd -6 -user dhcpd -group _dhcp -chroot /var/dhcpd -cf /etc/dhcpdv6.conf -pf /var/run/dhcpdv6.pid em root 72752 0.0 0.0 6564 2464 0 S+ 08:22 0:00.00 grep dhcp [2.4.4-RELEASE][admin@pfsense.domain]/root: ps -aux | grep radvd root 55465 0.0 0.0 6264 3916 - Ss 13:33 0:14.85 /usr/local/sbin/radvd -p /var/run/radvd.pid -C /var/etc/radvd.conf -m syslog root 96078 0.0 0.0 6564 2460 0 S+ 08:23 0:00.00 grep radvd
dhcp6c log:
Oct 5 16:54:36 dhcp6c 54488 no responses were received Oct 5 16:54:34 dhcp6c 54488 no responses were received Oct 5 16:54:19 dhcp6c 54488 Sending Release Oct 5 16:54:19 dhcp6c 54488 Sending Release Oct 5 16:54:11 dhcp6c 54488 Sending Release Oct 5 16:54:11 dhcp6c 54488 Sending Release Oct 5 16:53:59 dhcp6c 54488 add an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:53:59 dhcp6c 54488 add an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:53:59 dhcp6c 54488 add an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:53:59 dhcp6c 54488 add an address 2601:xx:xxxx:xxf0:xxx:xxxx:xxxx:4e3d/64 on em3 Oct 5 16:53:59 dhcp6c 54488 dhcp6c Received REQUEST Oct 5 16:53:59 dhcp6c 54488 Sending Request Oct 5 16:53:57 dhcp6c 54488 Sending Release Oct 5 16:53:57 dhcp6c 54488 Sending Request Oct 5 16:53:57 dhcp6c 54488 Sending Release Oct 5 16:53:56 dhcp6c 54488 Sending Request Oct 5 16:53:55 dhcp6c 54488 Sending Release Oct 5 16:53:55 dhcp6c 54488 Sending Release Oct 5 16:53:55 dhcp6c 54488 Sending Solicit Oct 5 16:53:54 dhcp6c 54488 remove an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:53:54 dhcp6c 54488 remove an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:53:54 dhcp6c 54488 failed to remove an address on em3: Can't assign requested address Oct 5 16:53:54 dhcp6c 54488 Sending Release Oct 5 16:53:54 dhcp6c 54488 Start address release Oct 5 16:53:54 dhcp6c 54488 remove an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:53:54 dhcp6c 54488 Sending Release Oct 5 16:53:54 dhcp6c 54488 Start address release Oct 5 16:53:54 dhcp6c 54488 restarting Oct 5 16:52:02 dhcp6c 54488 no responses were received Oct 5 16:52:01 dhcp6c 54488 no responses were received Oct 5 16:51:44 dhcp6c 54488 Sending Release Oct 5 16:51:44 dhcp6c 54488 Sending Release Oct 5 16:51:36 dhcp6c 54488 Sending Release Oct 5 16:51:36 dhcp6c 54488 Sending Release Oct 5 16:51:23 dhcp6c 54488 add an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:51:23 dhcp6c 54488 add an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:51:23 dhcp6c 54488 add an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:51:23 dhcp6c 54488 add an address 2601:xx:xxxx:xxf0:xxx:xxxx:xxxx:4e3d/64 on em3 Oct 5 16:51:23 dhcp6c 54488 dhcp6c Received REQUEST Oct 5 16:51:23 dhcp6c 54488 Sending Request Oct 5 16:51:22 dhcp6c 54488 Sending Release Oct 5 16:51:22 dhcp6c 54488 Sending Release Oct 5 16:51:21 dhcp6c 54488 Sending Request Oct 5 16:51:20 dhcp6c 54488 Sending Request Oct 5 16:51:20 dhcp6c 54488 Sending Release Oct 5 16:51:20 dhcp6c 54488 Sending Release Oct 5 16:51:19 dhcp6c 54488 Sending Solicit Oct 5 16:51:19 dhcp6c 54488 remove an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:51:19 dhcp6c 54488 remove an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:51:19 dhcp6c 54488 failed to remove an address on em3: Can't assign requested address Oct 5 16:51:19 dhcp6c 54488 Sending Release Oct 5 16:51:19 dhcp6c 54488 Start address release Oct 5 16:51:19 dhcp6c 54488 remove an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:51:19 dhcp6c 54488 Sending Release Oct 5 16:51:19 dhcp6c 54488 Start address release Oct 5 16:51:19 dhcp6c 54488 restarting Oct 5 16:50:47 dhcp6c 54488 no responses were received Oct 5 16:50:31 dhcp6c 54488 no responses were received Oct 5 16:50:29 dhcp6c 54488 Sending Release Oct 5 16:50:17 dhcp6c 54488 add an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:50:17 dhcp6c 54488 add an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:50:17 dhcp6c 54488 add an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:50:17 dhcp6c 54488 add an address 2601:xx:xxxx:xxf0:xxx:xxxx:xxxx:4e3d/64 on em3 Oct 5 16:50:17 dhcp6c 54488 dhcp6c Received REQUEST Oct 5 16:50:17 dhcp6c 54488 Sending Request Oct 5 16:50:16 dhcp6c 54488 Sending Release Oct 5 16:50:10 dhcp6c 54488 Sending Request Oct 5 16:50:09 dhcp6c 54488 Sending Release Oct 5 16:50:09 dhcp6c 54488 Sending Release Oct 5 16:50:06 dhcp6c 54488 Sending Request Oct 5 16:50:05 dhcp6c 54488 Sending Release Oct 5 16:50:05 dhcp6c 54488 Sending Release Oct 5 16:50:04 dhcp6c 54488 Sending Request Oct 5 16:50:03 dhcp6c 54488 Sending Request Oct 5 16:50:03 dhcp6c 54488 Sending Release Oct 5 16:50:03 dhcp6c 54488 Sending Release Oct 5 16:50:02 dhcp6c 54488 Sending Solicit Oct 5 16:50:02 dhcp6c 54488 remove an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:50:02 dhcp6c 54488 remove an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:50:02 dhcp6c 54488 failed to remove an address on em3: Can't assign requested address Oct 5 16:50:02 dhcp6c 54488 Sending Release Oct 5 16:50:02 dhcp6c 54488 Start address release Oct 5 16:50:02 dhcp6c 54488 remove an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:50:02 dhcp6c 54488 Sending Release Oct 5 16:50:02 dhcp6c 54488 Start address release Oct 5 16:50:02 dhcp6c 54488 restarting Oct 5 16:49:21 dhcp6c 54488 no responses were received Oct 5 16:49:21 dhcp6c 54488 no responses were received Oct 5 16:49:07 dhcp6c 54488 Sending Release Oct 5 16:49:07 dhcp6c 54488 Sending Release Oct 5 16:49:00 dhcp6c 54488 Sending Release Oct 5 16:49:00 dhcp6c 54488 Sending Release Oct 5 16:48:49 dhcp6c 54488 add an address 2001:xxx:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 5 16:48:49 dhcp6c 54488 add an address 2601:xx:xxxx:xxf2:xxx:xxxx:xxxx:18e4/64 on em0 Oct 5 16:48:49 dhcp6c 54488 add an address 2601:xx:xxxx:xxf1:xxx:xxxx:xxxx:18e5/64 on em1 Oct 5 16:48:49 dhcp6c 54488 add an address 2601:xx:xxxx:xxf0:xxx:xxxx:xxxx:4e3d/64 on em3 Oct 5 16:48:49 dhcp6c 54488 dhcp6c Received REQUEST Oct 5 16:48:49 dhcp6c 54488 Sending Request Oct 5 16:48:47 dhcp6c 54488 Sending Release Oct 5 16:48:47 dhcp6c 54488 Sending Release
-
It's still happening and only correlation in errors on LAN interface (in 10 days it's showing 3023 In errors on LAN interface).
-
Why do you use DHCPv6? When there are only common clients on the different LAN interfaces just use Router Advertisement. No need for DHCPv6 I guess.
When it stops working you should get small steps. From a client try to ping your default gateway via IPv6. Try to ping a host in the Internet via IP, not DNS name. Try to resolve a hostname from the client.
At the same time you might want to tcpdump on your local interface at the router and / or the WAN interface.
Can you still ping the Internet from the firewall itself? -
The basic steps i tried:
- ping IPv6 from client stop working (name resolve still working as DNS set to pfsense)
- ping IPv6 from pfsense itself LAN interfaces not working
- ping IPv6 from pfsense itself WAN interface working
IP v4 working without any issues. Disable/Enable IPv6 on WAN interface fix issue until it fail next time.
DHCPv6 - i was assuming that i need it for local clients to obtain IPv6 addresses.
-
Hi,
I strongly suggest to read about IPv6 since there are more than just a few changes. Know what you do. Neighbor Discovery for example is very important when it comes to such issues. Have a look at the ICMPv6 traffic. It will give you a clou why it stops working. Disable DHCPv6 as you probably won't need it. Configure Router Advertisement.
-
I was on comcast for many years - and in my opinion their ipv6 deployment is broken.. You fail to renew your wan Ipv6 or it changes, now your lan side PD will change or fails. If your using dhcpv6 and the client hasn't renewed to get a new IP from the new prefix on the track lan side then stuff starts to break down, etc.
So you can either deal with all the nonsense of their deployment - changed PD on reboot, changes in the wind - just plain IPv6 stops working.. Or just get yourself a free tunnel from Hurricane Electric and get free /48 that you can use no matter what ISP your using, etc. I moved away from comcast to new isp that does not yet support ipv6 - no biggy still have my same /48
And pmisch is 100 correct there - there are more than a few changes. Its way more than just a longer address ;) HE also has a certification program for free for ipv6 you can run through.. This will expose you to most of different aspects of IPv6 - and I do still believe they give you a free tshirt once you get sage level. I sport mine all the time - but its starting to show its wear since got back in 2011...
-
Same for me. You can get a new one like this:
https://twitter.com/da_philouz/status/956506517150355456
@johnpoz said in IPv6 routing stopping:I sport mine all the time - but its starting to show its wear since got back in 2011
-
So they are still giving them out - nice.. I have a dead shirt from 30 years ago that still wear now and then.. Its holding up better than the sage one ;) Then again I wear it much more - any sort of techie thing would sport it..
Sad is get more attention when you wear a GD tie die then something spouting IPv6.. But everyone knows who the GD is, the guy in line behind you at the store prob doesn't have a clue to what IPv6 is..
-
I disabled DHCPv6. The behavior is ugly, it's release/request IPv6 every minute, while i see both pltime=335647, vltime=335647 to be good.
Oct 23 08:34:32 dhcp6c dhcp6c REQUEST on em2 - running rc.newwanipv6 Oct 23 08:34:18 dhcp6c 7736 executes /var/etc/dhcp6c_wan_script.sh Oct 23 08:34:18 dhcp6c 7736 add an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 23 08:34:18 dhcp6c 7736 create an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335800, vltime=471713874413166520 Oct 23 08:34:18 dhcp6c 7736 make an IA: NA-0 Oct 23 08:34:18 dhcp6c 7736 add an address 2601:xx:xxxx:47f2:xxx:xxxx:fe1c:18e4/64 on em0 Oct 23 08:34:18 dhcp6c 7736 add an address 2601:xx:xxxx:47f1:xxx:xxxx:fe1c:18e5/64 on em1 Oct 23 08:34:18 dhcp6c 7736 add an address 2601:xx:xxxx:47f0:xxx:xxxx:fed2:4e3d/64 on em3 Oct 23 08:34:18 dhcp6c 7736 create a prefix 2601:xx:xxxx:47f0::/60 pltime=335800, vltime=335800 Oct 23 08:34:18 dhcp6c 7736 make an IA: PD-0 Oct 23 08:34:18 dhcp6c 7736 nameserver[1] 2001:558:feed::2 Oct 23 08:34:18 dhcp6c 7736 nameserver[0] 2001:558:feed::1 Oct 23 08:34:18 dhcp6c 7736 dhcp6c Received REQUEST Oct 23 08:34:18 dhcp6c 7736 get DHCP option DNS, len 32 Oct 23 08:34:18 dhcp6c 7736 IA_PD prefix: 2601:xx:xxxx:47f0::/60 pltime=335800 vltime=335800 Oct 23 08:34:18 dhcp6c 7736 get DHCP option IA_PD prefix, len 25 Oct 23 08:34:18 dhcp6c 7736 IA_PD: ID=0, T1=167900, T2=268640 Oct 23 08:34:18 dhcp6c 7736 get DHCP option IA_PD, len 41 Oct 23 08:34:18 dhcp6c 7736 IA_NA address: 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335800 vltime=335800 Oct 23 08:34:18 dhcp6c 7736 get DHCP option IA address, len 24 Oct 23 08:34:18 dhcp6c 7736 IA_NA: ID=0, T1=167900, T2=268640 Oct 23 08:34:18 dhcp6c 7736 get DHCP option identity association, len 40 Oct 23 08:34:18 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0 Oct 23 08:34:18 dhcp6c 7736 get DHCP option server ID, len 14 Oct 23 08:34:18 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:b8 Oct 23 08:34:18 dhcp6c 7736 get DHCP option client ID, len 14 Oct 23 08:34:18 dhcp6c 7736 receive reply from fe80::201:5cff:fe87:1c46%em2 on em2 Oct 23 08:34:17 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=2, retrans=4102 Oct 23 08:34:17 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:34:17 dhcp6c 7736 set IA_PD Oct 23 08:34:17 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:17 dhcp6c 7736 set option request (len 4) Oct 23 08:34:17 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:17 dhcp6c 7736 set identity association Oct 23 08:34:17 dhcp6c 7736 set IA address Oct 23 08:34:17 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:17 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:17 dhcp6c 7736 Sending Request Oct 23 08:34:16 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=2, retrans=4030 Oct 23 08:34:16 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:34:16 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:16 dhcp6c 7736 set identity association Oct 23 08:34:16 dhcp6c 7736 set IA address Oct 23 08:34:16 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:16 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:16 dhcp6c 7736 Sending Release Oct 23 08:34:16 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=2, retrans=3827 Oct 23 08:34:16 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:34:16 dhcp6c 7736 set IA_PD Oct 23 08:34:16 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:16 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:16 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:16 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:16 dhcp6c 7736 Sending Release Oct 23 08:34:15 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=1, retrans=2088 Oct 23 08:34:15 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:34:15 dhcp6c 7736 set IA_PD Oct 23 08:34:15 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:15 dhcp6c 7736 set option request (len 4) Oct 23 08:34:15 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:15 dhcp6c 7736 set identity association Oct 23 08:34:15 dhcp6c 7736 set IA address Oct 23 08:34:15 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:15 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:15 dhcp6c 7736 Sending Request Oct 23 08:34:14 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=0, retrans=1007 Oct 23 08:34:14 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:34:14 dhcp6c 7736 set IA_PD Oct 23 08:34:14 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:14 dhcp6c 7736 set option request (len 4) Oct 23 08:34:14 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:14 dhcp6c 7736 set identity association Oct 23 08:34:14 dhcp6c 7736 set IA address Oct 23 08:34:14 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:14 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:14 dhcp6c 7736 a new XID (94c412) is generated Oct 23 08:34:14 dhcp6c 7736 Sending Request Oct 23 08:34:14 dhcp6c 7736 picked a server (ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0) Oct 23 08:34:14 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=1, retrans=2087 Oct 23 08:34:14 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:34:14 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:14 dhcp6c 7736 set identity association Oct 23 08:34:14 dhcp6c 7736 set IA address Oct 23 08:34:14 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:14 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:14 dhcp6c 7736 Sending Release Oct 23 08:34:14 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=1, retrans=1948 Oct 23 08:34:14 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:34:14 dhcp6c 7736 set IA_PD Oct 23 08:34:14 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:14 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:14 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:14 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:14 dhcp6c 7736 Sending Release Oct 23 08:34:13 dhcp6c 7736 reset timer for em2 to 0.968935 Oct 23 08:34:13 dhcp6c 7736 server ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0, pref=-1 Oct 23 08:34:13 dhcp6c 7736 get DHCP option DNS, len 32 Oct 23 08:34:13 dhcp6c 7736 IA_PD prefix: 2601:xx:xxxx:47f0::/60 pltime=335805 vltime=335805 Oct 23 08:34:13 dhcp6c 7736 get DHCP option IA_PD prefix, len 25 Oct 23 08:34:13 dhcp6c 7736 IA_PD: ID=0, T1=167902, T2=268644 Oct 23 08:34:13 dhcp6c 7736 get DHCP option IA_PD, len 41 Oct 23 08:34:13 dhcp6c 7736 IA_NA address: 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335805 vltime=335805 Oct 23 08:34:13 dhcp6c 7736 get DHCP option IA address, len 24 Oct 23 08:34:13 dhcp6c 7736 IA_NA: ID=0, T1=167902, T2=268644 Oct 23 08:34:13 dhcp6c 7736 get DHCP option identity association, len 40 Oct 23 08:34:13 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0 Oct 23 08:34:13 dhcp6c 7736 get DHCP option server ID, len 14 Oct 23 08:34:13 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:b8 Oct 23 08:34:13 dhcp6c 7736 get DHCP option client ID, len 14 Oct 23 08:34:13 dhcp6c 7736 receive advertise from fe80::201:5cff:fe87:1c46%em2 on em2 Oct 23 08:34:13 dhcp6c 7736 reset a timer on em2, state=SOLICIT, timeo=0, retrans=1097 Oct 23 08:34:13 dhcp6c 7736 send solicit to ff02::1:2%em2 Oct 23 08:34:13 dhcp6c 7736 set IA_PD Oct 23 08:34:13 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:13 dhcp6c 7736 set option request (len 4) Oct 23 08:34:13 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:13 dhcp6c 7736 set identity association Oct 23 08:34:13 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:13 dhcp6c 7736 a new XID (73f88a) is generated Oct 23 08:34:13 dhcp6c 7736 Sending Solicit Oct 23 08:34:13 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=128 Oct 23 08:34:13 dhcp6c 7736 removing an event on em2, state=INIT Oct 23 08:34:13 dhcp6c 7736 removing an event on em2, state=INIT Oct 23 08:34:13 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=375 Oct 23 08:34:13 dhcp6c 7736 remove an address 2601:xx:xxxx:47f2:xxx:xxxx:fe1c:18e4/64 on em0 Oct 23 08:34:13 dhcp6c 7736 remove an address 2601:xx:xxxx:47f1:xxx:xxxx:fe1c:18e5/64 on em1 Oct 23 08:34:13 dhcp6c 7736 failed to remove an address on em3: Can't assign requested address Oct 23 08:34:13 dhcp6c 7736 remove a site prefix 2601:xx:xxxx:47f0::/60 Oct 23 08:34:13 dhcp6c 7736 remove an IA: PD-0 Oct 23 08:34:13 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:34:13 dhcp6c 7736 set IA_PD Oct 23 08:34:13 dhcp6c 7736 set IA_PD prefix Oct 23 08:34:13 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:13 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:13 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:13 dhcp6c 7736 a new XID (cfe4fe) is generated Oct 23 08:34:13 dhcp6c 7736 Sending Release Oct 23 08:34:13 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=0, retrans=945 Oct 23 08:34:13 dhcp6c 7736 release an IA: PD-0 Oct 23 08:34:13 dhcp6c 7736 Start address release Oct 23 08:34:13 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=752 Oct 23 08:34:13 dhcp6c 7736 remove an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 23 08:34:13 dhcp6c 7736 remove an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 Oct 23 08:34:13 dhcp6c 7736 remove an IA: NA-0 Oct 23 08:34:13 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:34:13 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:34:13 dhcp6c 7736 set identity association Oct 23 08:34:13 dhcp6c 7736 set IA address Oct 23 08:34:13 dhcp6c 7736 set server ID (len 14) Oct 23 08:34:13 dhcp6c 7736 set client ID (len 14) Oct 23 08:34:13 dhcp6c 7736 a new XID (f5bf6d) is generated Oct 23 08:34:13 dhcp6c 7736 Sending Release Oct 23 08:34:13 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=0, retrans=1024 Oct 23 08:34:13 dhcp6c 7736 release an IA: NA-0 Oct 23 08:34:13 dhcp6c 7736 Start address release Oct 23 08:34:13 dhcp6c 7736 restarting Oct 23 08:33:24 dhcp6c 7736 removing an event on em2, state=RELEASE Oct 23 08:33:24 dhcp6c 7736 no responses were received Oct 23 08:33:23 dhcp6c 7736 removing an event on em2, state=RELEASE Oct 23 08:33:23 dhcp6c 7736 no responses were received Oct 23 08:33:23 dhcp6c 7736 got an expected reply, sleeping. Oct 23 08:33:23 dhcp6c 7736 removing server (ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0) Oct 23 08:33:23 dhcp6c 7736 removing an event on em2, state=REQUEST Oct 23 08:33:23 dhcp6c 7736 script "/var/etc/dhcp6c_wan_script.sh" terminated Oct 23 08:33:23 dhcp6c dhcp6c REQUEST on em2 - running rc.newwanipv6 Oct 23 08:33:11 dhcp6c 7736 executes /var/etc/dhcp6c_wan_script.sh Oct 23 08:33:11 dhcp6c 7736 add an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 23 08:33:11 dhcp6c 7736 create an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335867, vltime=471713874413166587 Oct 23 08:33:11 dhcp6c 7736 make an IA: NA-0 Oct 23 08:33:11 dhcp6c 7736 add an address 2601:xx:xxxx:47f2:xxx:xxxx:fe1c:18e4/64 on em0 Oct 23 08:33:11 dhcp6c 7736 add an address 2601:xx:xxxx:47f1:xxx:xxxx:fe1c:18e5/64 on em1 Oct 23 08:33:11 dhcp6c 7736 add an address 2601:xx:xxxx:47f0:xxx:xxxx:fed2:4e3d/64 on em3 Oct 23 08:33:11 dhcp6c 7736 create a prefix 2601:xx:xxxx:47f0::/60 pltime=335867, vltime=335867 Oct 23 08:33:11 dhcp6c 7736 make an IA: PD-0 Oct 23 08:33:11 dhcp6c 7736 nameserver[1] 2001:558:feed::2 Oct 23 08:33:11 dhcp6c 7736 nameserver[0] 2001:558:feed::1 Oct 23 08:33:11 dhcp6c 7736 dhcp6c Received REQUEST Oct 23 08:33:11 dhcp6c 7736 get DHCP option DNS, len 32 Oct 23 08:33:11 dhcp6c 7736 IA_PD prefix: 2601:xx:xxxx:47f0::/60 pltime=335867 vltime=335867 Oct 23 08:33:11 dhcp6c 7736 get DHCP option IA_PD prefix, len 25 Oct 23 08:33:11 dhcp6c 7736 IA_PD: ID=0, T1=167933, T2=268693 Oct 23 08:33:11 dhcp6c 7736 get DHCP option IA_PD, len 41 Oct 23 08:33:11 dhcp6c 7736 IA_NA address: 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335867 vltime=335867 Oct 23 08:33:11 dhcp6c 7736 get DHCP option IA address, len 24 Oct 23 08:33:11 dhcp6c 7736 IA_NA: ID=0, T1=167933, T2=268693 Oct 23 08:33:11 dhcp6c 7736 get DHCP option identity association, len 40 Oct 23 08:33:11 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0 Oct 23 08:33:11 dhcp6c 7736 get DHCP option server ID, len 14 Oct 23 08:33:11 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:b8 Oct 23 08:33:11 dhcp6c 7736 get DHCP option client ID, len 14 Oct 23 08:33:11 dhcp6c 7736 receive reply from fe80::201:5cff:fe87:1c46%em2 on em2 Oct 23 08:33:11 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=4, retrans=15730 Oct 23 08:33:11 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:33:11 dhcp6c 7736 set IA_PD Oct 23 08:33:11 dhcp6c 7736 set IA_PD prefix Oct 23 08:33:11 dhcp6c 7736 set option request (len 4) Oct 23 08:33:11 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:33:11 dhcp6c 7736 set identity association Oct 23 08:33:11 dhcp6c 7736 set IA address Oct 23 08:33:11 dhcp6c 7736 set server ID (len 14) Oct 23 08:33:11 dhcp6c 7736 set client ID (len 14) Oct 23 08:33:11 dhcp6c 7736 Sending Request Oct 23 08:33:09 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=4, retrans=15142 Oct 23 08:33:09 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:33:09 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:33:09 dhcp6c 7736 set identity association Oct 23 08:33:09 dhcp6c 7736 set IA address Oct 23 08:33:09 dhcp6c 7736 set server ID (len 14) Oct 23 08:33:09 dhcp6c 7736 set client ID (len 14) Oct 23 08:33:09 dhcp6c 7736 Sending Release Oct 23 08:33:09 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=4, retrans=13740 Oct 23 08:33:09 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:33:09 dhcp6c 7736 set IA_PD Oct 23 08:33:09 dhcp6c 7736 set IA_PD prefix Oct 23 08:33:09 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:33:09 dhcp6c 7736 set server ID (len 14) Oct 23 08:33:09 dhcp6c 7736 set client ID (len 14) Oct 23 08:33:09 dhcp6c 7736 Sending Release Oct 23 08:33:03 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=3, retrans=7595 Oct 23 08:33:03 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:33:03 dhcp6c 7736 set IA_PD Oct 23 08:33:03 dhcp6c 7736 set IA_PD prefix Oct 23 08:33:03 dhcp6c 7736 set option request (len 4) Oct 23 08:33:03 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:33:03 dhcp6c 7736 set identity association Oct 23 08:33:03 dhcp6c 7736 set IA address Oct 23 08:33:03 dhcp6c 7736 set server ID (len 14) Oct 23 08:33:03 dhcp6c 7736 set client ID (len 14) Oct 23 08:33:03 dhcp6c 7736 Sending Request Oct 23 08:33:02 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=3, retrans=7124 Oct 23 08:33:02 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:33:02 dhcp6c 7736 set IA_PD Oct 23 08:33:02 dhcp6c 7736 set IA_PD prefix Oct 23 08:33:02 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:33:02 dhcp6c 7736 set server ID (len 14) Oct 23 08:33:02 dhcp6c 7736 set client ID (len 14) Oct 23 08:33:02 dhcp6c 7736 Sending Release Oct 23 08:33:02 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=3, retrans=7265 Oct 23 08:33:02 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:33:02 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:33:02 dhcp6c 7736 set identity association Oct 23 08:33:02 dhcp6c 7736 set IA address Oct 23 08:33:02 dhcp6c 7736 set server ID (len 14) Oct 23 08:33:02 dhcp6c 7736 set client ID (len 14) Oct 23 08:33:02 dhcp6c 7736 Sending Release Oct 23 08:32:59 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=2, retrans=3650 Oct 23 08:32:59 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:32:59 dhcp6c 7736 set IA_PD Oct 23 08:32:59 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:59 dhcp6c 7736 set option request (len 4) Oct 23 08:32:59 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:59 dhcp6c 7736 set identity association Oct 23 08:32:59 dhcp6c 7736 set IA address Oct 23 08:32:59 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:59 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:59 dhcp6c 7736 Sending Request Oct 23 08:32:58 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=2, retrans=3650 Oct 23 08:32:58 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:32:58 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:58 dhcp6c 7736 set identity association Oct 23 08:32:58 dhcp6c 7736 set IA address Oct 23 08:32:58 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:58 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:58 dhcp6c 7736 Sending Release Oct 23 08:32:58 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=2, retrans=3742 Oct 23 08:32:58 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:32:58 dhcp6c 7736 set IA_PD Oct 23 08:32:58 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:58 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:58 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:58 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:58 dhcp6c 7736 Sending Release Oct 23 08:32:57 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=1, retrans=1832 Oct 23 08:32:57 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:32:57 dhcp6c 7736 set IA_PD Oct 23 08:32:57 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:57 dhcp6c 7736 set option request (len 4) Oct 23 08:32:57 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:57 dhcp6c 7736 set identity association Oct 23 08:32:57 dhcp6c 7736 set IA address Oct 23 08:32:57 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:57 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:57 dhcp6c 7736 Sending Request Oct 23 08:32:57 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=0, retrans=924 Oct 23 08:32:57 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:32:57 dhcp6c 7736 set IA_PD Oct 23 08:32:57 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:57 dhcp6c 7736 set option request (len 4) Oct 23 08:32:57 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:57 dhcp6c 7736 set identity association Oct 23 08:32:57 dhcp6c 7736 set IA address Oct 23 08:32:57 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:57 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:57 dhcp6c 7736 a new XID (3bd5e8) is generated Oct 23 08:32:57 dhcp6c 7736 Sending Request Oct 23 08:32:57 dhcp6c 7736 picked a server (ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0) Oct 23 08:32:56 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=1, retrans=1887 Oct 23 08:32:56 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:32:56 dhcp6c 7736 set IA_PD Oct 23 08:32:56 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:56 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:56 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:56 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:56 dhcp6c 7736 Sending Release Oct 23 08:32:56 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=1, retrans=1915 Oct 23 08:32:56 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:32:56 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:56 dhcp6c 7736 set identity association Oct 23 08:32:56 dhcp6c 7736 set IA address Oct 23 08:32:56 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:56 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:56 dhcp6c 7736 Sending Release Oct 23 08:32:56 dhcp6c 7736 reset timer for em2 to 0.972419 Oct 23 08:32:56 dhcp6c 7736 server ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0, pref=-1 Oct 23 08:32:56 dhcp6c 7736 get DHCP option DNS, len 32 Oct 23 08:32:56 dhcp6c 7736 IA_PD prefix: 2601:xx:xxxx:47f0::/60 pltime=335882 vltime=335882 Oct 23 08:32:56 dhcp6c 7736 get DHCP option IA_PD prefix, len 25 Oct 23 08:32:56 dhcp6c 7736 IA_PD: ID=0, T1=167941, T2=268705 Oct 23 08:32:56 dhcp6c 7736 get DHCP option IA_PD, len 41 Oct 23 08:32:56 dhcp6c 7736 IA_NA address: 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335882 vltime=335882 Oct 23 08:32:56 dhcp6c 7736 get DHCP option IA address, len 24 Oct 23 08:32:56 dhcp6c 7736 IA_NA: ID=0, T1=167941, T2=268705 Oct 23 08:32:56 dhcp6c 7736 get DHCP option identity association, len 40 Oct 23 08:32:56 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0 Oct 23 08:32:56 dhcp6c 7736 get DHCP option server ID, len 14 Oct 23 08:32:56 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:b8 Oct 23 08:32:56 dhcp6c 7736 get DHCP option client ID, len 14 Oct 23 08:32:56 dhcp6c 7736 receive advertise from fe80::201:5cff:fe87:1c46%em2 on em2 Oct 23 08:32:56 dhcp6c 7736 reset a timer on em2, state=SOLICIT, timeo=0, retrans=1025 Oct 23 08:32:56 dhcp6c 7736 send solicit to ff02::1:2%em2 Oct 23 08:32:56 dhcp6c 7736 set IA_PD Oct 23 08:32:56 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:56 dhcp6c 7736 set option request (len 4) Oct 23 08:32:56 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:56 dhcp6c 7736 set identity association Oct 23 08:32:56 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:56 dhcp6c 7736 a new XID (b830ca) is generated Oct 23 08:32:56 dhcp6c 7736 Sending Solicit Oct 23 08:32:55 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=278 Oct 23 08:32:55 dhcp6c 7736 removing an event on em2, state=INIT Oct 23 08:32:55 dhcp6c 7736 removing an event on em2, state=INIT Oct 23 08:32:55 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=384 Oct 23 08:32:55 dhcp6c 7736 remove an address 2601:xx:xxxx:47f2:xxx:xxxx:fe1c:18e4/64 on em0 Oct 23 08:32:55 dhcp6c 7736 remove an address 2601:xx:xxxx:47f1:xxx:xxxx:fe1c:18e5/64 on em1 Oct 23 08:32:55 dhcp6c 7736 failed to remove an address on em3: Can't assign requested address Oct 23 08:32:55 dhcp6c 7736 remove a site prefix 2601:xx:xxxx:47f0::/60 Oct 23 08:32:55 dhcp6c 7736 remove an IA: PD-0 Oct 23 08:32:55 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:32:55 dhcp6c 7736 set IA_PD Oct 23 08:32:55 dhcp6c 7736 set IA_PD prefix Oct 23 08:32:55 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:55 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:55 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:55 dhcp6c 7736 a new XID (e6729e) is generated Oct 23 08:32:55 dhcp6c 7736 Sending Release Oct 23 08:32:55 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=0, retrans=991 Oct 23 08:32:55 dhcp6c 7736 release an IA: PD-0 Oct 23 08:32:55 dhcp6c 7736 Start address release Oct 23 08:32:55 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=192 Oct 23 08:32:55 dhcp6c 7736 remove an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 23 08:32:55 dhcp6c 7736 remove an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 Oct 23 08:32:55 dhcp6c 7736 remove an IA: NA-0 Oct 23 08:32:55 dhcp6c 7736 send release to ff02::1:2%em2 Oct 23 08:32:55 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:32:55 dhcp6c 7736 set identity association Oct 23 08:32:55 dhcp6c 7736 set IA address Oct 23 08:32:55 dhcp6c 7736 set server ID (len 14) Oct 23 08:32:55 dhcp6c 7736 set client ID (len 14) Oct 23 08:32:55 dhcp6c 7736 a new XID (89103e) is generated Oct 23 08:32:55 dhcp6c 7736 Sending Release Oct 23 08:32:55 dhcp6c 7736 reset a timer on em2, state=RELEASE, timeo=0, retrans=955 Oct 23 08:32:55 dhcp6c 7736 release an IA: NA-0 Oct 23 08:32:55 dhcp6c 7736 Start address release Oct 23 08:32:55 dhcp6c 7736 restarting Oct 23 08:32:07 dhcp6c 7736 got an expected reply, sleeping. Oct 23 08:32:07 dhcp6c 7736 removing server (ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0) Oct 23 08:32:07 dhcp6c 7736 removing an event on em2, state=REQUEST Oct 23 08:32:07 dhcp6c 7736 script "/var/etc/dhcp6c_wan_script.sh" terminated Oct 23 08:32:07 dhcp6c dhcp6c REQUEST on em2 - running rc.newwanipv6 Oct 23 08:31:53 dhcp6c 7736 executes /var/etc/dhcp6c_wan_script.sh Oct 23 08:31:53 dhcp6c 7736 add an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151/128 on em2 Oct 23 08:31:53 dhcp6c 7736 create an address 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335944, vltime=471713874413166664 Oct 23 08:31:53 dhcp6c 7736 make an IA: NA-0 Oct 23 08:31:53 dhcp6c 7736 add an address 2601:xx:xxxx:47f2:xxx:xxxx:fe1c:18e4/64 on em0 Oct 23 08:31:53 dhcp6c 7736 add an address 2601:xx:xxxx:47f1:xxx:xxxx:fe1c:18e5/64 on em1 Oct 23 08:31:53 dhcp6c 7736 add an address 2601:xx:xxxx:47f0:xxx:xxxx:fed2:4e3d/64 on em3 Oct 23 08:31:53 dhcp6c 7736 create a prefix 2601:xx:xxxx:47f0::/60 pltime=335944, vltime=335944 Oct 23 08:31:53 dhcp6c 7736 make an IA: PD-0 Oct 23 08:31:53 dhcp6c 7736 nameserver[1] 2001:558:feed::2 Oct 23 08:31:53 dhcp6c 7736 nameserver[0] 2001:558:feed::1 Oct 23 08:31:53 dhcp6c 7736 dhcp6c Received REQUEST Oct 23 08:31:53 dhcp6c 7736 get DHCP option DNS, len 32 Oct 23 08:31:53 dhcp6c 7736 IA_PD prefix: 2601:xx:xxxx:47f0::/60 pltime=335944 vltime=335944 Oct 23 08:31:53 dhcp6c 7736 get DHCP option IA_PD prefix, len 25 Oct 23 08:31:53 dhcp6c 7736 IA_PD: ID=0, T1=167972, T2=268755 Oct 23 08:31:53 dhcp6c 7736 get DHCP option IA_PD, len 41 Oct 23 08:31:53 dhcp6c 7736 IA_NA address: 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335944 vltime=335944 Oct 23 08:31:53 dhcp6c 7736 get DHCP option IA address, len 24 Oct 23 08:31:53 dhcp6c 7736 IA_NA: ID=0, T1=167972, T2=268755 Oct 23 08:31:53 dhcp6c 7736 get DHCP option identity association, len 40 Oct 23 08:31:53 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0 Oct 23 08:31:53 dhcp6c 7736 get DHCP option server ID, len 14 Oct 23 08:31:53 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:b8 Oct 23 08:31:53 dhcp6c 7736 get DHCP option client ID, len 14 Oct 23 08:31:53 dhcp6c 7736 receive reply from fe80::201:5cff:fe87:1c46%em2 on em2 Oct 23 08:31:53 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=3, retrans=7121 Oct 23 08:31:53 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:31:53 dhcp6c 7736 set IA_PD Oct 23 08:31:53 dhcp6c 7736 set IA_PD prefix Oct 23 08:31:53 dhcp6c 7736 set option request (len 4) Oct 23 08:31:53 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:31:53 dhcp6c 7736 set identity association Oct 23 08:31:53 dhcp6c 7736 set IA address Oct 23 08:31:53 dhcp6c 7736 set server ID (len 14) Oct 23 08:31:53 dhcp6c 7736 set client ID (len 14) Oct 23 08:31:53 dhcp6c 7736 Sending Request Oct 23 08:31:49 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=2, retrans=3518 Oct 23 08:31:49 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:31:49 dhcp6c 7736 set IA_PD Oct 23 08:31:49 dhcp6c 7736 set IA_PD prefix Oct 23 08:31:49 dhcp6c 7736 set option request (len 4) Oct 23 08:31:49 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:31:49 dhcp6c 7736 set identity association Oct 23 08:31:49 dhcp6c 7736 set IA address Oct 23 08:31:49 dhcp6c 7736 set server ID (len 14) Oct 23 08:31:49 dhcp6c 7736 set client ID (len 14) Oct 23 08:31:49 dhcp6c 7736 Sending Request Oct 23 08:31:47 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=1, retrans=1737 Oct 23 08:31:47 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:31:47 dhcp6c 7736 set IA_PD Oct 23 08:31:47 dhcp6c 7736 set IA_PD prefix Oct 23 08:31:47 dhcp6c 7736 set option request (len 4) Oct 23 08:31:47 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:31:47 dhcp6c 7736 set identity association Oct 23 08:31:47 dhcp6c 7736 set IA address Oct 23 08:31:47 dhcp6c 7736 set server ID (len 14) Oct 23 08:31:47 dhcp6c 7736 set client ID (len 14) Oct 23 08:31:47 dhcp6c 7736 Sending Request Oct 23 08:31:47 dhcp6c 7736 reset a timer on em2, state=REQUEST, timeo=0, retrans=909 Oct 23 08:31:47 dhcp6c 7736 send request to ff02::1:2%em2 Oct 23 08:31:47 dhcp6c 7736 set IA_PD Oct 23 08:31:47 dhcp6c 7736 set IA_PD prefix Oct 23 08:31:47 dhcp6c 7736 set option request (len 4) Oct 23 08:31:47 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:31:47 dhcp6c 7736 set identity association Oct 23 08:31:47 dhcp6c 7736 set IA address Oct 23 08:31:47 dhcp6c 7736 set server ID (len 14) Oct 23 08:31:47 dhcp6c 7736 set client ID (len 14) Oct 23 08:31:47 dhcp6c 7736 a new XID (30d131) is generated Oct 23 08:31:47 dhcp6c 7736 Sending Request Oct 23 08:31:47 dhcp6c 7736 picked a server (ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0) Oct 23 08:31:46 dhcp6c 7736 reset timer for em2 to 0.964474 Oct 23 08:31:46 dhcp6c 7736 server ID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0, pref=-1 Oct 23 08:31:46 dhcp6c 7736 get DHCP option DNS, len 32 Oct 23 08:31:46 dhcp6c 7736 IA_PD prefix: 2601:xx:xxxx:47f0::/60 pltime=335952 vltime=335952 Oct 23 08:31:46 dhcp6c 7736 get DHCP option IA_PD prefix, len 25 Oct 23 08:31:46 dhcp6c 7736 IA_PD: ID=0, T1=167976, T2=268761 Oct 23 08:31:46 dhcp6c 7736 get DHCP option IA_PD, len 41 Oct 23 08:31:46 dhcp6c 7736 IA_NA address: 2001:558:xxxx:x:xxxx:xxxx:xxxx:1151 pltime=335952 vltime=335952 Oct 23 08:31:46 dhcp6c 7736 get DHCP option IA address, len 24 Oct 23 08:31:46 dhcp6c 7736 IA_NA: ID=0, T1=167976, T2=268761 Oct 23 08:31:46 dhcp6c 7736 get DHCP option identity association, len 40 Oct 23 08:31:46 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:f0 Oct 23 08:31:46 dhcp6c 7736 get DHCP option server ID, len 14 Oct 23 08:31:46 dhcp6c 7736 DUID: 00:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:b8 Oct 23 08:31:46 dhcp6c 7736 get DHCP option client ID, len 14 Oct 23 08:31:46 dhcp6c 7736 receive advertise from fe80::201:5cff:fe87:1c46%em2 on em2 Oct 23 08:31:46 dhcp6c 7736 reset a timer on em2, state=SOLICIT, timeo=0, retrans=1091 Oct 23 08:31:46 dhcp6c 7736 send solicit to ff02::1:2%em2 Oct 23 08:31:45 dhcp6c 7736 set IA_PD Oct 23 08:31:45 dhcp6c 7736 set IA_PD prefix Oct 23 08:31:45 dhcp6c 7736 set option request (len 4) Oct 23 08:31:45 dhcp6c 7736 set elapsed time (len 2) Oct 23 08:31:45 dhcp6c 7736 set identity association Oct 23 08:31:45 dhcp6c 7736 set client ID (len 14) Oct 23 08:31:45 dhcp6c 7736 a new XID (d6930e) is generated Oct 23 08:31:45 dhcp6c 7736 Sending Solicit Oct 23 08:31:45 dhcp6c 7736 reset a timer on em2, state=INIT, timeo=0, retrans=891 Oct 23 08:31:45 dhcp6c 7641 called Oct 23 08:31:45 dhcp6c 7641 called Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of closure [}] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of closure [}] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[4] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[sla-len] (7) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[2] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[sla-id] (6) Oct 23 08:31:45 dhcp6c 7641 <3>begin of closure [{] (1) Oct 23 08:31:45 dhcp6c 7641 <5>[em0] (3) Oct 23 08:31:45 dhcp6c 7641 <3>[prefix-interface] (16) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of closure [}] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[4] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[sla-len] (7) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[1] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[sla-id] (6) Oct 23 08:31:45 dhcp6c 7641 <3>begin of closure [{] (1) Oct 23 08:31:45 dhcp6c 7641 <5>[em1] (3) Oct 23 08:31:45 dhcp6c 7641 <3>[prefix-interface] (16) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of closure [}] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[4] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[sla-len] (7) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[0] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[sla-id] (6) Oct 23 08:31:45 dhcp6c 7641 <3>begin of closure [{] (1) Oct 23 08:31:45 dhcp6c 7641 <5>[em3] (3) Oct 23 08:31:45 dhcp6c 7641 <3>[prefix-interface] (16) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[infinity] (8) Oct 23 08:31:45 dhcp6c 7641 <3>[60] (2) Oct 23 08:31:45 dhcp6c 7641 <3>[/] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[::] (2) Oct 23 08:31:45 dhcp6c 7641 <3>[prefix] (6) Oct 23 08:31:45 dhcp6c 7641 <13>begin of closure [{] (1) Oct 23 08:31:45 dhcp6c 7641 <13>[0] (1) Oct 23 08:31:45 dhcp6c 7641 <13>[pd] (2) Oct 23 08:31:45 dhcp6c 7641 <3>[id-assoc] (8) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of closure [}] (1) Oct 23 08:31:45 dhcp6c 7641 <13>begin of closure [{] (1) Oct 23 08:31:45 dhcp6c 7641 <13>[0] (1) Oct 23 08:31:45 dhcp6c 7641 <13>[na] (2) Oct 23 08:31:45 dhcp6c 7641 <3>[id-assoc] (8) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>end of closure [}] (1) Oct 23 08:31:45 dhcp6c 7641 <3>comment [# we'd like some nameservers please] (35) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>["/var/etc/dhcp6c_wan_script.sh"] (31) Oct 23 08:31:45 dhcp6c 7641 <3>[script] (6) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[domain-name] (11) Oct 23 08:31:45 dhcp6c 7641 <3>[request] (7) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[domain-name-servers] (19) Oct 23 08:31:45 dhcp6c 7641 <3>[request] (7) Oct 23 08:31:45 dhcp6c 7641 <3>comment [# request prefix delegation] (27) Oct 23 08:31:45 dhcp6c 7641 <3>end of sentence [;] (1) Oct 23 08:31:45 dhcp6c 7641 <3>[0] (1)