]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-2)
2025-10-27 03:15:35,991 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-64897220']
2025-10-27 03:15:36,297 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-64897220'] created succesfully
2025-10-27 03:15:36,715 - DEBUG - Image by name tiny_nat_fw not found
2025-10-27 03:15:36,715 - DEBUG - Installing image tiny_nat_fw
2025-10-27 03:15:36,715 - 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-10-27 03:15:36,716 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-27 03:15:36,716 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-27 03:15:36,769 - 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-10-27 03:15:36,869 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:15:36,870 - DEBUG - Unzip image
2025-10-27 03:15:36,870 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:15:36,898 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-27 03:15:36,898 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-64897220']
2025-10-27 03:15:37,001 - INFO - Service template: ['default-domain', 'ctest-service_template_1-64897220'] deleted successfully.
2025-10-27 03:15:37,001 - INFO - Skip interface_detach for VM ctest-right_vm-74677937 in state BUILD
2025-10-27 03:15:37,001 - INFO - Deleting VM ctest-right_vm-74677937
2025-10-27 03:15:37,043 - ERROR - VM ctest-right_vm-74677937 has failed to come up
2025-10-27 03:15:37,043 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:37,043 - ERROR - VM ctest-right_vm-74677937 has failed to come up
2025-10-27 03:15:37,043 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:37,151 - INFO - Skip interface_detach for VM ctest-left_vm-00570958 in state BUILD
2025-10-27 03:15:37,152 - INFO - Deleting VM ctest-left_vm-00570958
2025-10-27 03:15:37,152 - ERROR - VM ctest-left_vm-00570958 has failed to come up
2025-10-27 03:15:37,152 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:37,152 - ERROR - VM ctest-left_vm-00570958 has failed to come up
2025-10-27 03:15:37,152 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:37,237 - INFO - Deleting VN ctest-right_vn-76033316
2025-10-27 03:15:37,287 - DEBUG - VN 580201d9-774b-4229-bf87-845e09b4d269 still in use: Unable to complete operation on network 580201d9-774b-4229-bf87-845e09b4d269. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-40b02b57-3d57-462c-8a28-f40085847dfe']
2025-10-27 03:15:37,287 - WARNING - Deleting VN ctest-right_vn-76033316 failed..Will retry
2025-10-27 03:15:39,463 - DEBUG - Response for deleting network ()
2025-10-27 03:15:39,463 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/caeae0d8-3901-4abb-b36d-f203f87b69a9
2025-10-27 03:15:39,473 - DEBUG - Response Code: 404
2025-10-27 03:15:39,473 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:39,484 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:39,513 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:39,619 - INFO - Validated that VN ctest-right_vn-76033316 is not found in API Server
2025-10-27 03:15:39,632 - DEBUG - VN ctest-right_vn-76033316 is not present in Agent 10.0.0.130
2025-10-27 03:15:39,644 - DEBUG - VN ctest-right_vn-76033316 is not present in Agent 10.0.0.131
2025-10-27 03:15:39,644 - INFO - Validated that VN ctest-right_vn-76033316 is not in any agent
2025-10-27 03:15:39,684 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-76033316 info
2025-10-27 03:15:39,685 - INFO - Deleting VN ctest-left_vn-99740365
2025-10-27 03:15:39,875 - DEBUG - Response for deleting network ()
2025-10-27 03:15:39,876 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/6cd2968e-5c17-48a3-8f85-f892fd40c670
2025-10-27 03:15:39,885 - DEBUG - Response Code: 404
2025-10-27 03:15:39,885 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:39,900 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:39,915 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:40,041 - INFO - Validated that VN ctest-left_vn-99740365 is not found in API Server
2025-10-27 03:15:40,056 - DEBUG - VN ctest-left_vn-99740365 is not present in Agent 10.0.0.130
2025-10-27 03:15:40,072 - DEBUG - VN ctest-left_vn-99740365 is not present in Agent 10.0.0.131
2025-10-27 03:15:40,072 - INFO - Validated that VN ctest-left_vn-99740365 is not in any agent
2025-10-27 03:15:40,113 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-99740365 info
2025-10-27 03:15:40,113 - INFO - Deleting VN ctest-mgmt_vn-37319129
2025-10-27 03:15:40,320 - DEBUG - Response for deleting network ()
2025-10-27 03:15:40,320 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3a6d16e8-63fa-4292-912f-616a04dac6ef
2025-10-27 03:15:40,327 - DEBUG - Response Code: 404
2025-10-27 03:15:40,327 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:40,338 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:40,396 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:40,514 - INFO - Validated that VN ctest-mgmt_vn-37319129 is not found in API Server
2025-10-27 03:15:40,531 - DEBUG - VN ctest-mgmt_vn-37319129 is not present in Agent 10.0.0.130
2025-10-27 03:15:40,544 - DEBUG - VN ctest-mgmt_vn-37319129 is not present in Agent 10.0.0.131
2025-10-27 03:15:40,544 - INFO - Validated that VN ctest-mgmt_vn-37319129 is not in any agent
2025-10-27 03:15:40,585 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-37319129 info
2025-10-27 03:15:40,585 - INFO - Deleting VM ctest-vm-in-third-vn-55031517
2025-10-27 03:15:40,689 - INFO - Deleting VN ctest-third-vn-37188447
2025-10-27 03:15:40,741 - DEBUG - VN 2ef2e9fe-0e11-4d9b-9784-7c4935a7b629 still in use: Unable to complete operation on network 2ef2e9fe-0e11-4d9b-9784-7c4935a7b629. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e6fc6d7e-3eca-4f36-bc34-6d8f1697eeb2']
2025-10-27 03:15:40,741 - WARNING - Deleting VN ctest-third-vn-37188447 failed..Will retry
2025-10-27 03:15:42,883 - DEBUG - Response for deleting network ()
2025-10-27 03:15:44,166 - DEBUG - No XMPP flaps were noticed during the test
2025-10-27 03:15:44,166 - INFO -
2025-10-27 03:15:44,166 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2025-10-27 03:15:44,166 - INFO - --------------------------------------------------------------------------------
2025-10-27 03:15:44,170 - INFO - ================================================================================
2025-10-27 03:15:44,171 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-27 03:15:45,458 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.131': {'10.20.0.17': '0', '10.20.0.14': '0'}} with
2025-10-27 03:15:45,459 - INFO - Initial checks done. Running the testcase now
2025-10-27 03:15:45,459 - INFO -
2025-10-27 03:15:45,753 - DEBUG - Response for create_network : {'network': {'id': 'c6555d92-73ef-4a68-ad54-e150a562ac9a', 'name': 'ctest-mgmt_vn-68025332', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'project_id': '6578e223771e4dec9bd4874625a3ec7c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72614848', 'ctest-mgmt_vn-68025332'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:15:45,995 - DEBUG - Response for create_subnet : {'subnet': {'id': '96913f78-6ff0-4d3a-bce2-bf4205167f79', 'name': '', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'network_id': 'c6555d92-73ef-4a68-ad54-e150a562ac9a', 'ip_version': 4, 'cidr': '147.168.241.64/26', 'allocation_pools': [{'start': '147.168.241.66', 'end': '147.168.241.126'}], 'gateway_ip': '147.168.241.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '147.168.241.66', 'tags': [], 'project_id': '6578e223771e4dec9bd4874625a3ec7c'}}
2025-10-27 03:15:46,018 - INFO - Created VN ctest-mgmt_vn-68025332
2025-10-27 03:15:46,030 - DEBUG - VN ctest-mgmt_vn-68025332 UUID is c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,108 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:46,115 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:46,128 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:46,243 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,258 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,269 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2471a8f8-9ba8-4bd6-afb9-29fa7462d3b4
2025-10-27 03:15:46,277 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2471a8f8-9ba8-4bd6-afb9-29fa7462d3b4
2025-10-27 03:15:46,285 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3f93aa6b-8425-452a-b553-4d05bb3e2a89
2025-10-27 03:15:46,292 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:15:46,292 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,303 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2471a8f8-9ba8-4bd6-afb9-29fa7462d3b4
2025-10-27 03:15:46,320 - INFO - Verified VN network id 15 for VN c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,320 - INFO - Verifications in API Server for VN ctest-mgmt_vn-68025332 passed
2025-10-27 03:15:46,320 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,332 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2471a8f8-9ba8-4bd6-afb9-29fa7462d3b4
2025-10-27 03:15:46,341 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2471a8f8-9ba8-4bd6-afb9-29fa7462d3b4
2025-10-27 03:15:46,350 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3f93aa6b-8425-452a-b553-4d05bb3e2a89
2025-10-27 03:15:46,365 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14291431876121807464', 'uuid-lslong': '12489855403093568666'}, 'enable': 'true', 'created': '2025-10-27T03:15:45', 'last-modified': '2025-10-27T03:15:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.417064'}
2025-10-27 03:15:46,372 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:15:46,378 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14291431876121807464', 'uuid-lslong': '12489855403093568666'}, 'enable': 'true', 'created': '2025-10-27T03:15:45', 'last-modified': '2025-10-27T03:15:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435588'}
2025-10-27 03:15:46,385 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:15:46,393 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14291431876121807464', 'uuid-lslong': '12489855403093568666'}, 'enable': 'true', 'created': '2025-10-27T03:15:45', 'last-modified': '2025-10-27T03:15:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447223'}
2025-10-27 03:15:46,402 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:15:46,402 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-68025332 passed
2025-10-27 03:15:46,403 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-68025332 in API_Server ======
2025-10-27 03:15:46,403 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:46,412 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:46,429 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:46,526 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c6555d92-73ef-4a68-ad54-e150a562ac9a
2025-10-27 03:15:46,538 - DEBUG - =>VN ctest-mgmt_vn-68025332 has no policy to be verified
2025-10-27 03:15:46,538 - DEBUG - Verifying the vn in opserver
2025-10-27 03:15:46,538 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332 virtual network link through opserver 10.0.0.249
2025-10-27 03:15:46,538 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:15:46,558 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332?flat'}
2025-10-27 03:15:46,558 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332 virtual network link through opserver 10.0.0.33
2025-10-27 03:15:46,558 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:15:46,575 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332?flat'}
2025-10-27 03:15:46,575 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332 virtual network link through opserver 10.0.0.129
2025-10-27 03:15:46,575 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:15:46,591 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332?flat'}
2025-10-27 03:15:46,591 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72614848:ctest-mgmt_vn-68025332 is found in opserver
2025-10-27 03:15:46,616 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:15:46,627 - DEBUG - VRF ids for VN ctest-mgmt_vn-68025332: {}
2025-10-27 03:15:46,891 - DEBUG - Response for create_network : {'network': {'id': 'd08f0e7e-bbb9-42b8-a0bb-65d50f5c7619', 'name': 'ctest-left_vn-35100004', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'project_id': '6578e223771e4dec9bd4874625a3ec7c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72614848', 'ctest-left_vn-35100004'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:15:47,114 - DEBUG - Response for create_subnet : {'subnet': {'id': '4a862c53-a2c7-4842-a6e3-ef4530d4f142', 'name': '', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'network_id': 'd08f0e7e-bbb9-42b8-a0bb-65d50f5c7619', 'ip_version': 4, 'cidr': '115.229.101.192/26', 'allocation_pools': [{'start': '115.229.101.194', 'end': '115.229.101.254'}], 'gateway_ip': '115.229.101.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '115.229.101.194', 'tags': [], 'project_id': '6578e223771e4dec9bd4874625a3ec7c'}}
2025-10-27 03:15:47,139 - INFO - Created VN ctest-left_vn-35100004
2025-10-27 03:15:47,153 - DEBUG - VN ctest-left_vn-35100004 UUID is d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,244 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:47,254 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:47,266 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:47,364 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,378 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,387 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9a931fb-2e5d-42ea-9814-c555aaf14284
2025-10-27 03:15:47,397 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9a931fb-2e5d-42ea-9814-c555aaf14284
2025-10-27 03:15:47,407 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/43c5ac77-ab5b-4470-b64e-64d58312504f
2025-10-27 03:15:47,416 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:15:47,416 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,425 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9a931fb-2e5d-42ea-9814-c555aaf14284
2025-10-27 03:15:47,440 - INFO - Verified VN network id 16 for VN d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,440 - INFO - Verifications in API Server for VN ctest-left_vn-35100004 passed
2025-10-27 03:15:47,441 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,450 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9a931fb-2e5d-42ea-9814-c555aaf14284
2025-10-27 03:15:47,457 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9a931fb-2e5d-42ea-9814-c555aaf14284
2025-10-27 03:15:47,467 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/43c5ac77-ab5b-4470-b64e-64d58312504f
2025-10-27 03:15:47,481 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15028246419036783288', 'uuid-lslong': '11581962832473519641'}, 'enable': 'true', 'created': '2025-10-27T03:15:46', 'last-modified': '2025-10-27T03:15:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422528'}
2025-10-27 03:15:47,486 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:15:47,491 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15028246419036783288', 'uuid-lslong': '11581962832473519641'}, 'enable': 'true', 'created': '2025-10-27T03:15:46', 'last-modified': '2025-10-27T03:15:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437006'}
2025-10-27 03:15:47,498 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:15:47,505 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15028246419036783288', 'uuid-lslong': '11581962832473519641'}, 'enable': 'true', 'created': '2025-10-27T03:15:46', 'last-modified': '2025-10-27T03:15:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.448397'}
2025-10-27 03:15:47,511 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:15:47,511 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-35100004 passed
2025-10-27 03:15:47,512 - DEBUG - ====Verifying policy data for ctest-left_vn-35100004 in API_Server ======
2025-10-27 03:15:47,512 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:47,518 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:47,530 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:47,619 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d08f0e7e-bbb9-42b8-a0bb-65d50f5c7619
2025-10-27 03:15:47,630 - DEBUG - =>VN ctest-left_vn-35100004 has no policy to be verified
2025-10-27 03:15:47,630 - DEBUG - Verifying the vn in opserver
2025-10-27 03:15:47,630 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004 virtual network link through opserver 10.0.0.249
2025-10-27 03:15:47,630 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:15:47,643 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004?flat'}
2025-10-27 03:15:47,643 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004 virtual network link through opserver 10.0.0.33
2025-10-27 03:15:47,643 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:15:47,655 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004?flat'}
2025-10-27 03:15:47,656 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004 virtual network link through opserver 10.0.0.129
2025-10-27 03:15:47,656 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:15:47,668 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004?flat'}
2025-10-27 03:15:47,668 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72614848:ctest-left_vn-35100004 is found in opserver
2025-10-27 03:15:47,688 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:15:47,699 - DEBUG - VRF ids for VN ctest-left_vn-35100004: {}
2025-10-27 03:15:47,949 - DEBUG - Response for create_network : {'network': {'id': '1bca59f6-12ef-4e2d-8ab1-352b538a3728', 'name': 'ctest-right_vn-44130426', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'project_id': '6578e223771e4dec9bd4874625a3ec7c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72614848', 'ctest-right_vn-44130426'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:15:48,164 - DEBUG - Response for create_subnet : {'subnet': {'id': '0b413fa7-beac-4913-b46b-e343612d0afa', 'name': '', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'network_id': '1bca59f6-12ef-4e2d-8ab1-352b538a3728', 'ip_version': 4, 'cidr': '162.32.193.64/26', 'allocation_pools': [{'start': '162.32.193.66', 'end': '162.32.193.126'}], 'gateway_ip': '162.32.193.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.32.193.66', 'tags': [], 'project_id': '6578e223771e4dec9bd4874625a3ec7c'}}
2025-10-27 03:15:48,185 - INFO - Created VN ctest-right_vn-44130426
2025-10-27 03:15:48,199 - DEBUG - VN ctest-right_vn-44130426 UUID is 1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,270 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:48,276 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:48,287 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:48,388 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,399 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,409 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8dfed8c8-0776-4c10-a2a0-164830fd46dd
2025-10-27 03:15:48,416 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8dfed8c8-0776-4c10-a2a0-164830fd46dd
2025-10-27 03:15:48,423 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b2d25351-44be-4149-af96-37f7ba7e40f4
2025-10-27 03:15:48,429 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:15:48,430 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,439 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8dfed8c8-0776-4c10-a2a0-164830fd46dd
2025-10-27 03:15:48,454 - INFO - Verified VN network id 17 for VN 1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,455 - INFO - Verifications in API Server for VN ctest-right_vn-44130426 passed
2025-10-27 03:15:48,455 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,465 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8dfed8c8-0776-4c10-a2a0-164830fd46dd
2025-10-27 03:15:48,473 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8dfed8c8-0776-4c10-a2a0-164830fd46dd
2025-10-27 03:15:48,481 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b2d25351-44be-4149-af96-37f7ba7e40f4
2025-10-27 03:15:48,496 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2002511897734106669', 'uuid-lslong': '9993827508313274152'}, 'enable': 'true', 'created': '2025-10-27T03:15:47', 'last-modified': '2025-10-27T03:15:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.390396'}
2025-10-27 03:15:48,501 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:15:48,506 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2002511897734106669', 'uuid-lslong': '9993827508313274152'}, 'enable': 'true', 'created': '2025-10-27T03:15:47', 'last-modified': '2025-10-27T03:15:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.400188'}
2025-10-27 03:15:48,513 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:15:48,519 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2002511897734106669', 'uuid-lslong': '9993827508313274152'}, 'enable': 'true', 'created': '2025-10-27T03:15:47', 'last-modified': '2025-10-27T03:15:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414102'}
2025-10-27 03:15:48,526 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:15:48,526 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-44130426 passed
2025-10-27 03:15:48,527 - DEBUG - ====Verifying policy data for ctest-right_vn-44130426 in API_Server ======
2025-10-27 03:15:48,527 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:48,534 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:48,549 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:48,651 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1bca59f6-12ef-4e2d-8ab1-352b538a3728
2025-10-27 03:15:48,667 - DEBUG - =>VN ctest-right_vn-44130426 has no policy to be verified
2025-10-27 03:15:48,667 - DEBUG - Verifying the vn in opserver
2025-10-27 03:15:48,667 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426 virtual network link through opserver 10.0.0.249
2025-10-27 03:15:48,667 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:15:48,681 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426?flat'}
2025-10-27 03:15:48,681 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426 virtual network link through opserver 10.0.0.33
2025-10-27 03:15:48,681 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:15:48,696 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426?flat'}
2025-10-27 03:15:48,696 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426 virtual network link through opserver 10.0.0.129
2025-10-27 03:15:48,696 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:15:48,711 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426?flat'}
2025-10-27 03:15:48,711 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72614848:ctest-right_vn-44130426 is found in opserver
2025-10-27 03:15:48,737 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:15:48,750 - DEBUG - VRF ids for VN ctest-right_vn-44130426: {}
2025-10-27 03:15:49,035 - DEBUG - Response for create_network : {'network': {'id': '38a505cb-268e-4fa0-bcda-dcd37e604f11', 'name': 'ctest-trans_left_vn-60779179', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'project_id': '6578e223771e4dec9bd4874625a3ec7c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72614848', 'ctest-trans_left_vn-60779179'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:15:49,248 - DEBUG - Response for create_subnet : {'subnet': {'id': '56ea8371-b360-499d-8dd1-2d261d0e0efb', 'name': '', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'network_id': '38a505cb-268e-4fa0-bcda-dcd37e604f11', 'ip_version': 4, 'cidr': '4.237.150.128/26', 'allocation_pools': [{'start': '4.237.150.130', 'end': '4.237.150.190'}], 'gateway_ip': '4.237.150.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '4.237.150.130', 'tags': [], 'project_id': '6578e223771e4dec9bd4874625a3ec7c'}}
2025-10-27 03:15:49,272 - INFO - Created VN ctest-trans_left_vn-60779179
2025-10-27 03:15:49,285 - DEBUG - VN ctest-trans_left_vn-60779179 UUID is 38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,367 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:49,374 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:49,387 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:49,488 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,509 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,521 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b98883f9-3c2a-47ed-bb07-aac547378175
2025-10-27 03:15:49,535 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b98883f9-3c2a-47ed-bb07-aac547378175
2025-10-27 03:15:49,553 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/9ac9adec-399d-43b6-acca-f50debc84daf
2025-10-27 03:15:49,562 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-27 03:15:49,562 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,575 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b98883f9-3c2a-47ed-bb07-aac547378175
2025-10-27 03:15:49,599 - INFO - Verified VN network id 18 for VN 38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,599 - INFO - Verifications in API Server for VN ctest-trans_left_vn-60779179 passed
2025-10-27 03:15:49,599 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,611 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b98883f9-3c2a-47ed-bb07-aac547378175
2025-10-27 03:15:49,621 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b98883f9-3c2a-47ed-bb07-aac547378175
2025-10-27 03:15:49,631 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/9ac9adec-399d-43b6-acca-f50debc84daf
2025-10-27 03:15:49,647 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4081675007364583328', 'uuid-lslong': '13608432024969826065'}, 'enable': 'true', 'created': '2025-10-27T03:15:48', 'last-modified': '2025-10-27T03:15:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440651'}
2025-10-27 03:15:49,656 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-27 03:15:49,661 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4081675007364583328', 'uuid-lslong': '13608432024969826065'}, 'enable': 'true', 'created': '2025-10-27T03:15:48', 'last-modified': '2025-10-27T03:15:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.452836'}
2025-10-27 03:15:49,671 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-27 03:15:49,678 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4081675007364583328', 'uuid-lslong': '13608432024969826065'}, 'enable': 'true', 'created': '2025-10-27T03:15:48', 'last-modified': '2025-10-27T03:15:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471327'}
2025-10-27 03:15:49,685 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-27 03:15:49,685 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-60779179 passed
2025-10-27 03:15:49,685 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-60779179 in API_Server ======
2025-10-27 03:15:49,685 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:49,691 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:49,714 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:49,811 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38a505cb-268e-4fa0-bcda-dcd37e604f11
2025-10-27 03:15:49,820 - DEBUG - =>VN ctest-trans_left_vn-60779179 has no policy to be verified
2025-10-27 03:15:49,820 - DEBUG - Verifying the vn in opserver
2025-10-27 03:15:49,820 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179 virtual network link through opserver 10.0.0.249
2025-10-27 03:15:49,821 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:15:49,834 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179?flat'}
2025-10-27 03:15:49,834 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179 virtual network link through opserver 10.0.0.33
2025-10-27 03:15:49,834 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:15:49,846 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179?flat'}
2025-10-27 03:15:49,847 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179 virtual network link through opserver 10.0.0.129
2025-10-27 03:15:49,847 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:15:49,860 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179?flat'}
2025-10-27 03:15:49,860 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72614848:ctest-trans_left_vn-60779179 is found in opserver
2025-10-27 03:15:49,883 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:15:49,895 - DEBUG - VRF ids for VN ctest-trans_left_vn-60779179: {}
2025-10-27 03:15:50,181 - DEBUG - Response for create_network : {'network': {'id': '314ff6f2-ab81-46d9-b6ba-55c527bb4302', 'name': 'ctest-trans_right_vn-99689163', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'project_id': '6578e223771e4dec9bd4874625a3ec7c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72614848', 'ctest-trans_right_vn-99689163'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:15:50,432 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4cff99f-e590-4f74-876a-9e53607a5eb5', 'name': '', 'tenant_id': '6578e223771e4dec9bd4874625a3ec7c', 'network_id': '314ff6f2-ab81-46d9-b6ba-55c527bb4302', 'ip_version': 4, 'cidr': '26.132.249.0/26', 'allocation_pools': [{'start': '26.132.249.2', 'end': '26.132.249.62'}], 'gateway_ip': '26.132.249.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.132.249.2', 'tags': [], 'project_id': '6578e223771e4dec9bd4874625a3ec7c'}}
2025-10-27 03:15:50,454 - INFO - Created VN ctest-trans_right_vn-99689163
2025-10-27 03:15:50,465 - DEBUG - VN ctest-trans_right_vn-99689163 UUID is 314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,544 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:50,552 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:50,565 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:50,679 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,692 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,704 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bbb2a433-cff0-4cec-97c4-cb5225ef3f47
2025-10-27 03:15:50,716 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bbb2a433-cff0-4cec-97c4-cb5225ef3f47
2025-10-27 03:15:50,725 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/0a93345e-4ae4-43c5-aec4-da62aeba008b
2025-10-27 03:15:50,733 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-27 03:15:50,733 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,743 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bbb2a433-cff0-4cec-97c4-cb5225ef3f47
2025-10-27 03:15:50,759 - INFO - Verified VN network id 19 for VN 314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,759 - INFO - Verifications in API Server for VN ctest-trans_right_vn-99689163 passed
2025-10-27 03:15:50,759 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,773 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bbb2a433-cff0-4cec-97c4-cb5225ef3f47
2025-10-27 03:15:50,783 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bbb2a433-cff0-4cec-97c4-cb5225ef3f47
2025-10-27 03:15:50,791 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/0a93345e-4ae4-43c5-aec4-da62aeba008b
2025-10-27 03:15:50,805 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3553330153138505433', 'uuid-lslong': '13166930765834568450'}, 'enable': 'true', 'created': '2025-10-27T03:15:50', 'last-modified': '2025-10-27T03:15:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432903'}
2025-10-27 03:15:50,814 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-27 03:15:50,821 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3553330153138505433', 'uuid-lslong': '13166930765834568450'}, 'enable': 'true', 'created': '2025-10-27T03:15:50', 'last-modified': '2025-10-27T03:15:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.438856'}
2025-10-27 03:15:50,827 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-27 03:15:50,834 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72614848', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3553330153138505433', 'uuid-lslong': '13166930765834568450'}, 'enable': 'true', 'created': '2025-10-27T03:15:50', 'last-modified': '2025-10-27T03:15:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458901'}
2025-10-27 03:15:50,841 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-27 03:15:50,841 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-99689163 passed
2025-10-27 03:15:50,841 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-99689163 in API_Server ======
2025-10-27 03:15:50,841 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:50,850 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:50,865 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:50,970 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/314ff6f2-ab81-46d9-b6ba-55c527bb4302
2025-10-27 03:15:50,986 - DEBUG - =>VN ctest-trans_right_vn-99689163 has no policy to be verified
2025-10-27 03:15:50,986 - DEBUG - Verifying the vn in opserver
2025-10-27 03:15:50,986 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163 virtual network link through opserver 10.0.0.249
2025-10-27 03:15:50,986 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:15:51,005 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163?flat'}
2025-10-27 03:15:51,005 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163 virtual network link through opserver 10.0.0.33
2025-10-27 03:15:51,005 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:15:51,019 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163?flat'}
2025-10-27 03:15:51,019 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163 virtual network link through opserver 10.0.0.129
2025-10-27 03:15:51,019 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:15:51,037 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163?flat'}
2025-10-27 03:15:51,037 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72614848:ctest-trans_right_vn-99689163 is found in opserver
2025-10-27 03:15:51,066 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:15:51,079 - DEBUG - VRF ids for VN ctest-trans_right_vn-99689163: {}
2025-10-27 03:15:52,175 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-2)
2025-10-27 03:15:53,577 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-1)
2025-10-27 03:15:53,577 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-59146128']
2025-10-27 03:15:53,868 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-59146128'] created succesfully
2025-10-27 03:15:54,210 - DEBUG - Image by name tiny_trans_fw not found
2025-10-27 03:15:54,211 - DEBUG - Installing image tiny_trans_fw
2025-10-27 03:15:54,211 - 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-10-27 03:15:54,211 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-27 03:15:54,211 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-27 03:15:54,259 - 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-10-27 03:15:54,316 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-27 03:15:54,316 - DEBUG - Unzip image
2025-10-27 03:15:54,316 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-27 03:15:54,358 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-27 03:15:54,358 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-59146128']
2025-10-27 03:15:54,453 - INFO - Service template: ['default-domain', 'ctest-service_template_1-59146128'] deleted successfully.
2025-10-27 03:15:54,453 - INFO - Skip interface_detach for VM ctest-right_vm-11987130 in state BUILD
2025-10-27 03:15:54,453 - INFO - Deleting VM ctest-right_vm-11987130
2025-10-27 03:15:54,453 - ERROR - VM ctest-right_vm-11987130 has failed to come up
2025-10-27 03:15:54,453 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:54,454 - ERROR - VM ctest-right_vm-11987130 has failed to come up
2025-10-27 03:15:54,454 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:54,553 - INFO - Skip interface_detach for VM ctest-left_vm-43239621 in state BUILD
2025-10-27 03:15:54,553 - INFO - Deleting VM ctest-left_vm-43239621
2025-10-27 03:15:54,553 - ERROR - VM ctest-left_vm-43239621 has failed to come up
2025-10-27 03:15:54,554 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:54,554 - ERROR - VM ctest-left_vm-43239621 has failed to come up
2025-10-27 03:15:54,554 - ERROR - Nova failed to get host of the VM
2025-10-27 03:15:54,653 - INFO - Deleting VN ctest-trans_right_vn-99689163
2025-10-27 03:15:54,815 - DEBUG - Response for deleting network ()
2025-10-27 03:15:54,815 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bbb2a433-cff0-4cec-97c4-cb5225ef3f47
2025-10-27 03:15:54,822 - DEBUG - Response Code: 404
2025-10-27 03:15:54,822 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:54,830 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:54,858 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:54,966 - INFO - Validated that VN ctest-trans_right_vn-99689163 is not found in API Server
2025-10-27 03:15:54,985 - DEBUG - VN ctest-trans_right_vn-99689163 is not present in Agent 10.0.0.130
2025-10-27 03:15:55,000 - DEBUG - VN ctest-trans_right_vn-99689163 is not present in Agent 10.0.0.131
2025-10-27 03:15:55,001 - INFO - Validated that VN ctest-trans_right_vn-99689163 is not in any agent
2025-10-27 03:15:55,047 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-99689163 info
2025-10-27 03:15:55,048 - INFO - Deleting VN ctest-trans_left_vn-60779179
2025-10-27 03:15:55,243 - DEBUG - Response for deleting network ()
2025-10-27 03:15:55,243 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b98883f9-3c2a-47ed-bb07-aac547378175
2025-10-27 03:15:55,249 - DEBUG - Response Code: 404
2025-10-27 03:15:55,249 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:55,260 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:55,278 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:55,419 - INFO - Validated that VN ctest-trans_left_vn-60779179 is not found in API Server
2025-10-27 03:15:55,435 - DEBUG - VN ctest-trans_left_vn-60779179 is not present in Agent 10.0.0.130
2025-10-27 03:15:55,446 - DEBUG - VN ctest-trans_left_vn-60779179 is not present in Agent 10.0.0.131
2025-10-27 03:15:55,446 - INFO - Validated that VN ctest-trans_left_vn-60779179 is not in any agent
2025-10-27 03:15:55,525 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-60779179 info
2025-10-27 03:15:55,528 - INFO - Deleting VN ctest-right_vn-44130426
2025-10-27 03:15:55,579 - DEBUG - VN 1bca59f6-12ef-4e2d-8ab1-352b538a3728 still in use: Unable to complete operation on network 1bca59f6-12ef-4e2d-8ab1-352b538a3728. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9340cb17-51ff-4f16-ba46-86697aec3df1']
2025-10-27 03:15:55,579 - WARNING - Deleting VN ctest-right_vn-44130426 failed..Will retry
2025-10-27 03:15:57,727 - DEBUG - Response for deleting network ()
2025-10-27 03:15:57,727 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8dfed8c8-0776-4c10-a2a0-164830fd46dd
2025-10-27 03:15:57,734 - DEBUG - Response Code: 404
2025-10-27 03:15:57,734 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:57,741 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:57,760 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:57,848 - INFO - Validated that VN ctest-right_vn-44130426 is not found in API Server
2025-10-27 03:15:57,860 - DEBUG - VN ctest-right_vn-44130426 is not present in Agent 10.0.0.130
2025-10-27 03:15:57,873 - DEBUG - VN ctest-right_vn-44130426 is not present in Agent 10.0.0.131
2025-10-27 03:15:57,873 - INFO - Validated that VN ctest-right_vn-44130426 is not in any agent
2025-10-27 03:15:57,904 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-44130426 info
2025-10-27 03:15:57,904 - INFO - Deleting VN ctest-left_vn-35100004
2025-10-27 03:15:58,048 - DEBUG - Response for deleting network ()
2025-10-27 03:15:58,048 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9a931fb-2e5d-42ea-9814-c555aaf14284
2025-10-27 03:15:58,054 - DEBUG - Response Code: 404
2025-10-27 03:15:58,054 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:58,065 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:58,078 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:58,170 - INFO - Validated that VN ctest-left_vn-35100004 is not found in API Server
2025-10-27 03:15:58,185 - DEBUG - VN ctest-left_vn-35100004 is not present in Agent 10.0.0.130
2025-10-27 03:15:58,196 - DEBUG - VN ctest-left_vn-35100004 is not present in Agent 10.0.0.131
2025-10-27 03:15:58,196 - INFO - Validated that VN ctest-left_vn-35100004 is not in any agent
2025-10-27 03:15:58,230 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-35100004 info
2025-10-27 03:15:58,230 - INFO - Deleting VN ctest-mgmt_vn-68025332
2025-10-27 03:15:58,398 - DEBUG - Response for deleting network ()
2025-10-27 03:15:58,398 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2471a8f8-9ba8-4bd6-afb9-29fa7462d3b4
2025-10-27 03:15:58,403 - DEBUG - Response Code: 404
2025-10-27 03:15:58,404 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:15:58,418 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:15:58,475 - DEBUG - Requesting: http://10.0.0.249:8082/project/6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:15:58,562 - INFO - Validated that VN ctest-mgmt_vn-68025332 is not found in API Server
2025-10-27 03:15:58,575 - DEBUG - VN ctest-mgmt_vn-68025332 is not present in Agent 10.0.0.130
2025-10-27 03:15:58,590 - DEBUG - VN ctest-mgmt_vn-68025332 is not present in Agent 10.0.0.131
2025-10-27 03:15:58,590 - INFO - Validated that VN ctest-mgmt_vn-68025332 is not in any agent
2025-10-27 03:15:58,624 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-68025332 info
2025-10-27 03:15:59,925 - DEBUG - No XMPP flaps were noticed during the test
2025-10-27 03:15:59,925 - INFO -
2025-10-27 03:15:59,925 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-10-27 03:15:59,925 - INFO - --------------------------------------------------------------------------------
2025-10-27 03:16:00,744 - INFO - Deleted project: ctest-TestSvcRegr-72614848, ID : 6578e223-771e-4dec-9bd4-874625a3ec7c
2025-10-27 03:16:00,997 - INFO - Domain Default found not creating
2025-10-27 03:16:01,480 - INFO - Project ctest-TestSvcRegr-26100695 not found, creating it
2025-10-27 03:16:02,069 - INFO - Created Project:ctest-TestSvcRegr-26100695, ID : 399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:03,624 - INFO - ================================================================================
2025-10-27 03:16:03,624 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-27 03:16:04,901 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.131': {'10.20.0.17': '0', '10.20.0.14': '0'}} with
2025-10-27 03:16:04,901 - INFO - Initial checks done. Running the testcase now
2025-10-27 03:16:04,901 - INFO -
2025-10-27 03:16:05,693 - DEBUG - Response for create_network : {'network': {'id': '2313b5d4-baab-459e-a09e-702863636bfa', 'name': 'ctest-mgmt_vn-86926993', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'project_id': '399001f8a7694ff992d686249e57ee45', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26100695', 'ctest-mgmt_vn-86926993'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:16:05,978 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd17df713-3a33-4ef8-9b03-a3b6fdb101d5', 'name': '', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'network_id': '2313b5d4-baab-459e-a09e-702863636bfa', 'ip_version': 4, 'cidr': '211.143.19.192/26', 'allocation_pools': [{'start': '211.143.19.194', 'end': '211.143.19.254'}], 'gateway_ip': '211.143.19.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '211.143.19.194', 'tags': [], 'project_id': '399001f8a7694ff992d686249e57ee45'}}
2025-10-27 03:16:06,001 - INFO - Created VN ctest-mgmt_vn-86926993
2025-10-27 03:16:06,018 - DEBUG - VN ctest-mgmt_vn-86926993 UUID is 2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,099 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:06,402 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:06,432 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:06,449 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:06,538 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,554 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,565 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/08c0647b-4cc8-47c8-838a-7198e58e52e2
2025-10-27 03:16:06,574 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/08c0647b-4cc8-47c8-838a-7198e58e52e2
2025-10-27 03:16:06,583 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/42cf7e99-26f1-4cb8-b070-093df92fbb2b
2025-10-27 03:16:06,591 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:06,591 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,602 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/08c0647b-4cc8-47c8-838a-7198e58e52e2
2025-10-27 03:16:06,626 - INFO - Verified VN network id 15 for VN 2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,627 - INFO - Verifications in API Server for VN ctest-mgmt_vn-86926993 passed
2025-10-27 03:16:06,627 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,642 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/08c0647b-4cc8-47c8-838a-7198e58e52e2
2025-10-27 03:16:06,652 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/08c0647b-4cc8-47c8-838a-7198e58e52e2
2025-10-27 03:16:06,660 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/42cf7e99-26f1-4cb8-b070-093df92fbb2b
2025-10-27 03:16:06,675 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2527563741154461086', 'uuid-lslong': '11573811411157216250'}, 'enable': 'true', 'created': '2025-10-27T03:16:05', 'last-modified': '2025-10-27T03:16:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.751487'}
2025-10-27 03:16:06,683 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:06,689 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2527563741154461086', 'uuid-lslong': '11573811411157216250'}, 'enable': 'true', 'created': '2025-10-27T03:16:05', 'last-modified': '2025-10-27T03:16:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.769779'}
2025-10-27 03:16:06,695 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:06,702 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2527563741154461086', 'uuid-lslong': '11573811411157216250'}, 'enable': 'true', 'created': '2025-10-27T03:16:05', 'last-modified': '2025-10-27T03:16:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.782788'}
2025-10-27 03:16:06,710 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:06,710 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-86926993 passed
2025-10-27 03:16:06,710 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-86926993 in API_Server ======
2025-10-27 03:16:06,710 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:06,717 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:06,730 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:06,832 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2313b5d4-baab-459e-a09e-702863636bfa
2025-10-27 03:16:06,846 - DEBUG - =>VN ctest-mgmt_vn-86926993 has no policy to be verified
2025-10-27 03:16:06,846 - DEBUG - Verifying the vn in opserver
2025-10-27 03:16:06,847 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993 virtual network link through opserver 10.0.0.249
2025-10-27 03:16:06,847 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:07,150 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:07,217 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993?flat'}
2025-10-27 03:16:07,217 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993 virtual network link through opserver 10.0.0.33
2025-10-27 03:16:07,217 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:07,527 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:07,614 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993?flat'}
2025-10-27 03:16:07,614 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993 virtual network link through opserver 10.0.0.129
2025-10-27 03:16:07,614 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:07,930 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:07,995 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993?flat'}
2025-10-27 03:16:07,995 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-86926993 is found in opserver
2025-10-27 03:16:08,028 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:16:08,042 - DEBUG - VRF ids for VN ctest-mgmt_vn-86926993: {}
2025-10-27 03:16:08,364 - DEBUG - Response for create_network : {'network': {'id': '7fac1b52-7d35-4cf8-b57f-7b5bafcd9940', 'name': 'service-left-vn-37285002', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'project_id': '399001f8a7694ff992d686249e57ee45', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26100695', 'service-left-vn-37285002'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:16:08,621 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd197e066-909f-40e5-8d30-d81c7af56ad4', 'name': '', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'network_id': '7fac1b52-7d35-4cf8-b57f-7b5bafcd9940', 'ip_version': 4, 'cidr': '48.173.161.64/26', 'allocation_pools': [{'start': '48.173.161.66', 'end': '48.173.161.126'}], 'gateway_ip': '48.173.161.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '48.173.161.66', 'tags': [], 'project_id': '399001f8a7694ff992d686249e57ee45'}}
2025-10-27 03:16:08,644 - INFO - Created VN service-left-vn-37285002
2025-10-27 03:16:08,657 - DEBUG - VN service-left-vn-37285002 UUID is 7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:08,744 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:08,752 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:08,764 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:08,857 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:08,877 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:08,888 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/1d505174-d991-41b1-bad1-3141284cd56c
2025-10-27 03:16:08,900 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/1d505174-d991-41b1-bad1-3141284cd56c
2025-10-27 03:16:08,911 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/923ac2e2-34a8-4ab8-92de-f5d0f899b7d8
2025-10-27 03:16:08,920 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:08,920 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:08,935 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/1d505174-d991-41b1-bad1-3141284cd56c
2025-10-27 03:16:08,959 - INFO - Verified VN network id 16 for VN 7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:08,960 - INFO - Verifications in API Server for VN service-left-vn-37285002 passed
2025-10-27 03:16:08,961 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:08,977 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/1d505174-d991-41b1-bad1-3141284cd56c
2025-10-27 03:16:08,987 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/1d505174-d991-41b1-bad1-3141284cd56c
2025-10-27 03:16:08,995 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/923ac2e2-34a8-4ab8-92de-f5d0f899b7d8
2025-10-27 03:16:09,010 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9199758179912994040', 'uuid-lslong': '13078307476628937024'}, 'enable': 'true', 'created': '2025-10-27T03:16:08', 'last-modified': '2025-10-27T03:16:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425445'}
2025-10-27 03:16:09,016 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:09,022 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9199758179912994040', 'uuid-lslong': '13078307476628937024'}, 'enable': 'true', 'created': '2025-10-27T03:16:08', 'last-modified': '2025-10-27T03:16:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439737'}
2025-10-27 03:16:09,028 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:09,035 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9199758179912994040', 'uuid-lslong': '13078307476628937024'}, 'enable': 'true', 'created': '2025-10-27T03:16:08', 'last-modified': '2025-10-27T03:16:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453279'}
2025-10-27 03:16:09,042 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:09,042 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-37285002 passed
2025-10-27 03:16:09,042 - DEBUG - ====Verifying policy data for service-left-vn-37285002 in API_Server ======
2025-10-27 03:16:09,042 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:09,050 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:09,065 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:09,165 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/7fac1b52-7d35-4cf8-b57f-7b5bafcd9940
2025-10-27 03:16:09,179 - DEBUG - =>VN service-left-vn-37285002 has no policy to be verified
2025-10-27 03:16:09,179 - DEBUG - Verifying the vn in opserver
2025-10-27 03:16:09,179 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002 virtual network link through opserver 10.0.0.249
2025-10-27 03:16:09,179 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:09,255 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002?flat'}
2025-10-27 03:16:09,255 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002 virtual network link through opserver 10.0.0.33
2025-10-27 03:16:09,255 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:09,327 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002?flat'}
2025-10-27 03:16:09,327 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002 virtual network link through opserver 10.0.0.129
2025-10-27 03:16:09,327 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:09,398 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002?flat'}
2025-10-27 03:16:09,398 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26100695:service-left-vn-37285002 is found in opserver
2025-10-27 03:16:09,428 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:16:09,440 - DEBUG - VRF ids for VN service-left-vn-37285002: {}
2025-10-27 03:16:09,729 - DEBUG - Response for create_network : {'network': {'id': 'bc552971-18b5-45cf-88c7-708d9fd8211d', 'name': 'service-right-vn-96166705', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'project_id': '399001f8a7694ff992d686249e57ee45', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26100695', 'service-right-vn-96166705'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:16:09,936 - DEBUG - Response for create_subnet : {'subnet': {'id': '366c0a4d-587b-40b6-abe7-9e9d5973fa42', 'name': '', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'network_id': 'bc552971-18b5-45cf-88c7-708d9fd8211d', 'ip_version': 4, 'cidr': '21.32.110.64/26', 'allocation_pools': [{'start': '21.32.110.66', 'end': '21.32.110.126'}], 'gateway_ip': '21.32.110.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '21.32.110.66', 'tags': [], 'project_id': '399001f8a7694ff992d686249e57ee45'}}
2025-10-27 03:16:09,957 - INFO - Created VN service-right-vn-96166705
2025-10-27 03:16:09,969 - DEBUG - VN service-right-vn-96166705 UUID is bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,043 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:10,049 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:10,061 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:10,160 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,172 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,181 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f682fc6e-2a01-4edf-901f-6f38e6682b2e
2025-10-27 03:16:10,189 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f682fc6e-2a01-4edf-901f-6f38e6682b2e
2025-10-27 03:16:10,197 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/4a7f89d0-5888-4d19-b71c-41314998d0f4
2025-10-27 03:16:10,204 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:10,204 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,214 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f682fc6e-2a01-4edf-901f-6f38e6682b2e
2025-10-27 03:16:10,232 - INFO - Verified VN network id 17 for VN bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,232 - INFO - Verifications in API Server for VN service-right-vn-96166705 passed
2025-10-27 03:16:10,232 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,246 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f682fc6e-2a01-4edf-901f-6f38e6682b2e
2025-10-27 03:16:10,256 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f682fc6e-2a01-4edf-901f-6f38e6682b2e
2025-10-27 03:16:10,264 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/4a7f89d0-5888-4d19-b71c-41314998d0f4
2025-10-27 03:16:10,277 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13570798617873434063', 'uuid-lslong': '9855970063098061085'}, 'enable': 'true', 'created': '2025-10-27T03:16:09', 'last-modified': '2025-10-27T03:16:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397163'}
2025-10-27 03:16:10,283 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:10,288 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13570798617873434063', 'uuid-lslong': '9855970063098061085'}, 'enable': 'true', 'created': '2025-10-27T03:16:09', 'last-modified': '2025-10-27T03:16:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.401403'}
2025-10-27 03:16:10,294 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:10,300 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13570798617873434063', 'uuid-lslong': '9855970063098061085'}, 'enable': 'true', 'created': '2025-10-27T03:16:09', 'last-modified': '2025-10-27T03:16:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420562'}
2025-10-27 03:16:10,307 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:10,307 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-96166705 passed
2025-10-27 03:16:10,307 - DEBUG - ====Verifying policy data for service-right-vn-96166705 in API_Server ======
2025-10-27 03:16:10,307 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:10,314 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:10,326 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:10,419 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/bc552971-18b5-45cf-88c7-708d9fd8211d
2025-10-27 03:16:10,431 - DEBUG - =>VN service-right-vn-96166705 has no policy to be verified
2025-10-27 03:16:10,431 - DEBUG - Verifying the vn in opserver
2025-10-27 03:16:10,431 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705 virtual network link through opserver 10.0.0.249
2025-10-27 03:16:10,431 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:10,491 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705?flat'}
2025-10-27 03:16:10,491 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705 virtual network link through opserver 10.0.0.33
2025-10-27 03:16:10,491 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:10,548 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705?flat'}
2025-10-27 03:16:10,548 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705 virtual network link through opserver 10.0.0.129
2025-10-27 03:16:10,548 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:10,625 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705?flat'}
2025-10-27 03:16:10,625 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26100695:service-right-vn-96166705 is found in opserver
2025-10-27 03:16:10,649 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:16:10,660 - DEBUG - VRF ids for VN service-right-vn-96166705: {}
2025-10-27 03:16:10,720 - DEBUG - Services list from nova: [, , , ]
2025-10-27 03:16:11,781 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-2)
2025-10-27 03:16:13,072 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-1)
2025-10-27 03:16:13,072 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-94264317']
2025-10-27 03:16:13,397 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-94264317'] created succesfully
2025-10-27 03:16:13,876 - DEBUG - Image by name tiny_nat_fw not found
2025-10-27 03:16:13,876 - DEBUG - Installing image tiny_nat_fw
2025-10-27 03:16:13,876 - 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-10-27 03:16:13,876 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:16:13,877 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:16:13,877 - DEBUG - Unzip image
2025-10-27 03:16:13,877 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:16:13,924 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-27 03:16:13,925 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-94264317']
2025-10-27 03:16:14,029 - INFO - Service template: ['default-domain', 'ctest-service_template_1-94264317'] deleted successfully.
2025-10-27 03:16:14,029 - INFO - Skip interface_detach for VM ctest-right_vm-04013495 in state BUILD
2025-10-27 03:16:14,030 - INFO - Deleting VM ctest-right_vm-04013495
2025-10-27 03:16:14,081 - ERROR - VM ctest-right_vm-04013495 has failed to come up
2025-10-27 03:16:14,081 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:14,081 - ERROR - VM ctest-right_vm-04013495 has failed to come up
2025-10-27 03:16:14,081 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:14,220 - INFO - Skip interface_detach for VM ctest-left_vm-55023454 in state BUILD
2025-10-27 03:16:14,220 - INFO - Deleting VM ctest-left_vm-55023454
2025-10-27 03:16:14,220 - ERROR - VM ctest-left_vm-55023454 has failed to come up
2025-10-27 03:16:14,220 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:14,220 - ERROR - VM ctest-left_vm-55023454 has failed to come up
2025-10-27 03:16:14,220 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:14,324 - INFO - Deleting VN service-right-vn-96166705
2025-10-27 03:16:14,457 - DEBUG - VN bc552971-18b5-45cf-88c7-708d9fd8211d still in use: Unable to complete operation on network bc552971-18b5-45cf-88c7-708d9fd8211d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-58ffb675-55e4-4047-9942-268b4a48fce9']
2025-10-27 03:16:14,457 - WARNING - Deleting VN service-right-vn-96166705 failed..Will retry
2025-10-27 03:16:16,738 - DEBUG - Response for deleting network ()
2025-10-27 03:16:16,739 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f682fc6e-2a01-4edf-901f-6f38e6682b2e
2025-10-27 03:16:16,757 - DEBUG - Response Code: 404
2025-10-27 03:16:16,758 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:16,769 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:16,788 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:16,911 - INFO - Validated that VN service-right-vn-96166705 is not found in API Server
2025-10-27 03:16:16,930 - DEBUG - VN service-right-vn-96166705 is not present in Agent 10.0.0.130
2025-10-27 03:16:16,946 - DEBUG - VN service-right-vn-96166705 is not present in Agent 10.0.0.131
2025-10-27 03:16:16,946 - INFO - Validated that VN service-right-vn-96166705 is not in any agent
2025-10-27 03:16:16,985 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-96166705 info
2025-10-27 03:16:16,985 - INFO - Deleting VN service-left-vn-37285002
2025-10-27 03:16:17,185 - DEBUG - Response for deleting network ()
2025-10-27 03:16:17,185 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/1d505174-d991-41b1-bad1-3141284cd56c
2025-10-27 03:16:17,196 - DEBUG - Response Code: 404
2025-10-27 03:16:17,196 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:17,212 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:17,240 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:17,375 - INFO - Validated that VN service-left-vn-37285002 is not found in API Server
2025-10-27 03:16:17,390 - DEBUG - VN service-left-vn-37285002 is not present in Agent 10.0.0.130
2025-10-27 03:16:17,403 - DEBUG - VN service-left-vn-37285002 is not present in Agent 10.0.0.131
2025-10-27 03:16:17,403 - INFO - Validated that VN service-left-vn-37285002 is not in any agent
2025-10-27 03:16:17,449 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-37285002 info
2025-10-27 03:16:17,449 - INFO - Deleting VN ctest-mgmt_vn-86926993
2025-10-27 03:16:17,613 - DEBUG - Response for deleting network ()
2025-10-27 03:16:17,613 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/08c0647b-4cc8-47c8-838a-7198e58e52e2
2025-10-27 03:16:17,625 - DEBUG - Response Code: 404
2025-10-27 03:16:17,626 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:17,651 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:17,685 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:17,784 - INFO - Validated that VN ctest-mgmt_vn-86926993 is not found in API Server
2025-10-27 03:16:17,796 - DEBUG - VN ctest-mgmt_vn-86926993 is not present in Agent 10.0.0.130
2025-10-27 03:16:17,811 - DEBUG - VN ctest-mgmt_vn-86926993 is not present in Agent 10.0.0.131
2025-10-27 03:16:17,811 - INFO - Validated that VN ctest-mgmt_vn-86926993 is not in any agent
2025-10-27 03:16:17,851 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-86926993 info
2025-10-27 03:16:19,134 - DEBUG - No XMPP flaps were noticed during the test
2025-10-27 03:16:19,134 - INFO -
2025-10-27 03:16:19,135 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-10-27 03:16:19,135 - INFO - --------------------------------------------------------------------------------
2025-10-27 03:16:19,138 - INFO - ================================================================================
2025-10-27 03:16:19,138 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-27 03:16:20,401 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.131': {'10.20.0.17': '0', '10.20.0.14': '0'}} with
2025-10-27 03:16:20,401 - INFO - Initial checks done. Running the testcase now
2025-10-27 03:16:20,401 - INFO -
2025-10-27 03:16:20,669 - DEBUG - Response for create_network : {'network': {'id': '97b4fb09-48af-4b90-b315-676398ae7b79', 'name': 'ctest-mgmt_vn-89039991', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'project_id': '399001f8a7694ff992d686249e57ee45', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26100695', 'ctest-mgmt_vn-89039991'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:16:20,891 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd2e3b4cf-2850-48cf-bc98-7a08dd7e8681', 'name': '', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'network_id': '97b4fb09-48af-4b90-b315-676398ae7b79', 'ip_version': 4, 'cidr': '30.163.213.64/26', 'allocation_pools': [{'start': '30.163.213.66', 'end': '30.163.213.126'}], 'gateway_ip': '30.163.213.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '30.163.213.66', 'tags': [], 'project_id': '399001f8a7694ff992d686249e57ee45'}}
2025-10-27 03:16:20,919 - INFO - Created VN ctest-mgmt_vn-89039991
2025-10-27 03:16:20,933 - DEBUG - VN ctest-mgmt_vn-89039991 UUID is 97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,030 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:21,038 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:21,055 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:21,173 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,194 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,246 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f15c789a-475f-44d5-b44e-5999835d9218
2025-10-27 03:16:21,261 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f15c789a-475f-44d5-b44e-5999835d9218
2025-10-27 03:16:21,271 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/f8e0a953-a7ea-4e65-9569-ac23c5479b09
2025-10-27 03:16:21,281 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:21,281 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,292 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f15c789a-475f-44d5-b44e-5999835d9218
2025-10-27 03:16:21,313 - INFO - Verified VN network id 15 for VN 97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,313 - INFO - Verifications in API Server for VN ctest-mgmt_vn-89039991 passed
2025-10-27 03:16:21,313 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,330 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f15c789a-475f-44d5-b44e-5999835d9218
2025-10-27 03:16:21,339 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f15c789a-475f-44d5-b44e-5999835d9218
2025-10-27 03:16:21,348 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/f8e0a953-a7ea-4e65-9569-ac23c5479b09
2025-10-27 03:16:21,362 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10931638212827761552', 'uuid-lslong': '12904333984761019257'}, 'enable': 'true', 'created': '2025-10-27T03:16:20', 'last-modified': '2025-10-27T03:16:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.513947'}
2025-10-27 03:16:21,368 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:21,373 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10931638212827761552', 'uuid-lslong': '12904333984761019257'}, 'enable': 'true', 'created': '2025-10-27T03:16:20', 'last-modified': '2025-10-27T03:16:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.524515'}
2025-10-27 03:16:21,378 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:21,385 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10931638212827761552', 'uuid-lslong': '12904333984761019257'}, 'enable': 'true', 'created': '2025-10-27T03:16:20', 'last-modified': '2025-10-27T03:16:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.538347'}
2025-10-27 03:16:21,391 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-27 03:16:21,392 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-89039991 passed
2025-10-27 03:16:21,392 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-89039991 in API_Server ======
2025-10-27 03:16:21,392 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:21,399 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:21,411 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:21,504 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97b4fb09-48af-4b90-b315-676398ae7b79
2025-10-27 03:16:21,522 - DEBUG - =>VN ctest-mgmt_vn-89039991 has no policy to be verified
2025-10-27 03:16:21,522 - DEBUG - Verifying the vn in opserver
2025-10-27 03:16:21,522 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991 virtual network link through opserver 10.0.0.249
2025-10-27 03:16:21,522 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:21,535 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991?flat'}
2025-10-27 03:16:21,535 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991 virtual network link through opserver 10.0.0.33
2025-10-27 03:16:21,535 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:21,547 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991?flat'}
2025-10-27 03:16:21,548 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991 virtual network link through opserver 10.0.0.129
2025-10-27 03:16:21,548 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:21,560 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991?flat'}
2025-10-27 03:16:21,560 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26100695:ctest-mgmt_vn-89039991 is found in opserver
2025-10-27 03:16:21,581 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:16:21,592 - DEBUG - VRF ids for VN ctest-mgmt_vn-89039991: {}
2025-10-27 03:16:21,842 - DEBUG - Response for create_network : {'network': {'id': 'a356182e-beda-4e8d-823e-94ff40242a92', 'name': 'ctest-left_vn-07087555', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'project_id': '399001f8a7694ff992d686249e57ee45', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26100695', 'ctest-left_vn-07087555'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:16:22,082 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dbfa0bc1-348d-4338-bcdf-94701be43876', 'name': '', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'network_id': 'a356182e-beda-4e8d-823e-94ff40242a92', 'ip_version': 4, 'cidr': '132.158.250.64/26', 'allocation_pools': [{'start': '132.158.250.66', 'end': '132.158.250.126'}], 'gateway_ip': '132.158.250.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '132.158.250.66', 'tags': [], 'project_id': '399001f8a7694ff992d686249e57ee45'}}
2025-10-27 03:16:22,104 - INFO - Created VN ctest-left_vn-07087555
2025-10-27 03:16:22,120 - DEBUG - VN ctest-left_vn-07087555 UUID is a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,197 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:22,204 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:22,218 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:22,315 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,334 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,344 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a37b3f43-fa97-44e1-9de9-dc6e1c2fdf2d
2025-10-27 03:16:22,353 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a37b3f43-fa97-44e1-9de9-dc6e1c2fdf2d
2025-10-27 03:16:22,361 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/049827f8-4ce8-4413-8067-9403574998bf
2025-10-27 03:16:22,368 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:22,368 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,377 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a37b3f43-fa97-44e1-9de9-dc6e1c2fdf2d
2025-10-27 03:16:22,397 - INFO - Verified VN network id 16 for VN a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,397 - INFO - Verifications in API Server for VN ctest-left_vn-07087555 passed
2025-10-27 03:16:22,397 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,408 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a37b3f43-fa97-44e1-9de9-dc6e1c2fdf2d
2025-10-27 03:16:22,418 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a37b3f43-fa97-44e1-9de9-dc6e1c2fdf2d
2025-10-27 03:16:22,427 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/049827f8-4ce8-4413-8067-9403574998bf
2025-10-27 03:16:22,440 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11769621265228910221', 'uuid-lslong': '9385102497500375698'}, 'enable': 'true', 'created': '2025-10-27T03:16:21', 'last-modified': '2025-10-27T03:16:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413961'}
2025-10-27 03:16:22,445 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:22,451 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11769621265228910221', 'uuid-lslong': '9385102497500375698'}, 'enable': 'true', 'created': '2025-10-27T03:16:21', 'last-modified': '2025-10-27T03:16:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426147'}
2025-10-27 03:16:22,458 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:22,464 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11769621265228910221', 'uuid-lslong': '9385102497500375698'}, 'enable': 'true', 'created': '2025-10-27T03:16:21', 'last-modified': '2025-10-27T03:16:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436950'}
2025-10-27 03:16:22,470 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-27 03:16:22,470 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-07087555 passed
2025-10-27 03:16:22,471 - DEBUG - ====Verifying policy data for ctest-left_vn-07087555 in API_Server ======
2025-10-27 03:16:22,471 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:22,478 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:22,493 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:22,580 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/a356182e-beda-4e8d-823e-94ff40242a92
2025-10-27 03:16:22,593 - DEBUG - =>VN ctest-left_vn-07087555 has no policy to be verified
2025-10-27 03:16:22,593 - DEBUG - Verifying the vn in opserver
2025-10-27 03:16:22,593 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555 virtual network link through opserver 10.0.0.249
2025-10-27 03:16:22,593 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:22,607 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555?flat'}
2025-10-27 03:16:22,607 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555 virtual network link through opserver 10.0.0.33
2025-10-27 03:16:22,607 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:22,621 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555?flat'}
2025-10-27 03:16:22,622 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555 virtual network link through opserver 10.0.0.129
2025-10-27 03:16:22,622 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:22,637 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555?flat'}
2025-10-27 03:16:22,637 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26100695:ctest-left_vn-07087555 is found in opserver
2025-10-27 03:16:22,661 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:16:22,672 - DEBUG - VRF ids for VN ctest-left_vn-07087555: {}
2025-10-27 03:16:22,942 - DEBUG - Response for create_network : {'network': {'id': '9a96330e-14a9-4c1b-8018-409e896a5685', 'name': 'ctest-right_vn-40620865', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'project_id': '399001f8a7694ff992d686249e57ee45', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26100695', 'ctest-right_vn-40620865'], 'port_security_enabled': True, 'description': ''}}
2025-10-27 03:16:23,161 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd5aa8c19-5f0b-478b-84d8-a690ed82f080', 'name': '', 'tenant_id': '399001f8a7694ff992d686249e57ee45', 'network_id': '9a96330e-14a9-4c1b-8018-409e896a5685', 'ip_version': 4, 'cidr': '115.131.60.128/26', 'allocation_pools': [{'start': '115.131.60.130', 'end': '115.131.60.190'}], 'gateway_ip': '115.131.60.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '115.131.60.130', 'tags': [], 'project_id': '399001f8a7694ff992d686249e57ee45'}}
2025-10-27 03:16:23,184 - INFO - Created VN ctest-right_vn-40620865
2025-10-27 03:16:23,195 - DEBUG - VN ctest-right_vn-40620865 UUID is 9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,273 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:23,279 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:23,291 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:23,380 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,392 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,403 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d063a555-be2d-4d35-9673-eb0ac3190f3e
2025-10-27 03:16:23,412 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d063a555-be2d-4d35-9673-eb0ac3190f3e
2025-10-27 03:16:23,420 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/19f1a7f4-6a96-4ffd-a251-d72b31c42366
2025-10-27 03:16:23,427 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:23,427 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,439 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d063a555-be2d-4d35-9673-eb0ac3190f3e
2025-10-27 03:16:23,456 - INFO - Verified VN network id 17 for VN 9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,457 - INFO - Verifications in API Server for VN ctest-right_vn-40620865 passed
2025-10-27 03:16:23,457 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,466 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d063a555-be2d-4d35-9673-eb0ac3190f3e
2025-10-27 03:16:23,474 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d063a555-be2d-4d35-9673-eb0ac3190f3e
2025-10-27 03:16:23,483 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/19f1a7f4-6a96-4ffd-a251-d72b31c42366
2025-10-27 03:16:23,500 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11139146863916698651', 'uuid-lslong': '9230198485950289541'}, 'enable': 'true', 'created': '2025-10-27T03:16:22', 'last-modified': '2025-10-27T03:16:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395209'}
2025-10-27 03:16:23,510 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:23,517 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11139146863916698651', 'uuid-lslong': '9230198485950289541'}, 'enable': 'true', 'created': '2025-10-27T03:16:22', 'last-modified': '2025-10-27T03:16:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418955'}
2025-10-27 03:16:23,525 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:23,532 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26100695', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11139146863916698651', 'uuid-lslong': '9230198485950289541'}, 'enable': 'true', 'created': '2025-10-27T03:16:22', 'last-modified': '2025-10-27T03:16:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433638'}
2025-10-27 03:16:23,538 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-27 03:16:23,538 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-40620865 passed
2025-10-27 03:16:23,538 - DEBUG - ====Verifying policy data for ctest-right_vn-40620865 in API_Server ======
2025-10-27 03:16:23,539 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:23,545 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:23,560 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:23,662 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9a96330e-14a9-4c1b-8018-409e896a5685
2025-10-27 03:16:23,674 - DEBUG - =>VN ctest-right_vn-40620865 has no policy to be verified
2025-10-27 03:16:23,674 - DEBUG - Verifying the vn in opserver
2025-10-27 03:16:23,674 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865 virtual network link through opserver 10.0.0.249
2025-10-27 03:16:23,674 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-27 03:16:23,690 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865?flat'}
2025-10-27 03:16:23,690 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865 virtual network link through opserver 10.0.0.33
2025-10-27 03:16:23,690 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-27 03:16:23,709 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865?flat'}
2025-10-27 03:16:23,709 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865 virtual network link through opserver 10.0.0.129
2025-10-27 03:16:23,709 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2025-10-27 03:16:23,723 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865?flat'}
2025-10-27 03:16:23,723 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26100695:ctest-right_vn-40620865 is found in opserver
2025-10-27 03:16:23,752 - DEBUG - Do not have enough data to verify VN in agent
2025-10-27 03:16:23,766 - DEBUG - VRF ids for VN ctest-right_vn-40620865: {}
2025-10-27 03:16:24,846 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-1)
2025-10-27 03:16:26,163 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4489-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4489-2)
2025-10-27 03:16:26,163 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-33709224']
2025-10-27 03:16:26,480 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-33709224'] created succesfully
2025-10-27 03:16:26,899 - DEBUG - Image by name tiny_nat_fw not found
2025-10-27 03:16:26,899 - DEBUG - Installing image tiny_nat_fw
2025-10-27 03:16:26,899 - 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-10-27 03:16:26,900 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:16:26,904 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:16:26,905 - DEBUG - Unzip image
2025-10-27 03:16:26,905 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-27 03:16:26,930 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-27 03:16:26,931 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-33709224']
2025-10-27 03:16:27,021 - INFO - Service template: ['default-domain', 'ctest-service_template_1-33709224'] deleted successfully.
2025-10-27 03:16:27,021 - INFO - Skip interface_detach for VM ctest-right_vm-35103538 in state BUILD
2025-10-27 03:16:27,022 - INFO - Deleting VM ctest-right_vm-35103538
2025-10-27 03:16:27,022 - ERROR - VM ctest-right_vm-35103538 has failed to come up
2025-10-27 03:16:27,022 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:27,022 - ERROR - VM ctest-right_vm-35103538 has failed to come up
2025-10-27 03:16:27,022 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:27,112 - INFO - Skip interface_detach for VM ctest-left_vm-55027238 in state BUILD
2025-10-27 03:16:27,113 - INFO - Deleting VM ctest-left_vm-55027238
2025-10-27 03:16:27,114 - ERROR - VM ctest-left_vm-55027238 has failed to come up
2025-10-27 03:16:27,115 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:27,115 - ERROR - VM ctest-left_vm-55027238 has failed to come up
2025-10-27 03:16:27,116 - ERROR - Nova failed to get host of the VM
2025-10-27 03:16:27,249 - INFO - Deleting VN ctest-right_vn-40620865
2025-10-27 03:16:27,301 - DEBUG - VN 9a96330e-14a9-4c1b-8018-409e896a5685 still in use: Unable to complete operation on network 9a96330e-14a9-4c1b-8018-409e896a5685. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9f1d5f99-efad-40a3-86b6-3a940170b7b8']
2025-10-27 03:16:27,301 - WARNING - Deleting VN ctest-right_vn-40620865 failed..Will retry
2025-10-27 03:16:29,461 - DEBUG - Response for deleting network ()
2025-10-27 03:16:29,461 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d063a555-be2d-4d35-9673-eb0ac3190f3e
2025-10-27 03:16:29,469 - DEBUG - Response Code: 404
2025-10-27 03:16:29,469 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:29,476 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:29,505 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:29,622 - INFO - Validated that VN ctest-right_vn-40620865 is not found in API Server
2025-10-27 03:16:29,634 - DEBUG - VN ctest-right_vn-40620865 is not present in Agent 10.0.0.130
2025-10-27 03:16:29,648 - DEBUG - VN ctest-right_vn-40620865 is not present in Agent 10.0.0.131
2025-10-27 03:16:29,648 - INFO - Validated that VN ctest-right_vn-40620865 is not in any agent
2025-10-27 03:16:29,695 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-40620865 info
2025-10-27 03:16:29,695 - INFO - Deleting VN ctest-left_vn-07087555
2025-10-27 03:16:29,859 - DEBUG - Response for deleting network ()
2025-10-27 03:16:29,859 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a37b3f43-fa97-44e1-9de9-dc6e1c2fdf2d
2025-10-27 03:16:29,865 - DEBUG - Response Code: 404
2025-10-27 03:16:29,865 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:29,871 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:29,882 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:29,997 - INFO - Validated that VN ctest-left_vn-07087555 is not found in API Server
2025-10-27 03:16:30,009 - DEBUG - VN ctest-left_vn-07087555 is not present in Agent 10.0.0.130
2025-10-27 03:16:30,023 - DEBUG - VN ctest-left_vn-07087555 is not present in Agent 10.0.0.131
2025-10-27 03:16:30,023 - INFO - Validated that VN ctest-left_vn-07087555 is not in any agent
2025-10-27 03:16:30,057 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-07087555 info
2025-10-27 03:16:30,057 - INFO - Deleting VN ctest-mgmt_vn-89039991
2025-10-27 03:16:30,191 - DEBUG - Response for deleting network ()
2025-10-27 03:16:30,191 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f15c789a-475f-44d5-b44e-5999835d9218
2025-10-27 03:16:30,198 - DEBUG - Response Code: 404
2025-10-27 03:16:30,198 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-27 03:16:30,207 - DEBUG - Requesting: http://10.0.0.249:8082/domain/8213efff-6224-4943-8f79-85cc33013f73
2025-10-27 03:16:30,224 - DEBUG - Requesting: http://10.0.0.249:8082/project/399001f8-a769-4ff9-92d6-86249e57ee45
2025-10-27 03:16:30,320 - INFO - Validated that VN ctest-mgmt_vn-89039991 is not found in API Server
2025-10-27 03:16:30,330 - DEBUG - VN ctest-mgmt_vn-89039991 is not present in Agent 10.0.0.130
2025-10-27 03:16:30,341 - DEBUG - VN ctest-mgmt_vn-89039991 is not present in Agent 10.0.0.131
2025-10-27 03:16:30,341 - INFO - Validated that VN ctest-mgmt_vn-89039991 is not in any agent
2025-10-27 03:16:30,377 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-89039991 info
2025-10-27 03:16:31,659 - DEBUG - No XMPP flaps were noticed during the test
2025-10-27 03:16:31,659 - INFO -
2025-10-27 03:16:31,659 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-10-27 03:16:31,659 - INFO - --------------------------------------------------------------------------------
2025-10-27 03:16:32,392 - INFO - Deleted project: ctest-TestSvcRegr-26100695, ID : 399001f8-a769-4ff9-92d6-86249e57ee45