Testing PullReq against Topology Tests

Build: #395 failed

Job: TopoTest 3.0 Branch failed

Build log

The build generated 2,104 lines of output.The output is too long and has been truncated to the last 1,000 lines. Download or view full build log

21-Apr-2019 14:03:38 r1 ok
21-Apr-2019 14:03:38 r2 ok
21-Apr-2019 14:03:38 r3 ok
21-Apr-2019 14:03:38 r4 ok
21-Apr-2019 14:03:38 PASSED
21-Apr-2019 14:03:38 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_binding
21-Apr-2019 14:03:38
21-Apr-2019 14:03:38 ** Verifying MPLS LDP binding
21-Apr-2019 14:03:38 ******************************************
21-Apr-2019 14:03:38
21-Apr-2019 14:03:38 r1 ok
21-Apr-2019 14:03:38 r2 ok
21-Apr-2019 14:03:38 r3 ok
21-Apr-2019 14:03:38 r4 ok
21-Apr-2019 14:03:38 PASSED
21-Apr-2019 14:03:39 ldp-topo1/test_ldp_topo1.py::test_zebra_ipv4_routingTable
21-Apr-2019 14:03:39
21-Apr-2019 14:03:39 ** Verifying Zebra IPv4 Routing Table
21-Apr-2019 14:03:39 ******************************************
21-Apr-2019 14:03:39
21-Apr-2019 14:03:39 r1 ok
21-Apr-2019 14:03:39 r2 ok
21-Apr-2019 14:03:39 r3 ok
21-Apr-2019 14:03:39 r4 ok
21-Apr-2019 14:03:39 PASSED
21-Apr-2019 14:03:40 ldp-topo1/test_ldp_topo1.py::test_mpls_table
21-Apr-2019 14:03:40
21-Apr-2019 14:03:40 ** Verifying MPLS table
21-Apr-2019 14:03:40 ******************************************
21-Apr-2019 14:03:40
21-Apr-2019 14:03:40 r1 ok
21-Apr-2019 14:03:40 r2 ok
21-Apr-2019 14:03:40 r3 ok
21-Apr-2019 14:03:40 r4 ok
21-Apr-2019 14:03:40 PASSED
21-Apr-2019 14:03:40 ldp-topo1/test_ldp_topo1.py::test_linux_mpls_routes
21-Apr-2019 14:03:40
21-Apr-2019 14:03:40 ** Verifying Linux Kernel MPLS routes
21-Apr-2019 14:03:40 ******************************************
21-Apr-2019 14:03:40
21-Apr-2019 14:03:40 r1 ok
21-Apr-2019 14:03:40 r2 ok
21-Apr-2019 14:03:40 r3 ok
21-Apr-2019 14:03:40 r4 ok
21-Apr-2019 14:03:40 PASSED
21-Apr-2019 14:03:40 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:03:40
21-Apr-2019 14:03:40 ** Verifying unexpected STDERR output from daemons
21-Apr-2019 14:03:40 ******************************************
21-Apr-2019 14:03:40
21-Apr-2019 14:03:40 2019-04-21 14:03:40,498 INFO: r1: stopping ldpd
21-Apr-2019 14:03:40 2019-04-21 14:03:40,506 INFO: r1: stopping ospfd
21-Apr-2019 14:03:40 2019-04-21 14:03:40,510 INFO: r1: stopping zebra
21-Apr-2019 14:03:40 2019-04-21 14:03:40,513 INFO: r1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:03:42 2019-04-21 14:03:42,564 INFO: r2: stopping ldpd
21-Apr-2019 14:03:42 2019-04-21 14:03:42,578 INFO: r2: stopping ospfd
21-Apr-2019 14:03:42 2019-04-21 14:03:42,586 INFO: r2: stopping zebra
21-Apr-2019 14:03:42 2019-04-21 14:03:42,587 INFO: r2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:03:44 2019-04-21 14:03:44,626 INFO: r3: stopping ldpd
21-Apr-2019 14:03:44 2019-04-21 14:03:44,633 INFO: r3: stopping ospfd
21-Apr-2019 14:03:44 2019-04-21 14:03:44,637 INFO: r3: stopping zebra
21-Apr-2019 14:03:44 2019-04-21 14:03:44,641 INFO: r3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:03:46 2019-04-21 14:03:46,680 INFO: r4: stopping ldpd
21-Apr-2019 14:03:46 2019-04-21 14:03:46,687 INFO: r4: stopping ospfd
21-Apr-2019 14:03:46 2019-04-21 14:03:46,690 INFO: r4: stopping zebra
21-Apr-2019 14:03:46 2019-04-21 14:03:46,693 INFO: r4: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:03:48 PASSED
21-Apr-2019 14:03:49 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_memleak PASSED
21-Apr-2019 14:03:49
21-Apr-2019 14:03:49 ** test_ldp_topo1: Shutdown Topology
21-Apr-2019 14:03:49 ******************************************
21-Apr-2019 14:03:49
21-Apr-2019 14:03:49
21-Apr-2019 14:03:52 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ospf_convergence 2019-04-21 14:03:52,381 INFO: loading topology: test_ldp_vpls_topo1
21-Apr-2019 14:03:52 2019-04-21 14:03:52,382 INFO: starting topology: test_ldp_vpls_topo1
21-Apr-2019 14:03:52 2019-04-21 14:03:52,693 INFO: r1: running version: 3.0.3
21-Apr-2019 14:03:52 2019-04-21 14:03:52,695 INFO: r1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:03:54 2019-04-21 14:03:54,087 INFO: r2: running version: 3.0.3
21-Apr-2019 14:03:54 2019-04-21 14:03:54,089 INFO: r2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:03:55 2019-04-21 14:03:55,470 INFO: r3: running version: 3.0.3
21-Apr-2019 14:03:55 2019-04-21 14:03:55,472 INFO: r3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:03:56 2019-04-21 14:03:56,872 INFO: ce3: running version: 3.0.3
21-Apr-2019 14:03:56 2019-04-21 14:03:56,874 INFO: ce3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:03:58 2019-04-21 14:03:58,007 INFO: ce2: running version: 3.0.3
21-Apr-2019 14:03:58 2019-04-21 14:03:58,009 INFO: ce2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:03:59 2019-04-21 14:03:59,132 INFO: ce1: running version: 3.0.3
21-Apr-2019 14:03:59 2019-04-21 14:03:59,134 INFO: ce1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:04:00 2019-04-21 14:04:00,258 INFO: Test: check OSPF adjacencies
21-Apr-2019 14:04:00 2019-04-21 14:04:00,809 INFO: Comparing router "r1" "show ip ospf neighbor json" output
21-Apr-2019 14:04:00 2019-04-21 14:04:00,810 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:36 2019-04-21 14:04:36,750 INFO: 'router_json_cmp' succeeded after 35.94 seconds
21-Apr-2019 14:04:36 2019-04-21 14:04:36,750 INFO: Comparing router "r2" "show ip ospf neighbor json" output
21-Apr-2019 14:04:36 2019-04-21 14:04:36,751 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:36 2019-04-21 14:04:36,832 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:36 2019-04-21 14:04:36,833 INFO: Comparing router "r3" "show ip ospf neighbor json" output
21-Apr-2019 14:04:36 2019-04-21 14:04:36,833 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:36 2019-04-21 14:04:36,916 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:36 PASSED
21-Apr-2019 14:04:36 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_rib 2019-04-21 14:04:36,928 INFO: Test: verify RIB
21-Apr-2019 14:04:37 2019-04-21 14:04:37,439 INFO: Comparing router "r1" "show ip route json" output
21-Apr-2019 14:04:37 2019-04-21 14:04:37,439 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:45 2019-04-21 14:04:45,349 INFO: 'router_json_cmp' succeeded after 7.91 seconds
21-Apr-2019 14:04:45 2019-04-21 14:04:45,349 INFO: Comparing router "r2" "show ip route json" output
21-Apr-2019 14:04:45 2019-04-21 14:04:45,350 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:46 2019-04-21 14:04:46,647 INFO: 'router_json_cmp' succeeded after 1.30 seconds
21-Apr-2019 14:04:46 2019-04-21 14:04:46,648 INFO: Comparing router "r3" "show ip route json" output
21-Apr-2019 14:04:46 2019-04-21 14:04:46,648 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:46 2019-04-21 14:04:46,735 INFO: 'router_json_cmp' succeeded after 0.09 seconds
21-Apr-2019 14:04:46 PASSED
21-Apr-2019 14:04:46 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_adjacencies 2019-04-21 14:04:46,743 INFO: Test: verify LDP adjacencies
21-Apr-2019 14:04:47 2019-04-21 14:04:47,209 INFO: Comparing router "r1" "show mpls ldp discovery json" output
21-Apr-2019 14:04:47 2019-04-21 14:04:47,209 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:47 2019-04-21 14:04:47,287 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:47 2019-04-21 14:04:47,288 INFO: Comparing router "r2" "show mpls ldp discovery json" output
21-Apr-2019 14:04:47 2019-04-21 14:04:47,288 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:47 2019-04-21 14:04:47,366 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:47 2019-04-21 14:04:47,366 INFO: Comparing router "r3" "show mpls ldp discovery json" output
21-Apr-2019 14:04:47 2019-04-21 14:04:47,367 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:48 2019-04-21 14:04:48,044 INFO: 'router_json_cmp' succeeded after 0.68 seconds
21-Apr-2019 14:04:48 PASSED
21-Apr-2019 14:04:48 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_neighbors 2019-04-21 14:04:48,048 INFO: Test: verify LDP neighbors
21-Apr-2019 14:04:48 2019-04-21 14:04:48,666 INFO: Comparing router "r1" "show mpls ldp neighbor json" output
21-Apr-2019 14:04:48 2019-04-21 14:04:48,667 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:49 2019-04-21 14:04:49,331 INFO: 'router_json_cmp' succeeded after 0.66 seconds
21-Apr-2019 14:04:49 2019-04-21 14:04:49,331 INFO: Comparing router "r2" "show mpls ldp neighbor json" output
21-Apr-2019 14:04:49 2019-04-21 14:04:49,332 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:50 2019-04-21 14:04:50,614 INFO: 'router_json_cmp' succeeded after 1.28 seconds
21-Apr-2019 14:04:50 2019-04-21 14:04:50,614 INFO: Comparing router "r3" "show mpls ldp neighbor json" output
21-Apr-2019 14:04:50 2019-04-21 14:04:50,615 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:50 2019-04-21 14:04:50,726 INFO: 'router_json_cmp' succeeded after 0.11 seconds
21-Apr-2019 14:04:50 PASSED
21-Apr-2019 14:04:50 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_bindings 2019-04-21 14:04:50,731 INFO: Test: verify LDP bindings
21-Apr-2019 14:04:51 2019-04-21 14:04:51,200 INFO: Comparing router "r1" "show mpls ldp binding json" output
21-Apr-2019 14:04:51 2019-04-21 14:04:51,201 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:51 2019-04-21 14:04:51,283 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:51 2019-04-21 14:04:51,283 INFO: Comparing router "r2" "show mpls ldp binding json" output
21-Apr-2019 14:04:51 2019-04-21 14:04:51,283 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:51 2019-04-21 14:04:51,367 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:51 2019-04-21 14:04:51,368 INFO: Comparing router "r3" "show mpls ldp binding json" output
21-Apr-2019 14:04:51 2019-04-21 14:04:51,368 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:51 2019-04-21 14:04:51,454 INFO: 'router_json_cmp' succeeded after 0.09 seconds
21-Apr-2019 14:04:51 PASSED
21-Apr-2019 14:04:51 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pwid_bindings 2019-04-21 14:04:51,458 INFO: Test: verify LDP PW-ID bindings
21-Apr-2019 14:04:51 2019-04-21 14:04:51,921 INFO: Comparing router "r1" "show l2vpn atom binding json" output
21-Apr-2019 14:04:51 2019-04-21 14:04:51,922 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:51 2019-04-21 14:04:51,997 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:51 2019-04-21 14:04:51,997 INFO: Comparing router "r2" "show l2vpn atom binding json" output
21-Apr-2019 14:04:51 2019-04-21 14:04:51,998 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:52 2019-04-21 14:04:52,074 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:52 2019-04-21 14:04:52,075 INFO: Comparing router "r3" "show l2vpn atom binding json" output
21-Apr-2019 14:04:52 2019-04-21 14:04:52,075 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:52 2019-04-21 14:04:52,151 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:52 PASSED
21-Apr-2019 14:04:52 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires 2019-04-21 14:04:52,158 INFO: Test: verify LDP pseudowires
21-Apr-2019 14:04:52 2019-04-21 14:04:52,613 INFO: Comparing router "r1" "show l2vpn atom vc json" output
21-Apr-2019 14:04:52 2019-04-21 14:04:52,614 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:52 2019-04-21 14:04:52,691 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:52 2019-04-21 14:04:52,691 INFO: Comparing router "r2" "show l2vpn atom vc json" output
21-Apr-2019 14:04:52 2019-04-21 14:04:52,691 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:52 2019-04-21 14:04:52,774 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:52 2019-04-21 14:04:52,774 INFO: Comparing router "r3" "show l2vpn atom vc json" output
21-Apr-2019 14:04:52 2019-04-21 14:04:52,774 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:52 2019-04-21 14:04:52,854 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:52 PASSED
21-Apr-2019 14:04:52 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires_after_link_down 2019-04-21 14:04:52,858 INFO: Test: verify LDP pseudowires after r1-r2 link goes down
21-Apr-2019 14:04:53 2019-04-21 14:04:53,342 INFO: setting node "s4" link "s4-eth0" to state "down"
21-Apr-2019 14:04:53 2019-04-21 14:04:53,351 INFO: Comparing router "r1" "show l2vpn atom vc json" output
21-Apr-2019 14:04:53 2019-04-21 14:04:53,351 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:53 2019-04-21 14:04:53,429 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:53 2019-04-21 14:04:53,429 INFO: Comparing router "r2" "show l2vpn atom vc json" output
21-Apr-2019 14:04:53 2019-04-21 14:04:53,429 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:53 2019-04-21 14:04:53,509 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:53 2019-04-21 14:04:53,509 INFO: Comparing router "r3" "show l2vpn atom vc json" output
21-Apr-2019 14:04:53 2019-04-21 14:04:53,509 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:04:53 2019-04-21 14:04:53,591 INFO: 'router_json_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:04:53 PASSED
21-Apr-2019 14:04:54 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_memory_leak 2019-04-21 14:04:54,529 INFO: r1: stopping ldpd
21-Apr-2019 14:04:54 2019-04-21 14:04:54,534 INFO: r1: stopping ospfd
21-Apr-2019 14:04:54 2019-04-21 14:04:54,542 INFO: r1: stopping zebra
21-Apr-2019 14:04:54 2019-04-21 14:04:54,543 INFO: r1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:04:56 2019-04-21 14:04:56,589 INFO: r2: stopping ldpd
21-Apr-2019 14:04:56 2019-04-21 14:04:56,595 INFO: r2: stopping ospfd
21-Apr-2019 14:04:56 2019-04-21 14:04:56,600 INFO: r2: stopping zebra
21-Apr-2019 14:04:56 2019-04-21 14:04:56,601 INFO: r2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:04:58 2019-04-21 14:04:58,641 INFO: r3: stopping ldpd
21-Apr-2019 14:04:58 2019-04-21 14:04:58,649 INFO: r3: stopping ospfd
21-Apr-2019 14:04:58 2019-04-21 14:04:58,654 INFO: r3: stopping zebra
21-Apr-2019 14:04:58 2019-04-21 14:04:58,655 INFO: r3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:05:00 2019-04-21 14:05:00,709 INFO: ce3: stopping zebra
21-Apr-2019 14:05:00 2019-04-21 14:05:00,713 INFO: ce3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:05:02 2019-04-21 14:05:02,733 INFO: ce2: stopping zebra
21-Apr-2019 14:05:02 2019-04-21 14:05:02,734 INFO: ce2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:05:04 2019-04-21 14:05:04,752 INFO: ce1: stopping zebra
21-Apr-2019 14:05:04 2019-04-21 14:05:04,752 INFO: ce1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:05:06 PASSED2019-04-21 14:05:06,772 INFO: stopping topology: test_ldp_vpls_topo1
21-Apr-2019 14:05:06 2019-04-21 14:05:06,783 INFO: stopping "s3"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,783 INFO: stopping "s2"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,783 INFO: stopping "s1"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,784 INFO: stopping "s6"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,784 INFO: stopping "s5"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,784 INFO: stopping "s4"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,805 INFO: stopping "s3"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,806 INFO: stopping "s2"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,806 INFO: stopping "s1"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,806 INFO: stopping "s6"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,806 INFO: stopping "s5"
21-Apr-2019 14:05:06 2019-04-21 14:05:06,806 INFO: stopping "s4"
21-Apr-2019 14:05:08
21-Apr-2019 14:05:10 *** defaultIntf: warning: lm has no interfaces
21-Apr-2019 14:05:10 lm-proxy-topo1/test_lm-proxy-topo1.py::test_lm_proxy 2019-04-21 14:05:10,738 INFO: loading topology: test_lm-proxy-topo1
21-Apr-2019 14:05:10 2019-04-21 14:05:10,738 INFO: starting topology: test_lm-proxy-topo1
21-Apr-2019 14:05:10 2019-04-21 14:05:10,827 INFO: assert skipped at "test_lm-proxy-topo1/test_lm_proxy": Skipping test: no VRF support
21-Apr-2019 14:05:10 SKIPPED
21-Apr-2019 14:05:10 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_sr 2019-04-21 14:05:10,840 INFO:
21-Apr-2019 14:05:10
21-Apr-2019 14:05:10 ---- Starting OSPF Segment Routing tests ----
21-Apr-2019 14:05:10
21-Apr-2019 14:05:13 2019-04-21 14:05:13,309 INFO: loading topology: test_ospf_sr_topo1
21-Apr-2019 14:05:13 2019-04-21 14:05:13,310 INFO: starting topology: test_ospf_sr_topo1
21-Apr-2019 14:05:13 2019-04-21 14:05:13,554 INFO: r4: running version: 3.0.3
21-Apr-2019 14:05:13 2019-04-21 14:05:13,556 INFO: r4: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:14 2019-04-21 14:05:14,916 INFO: r1: running version: 3.0.3
21-Apr-2019 14:05:14 2019-04-21 14:05:14,919 INFO: r1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:16 2019-04-21 14:05:16,219 INFO: r2: running version: 3.0.3
21-Apr-2019 14:05:16 2019-04-21 14:05:16,222 INFO: r2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:17 2019-04-21 14:05:17,503 INFO: r3: running version: 3.0.3
21-Apr-2019 14:05:17 2019-04-21 14:05:17,505 INFO: r3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:18 2019-04-21 14:05:18,784 INFO: Unsupported FRR version
21-Apr-2019 14:05:18 2019-04-21 14:05:18,796 INFO: assert skipped at "test_ospf_sr_topo1/test_ospf_sr":
21-Apr-2019 14:05:18 0: Unsupported FRR version
21-Apr-2019 14:05:18 SKIPPED
21-Apr-2019 14:05:18 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_kernel_route 2019-04-21 14:05:18,805 INFO: assert skipped at "test_ospf_sr_topo1/test_ospf_kernel_route":
21-Apr-2019 14:05:18 0: Unsupported FRR version
21-Apr-2019 14:05:18 SKIPPED
21-Apr-2019 14:05:18 ospf-sr-topo1/test_ospf_sr_topo1.py::test_memory_leak 2019-04-21 14:05:18,809 INFO: assert skipped at "test_ospf_sr_topo1/test_memory_leak": Memory leak test/report is disabled
21-Apr-2019 14:05:18 SKIPPED2019-04-21 14:05:18,810 INFO: stopping topology: test_ospf_sr_topo1
21-Apr-2019 14:05:18 2019-04-21 14:05:18,818 INFO: r4: stopping ospfd
21-Apr-2019 14:05:18 2019-04-21 14:05:18,823 INFO: r4: stopping zebra
21-Apr-2019 14:05:18 2019-04-21 14:05:18,831 INFO: r1: stopping ospfd
21-Apr-2019 14:05:18 2019-04-21 14:05:18,836 INFO: r1: stopping zebra
21-Apr-2019 14:05:18 2019-04-21 14:05:18,850 INFO: r2: stopping ospfd
21-Apr-2019 14:05:18 2019-04-21 14:05:18,854 INFO: r2: stopping zebra
21-Apr-2019 14:05:18 2019-04-21 14:05:18,862 INFO: r3: stopping ospfd
21-Apr-2019 14:05:18 2019-04-21 14:05:18,867 INFO: r3: stopping zebra
21-Apr-2019 14:05:18 2019-04-21 14:05:18,869 INFO: stopping "s3"
21-Apr-2019 14:05:18 2019-04-21 14:05:18,869 INFO: stopping "s2"
21-Apr-2019 14:05:18 2019-04-21 14:05:18,869 INFO: stopping "s1"
21-Apr-2019 14:05:18 2019-04-21 14:05:18,939 INFO: stopping "s3"
21-Apr-2019 14:05:18 2019-04-21 14:05:18,940 INFO: stopping "s2"
21-Apr-2019 14:05:18 2019-04-21 14:05:18,940 INFO: stopping "s1"
21-Apr-2019 14:05:19 2019-04-21 14:05:19,839 INFO:
21-Apr-2019 14:05:19
21-Apr-2019 14:05:19 ---- OSPF Segment Routing tests End ----
21-Apr-2019 14:05:19
21-Apr-2019 14:05:19
21-Apr-2019 14:05:22 ospf-topo1/test_ospf_topo1.py::test_ospf_convergence 2019-04-21 14:05:22,352 INFO: loading topology: test_ospf_topo1
21-Apr-2019 14:05:22 2019-04-21 14:05:22,353 INFO: starting topology: test_ospf_topo1
21-Apr-2019 14:05:22 2019-04-21 14:05:22,461 INFO: r1: running version: 3.0.3
21-Apr-2019 14:05:22 2019-04-21 14:05:22,588 INFO: r4: running version: 3.0.3
21-Apr-2019 14:05:22 2019-04-21 14:05:22,590 INFO: r4: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:23 2019-04-21 14:05:23,955 INFO: r1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:25 2019-04-21 14:05:25,374 INFO: r2: running version: 3.0.3
21-Apr-2019 14:05:25 2019-04-21 14:05:25,375 INFO: r2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:27 2019-04-21 14:05:27,038 INFO: r3: running version: 3.0.3
21-Apr-2019 14:05:27 2019-04-21 14:05:27,040 INFO: r3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:05:28 2019-04-21 14:05:28,959 INFO: Waiting for router "r4" convergence
21-Apr-2019 14:05:28 2019-04-21 14:05:28,960 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:06:18 2019-04-21 14:06:18,861 INFO: 'router_output_cmp' succeeded after 49.90 seconds
21-Apr-2019 14:06:18 2019-04-21 14:06:18,862 INFO: Waiting for router "r1" convergence
21-Apr-2019 14:06:18 2019-04-21 14:06:18,863 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:06:18 2019-04-21 14:06:18,950 INFO: 'router_output_cmp' succeeded after 0.09 seconds
21-Apr-2019 14:06:18 2019-04-21 14:06:18,950 INFO: Waiting for router "r2" convergence
21-Apr-2019 14:06:18 2019-04-21 14:06:18,950 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:06:19 2019-04-21 14:06:19,031 INFO: 'router_output_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:06:19 2019-04-21 14:06:19,031 INFO: Waiting for router "r3" convergence
21-Apr-2019 14:06:19 2019-04-21 14:06:19,031 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
21-Apr-2019 14:06:19 2019-04-21 14:06:19,110 INFO: 'router_output_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:06:19 PASSED
21-Apr-2019 14:06:19 ospf-topo1/test_ospf_topo1.py::test_ospf_kernel_route 2019-04-21 14:06:19,439 INFO: Checking OSPF IPv4 kernel routes in "r4"
21-Apr-2019 14:06:19 2019-04-21 14:06:19,443 INFO: Checking OSPF IPv4 kernel routes in "r1"
21-Apr-2019 14:06:19 2019-04-21 14:06:19,446 INFO: Checking OSPF IPv4 kernel routes in "r2"
21-Apr-2019 14:06:19 2019-04-21 14:06:19,449 INFO: Checking OSPF IPv4 kernel routes in "r3"
21-Apr-2019 14:06:19 PASSED
21-Apr-2019 14:06:19 ospf-topo1/test_ospf_topo1.py::test_ospf6_convergence 2019-04-21 14:06:19,775 INFO: Waiting for router "r1" IPv6 OSPF convergence
21-Apr-2019 14:06:19 2019-04-21 14:06:19,776 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:07:38 2019-04-21 14:07:38,524 ERROR: 'compare_show_ipv6_ospf6' failed after 78.75 seconds
21-Apr-2019 14:07:38 2019-04-21 14:07:38,525 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 3 secs)
21-Apr-2019 14:07:38 2019-04-21 14:07:38,606 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:07:38 2019-04-21 14:07:38,606 INFO: Waiting for router "r2" IPv6 OSPF convergence
21-Apr-2019 14:07:38 2019-04-21 14:07:38,607 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:07:38 2019-04-21 14:07:38,686 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:07:38 2019-04-21 14:07:38,687 INFO: Waiting for router "r3" IPv6 OSPF convergence
21-Apr-2019 14:07:38 2019-04-21 14:07:38,687 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:07:38 2019-04-21 14:07:38,767 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:07:38 2019-04-21 14:07:38,767 INFO: Waiting for router "r4" IPv6 OSPF convergence
21-Apr-2019 14:07:38 2019-04-21 14:07:38,767 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:07:38 2019-04-21 14:07:38,846 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:07:38 PASSED
21-Apr-2019 14:07:39 ospf-topo1/test_ospf_topo1.py::test_ospf6_kernel_route 2019-04-21 14:07:39,172 INFO: Checking OSPF IPv6 kernel routes in "r4"
21-Apr-2019 14:07:39 2019-04-21 14:07:39,176 INFO: Checking OSPF IPv6 kernel routes in "r1"
21-Apr-2019 14:07:39 2019-04-21 14:07:39,179 INFO: Checking OSPF IPv6 kernel routes in "r2"
21-Apr-2019 14:07:39 2019-04-21 14:07:39,182 INFO: Checking OSPF IPv6 kernel routes in "r3"
21-Apr-2019 14:07:39 PASSED
21-Apr-2019 14:07:39 ospf-topo1/test_ospf_topo1.py::test_ospf_json 2019-04-21 14:07:39,503 INFO: Comparing router "r1" "show ip ospf json" output
21-Apr-2019 14:07:39 2019-04-21 14:07:39,581 INFO: Comparing router "r2" "show ip ospf json" output
21-Apr-2019 14:07:39 2019-04-21 14:07:39,658 INFO: Comparing router "r3" "show ip ospf json" output
21-Apr-2019 14:07:39 2019-04-21 14:07:39,743 INFO: Comparing router "r4" "show ip ospf json" output
21-Apr-2019 14:07:39 PASSED
21-Apr-2019 14:07:40 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down 2019-04-21 14:07:40,145 INFO: setting node "s3" link "s3-eth2" to state "down"
21-Apr-2019 14:07:40 2019-04-21 14:07:40,153 INFO: Waiting for router "r4" convergence after link failure
21-Apr-2019 14:07:40 2019-04-21 14:07:40,153 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
21-Apr-2019 14:07:40 2019-04-21 14:07:40,240 INFO: 'router_output_cmp' succeeded after 0.09 seconds
21-Apr-2019 14:07:40 2019-04-21 14:07:40,240 INFO: Waiting for router "r1" convergence after link failure
21-Apr-2019 14:07:40 2019-04-21 14:07:40,240 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
21-Apr-2019 14:08:18 2019-04-21 14:08:18,310 INFO: 'router_output_cmp' succeeded after 38.07 seconds
21-Apr-2019 14:08:18 2019-04-21 14:08:18,312 INFO: Waiting for router "r2" convergence after link failure
21-Apr-2019 14:08:18 2019-04-21 14:08:18,312 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
21-Apr-2019 14:08:18 2019-04-21 14:08:18,391 INFO: 'router_output_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:08:18 2019-04-21 14:08:18,391 INFO: Waiting for router "r3" convergence after link failure
21-Apr-2019 14:08:18 2019-04-21 14:08:18,391 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
21-Apr-2019 14:08:18 2019-04-21 14:08:18,469 INFO: 'router_output_cmp' succeeded after 0.08 seconds
21-Apr-2019 14:08:18 PASSED
21-Apr-2019 14:08:18 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down_kernel_route 2019-04-21 14:08:18,786 INFO: Checking OSPF IPv4 kernel routes in "r4" after link down
21-Apr-2019 14:08:18 2019-04-21 14:08:18,789 INFO: Checking OSPF IPv4 kernel routes in "r1" after link down
21-Apr-2019 14:08:18 2019-04-21 14:08:18,792 INFO: Checking OSPF IPv4 kernel routes in "r2" after link down
21-Apr-2019 14:08:18 2019-04-21 14:08:18,794 INFO: Checking OSPF IPv4 kernel routes in "r3" after link down
21-Apr-2019 14:08:18 PASSED
21-Apr-2019 14:08:19 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down 2019-04-21 14:08:19,104 INFO: Waiting for router "r1" IPv6 OSPF convergence after link down
21-Apr-2019 14:08:19 2019-04-21 14:08:19,105 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:08:28 2019-04-21 14:08:28,437 INFO: 'compare_show_ipv6_ospf6' succeeded after 9.33 seconds
21-Apr-2019 14:08:28 2019-04-21 14:08:28,438 INFO: Waiting for router "r2" IPv6 OSPF convergence after link down
21-Apr-2019 14:08:28 2019-04-21 14:08:28,438 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:08:28 2019-04-21 14:08:28,517 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:08:28 2019-04-21 14:08:28,517 INFO: Waiting for router "r3" IPv6 OSPF convergence after link down
21-Apr-2019 14:08:28 2019-04-21 14:08:28,517 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:08:28 2019-04-21 14:08:28,593 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:08:28 2019-04-21 14:08:28,593 INFO: Waiting for router "r4" IPv6 OSPF convergence after link down
21-Apr-2019 14:08:28 2019-04-21 14:08:28,594 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
21-Apr-2019 14:08:28 2019-04-21 14:08:28,670 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.08 seconds
21-Apr-2019 14:08:28 PASSED
21-Apr-2019 14:08:28 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down_kernel_route 2019-04-21 14:08:28,990 INFO: Checking OSPF IPv6 kernel routes in "r4" after link down
21-Apr-2019 14:08:28 2019-04-21 14:08:28,993 INFO: Checking OSPF IPv6 kernel routes in "r1" after link down
21-Apr-2019 14:08:28 2019-04-21 14:08:28,996 INFO: Checking OSPF IPv6 kernel routes in "r2" after link down
21-Apr-2019 14:08:28 2019-04-21 14:08:28,998 INFO: Checking OSPF IPv6 kernel routes in "r3" after link down
21-Apr-2019 14:08:29 PASSED
21-Apr-2019 14:08:29 ospf-topo1/test_ospf_topo1.py::test_memory_leak 2019-04-21 14:08:29,660 INFO: r4: stopping ospf6d
21-Apr-2019 14:08:29 2019-04-21 14:08:29,663 INFO: r4: stopping ospfd
21-Apr-2019 14:08:29 2019-04-21 14:08:29,670 INFO: r4: stopping zebra
21-Apr-2019 14:08:29 2019-04-21 14:08:29,671 INFO: r4: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:08:31 2019-04-21 14:08:31,719 INFO: r1: stopping ospf6d
21-Apr-2019 14:08:31 2019-04-21 14:08:31,724 INFO: r1: stopping ospfd
21-Apr-2019 14:08:31 2019-04-21 14:08:31,728 INFO: r1: stopping zebra
21-Apr-2019 14:08:31 2019-04-21 14:08:31,729 INFO: r1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:08:33 2019-04-21 14:08:33,772 INFO: r2: stopping ospf6d
21-Apr-2019 14:08:33 2019-04-21 14:08:33,780 INFO: r2: stopping ospfd
21-Apr-2019 14:08:33 2019-04-21 14:08:33,785 INFO: r2: stopping zebra
21-Apr-2019 14:08:33 2019-04-21 14:08:33,789 INFO: r2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:08:35 2019-04-21 14:08:35,831 INFO: r3: stopping ospf6d
21-Apr-2019 14:08:35 2019-04-21 14:08:35,836 INFO: r3: stopping ospfd
21-Apr-2019 14:08:35 2019-04-21 14:08:35,839 INFO: r3: stopping zebra
21-Apr-2019 14:08:35 2019-04-21 14:08:35,840 INFO: r3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:08:37 PASSED2019-04-21 14:08:37,880 INFO: stopping topology: test_ospf_topo1
21-Apr-2019 14:08:37 2019-04-21 14:08:37,895 INFO: stopping "s3"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,895 INFO: stopping "s2"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,896 INFO: stopping "s1"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,896 INFO: stopping "s6"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,896 INFO: stopping "s5"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,896 INFO: stopping "s4"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,910 INFO: stopping "s3"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,910 INFO: stopping "s2"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,910 INFO: stopping "s1"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,910 INFO: stopping "s6"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,910 INFO: stopping "s5"
21-Apr-2019 14:08:37 2019-04-21 14:08:37,910 INFO: stopping "s4"
21-Apr-2019 14:08:38
21-Apr-2019 14:08:41 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_convergence 2019-04-21 14:08:41,104 INFO: loading topology: test_ospf_topo1_vrf
21-Apr-2019 14:08:41 2019-04-21 14:08:41,105 INFO: starting topology: test_ospf_topo1_vrf
21-Apr-2019 14:08:41 2019-04-21 14:08:41,181 INFO: assert skipped at "test_ospf_topo1_vrf/test_ospf_convergence": Skipping OSPF VRF NETNS feature. VRF NETNS backend not available on FRR
21-Apr-2019 14:08:41 SKIPPED
21-Apr-2019 14:08:41 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_kernel_route 2019-04-21 14:08:41,189 INFO: assert skipped at "test_ospf_topo1_vrf/test_ospf_kernel_route": Skipping OSPF VRF NETNS feature. VRF NETNS backend not available on FRR
21-Apr-2019 14:08:41 SKIPPED
21-Apr-2019 14:08:41 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_json 2019-04-21 14:08:41,193 INFO: assert skipped at "test_ospf_topo1_vrf/test_ospf_json": Skipping OSPF VRF NETNS feature. VRF NETNS backend not available on FRR
21-Apr-2019 14:08:41 SKIPPED
21-Apr-2019 14:08:41 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_link_down 2019-04-21 14:08:41,197 INFO: assert skipped at "test_ospf_topo1_vrf/test_ospf_link_down": Skipping OSPF VRF NETNS feature. VRF NETNS backend not available on FRR
21-Apr-2019 14:08:41 SKIPPED
21-Apr-2019 14:08:41 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_link_down_kernel_route 2019-04-21 14:08:41,201 INFO: assert skipped at "test_ospf_topo1_vrf/test_ospf_link_down_kernel_route": Skipping OSPF VRF NETNS feature. VRF NETNS backend not available on FRR
21-Apr-2019 14:08:41 SKIPPED
21-Apr-2019 14:08:41 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_memory_leak 2019-04-21 14:08:41,204 INFO: assert skipped at "test_ospf_topo1_vrf/test_memory_leak": Skipping OSPF VRF NETNS feature. VRF NETNS backend not available on FRR
21-Apr-2019 14:08:41 SKIPPED
21-Apr-2019 14:08:43 ospf6-topo1/test_ospf6_topo1.py::test_router_running
21-Apr-2019 14:08:43
21-Apr-2019 14:08:43 ** test_ospf6_topo1: Setup Topology
21-Apr-2019 14:08:43 ******************************************
21-Apr-2019 14:08:43
21-Apr-2019 14:08:43 Cleanup old Mininet runs
21-Apr-2019 14:08:43 2019-04-21 14:08:43,521 INFO: r1: running version: 3.0.3
21-Apr-2019 14:08:43 2019-04-21 14:08:43,556 INFO: r1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:08:44 2019-04-21 14:08:44,602 INFO: r2: running version: 3.0.3
21-Apr-2019 14:08:44 2019-04-21 14:08:44,603 INFO: r2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:08:45 2019-04-21 14:08:45,668 INFO: r3: running version: 3.0.3
21-Apr-2019 14:08:45 2019-04-21 14:08:45,670 INFO: r3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:08:46 2019-04-21 14:08:46,730 INFO: r4: running version: 3.0.3
21-Apr-2019 14:08:46 2019-04-21 14:08:46,731 INFO: r4: waiting for zebra to start (1 seconds)
21-Apr-2019 14:08:53
21-Apr-2019 14:08:53
21-Apr-2019 14:08:53 ** Check if FRR/Quagga is running on each Router node
21-Apr-2019 14:08:53 ******************************************
21-Apr-2019 14:08:53
21-Apr-2019 14:08:53 PASSED
21-Apr-2019 14:08:53 ospf6-topo1/test_ospf6_topo1.py::test_ospf6_converged
21-Apr-2019 14:08:53
21-Apr-2019 14:08:53 ** Verify OSPF6 daemons to converge
21-Apr-2019 14:08:53 ******************************************
21-Apr-2019 14:08:53
21-Apr-2019 14:08:53 Timeout in 60:  Waiting for r1
21-Apr-2019 14:08:58 Timeout in 55:  Waiting for r1
21-Apr-2019 14:09:03 Timeout in 50:  Waiting for r1
21-Apr-2019 14:09:08 Timeout in 45:  Waiting for r1
21-Apr-2019 14:09:13 Timeout in 40:  Waiting for r1
21-Apr-2019 14:09:18 Timeout in 35:  Waiting for r1
21-Apr-2019 14:09:24 Timeout in 30:  Waiting for r1
21-Apr-2019 14:09:29 Timeout in 25:  Waiting for r3
21-Apr-2019 14:09:50 Timeout in 20:  Done
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 OSPFv3 converged.
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 waiting 15s for routes to populate
21-Apr-2019 14:09:50 PASSED
21-Apr-2019 14:09:50 ospf6-topo1/test_ospf6_topo1.py::test_ospfv3_routingTable
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 ** Verifying OSPFv3 Routing Table
21-Apr-2019 14:09:50 ******************************************
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 r1 ok
21-Apr-2019 14:09:50 r2 ok
21-Apr-2019 14:09:50 r3 ok
21-Apr-2019 14:09:50 r4 ok
21-Apr-2019 14:09:50 PASSED
21-Apr-2019 14:09:50 ospf6-topo1/test_ospf6_topo1.py::test_linux_ipv6_kernel_routingTable
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 ** Verifying Linux IPv6 Kernel Routing Table
21-Apr-2019 14:09:50 ******************************************
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 r1 ok
21-Apr-2019 14:09:50 r2 ok
21-Apr-2019 14:09:50 r3 ok
21-Apr-2019 14:09:50 r4 ok
21-Apr-2019 14:09:50 PASSED
21-Apr-2019 14:09:50 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 ** Verifying unexpected STDERR output from daemons
21-Apr-2019 14:09:50 ******************************************
21-Apr-2019 14:09:50
21-Apr-2019 14:09:50 2019-04-21 14:09:50,727 INFO: r1: stopping ospf6d
21-Apr-2019 14:09:50 2019-04-21 14:09:50,731 INFO: r1: stopping zebra
21-Apr-2019 14:09:50 2019-04-21 14:09:50,733 INFO: r1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:09:53
21-Apr-2019 14:09:53 r1: ospf6d crashed. Core file found - Backtrace follows:
21-Apr-2019 14:09:53 [New LWP 16856]
21-Apr-2019 14:09:53 [Thread debugging using libthread_db enabled]
21-Apr-2019 14:09:53 Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
21-Apr-2019 14:09:53 Core was generated by `/usr/lib/frr/ospf6d'.
21-Apr-2019 14:09:53 Program terminated with signal SIGABRT, Aborted.
21-Apr-2019 14:09:53 #0  0x00007f315175f428 in __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:54
21-Apr-2019 14:09:53 #0  0x00007f315175f428 in __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:54
21-Apr-2019 14:09:53 #1  0x00007f315176102a in __GI_abort () at abort.c:89
21-Apr-2019 14:09:53 #2  0x00007f3151d3bb2a in _zlog_assert_failed (assertion=assertion@entry=0x5598e47417f6 "node", file=file@entry=0x5598e47415d6 "ospf6_route.c", line=line@entry=767, function=function@entry=0x5598e4741bd0 <__func__.12966> "ospf6_route_remove") at log.c:693
21-Apr-2019 14:09:53 #3  0x00005598e47266e9 in ospf6_route_remove (route=route@entry=0x5598e64d4ec0, table=table@entry=0x5598e64c9e80) at ospf6_route.c:767
21-Apr-2019 14:09:53 #4  0x00005598e4726a5b in ospf6_route_remove_all (table=0x5598e64c9e80) at ospf6_route.c:923
21-Apr-2019 14:09:53 #5  0x00005598e470e1b7 in sigterm () at ospf6_main.c:131
21-Apr-2019 14:09:53 #6  0x00007f3151d49028 in quagga_sigevent_process () at sigevent.c:104
21-Apr-2019 14:09:53 #7  0x00007f3151d29fb5 in thread_fetch (m=m@entry=0x5598e6362740, fetch=fetch@entry=0x7ffca6f31d20) at thread.c:1072
21-Apr-2019 14:09:53 #8  0x00007f3151d56f23 in frr_run (master=0x5598e6362740) at libfrr.c:424
21-Apr-2019 14:09:53 #9  0x00005598e470dabd in main (argc=1, argv=0x7ffca6f31f08, envp=<optimized out>) at ospf6_main.c:212
21-Apr-2019 14:09:53
21-Apr-2019 14:09:53 Router r1 OSPF6d StdErr Log:
21-Apr-2019 14:09:53 log: showing active allocations in memory group libfrr
21-Apr-2019 14:09:53 log: memstats:  Vector                        :   6220 *         16
21-Apr-2019 14:09:53 log: memstats:  Vector index                  :   6220 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Link List                     :     34 *         40
21-Apr-2019 14:09:53 log: memstats:  Link Node                     :     75 *         24
21-Apr-2019 14:09:53 log: memstats:  Graph                         :     16 *          8
21-Apr-2019 14:09:53 log: memstats:  Graph Node                    :   3091 *         32
21-Apr-2019 14:09:53 log: memstats:  Host config                   :      3 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Command Tokens                :   2574 *         64
21-Apr-2019 14:09:53 log: memstats:  Command Token Text            :   1986 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Command Token Help            :   1986 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Prefix                        :      5 *         40
21-Apr-2019 14:09:53 log: memstats:  Thread                        :     40 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Thread master                 :      2 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Thread stats                  :     35 *         64
21-Apr-2019 14:09:53 log: memstats:  Interface                     :      3 *        184
21-Apr-2019 14:09:53 log: memstats:  Connected                     :      5 *         40
21-Apr-2019 14:09:53 log: memstats:  Buffer                        :      1 *         24
21-Apr-2019 14:09:53 log: memstats:  Route table                   :     36 *         40
21-Apr-2019 14:09:53 log: memstats:  Route node                    :     82 *         96
21-Apr-2019 14:09:53 log: memstats:  Hash                          :     26 *         40
21-Apr-2019 14:09:53 log: memstats:  Hash Bucket                   :    559 *         24
21-Apr-2019 14:09:53 log: memstats:  Hash Index                    :     26 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Stream                        :      2 *         40
21-Apr-2019 14:09:53 log: memstats:  Stream data                   :      2 *       4096
21-Apr-2019 14:09:53 log: memstats:  Logging                       :      2 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Zclient                       :      1 *       2896
21-Apr-2019 14:09:53 log: memstats:  Redistribution instance IDs   :      4 *          2
21-Apr-2019 14:09:53 log: memstats:  Privilege information         :      2 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Priority queue                :      2 *         32
21-Apr-2019 14:09:53 log: memstats:  Priority queue data           :      2 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  VRF                           :      1 *        136
21-Apr-2019 14:09:53 log: memstats:  VRF bit-map                   :     94 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  Temporary memory              :      1 * (variably sized)
21-Apr-2019 14:09:53 log: showing active allocations in memory group ospf6d
21-Apr-2019 14:09:53 log: memstats:  OSPF6 top                     :      1 *        576
21-Apr-2019 14:09:53 log: memstats:  OSPF6 area                    :      1 *        216
21-Apr-2019 14:09:53 log: memstats:  OSPF6 interface               :      2 *        208
21-Apr-2019 14:09:53 log: memstats:  OSPF6 neighbor                :      2 *        248
21-Apr-2019 14:09:53 log: memstats:  OSPF6 route                   :     21 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  OSPF6 message                 :      2 *       1500
21-Apr-2019 14:09:53 log: memstats:  OSPF6 LSA                     :     48 * (variably sized)
21-Apr-2019 14:09:53 log: memstats:  OSPF6 LSA database            :     24 *         40
21-Apr-2019 14:09:53 log: memstats:  OSPF6 vertex                  :      4 *        232
21-Apr-2019 14:09:53 log: memstats:  OSPF6 nexthop                 :     13 *         20
21-Apr-2019 14:09:53 log: memstats:  OSPF6 ext. info               :      1 *         32
21-Apr-2019 14:09:53
21-Apr-2019 14:09:53 2019-04-21 14:09:53,484 INFO: r2: stopping ospf6d
21-Apr-2019 14:09:53 2019-04-21 14:09:53,489 INFO: r2: stopping zebra
21-Apr-2019 14:09:53 2019-04-21 14:09:53,495 INFO: r2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:09:56
21-Apr-2019 14:09:56 r2: ospf6d crashed. Core file found - Backtrace follows:
21-Apr-2019 14:09:56 [New LWP 16878]
21-Apr-2019 14:09:56 [Thread debugging using libthread_db enabled]
21-Apr-2019 14:09:56 Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
21-Apr-2019 14:09:56 Core was generated by `/usr/lib/frr/ospf6d'.
21-Apr-2019 14:09:56 Program terminated with signal SIGABRT, Aborted.
21-Apr-2019 14:09:56 #0  0x00007fbd8ca98428 in __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:54
21-Apr-2019 14:09:56 #0  0x00007fbd8ca98428 in __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:54
21-Apr-2019 14:09:56 #1  0x00007fbd8ca9a02a in __GI_abort () at abort.c:89
21-Apr-2019 14:09:56 #2  0x00007fbd8d074b2a in _zlog_assert_failed (assertion=assertion@entry=0x5628a1ea57f6 "node", file=file@entry=0x5628a1ea55d6 "ospf6_route.c", line=line@entry=767, function=function@entry=0x5628a1ea5bd0 <__func__.12966> "ospf6_route_remove") at log.c:693
21-Apr-2019 14:09:56 #3  0x00005628a1e8a6e9 in ospf6_route_remove (route=route@entry=0x5628a2706110, table=table@entry=0x5628a26fae80) at ospf6_route.c:767
21-Apr-2019 14:09:56 #4  0x00005628a1e8aa5b in ospf6_route_remove_all (table=0x5628a26fae80) at ospf6_route.c:923
21-Apr-2019 14:09:56 #5  0x00005628a1e721b7 in sigterm () at ospf6_main.c:131
21-Apr-2019 14:09:56 #6  0x00007fbd8d082028 in quagga_sigevent_process () at sigevent.c:104
21-Apr-2019 14:09:56 #7  0x00007fbd8d062fb5 in thread_fetch (m=m@entry=0x5628a2593740, fetch=fetch@entry=0x7ffd61491a20) at thread.c:1072
21-Apr-2019 14:09:56 #8  0x00007fbd8d08ff23 in frr_run (master=0x5628a2593740) at libfrr.c:424
21-Apr-2019 14:09:56 #9  0x00005628a1e71abd in main (argc=1, argv=0x7ffd61491c08, envp=<optimized out>) at ospf6_main.c:212
21-Apr-2019 14:09:56
21-Apr-2019 14:09:56 Router r2 OSPF6d StdErr Log:
21-Apr-2019 14:09:56 log: showing active allocations in memory group libfrr
21-Apr-2019 14:09:56 log: memstats:  Vector                        :   6220 *         16
21-Apr-2019 14:09:56 log: memstats:  Vector index                  :   6220 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Link List                     :     34 *         40
21-Apr-2019 14:09:56 log: memstats:  Link Node                     :     71 *         24
21-Apr-2019 14:09:56 log: memstats:  Graph                         :     16 *          8
21-Apr-2019 14:09:56 log: memstats:  Graph Node                    :   3091 *         32
21-Apr-2019 14:09:56 log: memstats:  Host config                   :      3 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Command Tokens                :   2574 *         64
21-Apr-2019 14:09:56 log: memstats:  Command Token Text            :   1986 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Command Token Help            :   1986 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Prefix                        :      5 *         40
21-Apr-2019 14:09:56 log: memstats:  Thread                        :     40 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Thread master                 :      2 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Thread stats                  :     35 *         64
21-Apr-2019 14:09:56 log: memstats:  Interface                     :      3 *        184
21-Apr-2019 14:09:56 log: memstats:  Connected                     :      5 *         40
21-Apr-2019 14:09:56 log: memstats:  Buffer                        :      1 *         24
21-Apr-2019 14:09:56 log: memstats:  Route table                   :     36 *         40
21-Apr-2019 14:09:56 log: memstats:  Route node                    :     82 *         96
21-Apr-2019 14:09:56 log: memstats:  Hash                          :     26 *         40
21-Apr-2019 14:09:56 log: memstats:  Hash Bucket                   :    559 *         24
21-Apr-2019 14:09:56 log: memstats:  Hash Index                    :     26 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Stream                        :      2 *         40
21-Apr-2019 14:09:56 log: memstats:  Stream data                   :      2 *       4096
21-Apr-2019 14:09:56 log: memstats:  Logging                       :      2 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Zclient                       :      1 *       2896
21-Apr-2019 14:09:56 log: memstats:  Redistribution instance IDs   :      4 *          2
21-Apr-2019 14:09:56 log: memstats:  Privilege information         :      2 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Priority queue                :      2 *         32
21-Apr-2019 14:09:56 log: memstats:  Priority queue data           :      2 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  VRF                           :      1 *        136
21-Apr-2019 14:09:56 log: memstats:  VRF bit-map                   :     94 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  Temporary memory              :      1 * (variably sized)
21-Apr-2019 14:09:56 log: showing active allocations in memory group ospf6d
21-Apr-2019 14:09:56 log: memstats:  OSPF6 top                     :      1 *        576
21-Apr-2019 14:09:56 log: memstats:  OSPF6 area                    :      1 *        216
21-Apr-2019 14:09:56 log: memstats:  OSPF6 interface               :      2 *        208
21-Apr-2019 14:09:56 log: memstats:  OSPF6 neighbor                :      2 *        248
21-Apr-2019 14:09:56 log: memstats:  OSPF6 route                   :     21 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  OSPF6 message                 :      2 *       1500
21-Apr-2019 14:09:56 log: memstats:  OSPF6 LSA                     :     48 * (variably sized)
21-Apr-2019 14:09:56 log: memstats:  OSPF6 LSA database            :     24 *         40
21-Apr-2019 14:09:56 log: memstats:  OSPF6 vertex                  :      4 *        232
21-Apr-2019 14:09:56 log: memstats:  OSPF6 nexthop                 :     13 *         20
21-Apr-2019 14:09:56 log: memstats:  OSPF6 ext. info               :      1 *         32
21-Apr-2019 14:09:56
21-Apr-2019 14:09:56 2019-04-21 14:09:56,066 INFO: r3: stopping ospf6d
21-Apr-2019 14:09:56 2019-04-21 14:09:56,076 INFO: r3: stopping zebra
21-Apr-2019 14:09:56 2019-04-21 14:09:56,081 INFO: r3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:09:58 2019-04-21 14:09:58,137 INFO: r4: stopping ospf6d
21-Apr-2019 14:09:58 2019-04-21 14:09:58,142 INFO: r4: stopping zebra
21-Apr-2019 14:09:58 2019-04-21 14:09:58,144 INFO: r4: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:10:00 PASSED
21-Apr-2019 14:10:00 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_memleak 2019-04-21 14:10:00,183 INFO:
21-Apr-2019 14:10:00 Router r1 ospf6d StdErr Log:
21-Apr-2019 14:10:00 log: showing active allocations in memory group libfrr
21-Apr-2019 14:10:00 log: memstats:  Vector                        :   6220 *         16
21-Apr-2019 14:10:00 log: memstats:  Vector index                  :   6220 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Link List                     :     34 *         40
21-Apr-2019 14:10:00 log: memstats:  Link Node                     :     75 *         24
21-Apr-2019 14:10:00 log: memstats:  Graph                         :     16 *          8
21-Apr-2019 14:10:00 log: memstats:  Graph Node                    :   3091 *         32
21-Apr-2019 14:10:00 log: memstats:  Host config                   :      3 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Command Tokens                :   2574 *         64
21-Apr-2019 14:10:00 log: memstats:  Command Token Text            :   1986 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Command Token Help            :   1986 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Prefix                        :      5 *         40
21-Apr-2019 14:10:00 log: memstats:  Thread                        :     40 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Thread master                 :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Thread stats                  :     35 *         64
21-Apr-2019 14:10:00 log: memstats:  Interface                     :      3 *        184
21-Apr-2019 14:10:00 log: memstats:  Connected                     :      5 *         40
21-Apr-2019 14:10:00 log: memstats:  Buffer                        :      1 *         24
21-Apr-2019 14:10:00 log: memstats:  Route table                   :     36 *         40
21-Apr-2019 14:10:00 log: memstats:  Route node                    :     82 *         96
21-Apr-2019 14:10:00 log: memstats:  Hash                          :     26 *         40
21-Apr-2019 14:10:00 log: memstats:  Hash Bucket                   :    559 *         24
21-Apr-2019 14:10:00 log: memstats:  Hash Index                    :     26 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Stream                        :      2 *         40
21-Apr-2019 14:10:00 log: memstats:  Stream data                   :      2 *       4096
21-Apr-2019 14:10:00 log: memstats:  Logging                       :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Zclient                       :      1 *       2896
21-Apr-2019 14:10:00 log: memstats:  Redistribution instance IDs   :      4 *          2
21-Apr-2019 14:10:00 log: memstats:  Privilege information         :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Priority queue                :      2 *         32
21-Apr-2019 14:10:00 log: memstats:  Priority queue data           :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  VRF                           :      1 *        136
21-Apr-2019 14:10:00 log: memstats:  VRF bit-map                   :     94 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Temporary memory              :      1 * (variably sized)
21-Apr-2019 14:10:00 log: showing active allocations in memory group ospf6d
21-Apr-2019 14:10:00 log: memstats:  OSPF6 top                     :      1 *        576
21-Apr-2019 14:10:00 log: memstats:  OSPF6 area                    :      1 *        216
21-Apr-2019 14:10:00 log: memstats:  OSPF6 interface               :      2 *        208
21-Apr-2019 14:10:00 log: memstats:  OSPF6 neighbor                :      2 *        248
21-Apr-2019 14:10:00 log: memstats:  OSPF6 route                   :     21 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  OSPF6 message                 :      2 *       1500
21-Apr-2019 14:10:00 log: memstats:  OSPF6 LSA                     :     48 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  OSPF6 LSA database            :     24 *         40
21-Apr-2019 14:10:00 log: memstats:  OSPF6 vertex                  :      4 *        232
21-Apr-2019 14:10:00 log: memstats:  OSPF6 nexthop                 :     13 *         20
21-Apr-2019 14:10:00 log: memstats:  OSPF6 ext. info               :      1 *         32
21-Apr-2019 14:10:00
21-Apr-2019 14:10:00 2019-04-21 14:10:00,190 INFO:
21-Apr-2019 14:10:00 Router r2 ospf6d StdErr Log:
21-Apr-2019 14:10:00 log: showing active allocations in memory group libfrr
21-Apr-2019 14:10:00 log: memstats:  Vector                        :   6220 *         16
21-Apr-2019 14:10:00 log: memstats:  Vector index                  :   6220 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Link List                     :     34 *         40
21-Apr-2019 14:10:00 log: memstats:  Link Node                     :     71 *         24
21-Apr-2019 14:10:00 log: memstats:  Graph                         :     16 *          8
21-Apr-2019 14:10:00 log: memstats:  Graph Node                    :   3091 *         32
21-Apr-2019 14:10:00 log: memstats:  Host config                   :      3 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Command Tokens                :   2574 *         64
21-Apr-2019 14:10:00 log: memstats:  Command Token Text            :   1986 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Command Token Help            :   1986 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Prefix                        :      5 *         40
21-Apr-2019 14:10:00 log: memstats:  Thread                        :     40 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Thread master                 :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Thread stats                  :     35 *         64
21-Apr-2019 14:10:00 log: memstats:  Interface                     :      3 *        184
21-Apr-2019 14:10:00 log: memstats:  Connected                     :      5 *         40
21-Apr-2019 14:10:00 log: memstats:  Buffer                        :      1 *         24
21-Apr-2019 14:10:00 log: memstats:  Route table                   :     36 *         40
21-Apr-2019 14:10:00 log: memstats:  Route node                    :     82 *         96
21-Apr-2019 14:10:00 log: memstats:  Hash                          :     26 *         40
21-Apr-2019 14:10:00 log: memstats:  Hash Bucket                   :    559 *         24
21-Apr-2019 14:10:00 log: memstats:  Hash Index                    :     26 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Stream                        :      2 *         40
21-Apr-2019 14:10:00 log: memstats:  Stream data                   :      2 *       4096
21-Apr-2019 14:10:00 log: memstats:  Logging                       :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Zclient                       :      1 *       2896
21-Apr-2019 14:10:00 log: memstats:  Redistribution instance IDs   :      4 *          2
21-Apr-2019 14:10:00 log: memstats:  Privilege information         :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Priority queue                :      2 *         32
21-Apr-2019 14:10:00 log: memstats:  Priority queue data           :      2 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  VRF                           :      1 *        136
21-Apr-2019 14:10:00 log: memstats:  VRF bit-map                   :     94 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  Temporary memory              :      1 * (variably sized)
21-Apr-2019 14:10:00 log: showing active allocations in memory group ospf6d
21-Apr-2019 14:10:00 log: memstats:  OSPF6 top                     :      1 *        576
21-Apr-2019 14:10:00 log: memstats:  OSPF6 area                    :      1 *        216
21-Apr-2019 14:10:00 log: memstats:  OSPF6 interface               :      2 *        208
21-Apr-2019 14:10:00 log: memstats:  OSPF6 neighbor                :      2 *        248
21-Apr-2019 14:10:00 log: memstats:  OSPF6 route                   :     21 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  OSPF6 message                 :      2 *       1500
21-Apr-2019 14:10:00 log: memstats:  OSPF6 LSA                     :     48 * (variably sized)
21-Apr-2019 14:10:00 log: memstats:  OSPF6 LSA database            :     24 *         40
21-Apr-2019 14:10:00 log: memstats:  OSPF6 vertex                  :      4 *        232
21-Apr-2019 14:10:00 log: memstats:  OSPF6 nexthop                 :     13 *         20
21-Apr-2019 14:10:00 log: memstats:  OSPF6 ext. info               :      1 *         32
21-Apr-2019 14:10:00
21-Apr-2019 14:10:01 PASSED
21-Apr-2019 14:10:01
21-Apr-2019 14:10:01 ** test_ospf6_topo1: Shutdown Topology
21-Apr-2019 14:10:01 ******************************************
21-Apr-2019 14:10:01
21-Apr-2019 14:10:01
21-Apr-2019 14:10:03 rip-topo1/test_rip_topo1.py::test_router_running
21-Apr-2019 14:10:03
21-Apr-2019 14:10:03 ** test_rip_topo1: Setup Topology
21-Apr-2019 14:10:03 ******************************************
21-Apr-2019 14:10:03
21-Apr-2019 14:10:03 Cleanup old Mininet runs
21-Apr-2019 14:10:03 2019-04-21 14:10:03,180 INFO: r1: running version: 3.0.3
21-Apr-2019 14:10:03 2019-04-21 14:10:03,183 INFO: r1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:10:04 2019-04-21 14:10:04,236 INFO: r2: running version: 3.0.3
21-Apr-2019 14:10:04 2019-04-21 14:10:04,238 INFO: r2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:10:05 2019-04-21 14:10:05,297 INFO: r3: running version: 3.0.3
21-Apr-2019 14:10:05 2019-04-21 14:10:05,299 INFO: r3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:10:11
21-Apr-2019 14:10:11
21-Apr-2019 14:10:11 ** Check if FRR/Quagga is running on each Router node
21-Apr-2019 14:10:11 ******************************************
21-Apr-2019 14:10:11
21-Apr-2019 14:10:11 PASSED
21-Apr-2019 14:11:11 rip-topo1/test_rip_topo1.py::test_converge_protocols
21-Apr-2019 14:11:11
21-Apr-2019 14:11:11 ** Waiting for protocols convergence
21-Apr-2019 14:11:11 ******************************************
21-Apr-2019 14:11:11
21-Apr-2019 14:11:11 PASSED
21-Apr-2019 14:11:12 rip-topo1/test_rip_topo1.py::test_rip_status
21-Apr-2019 14:11:12
21-Apr-2019 14:11:12 ** Verifing RIP status
21-Apr-2019 14:11:12 ******************************************
21-Apr-2019 14:11:12
21-Apr-2019 14:11:12 r1 ok
21-Apr-2019 14:11:12 r2 ok
21-Apr-2019 14:11:12 r3 ok
21-Apr-2019 14:11:12 PASSED
21-Apr-2019 14:11:12 rip-topo1/test_rip_topo1.py::test_rip_routes
21-Apr-2019 14:11:12
21-Apr-2019 14:11:12 ** Verifing RIP routes
21-Apr-2019 14:11:12 ******************************************
21-Apr-2019 14:11:12
21-Apr-2019 14:11:12 r1 ok
21-Apr-2019 14:11:12 r2 ok
21-Apr-2019 14:11:12 r3 ok
21-Apr-2019 14:11:12 PASSED
21-Apr-2019 14:11:13 rip-topo1/test_rip_topo1.py::test_zebra_ipv4_routingTable
21-Apr-2019 14:11:13
21-Apr-2019 14:11:13 ** Verifing Zebra IPv4 Routing Table
21-Apr-2019 14:11:13 ******************************************
21-Apr-2019 14:11:13
21-Apr-2019 14:11:13 r1 ok
21-Apr-2019 14:11:13 r2 ok
21-Apr-2019 14:11:13 r3 ok
21-Apr-2019 14:11:13 PASSED
21-Apr-2019 14:11:13 rip-topo1/test_rip_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:11:13
21-Apr-2019 14:11:13 ** Verifing unexpected STDERR output from daemons
21-Apr-2019 14:11:13 ******************************************
21-Apr-2019 14:11:13
21-Apr-2019 14:11:13 2019-04-21 14:11:13,240 INFO: r1: stopping ripd
21-Apr-2019 14:11:13 2019-04-21 14:11:13,243 INFO: r1: stopping zebra
21-Apr-2019 14:11:13 2019-04-21 14:11:13,245 INFO: r1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:11:15 PASSED
21-Apr-2019 14:11:15
21-Apr-2019 14:11:15 ** test_rip_topo1: Shutdown Topology
21-Apr-2019 14:11:15 ******************************************
21-Apr-2019 14:11:15
21-Apr-2019 14:11:15 2019-04-21 14:11:15,626 INFO: r2: stopping ripd
21-Apr-2019 14:11:15 2019-04-21 14:11:15,629 INFO: r2: stopping zebra
21-Apr-2019 14:11:15 2019-04-21 14:11:15,630 INFO: r2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:11:17 2019-04-21 14:11:17,764 INFO: r3: stopping ripd
21-Apr-2019 14:11:17 2019-04-21 14:11:17,768 INFO: r3: stopping zebra
21-Apr-2019 14:11:17 2019-04-21 14:11:17,773 INFO: r3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:11:19
21-Apr-2019 14:11:22 ripng-topo1/test_ripng_topo1.py::test_router_running
21-Apr-2019 14:11:22
21-Apr-2019 14:11:22 ** test_ripng_topo1: Setup Topology
21-Apr-2019 14:11:22 ******************************************
21-Apr-2019 14:11:22
21-Apr-2019 14:11:22 Cleanup old Mininet runs
21-Apr-2019 14:11:22 2019-04-21 14:11:22,068 INFO: r1: running version: 3.0.3
21-Apr-2019 14:11:22 2019-04-21 14:11:22,071 INFO: r1: waiting for zebra to start (1 seconds)
21-Apr-2019 14:11:23 2019-04-21 14:11:23,121 INFO: r2: running version: 3.0.3
21-Apr-2019 14:11:23 2019-04-21 14:11:23,122 INFO: r2: waiting for zebra to start (1 seconds)
21-Apr-2019 14:11:24 2019-04-21 14:11:24,174 INFO: r3: running version: 3.0.3
21-Apr-2019 14:11:24 2019-04-21 14:11:24,175 INFO: r3: waiting for zebra to start (1 seconds)
21-Apr-2019 14:11:30
21-Apr-2019 14:11:30
21-Apr-2019 14:11:30 ** Check if FRR/Quagga is running on each Router node
21-Apr-2019 14:11:30 ******************************************
21-Apr-2019 14:11:30
21-Apr-2019 14:11:30 PASSED
21-Apr-2019 14:12:30 ripng-topo1/test_ripng_topo1.py::test_converge_protocols
21-Apr-2019 14:12:30
21-Apr-2019 14:12:30 ** Waiting for protocols convergence
21-Apr-2019 14:12:30 ******************************************
21-Apr-2019 14:12:30
21-Apr-2019 14:12:30 PASSED
21-Apr-2019 14:12:31 ripng-topo1/test_ripng_topo1.py::test_ripng_status
21-Apr-2019 14:12:31
21-Apr-2019 14:12:31 ** Verifying RIPng status
21-Apr-2019 14:12:31 ******************************************
21-Apr-2019 14:12:31
21-Apr-2019 14:12:31 r1 ok
21-Apr-2019 14:12:31 r2 ok
21-Apr-2019 14:12:31 r3 ok
21-Apr-2019 14:12:31 PASSED
21-Apr-2019 14:12:31 ripng-topo1/test_ripng_topo1.py::test_ripng_routes
21-Apr-2019 14:12:31
21-Apr-2019 14:12:31 ** Verifying RIPng routes
21-Apr-2019 14:12:31 ******************************************
21-Apr-2019 14:12:31
21-Apr-2019 14:12:31 r1 ok
21-Apr-2019 14:12:31 r2 ok
21-Apr-2019 14:12:31 r3 ok
21-Apr-2019 14:12:31 PASSED
21-Apr-2019 14:12:32 ripng-topo1/test_ripng_topo1.py::test_zebra_ipv6_routingTable
21-Apr-2019 14:12:32
21-Apr-2019 14:12:32 ** Verifying Zebra IPv6 Routing Table
21-Apr-2019 14:12:32 ******************************************
21-Apr-2019 14:12:32
21-Apr-2019 14:12:32 r1 ok
21-Apr-2019 14:12:32 r2 ok
21-Apr-2019 14:12:32 r3 ok
21-Apr-2019 14:12:32 PASSED
21-Apr-2019 14:12:32 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:32
21-Apr-2019 14:12:32 ** Verifying unexpected STDERR output from daemons
21-Apr-2019 14:12:32 ******************************************
21-Apr-2019 14:12:32
21-Apr-2019 14:12:32 2019-04-21 14:12:32,118 INFO: r1: stopping ripngd
21-Apr-2019 14:12:32 2019-04-21 14:12:32,121 INFO: r1: stopping zebra
21-Apr-2019 14:12:32 2019-04-21 14:12:32,125 INFO: r1: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:12:34 PASSED
21-Apr-2019 14:12:34 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_memleak PASSED
21-Apr-2019 14:12:34
21-Apr-2019 14:12:34 ** test_ripng_topo1: Shutdown Topology
21-Apr-2019 14:12:34 ******************************************
21-Apr-2019 14:12:34
21-Apr-2019 14:12:34 2019-04-21 14:12:34,776 INFO: r2: stopping ripngd
21-Apr-2019 14:12:34 2019-04-21 14:12:34,780 INFO: r2: stopping zebra
21-Apr-2019 14:12:34 2019-04-21 14:12:34,783 INFO: r2: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:12:36 2019-04-21 14:12:36,950 INFO: r3: stopping ripngd
21-Apr-2019 14:12:36 2019-04-21 14:12:36,954 INFO: r3: stopping zebra
21-Apr-2019 14:12:36 2019-04-21 14:12:36,955 INFO: r3: waiting for daemons stopping (2 seconds)
21-Apr-2019 14:12:39
21-Apr-2019 14:12:39
21-Apr-2019 14:12:39 =============================== warnings summary ===============================
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_router_running
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_error_messages_vtysh
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_error_messages_daemons
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_converge_protocols
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_rip_status
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_ripng_status
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_ospfv2_interfaces
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_isis_interfaces
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_bgp_summary
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_bgp_ipv6_summary
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_bgp_ipv4
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_bgp_ipv6
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_mpls_interfaces
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 all-protocol-startup/test_all_protocol_startup.py::test_shutdown_check_memleak
21-Apr-2019 14:12:39 bfd-topo1/test_bfd_topo1.py::test_bfd_connection
21-Apr-2019 14:12:39 bfd-topo1/test_bfd_topo1.py::test_bgp_convergence
21-Apr-2019 14:12:39 bfd-topo1/test_bfd_topo1.py::test_bgp_fast_convergence
21-Apr-2019 14:12:39 bfd-topo1/test_bfd_topo1.py::test_bfd_fast_convergence
21-Apr-2019 14:12:39 bfd-topo1/test_bfd_topo1.py::test_bgp_fast_reconvergence
21-Apr-2019 14:12:39 bfd-topo1/test_bfd_topo1.py::test_memory_leak
21-Apr-2019 14:12:39 bgp-ecmp-topo1/test_bgp_ecmp_topo1.py::test_bgp_convergence
21-Apr-2019 14:12:39 bgp-ecmp-topo1/test_bgp_ecmp_topo1.py::test_bgp_ecmp
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_adjacencies
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_add_routes
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_check_routes
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_cleanup_all
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_memory_leak
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_check_linux_vrf
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_adjacencies
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_check_routes
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_check_linux_mpls
21-Apr-2019 14:12:39 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_memory_leak
21-Apr-2019 14:12:39 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_router_running
21-Apr-2019 14:12:39 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_bgp_converge
21-Apr-2019 14:12:39 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_bgp_routingTable
21-Apr-2019 14:12:39 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_shutdown_check_memleak
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_add_routes
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_adjacencies
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_check_routes
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_check_close
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_check_timeout
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_cleanup_all
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_memory_leak
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_add_routes
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_adjacencies
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_check_routes
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_check_close
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_check_timeout
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_cleanup_all
21-Apr-2019 14:12:39 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_memory_leak
21-Apr-2019 14:12:39 eigrp-topo1/test_eigrp_topo1.py::test_converge_protocols
21-Apr-2019 14:12:39 eigrp-topo1/test_eigrp_topo1.py::test_eigrp_routes
21-Apr-2019 14:12:39 eigrp-topo1/test_eigrp_topo1.py::test_zebra_ipv4_routingTable
21-Apr-2019 14:12:39 eigrp-topo1/test_eigrp_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 isis-topo1/test_isis_topo1.py::test_isis_convergence
21-Apr-2019 14:12:39 isis-topo1/test_isis_topo1.py::test_isis_route_installation
21-Apr-2019 14:12:39 isis-topo1/test_isis_topo1.py::test_isis_linux_route_installation
21-Apr-2019 14:12:39 isis-topo1/test_isis_topo1.py::test_isis_route6_installation
21-Apr-2019 14:12:39 isis-topo1/test_isis_topo1.py::test_isis_linux_route6_installation
21-Apr-2019 14:12:39 isis-topo1/test_isis_topo1.py::test_memory_leak
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_router_running
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_mpls_interfaces
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_neighbor_establish
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_discovery
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_neighbor
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_binding
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_zebra_ipv4_routingTable
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_mpls_table
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_linux_mpls_routes
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_memleak
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ospf_convergence
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_rib
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_adjacencies
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_neighbors
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_bindings
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pwid_bindings
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires_after_link_down
21-Apr-2019 14:12:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_memory_leak
21-Apr-2019 14:12:39 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_sr
21-Apr-2019 14:12:39 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_kernel_route
21-Apr-2019 14:12:39 ospf-sr-topo1/test_ospf_sr_topo1.py::test_memory_leak
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf_convergence
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf_kernel_route
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf6_convergence
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf6_kernel_route
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf_json
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down_kernel_route
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down_kernel_route
21-Apr-2019 14:12:39 ospf-topo1/test_ospf_topo1.py::test_memory_leak
21-Apr-2019 14:12:39 ospf6-topo1/test_ospf6_topo1.py::test_router_running
21-Apr-2019 14:12:39 ospf6-topo1/test_ospf6_topo1.py::test_ospf6_converged
21-Apr-2019 14:12:39 ospf6-topo1/test_ospf6_topo1.py::test_ospfv3_routingTable
21-Apr-2019 14:12:39 ospf6-topo1/test_ospf6_topo1.py::test_linux_ipv6_kernel_routingTable
21-Apr-2019 14:12:39 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_memleak
21-Apr-2019 14:12:39 rip-topo1/test_rip_topo1.py::test_router_running
21-Apr-2019 14:12:39 rip-topo1/test_rip_topo1.py::test_converge_protocols
21-Apr-2019 14:12:39 rip-topo1/test_rip_topo1.py::test_rip_status
21-Apr-2019 14:12:39 rip-topo1/test_rip_topo1.py::test_rip_routes
21-Apr-2019 14:12:39 rip-topo1/test_rip_topo1.py::test_zebra_ipv4_routingTable
21-Apr-2019 14:12:39 rip-topo1/test_rip_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_router_running
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_converge_protocols
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_ripng_status
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_ripng_routes
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_zebra_ipv6_routingTable
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_stderr
21-Apr-2019 14:12:39 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_memleak
21-Apr-2019 14:12:39   /root/bamboo-agent-home/xml-data/build-dir/FRR-TOPOPR-T3B/topotests/conftest.py:25: PytestDeprecationWarning: the `pytest.config` global is deprecated.  Please use `request.config` or `pytest_configure` (if you're a pytest plugin) instead.
21-Apr-2019 14:12:39     if pytest.config.getoption('--topology-only'):
21-Apr-2019 14:12:39
21-Apr-2019 14:12:39 -- Docs: https://docs.pytest.org/en/latest/warnings.html
21-Apr-2019 14:12:39 - generated xml file: /root/bamboo-agent-home/xml-data/build-dir/FRR-TOPOPR-T3B/topotests.xml -
21-Apr-2019 14:12:39 ======= 1 failed, 80 passed, 43 skipped, 114 warnings in 1002.02 seconds =======
21-Apr-2019 14:12:39 Topotests exit status: tests failed
21-Apr-2019 14:12:39 Finished task 'Run Topology Tests' with result: Success
21-Apr-2019 14:12:39 Starting task 'Parse Topology Test Results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
21-Apr-2019 14:12:39 Parsing test results under /root/bamboo-agent-home/xml-data/build-dir/FRR-TOPOPR-T3B...
21-Apr-2019 14:12:39 Failing task since 1 failing test cases were found.
21-Apr-2019 14:12:39 Finished task 'Parse Topology Test Results' with result: Failed
21-Apr-2019 14:12:39 Running post build plugin 'Docker Container Cleanup'
21-Apr-2019 14:12:39 Running post build plugin 'NCover Results Collector'
21-Apr-2019 14:12:39 Running post build plugin 'Clover Results Collector'
21-Apr-2019 14:12:39 Running post build plugin 'npm Cache Cleanup'
21-Apr-2019 14:12:39 Running post build plugin 'Artifact Copier'
21-Apr-2019 14:12:39 Publishing an artifact: MemoryLeaks
21-Apr-2019 14:12:39 Finished publishing of artifact Non required job artifact: [MemoryLeaks], pattern: [memleak*.txt] anchored at: [topotests] in 282.1 ms
21-Apr-2019 14:12:39 Publishing an artifact: TestExecutionLogs
21-Apr-2019 14:12:39 Finished publishing of artifact Non required job artifact: [TestExecutionLogs], pattern: [**] anchored at: [/tmp/topotests] in 262.3 ms
21-Apr-2019 14:12:39 Publishing an artifact: ErrorLog
21-Apr-2019 14:12:39 Finished publishing of artifact Non required job artifact: [ErrorLog], pattern: [log_*.txt] anchored at: [topotests] in 14.90 ms
21-Apr-2019 14:12:39 Successfully removed working directory at '/root/bamboo-agent-home/xml-data/build-dir/FRR-TOPOPR-T3B'
21-Apr-2019 14:12:39 Finalising the build...
21-Apr-2019 14:12:39 Stopping timer.
21-Apr-2019 14:12:39 Build FRR-TOPOPR-T3B-395 completed.
21-Apr-2019 14:12:40 Running on server: post build plugin 'NCover Results Collector'
21-Apr-2019 14:12:40 Running on server: post build plugin 'Build Hanging Detection Configuration'
21-Apr-2019 14:12:40 Running on server: post build plugin 'Clover Delta Calculator'
21-Apr-2019 14:12:40 Running on server: post build plugin 'Maven Dependencies Postprocessor'
21-Apr-2019 14:12:40 All post build plugins have finished
21-Apr-2019 14:12:40 Generating build results summary...
21-Apr-2019 14:12:40 Saving build results to disk...
21-Apr-2019 14:12:40 Logging substituted variables...
21-Apr-2019 14:12:40 Indexing build results...
21-Apr-2019 14:12:40 Finished building FRR-TOPOPR-T3B-395.