]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1)
2025-09-04 03:08:30,045 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-69117049']
2025-09-04 03:08:30,379 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-69117049'] created succesfully
2025-09-04 03:08:30,755 - DEBUG - Image by name tiny_nat_fw not found
2025-09-04 03:08:30,755 - DEBUG - Installing image tiny_nat_fw
2025-09-04 03:08:30,755 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-09-04 03:08:30,755 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-04 03:08:30,755 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-04 03:08:30,773 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:08:30,839 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:08:30,839 - DEBUG - Unzip image
2025-09-04 03:08:30,839 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:08:30,875 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-04 03:08:30,876 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-69117049']
2025-09-04 03:08:30,984 - INFO - Service template: ['default-domain', 'ctest-service_template_1-69117049'] deleted successfully.
2025-09-04 03:08:30,984 - INFO - Skip interface_detach for VM ctest-right_vm-38202984 in state BUILD
2025-09-04 03:08:30,985 - INFO - Deleting VM ctest-right_vm-38202984
2025-09-04 03:08:31,011 - ERROR - VM ctest-right_vm-38202984 has failed to come up
2025-09-04 03:08:31,012 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:31,012 - ERROR - VM ctest-right_vm-38202984 has failed to come up
2025-09-04 03:08:31,012 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:31,098 - INFO - Skip interface_detach for VM ctest-left_vm-82358521 in state BUILD
2025-09-04 03:08:31,098 - INFO - Deleting VM ctest-left_vm-82358521
2025-09-04 03:08:31,098 - ERROR - VM ctest-left_vm-82358521 has failed to come up
2025-09-04 03:08:31,098 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:31,098 - ERROR - VM ctest-left_vm-82358521 has failed to come up
2025-09-04 03:08:31,098 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:31,193 - INFO - Deleting VN ctest-right_vn-04713803
2025-09-04 03:08:31,240 - DEBUG - VN 28d66e7c-ff03-4bb5-a128-d62e52b88045 still in use: Unable to complete operation on network 28d66e7c-ff03-4bb5-a128-d62e52b88045. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d3f342db-b61e-4e06-8a6a-f71e3ee51c67']
2025-09-04 03:08:31,240 - WARNING - Deleting VN ctest-right_vn-04713803 failed..Will retry
2025-09-04 03:08:33,401 - DEBUG - Response for deleting network ()
2025-09-04 03:08:33,401 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/de15c85e-581b-4dea-809f-98554217f059
2025-09-04 03:08:33,408 - DEBUG - Response Code: 404
2025-09-04 03:08:33,408 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:33,426 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:33,481 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:33,586 - INFO - Validated that VN ctest-right_vn-04713803 is not found in API Server
2025-09-04 03:08:33,608 - DEBUG - VN ctest-right_vn-04713803 is not present in Agent 10.0.0.241
2025-09-04 03:08:33,618 - DEBUG - VN ctest-right_vn-04713803 is not present in Agent 10.0.0.242
2025-09-04 03:08:33,618 - INFO - Validated that VN ctest-right_vn-04713803 is not in any agent
2025-09-04 03:08:33,649 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-04713803 info
2025-09-04 03:08:33,650 - INFO - Deleting VN ctest-left_vn-09063904
2025-09-04 03:08:33,812 - DEBUG - Response for deleting network ()
2025-09-04 03:08:33,812 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a26354d4-c326-4126-b1fd-68fff6ec09d6
2025-09-04 03:08:33,824 - DEBUG - Response Code: 404
2025-09-04 03:08:33,824 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:33,833 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:33,888 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:33,993 - INFO - Validated that VN ctest-left_vn-09063904 is not found in API Server
2025-09-04 03:08:34,006 - DEBUG - VN ctest-left_vn-09063904 is not present in Agent 10.0.0.241
2025-09-04 03:08:34,016 - DEBUG - VN ctest-left_vn-09063904 is not present in Agent 10.0.0.242
2025-09-04 03:08:34,016 - INFO - Validated that VN ctest-left_vn-09063904 is not in any agent
2025-09-04 03:08:34,051 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-09063904 info
2025-09-04 03:08:34,051 - INFO - Deleting VN ctest-mgmt_vn-63499794
2025-09-04 03:08:34,206 - DEBUG - Response for deleting network ()
2025-09-04 03:08:34,206 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7f4fc1d1-b037-4d8d-b414-95b3659d50ae
2025-09-04 03:08:34,213 - DEBUG - Response Code: 404
2025-09-04 03:08:34,213 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:34,219 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:34,232 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:34,354 - INFO - Validated that VN ctest-mgmt_vn-63499794 is not found in API Server
2025-09-04 03:08:34,368 - DEBUG - VN ctest-mgmt_vn-63499794 is not present in Agent 10.0.0.241
2025-09-04 03:08:34,378 - DEBUG - VN ctest-mgmt_vn-63499794 is not present in Agent 10.0.0.242
2025-09-04 03:08:34,378 - INFO - Validated that VN ctest-mgmt_vn-63499794 is not in any agent
2025-09-04 03:08:34,419 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-63499794 info
2025-09-04 03:08:34,419 - INFO - Deleting VM ctest-vm-in-third-vn-35552942
2025-09-04 03:08:34,497 - INFO - Deleting VN ctest-third-vn-98642019
2025-09-04 03:08:34,546 - DEBUG - VN 71659938-5ccd-47cd-b9f3-15e83a44d38f still in use: Unable to complete operation on network 71659938-5ccd-47cd-b9f3-15e83a44d38f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7bb867fd-ae32-4024-8f12-e40de41c10ab']
2025-09-04 03:08:34,546 - WARNING - Deleting VN ctest-third-vn-98642019 failed..Will retry
2025-09-04 03:08:36,716 - DEBUG - Response for deleting network ()
2025-09-04 03:08:37,987 - DEBUG - No XMPP flaps were noticed during the test
2025-09-04 03:08:37,987 - INFO -
2025-09-04 03:08:37,987 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-09-04 03:08:37,987 - INFO - --------------------------------------------------------------------------------
2025-09-04 03:08:37,990 - INFO - ================================================================================
2025-09-04 03:08:37,990 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-09-04 03:08:39,292 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.25': '0', '10.20.0.254': '0'}, '10.0.0.242': {'10.20.0.25': '0', '10.20.0.254': '0'}} with
2025-09-04 03:08:39,292 - INFO - Initial checks done. Running the testcase now
2025-09-04 03:08:39,292 - INFO -
2025-09-04 03:08:39,568 - DEBUG - Response for create_network : {'network': {'id': 'd9514288-d3f9-4870-9ac8-669ef863e8cd', 'name': 'ctest-mgmt_vn-19668282', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'project_id': '7269c3f68b7549f1be774b4683627ed6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68561518', 'ctest-mgmt_vn-19668282'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:08:39,865 - DEBUG - Response for create_subnet : {'subnet': {'id': '909f0f94-8206-426e-8e2d-747f4cca993e', 'name': '', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'network_id': 'd9514288-d3f9-4870-9ac8-669ef863e8cd', 'ip_version': 4, 'cidr': '29.67.240.128/26', 'allocation_pools': [{'start': '29.67.240.130', 'end': '29.67.240.190'}], 'gateway_ip': '29.67.240.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.67.240.130', 'tags': [], 'project_id': '7269c3f68b7549f1be774b4683627ed6'}}
2025-09-04 03:08:39,888 - INFO - Created VN ctest-mgmt_vn-19668282
2025-09-04 03:08:39,904 - DEBUG - VN ctest-mgmt_vn-19668282 UUID is d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:39,977 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:39,984 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:39,996 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:40,120 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:40,142 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:40,157 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/490c91a4-6706-4953-8fe7-5097b7117b16
2025-09-04 03:08:40,168 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/490c91a4-6706-4953-8fe7-5097b7117b16
2025-09-04 03:08:40,177 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/320ddd27-fd23-4fc4-b801-7e300989e4cf
2025-09-04 03:08:40,185 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:40,185 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:40,196 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/490c91a4-6706-4953-8fe7-5097b7117b16
2025-09-04 03:08:40,214 - INFO - Verified VN network id 13 for VN d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:40,214 - INFO - Verifications in API Server for VN ctest-mgmt_vn-19668282 passed
2025-09-04 03:08:40,214 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:40,224 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/490c91a4-6706-4953-8fe7-5097b7117b16
2025-09-04 03:08:40,232 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/490c91a4-6706-4953-8fe7-5097b7117b16
2025-09-04 03:08:40,239 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/320ddd27-fd23-4fc4-b801-7e300989e4cf
2025-09-04 03:08:40,252 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15659370534783240304', 'uuid-lslong': '11153277310141196493'}, 'enable': 'true', 'created': '2025-09-04T03:08:39', 'last-modified': '2025-09-04T03:08:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.472472'}
2025-09-04 03:08:40,258 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:40,263 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15659370534783240304', 'uuid-lslong': '11153277310141196493'}, 'enable': 'true', 'created': '2025-09-04T03:08:39', 'last-modified': '2025-09-04T03:08:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484718'}
2025-09-04 03:08:40,268 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:40,274 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15659370534783240304', 'uuid-lslong': '11153277310141196493'}, 'enable': 'true', 'created': '2025-09-04T03:08:39', 'last-modified': '2025-09-04T03:08:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493089'}
2025-09-04 03:08:40,279 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:40,279 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-19668282 passed
2025-09-04 03:08:40,280 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-19668282 in API_Server ======
2025-09-04 03:08:40,280 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:40,286 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:40,300 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:40,402 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d9514288-d3f9-4870-9ac8-669ef863e8cd
2025-09-04 03:08:40,412 - DEBUG - =>VN ctest-mgmt_vn-19668282 has no policy to be verified
2025-09-04 03:08:40,412 - DEBUG - Verifying the vn in opserver
2025-09-04 03:08:40,412 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282 virtual network link through opserver 10.0.0.57
2025-09-04 03:08:40,412 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:08:40,425 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282?flat'}
2025-09-04 03:08:40,425 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282 virtual network link through opserver 10.0.0.38
2025-09-04 03:08:40,425 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:08:40,437 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282?flat'}
2025-09-04 03:08:40,437 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282 virtual network link through opserver 10.0.0.131
2025-09-04 03:08:40,437 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:08:40,451 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282?flat'}
2025-09-04 03:08:40,451 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68561518:ctest-mgmt_vn-19668282 is found in opserver
2025-09-04 03:08:40,469 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:08:40,478 - DEBUG - VRF ids for VN ctest-mgmt_vn-19668282: {}
2025-09-04 03:08:40,755 - DEBUG - Response for create_network : {'network': {'id': 'e19d6e93-74e5-4368-9462-b251b05a971f', 'name': 'ctest-left_vn-87738769', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'project_id': '7269c3f68b7549f1be774b4683627ed6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68561518', 'ctest-left_vn-87738769'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:08:40,982 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c8b5a6e0-6e9e-48d9-b445-4c00e9c14c1e', 'name': '', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'network_id': 'e19d6e93-74e5-4368-9462-b251b05a971f', 'ip_version': 4, 'cidr': '216.124.0.128/26', 'allocation_pools': [{'start': '216.124.0.130', 'end': '216.124.0.190'}], 'gateway_ip': '216.124.0.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.124.0.130', 'tags': [], 'project_id': '7269c3f68b7549f1be774b4683627ed6'}}
2025-09-04 03:08:41,001 - INFO - Created VN ctest-left_vn-87738769
2025-09-04 03:08:41,012 - DEBUG - VN ctest-left_vn-87738769 UUID is e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,085 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:41,092 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:41,103 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:41,189 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,203 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,213 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/df18e64b-66cc-4762-8cbc-4c033d4552fe
2025-09-04 03:08:41,221 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/df18e64b-66cc-4762-8cbc-4c033d4552fe
2025-09-04 03:08:41,229 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/43bc4a27-bc0d-4ab3-929e-3e51562078d8
2025-09-04 03:08:41,236 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:08:41,236 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,245 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/df18e64b-66cc-4762-8cbc-4c033d4552fe
2025-09-04 03:08:41,261 - INFO - Verified VN network id 14 for VN e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,261 - INFO - Verifications in API Server for VN ctest-left_vn-87738769 passed
2025-09-04 03:08:41,261 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,269 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/df18e64b-66cc-4762-8cbc-4c033d4552fe
2025-09-04 03:08:41,277 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/df18e64b-66cc-4762-8cbc-4c033d4552fe
2025-09-04 03:08:41,283 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/43bc4a27-bc0d-4ab3-929e-3e51562078d8
2025-09-04 03:08:41,295 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16257271809477788520', 'uuid-lslong': '10692304529251800863'}, 'enable': 'true', 'created': '2025-09-04T03:08:40', 'last-modified': '2025-09-04T03:08:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370400'}
2025-09-04 03:08:41,303 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:08:41,310 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16257271809477788520', 'uuid-lslong': '10692304529251800863'}, 'enable': 'true', 'created': '2025-09-04T03:08:40', 'last-modified': '2025-09-04T03:08:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384353'}
2025-09-04 03:08:41,316 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:08:41,321 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16257271809477788520', 'uuid-lslong': '10692304529251800863'}, 'enable': 'true', 'created': '2025-09-04T03:08:40', 'last-modified': '2025-09-04T03:08:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399011'}
2025-09-04 03:08:41,327 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:08:41,328 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-87738769 passed
2025-09-04 03:08:41,328 - DEBUG - ====Verifying policy data for ctest-left_vn-87738769 in API_Server ======
2025-09-04 03:08:41,328 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:41,334 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:41,346 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:41,448 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e19d6e93-74e5-4368-9462-b251b05a971f
2025-09-04 03:08:41,460 - DEBUG - =>VN ctest-left_vn-87738769 has no policy to be verified
2025-09-04 03:08:41,460 - DEBUG - Verifying the vn in opserver
2025-09-04 03:08:41,460 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769 virtual network link through opserver 10.0.0.57
2025-09-04 03:08:41,460 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:08:41,473 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769?flat'}
2025-09-04 03:08:41,473 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769 virtual network link through opserver 10.0.0.38
2025-09-04 03:08:41,473 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:08:41,486 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769?flat'}
2025-09-04 03:08:41,486 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769 virtual network link through opserver 10.0.0.131
2025-09-04 03:08:41,486 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:08:41,498 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769?flat'}
2025-09-04 03:08:41,498 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68561518:ctest-left_vn-87738769 is found in opserver
2025-09-04 03:08:41,518 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:08:41,527 - DEBUG - VRF ids for VN ctest-left_vn-87738769: {}
2025-09-04 03:08:41,788 - DEBUG - Response for create_network : {'network': {'id': '0eec71c4-7b73-4acf-9a6e-66c3209a8e7b', 'name': 'ctest-right_vn-47352261', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'project_id': '7269c3f68b7549f1be774b4683627ed6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68561518', 'ctest-right_vn-47352261'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:08:42,027 - DEBUG - Response for create_subnet : {'subnet': {'id': '950809b7-30a2-473c-ac5c-8955ef48b735', 'name': '', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'network_id': '0eec71c4-7b73-4acf-9a6e-66c3209a8e7b', 'ip_version': 4, 'cidr': '74.216.216.128/26', 'allocation_pools': [{'start': '74.216.216.130', 'end': '74.216.216.190'}], 'gateway_ip': '74.216.216.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.216.216.130', 'tags': [], 'project_id': '7269c3f68b7549f1be774b4683627ed6'}}
2025-09-04 03:08:42,051 - INFO - Created VN ctest-right_vn-47352261
2025-09-04 03:08:42,062 - DEBUG - VN ctest-right_vn-47352261 UUID is 0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,140 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:42,146 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:42,158 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:42,258 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,271 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,282 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/dd88676b-e4bb-4038-8cbe-e5e4b402c5f4
2025-09-04 03:08:42,295 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/dd88676b-e4bb-4038-8cbe-e5e4b402c5f4
2025-09-04 03:08:42,305 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/a19633f6-1427-48ad-b6bd-e6343ab426f2
2025-09-04 03:08:42,320 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:08:42,320 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,330 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/dd88676b-e4bb-4038-8cbe-e5e4b402c5f4
2025-09-04 03:08:42,350 - INFO - Verified VN network id 15 for VN 0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,350 - INFO - Verifications in API Server for VN ctest-right_vn-47352261 passed
2025-09-04 03:08:42,351 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,365 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/dd88676b-e4bb-4038-8cbe-e5e4b402c5f4
2025-09-04 03:08:42,377 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/dd88676b-e4bb-4038-8cbe-e5e4b402c5f4
2025-09-04 03:08:42,385 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/a19633f6-1427-48ad-b6bd-e6343ab426f2
2025-09-04 03:08:42,401 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1075359499733387983', 'uuid-lslong': '11127944717530730107'}, 'enable': 'true', 'created': '2025-09-04T03:08:41', 'last-modified': '2025-09-04T03:08:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435417'}
2025-09-04 03:08:42,407 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:08:42,415 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1075359499733387983', 'uuid-lslong': '11127944717530730107'}, 'enable': 'true', 'created': '2025-09-04T03:08:41', 'last-modified': '2025-09-04T03:08:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435957'}
2025-09-04 03:08:42,424 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:08:42,432 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1075359499733387983', 'uuid-lslong': '11127944717530730107'}, 'enable': 'true', 'created': '2025-09-04T03:08:41', 'last-modified': '2025-09-04T03:08:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.460570'}
2025-09-04 03:08:42,438 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:08:42,438 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-47352261 passed
2025-09-04 03:08:42,439 - DEBUG - ====Verifying policy data for ctest-right_vn-47352261 in API_Server ======
2025-09-04 03:08:42,439 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:42,447 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:42,461 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:42,571 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/0eec71c4-7b73-4acf-9a6e-66c3209a8e7b
2025-09-04 03:08:42,583 - DEBUG - =>VN ctest-right_vn-47352261 has no policy to be verified
2025-09-04 03:08:42,583 - DEBUG - Verifying the vn in opserver
2025-09-04 03:08:42,583 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261 virtual network link through opserver 10.0.0.57
2025-09-04 03:08:42,583 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:08:42,598 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261?flat'}
2025-09-04 03:08:42,599 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261 virtual network link through opserver 10.0.0.38
2025-09-04 03:08:42,599 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:08:42,614 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261?flat'}
2025-09-04 03:08:42,614 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261 virtual network link through opserver 10.0.0.131
2025-09-04 03:08:42,614 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:08:42,629 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261?flat'}
2025-09-04 03:08:42,629 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68561518:ctest-right_vn-47352261 is found in opserver
2025-09-04 03:08:42,654 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:08:42,666 - DEBUG - VRF ids for VN ctest-right_vn-47352261: {}
2025-09-04 03:08:42,939 - DEBUG - Response for create_network : {'network': {'id': '7f62806f-c92f-493f-aafc-0ecf6022397a', 'name': 'ctest-trans_left_vn-74398333', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'project_id': '7269c3f68b7549f1be774b4683627ed6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68561518', 'ctest-trans_left_vn-74398333'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:08:43,144 - DEBUG - Response for create_subnet : {'subnet': {'id': '9adc6d4b-e6d9-4e81-ba68-b7c133b8da15', 'name': '', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'network_id': '7f62806f-c92f-493f-aafc-0ecf6022397a', 'ip_version': 4, 'cidr': '86.136.10.64/26', 'allocation_pools': [{'start': '86.136.10.66', 'end': '86.136.10.126'}], 'gateway_ip': '86.136.10.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '86.136.10.66', 'tags': [], 'project_id': '7269c3f68b7549f1be774b4683627ed6'}}
2025-09-04 03:08:43,166 - INFO - Created VN ctest-trans_left_vn-74398333
2025-09-04 03:08:43,181 - DEBUG - VN ctest-trans_left_vn-74398333 UUID is 7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,251 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:43,258 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:43,268 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:43,363 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,377 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,392 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/84b7e361-8311-41d7-ac2a-0b82b6596809
2025-09-04 03:08:43,399 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/84b7e361-8311-41d7-ac2a-0b82b6596809
2025-09-04 03:08:43,407 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/2b9d930b-1932-48d5-aa77-8a84cce879ed
2025-09-04 03:08:43,416 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-04 03:08:43,416 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,425 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/84b7e361-8311-41d7-ac2a-0b82b6596809
2025-09-04 03:08:43,446 - INFO - Verified VN network id 16 for VN 7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,446 - INFO - Verifications in API Server for VN ctest-trans_left_vn-74398333 passed
2025-09-04 03:08:43,446 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,457 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/84b7e361-8311-41d7-ac2a-0b82b6596809
2025-09-04 03:08:43,465 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/84b7e361-8311-41d7-ac2a-0b82b6596809
2025-09-04 03:08:43,473 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/2b9d930b-1932-48d5-aa77-8a84cce879ed
2025-09-04 03:08:43,486 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9179040208139536703', 'uuid-lslong': '12320738964412709242'}, 'enable': 'true', 'created': '2025-09-04T03:08:42', 'last-modified': '2025-09-04T03:08:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399593'}
2025-09-04 03:08:43,491 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-04 03:08:43,497 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9179040208139536703', 'uuid-lslong': '12320738964412709242'}, 'enable': 'true', 'created': '2025-09-04T03:08:42', 'last-modified': '2025-09-04T03:08:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410699'}
2025-09-04 03:08:43,504 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-04 03:08:43,510 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9179040208139536703', 'uuid-lslong': '12320738964412709242'}, 'enable': 'true', 'created': '2025-09-04T03:08:42', 'last-modified': '2025-09-04T03:08:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425441'}
2025-09-04 03:08:43,517 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-04 03:08:43,517 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-74398333 passed
2025-09-04 03:08:43,517 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-74398333 in API_Server ======
2025-09-04 03:08:43,517 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:43,527 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:43,543 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:43,631 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7f62806f-c92f-493f-aafc-0ecf6022397a
2025-09-04 03:08:43,644 - DEBUG - =>VN ctest-trans_left_vn-74398333 has no policy to be verified
2025-09-04 03:08:43,644 - DEBUG - Verifying the vn in opserver
2025-09-04 03:08:43,644 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333 virtual network link through opserver 10.0.0.57
2025-09-04 03:08:43,644 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:08:43,657 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333?flat'}
2025-09-04 03:08:43,657 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333 virtual network link through opserver 10.0.0.38
2025-09-04 03:08:43,657 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:08:43,671 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333?flat'}
2025-09-04 03:08:43,671 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333 virtual network link through opserver 10.0.0.131
2025-09-04 03:08:43,671 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:08:43,685 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333?flat'}
2025-09-04 03:08:43,685 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68561518:ctest-trans_left_vn-74398333 is found in opserver
2025-09-04 03:08:43,707 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:08:43,717 - DEBUG - VRF ids for VN ctest-trans_left_vn-74398333: {}
2025-09-04 03:08:43,986 - DEBUG - Response for create_network : {'network': {'id': '427491b1-70f8-464e-8b9a-96643ed07f53', 'name': 'ctest-trans_right_vn-04355734', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'project_id': '7269c3f68b7549f1be774b4683627ed6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68561518', 'ctest-trans_right_vn-04355734'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:08:44,240 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd35b7959-dd35-4796-9789-219e3aa2bce6', 'name': '', 'tenant_id': '7269c3f68b7549f1be774b4683627ed6', 'network_id': '427491b1-70f8-464e-8b9a-96643ed07f53', 'ip_version': 4, 'cidr': '160.108.207.0/26', 'allocation_pools': [{'start': '160.108.207.2', 'end': '160.108.207.62'}], 'gateway_ip': '160.108.207.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.108.207.2', 'tags': [], 'project_id': '7269c3f68b7549f1be774b4683627ed6'}}
2025-09-04 03:08:44,262 - INFO - Created VN ctest-trans_right_vn-04355734
2025-09-04 03:08:44,275 - DEBUG - VN ctest-trans_right_vn-04355734 UUID is 427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,361 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:44,372 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:44,386 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:44,483 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,497 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,507 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/bee4ff29-4985-44b5-b686-35149a05bfde
2025-09-04 03:08:44,515 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/bee4ff29-4985-44b5-b686-35149a05bfde
2025-09-04 03:08:44,522 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/bfd67d83-ca01-4691-9f58-74053340205d
2025-09-04 03:08:44,528 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-04 03:08:44,528 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,537 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/bee4ff29-4985-44b5-b686-35149a05bfde
2025-09-04 03:08:44,550 - INFO - Verified VN network id 17 for VN 427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,550 - INFO - Verifications in API Server for VN ctest-trans_right_vn-04355734 passed
2025-09-04 03:08:44,550 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,558 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/bee4ff29-4985-44b5-b686-35149a05bfde
2025-09-04 03:08:44,565 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/bee4ff29-4985-44b5-b686-35149a05bfde
2025-09-04 03:08:44,572 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/bfd67d83-ca01-4691-9f58-74053340205d
2025-09-04 03:08:44,583 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4788612495092237902', 'uuid-lslong': '10059518074980171603'}, 'enable': 'true', 'created': '2025-09-04T03:08:43', 'last-modified': '2025-09-04T03:08:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.412227'}
2025-09-04 03:08:44,590 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-04 03:08:44,596 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4788612495092237902', 'uuid-lslong': '10059518074980171603'}, 'enable': 'true', 'created': '2025-09-04T03:08:43', 'last-modified': '2025-09-04T03:08:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426553'}
2025-09-04 03:08:44,601 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-04 03:08:44,606 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68561518', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4788612495092237902', 'uuid-lslong': '10059518074980171603'}, 'enable': 'true', 'created': '2025-09-04T03:08:43', 'last-modified': '2025-09-04T03:08:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441172'}
2025-09-04 03:08:44,612 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-04 03:08:44,612 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-04355734 passed
2025-09-04 03:08:44,612 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-04355734 in API_Server ======
2025-09-04 03:08:44,612 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:44,620 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:44,631 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:44,722 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/427491b1-70f8-464e-8b9a-96643ed07f53
2025-09-04 03:08:44,734 - DEBUG - =>VN ctest-trans_right_vn-04355734 has no policy to be verified
2025-09-04 03:08:44,734 - DEBUG - Verifying the vn in opserver
2025-09-04 03:08:44,734 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734 virtual network link through opserver 10.0.0.57
2025-09-04 03:08:44,734 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:08:44,747 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734?flat'}
2025-09-04 03:08:44,747 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734 virtual network link through opserver 10.0.0.38
2025-09-04 03:08:44,747 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:08:44,761 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734?flat'}
2025-09-04 03:08:44,761 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734 virtual network link through opserver 10.0.0.131
2025-09-04 03:08:44,761 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:08:44,774 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734?flat'}
2025-09-04 03:08:44,774 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68561518:ctest-trans_right_vn-04355734 is found in opserver
2025-09-04 03:08:44,795 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:08:44,806 - DEBUG - VRF ids for VN ctest-trans_right_vn-04355734: {}
2025-09-04 03:08:45,885 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1)
2025-09-04 03:08:47,318 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2)
2025-09-04 03:08:47,318 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-79268275']
2025-09-04 03:08:47,628 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-79268275'] created succesfully
2025-09-04 03:08:47,962 - DEBUG - Image by name tiny_trans_fw not found
2025-09-04 03:08:47,962 - DEBUG - Installing image tiny_trans_fw
2025-09-04 03:08:47,962 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-09-04 03:08:47,962 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-04 03:08:47,962 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-04 03:08:47,980 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-04 03:08:48,043 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-04 03:08:48,043 - DEBUG - Unzip image
2025-09-04 03:08:48,043 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-04 03:08:48,085 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-09-04 03:08:48,085 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-79268275']
2025-09-04 03:08:48,188 - INFO - Service template: ['default-domain', 'ctest-service_template_1-79268275'] deleted successfully.
2025-09-04 03:08:48,188 - INFO - Skip interface_detach for VM ctest-right_vm-70157179 in state BUILD
2025-09-04 03:08:48,189 - INFO - Deleting VM ctest-right_vm-70157179
2025-09-04 03:08:48,189 - ERROR - VM ctest-right_vm-70157179 has failed to come up
2025-09-04 03:08:48,189 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:48,189 - ERROR - VM ctest-right_vm-70157179 has failed to come up
2025-09-04 03:08:48,189 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:48,287 - INFO - Skip interface_detach for VM ctest-left_vm-57140203 in state BUILD
2025-09-04 03:08:48,287 - INFO - Deleting VM ctest-left_vm-57140203
2025-09-04 03:08:48,287 - ERROR - VM ctest-left_vm-57140203 has failed to come up
2025-09-04 03:08:48,287 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:48,287 - ERROR - VM ctest-left_vm-57140203 has failed to come up
2025-09-04 03:08:48,287 - ERROR - Nova failed to get host of the VM
2025-09-04 03:08:48,401 - INFO - Deleting VN ctest-trans_right_vn-04355734
2025-09-04 03:08:48,583 - DEBUG - Response for deleting network ()
2025-09-04 03:08:48,584 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/bee4ff29-4985-44b5-b686-35149a05bfde
2025-09-04 03:08:48,590 - DEBUG - Response Code: 404
2025-09-04 03:08:48,590 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:48,598 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:48,620 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:48,716 - INFO - Validated that VN ctest-trans_right_vn-04355734 is not found in API Server
2025-09-04 03:08:48,727 - DEBUG - VN ctest-trans_right_vn-04355734 is not present in Agent 10.0.0.241
2025-09-04 03:08:48,758 - DEBUG - VN ctest-trans_right_vn-04355734 is not present in Agent 10.0.0.242
2025-09-04 03:08:48,758 - INFO - Validated that VN ctest-trans_right_vn-04355734 is not in any agent
2025-09-04 03:08:48,803 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-04355734 info
2025-09-04 03:08:48,803 - INFO - Deleting VN ctest-trans_left_vn-74398333
2025-09-04 03:08:48,951 - DEBUG - Response for deleting network ()
2025-09-04 03:08:48,951 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/84b7e361-8311-41d7-ac2a-0b82b6596809
2025-09-04 03:08:48,959 - DEBUG - Response Code: 404
2025-09-04 03:08:48,959 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:48,978 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:48,995 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:49,093 - INFO - Validated that VN ctest-trans_left_vn-74398333 is not found in API Server
2025-09-04 03:08:49,107 - DEBUG - VN ctest-trans_left_vn-74398333 is not present in Agent 10.0.0.241
2025-09-04 03:08:49,124 - DEBUG - VN ctest-trans_left_vn-74398333 is not present in Agent 10.0.0.242
2025-09-04 03:08:49,124 - INFO - Validated that VN ctest-trans_left_vn-74398333 is not in any agent
2025-09-04 03:08:49,171 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-74398333 info
2025-09-04 03:08:49,171 - INFO - Deleting VN ctest-right_vn-47352261
2025-09-04 03:08:49,224 - DEBUG - VN 0eec71c4-7b73-4acf-9a6e-66c3209a8e7b still in use: Unable to complete operation on network 0eec71c4-7b73-4acf-9a6e-66c3209a8e7b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-635ffd47-087b-4d88-843c-560792abffb8']
2025-09-04 03:08:49,224 - WARNING - Deleting VN ctest-right_vn-47352261 failed..Will retry
2025-09-04 03:08:51,389 - DEBUG - Response for deleting network ()
2025-09-04 03:08:51,389 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/dd88676b-e4bb-4038-8cbe-e5e4b402c5f4
2025-09-04 03:08:51,396 - DEBUG - Response Code: 404
2025-09-04 03:08:51,397 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:51,402 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:51,420 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:51,512 - INFO - Validated that VN ctest-right_vn-47352261 is not found in API Server
2025-09-04 03:08:51,525 - DEBUG - VN ctest-right_vn-47352261 is not present in Agent 10.0.0.241
2025-09-04 03:08:51,535 - DEBUG - VN ctest-right_vn-47352261 is not present in Agent 10.0.0.242
2025-09-04 03:08:51,535 - INFO - Validated that VN ctest-right_vn-47352261 is not in any agent
2025-09-04 03:08:51,567 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-47352261 info
2025-09-04 03:08:51,567 - INFO - Deleting VN ctest-left_vn-87738769
2025-09-04 03:08:51,734 - DEBUG - Response for deleting network ()
2025-09-04 03:08:51,734 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/df18e64b-66cc-4762-8cbc-4c033d4552fe
2025-09-04 03:08:51,740 - DEBUG - Response Code: 404
2025-09-04 03:08:51,740 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:51,753 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:51,772 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:51,883 - INFO - Validated that VN ctest-left_vn-87738769 is not found in API Server
2025-09-04 03:08:51,894 - DEBUG - VN ctest-left_vn-87738769 is not present in Agent 10.0.0.241
2025-09-04 03:08:51,906 - DEBUG - VN ctest-left_vn-87738769 is not present in Agent 10.0.0.242
2025-09-04 03:08:51,906 - INFO - Validated that VN ctest-left_vn-87738769 is not in any agent
2025-09-04 03:08:51,937 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-87738769 info
2025-09-04 03:08:51,937 - INFO - Deleting VN ctest-mgmt_vn-19668282
2025-09-04 03:08:52,118 - DEBUG - Response for deleting network ()
2025-09-04 03:08:52,118 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/490c91a4-6706-4953-8fe7-5097b7117b16
2025-09-04 03:08:52,126 - DEBUG - Response Code: 404
2025-09-04 03:08:52,126 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:52,142 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:52,155 - DEBUG - Requesting: http://10.0.0.57:8082/project/7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:52,248 - INFO - Validated that VN ctest-mgmt_vn-19668282 is not found in API Server
2025-09-04 03:08:52,258 - DEBUG - VN ctest-mgmt_vn-19668282 is not present in Agent 10.0.0.241
2025-09-04 03:08:52,270 - DEBUG - VN ctest-mgmt_vn-19668282 is not present in Agent 10.0.0.242
2025-09-04 03:08:52,270 - INFO - Validated that VN ctest-mgmt_vn-19668282 is not in any agent
2025-09-04 03:08:52,302 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-19668282 info
2025-09-04 03:08:53,593 - DEBUG - No XMPP flaps were noticed during the test
2025-09-04 03:08:53,593 - INFO -
2025-09-04 03:08:53,593 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-09-04 03:08:53,593 - INFO - --------------------------------------------------------------------------------
2025-09-04 03:08:54,311 - INFO - Deleted project: ctest-TestSvcRegr-68561518, ID : 7269c3f6-8b75-49f1-be77-4b4683627ed6
2025-09-04 03:08:54,496 - INFO - Domain Default found not creating
2025-09-04 03:08:54,650 - INFO - Project ctest-TestSvcRegr-75810461 not found, creating it
2025-09-04 03:08:55,178 - INFO - Created Project:ctest-TestSvcRegr-75810461, ID : 9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:08:56,662 - INFO - ================================================================================
2025-09-04 03:08:56,662 - INFO - STARTING TEST : test_svc_CEM_22032
2025-09-04 03:08:57,933 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.25': '0', '10.20.0.254': '0'}, '10.0.0.242': {'10.20.0.25': '0', '10.20.0.254': '0'}} with
2025-09-04 03:08:57,933 - INFO - Initial checks done. Running the testcase now
2025-09-04 03:08:57,933 - INFO -
2025-09-04 03:08:58,615 - DEBUG - Response for create_network : {'network': {'id': '39cdf7c9-500d-4477-af82-bec52c38d092', 'name': 'ctest-mgmt_vn-54543361', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-75810461', 'ctest-mgmt_vn-54543361'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:08:58,942 - DEBUG - Response for create_subnet : {'subnet': {'id': '9d7605ff-0b4a-41fb-adf1-c64007b6a3fc', 'name': '', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'network_id': '39cdf7c9-500d-4477-af82-bec52c38d092', 'ip_version': 4, 'cidr': '198.229.110.128/26', 'allocation_pools': [{'start': '198.229.110.130', 'end': '198.229.110.190'}], 'gateway_ip': '198.229.110.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.229.110.130', 'tags': [], 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051'}}
2025-09-04 03:08:58,963 - INFO - Created VN ctest-mgmt_vn-54543361
2025-09-04 03:08:58,976 - DEBUG - VN ctest-mgmt_vn-54543361 UUID is 39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,052 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:59,345 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:59,373 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:59,389 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:08:59,480 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,491 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,500 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/271b8b43-350a-4d05-9a1f-8f2c3587f646
2025-09-04 03:08:59,508 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/271b8b43-350a-4d05-9a1f-8f2c3587f646
2025-09-04 03:08:59,515 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/c7b6d71d-d414-424b-915b-40e07ec64878
2025-09-04 03:08:59,520 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:59,520 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,528 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/271b8b43-350a-4d05-9a1f-8f2c3587f646
2025-09-04 03:08:59,542 - INFO - Verified VN network id 13 for VN 39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,542 - INFO - Verifications in API Server for VN ctest-mgmt_vn-54543361 passed
2025-09-04 03:08:59,542 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,551 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/271b8b43-350a-4d05-9a1f-8f2c3587f646
2025-09-04 03:08:59,558 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/271b8b43-350a-4d05-9a1f-8f2c3587f646
2025-09-04 03:08:59,565 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/c7b6d71d-d414-424b-915b-40e07ec64878
2025-09-04 03:08:59,577 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4165257674391110775', 'uuid-lslong': '12646880457669529746'}, 'enable': 'true', 'created': '2025-09-04T03:08:58', 'last-modified': '2025-09-04T03:08:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.695392'}
2025-09-04 03:08:59,582 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:59,587 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4165257674391110775', 'uuid-lslong': '12646880457669529746'}, 'enable': 'true', 'created': '2025-09-04T03:08:58', 'last-modified': '2025-09-04T03:08:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.705320'}
2025-09-04 03:08:59,595 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:59,600 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4165257674391110775', 'uuid-lslong': '12646880457669529746'}, 'enable': 'true', 'created': '2025-09-04T03:08:58', 'last-modified': '2025-09-04T03:08:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.721978'}
2025-09-04 03:08:59,605 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:08:59,605 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-54543361 passed
2025-09-04 03:08:59,605 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-54543361 in API_Server ======
2025-09-04 03:08:59,605 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:08:59,611 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:08:59,621 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:08:59,711 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/39cdf7c9-500d-4477-af82-bec52c38d092
2025-09-04 03:08:59,723 - DEBUG - =>VN ctest-mgmt_vn-54543361 has no policy to be verified
2025-09-04 03:08:59,723 - DEBUG - Verifying the vn in opserver
2025-09-04 03:08:59,723 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361 virtual network link through opserver 10.0.0.57
2025-09-04 03:08:59,723 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:00,021 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:00,078 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361?flat'}
2025-09-04 03:09:00,078 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361 virtual network link through opserver 10.0.0.38
2025-09-04 03:09:00,078 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:00,389 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:00,458 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361?flat'}
2025-09-04 03:09:00,458 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361 virtual network link through opserver 10.0.0.131
2025-09-04 03:09:00,458 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:00,763 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:00,824 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361?flat'}
2025-09-04 03:09:00,824 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-54543361 is found in opserver
2025-09-04 03:09:00,846 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:09:00,857 - DEBUG - VRF ids for VN ctest-mgmt_vn-54543361: {}
2025-09-04 03:09:01,108 - DEBUG - Response for create_network : {'network': {'id': '7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0', 'name': 'service-left-vn-65537956', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-75810461', 'service-left-vn-65537956'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:09:01,317 - DEBUG - Response for create_subnet : {'subnet': {'id': '863898b0-e653-4b99-b439-ab36d3675538', 'name': '', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'network_id': '7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0', 'ip_version': 4, 'cidr': '216.158.39.0/26', 'allocation_pools': [{'start': '216.158.39.2', 'end': '216.158.39.62'}], 'gateway_ip': '216.158.39.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.158.39.2', 'tags': [], 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051'}}
2025-09-04 03:09:01,343 - INFO - Created VN service-left-vn-65537956
2025-09-04 03:09:01,359 - DEBUG - VN service-left-vn-65537956 UUID is 7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,433 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:01,439 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:01,452 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:01,546 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,559 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,568 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/99599472-95d6-4c6b-ac8f-65fd2d26330d
2025-09-04 03:09:01,575 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/99599472-95d6-4c6b-ac8f-65fd2d26330d
2025-09-04 03:09:01,582 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/cd20032a-a76c-482e-8bc8-c9ff7ca19fa1
2025-09-04 03:09:01,590 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:01,590 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,599 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/99599472-95d6-4c6b-ac8f-65fd2d26330d
2025-09-04 03:09:01,613 - INFO - Verified VN network id 14 for VN 7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,613 - INFO - Verifications in API Server for VN service-left-vn-65537956 passed
2025-09-04 03:09:01,613 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,627 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/99599472-95d6-4c6b-ac8f-65fd2d26330d
2025-09-04 03:09:01,634 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/99599472-95d6-4c6b-ac8f-65fd2d26330d
2025-09-04 03:09:01,641 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/cd20032a-a76c-482e-8bc8-c9ff7ca19fa1
2025-09-04 03:09:01,653 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9058582709317553635', 'uuid-lslong': '10057006342661124560'}, 'enable': 'true', 'created': '2025-09-04T03:09:01', 'last-modified': '2025-09-04T03:09:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.390478'}
2025-09-04 03:09:01,658 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:01,664 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9058582709317553635', 'uuid-lslong': '10057006342661124560'}, 'enable': 'true', 'created': '2025-09-04T03:09:01', 'last-modified': '2025-09-04T03:09:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397777'}
2025-09-04 03:09:01,669 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:01,674 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9058582709317553635', 'uuid-lslong': '10057006342661124560'}, 'enable': 'true', 'created': '2025-09-04T03:09:01', 'last-modified': '2025-09-04T03:09:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.412435'}
2025-09-04 03:09:01,678 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:01,678 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-65537956 passed
2025-09-04 03:09:01,679 - DEBUG - ====Verifying policy data for service-left-vn-65537956 in API_Server ======
2025-09-04 03:09:01,679 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:01,684 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:01,695 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:01,785 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/7db68cfa-cd5e-4de3-8b91-a9fbfe2ab1d0
2025-09-04 03:09:01,794 - DEBUG - =>VN service-left-vn-65537956 has no policy to be verified
2025-09-04 03:09:01,794 - DEBUG - Verifying the vn in opserver
2025-09-04 03:09:01,794 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956 virtual network link through opserver 10.0.0.57
2025-09-04 03:09:01,794 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:01,852 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956?flat'}
2025-09-04 03:09:01,852 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956 virtual network link through opserver 10.0.0.38
2025-09-04 03:09:01,852 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:01,916 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956?flat'}
2025-09-04 03:09:01,916 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956 virtual network link through opserver 10.0.0.131
2025-09-04 03:09:01,916 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:01,973 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956?flat'}
2025-09-04 03:09:01,973 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-75810461:service-left-vn-65537956 is found in opserver
2025-09-04 03:09:01,998 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:09:02,008 - DEBUG - VRF ids for VN service-left-vn-65537956: {}
2025-09-04 03:09:02,365 - DEBUG - Response for create_network : {'network': {'id': '40fd85fd-cd01-4acb-896d-ebd9d9a476c8', 'name': 'service-right-vn-88085013', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-75810461', 'service-right-vn-88085013'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:09:02,618 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bfc883f5-b7cb-40e6-8f55-c16b0c350b85', 'name': '', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'network_id': '40fd85fd-cd01-4acb-896d-ebd9d9a476c8', 'ip_version': 4, 'cidr': '86.130.208.0/26', 'allocation_pools': [{'start': '86.130.208.2', 'end': '86.130.208.62'}], 'gateway_ip': '86.130.208.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '86.130.208.2', 'tags': [], 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051'}}
2025-09-04 03:09:02,646 - INFO - Created VN service-right-vn-88085013
2025-09-04 03:09:02,660 - DEBUG - VN service-right-vn-88085013 UUID is 40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:02,748 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:02,754 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:02,767 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:02,874 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:02,888 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:02,898 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2b078ac4-db47-40d5-945b-7e88ed30acc0
2025-09-04 03:09:02,908 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2b078ac4-db47-40d5-945b-7e88ed30acc0
2025-09-04 03:09:02,915 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/4f2eb59f-67dd-4009-b93a-32ecdb4394ee
2025-09-04 03:09:02,924 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:02,924 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:02,934 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2b078ac4-db47-40d5-945b-7e88ed30acc0
2025-09-04 03:09:02,953 - INFO - Verified VN network id 15 for VN 40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:02,953 - INFO - Verifications in API Server for VN service-right-vn-88085013 passed
2025-09-04 03:09:02,953 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:02,964 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2b078ac4-db47-40d5-945b-7e88ed30acc0
2025-09-04 03:09:02,972 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2b078ac4-db47-40d5-945b-7e88ed30acc0
2025-09-04 03:09:02,982 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/4f2eb59f-67dd-4009-b93a-32ecdb4394ee
2025-09-04 03:09:02,997 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4683046512647817931', 'uuid-lslong': '9902830476549453512'}, 'enable': 'true', 'created': '2025-09-04T03:09:02', 'last-modified': '2025-09-04T03:09:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441273'}
2025-09-04 03:09:03,003 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:03,008 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4683046512647817931', 'uuid-lslong': '9902830476549453512'}, 'enable': 'true', 'created': '2025-09-04T03:09:02', 'last-modified': '2025-09-04T03:09:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.450926'}
2025-09-04 03:09:03,014 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:03,019 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4683046512647817931', 'uuid-lslong': '9902830476549453512'}, 'enable': 'true', 'created': '2025-09-04T03:09:02', 'last-modified': '2025-09-04T03:09:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464442'}
2025-09-04 03:09:03,026 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:03,026 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-88085013 passed
2025-09-04 03:09:03,026 - DEBUG - ====Verifying policy data for service-right-vn-88085013 in API_Server ======
2025-09-04 03:09:03,027 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:03,034 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:03,047 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:03,141 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/40fd85fd-cd01-4acb-896d-ebd9d9a476c8
2025-09-04 03:09:03,152 - DEBUG - =>VN service-right-vn-88085013 has no policy to be verified
2025-09-04 03:09:03,152 - DEBUG - Verifying the vn in opserver
2025-09-04 03:09:03,152 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013 virtual network link through opserver 10.0.0.57
2025-09-04 03:09:03,152 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:03,205 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013?flat'}
2025-09-04 03:09:03,205 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013 virtual network link through opserver 10.0.0.38
2025-09-04 03:09:03,205 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:03,280 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013?flat'}
2025-09-04 03:09:03,280 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013 virtual network link through opserver 10.0.0.131
2025-09-04 03:09:03,281 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:03,354 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013?flat'}
2025-09-04 03:09:03,354 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-75810461:service-right-vn-88085013 is found in opserver
2025-09-04 03:09:03,375 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:09:03,384 - DEBUG - VRF ids for VN service-right-vn-88085013: {}
2025-09-04 03:09:03,451 - DEBUG - Services list from nova: [, , , ]
2025-09-04 03:09:04,509 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1)
2025-09-04 03:09:05,793 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2)
2025-09-04 03:09:05,793 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-92617229']
2025-09-04 03:09:06,094 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92617229'] created succesfully
2025-09-04 03:09:06,480 - DEBUG - Image by name tiny_nat_fw not found
2025-09-04 03:09:06,480 - DEBUG - Installing image tiny_nat_fw
2025-09-04 03:09:06,480 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-09-04 03:09:06,481 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:09:06,481 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:09:06,481 - DEBUG - Unzip image
2025-09-04 03:09:06,481 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:09:06,500 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-04 03:09:06,500 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-92617229']
2025-09-04 03:09:06,608 - INFO - Service template: ['default-domain', 'ctest-service_template_1-92617229'] deleted successfully.
2025-09-04 03:09:06,608 - INFO - Skip interface_detach for VM ctest-right_vm-12730381 in state BUILD
2025-09-04 03:09:06,609 - INFO - Deleting VM ctest-right_vm-12730381
2025-09-04 03:09:06,674 - ERROR - VM ctest-right_vm-12730381 has failed to come up
2025-09-04 03:09:06,674 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:06,674 - ERROR - VM ctest-right_vm-12730381 has failed to come up
2025-09-04 03:09:06,674 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:06,776 - INFO - Skip interface_detach for VM ctest-left_vm-55608731 in state BUILD
2025-09-04 03:09:06,776 - INFO - Deleting VM ctest-left_vm-55608731
2025-09-04 03:09:06,776 - ERROR - VM ctest-left_vm-55608731 has failed to come up
2025-09-04 03:09:06,776 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:06,776 - ERROR - VM ctest-left_vm-55608731 has failed to come up
2025-09-04 03:09:06,777 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:06,847 - INFO - Deleting VN service-right-vn-88085013
2025-09-04 03:09:06,893 - DEBUG - VN 40fd85fd-cd01-4acb-896d-ebd9d9a476c8 still in use: Unable to complete operation on network 40fd85fd-cd01-4acb-896d-ebd9d9a476c8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-fc3b6510-c86d-4dba-91dd-e7b79428a262']
2025-09-04 03:09:06,893 - WARNING - Deleting VN service-right-vn-88085013 failed..Will retry
2025-09-04 03:09:09,141 - DEBUG - Response for deleting network ()
2025-09-04 03:09:09,141 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2b078ac4-db47-40d5-945b-7e88ed30acc0
2025-09-04 03:09:09,147 - DEBUG - Response Code: 404
2025-09-04 03:09:09,147 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:09,165 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:09,181 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:09,298 - INFO - Validated that VN service-right-vn-88085013 is not found in API Server
2025-09-04 03:09:09,310 - DEBUG - VN service-right-vn-88085013 is not present in Agent 10.0.0.241
2025-09-04 03:09:09,320 - DEBUG - VN service-right-vn-88085013 is not present in Agent 10.0.0.242
2025-09-04 03:09:09,320 - INFO - Validated that VN service-right-vn-88085013 is not in any agent
2025-09-04 03:09:09,349 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-88085013 info
2025-09-04 03:09:09,350 - INFO - Deleting VN service-left-vn-65537956
2025-09-04 03:09:09,498 - DEBUG - Response for deleting network ()
2025-09-04 03:09:09,498 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/99599472-95d6-4c6b-ac8f-65fd2d26330d
2025-09-04 03:09:09,506 - DEBUG - Response Code: 404
2025-09-04 03:09:09,506 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:09,513 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:09,552 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:09,654 - INFO - Validated that VN service-left-vn-65537956 is not found in API Server
2025-09-04 03:09:09,666 - DEBUG - VN service-left-vn-65537956 is not present in Agent 10.0.0.241
2025-09-04 03:09:09,675 - DEBUG - VN service-left-vn-65537956 is not present in Agent 10.0.0.242
2025-09-04 03:09:09,675 - INFO - Validated that VN service-left-vn-65537956 is not in any agent
2025-09-04 03:09:09,707 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-65537956 info
2025-09-04 03:09:09,707 - INFO - Deleting VN ctest-mgmt_vn-54543361
2025-09-04 03:09:09,878 - DEBUG - Response for deleting network ()
2025-09-04 03:09:09,879 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/271b8b43-350a-4d05-9a1f-8f2c3587f646
2025-09-04 03:09:09,887 - DEBUG - Response Code: 404
2025-09-04 03:09:09,887 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:09,899 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:09,914 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:10,090 - INFO - Validated that VN ctest-mgmt_vn-54543361 is not found in API Server
2025-09-04 03:09:10,104 - DEBUG - VN ctest-mgmt_vn-54543361 is not present in Agent 10.0.0.241
2025-09-04 03:09:10,114 - DEBUG - VN ctest-mgmt_vn-54543361 is not present in Agent 10.0.0.242
2025-09-04 03:09:10,114 - INFO - Validated that VN ctest-mgmt_vn-54543361 is not in any agent
2025-09-04 03:09:10,144 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-54543361 info
2025-09-04 03:09:11,413 - DEBUG - No XMPP flaps were noticed during the test
2025-09-04 03:09:11,413 - INFO -
2025-09-04 03:09:11,413 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-09-04 03:09:11,414 - INFO - --------------------------------------------------------------------------------
2025-09-04 03:09:11,419 - INFO - ================================================================================
2025-09-04 03:09:11,419 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-09-04 03:09:12,701 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.25': '0', '10.20.0.254': '0'}, '10.0.0.242': {'10.20.0.25': '0', '10.20.0.254': '0'}} with
2025-09-04 03:09:12,701 - INFO - Initial checks done. Running the testcase now
2025-09-04 03:09:12,701 - INFO -
2025-09-04 03:09:12,970 - DEBUG - Response for create_network : {'network': {'id': 'e7ff44f3-f77f-4b30-806a-58b19404aa8d', 'name': 'ctest-mgmt_vn-87206790', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-75810461', 'ctest-mgmt_vn-87206790'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:09:13,215 - DEBUG - Response for create_subnet : {'subnet': {'id': '12b5bfef-80e4-4328-834a-b15045241fba', 'name': '', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'network_id': 'e7ff44f3-f77f-4b30-806a-58b19404aa8d', 'ip_version': 4, 'cidr': '67.160.233.192/26', 'allocation_pools': [{'start': '67.160.233.194', 'end': '67.160.233.254'}], 'gateway_ip': '67.160.233.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '67.160.233.194', 'tags': [], 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051'}}
2025-09-04 03:09:13,239 - INFO - Created VN ctest-mgmt_vn-87206790
2025-09-04 03:09:13,250 - DEBUG - VN ctest-mgmt_vn-87206790 UUID is e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,330 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:13,336 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:13,346 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:13,433 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,446 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,458 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e9087de2-5961-496e-9df3-1a395336bbb2
2025-09-04 03:09:13,467 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e9087de2-5961-496e-9df3-1a395336bbb2
2025-09-04 03:09:13,474 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/05987e63-9856-46bf-9dcb-a771676bcb72
2025-09-04 03:09:13,482 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:09:13,482 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,490 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e9087de2-5961-496e-9df3-1a395336bbb2
2025-09-04 03:09:13,507 - INFO - Verified VN network id 13 for VN e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,507 - INFO - Verifications in API Server for VN ctest-mgmt_vn-87206790 passed
2025-09-04 03:09:13,507 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,519 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e9087de2-5961-496e-9df3-1a395336bbb2
2025-09-04 03:09:13,528 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e9087de2-5961-496e-9df3-1a395336bbb2
2025-09-04 03:09:13,535 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/05987e63-9856-46bf-9dcb-a771676bcb72
2025-09-04 03:09:13,547 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16717156156442626864', 'uuid-lslong': '9253305904101894797'}, 'enable': 'true', 'created': '2025-09-04T03:09:12', 'last-modified': '2025-09-04T03:09:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395508'}
2025-09-04 03:09:13,553 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:09:13,561 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16717156156442626864', 'uuid-lslong': '9253305904101894797'}, 'enable': 'true', 'created': '2025-09-04T03:09:12', 'last-modified': '2025-09-04T03:09:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.400816'}
2025-09-04 03:09:13,566 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:09:13,571 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16717156156442626864', 'uuid-lslong': '9253305904101894797'}, 'enable': 'true', 'created': '2025-09-04T03:09:12', 'last-modified': '2025-09-04T03:09:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420701'}
2025-09-04 03:09:13,577 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-04 03:09:13,577 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-87206790 passed
2025-09-04 03:09:13,577 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-87206790 in API_Server ======
2025-09-04 03:09:13,577 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:13,583 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:13,596 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:13,693 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/e7ff44f3-f77f-4b30-806a-58b19404aa8d
2025-09-04 03:09:13,702 - DEBUG - =>VN ctest-mgmt_vn-87206790 has no policy to be verified
2025-09-04 03:09:13,703 - DEBUG - Verifying the vn in opserver
2025-09-04 03:09:13,703 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790 virtual network link through opserver 10.0.0.57
2025-09-04 03:09:13,703 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:13,715 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790?flat'}
2025-09-04 03:09:13,715 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790 virtual network link through opserver 10.0.0.38
2025-09-04 03:09:13,715 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:13,729 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790?flat'}
2025-09-04 03:09:13,729 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790 virtual network link through opserver 10.0.0.131
2025-09-04 03:09:13,729 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:13,749 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790?flat'}
2025-09-04 03:09:13,749 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-75810461:ctest-mgmt_vn-87206790 is found in opserver
2025-09-04 03:09:13,770 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:09:13,779 - DEBUG - VRF ids for VN ctest-mgmt_vn-87206790: {}
2025-09-04 03:09:14,081 - DEBUG - Response for create_network : {'network': {'id': 'd4e1fbff-b679-4a25-9dad-8ed9fa44524f', 'name': 'ctest-left_vn-30391490', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-75810461', 'ctest-left_vn-30391490'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:09:14,293 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f4cd22dd-e8c3-4347-be06-66fdeda57cc0', 'name': '', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'network_id': 'd4e1fbff-b679-4a25-9dad-8ed9fa44524f', 'ip_version': 4, 'cidr': '60.117.80.128/26', 'allocation_pools': [{'start': '60.117.80.130', 'end': '60.117.80.190'}], 'gateway_ip': '60.117.80.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '60.117.80.130', 'tags': [], 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051'}}
2025-09-04 03:09:14,318 - INFO - Created VN ctest-left_vn-30391490
2025-09-04 03:09:14,334 - DEBUG - VN ctest-left_vn-30391490 UUID is d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,433 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:14,440 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:14,451 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:14,535 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,545 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,554 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/21e5e8b8-63ae-4a29-a017-e8fd1ffe1c06
2025-09-04 03:09:14,561 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/21e5e8b8-63ae-4a29-a017-e8fd1ffe1c06
2025-09-04 03:09:14,567 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/3c48117e-b5ad-4b0b-94f0-c824aab62382
2025-09-04 03:09:14,574 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:14,574 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,582 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/21e5e8b8-63ae-4a29-a017-e8fd1ffe1c06
2025-09-04 03:09:14,598 - INFO - Verified VN network id 14 for VN d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,598 - INFO - Verifications in API Server for VN ctest-left_vn-30391490 passed
2025-09-04 03:09:14,598 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,609 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/21e5e8b8-63ae-4a29-a017-e8fd1ffe1c06
2025-09-04 03:09:14,615 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/21e5e8b8-63ae-4a29-a017-e8fd1ffe1c06
2025-09-04 03:09:14,622 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/3c48117e-b5ad-4b0b-94f0-c824aab62382
2025-09-04 03:09:14,635 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15339818881497254437', 'uuid-lslong': '11361894501783458383'}, 'enable': 'true', 'created': '2025-09-04T03:09:14', 'last-modified': '2025-09-04T03:09:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398190'}
2025-09-04 03:09:14,641 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:14,646 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15339818881497254437', 'uuid-lslong': '11361894501783458383'}, 'enable': 'true', 'created': '2025-09-04T03:09:14', 'last-modified': '2025-09-04T03:09:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414153'}
2025-09-04 03:09:14,652 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:14,657 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15339818881497254437', 'uuid-lslong': '11361894501783458383'}, 'enable': 'true', 'created': '2025-09-04T03:09:14', 'last-modified': '2025-09-04T03:09:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418758'}
2025-09-04 03:09:14,662 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-04 03:09:14,662 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-30391490 passed
2025-09-04 03:09:14,662 - DEBUG - ====Verifying policy data for ctest-left_vn-30391490 in API_Server ======
2025-09-04 03:09:14,662 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:14,668 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:14,679 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:14,764 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/d4e1fbff-b679-4a25-9dad-8ed9fa44524f
2025-09-04 03:09:14,773 - DEBUG - =>VN ctest-left_vn-30391490 has no policy to be verified
2025-09-04 03:09:14,773 - DEBUG - Verifying the vn in opserver
2025-09-04 03:09:14,773 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490 virtual network link through opserver 10.0.0.57
2025-09-04 03:09:14,773 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:14,786 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490?flat'}
2025-09-04 03:09:14,786 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490 virtual network link through opserver 10.0.0.38
2025-09-04 03:09:14,786 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:14,799 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490?flat'}
2025-09-04 03:09:14,799 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490 virtual network link through opserver 10.0.0.131
2025-09-04 03:09:14,799 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:14,811 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490?flat'}
2025-09-04 03:09:14,811 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-75810461:ctest-left_vn-30391490 is found in opserver
2025-09-04 03:09:14,831 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:09:14,840 - DEBUG - VRF ids for VN ctest-left_vn-30391490: {}
2025-09-04 03:09:15,138 - DEBUG - Response for create_network : {'network': {'id': '22ebd207-d62e-4f03-9d07-27e16253ae82', 'name': 'ctest-right_vn-87474304', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-75810461', 'ctest-right_vn-87474304'], 'port_security_enabled': True, 'description': ''}}
2025-09-04 03:09:15,381 - DEBUG - Response for create_subnet : {'subnet': {'id': '52688c15-c1c4-42d9-b078-933b6c915adb', 'name': '', 'tenant_id': '9928aa8ec9a5497cacbe0ce61df2d051', 'network_id': '22ebd207-d62e-4f03-9d07-27e16253ae82', 'ip_version': 4, 'cidr': '111.177.248.0/26', 'allocation_pools': [{'start': '111.177.248.2', 'end': '111.177.248.62'}], 'gateway_ip': '111.177.248.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '111.177.248.2', 'tags': [], 'project_id': '9928aa8ec9a5497cacbe0ce61df2d051'}}
2025-09-04 03:09:15,406 - INFO - Created VN ctest-right_vn-87474304
2025-09-04 03:09:15,420 - DEBUG - VN ctest-right_vn-87474304 UUID is 22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,500 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:15,507 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:15,519 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:15,609 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,620 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,632 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/d2b59688-3ad9-40cc-aac3-3d5fca79d548
2025-09-04 03:09:15,640 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/d2b59688-3ad9-40cc-aac3-3d5fca79d548
2025-09-04 03:09:15,649 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/e47732c2-99ad-4aa2-9f9f-179a104f560b
2025-09-04 03:09:15,657 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:15,657 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,668 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/d2b59688-3ad9-40cc-aac3-3d5fca79d548
2025-09-04 03:09:15,683 - INFO - Verified VN network id 15 for VN 22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,683 - INFO - Verifications in API Server for VN ctest-right_vn-87474304 passed
2025-09-04 03:09:15,683 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,692 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/d2b59688-3ad9-40cc-aac3-3d5fca79d548
2025-09-04 03:09:15,700 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/d2b59688-3ad9-40cc-aac3-3d5fca79d548
2025-09-04 03:09:15,706 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/e47732c2-99ad-4aa2-9f9f-179a104f560b
2025-09-04 03:09:15,722 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2516335747916517123', 'uuid-lslong': '11315056437762436738'}, 'enable': 'true', 'created': '2025-09-04T03:09:15', 'last-modified': '2025-09-04T03:09:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384644'}
2025-09-04 03:09:15,729 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:15,735 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2516335747916517123', 'uuid-lslong': '11315056437762436738'}, 'enable': 'true', 'created': '2025-09-04T03:09:15', 'last-modified': '2025-09-04T03:09:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405285'}
2025-09-04 03:09:15,741 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:15,747 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-75810461', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2516335747916517123', 'uuid-lslong': '11315056437762436738'}, 'enable': 'true', 'created': '2025-09-04T03:09:15', 'last-modified': '2025-09-04T03:09:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414096'}
2025-09-04 03:09:15,754 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-04 03:09:15,754 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-87474304 passed
2025-09-04 03:09:15,755 - DEBUG - ====Verifying policy data for ctest-right_vn-87474304 in API_Server ======
2025-09-04 03:09:15,755 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:15,762 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:15,774 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:15,866 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/22ebd207-d62e-4f03-9d07-27e16253ae82
2025-09-04 03:09:15,880 - DEBUG - =>VN ctest-right_vn-87474304 has no policy to be verified
2025-09-04 03:09:15,880 - DEBUG - Verifying the vn in opserver
2025-09-04 03:09:15,880 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304 virtual network link through opserver 10.0.0.57
2025-09-04 03:09:15,880 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2025-09-04 03:09:15,892 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304?flat'}
2025-09-04 03:09:15,892 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304 virtual network link through opserver 10.0.0.38
2025-09-04 03:09:15,892 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-04 03:09:15,904 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304?flat'}
2025-09-04 03:09:15,904 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304 virtual network link through opserver 10.0.0.131
2025-09-04 03:09:15,904 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks
2025-09-04 03:09:15,916 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304?flat'}
2025-09-04 03:09:15,916 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-75810461:ctest-right_vn-87474304 is found in opserver
2025-09-04 03:09:15,935 - DEBUG - Do not have enough data to verify VN in agent
2025-09-04 03:09:15,945 - DEBUG - VRF ids for VN ctest-right_vn-87474304: {}
2025-09-04 03:09:17,191 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2)
2025-09-04 03:09:18,759 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1)
2025-09-04 03:09:18,760 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-35194668']
2025-09-04 03:09:19,057 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-35194668'] created succesfully
2025-09-04 03:09:19,425 - DEBUG - Image by name tiny_nat_fw not found
2025-09-04 03:09:19,425 - DEBUG - Installing image tiny_nat_fw
2025-09-04 03:09:19,425 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-09-04 03:09:19,425 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:09:19,425 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:09:19,426 - DEBUG - Unzip image
2025-09-04 03:09:19,426 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-04 03:09:19,444 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-04 03:09:19,444 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-35194668']
2025-09-04 03:09:19,544 - INFO - Service template: ['default-domain', 'ctest-service_template_1-35194668'] deleted successfully.
2025-09-04 03:09:19,544 - INFO - Skip interface_detach for VM ctest-right_vm-51666675 in state BUILD
2025-09-04 03:09:19,545 - INFO - Deleting VM ctest-right_vm-51666675
2025-09-04 03:09:19,545 - ERROR - VM ctest-right_vm-51666675 has failed to come up
2025-09-04 03:09:19,545 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:19,545 - ERROR - VM ctest-right_vm-51666675 has failed to come up
2025-09-04 03:09:19,545 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:19,652 - INFO - Skip interface_detach for VM ctest-left_vm-00779773 in state BUILD
2025-09-04 03:09:19,652 - INFO - Deleting VM ctest-left_vm-00779773
2025-09-04 03:09:19,652 - ERROR - VM ctest-left_vm-00779773 has failed to come up
2025-09-04 03:09:19,652 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:19,652 - ERROR - VM ctest-left_vm-00779773 has failed to come up
2025-09-04 03:09:19,652 - ERROR - Nova failed to get host of the VM
2025-09-04 03:09:19,727 - INFO - Deleting VN ctest-right_vn-87474304
2025-09-04 03:09:19,775 - DEBUG - VN 22ebd207-d62e-4f03-9d07-27e16253ae82 still in use: Unable to complete operation on network 22ebd207-d62e-4f03-9d07-27e16253ae82. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-bc5f5921-76de-463b-991e-0a4a79bcab5f']
2025-09-04 03:09:19,775 - WARNING - Deleting VN ctest-right_vn-87474304 failed..Will retry
2025-09-04 03:09:21,945 - DEBUG - Response for deleting network ()
2025-09-04 03:09:21,945 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/d2b59688-3ad9-40cc-aac3-3d5fca79d548
2025-09-04 03:09:21,952 - DEBUG - Response Code: 404
2025-09-04 03:09:21,952 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:21,958 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:21,987 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:22,088 - INFO - Validated that VN ctest-right_vn-87474304 is not found in API Server
2025-09-04 03:09:22,099 - DEBUG - VN ctest-right_vn-87474304 is not present in Agent 10.0.0.241
2025-09-04 03:09:22,111 - DEBUG - VN ctest-right_vn-87474304 is not present in Agent 10.0.0.242
2025-09-04 03:09:22,111 - INFO - Validated that VN ctest-right_vn-87474304 is not in any agent
2025-09-04 03:09:22,140 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-87474304 info
2025-09-04 03:09:22,140 - INFO - Deleting VN ctest-left_vn-30391490
2025-09-04 03:09:22,305 - DEBUG - Response for deleting network ()
2025-09-04 03:09:22,305 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/21e5e8b8-63ae-4a29-a017-e8fd1ffe1c06
2025-09-04 03:09:22,311 - DEBUG - Response Code: 404
2025-09-04 03:09:22,311 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:22,322 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:22,341 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:22,437 - INFO - Validated that VN ctest-left_vn-30391490 is not found in API Server
2025-09-04 03:09:22,450 - DEBUG - VN ctest-left_vn-30391490 is not present in Agent 10.0.0.241
2025-09-04 03:09:22,461 - DEBUG - VN ctest-left_vn-30391490 is not present in Agent 10.0.0.242
2025-09-04 03:09:22,461 - INFO - Validated that VN ctest-left_vn-30391490 is not in any agent
2025-09-04 03:09:22,494 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-30391490 info
2025-09-04 03:09:22,494 - INFO - Deleting VN ctest-mgmt_vn-87206790
2025-09-04 03:09:22,662 - DEBUG - Response for deleting network ()
2025-09-04 03:09:22,662 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e9087de2-5961-496e-9df3-1a395336bbb2
2025-09-04 03:09:22,679 - DEBUG - Response Code: 404
2025-09-04 03:09:22,679 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2025-09-04 03:09:22,685 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c
2025-09-04 03:09:22,742 - DEBUG - Requesting: http://10.0.0.57:8082/project/9928aa8e-c9a5-497c-acbe-0ce61df2d051
2025-09-04 03:09:22,823 - INFO - Validated that VN ctest-mgmt_vn-87206790 is not found in API Server
2025-09-04 03:09:22,834 - DEBUG - VN ctest-mgmt_vn-87206790 is not present in Agent 10.0.0.241
2025-09-04 03:09:22,844 - DEBUG - VN ctest-mgmt_vn-87206790 is not present in Agent 10.0.0.242
2025-09-04 03:09:22,844 - INFO - Validated that VN ctest-mgmt_vn-87206790 is not in any agent
2025-09-04 03:09:22,872 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-87206790 info
2025-09-04 03:09:24,153 - DEBUG - No XMPP flaps were noticed during the test
2025-09-04 03:09:24,153 - INFO -
2025-09-04 03:09:24,154 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-09-04 03:09:24,154 - INFO - --------------------------------------------------------------------------------
2025-09-04 03:09:24,862 - INFO - Deleted project: ctest-TestSvcRegr-75810461, ID : 9928aa8e-c9a5-497c-acbe-0ce61df2d051