<div dir="ltr"><div>Thanks, we are tracking this.<br></div>If it starts to appear frequently, please let me know.<br></div><div class="gmail_extra"><br><div class="gmail_quote">On Mon, Sep 5, 2016 at 12:11 AM, Nir Soffer <span dir="ltr"><<a href="mailto:nsoffer@redhat.com" target="_blank">nsoffer@redhat.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">The tests passed after retriggering this build, this is probably a<br>
timing issue in the tests.<br>
<div class="HOEnZb"><div class="h5"><br>
On Sun, Sep 4, 2016 at 11:45 PM, Nir Soffer <<a href="mailto:nsoffer@redhat.com">nsoffer@redhat.com</a>> wrote:<br>
> Hi all,<br>
><br>
> These tests seems to fail unrelated builds:<br>
> <a href="http://jenkins.ovirt.org/job/vdsm_master_check-patch-fc24-x86_64/1600/console" rel="noreferrer" target="_blank">http://jenkins.ovirt.org/job/<wbr>vdsm_master_check-patch-fc24-<wbr>x86_64/1600/console</a><br>
><br>
> Seen it once, please check.<br>
><br>
> 19:43:05 ==============================<wbr>==============================<wbr>==========<br>
> 19:43:05 ERROR: test_local_auto_with_dynamic_<wbr>address_from_ra<br>
> (network.netinfo_test.<wbr>TestIPv6Addresses)<br>
> 19:43:05 ------------------------------<wbr>------------------------------<wbr>----------<br>
> 19:43:05 Traceback (most recent call last):<br>
> 19:43:05 File<br>
> "/home/jenkins/workspace/vdsm_<wbr>master_check-patch-fc24-x86_<wbr>64/vdsm/tests/testValidation.<wbr>py",<br>
> line 97, in wrapper<br>
> 19:43:05 return f(*args, **kwargs)<br>
> 19:43:05 File<br>
> "/home/jenkins/workspace/vdsm_<wbr>master_check-patch-fc24-x86_<wbr>64/vdsm/tests/network/netinfo_<wbr>test.py",<br>
> line 419, in test_local_auto_with_dynamic_<wbr>address_from_ra<br>
> 19:43:05 IPV6_NETPREFIX_LEN, family=6)<br>
> 19:43:05 File "/usr/lib64/python2.7/<wbr>contextlib.py", line 24, in __exit__<br>
> 19:43:05 self.gen.next()<br>
> 19:43:05 File<br>
> "/home/jenkins/workspace/vdsm_<wbr>master_check-patch-fc24-x86_<wbr>64/vdsm/tests/network/<wbr>nettestlib.py",<br>
> line 454, in wait_for_ipv6<br>
> 19:43:05 raise Exception('IPv6 addresses has not been caught within the '<br>
> 19:43:05 Exception: IPv6 addresses has not been caught within the given timeout.<br>
> 19:43:05<br>
> 19:43:05 -------------------- >> begin captured logging << --------------------<br>
> 19:43:05 2016-09-04 19:42:32,949 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link add name veth_hU8rsgT1oy<br>
> type veth peer name veth_OgkZCPxdBp (cwd None)<br>
> 19:43:05 2016-09-04 19:42:32,962 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:32,965 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /usr/sbin/dnsmasq --dhcp-authoritative<br>
> -p 0 --dhcp-option=3 --dhcp-option=6 -i veth_hU8rsgT1oy -I lo -d<br>
> --bind-dynamic --enable-ra --dhcp-range=2001:1:1:1::,<wbr>slaac,2m (cwd<br>
> None)<br>
> 19:43:05 2016-09-04 19:42:33,475 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/service iptables status (cwd<br>
> None)<br>
> 19:43:05 2016-09-04 19:42:33,501 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = 'Redirecting to /bin/systemctl status<br>
> iptables.service\nRunning in chroot, ignoring request.\n'; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:33,502 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/iptables --wait -I INPUT -i<br>
> veth_hU8rsgT1oy -p udp --sport 68 --dport 67 -j ACCEPT (cwd None)<br>
> 19:43:05 2016-09-04 19:42:33,514 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:33,515 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/iptables --wait -I INPUT -i<br>
> veth_hU8rsgT1oy -p udp --sport 546 --dport 547 -j ACCEPT (cwd None)<br>
> 19:43:05 2016-09-04 19:42:33,523 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:33,525 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link set dev veth_OgkZCPxdBp<br>
> up (cwd None)<br>
> 19:43:05 2016-09-04 19:42:33,532 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:33,533 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link set dev veth_hU8rsgT1oy<br>
> up (cwd None)<br>
> 19:43:05 2016-09-04 19:42:33,543 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:33,543 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip -6 addr add dev<br>
> veth_hU8rsgT1oy 2001:1:1:1::1/64 (cwd None)<br>
> 19:43:05 2016-09-04 19:42:33,550 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:53,541 DEBUG [root] (MainThread) dnsmasq:<br>
> started, version 2.76 DNS disabled<br>
> 19:43:05 dnsmasq: compile time options: IPv6 GNU-getopt DBus no-i18n<br>
> IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset auth DNSSEC loop-detect<br>
> inotify<br>
> 19:43:05 dnsmasq: warning: interface veth_hU8rsgT1oy does not currently exist<br>
> 19:43:05 dnsmasq-dhcp: router advertisement on 2001:1:1:1::<br>
> 19:43:05 dnsmasq-dhcp: IPv6 router advertisement enabled<br>
> 19:43:05 dnsmasq-dhcp: RTR-ADVERT(veth_hU8rsgT1oy) 2001:1:1:1::<br>
> 19:43:05 dnsmasq-dhcp: RTR-ADVERT(veth_hU8rsgT1oy) 2001:1:1:1::<br>
> 19:43:05<br>
> 19:43:05 2016-09-04 19:42:53,542 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/service iptables status (cwd<br>
> None)<br>
> 19:43:05 2016-09-04 19:42:53,589 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = 'Redirecting to /bin/systemctl status<br>
> iptables.service\nRunning in chroot, ignoring request.\n'; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:53,590 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/iptables --wait -D INPUT -i<br>
> veth_hU8rsgT1oy -p udp --sport 68 --dport 67 -j ACCEPT (cwd None)<br>
> 19:43:05 2016-09-04 19:42:53,612 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:53,613 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/iptables --wait -D INPUT -i<br>
> veth_hU8rsgT1oy -p udp --sport 546 --dport 547 -j ACCEPT (cwd None)<br>
> 19:43:05 2016-09-04 19:42:53,630 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:53,631 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link del dev veth_hU8rsgT1oy<br>
> (cwd None)<br>
> 19:43:05 2016-09-04 19:42:53,669 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 --------------------- >> end captured logging << ---------------------<br>
> 19:43:05<br>
> 19:43:05 ==============================<wbr>==============================<wbr>==========<br>
> 19:43:05 FAIL: test_events_keys (network.netlink_test.<wbr>NetlinkEventMonitorTests)<br>
> 19:43:05 ------------------------------<wbr>------------------------------<wbr>----------<br>
> 19:43:05 Traceback (most recent call last):<br>
> 19:43:05 File<br>
> "/home/jenkins/workspace/vdsm_<wbr>master_check-patch-fc24-x86_<wbr>64/vdsm/tests/testValidation.<wbr>py",<br>
> line 97, in wrapper<br>
> 19:43:05 return f(*args, **kwargs)<br>
> 19:43:05 File<br>
> "/home/jenkins/workspace/vdsm_<wbr>master_check-patch-fc24-x86_<wbr>64/vdsm/tests/network/netlink_<wbr>test.py",<br>
> line 173, in test_events_keys<br>
> 19:43:05 for d in _caught])))<br>
> 19:43:05 AssertionError: Expected events have not been caught (in the<br>
> right order).<br>
> 19:43:05 Expected:<br>
> 19:43:05 {'event': 'new_link', 'name': 'dummy_40rKa'}<br>
> 19:43:05 {'event': 'new_addr', 'address': '<a href="http://192.0.2.1/24" rel="noreferrer" target="_blank">192.0.2.1/24</a>'}<br>
> 19:43:05 {'event': 'new_link', 'name': 'dummy_40rKa'}<br>
> 19:43:05 {'event': 'new_addr', 'family': 'inet6'}<br>
> 19:43:05 {'event': 'del_neigh'}<br>
> 19:43:05 {'event': 'del_addr', 'family': 'inet6'}<br>
> 19:43:05 {'event': 'del_addr', 'address': '<a href="http://192.0.2.1/24" rel="noreferrer" target="_blank">192.0.2.1/24</a>'}<br>
> 19:43:05 {'destination': '192.0.2.1', 'event': 'del_route'}<br>
> 19:43:05 {'event': 'del_link', 'name': 'dummy_40rKa'}.<br>
> 19:43:05 Caught:<br>
> 19:43:05 {'event': 'new_link', 'name': 'dummy_40rKa', 'address':<br>
> '6e:f3:b9:80:5d:20'}<br>
> 19:43:05 {'event': 'new_addr', 'family': 'inet', 'address': '<a href="http://192.0.2.1/24" rel="noreferrer" target="_blank">192.0.2.1/24</a>'}<br>
> 19:43:05 {'destination': '192.0.2.1', 'event': 'new_route', 'family': 'inet'}<br>
> 19:43:05 {'event': 'new_link', 'name': 'dummy_40rKa', 'address':<br>
> '6e:f3:b9:80:5d:20'}<br>
> 19:43:05 {'destination': '<a href="http://192.0.2.0/24" rel="noreferrer" target="_blank">192.0.2.0/24</a>', 'event': 'new_route', 'family': 'inet'}<br>
> 19:43:05 {'destination': '192.0.2.0', 'event': 'new_route', 'family': 'inet'}<br>
> 19:43:05 {'destination': '192.0.2.255', 'event': 'new_route', 'family': 'inet'}<br>
> 19:43:05 {'destination': 'ff00::/8', 'event': 'new_route', 'family': 'inet6'}<br>
> 19:43:05 {'destination': 'fe80::/64', 'event': 'new_route', 'family': 'inet6'}<br>
> 19:43:05 {'event': 'new_link', 'name': 'dummy_40rKa', 'address':<br>
> '6e:f3:b9:80:5d:20'}<br>
> 19:43:05 {'event': 'new_addr', 'family': 'inet6', 'address':<br>
> 'fe80::6cf3:b9ff:fe80:5d20/64'<wbr>}<br>
> 19:43:05 {'destination': 'fe80::6cf3:b9ff:fe80:5d20', 'event':<br>
> 'new_route', 'family': 'inet6'}<br>
> 19:43:05 {'destination': 'fe80::', 'event': 'new_route', 'family': 'inet6'}<br>
> 19:43:05 {'event': 'new_link', 'name': 'dummy_40rKa', 'address':<br>
> '6e:f3:b9:80:5d:20'}<br>
> 19:43:05 {'destination': 'fe80::/64', 'event': 'del_route', 'family': 'inet6'}<br>
> 19:43:05 {'destination': 'ff00::/8', 'event': 'del_route', 'family': 'inet6'}<br>
> 19:43:05 {'event': 'del_addr', 'family': 'inet6', 'address':<br>
> 'fe80::6cf3:b9ff:fe80:5d20/64'<wbr>}<br>
> 19:43:05 {'destination': 'fe80::', 'event': 'del_route', 'family': 'inet6'}<br>
> 19:43:05 {'destination': 'fe80::6cf3:b9ff:fe80:5d20', 'event':<br>
> 'del_route', 'family': 'inet6'}<br>
> 19:43:05 {'event': 'del_addr', 'family': 'inet', 'address': '<a href="http://192.0.2.1/24" rel="noreferrer" target="_blank">192.0.2.1/24</a>'}<br>
> 19:43:05 {'destination': '192.0.2.1', 'event': 'del_route', 'family': 'inet'}<br>
> 19:43:05 {'event': 'del_link', 'name': 'dummy_40rKa', 'address':<br>
> '6e:f3:b9:80:5d:20'}.<br>
> 19:43:05 -------------------- >> begin captured logging << --------------------<br>
> 19:43:05 2016-09-04 19:42:54,605 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link add name dummy_40rKa<br>
> type dummy (cwd None)<br>
> 19:43:05 2016-09-04 19:42:54,623 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:54,623 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip -4 addr add dev dummy_40rKa<br>
> <a href="http://192.0.2.1/24" rel="noreferrer" target="_blank">192.0.2.1/24</a> (cwd None)<br>
> 19:43:05 2016-09-04 19:42:54,640 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:05 2016-09-04 19:42:54,642 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link set dev dummy_40rKa up<br>
> (cwd None)<br>
> 19:43:05 2016-09-04 19:42:54,658 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:16 2016-09-04 19:42:54,659 DEBUG [root] (MainThread)<br>
> /usr/bin/taskset --cpu-list 0-1 /sbin/ip link del dev dummy_40rKa (cwd<br>
> None)<br>
> 19:43:16 2016-09-04 19:42:54,684 DEBUG [root] (MainThread) SUCCESS:<br>
> <err> = ''; <rc> = 0<br>
> 19:43:16 --------------------- >> end captured logging << ---------------------<br>
</div></div></blockquote></div><br></div>