Build #1

FreeRangeRouting Protocol Suite

Build: #1 was successful

Job: TopoTests Ubuntu 16.04 i386 Part 0 was successful

Build log

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

02-May-2019 06:05:18 ** Verifying MPLS LDP discovery
02-May-2019 06:05:18 ******************************************
02-May-2019 06:05:18
02-May-2019 06:05:18 r1 ok
02-May-2019 06:05:18 r2 ok
02-May-2019 06:05:18 r3 ok
02-May-2019 06:05:18 r4 ok
02-May-2019 06:05:18 PASSED
02-May-2019 06:05:19 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_neighbor
02-May-2019 06:05:19
02-May-2019 06:05:19 ** Verifying MPLS LDP neighbor
02-May-2019 06:05:19 ******************************************
02-May-2019 06:05:19
02-May-2019 06:05:19 r1 ok
02-May-2019 06:05:19 r2 ok
02-May-2019 06:05:19 r3 ok
02-May-2019 06:05:19 r4 ok
02-May-2019 06:05:19 PASSED
02-May-2019 06:05:20 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_binding
02-May-2019 06:05:20
02-May-2019 06:05:20 ** Verifying MPLS LDP binding
02-May-2019 06:05:20 ******************************************
02-May-2019 06:05:20
02-May-2019 06:05:20 r1 ok
02-May-2019 06:05:20 r2 ok
02-May-2019 06:05:20 r3 ok
02-May-2019 06:05:20 r4 ok
02-May-2019 06:05:20 PASSED
02-May-2019 06:05:21 ldp-topo1/test_ldp_topo1.py::test_zebra_ipv4_routingTable
02-May-2019 06:05:21
02-May-2019 06:05:21 ** Verifying Zebra IPv4 Routing Table
02-May-2019 06:05:21 ******************************************
02-May-2019 06:05:21
02-May-2019 06:05:21 r1 ok
02-May-2019 06:05:21 r2 ok
02-May-2019 06:05:21 r3 ok
02-May-2019 06:05:21 r4 ok
02-May-2019 06:05:21 PASSED
02-May-2019 06:05:22 ldp-topo1/test_ldp_topo1.py::test_mpls_table
02-May-2019 06:05:22
02-May-2019 06:05:22 ** Verifying MPLS table
02-May-2019 06:05:22 ******************************************
02-May-2019 06:05:22
02-May-2019 06:05:22 r1 ok
02-May-2019 06:05:22 r2 ok
02-May-2019 06:05:22 r3 ok
02-May-2019 06:05:22 r4 ok
02-May-2019 06:05:22 PASSED
02-May-2019 06:05:22 ldp-topo1/test_ldp_topo1.py::test_linux_mpls_routes
02-May-2019 06:05:22
02-May-2019 06:05:22 ** Verifying Linux Kernel MPLS routes
02-May-2019 06:05:22 ******************************************
02-May-2019 06:05:22
02-May-2019 06:05:22 r1 ok
02-May-2019 06:05:22 r2 ok
02-May-2019 06:05:22 r3 ok
02-May-2019 06:05:22 r4 ok
02-May-2019 06:05:22 PASSED
02-May-2019 06:05:22 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_stderr
02-May-2019 06:05:22
02-May-2019 06:05:22 ** Verifying unexpected STDERR output from daemons
02-May-2019 06:05:22 ******************************************
02-May-2019 06:05:22
02-May-2019 06:05:22 2019-05-02 15:05:22,969 INFO: r1: stopping ldpd
02-May-2019 06:05:22 2019-05-02 15:05:22,974 INFO: r1: stopping ospfd
02-May-2019 06:05:22 2019-05-02 15:05:22,978 INFO: r1: stopping staticd
02-May-2019 06:05:22 2019-05-02 15:05:22,984 INFO: r1: stopping zebra
02-May-2019 06:05:22 2019-05-02 15:05:22,984 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:05:25 2019-05-02 15:05:25,037 INFO: r2: stopping ldpd
02-May-2019 06:05:25 2019-05-02 15:05:25,044 INFO: r2: stopping ospfd
02-May-2019 06:05:25 2019-05-02 15:05:25,046 INFO: r2: stopping staticd
02-May-2019 06:05:25 2019-05-02 15:05:25,053 INFO: r2: stopping zebra
02-May-2019 06:05:25 2019-05-02 15:05:25,054 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:05:27
02-May-2019 06:05:27 Router r2 LDPd StdErr Log:
02-May-2019 06:05:27 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r2/ldpd.err: No such file or directory
02-May-2019 06:05:27
02-May-2019 06:05:27
02-May-2019 06:05:27 Router r2 OSPFd StdErr Log:
02-May-2019 06:05:27 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r2/ospfd.err: No such file or directory
02-May-2019 06:05:27
02-May-2019 06:05:27
02-May-2019 06:05:27 Router r2 Zebra StdErr Log:
02-May-2019 06:05:27 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r2/zebra.err: No such file or directory
02-May-2019 06:05:27
02-May-2019 06:05:27 2019-05-02 15:05:27,103 INFO: r3: stopping ldpd
02-May-2019 06:05:27 2019-05-02 15:05:27,108 INFO: r3: stopping ospfd
02-May-2019 06:05:27 2019-05-02 15:05:27,113 INFO: r3: stopping staticd
02-May-2019 06:05:27 2019-05-02 15:05:27,117 INFO: r3: stopping zebra
02-May-2019 06:05:27 2019-05-02 15:05:27,117 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:05:29
02-May-2019 06:05:29 Router r3 LDPd StdErr Log:
02-May-2019 06:05:29 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r3/ldpd.err: No such file or directory
02-May-2019 06:05:29
02-May-2019 06:05:29
02-May-2019 06:05:29 Router r3 OSPFd StdErr Log:
02-May-2019 06:05:29 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r3/ospfd.err: No such file or directory
02-May-2019 06:05:29
02-May-2019 06:05:29
02-May-2019 06:05:29 Router r3 Zebra StdErr Log:
02-May-2019 06:05:29 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r3/zebra.err: No such file or directory
02-May-2019 06:05:29
02-May-2019 06:05:29 2019-05-02 15:05:29,156 INFO: r4: stopping ldpd
02-May-2019 06:05:29 2019-05-02 15:05:29,162 INFO: r4: stopping ospfd
02-May-2019 06:05:29 2019-05-02 15:05:29,165 INFO: r4: stopping staticd
02-May-2019 06:05:29 2019-05-02 15:05:29,167 INFO: r4: stopping zebra
02-May-2019 06:05:29 2019-05-02 15:05:29,167 INFO: r4: waiting for daemons stopping (2 seconds)
02-May-2019 06:05:31
02-May-2019 06:05:31 Router r4 LDPd StdErr Log:
02-May-2019 06:05:31 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r4/ldpd.err: No such file or directory
02-May-2019 06:05:31
02-May-2019 06:05:31
02-May-2019 06:05:31 Router r4 OSPFd StdErr Log:
02-May-2019 06:05:31 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r4/ospfd.err: No such file or directory
02-May-2019 06:05:31
02-May-2019 06:05:31
02-May-2019 06:05:31 Router r4 Zebra StdErr Log:
02-May-2019 06:05:31 cat: /tmp/topotests/ldp-topo1.test_ldp_topo1/r4/zebra.err: No such file or directory
02-May-2019 06:05:31
02-May-2019 06:05:31 PASSED
02-May-2019 06:05:31 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_memleak PASSED
02-May-2019 06:05:31
02-May-2019 06:05:31 ** test_ldp_topo1: Shutdown Topology
02-May-2019 06:05:31 ******************************************
02-May-2019 06:05:31
02-May-2019 06:05:31
02-May-2019 06:05:33 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ospf_convergence 2019-05-02 15:05:33,621 INFO: loading topology: test_ldp_vpls_topo1
02-May-2019 06:05:33 2019-05-02 15:05:33,622 INFO: starting topology: test_ldp_vpls_topo1
02-May-2019 06:05:33 2019-05-02 15:05:33,788 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:05:33 2019-05-02 15:05:33,790 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:05:35 2019-05-02 15:05:35,433 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:05:35 2019-05-02 15:05:35,434 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:05:37 2019-05-02 15:05:37,062 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:05:37 2019-05-02 15:05:37,063 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:05:38 2019-05-02 15:05:38,676 INFO: ce3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:05:38 2019-05-02 15:05:38,677 INFO: ce3: waiting for zebra to start (1 seconds)
02-May-2019 06:05:39 2019-05-02 15:05:39,985 INFO: ce2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:05:39 2019-05-02 15:05:39,986 INFO: ce2: waiting for zebra to start (1 seconds)
02-May-2019 06:05:41 2019-05-02 15:05:41,360 INFO: ce1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:05:41 2019-05-02 15:05:41,361 INFO: ce1: waiting for zebra to start (1 seconds)
02-May-2019 06:05:42 2019-05-02 15:05:42,651 INFO: Test: check OSPF adjacencies
02-May-2019 06:05:43 2019-05-02 15:05:43,575 INFO: Comparing router "r1" "show ip ospf neighbor json" output
02-May-2019 06:05:43 2019-05-02 15:05:43,575 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:28 2019-05-02 15:06:28,566 INFO: 'router_json_cmp' succeeded after 44.99 seconds
02-May-2019 06:06:28 2019-05-02 15:06:28,567 INFO: Comparing router "r2" "show ip ospf neighbor json" output
02-May-2019 06:06:28 2019-05-02 15:06:28,567 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:38 2019-05-02 15:06:38,355 INFO: 'router_json_cmp' succeeded after 9.79 seconds
02-May-2019 06:06:38 2019-05-02 15:06:38,355 INFO: Comparing router "r3" "show ip ospf neighbor json" output
02-May-2019 06:06:38 2019-05-02 15:06:38,356 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:38 2019-05-02 15:06:38,471 INFO: 'router_json_cmp' succeeded after 0.12 seconds
02-May-2019 06:06:38 PASSED
02-May-2019 06:06:38 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_rib 2019-05-02 15:06:38,476 INFO: Test: verify RIB
02-May-2019 06:06:39 2019-05-02 15:06:39,325 INFO: Comparing router "r1" "show ip route json" output
02-May-2019 06:06:39 2019-05-02 15:06:39,326 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:39 2019-05-02 15:06:39,463 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:06:39 2019-05-02 15:06:39,463 INFO: Comparing router "r2" "show ip route json" output
02-May-2019 06:06:39 2019-05-02 15:06:39,463 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:39 2019-05-02 15:06:39,598 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:06:39 2019-05-02 15:06:39,598 INFO: Comparing router "r3" "show ip route json" output
02-May-2019 06:06:39 2019-05-02 15:06:39,599 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:39 2019-05-02 15:06:39,729 INFO: 'router_json_cmp' succeeded after 0.13 seconds
02-May-2019 06:06:39 PASSED
02-May-2019 06:06:39 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_adjacencies 2019-05-02 15:06:39,733 INFO: Test: verify LDP adjacencies
02-May-2019 06:06:40 2019-05-02 15:06:40,494 INFO: Comparing router "r1" "show mpls ldp discovery json" output
02-May-2019 06:06:40 2019-05-02 15:06:40,494 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:40 2019-05-02 15:06:40,640 INFO: 'router_json_cmp' succeeded after 0.15 seconds
02-May-2019 06:06:40 2019-05-02 15:06:40,640 INFO: Comparing router "r2" "show mpls ldp discovery json" output
02-May-2019 06:06:40 2019-05-02 15:06:40,640 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:40 2019-05-02 15:06:40,751 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:40 2019-05-02 15:06:40,751 INFO: Comparing router "r3" "show mpls ldp discovery json" output
02-May-2019 06:06:40 2019-05-02 15:06:40,751 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:40 2019-05-02 15:06:40,859 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:40 PASSED
02-May-2019 06:06:40 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_neighbors 2019-05-02 15:06:40,863 INFO: Test: verify LDP neighbors
02-May-2019 06:06:41 2019-05-02 15:06:41,621 INFO: Comparing router "r1" "show mpls ldp neighbor json" output
02-May-2019 06:06:41 2019-05-02 15:06:41,622 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:41 2019-05-02 15:06:41,766 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:06:41 2019-05-02 15:06:41,767 INFO: Comparing router "r2" "show mpls ldp neighbor json" output
02-May-2019 06:06:41 2019-05-02 15:06:41,767 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:41 2019-05-02 15:06:41,894 INFO: 'router_json_cmp' succeeded after 0.13 seconds
02-May-2019 06:06:41 2019-05-02 15:06:41,894 INFO: Comparing router "r3" "show mpls ldp neighbor json" output
02-May-2019 06:06:41 2019-05-02 15:06:41,894 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:42 2019-05-02 15:06:42,021 INFO: 'router_json_cmp' succeeded after 0.13 seconds
02-May-2019 06:06:42 PASSED
02-May-2019 06:06:42 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_bindings 2019-05-02 15:06:42,026 INFO: Test: verify LDP bindings
02-May-2019 06:06:42 2019-05-02 15:06:42,787 INFO: Comparing router "r1" "show mpls ldp binding json" output
02-May-2019 06:06:42 2019-05-02 15:06:42,788 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:42 2019-05-02 15:06:42,898 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:42 2019-05-02 15:06:42,898 INFO: Comparing router "r2" "show mpls ldp binding json" output
02-May-2019 06:06:42 2019-05-02 15:06:42,898 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:43 2019-05-02 15:06:43,026 INFO: 'router_json_cmp' succeeded after 0.13 seconds
02-May-2019 06:06:43 2019-05-02 15:06:43,026 INFO: Comparing router "r3" "show mpls ldp binding json" output
02-May-2019 06:06:43 2019-05-02 15:06:43,026 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:43 2019-05-02 15:06:43,166 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:06:43 PASSED
02-May-2019 06:06:43 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pwid_bindings 2019-05-02 15:06:43,169 INFO: Test: verify LDP PW-ID bindings
02-May-2019 06:06:43 2019-05-02 15:06:43,893 INFO: Comparing router "r1" "show l2vpn atom binding json" output
02-May-2019 06:06:43 2019-05-02 15:06:43,893 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:44 2019-05-02 15:06:44,010 INFO: 'router_json_cmp' succeeded after 0.12 seconds
02-May-2019 06:06:44 2019-05-02 15:06:44,011 INFO: Comparing router "r2" "show l2vpn atom binding json" output
02-May-2019 06:06:44 2019-05-02 15:06:44,011 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:44 2019-05-02 15:06:44,126 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:44 2019-05-02 15:06:44,126 INFO: Comparing router "r3" "show l2vpn atom binding json" output
02-May-2019 06:06:44 2019-05-02 15:06:44,127 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:44 2019-05-02 15:06:44,235 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:44 PASSED
02-May-2019 06:06:44 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires 2019-05-02 15:06:44,240 INFO: Test: verify LDP pseudowires
02-May-2019 06:06:44 2019-05-02 15:06:44,966 INFO: Comparing router "r1" "show l2vpn atom vc json" output
02-May-2019 06:06:44 2019-05-02 15:06:44,966 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:45 2019-05-02 15:06:45,110 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:06:45 2019-05-02 15:06:45,110 INFO: Comparing router "r2" "show l2vpn atom vc json" output
02-May-2019 06:06:45 2019-05-02 15:06:45,110 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:45 2019-05-02 15:06:45,223 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:45 2019-05-02 15:06:45,223 INFO: Comparing router "r3" "show l2vpn atom vc json" output
02-May-2019 06:06:45 2019-05-02 15:06:45,223 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:45 2019-05-02 15:06:45,355 INFO: 'router_json_cmp' succeeded after 0.13 seconds
02-May-2019 06:06:45 PASSED
02-May-2019 06:06:45 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires_after_link_down 2019-05-02 15:06:45,357 INFO: Test: verify LDP pseudowires after r1-r2 link goes down
02-May-2019 06:06:46 2019-05-02 15:06:46,149 INFO: setting node "s4" link "s4-eth0" to state "down"
02-May-2019 06:06:46 2019-05-02 15:06:46,154 INFO: Comparing router "r1" "show l2vpn atom vc json" output
02-May-2019 06:06:46 2019-05-02 15:06:46,155 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:46 2019-05-02 15:06:46,298 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:06:46 2019-05-02 15:06:46,299 INFO: Comparing router "r2" "show l2vpn atom vc json" output
02-May-2019 06:06:46 2019-05-02 15:06:46,299 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:46 2019-05-02 15:06:46,413 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:46 2019-05-02 15:06:46,413 INFO: Comparing router "r3" "show l2vpn atom vc json" output
02-May-2019 06:06:46 2019-05-02 15:06:46,413 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:06:46 2019-05-02 15:06:46,528 INFO: 'router_json_cmp' succeeded after 0.11 seconds
02-May-2019 06:06:46 PASSED
02-May-2019 06:06:48 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_memory_leak 2019-05-02 15:06:48,008 INFO: r1: stopping ldpd
02-May-2019 06:06:48 2019-05-02 15:06:48,012 INFO: r1: stopping ospfd
02-May-2019 06:06:48 2019-05-02 15:06:48,015 INFO: r1: stopping staticd
02-May-2019 06:06:48 2019-05-02 15:06:48,018 INFO: r1: stopping zebra
02-May-2019 06:06:48 2019-05-02 15:06:48,019 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:06:50 2019-05-02 15:06:50,068 INFO: r2: stopping ldpd
02-May-2019 06:06:50 2019-05-02 15:06:50,072 INFO: r2: stopping ospfd
02-May-2019 06:06:50 2019-05-02 15:06:50,075 INFO: r2: stopping staticd
02-May-2019 06:06:50 2019-05-02 15:06:50,079 INFO: r2: stopping zebra
02-May-2019 06:06:50 2019-05-02 15:06:50,079 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:06:52 2019-05-02 15:06:52,129 INFO: r3: stopping ldpd
02-May-2019 06:06:52 2019-05-02 15:06:52,134 INFO: r3: stopping ospfd
02-May-2019 06:06:52 2019-05-02 15:06:52,138 INFO: r3: stopping staticd
02-May-2019 06:06:52 2019-05-02 15:06:52,142 INFO: r3: stopping zebra
02-May-2019 06:06:52 2019-05-02 15:06:52,143 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:06:54 2019-05-02 15:06:54,195 INFO: ce3: stopping staticd
02-May-2019 06:06:54 2019-05-02 15:06:54,198 INFO: ce3: stopping zebra
02-May-2019 06:06:54 2019-05-02 15:06:54,199 INFO: ce3: waiting for daemons stopping (2 seconds)
02-May-2019 06:06:56 2019-05-02 15:06:56,225 INFO: ce2: stopping staticd
02-May-2019 06:06:56 2019-05-02 15:06:56,229 INFO: ce2: stopping zebra
02-May-2019 06:06:56 2019-05-02 15:06:56,229 INFO: ce2: waiting for daemons stopping (2 seconds)
02-May-2019 06:06:58 2019-05-02 15:06:58,258 INFO: ce1: stopping staticd
02-May-2019 06:06:58 2019-05-02 15:06:58,261 INFO: ce1: stopping zebra
02-May-2019 06:06:58 2019-05-02 15:06:58,262 INFO: ce1: waiting for daemons stopping (2 seconds)
02-May-2019 06:07:00 PASSED2019-05-02 15:07:00,277 INFO: stopping topology: test_ldp_vpls_topo1
02-May-2019 06:07:00 2019-05-02 15:07:00,285 INFO: stopping "s3"
02-May-2019 06:07:00 2019-05-02 15:07:00,285 INFO: stopping "s2"
02-May-2019 06:07:00 2019-05-02 15:07:00,285 INFO: stopping "s1"
02-May-2019 06:07:00 2019-05-02 15:07:00,285 INFO: stopping "s6"
02-May-2019 06:07:00 2019-05-02 15:07:00,285 INFO: stopping "s5"
02-May-2019 06:07:00 2019-05-02 15:07:00,285 INFO: stopping "s4"
02-May-2019 06:07:00 2019-05-02 15:07:00,306 INFO: stopping "s3"
02-May-2019 06:07:00 2019-05-02 15:07:00,306 INFO: stopping "s2"
02-May-2019 06:07:00 2019-05-02 15:07:00,306 INFO: stopping "s1"
02-May-2019 06:07:00 2019-05-02 15:07:00,306 INFO: stopping "s6"
02-May-2019 06:07:00 2019-05-02 15:07:00,307 INFO: stopping "s5"
02-May-2019 06:07:00 2019-05-02 15:07:00,307 INFO: stopping "s4"
02-May-2019 06:07:01
02-May-2019 06:07:03 *** defaultIntf: warning: lm has no interfaces
02-May-2019 06:07:03 lm-proxy-topo1/test_lm-proxy-topo1.py::test_lm_proxy 2019-05-02 15:07:03,419 INFO: loading topology: test_lm-proxy-topo1
02-May-2019 06:07:03 2019-05-02 15:07:03,420 INFO: starting topology: test_lm-proxy-topo1
02-May-2019 06:07:03 2019-05-02 15:07:03,630 INFO: assert skipped at "test_lm-proxy-topo1/test_lm_proxy": Skipping test: ip/iproute2 has no VRF support
02-May-2019 06:07:03 SKIPPED
02-May-2019 06:07:03 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_sr 2019-05-02 15:07:03,634 INFO:
02-May-2019 06:07:03
02-May-2019 06:07:03 ---- Starting OSPF Segment Routing tests ----
02-May-2019 06:07:03
02-May-2019 06:07:05 2019-05-02 15:07:05,389 INFO: loading topology: test_ospf_sr_topo1
02-May-2019 06:07:05 2019-05-02 15:07:05,389 INFO: starting topology: test_ospf_sr_topo1
02-May-2019 06:07:05 2019-05-02 15:07:05,482 INFO: r4: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:07:05 2019-05-02 15:07:05,483 INFO: r4: waiting for zebra to start (1 seconds)
02-May-2019 06:07:06 2019-05-02 15:07:06,932 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:07:06 2019-05-02 15:07:06,932 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:07:08 2019-05-02 15:07:08,382 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:07:08 2019-05-02 15:07:08,383 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:07:09 2019-05-02 15:07:09,855 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:07:09 2019-05-02 15:07:09,856 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:07:12 2019-05-02 15:07:12,333 INFO: --- test OSPF Segment Routing Data Base ---
02-May-2019 06:07:12 2019-05-02 15:07:12,334 INFO:         Router "r1"
02-May-2019 06:07:12 2019-05-02 15:07:12,334 INFO: 'compare_ospf_srdb' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:02 2019-05-02 15:08:02,822 INFO: 'compare_ospf_srdb' succeeded after 50.49 seconds
02-May-2019 06:08:02 2019-05-02 15:08:02,822 INFO:         Router "r2"
02-May-2019 06:08:02 2019-05-02 15:08:02,823 INFO: 'compare_ospf_srdb' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:02 2019-05-02 15:08:02,942 INFO: 'compare_ospf_srdb' succeeded after 0.12 seconds
02-May-2019 06:08:02 2019-05-02 15:08:02,942 INFO:         Router "r3"
02-May-2019 06:08:02 2019-05-02 15:08:02,943 INFO: 'compare_ospf_srdb' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:03 2019-05-02 15:08:03,065 INFO: 'compare_ospf_srdb' succeeded after 0.12 seconds
02-May-2019 06:08:03 2019-05-02 15:08:03,065 INFO:         Router "r4"
02-May-2019 06:08:03 2019-05-02 15:08:03,065 INFO: 'compare_ospf_srdb' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:03 2019-05-02 15:08:03,181 INFO: 'compare_ospf_srdb' succeeded after 0.12 seconds
02-May-2019 06:08:03 PASSED
02-May-2019 06:08:03 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_kernel_route 2019-05-02 15:08:03,655 INFO: --- test OSPF Segment Routing MPLS tables ---
02-May-2019 06:08:03 2019-05-02 15:08:03,655 INFO:         Router "r1"
02-May-2019 06:08:03 2019-05-02 15:08:03,655 INFO: 'compare_mpls_table' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:03 2019-05-02 15:08:03,794 INFO: 'compare_mpls_table' succeeded after 0.14 seconds
02-May-2019 06:08:03 2019-05-02 15:08:03,794 INFO:         Router "r2"
02-May-2019 06:08:03 2019-05-02 15:08:03,794 INFO: 'compare_mpls_table' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:03 2019-05-02 15:08:03,913 INFO: 'compare_mpls_table' succeeded after 0.12 seconds
02-May-2019 06:08:03 2019-05-02 15:08:03,913 INFO:         Router "r3"
02-May-2019 06:08:03 2019-05-02 15:08:03,913 INFO: 'compare_mpls_table' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:04 2019-05-02 15:08:04,032 INFO: 'compare_mpls_table' succeeded after 0.12 seconds
02-May-2019 06:08:04 2019-05-02 15:08:04,032 INFO:         Router "r4"
02-May-2019 06:08:04 2019-05-02 15:08:04,032 INFO: 'compare_mpls_table' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:08:04 2019-05-02 15:08:04,147 INFO: 'compare_mpls_table' succeeded after 0.11 seconds
02-May-2019 06:08:04 PASSED
02-May-2019 06:08:05 ospf-sr-topo1/test_ospf_sr_topo1.py::test_memory_leak 2019-05-02 15:08:05,100 INFO: r4: stopping ospfd
02-May-2019 06:08:05 2019-05-02 15:08:05,106 INFO: r4: stopping staticd
02-May-2019 06:08:05 2019-05-02 15:08:05,109 INFO: r4: stopping zebra
02-May-2019 06:08:05 2019-05-02 15:08:05,110 INFO: r4: waiting for daemons stopping (2 seconds)
02-May-2019 06:08:07 2019-05-02 15:08:07,137 INFO: r1: stopping ospfd
02-May-2019 06:08:07 2019-05-02 15:08:07,142 INFO: r1: stopping staticd
02-May-2019 06:08:07 2019-05-02 15:08:07,145 INFO: r1: stopping zebra
02-May-2019 06:08:07 2019-05-02 15:08:07,145 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:08:09 2019-05-02 15:08:09,181 INFO: r2: stopping ospfd
02-May-2019 06:08:09 2019-05-02 15:08:09,186 INFO: r2: stopping staticd
02-May-2019 06:08:09 2019-05-02 15:08:09,190 INFO: r2: stopping zebra
02-May-2019 06:08:09 2019-05-02 15:08:09,190 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:08:11 2019-05-02 15:08:11,230 INFO: r3: stopping ospfd
02-May-2019 06:08:11 2019-05-02 15:08:11,234 INFO: r3: stopping staticd
02-May-2019 06:08:11 2019-05-02 15:08:11,237 INFO: r3: stopping zebra
02-May-2019 06:08:11 2019-05-02 15:08:11,237 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:08:13 PASSED2019-05-02 15:08:13,269 INFO: stopping topology: test_ospf_sr_topo1
02-May-2019 06:08:13 2019-05-02 15:08:13,280 INFO: stopping "s3"
02-May-2019 06:08:13 2019-05-02 15:08:13,280 INFO: stopping "s2"
02-May-2019 06:08:13 2019-05-02 15:08:13,280 INFO: stopping "s1"
02-May-2019 06:08:13 2019-05-02 15:08:13,290 INFO: stopping "s3"
02-May-2019 06:08:13 2019-05-02 15:08:13,290 INFO: stopping "s2"
02-May-2019 06:08:13 2019-05-02 15:08:13,290 INFO: stopping "s1"
02-May-2019 06:08:13 2019-05-02 15:08:13,889 INFO:
02-May-2019 06:08:13
02-May-2019 06:08:13 ---- OSPF Segment Routing tests End ----
02-May-2019 06:08:13
02-May-2019 06:08:13
02-May-2019 06:08:16 ospf-topo1/test_ospf_topo1.py::test_ospf_convergence 2019-05-02 15:08:16,022 INFO: loading topology: test_ospf_topo1
02-May-2019 06:08:16 2019-05-02 15:08:16,023 INFO: starting topology: test_ospf_topo1
02-May-2019 06:08:16 2019-05-02 15:08:16,108 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:08:16 2019-05-02 15:08:16,182 INFO: r4: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:08:16 2019-05-02 15:08:16,182 INFO: r4: waiting for zebra to start (1 seconds)
02-May-2019 06:08:17 2019-05-02 15:08:17,764 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:08:19 2019-05-02 15:08:19,398 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:08:19 2019-05-02 15:08:19,399 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:08:21 2019-05-02 15:08:21,018 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:08:21 2019-05-02 15:08:21,019 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:08:23 2019-05-02 15:08:23,108 INFO: Waiting for router "r4" convergence
02-May-2019 06:08:23 2019-05-02 15:08:23,109 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:09:12 2019-05-02 15:09:12,472 INFO: 'router_output_cmp' succeeded after 49.36 seconds
02-May-2019 06:09:12 2019-05-02 15:09:12,472 INFO: Waiting for router "r1" convergence
02-May-2019 06:09:12 2019-05-02 15:09:12,472 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:09:12 2019-05-02 15:09:12,601 INFO: 'router_output_cmp' succeeded after 0.13 seconds
02-May-2019 06:09:12 2019-05-02 15:09:12,602 INFO: Waiting for router "r2" convergence
02-May-2019 06:09:12 2019-05-02 15:09:12,602 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:09:12 2019-05-02 15:09:12,731 INFO: 'router_output_cmp' succeeded after 0.13 seconds
02-May-2019 06:09:12 2019-05-02 15:09:12,731 INFO: Waiting for router "r3" convergence
02-May-2019 06:09:12 2019-05-02 15:09:12,731 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:09:12 2019-05-02 15:09:12,856 INFO: 'router_output_cmp' succeeded after 0.12 seconds
02-May-2019 06:09:12 PASSED
02-May-2019 06:09:13 ospf-topo1/test_ospf_topo1.py::test_ospf_kernel_route 2019-05-02 15:09:13,366 INFO: Checking OSPF IPv4 kernel routes in "r4"
02-May-2019 06:09:13 2019-05-02 15:09:13,368 INFO: Checking OSPF IPv4 kernel routes in "r1"
02-May-2019 06:09:13 2019-05-02 15:09:13,370 INFO: Checking OSPF IPv4 kernel routes in "r2"
02-May-2019 06:09:13 2019-05-02 15:09:13,372 INFO: Checking OSPF IPv4 kernel routes in "r3"
02-May-2019 06:09:13 PASSED
02-May-2019 06:09:13 ospf-topo1/test_ospf_topo1.py::test_ospf6_convergence 2019-05-02 15:09:13,920 INFO: Waiting for router "r1" IPv6 OSPF convergence
02-May-2019 06:09:13 2019-05-02 15:09:13,921 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:09:14 2019-05-02 15:09:14,039 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.12 seconds
02-May-2019 06:09:14 2019-05-02 15:09:14,039 INFO: Waiting for router "r2" IPv6 OSPF convergence
02-May-2019 06:09:14 2019-05-02 15:09:14,040 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:09:14 2019-05-02 15:09:14,164 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.12 seconds
02-May-2019 06:09:14 2019-05-02 15:09:14,165 INFO: Waiting for router "r3" IPv6 OSPF convergence
02-May-2019 06:09:14 2019-05-02 15:09:14,165 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:09:14 2019-05-02 15:09:14,288 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.12 seconds
02-May-2019 06:09:14 2019-05-02 15:09:14,288 INFO: Waiting for router "r4" IPv6 OSPF convergence
02-May-2019 06:09:14 2019-05-02 15:09:14,289 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:09:14 2019-05-02 15:09:14,412 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.12 seconds
02-May-2019 06:09:14 PASSED
02-May-2019 06:09:14 ospf-topo1/test_ospf_topo1.py::test_ospf6_kernel_route 2019-05-02 15:09:14,918 INFO: Checking OSPF IPv6 kernel routes in "r4"
02-May-2019 06:09:14 2019-05-02 15:09:14,921 INFO: Checking OSPF IPv6 kernel routes in "r1"
02-May-2019 06:09:14 2019-05-02 15:09:14,923 INFO: Checking OSPF IPv6 kernel routes in "r2"
02-May-2019 06:09:14 2019-05-02 15:09:14,926 INFO: Checking OSPF IPv6 kernel routes in "r3"
02-May-2019 06:09:14 PASSED
02-May-2019 06:09:15 ospf-topo1/test_ospf_topo1.py::test_ospf_json 2019-05-02 15:09:15,435 INFO: Comparing router "r1" "show ip ospf json" output
02-May-2019 06:09:15 2019-05-02 15:09:15,557 INFO: Comparing router "r2" "show ip ospf json" output
02-May-2019 06:09:15 2019-05-02 15:09:15,679 INFO: Comparing router "r3" "show ip ospf json" output
02-May-2019 06:09:15 2019-05-02 15:09:15,815 INFO: Comparing router "r4" "show ip ospf json" output
02-May-2019 06:09:15 PASSED
02-May-2019 06:09:16 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down 2019-05-02 15:09:16,450 INFO: setting node "s3" link "s3-eth2" to state "down"
02-May-2019 06:09:16 2019-05-02 15:09:16,453 INFO: Waiting for router "r4" convergence after link failure
02-May-2019 06:09:16 2019-05-02 15:09:16,454 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:09:16 2019-05-02 15:09:16,578 INFO: 'router_output_cmp' succeeded after 0.12 seconds
02-May-2019 06:09:16 2019-05-02 15:09:16,579 INFO: Waiting for router "r1" convergence after link failure
02-May-2019 06:09:16 2019-05-02 15:09:16,579 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:09:53 2019-05-02 15:09:53,724 INFO: 'router_output_cmp' succeeded after 37.15 seconds
02-May-2019 06:09:53 2019-05-02 15:09:53,725 INFO: Waiting for router "r2" convergence after link failure
02-May-2019 06:09:53 2019-05-02 15:09:53,725 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:09:53 2019-05-02 15:09:53,848 INFO: 'router_output_cmp' succeeded after 0.12 seconds
02-May-2019 06:09:53 2019-05-02 15:09:53,848 INFO: Waiting for router "r3" convergence after link failure
02-May-2019 06:09:53 2019-05-02 15:09:53,848 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:09:54 2019-05-02 15:09:53,999 INFO: 'router_output_cmp' succeeded after 0.15 seconds
02-May-2019 06:09:54 PASSED
02-May-2019 06:09:54 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down_kernel_route 2019-05-02 15:09:54,526 INFO: Checking OSPF IPv4 kernel routes in "r4" after link down
02-May-2019 06:09:54 2019-05-02 15:09:54,529 INFO: Checking OSPF IPv4 kernel routes in "r1" after link down
02-May-2019 06:09:54 2019-05-02 15:09:54,531 INFO: Checking OSPF IPv4 kernel routes in "r2" after link down
02-May-2019 06:09:54 2019-05-02 15:09:54,532 INFO: Checking OSPF IPv4 kernel routes in "r3" after link down
02-May-2019 06:09:54 PASSED
02-May-2019 06:09:55 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down 2019-05-02 15:09:55,030 INFO: Waiting for router "r1" IPv6 OSPF convergence after link down
02-May-2019 06:09:55 2019-05-02 15:09:55,030 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:10:04 2019-05-02 15:10:04,575 INFO: 'compare_show_ipv6_ospf6' succeeded after 9.55 seconds
02-May-2019 06:10:04 2019-05-02 15:10:04,575 INFO: Waiting for router "r2" IPv6 OSPF convergence after link down
02-May-2019 06:10:04 2019-05-02 15:10:04,575 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:10:04 2019-05-02 15:10:04,702 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.13 seconds
02-May-2019 06:10:04 2019-05-02 15:10:04,702 INFO: Waiting for router "r3" IPv6 OSPF convergence after link down
02-May-2019 06:10:04 2019-05-02 15:10:04,702 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:10:04 2019-05-02 15:10:04,846 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.14 seconds
02-May-2019 06:10:04 2019-05-02 15:10:04,847 INFO: Waiting for router "r4" IPv6 OSPF convergence after link down
02-May-2019 06:10:04 2019-05-02 15:10:04,847 INFO: 'compare_show_ipv6_ospf6' polling started (interval 3 secs, maximum wait 75 secs)
02-May-2019 06:10:04 2019-05-02 15:10:04,970 INFO: 'compare_show_ipv6_ospf6' succeeded after 0.12 seconds
02-May-2019 06:10:04 PASSED
02-May-2019 06:10:05 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down_kernel_route 2019-05-02 15:10:05,479 INFO: Checking OSPF IPv6 kernel routes in "r4" after link down
02-May-2019 06:10:05 2019-05-02 15:10:05,482 INFO: Checking OSPF IPv6 kernel routes in "r1" after link down
02-May-2019 06:10:05 2019-05-02 15:10:05,485 INFO: Checking OSPF IPv6 kernel routes in "r2" after link down
02-May-2019 06:10:05 2019-05-02 15:10:05,487 INFO: Checking OSPF IPv6 kernel routes in "r3" after link down
02-May-2019 06:10:05 PASSED
02-May-2019 06:10:06 ospf-topo1/test_ospf_topo1.py::test_memory_leak 2019-05-02 15:10:06,589 INFO: r4: stopping ospf6d
02-May-2019 06:10:06 2019-05-02 15:10:06,593 INFO: r4: stopping ospfd
02-May-2019 06:10:06 2019-05-02 15:10:06,599 INFO: r4: stopping staticd
02-May-2019 06:10:06 2019-05-02 15:10:06,602 INFO: r4: stopping zebra
02-May-2019 06:10:06 2019-05-02 15:10:06,603 INFO: r4: waiting for daemons stopping (2 seconds)
02-May-2019 06:10:08 2019-05-02 15:10:08,642 INFO: r1: stopping ospf6d
02-May-2019 06:10:08 2019-05-02 15:10:08,649 INFO: r1: stopping ospfd
02-May-2019 06:10:08 2019-05-02 15:10:08,655 INFO: r1: stopping staticd
02-May-2019 06:10:08 2019-05-02 15:10:08,658 INFO: r1: stopping zebra
02-May-2019 06:10:08 2019-05-02 15:10:08,659 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:10:10 2019-05-02 15:10:10,702 INFO: r2: stopping ospf6d
02-May-2019 06:10:10 2019-05-02 15:10:10,707 INFO: r2: stopping ospfd
02-May-2019 06:10:10 2019-05-02 15:10:10,712 INFO: r2: stopping staticd
02-May-2019 06:10:10 2019-05-02 15:10:10,715 INFO: r2: stopping zebra
02-May-2019 06:10:10 2019-05-02 15:10:10,715 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:10:12 2019-05-02 15:10:12,757 INFO: r3: stopping ospf6d
02-May-2019 06:10:12 2019-05-02 15:10:12,763 INFO: r3: stopping ospfd
02-May-2019 06:10:12 2019-05-02 15:10:12,767 INFO: r3: stopping staticd
02-May-2019 06:10:12 2019-05-02 15:10:12,770 INFO: r3: stopping zebra
02-May-2019 06:10:12 2019-05-02 15:10:12,770 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:10:14 PASSED2019-05-02 15:10:14,806 INFO: stopping topology: test_ospf_topo1
02-May-2019 06:10:14 2019-05-02 15:10:14,816 INFO: stopping "s3"
02-May-2019 06:10:14 2019-05-02 15:10:14,817 INFO: stopping "s2"
02-May-2019 06:10:14 2019-05-02 15:10:14,817 INFO: stopping "s1"
02-May-2019 06:10:14 2019-05-02 15:10:14,817 INFO: stopping "s6"
02-May-2019 06:10:14 2019-05-02 15:10:14,817 INFO: stopping "s5"
02-May-2019 06:10:14 2019-05-02 15:10:14,817 INFO: stopping "s4"
02-May-2019 06:10:14 2019-05-02 15:10:14,826 INFO: stopping "s3"
02-May-2019 06:10:14 2019-05-02 15:10:14,826 INFO: stopping "s2"
02-May-2019 06:10:14 2019-05-02 15:10:14,826 INFO: stopping "s1"
02-May-2019 06:10:14 2019-05-02 15:10:14,826 INFO: stopping "s6"
02-May-2019 06:10:14 2019-05-02 15:10:14,826 INFO: stopping "s5"
02-May-2019 06:10:14 2019-05-02 15:10:14,826 INFO: stopping "s4"
02-May-2019 06:10:15
02-May-2019 06:10:17 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_convergence 2019-05-02 15:10:17,534 INFO: loading topology: test_ospf_topo1_vrf
02-May-2019 06:10:17 2019-05-02 15:10:17,535 INFO: starting topology: test_ospf_topo1_vrf
02-May-2019 06:10:17 RTNETLINK answers: Invalid argument
02-May-2019 06:10:17 RTNETLINK answers: Invalid argument
02-May-2019 06:10:17 RTNETLINK answers: Invalid argument
02-May-2019 06:10:17 RTNETLINK answers: Invalid argument
02-May-2019 06:10:17 r3-cust1
02-May-2019 06:10:17 r2-cust1
02-May-2019 06:10:17 2019-05-02 15:10:17,618 INFO: Testing with VRF Namespace support
02-May-2019 06:10:17 2019-05-02 15:10:17,829 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:10:17 2019-05-02 15:10:17,830 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:10:19 2019-05-02 15:10:19,263 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:10:19 2019-05-02 15:10:19,265 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:10:20 2019-05-02 15:10:20,718 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:10:20 2019-05-02 15:10:20,719 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:10:22 2019-05-02 15:10:22,584 INFO: Waiting for router "r1" convergence
02-May-2019 06:10:22 2019-05-02 15:10:22,585 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:11:11 2019-05-02 15:11:11,930 INFO: 'router_output_cmp' succeeded after 49.35 seconds
02-May-2019 06:11:11 2019-05-02 15:11:11,930 INFO: Waiting for router "r2" convergence
02-May-2019 06:11:11 2019-05-02 15:11:11,930 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:11:12 2019-05-02 15:11:12,052 INFO: 'router_output_cmp' succeeded after 0.12 seconds
02-May-2019 06:11:12 2019-05-02 15:11:12,052 INFO: Waiting for router "r3" convergence
02-May-2019 06:11:12 2019-05-02 15:11:12,052 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 80 secs)
02-May-2019 06:11:12 2019-05-02 15:11:12,174 INFO: 'router_output_cmp' succeeded after 0.12 seconds
02-May-2019 06:11:12 PASSED
02-May-2019 06:11:12 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_kernel_route 2019-05-02 15:11:12,551 INFO: Checking OSPF IPv4 kernel routes in "r1"
02-May-2019 06:11:12 2019-05-02 15:11:12,552 INFO: 'compare_show_ip_route_vrf' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:12 2019-05-02 15:11:12,668 INFO: 'compare_show_ip_route_vrf' succeeded after 0.12 seconds
02-May-2019 06:11:12 2019-05-02 15:11:12,668 INFO: Checking OSPF IPv4 kernel routes in "r2"
02-May-2019 06:11:12 2019-05-02 15:11:12,669 INFO: 'compare_show_ip_route_vrf' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:12 2019-05-02 15:11:12,805 INFO: 'compare_show_ip_route_vrf' succeeded after 0.14 seconds
02-May-2019 06:11:12 2019-05-02 15:11:12,806 INFO: Checking OSPF IPv4 kernel routes in "r3"
02-May-2019 06:11:12 2019-05-02 15:11:12,806 INFO: 'compare_show_ip_route_vrf' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:12 2019-05-02 15:11:12,946 INFO: 'compare_show_ip_route_vrf' succeeded after 0.14 seconds
02-May-2019 06:11:12 PASSED
02-May-2019 06:11:13 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_json 2019-05-02 15:11:13,346 INFO: Comparing router "r1" "show ip ospf vrf r1-cust1 json" output
02-May-2019 06:11:13 2019-05-02 15:11:13,346 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 5 secs)
02-May-2019 06:11:13 2019-05-02 15:11:13,468 INFO: 'router_json_cmp' succeeded after 0.12 seconds
02-May-2019 06:11:13 2019-05-02 15:11:13,469 INFO: Comparing router "r2" "show ip ospf vrf r2-cust1 json" output
02-May-2019 06:11:13 2019-05-02 15:11:13,469 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 5 secs)
02-May-2019 06:11:13 2019-05-02 15:11:13,586 INFO: 'router_json_cmp' succeeded after 0.12 seconds
02-May-2019 06:11:13 2019-05-02 15:11:13,586 INFO: Comparing router "r3" "show ip ospf vrf r3-cust1 json" output
02-May-2019 06:11:13 2019-05-02 15:11:13,586 INFO: 'router_json_cmp' polling started (interval 0.5 secs, maximum wait 5 secs)
02-May-2019 06:11:13 2019-05-02 15:11:13,725 INFO: 'router_json_cmp' succeeded after 0.14 seconds
02-May-2019 06:11:13 PASSED
02-May-2019 06:11:14 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_link_down 2019-05-02 15:11:14,246 INFO: Waiting for router "r1" convergence after link failure
02-May-2019 06:11:14 2019-05-02 15:11:14,246 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:52 2019-05-02 15:11:52,235 INFO: 'router_output_cmp' succeeded after 37.99 seconds
02-May-2019 06:11:52 2019-05-02 15:11:52,235 INFO: Waiting for router "r2" convergence after link failure
02-May-2019 06:11:52 2019-05-02 15:11:52,235 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:52 2019-05-02 15:11:52,375 INFO: 'router_output_cmp' succeeded after 0.14 seconds
02-May-2019 06:11:52 2019-05-02 15:11:52,375 INFO: Waiting for router "r3" convergence after link failure
02-May-2019 06:11:52 2019-05-02 15:11:52,376 INFO: 'router_output_cmp' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:52 2019-05-02 15:11:52,518 INFO: 'router_output_cmp' succeeded after 0.14 seconds
02-May-2019 06:11:52 PASSED
02-May-2019 06:11:52 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_link_down_kernel_route 2019-05-02 15:11:52,952 INFO: Checking OSPF IPv4 kernel routes in "r1" after link down
02-May-2019 06:11:52 2019-05-02 15:11:52,952 INFO: 'compare_show_ip_route_vrf' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:53 2019-05-02 15:11:53,074 INFO: 'compare_show_ip_route_vrf' succeeded after 0.12 seconds
02-May-2019 06:11:53 2019-05-02 15:11:53,074 INFO: Checking OSPF IPv4 kernel routes in "r2" after link down
02-May-2019 06:11:53 2019-05-02 15:11:53,074 INFO: 'compare_show_ip_route_vrf' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:53 2019-05-02 15:11:53,194 INFO: 'compare_show_ip_route_vrf' succeeded after 0.12 seconds
02-May-2019 06:11:53 2019-05-02 15:11:53,194 INFO: Checking OSPF IPv4 kernel routes in "r3" after link down
02-May-2019 06:11:53 2019-05-02 15:11:53,194 INFO: 'compare_show_ip_route_vrf' polling started (interval 0.5 secs, maximum wait 70 secs)
02-May-2019 06:11:53 2019-05-02 15:11:53,315 INFO: 'compare_show_ip_route_vrf' succeeded after 0.12 seconds
02-May-2019 06:11:53 PASSED
02-May-2019 06:11:54 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_memory_leak 2019-05-02 15:11:54,103 INFO: r1: stopping ospfd
02-May-2019 06:11:54 2019-05-02 15:11:54,106 INFO: r1: stopping staticd
02-May-2019 06:11:54 2019-05-02 15:11:54,108 INFO: r1: stopping zebra
02-May-2019 06:11:54 2019-05-02 15:11:54,109 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:11:56 2019-05-02 15:11:56,142 INFO: r2: stopping ospfd
02-May-2019 06:11:56 2019-05-02 15:11:56,146 INFO: r2: stopping staticd
02-May-2019 06:11:56 2019-05-02 15:11:56,149 INFO: r2: stopping zebra
02-May-2019 06:11:56 2019-05-02 15:11:56,150 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:11:58 2019-05-02 15:11:58,186 INFO: r3: stopping ospfd
02-May-2019 06:11:58 2019-05-02 15:11:58,190 INFO: r3: stopping staticd
02-May-2019 06:11:58 2019-05-02 15:11:58,193 INFO: r3: stopping zebra
02-May-2019 06:11:58 2019-05-02 15:11:58,193 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:12:00 PASSED2019-05-02 15:12:00,263 INFO: stopping topology: test_ospf_topo1_vrf
02-May-2019 06:12:00 2019-05-02 15:12:00,270 INFO: stopping "s3"
02-May-2019 06:12:00 2019-05-02 15:12:00,270 INFO: stopping "s2"
02-May-2019 06:12:00 2019-05-02 15:12:00,270 INFO: stopping "s1"
02-May-2019 06:12:00 2019-05-02 15:12:00,270 INFO: stopping "s4"
02-May-2019 06:12:00 2019-05-02 15:12:00,277 INFO: stopping "s3"
02-May-2019 06:12:00 2019-05-02 15:12:00,277 INFO: stopping "s2"
02-May-2019 06:12:00 2019-05-02 15:12:00,277 INFO: stopping "s1"
02-May-2019 06:12:00 2019-05-02 15:12:00,277 INFO: stopping "s4"
02-May-2019 06:12:00
02-May-2019 06:12:03 ospf6-topo1/test_ospf6_topo1.py::test_router_running
02-May-2019 06:12:03
02-May-2019 06:12:03 ** test_ospf6_topo1: Setup Topology
02-May-2019 06:12:03 ******************************************
02-May-2019 06:12:03
02-May-2019 06:12:03 Cleanup old Mininet runs
02-May-2019 06:12:03 2019-05-02 15:12:03,029 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:12:03 2019-05-02 15:12:03,052 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:12:04 2019-05-02 15:12:04,099 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:12:04 2019-05-02 15:12:04,099 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:12:05 2019-05-02 15:12:05,166 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:12:05 2019-05-02 15:12:05,167 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:12:06 2019-05-02 15:12:06,225 INFO: r4: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:12:06 2019-05-02 15:12:06,226 INFO: r4: waiting for zebra to start (1 seconds)
02-May-2019 06:12:12
02-May-2019 06:12:12
02-May-2019 06:12:12 ** Check if FRR/Quagga is running on each Router node
02-May-2019 06:12:12 ******************************************
02-May-2019 06:12:12
02-May-2019 06:12:12 PASSED
02-May-2019 06:12:12 ospf6-topo1/test_ospf6_topo1.py::test_ospf6_converged
02-May-2019 06:12:12
02-May-2019 06:12:12 ** Verify OSPF6 daemons to converge
02-May-2019 06:12:12 ******************************************
02-May-2019 06:12:12
02-May-2019 06:12:12 Timeout in 60:  Waiting for r1
02-May-2019 06:12:18 Timeout in 55:  Waiting for r1
02-May-2019 06:12:23 Timeout in 50:  Waiting for r1
02-May-2019 06:12:28 Timeout in 45:  Waiting for r1
02-May-2019 06:12:33 Timeout in 40:  Waiting for r1
02-May-2019 06:12:38 Timeout in 35:  Waiting for r1
02-May-2019 06:12:43 Timeout in 30:  Waiting for r1
02-May-2019 06:12:48 Timeout in 25:  Waiting for r1
02-May-2019 06:13:10 Timeout in 20:  Done
02-May-2019 06:13:10
02-May-2019 06:13:10 OSPFv3 converged.
02-May-2019 06:13:10
02-May-2019 06:13:10 waiting 15s for routes to populate
02-May-2019 06:13:10 PASSED
02-May-2019 06:13:11 ospf6-topo1/test_ospf6_topo1.py::test_ospfv3_routingTable
02-May-2019 06:13:11
02-May-2019 06:13:11 ** Verifying OSPFv3 Routing Table
02-May-2019 06:13:11 ******************************************
02-May-2019 06:13:11
02-May-2019 06:13:11 r1 ok
02-May-2019 06:13:11 r2 ok
02-May-2019 06:13:11 r3 ok
02-May-2019 06:13:11 r4 ok
02-May-2019 06:13:11 PASSED
02-May-2019 06:13:11 ospf6-topo1/test_ospf6_topo1.py::test_linux_ipv6_kernel_routingTable
02-May-2019 06:13:11
02-May-2019 06:13:11 ** Verifying Linux IPv6 Kernel Routing Table
02-May-2019 06:13:11 ******************************************
02-May-2019 06:13:11
02-May-2019 06:13:11 r1 ok
02-May-2019 06:13:11 r2 ok
02-May-2019 06:13:11 r3 ok
02-May-2019 06:13:11 r4 ok
02-May-2019 06:13:11 PASSED
02-May-2019 06:13:11 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_stderr
02-May-2019 06:13:11
02-May-2019 06:13:11 ** Verifying unexpected STDERR output from daemons
02-May-2019 06:13:11 ******************************************
02-May-2019 06:13:11
02-May-2019 06:13:11 2019-05-02 15:13:11,073 INFO: r1: stopping ospf6d
02-May-2019 06:13:11 2019-05-02 15:13:11,082 INFO: r1: stopping staticd
02-May-2019 06:13:11 2019-05-02 15:13:11,086 INFO: r1: stopping zebra
02-May-2019 06:13:11 2019-05-02 15:13:11,087 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:13:13 2019-05-02 15:13:13,123 INFO: r2: stopping ospf6d
02-May-2019 06:13:13 2019-05-02 15:13:13,128 INFO: r2: stopping staticd
02-May-2019 06:13:13 2019-05-02 15:13:13,132 INFO: r2: stopping zebra
02-May-2019 06:13:13 2019-05-02 15:13:13,132 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:13:15
02-May-2019 06:13:15 Router r2 OSPF6d StdErr Log:
02-May-2019 06:13:15 cat: /tmp/topotests/ospf6-topo1.test_ospf6_topo1/r2/ospf6d.err: No such file or directory
02-May-2019 06:13:15
02-May-2019 06:13:15
02-May-2019 06:13:15 Router r2 Zebra StdErr Log:
02-May-2019 06:13:15 cat: /tmp/topotests/ospf6-topo1.test_ospf6_topo1/r2/zebra.err: No such file or directory
02-May-2019 06:13:15
02-May-2019 06:13:15 2019-05-02 15:13:15,172 INFO: r3: stopping ospf6d
02-May-2019 06:13:15 2019-05-02 15:13:15,176 INFO: r3: stopping staticd
02-May-2019 06:13:15 2019-05-02 15:13:15,182 INFO: r3: stopping zebra
02-May-2019 06:13:15 2019-05-02 15:13:15,183 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:13:17
02-May-2019 06:13:17 Router r3 OSPF6d StdErr Log:
02-May-2019 06:13:17 cat: /tmp/topotests/ospf6-topo1.test_ospf6_topo1/r3/ospf6d.err: No such file or directory
02-May-2019 06:13:17
02-May-2019 06:13:17
02-May-2019 06:13:17 Router r3 Zebra StdErr Log:
02-May-2019 06:13:17 cat: /tmp/topotests/ospf6-topo1.test_ospf6_topo1/r3/zebra.err: No such file or directory
02-May-2019 06:13:17
02-May-2019 06:13:17 2019-05-02 15:13:17,214 INFO: r4: stopping ospf6d
02-May-2019 06:13:17 2019-05-02 15:13:17,218 INFO: r4: stopping staticd
02-May-2019 06:13:17 2019-05-02 15:13:17,221 INFO: r4: stopping zebra
02-May-2019 06:13:17 2019-05-02 15:13:17,221 INFO: r4: waiting for daemons stopping (2 seconds)
02-May-2019 06:13:19
02-May-2019 06:13:19 Router r4 OSPF6d StdErr Log:
02-May-2019 06:13:19 cat: /tmp/topotests/ospf6-topo1.test_ospf6_topo1/r4/ospf6d.err: No such file or directory
02-May-2019 06:13:19
02-May-2019 06:13:19
02-May-2019 06:13:19 Router r4 Zebra StdErr Log:
02-May-2019 06:13:19 cat: /tmp/topotests/ospf6-topo1.test_ospf6_topo1/r4/zebra.err: No such file or directory
02-May-2019 06:13:19
02-May-2019 06:13:19 PASSED
02-May-2019 06:13:20 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_memleak PASSED
02-May-2019 06:13:20
02-May-2019 06:13:20 ** test_ospf6_topo1: Shutdown Topology
02-May-2019 06:13:20 ******************************************
02-May-2019 06:13:20
02-May-2019 06:13:20
02-May-2019 06:13:21 pim-basic/test_pim.py::test_pim_send_mcast_stream 2019-05-02 15:13:21,627 INFO: loading topology: test_pim
02-May-2019 06:13:21 2019-05-02 15:13:21,628 INFO: starting topology: test_pim
02-May-2019 06:13:21 2019-05-02 15:13:21,694 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:13:21 2019-05-02 15:13:21,695 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:13:23 2019-05-02 15:13:23,153 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:13:23 2019-05-02 15:13:23,154 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:13:24 2019-05-02 15:13:24,613 INFO: Establish a Mcast stream from r2->r1 and then ensure S,G created
02-May-2019 06:13:25 PASSED
02-May-2019 06:13:25 pim-basic/test_pim.py::test_pim_igmp_report 2019-05-02 15:13:25,097 INFO: Send a igmp report from r2-r1 and ensure *,G created
02-May-2019 06:13:25 PASSED
02-May-2019 06:13:25 pim-basic/test_pim.py::test_memory_leak 2019-05-02 15:13:25,967 INFO: r1: stopping pimd
02-May-2019 06:13:25 2019-05-02 15:13:25,969 INFO: r1: stopping staticd
02-May-2019 06:13:25 2019-05-02 15:13:25,971 INFO: r1: stopping zebra
02-May-2019 06:13:25 2019-05-02 15:13:25,971 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:13:28 2019-05-02 15:13:28,009 INFO: r2: stopping pimd
02-May-2019 06:13:28 2019-05-02 15:13:28,011 INFO: r2: stopping staticd
02-May-2019 06:13:28 2019-05-02 15:13:28,015 INFO: r2: stopping zebra
02-May-2019 06:13:28 2019-05-02 15:13:28,015 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:13:30 PASSED2019-05-02 15:13:30,054 INFO: stopping topology: test_pim
02-May-2019 06:13:30 2019-05-02 15:13:30,054 INFO: stopping "sw1"
02-May-2019 06:13:30 2019-05-02 15:13:30,061 INFO: stopping "sw1"
02-May-2019 06:13:30
02-May-2019 06:13:32 rip-topo1/test_rip_topo1.py::test_router_running
02-May-2019 06:13:32
02-May-2019 06:13:32 ** test_rip_topo1: Setup Topology
02-May-2019 06:13:32 ******************************************
02-May-2019 06:13:32
02-May-2019 06:13:32 Cleanup old Mininet runs
02-May-2019 06:13:32 2019-05-02 15:13:32,512 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:13:32 2019-05-02 15:13:32,513 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:13:33 2019-05-02 15:13:33,573 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:13:33 2019-05-02 15:13:33,574 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:13:34 2019-05-02 15:13:34,629 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:13:34 2019-05-02 15:13:34,630 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:13:41
02-May-2019 06:13:41
02-May-2019 06:13:41 ** Check if FRR/Quagga is running on each Router node
02-May-2019 06:13:41 ******************************************
02-May-2019 06:13:41
02-May-2019 06:13:41 PASSED
02-May-2019 06:14:41 rip-topo1/test_rip_topo1.py::test_converge_protocols
02-May-2019 06:14:41
02-May-2019 06:14:41 ** Waiting for protocols convergence
02-May-2019 06:14:41 ******************************************
02-May-2019 06:14:41
02-May-2019 06:14:41 PASSED
02-May-2019 06:14:42 rip-topo1/test_rip_topo1.py::test_rip_status
02-May-2019 06:14:42
02-May-2019 06:14:42 ** Verifing RIP status
02-May-2019 06:14:42 ******************************************
02-May-2019 06:14:42
02-May-2019 06:14:42 r1 ok
02-May-2019 06:14:42 r2 ok
02-May-2019 06:14:42 r3 ok
02-May-2019 06:14:42 PASSED
02-May-2019 06:14:42 rip-topo1/test_rip_topo1.py::test_rip_routes
02-May-2019 06:14:42
02-May-2019 06:14:42 ** Verifing RIP routes
02-May-2019 06:14:42 ******************************************
02-May-2019 06:14:42
02-May-2019 06:14:42 r1 ok
02-May-2019 06:14:42 r2 ok
02-May-2019 06:14:42 r3 ok
02-May-2019 06:14:42 PASSED
02-May-2019 06:14:43 rip-topo1/test_rip_topo1.py::test_zebra_ipv4_routingTable
02-May-2019 06:14:43
02-May-2019 06:14:43 ** Verifing Zebra IPv4 Routing Table
02-May-2019 06:14:43 ******************************************
02-May-2019 06:14:43
02-May-2019 06:14:43 r1 ok
02-May-2019 06:14:43 r2 ok
02-May-2019 06:14:43 r3 ok
02-May-2019 06:14:43 PASSED
02-May-2019 06:14:43 rip-topo1/test_rip_topo1.py::test_shutdown_check_stderr
02-May-2019 06:14:43
02-May-2019 06:14:43 ** Verifing unexpected STDERR output from daemons
02-May-2019 06:14:43 ******************************************
02-May-2019 06:14:43
02-May-2019 06:14:43 2019-05-02 15:14:43,708 INFO: r1: stopping ripd
02-May-2019 06:14:43 2019-05-02 15:14:43,711 INFO: r1: stopping staticd
02-May-2019 06:14:43 2019-05-02 15:14:43,713 INFO: r1: stopping zebra
02-May-2019 06:14:43 2019-05-02 15:14:43,714 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:14:46 PASSED
02-May-2019 06:14:46
02-May-2019 06:14:46 ** test_rip_topo1: Shutdown Topology
02-May-2019 06:14:46 ******************************************
02-May-2019 06:14:46
02-May-2019 06:14:46 2019-05-02 15:14:46,058 INFO: r2: stopping ripd
02-May-2019 06:14:46 2019-05-02 15:14:46,061 INFO: r2: stopping staticd
02-May-2019 06:14:46 2019-05-02 15:14:46,063 INFO: r2: stopping zebra
02-May-2019 06:14:46 2019-05-02 15:14:46,064 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:14:48 2019-05-02 15:14:48,204 INFO: r3: stopping ripd
02-May-2019 06:14:48 2019-05-02 15:14:48,209 INFO: r3: stopping staticd
02-May-2019 06:14:48 2019-05-02 15:14:48,212 INFO: r3: stopping zebra
02-May-2019 06:14:48 2019-05-02 15:14:48,212 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:14:50
02-May-2019 06:14:52 ripng-topo1/test_ripng_topo1.py::test_router_running
02-May-2019 06:14:52
02-May-2019 06:14:52 ** test_ripng_topo1: Setup Topology
02-May-2019 06:14:52 ******************************************
02-May-2019 06:14:52
02-May-2019 06:14:52 Cleanup old Mininet runs
02-May-2019 06:14:52 2019-05-02 15:14:52,450 INFO: r1: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:14:52 2019-05-02 15:14:52,453 INFO: r1: waiting for zebra to start (1 seconds)
02-May-2019 06:14:53 2019-05-02 15:14:53,515 INFO: r2: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:14:53 2019-05-02 15:14:53,516 INFO: r2: waiting for zebra to start (1 seconds)
02-May-2019 06:14:54 2019-05-02 15:14:54,572 INFO: r3: running version: 7.1-dev-20190502-09-g5e76ce506
02-May-2019 06:14:54 2019-05-02 15:14:54,574 INFO: r3: waiting for zebra to start (1 seconds)
02-May-2019 06:15:01
02-May-2019 06:15:01
02-May-2019 06:15:01 ** Check if FRR/Quagga is running on each Router node
02-May-2019 06:15:01 ******************************************
02-May-2019 06:15:01
02-May-2019 06:15:01 PASSED
02-May-2019 06:16:01 ripng-topo1/test_ripng_topo1.py::test_converge_protocols
02-May-2019 06:16:01
02-May-2019 06:16:01 ** Waiting for protocols convergence
02-May-2019 06:16:01 ******************************************
02-May-2019 06:16:01
02-May-2019 06:16:01 PASSED
02-May-2019 06:16:02 ripng-topo1/test_ripng_topo1.py::test_ripng_status
02-May-2019 06:16:02
02-May-2019 06:16:02 ** Verifying RIPng status
02-May-2019 06:16:02 ******************************************
02-May-2019 06:16:02
02-May-2019 06:16:02 r1 ok
02-May-2019 06:16:02 r2 ok
02-May-2019 06:16:02 r3 ok
02-May-2019 06:16:02 PASSED
02-May-2019 06:16:02 ripng-topo1/test_ripng_topo1.py::test_ripng_routes
02-May-2019 06:16:02
02-May-2019 06:16:02 ** Verifying RIPng routes
02-May-2019 06:16:02 ******************************************
02-May-2019 06:16:02
02-May-2019 06:16:02 r1 ok
02-May-2019 06:16:02 r2 ok
02-May-2019 06:16:02 r3 ok
02-May-2019 06:16:02 PASSED
02-May-2019 06:16:03 ripng-topo1/test_ripng_topo1.py::test_zebra_ipv6_routingTable
02-May-2019 06:16:03
02-May-2019 06:16:03 ** Verifying Zebra IPv6 Routing Table
02-May-2019 06:16:03 ******************************************
02-May-2019 06:16:03
02-May-2019 06:16:03 r1 ok
02-May-2019 06:16:03 r2 ok
02-May-2019 06:16:03 r3 ok
02-May-2019 06:16:03 PASSED
02-May-2019 06:16:03 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:03
02-May-2019 06:16:03 ** Verifying unexpected STDERR output from daemons
02-May-2019 06:16:03 ******************************************
02-May-2019 06:16:03
02-May-2019 06:16:03 2019-05-02 15:16:03,771 INFO: r1: stopping ripngd
02-May-2019 06:16:03 2019-05-02 15:16:03,774 INFO: r1: stopping staticd
02-May-2019 06:16:03 2019-05-02 15:16:03,776 INFO: r1: stopping zebra
02-May-2019 06:16:03 2019-05-02 15:16:03,776 INFO: r1: waiting for daemons stopping (2 seconds)
02-May-2019 06:16:05 PASSED
02-May-2019 06:16:06 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_memleak PASSED
02-May-2019 06:16:06
02-May-2019 06:16:06 ** test_ripng_topo1: Shutdown Topology
02-May-2019 06:16:06 ******************************************
02-May-2019 06:16:06
02-May-2019 06:16:06 2019-05-02 15:16:06,172 INFO: r2: stopping ripngd
02-May-2019 06:16:06 2019-05-02 15:16:06,181 INFO: r2: stopping staticd
02-May-2019 06:16:06 2019-05-02 15:16:06,185 INFO: r2: stopping zebra
02-May-2019 06:16:06 2019-05-02 15:16:06,194 INFO: r2: waiting for daemons stopping (2 seconds)
02-May-2019 06:16:08 2019-05-02 15:16:08,339 INFO: r3: stopping ripngd
02-May-2019 06:16:08 2019-05-02 15:16:08,342 INFO: r3: stopping staticd
02-May-2019 06:16:08 2019-05-02 15:16:08,345 INFO: r3: stopping zebra
02-May-2019 06:16:08 2019-05-02 15:16:08,345 INFO: r3: waiting for daemons stopping (2 seconds)
02-May-2019 06:16:10
02-May-2019 06:16:10
02-May-2019 06:16:10 =============================== warnings summary ===============================
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_router_running
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_error_messages_vtysh
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_error_messages_daemons
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_converge_protocols
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_rip_status
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_ripng_status
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_ospfv2_interfaces
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_isis_interfaces
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_bgp_summary
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_bgp_ipv6_summary
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_bgp_ipv4
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_bgp_ipv6
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_mpls_interfaces
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_shutdown_check_stderr
02-May-2019 06:16:10 all-protocol-startup/test_all_protocol_startup.py::test_shutdown_check_memleak
02-May-2019 06:16:10 bfd-topo1/test_bfd_topo1.py::test_bfd_connection
02-May-2019 06:16:10 bfd-topo1/test_bfd_topo1.py::test_bgp_convergence
02-May-2019 06:16:10 bfd-topo1/test_bfd_topo1.py::test_bgp_fast_convergence
02-May-2019 06:16:10 bfd-topo1/test_bfd_topo1.py::test_bfd_fast_convergence
02-May-2019 06:16:10 bfd-topo1/test_bfd_topo1.py::test_bgp_fast_reconvergence
02-May-2019 06:16:10 bfd-topo1/test_bfd_topo1.py::test_memory_leak
02-May-2019 06:16:10 bfd-topo2/test_bfd_topo2.py::test_protocols_convergence
02-May-2019 06:16:10 bfd-topo2/test_bfd_topo2.py::test_bfd_connection
02-May-2019 06:16:10 bfd-topo2/test_bfd_topo2.py::test_memory_leak
02-May-2019 06:16:10 bgp-ecmp-topo1/test_bgp_ecmp_topo1.py::test_bgp_convergence
02-May-2019 06:16:10 bgp-ecmp-topo1/test_bgp_ecmp_topo1.py::test_bgp_ecmp
02-May-2019 06:16:10 bgp-vrf-route-leak-basic/test_bgp.py::test_vrf_route_leak
02-May-2019 06:16:10 bgp-vrf-route-leak-basic/test_bgp.py::test_memory_leak
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_adjacencies
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_add_routes
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_check_routes
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_cleanup_all
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_direct/test_bgp_l3vpn_to_bgp_direct.py::test_memory_leak
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_check_linux_vrf
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_adjacencies
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_check_routes
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_check_linux_mpls
02-May-2019 06:16:10 bgp_l3vpn_to_bgp_vrf/test_bgp_l3vpn_to_bgp_vrf.py::test_memory_leak
02-May-2019 06:16:10 bgp_local_as_private_remove/test_bgp_local_as_private_remove.py::test_bgp_remove_private_as
02-May-2019 06:16:10 bgp_maximum_prefix_invalid_update/test_bgp_maximum_prefix_invalid_update.py::test_bgp_maximum_prefix_invalid
02-May-2019 06:16:10 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_router_running
02-May-2019 06:16:10 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_bgp_converge
02-May-2019 06:16:10 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_bgp_routingTable
02-May-2019 06:16:10 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:10 bgp_multiview_topo1/test_bgp_multiview_topo1.py::test_shutdown_check_memleak
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_add_routes
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_adjacencies
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_check_routes
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_check_close
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_check_timeout
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_cleanup_all
02-May-2019 06:16:10 bgp_rfapi_basic_sanity/test_bgp_rfapi_basic_sanity.py::test_memory_leak
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_add_routes
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_adjacencies
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_check_routes
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_check_close
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_check_timeout
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_cleanup_all
02-May-2019 06:16:10 bgp_rfapi_basic_sanity_config2/test_bgp_rfapi_basic_sanity_config2.py::test_memory_leak
02-May-2019 06:16:10 eigrp-topo1/test_eigrp_topo1.py::test_converge_protocols
02-May-2019 06:16:10 eigrp-topo1/test_eigrp_topo1.py::test_eigrp_routes
02-May-2019 06:16:10 eigrp-topo1/test_eigrp_topo1.py::test_zebra_ipv4_routingTable
02-May-2019 06:16:10 eigrp-topo1/test_eigrp_topo1.py::test_shut_interface_and_recover
02-May-2019 06:16:10 eigrp-topo1/test_eigrp_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:10 isis-topo1/test_isis_topo1.py::test_isis_convergence
02-May-2019 06:16:10 isis-topo1/test_isis_topo1.py::test_isis_route_installation
02-May-2019 06:16:10 isis-topo1/test_isis_topo1.py::test_isis_linux_route_installation
02-May-2019 06:16:10 isis-topo1/test_isis_topo1.py::test_isis_route6_installation
02-May-2019 06:16:10 isis-topo1/test_isis_topo1.py::test_isis_linux_route6_installation
02-May-2019 06:16:10 isis-topo1/test_isis_topo1.py::test_memory_leak
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_router_running
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_mpls_interfaces
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_neighbor_establish
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_discovery
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_neighbor
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_mpls_ldp_binding
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_zebra_ipv4_routingTable
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_mpls_table
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_linux_mpls_routes
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:10 ldp-topo1/test_ldp_topo1.py::test_shutdown_check_memleak
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ospf_convergence
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_rib
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_adjacencies
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_neighbors
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_bindings
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pwid_bindings
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_ldp_pseudowires_after_link_down
02-May-2019 06:16:10 ldp-vpls-topo1/test_ldp_vpls_topo1.py::test_memory_leak
02-May-2019 06:16:10 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_sr
02-May-2019 06:16:10 ospf-sr-topo1/test_ospf_sr_topo1.py::test_ospf_kernel_route
02-May-2019 06:16:10 ospf-sr-topo1/test_ospf_sr_topo1.py::test_memory_leak
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf_convergence
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf_kernel_route
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf6_convergence
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf6_kernel_route
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf_json
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf_link_down_kernel_route
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_ospf6_link_down_kernel_route
02-May-2019 06:16:10 ospf-topo1/test_ospf_topo1.py::test_memory_leak
02-May-2019 06:16:10 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_convergence
02-May-2019 06:16:10 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_kernel_route
02-May-2019 06:16:10 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_json
02-May-2019 06:16:10 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_link_down
02-May-2019 06:16:10 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_ospf_link_down_kernel_route
02-May-2019 06:16:10 ospf-topo1-vrf/test_ospf_topo1_vrf.py::test_memory_leak
02-May-2019 06:16:10 ospf6-topo1/test_ospf6_topo1.py::test_router_running
02-May-2019 06:16:10 ospf6-topo1/test_ospf6_topo1.py::test_ospf6_converged
02-May-2019 06:16:10 ospf6-topo1/test_ospf6_topo1.py::test_ospfv3_routingTable
02-May-2019 06:16:10 ospf6-topo1/test_ospf6_topo1.py::test_linux_ipv6_kernel_routingTable
02-May-2019 06:16:10 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:10 ospf6-topo1/test_ospf6_topo1.py::test_shutdown_check_memleak
02-May-2019 06:16:10 pim-basic/test_pim.py::test_pim_send_mcast_stream
02-May-2019 06:16:10 pim-basic/test_pim.py::test_pim_igmp_report
02-May-2019 06:16:10 pim-basic/test_pim.py::test_memory_leak
02-May-2019 06:16:10 rip-topo1/test_rip_topo1.py::test_router_running
02-May-2019 06:16:10 rip-topo1/test_rip_topo1.py::test_converge_protocols
02-May-2019 06:16:10 rip-topo1/test_rip_topo1.py::test_rip_status
02-May-2019 06:16:10 rip-topo1/test_rip_topo1.py::test_rip_routes
02-May-2019 06:16:10 rip-topo1/test_rip_topo1.py::test_zebra_ipv4_routingTable
02-May-2019 06:16:10 rip-topo1/test_rip_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_router_running
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_converge_protocols
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_ripng_status
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_ripng_routes
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_zebra_ipv6_routingTable
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_stderr
02-May-2019 06:16:10 ripng-topo1/test_ripng_topo1.py::test_shutdown_check_memleak
02-May-2019 06:16:10   /root/bamboo-agent-home/xml-data/build-dir/FRR-FRR36-TOPOI386/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.
02-May-2019 06:16:10     if pytest.config.getoption('--topology-only'):
02-May-2019 06:16:10
02-May-2019 06:16:10 -- Docs: https://docs.pytest.org/en/latest/warnings.html
02-May-2019 06:16:10 - generated xml file: /root/bamboo-agent-home/xml-data/build-dir/FRR-FRR36-TOPOI386/topotests.xml -
02-May-2019 06:16:10 ============ 129 passed, 6 skipped, 131 warnings in 1450.34 seconds ============
02-May-2019 06:16:10 Topotests exit status: Success
02-May-2019 06:16:10 Finished task 'Run Topology Tests' with result: Success
02-May-2019 06:16:10 Starting task 'Parse Topology Test Results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
02-May-2019 06:16:10 Parsing test results under /root/bamboo-agent-home/xml-data/build-dir/FRR-FRR36-TOPOI386...
02-May-2019 06:16:10 Finished task 'Parse Topology Test Results' with result: Success
02-May-2019 06:16:10 Running post build plugin 'Docker Container Cleanup'
02-May-2019 06:16:10 Running post build plugin 'NCover Results Collector'
02-May-2019 06:16:10 Running post build plugin 'Clover Results Collector'
02-May-2019 06:16:10 Running post build plugin 'npm Cache Cleanup'
02-May-2019 06:16:10 Running post build plugin 'Artifact Copier'
02-May-2019 06:16:10 Publishing an artifact: ErrorLog
02-May-2019 06:16:11 Finished publishing of artifact Non required job artifact: [ErrorLog], pattern: [log_*.txt] anchored at: [.] in 823.3 ms
02-May-2019 06:16:11 Publishing an artifact: MemoryLeaks
02-May-2019 06:16:11 Finished publishing of artifact Non required job artifact: [MemoryLeaks], pattern: [memleak*.txt] anchored at: [topotests] in 174.7 ms
02-May-2019 06:16:11 Successfully removed working directory at '/root/bamboo-agent-home/xml-data/build-dir/FRR-FRR36-TOPOI386'
02-May-2019 06:16:11 Finalising the build...
02-May-2019 06:16:11 Stopping timer.
02-May-2019 06:16:11 Build FRR-FRR36-TOPOI386-1 completed.
02-May-2019 06:16:12 Running on server: post build plugin 'NCover Results Collector'
02-May-2019 06:16:12 Running on server: post build plugin 'Build Hanging Detection Configuration'
02-May-2019 06:16:12 Running on server: post build plugin 'Clover Delta Calculator'
02-May-2019 06:16:12 Running on server: post build plugin 'Maven Dependencies Postprocessor'
02-May-2019 06:16:12 All post build plugins have finished
02-May-2019 06:16:12 Generating build results summary...
02-May-2019 06:16:12 Saving build results to disk...
02-May-2019 06:16:12 Logging substituted variables...
02-May-2019 06:16:13 Indexing build results...
02-May-2019 06:16:13 Finished building FRR-FRR36-TOPOI386-1.