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-p3

    After 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.


  • Rebel Alliance Global Moderator

    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


  • Rebel Alliance Global Moderator

    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)