2026-11-09 15:58:55,953 - INFO - Domain Default found not creating
2026-11-09 15:58:56,144 - INFO - Project ctest-TestSvcRegr-29115857 not found, creating it
2026-11-09 15:58:56,608 - INFO - Created Project:ctest-TestSvcRegr-29115857, ID : 500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:58:58,333 - INFO - ================================================================================
2026-11-09 15:58:58,334 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-11-09 15:58:58,589 - DEBUG - Nothing to compare xmpp stats {'10.0.0.163': {'10.20.0.17': '0'}} with
2026-11-09 15:58:58,589 - INFO - Initial checks done. Running the testcase now
2026-11-09 15:58:58,589 - INFO -
2026-11-09 15:58:59,207 - DEBUG - Response for create_network : {'network': {'id': '982f24d7-90d1-4728-b375-0a34758b674d', 'name': 'ctest-mgmt_vn-96352767', 'tenant_id': '500be2852d86491bb3121b8540c495e1', 'project_id': '500be2852d86491bb3121b8540c495e1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-mgmt_vn-96352767'], 'port_security_enabled': True, 'description': ''}}
2026-11-09 15:58:59,389 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b78ca6d2-bece-4d78-b5e4-f7e62db09ecf', 'name': '', 'tenant_id': '500be2852d86491bb3121b8540c495e1', 'network_id': '982f24d7-90d1-4728-b375-0a34758b674d', 'ip_version': 4, 'cidr': '168.184.50.192/26', 'allocation_pools': [{'start': '168.184.50.194', 'end': '168.184.50.254'}], 'gateway_ip': '168.184.50.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.184.50.194', 'tags': [], 'project_id': '500be2852d86491bb3121b8540c495e1'}}
2026-11-09 15:58:59,405 - INFO - Created VN ctest-mgmt_vn-96352767
2026-11-09 15:58:59,454 - DEBUG - VN ctest-mgmt_vn-96352767 UUID is 982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:58:59,558 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:58:59,930 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:58:59,956 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 15:58:59,969 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:59:00,042 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:59:00,053 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:59:00,062 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/fb18b8af-3dfd-4587-9415-82e026b77e76
2026-11-09 15:59:00,071 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/fb18b8af-3dfd-4587-9415-82e026b77e76
2026-11-09 15:59:00,081 - DEBUG - Requesting: http://10.0.0.163:8082/route-target/6691fab1-b311-46ae-904c-368703d6d206
2026-11-09 15:59:00,090 - DEBUG - Route Targets: ['target:64512:8000005']
2026-11-09 15:59:00,090 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:59:00,100 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/fb18b8af-3dfd-4587-9415-82e026b77e76
2026-11-09 15:59:00,116 - INFO - Verified VN network id 12 for VN 982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:59:00,116 - INFO - Verifications in API Server for VN ctest-mgmt_vn-96352767 passed
2026-11-09 15:59:00,116 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:59:00,132 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/fb18b8af-3dfd-4587-9415-82e026b77e76
2026-11-09 15:59:00,140 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/fb18b8af-3dfd-4587-9415-82e026b77e76
2026-11-09 15:59:00,146 - DEBUG - Requesting: http://10.0.0.163:8082/route-target/6691fab1-b311-46ae-904c-368703d6d206
2026-11-09 15:59:00,159 - DEBUG - Control-node 10.0.0.163 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29115857', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10966024125936650024', 'uuid-lslong': '12931253125490894669'}, 'enable': 'true', 'created': '2026-11-09T15:58:59', 'last-modified': '2026-11-09T15:58:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.801340'}
2026-11-09 15:59:00,166 - DEBUG - Route Targets: ['target:64512:8000005']
2026-11-09 15:59:00,166 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-96352767 passed
2026-11-09 15:59:00,166 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-96352767 in API_Server ======
2026-11-09 15:59:00,167 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:59:00,172 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 15:59:00,182 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:59:00,256 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/982f24d7-90d1-4728-b375-0a34758b674d
2026-11-09 15:59:00,265 - DEBUG - =>VN ctest-mgmt_vn-96352767 has no policy to be verified
2026-11-09 15:59:00,265 - DEBUG - Verifying the vn in opserver
2026-11-09 15:59:00,265 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 virtual network link through opserver 10.0.0.163
2026-11-09 15:59:00,265 - DEBUG - Requesting: http://10.0.0.163:8081/analytics/uves/virtual-networks
2026-11-09 15:59:00,627 - DEBUG - Requesting: http://10.0.0.163:8081/analytics/uves/virtual-networks
2026-11-09 15:59:00,678 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767', 'href': 'http://10.0.0.163:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767?flat'}
2026-11-09 15:59:00,678 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 is found in opserver
2026-11-09 15:59:00,688 - DEBUG - Do not have enough data to verify VN in agent
2026-11-09 15:59:00,694 - DEBUG - VRF ids for VN ctest-mgmt_vn-96352767: {}
2026-11-09 15:59:00,882 - DEBUG - Response for create_network : {'network': {'id': '21ac1184-8f2c-4662-9e9e-27ff9b51eb2e', 'name': 'ctest-left_vn-77947792', 'tenant_id': '500be2852d86491bb3121b8540c495e1', 'project_id': '500be2852d86491bb3121b8540c495e1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-left_vn-77947792'], 'port_security_enabled': True, 'description': ''}}
2026-11-09 15:59:01,121 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e195924f-77c6-43f4-ace8-1fb625f040ce', 'name': '', 'tenant_id': '500be2852d86491bb3121b8540c495e1', 'network_id': '21ac1184-8f2c-4662-9e9e-27ff9b51eb2e', 'ip_version': 4, 'cidr': '65.122.242.64/26', 'allocation_pools': [{'start': '65.122.242.66', 'end': '65.122.242.126'}], 'gateway_ip': '65.122.242.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '65.122.242.66', 'tags': [], 'project_id': '500be2852d86491bb3121b8540c495e1'}}
2026-11-09 15:59:01,138 - INFO - Created VN ctest-left_vn-77947792
2026-11-09 15:59:01,190 - DEBUG - VN ctest-left_vn-77947792 UUID is 21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,300 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:59:01,306 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 15:59:01,317 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:59:01,399 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,411 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,421 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/79ae20a6-6632-4b75-8299-6a80244d9156
2026-11-09 15:59:01,429 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/79ae20a6-6632-4b75-8299-6a80244d9156
2026-11-09 15:59:01,437 - DEBUG - Requesting: http://10.0.0.163:8082/route-target/5026747e-1745-400c-a568-e5af6e12be4b
2026-11-09 15:59:01,443 - DEBUG - Route Targets: ['target:64512:8000006']
2026-11-09 15:59:01,443 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,452 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/79ae20a6-6632-4b75-8299-6a80244d9156
2026-11-09 15:59:01,468 - INFO - Verified VN network id 13 for VN 21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,468 - INFO - Verifications in API Server for VN ctest-left_vn-77947792 passed
2026-11-09 15:59:01,468 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,479 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/79ae20a6-6632-4b75-8299-6a80244d9156
2026-11-09 15:59:01,489 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/79ae20a6-6632-4b75-8299-6a80244d9156
2026-11-09 15:59:01,499 - DEBUG - Requesting: http://10.0.0.163:8082/route-target/5026747e-1745-400c-a568-e5af6e12be4b
2026-11-09 15:59:01,514 - DEBUG - Control-node 10.0.0.163 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29115857', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2426333560281253474', 'uuid-lslong': '11429616883088878382'}, 'enable': 'true', 'created': '2026-11-09T15:59:00', 'last-modified': '2026-11-09T15:59:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430832'}
2026-11-09 15:59:01,521 - DEBUG - Route Targets: ['target:64512:8000006']
2026-11-09 15:59:01,521 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-77947792 passed
2026-11-09 15:59:01,522 - DEBUG - ====Verifying policy data for ctest-left_vn-77947792 in API_Server ======
2026-11-09 15:59:01,522 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:59:01,529 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 15:59:01,541 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:59:01,623 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/21ac1184-8f2c-4662-9e9e-27ff9b51eb2e
2026-11-09 15:59:01,632 - DEBUG - =>VN ctest-left_vn-77947792 has no policy to be verified
2026-11-09 15:59:01,632 - DEBUG - Verifying the vn in opserver
2026-11-09 15:59:01,632 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792 virtual network link through opserver 10.0.0.163
2026-11-09 15:59:01,632 - DEBUG - Requesting: http://10.0.0.163:8081/analytics/uves/virtual-networks
2026-11-09 15:59:01,643 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792', 'href': 'http://10.0.0.163:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792?flat'}
2026-11-09 15:59:01,643 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792 is found in opserver
2026-11-09 15:59:01,655 - DEBUG - Do not have enough data to verify VN in agent
2026-11-09 15:59:01,660 - DEBUG - VRF ids for VN ctest-left_vn-77947792: {}
2026-11-09 15:59:01,901 - DEBUG - Response for create_network : {'network': {'id': '5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d', 'name': 'ctest-right_vn-33867611', 'tenant_id': '500be2852d86491bb3121b8540c495e1', 'project_id': '500be2852d86491bb3121b8540c495e1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-right_vn-33867611'], 'port_security_enabled': True, 'description': ''}}
2026-11-09 15:59:02,131 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c3c310cb-1bc4-42fd-9d40-4a8302ec8d97', 'name': '', 'tenant_id': '500be2852d86491bb3121b8540c495e1', 'network_id': '5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d', 'ip_version': 4, 'cidr': '60.165.232.64/26', 'allocation_pools': [{'start': '60.165.232.66', 'end': '60.165.232.126'}], 'gateway_ip': '60.165.232.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '60.165.232.66', 'tags': [], 'project_id': '500be2852d86491bb3121b8540c495e1'}}
2026-11-09 15:59:02,149 - INFO - Created VN ctest-right_vn-33867611
2026-11-09 15:59:02,202 - DEBUG - VN ctest-right_vn-33867611 UUID is 5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,310 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:59:02,316 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 15:59:02,331 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:59:02,404 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,415 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,425 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/81745dc9-e7a3-4f84-b63c-507e66a419e7
2026-11-09 15:59:02,432 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/81745dc9-e7a3-4f84-b63c-507e66a419e7
2026-11-09 15:59:02,439 - DEBUG - Requesting: http://10.0.0.163:8082/route-target/f40e76fe-22af-4aab-82e3-629ba91d0bfd
2026-11-09 15:59:02,445 - DEBUG - Route Targets: ['target:64512:8000007']
2026-11-09 15:59:02,446 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,455 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/81745dc9-e7a3-4f84-b63c-507e66a419e7
2026-11-09 15:59:02,469 - INFO - Verified VN network id 14 for VN 5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,469 - INFO - Verifications in API Server for VN ctest-right_vn-33867611 passed
2026-11-09 15:59:02,469 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,478 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/81745dc9-e7a3-4f84-b63c-507e66a419e7
2026-11-09 15:59:02,484 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/81745dc9-e7a3-4f84-b63c-507e66a419e7
2026-11-09 15:59:02,491 - DEBUG - Requesting: http://10.0.0.163:8082/route-target/f40e76fe-22af-4aab-82e3-629ba91d0bfd
2026-11-09 15:59:02,503 - DEBUG - Control-node 10.0.0.163 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29115857', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6664721254641519140', 'uuid-lslong': '11996190441131461005'}, 'enable': 'true', 'created': '2026-11-09T15:59:01', 'last-modified': '2026-11-09T15:59:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399162'}
2026-11-09 15:59:02,509 - DEBUG - Route Targets: ['target:64512:8000007']
2026-11-09 15:59:02,509 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-33867611 passed
2026-11-09 15:59:02,509 - DEBUG - ====Verifying policy data for ctest-right_vn-33867611 in API_Server ======
2026-11-09 15:59:02,509 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 15:59:02,515 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 15:59:02,527 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 15:59:02,604 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-network/5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d
2026-11-09 15:59:02,616 - DEBUG - =>VN ctest-right_vn-33867611 has no policy to be verified
2026-11-09 15:59:02,616 - DEBUG - Verifying the vn in opserver
2026-11-09 15:59:02,616 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611 virtual network link through opserver 10.0.0.163
2026-11-09 15:59:02,616 - DEBUG - Requesting: http://10.0.0.163:8081/analytics/uves/virtual-networks
2026-11-09 15:59:02,626 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611', 'href': 'http://10.0.0.163:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611?flat'}
2026-11-09 15:59:02,627 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611 is found in opserver
2026-11-09 15:59:02,636 - DEBUG - Do not have enough data to verify VN in agent
2026-11-09 15:59:02,641 - DEBUG - VRF ids for VN ctest-right_vn-33867611: {}
2026-11-09 15:59:02,725 - DEBUG - Services list from nova: [, , ]
2026-11-09 15:59:03,237 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6501-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6501-1)
2026-11-09 15:59:03,738 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6501-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6501-1)
2026-11-09 15:59:03,739 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-21561851']
2026-11-09 15:59:04,106 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-21561851'] created succesfully
2026-11-09 15:59:04,371 - DEBUG - Image by name cirros_in_net not found
2026-11-09 15:59:04,371 - DEBUG - Installing image cirros_in_net
2026-11-09 15:59:04,371 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'}
2026-11-09 15:59:04,372 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-11-09 15:59:04,372 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-11-09 15:59:04,385 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz -O /contrail-test/images/cirros-in-net.vmdk.gz
2026-11-09 15:59:04,536 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz
2026-11-09 15:59:04,536 - DEBUG - Unzip image
2026-11-09 15:59:04,536 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz
2026-11-09 15:59:04,709 - DEBUG - Try to create image in user context
2026-11-09 15:59:04,974 - DEBUG - Image has been created in user context
2026-11-09 15:59:04,974 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk
2026-11-09 15:59:04,991 - DEBUG - Downloaded image has been removed
2026-11-09 15:59:05,008 - DEBUG - Uploaded image status = active
2026-11-09 15:59:05,418 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6501-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6501-1)
2026-11-09 15:59:05,418 - INFO - Waiting for VM ctest-pt_svm0-79436715 to be up..
2026-11-09 15:59:05,460 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:10,509 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:15,598 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:20,686 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:25,779 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:30,865 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:35,955 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:41,077 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:46,174 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:51,263 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 15:59:56,352 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 16:00:01,445 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-11-09 16:00:06,557 - DEBUG - VM is in ACTIVE state now
2026-11-09 16:00:06,557 - INFO - VM name : ctest-pt_svm0-79436715
2026-11-09 16:00:06,649 - DEBUG - VM ctest-pt_svm0-79436715 ID is ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34
2026-11-09 16:00:06,670 - DEBUG - VM ctest-pt_svm0-79436715 launched on Node cn-jenkins-deploy-platform-ansible-os-6501-1
2026-11-09 16:00:06,983 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34
2026-11-09 16:00:06,996 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/3d5e8ff2-6609-4298-95cb-fa53ed42a64f
2026-11-09 16:00:07,011 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/5bd5ca74-b676-4936-8dea-2552f9263344
2026-11-09 16:00:07,023 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/bbf6c835-42dd-4325-b828-8b0789ac9f99
2026-11-09 16:00:10,308 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2026-11-09 16:00:10,308 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-79436715 failed!
2026-11-09 16:00:10,366 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 is 168.184.50.193 and allocation pool is NOT set
2026-11-09 16:00:14,445 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-11-09 16:00:14,445 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-79436715 failed!
2026-11-09 16:00:14,498 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 is 168.184.50.193 and allocation pool is NOT set
2026-11-09 16:00:18,566 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=4.13 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1003ms\r\nrtt min/avg/max/mdev = 4.129/4.129/4.129/0.000 ms')
2026-11-09 16:00:18,567 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-79436715 passed
2026-11-09 16:00:18,715 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:18,715 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-79436715, IP 168.184.50.195, Port 22
2026-11-09 16:00:18,780 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-11-09 16:00:18,869 - DEBUG - VM ctest-pt_svm0-79436715 is NOT ready for SSH connections, VM status: ACTIVE
2026-11-09 16:00:23,869 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:23,869 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-79436715, IP 168.184.50.195, Port 22
2026-11-09 16:00:23,936 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-11-09 16:00:24,026 - DEBUG - VM ctest-pt_svm0-79436715 is NOT ready for SSH connections, VM status: ACTIVE
2026-11-09 16:00:29,026 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:29,026 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-79436715, IP 168.184.50.195, Port 22
2026-11-09 16:00:29,093 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-11-09 16:00:29,203 - DEBUG - VM ctest-pt_svm0-79436715 is NOT ready for SSH connections, VM status: ACTIVE
2026-11-09 16:00:34,204 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:34,204 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-79436715, IP 168.184.50.195, Port 22
2026-11-09 16:00:34,382 - DEBUG - VM ctest-pt_svm0-79436715 is ready for SSH connections
2026-11-09 16:00:34,538 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:34,539 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-79436715, IP 168.184.50.195, Port 22
2026-11-09 16:00:34,704 - DEBUG - VM ctest-pt_svm0-79436715 is ready for SSH connections
2026-11-09 16:00:34,854 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:34,855 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-79436715, IP 168.184.50.195, Port 22
2026-11-09 16:00:35,010 - DEBUG - VM ctest-pt_svm0-79436715 is ready for SSH connections
2026-11-09 16:00:35,016 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-si-61110877']
2026-11-09 16:00:35,918 - INFO - Waiting for VM ctest-left_vm-98330980 to be up..
2026-11-09 16:00:36,088 - DEBUG - VM is in ACTIVE state now
2026-11-09 16:00:36,089 - INFO - VM name : ctest-left_vm-98330980
2026-11-09 16:00:36,185 - DEBUG - VM ctest-left_vm-98330980 ID is d0bab4b1-0576-4899-8f8d-b072b8ceffde
2026-11-09 16:00:36,185 - DEBUG - VM ctest-left_vm-98330980 launched on Node cn-jenkins-deploy-platform-ansible-os-6501-1
2026-11-09 16:00:36,271 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/d0bab4b1-0576-4899-8f8d-b072b8ceffde
2026-11-09 16:00:36,278 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/3b45abbf-8266-45b5-8f71-ba1f387608b4
2026-11-09 16:00:37,508 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.89 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.460 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.460/2.175/3.891/1.715 ms')
2026-11-09 16:00:37,509 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-98330980 passed
2026-11-09 16:00:37,658 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:37,659 - DEBUG - Waiting to SSH to VM ctest-left_vm-98330980, IP 65.122.242.67, Port 22
2026-11-09 16:00:37,815 - DEBUG - VM ctest-left_vm-98330980 is ready for SSH connections
2026-11-09 16:00:37,815 - INFO - Waiting for VM ctest-right_vm-42834492 to be up..
2026-11-09 16:00:37,893 - DEBUG - VM is in ACTIVE state now
2026-11-09 16:00:37,893 - INFO - VM name : ctest-right_vm-42834492
2026-11-09 16:00:37,969 - DEBUG - VM ctest-right_vm-42834492 ID is ab5f67fc-6ee6-479f-b165-965f51ad7eca
2026-11-09 16:00:37,969 - DEBUG - VM ctest-right_vm-42834492 launched on Node cn-jenkins-deploy-platform-ansible-os-6501-1
2026-11-09 16:00:38,048 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/ab5f67fc-6ee6-479f-b165-965f51ad7eca
2026-11-09 16:00:38,056 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/38516ca3-848e-4143-87c0-ef3bd1c4ba11
2026-11-09 16:00:39,286 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.63 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.413 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.413/2.021/3.630/1.608 ms')
2026-11-09 16:00:39,287 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-42834492 passed
2026-11-09 16:00:39,442 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:39,442 - DEBUG - Waiting to SSH to VM ctest-right_vm-42834492, IP 60.165.232.67, Port 22
2026-11-09 16:00:39,598 - DEBUG - VM ctest-right_vm-42834492 is ready for SSH connections
2026-11-09 16:00:39,605 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 65535], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = 65535], action_list = simple_action = pass, gateway_name = None, apply_service = ['default-domain:ctest-TestSvcRegr-29115857:ctest-si-61110877'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2026-11-09 16:00:40,039 - INFO - Attaching policy ctest-policy-60667146 to vn ctest-left_vn-77947792
2026-11-09 16:00:40,594 - INFO - Attaching policy ctest-policy-60667146 to vn ctest-right_vn-33867611
2026-11-09 16:00:41,030 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-21561851'] created succesfully
2026-11-09 16:00:41,262 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-si-61110877'] created succesfully
2026-11-09 16:00:41,263 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:41,268 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:41,279 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:41,351 - DEBUG - Requesting: http://10.0.0.163:8082/service-instance/13b0270b-7250-4e54-b9cf-d75c39e7264f
2026-11-09 16:00:41,362 - DEBUG - SI 'ctest-si-61110877' has service template ref to ctest-service_template_1-21561851
2026-11-09 16:00:41,362 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:41,367 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:41,384 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:41,472 - DEBUG - Requesting: http://10.0.0.163:8082/service-instance/13b0270b-7250-4e54-b9cf-d75c39e7264f
2026-11-09 16:00:41,481 - DEBUG - Requesting: http://10.0.0.163:8082/port-tuple/8f285730-d497-430a-b589-f4c2d51d39ed
2026-11-09 16:00:41,491 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/3d5e8ff2-6609-4298-95cb-fa53ed42a64f
2026-11-09 16:00:41,501 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/5bd5ca74-b676-4936-8dea-2552f9263344
2026-11-09 16:00:41,514 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/bbf6c835-42dd-4325-b828-8b0789ac9f99
2026-11-09 16:00:41,744 - DEBUG - VM is in ACTIVE state now
2026-11-09 16:00:42,162 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34
2026-11-09 16:00:42,170 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/3d5e8ff2-6609-4298-95cb-fa53ed42a64f
2026-11-09 16:00:42,179 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/5bd5ca74-b676-4936-8dea-2552f9263344
2026-11-09 16:00:42,189 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/bbf6c835-42dd-4325-b828-8b0789ac9f99
2026-11-09 16:00:42,198 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/170f6d9c-b817-436e-8eba-977b94ca552f
2026-11-09 16:00:42,205 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/89a9c123-b543-4a93-a38d-a6e10421fede
2026-11-09 16:00:42,211 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/c1a6ea65-397e-4d7a-a56b-8e574a4a9b51
2026-11-09 16:00:42,220 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/105cb824-5385-4ec9-821b-c37f37dfb458
2026-11-09 16:00:42,228 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/764325b0-240a-473f-955b-01fe3734fcee
2026-11-09 16:00:42,236 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/ec282670-8e18-46da-94e4-89d1bb6ad7d0
2026-11-09 16:00:42,245 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/6f077dcf-a40b-4b70-a516-cbeb5bcc1e81
2026-11-09 16:00:42,252 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/7e4b89d0-5a45-453b-88a3-b3b72d4f603d
2026-11-09 16:00:42,259 - DEBUG - Requesting: http://10.0.0.163:8082/instance-ip/c7d2649b-b2d9-4f71-8627-f9df1d62467a
2026-11-09 16:00:42,646 - DEBUG - VM is in ACTIVE state now
2026-11-09 16:00:42,646 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34
2026-11-09 16:00:42,653 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/3d5e8ff2-6609-4298-95cb-fa53ed42a64f
2026-11-09 16:00:42,661 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/5bd5ca74-b676-4936-8dea-2552f9263344
2026-11-09 16:00:42,670 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine-interface/bbf6c835-42dd-4325-b828-8b0789ac9f99
2026-11-09 16:00:43,912 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.43 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.620 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.620/1.022/1.425/0.402 ms')
2026-11-09 16:00:43,913 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-79436715 passed
2026-11-09 16:00:43,951 - DEBUG - VM ctest-pt_svm0-79436715 Tap interface: {'index': '6', 'name': 'tapbbf6c835-42', 'uuid': 'bbf6c835-42dd-4325-b828-8b0789ac9f99', 'vrf_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792:ctest-left_vn-77947792', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '44', 'l2_label': '48', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792', 'vm_uuid': 'ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34', 'vm_name': 'ctest-pt_svm0-79436715', 'ip_addr': '65.122.242.68', 'mac_addr': '02:bb:f6:c8:35:42', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-29115857:bbf6c835-42dd-4325-b828-8b0789ac9f99', 'sg_uuid_list': ['6e75e0e1-4bbe-4f51-bffe-bec3d457a611'], 'static_route_list': None, 'vm_project_uuid': '500be285-2d86-491b-b312-1b8540c495e1', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '3d5e8ff2-6609-4298-95cb-fa53ed42a64f', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-11-09 16:00:43,951 - DEBUG - Agent 10.0.0.163 vrf name: default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792:ctest-left_vn-77947792
2026-11-09 16:00:43,957 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792:ctest-left_vn-77947792', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.17:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-11-09 16:00:44,119 - DEBUG - VM ctest-pt_svm0-79436715 : Tap interface tapbbf6c835-42 is set to Active
2026-11-09 16:00:44,120 - DEBUG - Tap interface tapbbf6c835-42 detail : {'index': '6', 'name': 'tapbbf6c835-42', 'uuid': 'bbf6c835-42dd-4325-b828-8b0789ac9f99', 'vrf_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792:ctest-left_vn-77947792', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '44', 'l2_label': '48', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792', 'vm_uuid': 'ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34', 'vm_name': 'ctest-pt_svm0-79436715', 'ip_addr': '65.122.242.68', 'mac_addr': '02:bb:f6:c8:35:42', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-29115857:bbf6c835-42dd-4325-b828-8b0789ac9f99', 'sg_uuid_list': ['6e75e0e1-4bbe-4f51-bffe-bec3d457a611'], 'static_route_list': None, 'vm_project_uuid': '500be285-2d86-491b-b312-1b8540c495e1', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '3d5e8ff2-6609-4298-95cb-fa53ed42a64f', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-11-09 16:00:44,315 - DEBUG - Starting Layer 2 verification in Agent
2026-11-09 16:00:44,329 - DEBUG - Layer 2 path is seen for VM MAC 02:bb:f6:c8:35:42 in agent 10.0.0.163
2026-11-09 16:00:44,329 - DEBUG - Active layer 2 route in agent is present for VMI tapbbf6c835-42
2026-11-09 16:00:44,329 - DEBUG - L2 label(48) matches bw route table and interface table
2026-11-09 16:00:44,336 - DEBUG - VN default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792 is found in Agent of node 10.0.0.163
2026-11-09 16:00:44,546 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792 is consistent in agent 10.0.0.163
2026-11-09 16:00:44,546 - DEBUG - Route for VM IP ['65.122.242.68'] is consistent in agent 10.0.0.163
2026-11-09 16:00:44,546 - DEBUG - VN default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792 verification for VM ctest-pt_svm0-79436715 in Agent 10.0.0.163 passed
2026-11-09 16:00:44,738 - DEBUG - Starting all layer 2 verification in agent 10.0.0.163
2026-11-09 16:00:44,750 - DEBUG - Route for VM MAC 02:bb:f6:c8:35:42 is consistent in agent 10.0.0.163
2026-11-09 16:00:44,769 - DEBUG - VM ctest-pt_svm0-79436715 Tap interface: {'index': '5', 'name': 'tap5bd5ca74-b6', 'uuid': '5bd5ca74-b676-4936-8dea-2552f9263344', 'vrf_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767:ctest-mgmt_vn-96352767', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '39', 'l2_label': '43', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767', 'vm_uuid': 'ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34', 'vm_name': 'ctest-pt_svm0-79436715', 'ip_addr': '168.184.50.195', 'mac_addr': '02:5b:d5:ca:74:b6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-29115857:5bd5ca74-b676-4936-8dea-2552f9263344', 'sg_uuid_list': ['6e75e0e1-4bbe-4f51-bffe-bec3d457a611'], 'static_route_list': None, 'vm_project_uuid': '500be285-2d86-491b-b312-1b8540c495e1', 'admin_state': 'Enabled', 'flow_key_idx': '26', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-11-09 16:00:44,769 - DEBUG - Agent 10.0.0.163 vrf name: default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767:ctest-mgmt_vn-96352767
2026-11-09 16:00:44,775 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767:ctest-mgmt_vn-96352767', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.17:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-11-09 16:00:44,984 - DEBUG - VM ctest-pt_svm0-79436715 : Tap interface tap5bd5ca74-b6 is set to Active
2026-11-09 16:00:44,984 - DEBUG - Tap interface tap5bd5ca74-b6 detail : {'index': '5', 'name': 'tap5bd5ca74-b6', 'uuid': '5bd5ca74-b676-4936-8dea-2552f9263344', 'vrf_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767:ctest-mgmt_vn-96352767', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '39', 'l2_label': '43', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767', 'vm_uuid': 'ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34', 'vm_name': 'ctest-pt_svm0-79436715', 'ip_addr': '168.184.50.195', 'mac_addr': '02:5b:d5:ca:74:b6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-29115857:5bd5ca74-b676-4936-8dea-2552f9263344', 'sg_uuid_list': ['6e75e0e1-4bbe-4f51-bffe-bec3d457a611'], 'static_route_list': None, 'vm_project_uuid': '500be285-2d86-491b-b312-1b8540c495e1', 'admin_state': 'Enabled', 'flow_key_idx': '26', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-11-09 16:00:45,178 - DEBUG - Starting Layer 2 verification in Agent
2026-11-09 16:00:45,189 - DEBUG - Layer 2 path is seen for VM MAC 02:5b:d5:ca:74:b6 in agent 10.0.0.163
2026-11-09 16:00:45,189 - DEBUG - Active layer 2 route in agent is present for VMI tap5bd5ca74-b6
2026-11-09 16:00:45,189 - DEBUG - L2 label(43) matches bw route table and interface table
2026-11-09 16:00:45,194 - DEBUG - VN default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 is found in Agent of node 10.0.0.163
2026-11-09 16:00:45,408 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 is consistent in agent 10.0.0.163
2026-11-09 16:00:45,408 - DEBUG - Route for VM IP ['168.184.50.195'] is consistent in agent 10.0.0.163
2026-11-09 16:00:45,408 - DEBUG - VN default-domain:ctest-TestSvcRegr-29115857:ctest-mgmt_vn-96352767 verification for VM ctest-pt_svm0-79436715 in Agent 10.0.0.163 passed
2026-11-09 16:00:45,610 - DEBUG - Starting all layer 2 verification in agent 10.0.0.163
2026-11-09 16:00:45,620 - DEBUG - Route for VM MAC 02:5b:d5:ca:74:b6 is consistent in agent 10.0.0.163
2026-11-09 16:00:45,640 - DEBUG - VM ctest-pt_svm0-79436715 Tap interface: {'index': '7', 'name': 'tap3d5e8ff2-66', 'uuid': '3d5e8ff2-6609-4298-95cb-fa53ed42a64f', 'vrf_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611:ctest-right_vn-33867611', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '49', 'l2_label': '53', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611', 'vm_uuid': 'ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34', 'vm_name': 'ctest-pt_svm0-79436715', 'ip_addr': '60.165.232.68', 'mac_addr': '02:3d:5e:8f:f2:66', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-29115857:3d5e8ff2-6609-4298-95cb-fa53ed42a64f', 'sg_uuid_list': ['6e75e0e1-4bbe-4f51-bffe-bec3d457a611'], 'static_route_list': None, 'vm_project_uuid': '500be285-2d86-491b-b312-1b8540c495e1', 'admin_state': 'Enabled', 'flow_key_idx': '36', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': 'bbf6c835-42dd-4325-b828-8b0789ac9f99', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-11-09 16:00:45,640 - DEBUG - Agent 10.0.0.163 vrf name: default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611:ctest-right_vn-33867611
2026-11-09 16:00:45,645 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611:ctest-right_vn-33867611', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.17:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-11-09 16:00:45,850 - DEBUG - VM ctest-pt_svm0-79436715 : Tap interface tap3d5e8ff2-66 is set to Active
2026-11-09 16:00:45,850 - DEBUG - Tap interface tap3d5e8ff2-66 detail : {'index': '7', 'name': 'tap3d5e8ff2-66', 'uuid': '3d5e8ff2-6609-4298-95cb-fa53ed42a64f', 'vrf_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611:ctest-right_vn-33867611', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '49', 'l2_label': '53', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611', 'vm_uuid': 'ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34', 'vm_name': 'ctest-pt_svm0-79436715', 'ip_addr': '60.165.232.68', 'mac_addr': '02:3d:5e:8f:f2:66', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-29115857:3d5e8ff2-6609-4298-95cb-fa53ed42a64f', 'sg_uuid_list': ['6e75e0e1-4bbe-4f51-bffe-bec3d457a611'], 'static_route_list': None, 'vm_project_uuid': '500be285-2d86-491b-b312-1b8540c495e1', 'admin_state': 'Enabled', 'flow_key_idx': '36', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': 'bbf6c835-42dd-4325-b828-8b0789ac9f99', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-11-09 16:00:46,046 - DEBUG - Starting Layer 2 verification in Agent
2026-11-09 16:00:46,058 - DEBUG - Layer 2 path is seen for VM MAC 02:3d:5e:8f:f2:66 in agent 10.0.0.163
2026-11-09 16:00:46,058 - DEBUG - Active layer 2 route in agent is present for VMI tap3d5e8ff2-66
2026-11-09 16:00:46,058 - DEBUG - L2 label(53) matches bw route table and interface table
2026-11-09 16:00:46,063 - DEBUG - VN default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611 is found in Agent of node 10.0.0.163
2026-11-09 16:00:46,271 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611 is consistent in agent 10.0.0.163
2026-11-09 16:00:46,272 - DEBUG - Route for VM IP ['60.165.232.68'] is consistent in agent 10.0.0.163
2026-11-09 16:00:46,272 - DEBUG - VN default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611 verification for VM ctest-pt_svm0-79436715 in Agent 10.0.0.163 passed
2026-11-09 16:00:46,467 - DEBUG - Starting all layer 2 verification in agent 10.0.0.163
2026-11-09 16:00:46,482 - DEBUG - Route for VM MAC 02:3d:5e:8f:f2:66 is consistent in agent 10.0.0.163
2026-11-09 16:00:46,482 - INFO - VM ctest-pt_svm0-79436715 verifications in Compute nodes passed
2026-11-09 16:00:46,483 - DEBUG - The SVMs in the SI are : []
2026-11-09 16:00:46,483 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34
2026-11-09 16:00:46,494 - DEBUG - Service VM for SI 'ctest-si-61110877' is launched
2026-11-09 16:00:46,494 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:46,502 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:46,516 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:46,593 - DEBUG - Requesting: http://10.0.0.163:8082/service-instance/13b0270b-7250-4e54-b9cf-d75c39e7264f
2026-11-09 16:00:46,602 - DEBUG - SI ctest-si-61110877 has Port Tuple: ['ctest-port_tuple-75949362']
2026-11-09 16:00:46,702 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-29115857:ctest-left_vn-77947792
2026-11-09 16:00:46,850 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-29115857:ctest-right_vn-33867611
2026-11-09 16:00:47,010 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,026 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,026 - INFO - Route to 60.165.232.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-6501-1, and SI ctest-si-61110877 is seen as the NH
2026-11-09 16:00:47,193 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,202 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,202 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,207 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,208 - INFO - Route to 65.122.242.67 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-6501-1, and SI ctest-si-61110877 is seen as the NH
2026-11-09 16:00:47,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-09 16:00:47,208 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 60.165.232.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.163, gateway password: c0ntrail123
2026-11-09 16:00:47,208 - DEBUG - ping -s 56 -c 3 -W 1 60.165.232.67
2026-11-09 16:00:51,165 - DEBUG - PING 60.165.232.67 (60.165.232.67): 56 data bytes
64 bytes from 60.165.232.67: seq=0 ttl=61 time=9.715 ms
64 bytes from 60.165.232.67: seq=1 ttl=61 time=1.924 ms
64 bytes from 60.165.232.67: seq=2 ttl=61 time=1.610 ms
--- 60.165.232.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.610/4.416/9.715 ms
2026-11-09 16:00:51,165 - INFO - Ping to IP 60.165.232.67 from VM ctest-left_vm-98330980 passed
2026-11-09 16:00:51,165 - INFO - Dettaching policy ctest-policy-60667146 from vn ctest-right_vn-33867611
2026-11-09 16:00:51,379 - INFO - Dettaching policy ctest-policy-60667146 from vn ctest-left_vn-77947792
2026-11-09 16:00:51,666 - INFO - Deleted policy ctest-policy-60667146
2026-11-09 16:00:51,666 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-si-61110877']
2026-11-09 16:00:51,761 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-29115857', 'ctest-si-61110877']
2026-11-09 16:00:51,762 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:51,774 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:51,789 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:51,859 - DEBUG - Service instance ctest-si-61110877 removed from api server
2026-11-09 16:00:51,859 - DEBUG - Requesting: http://10.0.0.163:8082/virtual-machine/ec3dfc4f-c5ba-4f06-afe7-49d1f5de3d34
2026-11-09 16:00:51,869 - DEBUG - All Service VMs unlinked from SI ctest-si-61110877
2026-11-09 16:00:51,873 - DEBUG - 0 svc intances found in all projects. They are []
2026-11-09 16:00:51,873 - DEBUG - 0 svc intances found in current project. They are []
2026-11-09 16:00:51,918 - DEBUG - 0 svc intances found in all projects. They are []
2026-11-09 16:00:51,918 - DEBUG - 0 svc intances found in current project. They are []
2026-11-09 16:00:51,918 - INFO - Deleting VM ctest-pt_svm0-79436715
2026-11-09 16:00:52,001 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-21561851']
2026-11-09 16:00:52,082 - INFO - Service template: ['default-domain', 'ctest-service_template_1-21561851'] deleted successfully.
2026-11-09 16:00:52,083 - INFO - Deleting VM ctest-right_vm-42834492
2026-11-09 16:00:52,164 - INFO - Deleting VM ctest-left_vm-98330980
2026-11-09 16:00:52,235 - INFO - Deleting VN ctest-right_vn-33867611
2026-11-09 16:00:52,270 - DEBUG - VN 5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d still in use: Unable to complete operation on network 5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-32898d4c-27f4-4a44-b6ff-3cf9b8997c81']
2026-11-09 16:00:52,270 - WARNING - Deleting VN ctest-right_vn-33867611 failed..Will retry
2026-11-09 16:00:54,305 - DEBUG - VN 5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d still in use: Unable to complete operation on network 5c7dd8ab-7de7-4a24-a67b-07a5cd93c98d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-345568b1-2cbe-4744-b006-dc5bdd9948af']
2026-11-09 16:00:54,305 - WARNING - Deleting VN ctest-right_vn-33867611 failed..Will retry
2026-11-09 16:00:56,421 - DEBUG - Response for deleting network ()
2026-11-09 16:00:56,421 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/81745dc9-e7a3-4f84-b63c-507e66a419e7
2026-11-09 16:00:56,428 - DEBUG - Response Code: 404
2026-11-09 16:00:56,428 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:56,432 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:56,489 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:56,561 - INFO - Validated that VN ctest-right_vn-33867611 is not found in API Server
2026-11-09 16:00:56,570 - DEBUG - VN ctest-right_vn-33867611 is not present in Agent 10.0.0.163
2026-11-09 16:00:56,570 - INFO - Validated that VN ctest-right_vn-33867611 is not in any agent
2026-11-09 16:00:56,587 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-33867611 info
2026-11-09 16:00:56,587 - INFO - Deleting VN ctest-left_vn-77947792
2026-11-09 16:00:56,709 - DEBUG - Response for deleting network ()
2026-11-09 16:00:56,709 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/79ae20a6-6632-4b75-8299-6a80244d9156
2026-11-09 16:00:56,720 - DEBUG - Response Code: 404
2026-11-09 16:00:56,720 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:56,726 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:56,780 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:56,866 - INFO - Validated that VN ctest-left_vn-77947792 is not found in API Server
2026-11-09 16:00:56,878 - DEBUG - VN ctest-left_vn-77947792 is not present in Agent 10.0.0.163
2026-11-09 16:00:56,878 - INFO - Validated that VN ctest-left_vn-77947792 is not in any agent
2026-11-09 16:00:56,895 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-77947792 info
2026-11-09 16:00:56,895 - INFO - Deleting VN ctest-mgmt_vn-96352767
2026-11-09 16:00:57,015 - DEBUG - Response for deleting network ()
2026-11-09 16:00:57,015 - DEBUG - Requesting: http://10.0.0.163:8082/routing-instance/fb18b8af-3dfd-4587-9415-82e026b77e76
2026-11-09 16:00:57,021 - DEBUG - Response Code: 404
2026-11-09 16:00:57,022 - DEBUG - Requesting: http://10.0.0.163:8082/domains
2026-11-09 16:00:57,027 - DEBUG - Requesting: http://10.0.0.163:8082/domain/004d1e4e-d25c-4f1d-b649-20b0db97c4c1
2026-11-09 16:00:57,080 - DEBUG - Requesting: http://10.0.0.163:8082/project/500be285-2d86-491b-b312-1b8540c495e1
2026-11-09 16:00:57,154 - INFO - Validated that VN ctest-mgmt_vn-96352767 is not found in API Server
2026-11-09 16:00:57,166 - DEBUG - VN ctest-mgmt_vn-96352767 is not present in Agent 10.0.0.163
2026-11-09 16:00:57,166 - INFO - Validated that VN ctest-mgmt_vn-96352767 is not in any agent
2026-11-09 16:00:57,186 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-96352767 info
2026-11-09 16:00:57,444 - DEBUG - No XMPP flaps were noticed during the test
2026-11-09 16:00:57,444 - INFO -
2026-11-09 16:00:57,444 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:59]
2026-11-09 16:00:57,444 - INFO - --------------------------------------------------------------------------------
2026-11-09 16:00:58,220 - INFO - Deleted project: ctest-TestSvcRegr-29115857, ID : 500be285-2d86-491b-b312-1b8540c495e1