]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-1)
2025-08-10 21:34:48,843 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-41611555']
2025-08-10 21:34:49,129 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-41611555'] created succesfully
2025-08-10 21:34:49,470 - DEBUG - Image by name tiny_nat_fw not found
2025-08-10 21:34:49,470 - DEBUG - Installing image tiny_nat_fw
2025-08-10 21:34:49,470 - 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-08-10 21:34:49,470 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-10 21:34:49,470 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-10 21:34:49,485 - 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-08-10 21:34:49,810 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:34:49,810 - DEBUG - Unzip image
2025-08-10 21:34:49,810 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:34:49,826 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-10 21:34:49,827 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-41611555']
2025-08-10 21:34:49,908 - INFO - Service template: ['default-domain', 'ctest-service_template_1-41611555'] deleted successfully.
2025-08-10 21:34:49,908 - INFO - Skip interface_detach for VM ctest-right_vm-09811294 in state BUILD
2025-08-10 21:34:49,908 - INFO - Deleting VM ctest-right_vm-09811294
2025-08-10 21:34:49,931 - ERROR - VM ctest-right_vm-09811294 has failed to come up
2025-08-10 21:34:49,932 - ERROR - Nova failed to get host of the VM
2025-08-10 21:34:49,932 - ERROR - VM ctest-right_vm-09811294 has failed to come up
2025-08-10 21:34:49,932 - ERROR - Nova failed to get host of the VM
2025-08-10 21:34:49,992 - INFO - Skip interface_detach for VM ctest-left_vm-06873426 in state BUILD
2025-08-10 21:34:49,992 - INFO - Deleting VM ctest-left_vm-06873426
2025-08-10 21:34:49,992 - ERROR - VM ctest-left_vm-06873426 has failed to come up
2025-08-10 21:34:49,992 - ERROR - Nova failed to get host of the VM
2025-08-10 21:34:49,992 - ERROR - VM ctest-left_vm-06873426 has failed to come up
2025-08-10 21:34:49,992 - ERROR - Nova failed to get host of the VM
2025-08-10 21:34:50,047 - INFO - Deleting VN ctest-right_vn-93625886
2025-08-10 21:34:50,084 - DEBUG - VN dbfeb3e4-1eb9-4ab8-b601-2dd8a0c1b6e2 still in use: Unable to complete operation on network dbfeb3e4-1eb9-4ab8-b601-2dd8a0c1b6e2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-91997d3a-3539-471f-a6e2-714e87205bb3']
2025-08-10 21:34:50,084 - WARNING - Deleting VN ctest-right_vn-93625886 failed..Will retry
2025-08-10 21:34:52,222 - DEBUG - Response for deleting network ()
2025-08-10 21:34:52,222 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/23d76bf8-5b50-41f8-87bf-8df4c9b3f7f1
2025-08-10 21:34:52,228 - DEBUG - Response Code: 404
2025-08-10 21:34:52,228 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:52,238 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:52,253 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:52,338 - INFO - Validated that VN ctest-right_vn-93625886 is not found in API Server
2025-08-10 21:34:52,349 - DEBUG - VN ctest-right_vn-93625886 is not present in Agent 10.0.0.254
2025-08-10 21:34:52,359 - DEBUG - VN ctest-right_vn-93625886 is not present in Agent 10.0.0.50
2025-08-10 21:34:52,359 - INFO - Validated that VN ctest-right_vn-93625886 is not in any agent
2025-08-10 21:34:52,388 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-93625886 info
2025-08-10 21:34:52,388 - INFO - Deleting VN ctest-left_vn-79338545
2025-08-10 21:34:52,517 - DEBUG - Response for deleting network ()
2025-08-10 21:34:52,517 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/3bbb2986-2619-4fde-b3b6-9680f4ed6763
2025-08-10 21:34:52,526 - DEBUG - Response Code: 404
2025-08-10 21:34:52,526 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:52,531 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:52,542 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:52,635 - INFO - Validated that VN ctest-left_vn-79338545 is not found in API Server
2025-08-10 21:34:52,649 - DEBUG - VN ctest-left_vn-79338545 is not present in Agent 10.0.0.254
2025-08-10 21:34:52,659 - DEBUG - VN ctest-left_vn-79338545 is not present in Agent 10.0.0.50
2025-08-10 21:34:52,659 - INFO - Validated that VN ctest-left_vn-79338545 is not in any agent
2025-08-10 21:34:52,691 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-79338545 info
2025-08-10 21:34:52,691 - INFO - Deleting VN ctest-mgmt_vn-59881526
2025-08-10 21:34:52,829 - DEBUG - Response for deleting network ()
2025-08-10 21:34:52,829 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9957384d-833c-42a9-8a90-605a3ce35c19
2025-08-10 21:34:52,837 - DEBUG - Response Code: 404
2025-08-10 21:34:52,837 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:52,843 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:52,897 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:52,989 - INFO - Validated that VN ctest-mgmt_vn-59881526 is not found in API Server
2025-08-10 21:34:53,006 - DEBUG - VN ctest-mgmt_vn-59881526 is not present in Agent 10.0.0.254
2025-08-10 21:34:53,017 - DEBUG - VN ctest-mgmt_vn-59881526 is not present in Agent 10.0.0.50
2025-08-10 21:34:53,018 - INFO - Validated that VN ctest-mgmt_vn-59881526 is not in any agent
2025-08-10 21:34:53,051 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-59881526 info
2025-08-10 21:34:53,052 - INFO - Deleting VM ctest-vm-in-third-vn-03504650
2025-08-10 21:34:53,117 - INFO - Deleting VN ctest-third-vn-62882966
2025-08-10 21:34:53,152 - DEBUG - VN aa83ee4e-8c8f-4c91-aed7-94fb9012d23d still in use: Unable to complete operation on network aa83ee4e-8c8f-4c91-aed7-94fb9012d23d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7b9b319b-4650-4080-b94e-b04cabc2a312']
2025-08-10 21:34:53,152 - WARNING - Deleting VN ctest-third-vn-62882966 failed..Will retry
2025-08-10 21:34:55,284 - DEBUG - Response for deleting network ()
2025-08-10 21:34:56,535 - DEBUG - No XMPP flaps were noticed during the test
2025-08-10 21:34:56,535 - INFO -
2025-08-10 21:34:56,535 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-08-10 21:34:56,535 - INFO - --------------------------------------------------------------------------------
2025-08-10 21:34:56,539 - INFO - ================================================================================
2025-08-10 21:34:56,539 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-10 21:34:57,786 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.50': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-08-10 21:34:57,786 - INFO - Initial checks done. Running the testcase now
2025-08-10 21:34:57,786 - INFO -
2025-08-10 21:34:58,010 - DEBUG - Response for create_network : {'network': {'id': 'c1b48f07-ea2c-4e18-94be-f77902fe55f6', 'name': 'ctest-mgmt_vn-92009070', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'project_id': '6f83f284f79745cd9ad75ca8a77660d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86937522', 'ctest-mgmt_vn-92009070'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:34:58,223 - DEBUG - Response for create_subnet : {'subnet': {'id': '120e447d-cc5a-4d33-9c64-a6e8410f1981', 'name': '', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'network_id': 'c1b48f07-ea2c-4e18-94be-f77902fe55f6', 'ip_version': 4, 'cidr': '118.245.30.128/26', 'allocation_pools': [{'start': '118.245.30.130', 'end': '118.245.30.190'}], 'gateway_ip': '118.245.30.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '118.245.30.130', 'tags': [], 'project_id': '6f83f284f79745cd9ad75ca8a77660d3'}}
2025-08-10 21:34:58,244 - INFO - Created VN ctest-mgmt_vn-92009070
2025-08-10 21:34:58,257 - DEBUG - VN ctest-mgmt_vn-92009070 UUID is c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,314 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:58,319 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:58,330 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:58,407 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,418 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,427 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/5d789933-771f-4387-81ec-db51e763eaa2
2025-08-10 21:34:58,435 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/5d789933-771f-4387-81ec-db51e763eaa2
2025-08-10 21:34:58,442 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/61f5726e-b8f7-43d3-8f76-65207c21d4a9
2025-08-10 21:34:58,448 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:34:58,448 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,456 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/5d789933-771f-4387-81ec-db51e763eaa2
2025-08-10 21:34:58,469 - INFO - Verified VN network id 11 for VN c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,469 - INFO - Verifications in API Server for VN ctest-mgmt_vn-92009070 passed
2025-08-10 21:34:58,469 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,478 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/5d789933-771f-4387-81ec-db51e763eaa2
2025-08-10 21:34:58,485 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/5d789933-771f-4387-81ec-db51e763eaa2
2025-08-10 21:34:58,491 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/61f5726e-b8f7-43d3-8f76-65207c21d4a9
2025-08-10 21:34:58,503 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13957938409284324888', 'uuid-lslong': '10718276262301685238'}, 'enable': 'true', 'created': '2025-08-10T21:34:57', 'last-modified': '2025-08-10T21:34:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.327967'}
2025-08-10 21:34:58,508 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:34:58,514 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13957938409284324888', 'uuid-lslong': '10718276262301685238'}, 'enable': 'true', 'created': '2025-08-10T21:34:57', 'last-modified': '2025-08-10T21:34:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.339191'}
2025-08-10 21:34:58,519 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:34:58,524 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13957938409284324888', 'uuid-lslong': '10718276262301685238'}, 'enable': 'true', 'created': '2025-08-10T21:34:57', 'last-modified': '2025-08-10T21:34:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.348248'}
2025-08-10 21:34:58,530 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:34:58,531 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-92009070 passed
2025-08-10 21:34:58,531 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-92009070 in API_Server ======
2025-08-10 21:34:58,531 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:58,536 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:58,548 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:58,635 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c1b48f07-ea2c-4e18-94be-f77902fe55f6
2025-08-10 21:34:58,648 - DEBUG - =>VN ctest-mgmt_vn-92009070 has no policy to be verified
2025-08-10 21:34:58,648 - DEBUG - Verifying the vn in opserver
2025-08-10 21:34:58,648 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070 virtual network link through opserver 10.0.0.48
2025-08-10 21:34:58,648 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:34:58,660 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070?flat'}
2025-08-10 21:34:58,660 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070 virtual network link through opserver 10.0.0.38
2025-08-10 21:34:58,660 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:34:58,671 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070?flat'}
2025-08-10 21:34:58,671 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070 virtual network link through opserver 10.0.0.49
2025-08-10 21:34:58,671 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:34:58,681 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070?flat'}
2025-08-10 21:34:58,681 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86937522:ctest-mgmt_vn-92009070 is found in opserver
2025-08-10 21:34:58,702 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:34:58,711 - DEBUG - VRF ids for VN ctest-mgmt_vn-92009070: {}
2025-08-10 21:34:58,959 - DEBUG - Response for create_network : {'network': {'id': 'd76fbdc7-1e83-4228-a299-8f3043acef3f', 'name': 'ctest-left_vn-81829639', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'project_id': '6f83f284f79745cd9ad75ca8a77660d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86937522', 'ctest-left_vn-81829639'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:34:59,197 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c73997c4-ea80-451c-b38d-69a8184a4086', 'name': '', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'network_id': 'd76fbdc7-1e83-4228-a299-8f3043acef3f', 'ip_version': 4, 'cidr': '22.190.77.192/26', 'allocation_pools': [{'start': '22.190.77.194', 'end': '22.190.77.254'}], 'gateway_ip': '22.190.77.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22.190.77.194', 'tags': [], 'project_id': '6f83f284f79745cd9ad75ca8a77660d3'}}
2025-08-10 21:34:59,215 - INFO - Created VN ctest-left_vn-81829639
2025-08-10 21:34:59,225 - DEBUG - VN ctest-left_vn-81829639 UUID is d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,279 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:59,285 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:59,295 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:59,391 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,406 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,424 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/b2d53567-fdf1-465f-b1c3-02e0eff46aee
2025-08-10 21:34:59,431 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/b2d53567-fdf1-465f-b1c3-02e0eff46aee
2025-08-10 21:34:59,440 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/867359e4-4eb2-4ee8-9c46-b2d2e97e9c13
2025-08-10 21:34:59,446 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:34:59,446 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,456 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/b2d53567-fdf1-465f-b1c3-02e0eff46aee
2025-08-10 21:34:59,471 - INFO - Verified VN network id 12 for VN d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,471 - INFO - Verifications in API Server for VN ctest-left_vn-81829639 passed
2025-08-10 21:34:59,471 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,479 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/b2d53567-fdf1-465f-b1c3-02e0eff46aee
2025-08-10 21:34:59,486 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/b2d53567-fdf1-465f-b1c3-02e0eff46aee
2025-08-10 21:34:59,493 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/867359e4-4eb2-4ee8-9c46-b2d2e97e9c13
2025-08-10 21:34:59,505 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15523835103477449256', 'uuid-lslong': '11716553343037665087'}, 'enable': 'true', 'created': '2025-08-10T21:34:58', 'last-modified': '2025-08-10T21:34:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.349537'}
2025-08-10 21:34:59,511 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:34:59,516 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15523835103477449256', 'uuid-lslong': '11716553343037665087'}, 'enable': 'true', 'created': '2025-08-10T21:34:58', 'last-modified': '2025-08-10T21:34:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.358172'}
2025-08-10 21:34:59,522 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:34:59,530 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15523835103477449256', 'uuid-lslong': '11716553343037665087'}, 'enable': 'true', 'created': '2025-08-10T21:34:58', 'last-modified': '2025-08-10T21:34:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.371127'}
2025-08-10 21:34:59,539 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:34:59,539 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-81829639 passed
2025-08-10 21:34:59,539 - DEBUG - ====Verifying policy data for ctest-left_vn-81829639 in API_Server ======
2025-08-10 21:34:59,539 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:34:59,547 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:34:59,563 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:34:59,650 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d76fbdc7-1e83-4228-a299-8f3043acef3f
2025-08-10 21:34:59,662 - DEBUG - =>VN ctest-left_vn-81829639 has no policy to be verified
2025-08-10 21:34:59,662 - DEBUG - Verifying the vn in opserver
2025-08-10 21:34:59,662 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639 virtual network link through opserver 10.0.0.48
2025-08-10 21:34:59,662 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:34:59,675 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639?flat'}
2025-08-10 21:34:59,675 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639 virtual network link through opserver 10.0.0.38
2025-08-10 21:34:59,675 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:34:59,686 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639?flat'}
2025-08-10 21:34:59,686 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639 virtual network link through opserver 10.0.0.49
2025-08-10 21:34:59,686 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:34:59,697 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639?flat'}
2025-08-10 21:34:59,697 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86937522:ctest-left_vn-81829639 is found in opserver
2025-08-10 21:34:59,718 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:34:59,727 - DEBUG - VRF ids for VN ctest-left_vn-81829639: {}
2025-08-10 21:34:59,945 - DEBUG - Response for create_network : {'network': {'id': 'd3e0c34a-0fc6-40cd-9664-6809d1f30fe7', 'name': 'ctest-right_vn-55900551', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'project_id': '6f83f284f79745cd9ad75ca8a77660d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86937522', 'ctest-right_vn-55900551'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:00,187 - DEBUG - Response for create_subnet : {'subnet': {'id': '13e6d45a-9b6a-42ab-94f9-fd893cdc3269', 'name': '', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'network_id': 'd3e0c34a-0fc6-40cd-9664-6809d1f30fe7', 'ip_version': 4, 'cidr': '75.142.7.0/26', 'allocation_pools': [{'start': '75.142.7.2', 'end': '75.142.7.62'}], 'gateway_ip': '75.142.7.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '75.142.7.2', 'tags': [], 'project_id': '6f83f284f79745cd9ad75ca8a77660d3'}}
2025-08-10 21:35:00,205 - INFO - Created VN ctest-right_vn-55900551
2025-08-10 21:35:00,217 - DEBUG - VN ctest-right_vn-55900551 UUID is d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,275 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:00,280 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:00,291 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:00,375 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,396 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,411 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4acbfef6-a9a5-4721-876e-59d63c6ace91
2025-08-10 21:35:00,418 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4acbfef6-a9a5-4721-876e-59d63c6ace91
2025-08-10 21:35:00,425 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/b763469e-a078-4281-8a84-0ac5ce9d62a7
2025-08-10 21:35:00,433 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:00,433 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,445 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4acbfef6-a9a5-4721-876e-59d63c6ace91
2025-08-10 21:35:00,459 - INFO - Verified VN network id 13 for VN d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,459 - INFO - Verifications in API Server for VN ctest-right_vn-55900551 passed
2025-08-10 21:35:00,460 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,469 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4acbfef6-a9a5-4721-876e-59d63c6ace91
2025-08-10 21:35:00,476 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4acbfef6-a9a5-4721-876e-59d63c6ace91
2025-08-10 21:35:00,484 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/b763469e-a078-4281-8a84-0ac5ce9d62a7
2025-08-10 21:35:00,497 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15267417459645628621', 'uuid-lslong': '10836900994746617831'}, 'enable': 'true', 'created': '2025-08-10T21:34:59', 'last-modified': '2025-08-10T21:35:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.360484'}
2025-08-10 21:35:00,503 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:00,508 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15267417459645628621', 'uuid-lslong': '10836900994746617831'}, 'enable': 'true', 'created': '2025-08-10T21:34:59', 'last-modified': '2025-08-10T21:35:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378147'}
2025-08-10 21:35:00,515 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:00,521 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15267417459645628621', 'uuid-lslong': '10836900994746617831'}, 'enable': 'true', 'created': '2025-08-10T21:34:59', 'last-modified': '2025-08-10T21:35:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387423'}
2025-08-10 21:35:00,527 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:00,527 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-55900551 passed
2025-08-10 21:35:00,527 - DEBUG - ====Verifying policy data for ctest-right_vn-55900551 in API_Server ======
2025-08-10 21:35:00,527 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:00,533 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:00,547 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:00,635 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d3e0c34a-0fc6-40cd-9664-6809d1f30fe7
2025-08-10 21:35:00,646 - DEBUG - =>VN ctest-right_vn-55900551 has no policy to be verified
2025-08-10 21:35:00,646 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:00,646 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:00,646 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:00,659 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551?flat'}
2025-08-10 21:35:00,659 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:00,659 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:00,670 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551?flat'}
2025-08-10 21:35:00,670 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:00,670 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:00,681 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551?flat'}
2025-08-10 21:35:00,681 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86937522:ctest-right_vn-55900551 is found in opserver
2025-08-10 21:35:00,705 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:00,714 - DEBUG - VRF ids for VN ctest-right_vn-55900551: {}
2025-08-10 21:35:00,958 - DEBUG - Response for create_network : {'network': {'id': '58971780-95c5-4fa4-9767-fd49436d32c4', 'name': 'ctest-trans_left_vn-09784960', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'project_id': '6f83f284f79745cd9ad75ca8a77660d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86937522', 'ctest-trans_left_vn-09784960'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:01,182 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e440e672-63c7-4186-8a93-09d53f0458df', 'name': '', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'network_id': '58971780-95c5-4fa4-9767-fd49436d32c4', 'ip_version': 4, 'cidr': '63.184.213.192/26', 'allocation_pools': [{'start': '63.184.213.194', 'end': '63.184.213.254'}], 'gateway_ip': '63.184.213.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.184.213.194', 'tags': [], 'project_id': '6f83f284f79745cd9ad75ca8a77660d3'}}
2025-08-10 21:35:01,201 - INFO - Created VN ctest-trans_left_vn-09784960
2025-08-10 21:35:01,211 - DEBUG - VN ctest-trans_left_vn-09784960 UUID is 58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,273 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:01,281 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:01,296 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:01,386 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,397 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,406 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e3851f13-b95b-4a4d-9dce-05f5d79f6b3f
2025-08-10 21:35:01,416 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e3851f13-b95b-4a4d-9dce-05f5d79f6b3f
2025-08-10 21:35:01,423 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/f9c92315-f042-487a-80ad-aa78c36ea9cf
2025-08-10 21:35:01,429 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-10 21:35:01,429 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,438 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e3851f13-b95b-4a4d-9dce-05f5d79f6b3f
2025-08-10 21:35:01,452 - INFO - Verified VN network id 14 for VN 58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,452 - INFO - Verifications in API Server for VN ctest-trans_left_vn-09784960 passed
2025-08-10 21:35:01,452 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,461 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e3851f13-b95b-4a4d-9dce-05f5d79f6b3f
2025-08-10 21:35:01,470 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e3851f13-b95b-4a4d-9dce-05f5d79f6b3f
2025-08-10 21:35:01,480 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/f9c92315-f042-487a-80ad-aa78c36ea9cf
2025-08-10 21:35:01,492 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6383596837856956324', 'uuid-lslong': '10909967113433985732'}, 'enable': 'true', 'created': '2025-08-10T21:35:00', 'last-modified': '2025-08-10T21:35:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.366163'}
2025-08-10 21:35:01,498 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-10 21:35:01,504 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6383596837856956324', 'uuid-lslong': '10909967113433985732'}, 'enable': 'true', 'created': '2025-08-10T21:35:00', 'last-modified': '2025-08-10T21:35:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378204'}
2025-08-10 21:35:01,510 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-10 21:35:01,515 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6383596837856956324', 'uuid-lslong': '10909967113433985732'}, 'enable': 'true', 'created': '2025-08-10T21:35:00', 'last-modified': '2025-08-10T21:35:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387600'}
2025-08-10 21:35:01,521 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-10 21:35:01,521 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-09784960 passed
2025-08-10 21:35:01,521 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-09784960 in API_Server ======
2025-08-10 21:35:01,521 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:01,527 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:01,538 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:01,635 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/58971780-95c5-4fa4-9767-fd49436d32c4
2025-08-10 21:35:01,645 - DEBUG - =>VN ctest-trans_left_vn-09784960 has no policy to be verified
2025-08-10 21:35:01,645 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:01,645 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:01,645 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:01,657 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960?flat'}
2025-08-10 21:35:01,657 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:01,657 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:01,669 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960?flat'}
2025-08-10 21:35:01,669 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:01,669 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:01,680 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960?flat'}
2025-08-10 21:35:01,680 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86937522:ctest-trans_left_vn-09784960 is found in opserver
2025-08-10 21:35:01,701 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:01,710 - DEBUG - VRF ids for VN ctest-trans_left_vn-09784960: {}
2025-08-10 21:35:01,940 - DEBUG - Response for create_network : {'network': {'id': 'e7013a1b-ec19-4c3c-83aa-c639ce51ef49', 'name': 'ctest-trans_right_vn-20903283', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'project_id': '6f83f284f79745cd9ad75ca8a77660d3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86937522', 'ctest-trans_right_vn-20903283'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:02,160 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c8e6bbd4-b95d-48b4-8edb-53c27e8872b5', 'name': '', 'tenant_id': '6f83f284f79745cd9ad75ca8a77660d3', 'network_id': 'e7013a1b-ec19-4c3c-83aa-c639ce51ef49', 'ip_version': 4, 'cidr': '174.81.198.0/26', 'allocation_pools': [{'start': '174.81.198.2', 'end': '174.81.198.62'}], 'gateway_ip': '174.81.198.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.81.198.2', 'tags': [], 'project_id': '6f83f284f79745cd9ad75ca8a77660d3'}}
2025-08-10 21:35:02,179 - INFO - Created VN ctest-trans_right_vn-20903283
2025-08-10 21:35:02,190 - DEBUG - VN ctest-trans_right_vn-20903283 UUID is e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,247 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:02,252 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:02,263 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:02,354 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,364 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,373 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ff2c4b5e-8f2b-4ee9-b4bf-c99f2065ca84
2025-08-10 21:35:02,379 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ff2c4b5e-8f2b-4ee9-b4bf-c99f2065ca84
2025-08-10 21:35:02,385 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/57dcae15-3cce-456d-8d96-8a987495d93f
2025-08-10 21:35:02,391 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-10 21:35:02,391 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,400 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ff2c4b5e-8f2b-4ee9-b4bf-c99f2065ca84
2025-08-10 21:35:02,414 - INFO - Verified VN network id 15 for VN e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,414 - INFO - Verifications in API Server for VN ctest-trans_right_vn-20903283 passed
2025-08-10 21:35:02,415 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,423 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ff2c4b5e-8f2b-4ee9-b4bf-c99f2065ca84
2025-08-10 21:35:02,429 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ff2c4b5e-8f2b-4ee9-b4bf-c99f2065ca84
2025-08-10 21:35:02,435 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/57dcae15-3cce-456d-8d96-8a987495d93f
2025-08-10 21:35:02,447 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16645649589337672764', 'uuid-lslong': '9487613516586282825'}, 'enable': 'true', 'created': '2025-08-10T21:35:01', 'last-modified': '2025-08-10T21:35:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.339994'}
2025-08-10 21:35:02,453 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-10 21:35:02,458 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16645649589337672764', 'uuid-lslong': '9487613516586282825'}, 'enable': 'true', 'created': '2025-08-10T21:35:01', 'last-modified': '2025-08-10T21:35:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.355720'}
2025-08-10 21:35:02,464 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-10 21:35:02,469 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86937522', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16645649589337672764', 'uuid-lslong': '9487613516586282825'}, 'enable': 'true', 'created': '2025-08-10T21:35:01', 'last-modified': '2025-08-10T21:35:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369102'}
2025-08-10 21:35:02,476 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-10 21:35:02,476 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-20903283 passed
2025-08-10 21:35:02,477 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-20903283 in API_Server ======
2025-08-10 21:35:02,477 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:02,483 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:02,496 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:02,591 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e7013a1b-ec19-4c3c-83aa-c639ce51ef49
2025-08-10 21:35:02,602 - DEBUG - =>VN ctest-trans_right_vn-20903283 has no policy to be verified
2025-08-10 21:35:02,603 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:02,603 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:02,603 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:02,617 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283?flat'}
2025-08-10 21:35:02,617 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:02,617 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:02,629 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283?flat'}
2025-08-10 21:35:02,629 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:02,629 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:02,640 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283?flat'}
2025-08-10 21:35:02,640 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86937522:ctest-trans_right_vn-20903283 is found in opserver
2025-08-10 21:35:02,661 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:02,671 - DEBUG - VRF ids for VN ctest-trans_right_vn-20903283: {}
2025-08-10 21:35:03,656 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-1)
2025-08-10 21:35:04,835 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-2)
2025-08-10 21:35:04,835 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-73424885']
2025-08-10 21:35:05,137 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-73424885'] created succesfully
2025-08-10 21:35:05,481 - DEBUG - Image by name tiny_trans_fw not found
2025-08-10 21:35:05,481 - DEBUG - Installing image tiny_trans_fw
2025-08-10 21:35:05,481 - 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-08-10 21:35:05,481 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-10 21:35:05,481 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-10 21:35:05,513 - 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-08-10 21:35:05,562 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-10 21:35:05,563 - DEBUG - Unzip image
2025-08-10 21:35:05,563 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-10 21:35:05,583 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-10 21:35:05,583 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-73424885']
2025-08-10 21:35:05,704 - INFO - Service template: ['default-domain', 'ctest-service_template_1-73424885'] deleted successfully.
2025-08-10 21:35:05,705 - INFO - Skip interface_detach for VM ctest-right_vm-76455158 in state BUILD
2025-08-10 21:35:05,705 - INFO - Deleting VM ctest-right_vm-76455158
2025-08-10 21:35:05,705 - ERROR - VM ctest-right_vm-76455158 has failed to come up
2025-08-10 21:35:05,705 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:05,705 - ERROR - VM ctest-right_vm-76455158 has failed to come up
2025-08-10 21:35:05,705 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:05,822 - INFO - Skip interface_detach for VM ctest-left_vm-90691040 in state BUILD
2025-08-10 21:35:05,823 - INFO - Deleting VM ctest-left_vm-90691040
2025-08-10 21:35:05,823 - ERROR - VM ctest-left_vm-90691040 has failed to come up
2025-08-10 21:35:05,823 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:05,823 - ERROR - VM ctest-left_vm-90691040 has failed to come up
2025-08-10 21:35:05,823 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:05,896 - INFO - Deleting VN ctest-trans_right_vn-20903283
2025-08-10 21:35:06,044 - DEBUG - Response for deleting network ()
2025-08-10 21:35:06,044 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ff2c4b5e-8f2b-4ee9-b4bf-c99f2065ca84
2025-08-10 21:35:06,053 - DEBUG - Response Code: 404
2025-08-10 21:35:06,054 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:06,068 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:06,085 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:06,175 - INFO - Validated that VN ctest-trans_right_vn-20903283 is not found in API Server
2025-08-10 21:35:06,186 - DEBUG - VN ctest-trans_right_vn-20903283 is not present in Agent 10.0.0.254
2025-08-10 21:35:06,196 - DEBUG - VN ctest-trans_right_vn-20903283 is not present in Agent 10.0.0.50
2025-08-10 21:35:06,196 - INFO - Validated that VN ctest-trans_right_vn-20903283 is not in any agent
2025-08-10 21:35:06,230 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-20903283 info
2025-08-10 21:35:06,230 - INFO - Deleting VN ctest-trans_left_vn-09784960
2025-08-10 21:35:06,370 - DEBUG - Response for deleting network ()
2025-08-10 21:35:06,370 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e3851f13-b95b-4a4d-9dce-05f5d79f6b3f
2025-08-10 21:35:06,380 - DEBUG - Response Code: 404
2025-08-10 21:35:06,381 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:06,387 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:06,446 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:06,561 - INFO - Validated that VN ctest-trans_left_vn-09784960 is not found in API Server
2025-08-10 21:35:06,573 - DEBUG - VN ctest-trans_left_vn-09784960 is not present in Agent 10.0.0.254
2025-08-10 21:35:06,594 - DEBUG - VN ctest-trans_left_vn-09784960 is not present in Agent 10.0.0.50
2025-08-10 21:35:06,594 - INFO - Validated that VN ctest-trans_left_vn-09784960 is not in any agent
2025-08-10 21:35:06,648 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-09784960 info
2025-08-10 21:35:06,648 - INFO - Deleting VN ctest-right_vn-55900551
2025-08-10 21:35:06,692 - DEBUG - VN d3e0c34a-0fc6-40cd-9664-6809d1f30fe7 still in use: Unable to complete operation on network d3e0c34a-0fc6-40cd-9664-6809d1f30fe7. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-706b3060-d2bf-4d15-91f8-2d7ffd527f87']
2025-08-10 21:35:06,693 - WARNING - Deleting VN ctest-right_vn-55900551 failed..Will retry
2025-08-10 21:35:08,826 - DEBUG - Response for deleting network ()
2025-08-10 21:35:08,826 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4acbfef6-a9a5-4721-876e-59d63c6ace91
2025-08-10 21:35:08,836 - DEBUG - Response Code: 404
2025-08-10 21:35:08,836 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:08,842 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:08,852 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:08,938 - INFO - Validated that VN ctest-right_vn-55900551 is not found in API Server
2025-08-10 21:35:08,949 - DEBUG - VN ctest-right_vn-55900551 is not present in Agent 10.0.0.254
2025-08-10 21:35:08,959 - DEBUG - VN ctest-right_vn-55900551 is not present in Agent 10.0.0.50
2025-08-10 21:35:08,959 - INFO - Validated that VN ctest-right_vn-55900551 is not in any agent
2025-08-10 21:35:08,987 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-55900551 info
2025-08-10 21:35:08,987 - INFO - Deleting VN ctest-left_vn-81829639
2025-08-10 21:35:09,114 - DEBUG - Response for deleting network ()
2025-08-10 21:35:09,114 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/b2d53567-fdf1-465f-b1c3-02e0eff46aee
2025-08-10 21:35:09,123 - DEBUG - Response Code: 404
2025-08-10 21:35:09,123 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:09,132 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:09,143 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:09,225 - INFO - Validated that VN ctest-left_vn-81829639 is not found in API Server
2025-08-10 21:35:09,236 - DEBUG - VN ctest-left_vn-81829639 is not present in Agent 10.0.0.254
2025-08-10 21:35:09,245 - DEBUG - VN ctest-left_vn-81829639 is not present in Agent 10.0.0.50
2025-08-10 21:35:09,245 - INFO - Validated that VN ctest-left_vn-81829639 is not in any agent
2025-08-10 21:35:09,275 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-81829639 info
2025-08-10 21:35:09,275 - INFO - Deleting VN ctest-mgmt_vn-92009070
2025-08-10 21:35:09,394 - DEBUG - Response for deleting network ()
2025-08-10 21:35:09,395 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/5d789933-771f-4387-81ec-db51e763eaa2
2025-08-10 21:35:09,400 - DEBUG - Response Code: 404
2025-08-10 21:35:09,400 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:09,405 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:09,465 - DEBUG - Requesting: http://10.0.0.48:8082/project/6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:09,544 - INFO - Validated that VN ctest-mgmt_vn-92009070 is not found in API Server
2025-08-10 21:35:09,558 - DEBUG - VN ctest-mgmt_vn-92009070 is not present in Agent 10.0.0.254
2025-08-10 21:35:09,568 - DEBUG - VN ctest-mgmt_vn-92009070 is not present in Agent 10.0.0.50
2025-08-10 21:35:09,568 - INFO - Validated that VN ctest-mgmt_vn-92009070 is not in any agent
2025-08-10 21:35:09,601 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-92009070 info
2025-08-10 21:35:10,854 - DEBUG - No XMPP flaps were noticed during the test
2025-08-10 21:35:10,854 - INFO -
2025-08-10 21:35:10,854 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:14]
2025-08-10 21:35:10,854 - INFO - --------------------------------------------------------------------------------
2025-08-10 21:35:11,533 - INFO - Deleted project: ctest-TestSvcRegr-86937522, ID : 6f83f284-f797-45cd-9ad7-5ca8a77660d3
2025-08-10 21:35:11,705 - INFO - Domain Default found not creating
2025-08-10 21:35:11,842 - INFO - Project ctest-TestSvcRegr-07540207 not found, creating it
2025-08-10 21:35:12,324 - INFO - Created Project:ctest-TestSvcRegr-07540207, ID : eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:13,735 - INFO - ================================================================================
2025-08-10 21:35:13,735 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-10 21:35:14,991 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.50': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-08-10 21:35:14,991 - INFO - Initial checks done. Running the testcase now
2025-08-10 21:35:14,991 - INFO -
2025-08-10 21:35:15,604 - DEBUG - Response for create_network : {'network': {'id': '5359676d-b591-4b5b-81d6-9e4928c6292c', 'name': 'ctest-mgmt_vn-87187079', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'project_id': 'eda8c400a7f04f2082e991ba068fd53b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-07540207', 'ctest-mgmt_vn-87187079'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:15,892 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f7aa8b70-3ec4-4753-99c8-d9f71417b998', 'name': '', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'network_id': '5359676d-b591-4b5b-81d6-9e4928c6292c', 'ip_version': 4, 'cidr': '131.209.199.0/26', 'allocation_pools': [{'start': '131.209.199.2', 'end': '131.209.199.62'}], 'gateway_ip': '131.209.199.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '131.209.199.2', 'tags': [], 'project_id': 'eda8c400a7f04f2082e991ba068fd53b'}}
2025-08-10 21:35:15,911 - INFO - Created VN ctest-mgmt_vn-87187079
2025-08-10 21:35:15,923 - DEBUG - VN ctest-mgmt_vn-87187079 UUID is 5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:15,982 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:16,282 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:16,310 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:16,324 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:16,419 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:16,430 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:16,439 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9a33a4d8-8a49-4c45-a8b5-a5c5e78f1334
2025-08-10 21:35:16,445 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9a33a4d8-8a49-4c45-a8b5-a5c5e78f1334
2025-08-10 21:35:16,451 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/96bd9e33-706d-4437-a77c-d8d2bd28866e
2025-08-10 21:35:16,457 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:16,457 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:16,466 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9a33a4d8-8a49-4c45-a8b5-a5c5e78f1334
2025-08-10 21:35:16,479 - INFO - Verified VN network id 11 for VN 5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:16,479 - INFO - Verifications in API Server for VN ctest-mgmt_vn-87187079 passed
2025-08-10 21:35:16,479 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:16,488 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9a33a4d8-8a49-4c45-a8b5-a5c5e78f1334
2025-08-10 21:35:16,494 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9a33a4d8-8a49-4c45-a8b5-a5c5e78f1334
2025-08-10 21:35:16,500 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/96bd9e33-706d-4437-a77c-d8d2bd28866e
2025-08-10 21:35:16,512 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6005945298970561371', 'uuid-lslong': '9355839312962660652'}, 'enable': 'true', 'created': '2025-08-10T21:35:15', 'last-modified': '2025-08-10T21:35:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.681358'}
2025-08-10 21:35:16,519 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:16,524 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6005945298970561371', 'uuid-lslong': '9355839312962660652'}, 'enable': 'true', 'created': '2025-08-10T21:35:15', 'last-modified': '2025-08-10T21:35:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.691752'}
2025-08-10 21:35:16,530 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:16,536 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6005945298970561371', 'uuid-lslong': '9355839312962660652'}, 'enable': 'true', 'created': '2025-08-10T21:35:15', 'last-modified': '2025-08-10T21:35:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.701988'}
2025-08-10 21:35:16,542 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:16,542 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-87187079 passed
2025-08-10 21:35:16,543 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-87187079 in API_Server ======
2025-08-10 21:35:16,543 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:16,548 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:16,559 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:16,649 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5359676d-b591-4b5b-81d6-9e4928c6292c
2025-08-10 21:35:16,660 - DEBUG - =>VN ctest-mgmt_vn-87187079 has no policy to be verified
2025-08-10 21:35:16,660 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:16,660 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:16,660 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:16,954 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:17,009 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079?flat'}
2025-08-10 21:35:17,009 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:17,010 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:17,301 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:17,355 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079?flat'}
2025-08-10 21:35:17,355 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:17,355 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:17,646 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:17,698 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079?flat'}
2025-08-10 21:35:17,698 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-87187079 is found in opserver
2025-08-10 21:35:17,719 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:17,728 - DEBUG - VRF ids for VN ctest-mgmt_vn-87187079: {}
2025-08-10 21:35:17,954 - DEBUG - Response for create_network : {'network': {'id': '9250002b-b1e0-4ff0-b588-5aedebcd173c', 'name': 'service-left-vn-17999627', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'project_id': 'eda8c400a7f04f2082e991ba068fd53b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-07540207', 'service-left-vn-17999627'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:18,195 - DEBUG - Response for create_subnet : {'subnet': {'id': '4c657e78-8a96-45b3-ae04-224b2a2ec21f', 'name': '', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'network_id': '9250002b-b1e0-4ff0-b588-5aedebcd173c', 'ip_version': 4, 'cidr': '198.228.187.64/26', 'allocation_pools': [{'start': '198.228.187.66', 'end': '198.228.187.126'}], 'gateway_ip': '198.228.187.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.228.187.66', 'tags': [], 'project_id': 'eda8c400a7f04f2082e991ba068fd53b'}}
2025-08-10 21:35:18,217 - INFO - Created VN service-left-vn-17999627
2025-08-10 21:35:18,228 - DEBUG - VN service-left-vn-17999627 UUID is 9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,286 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:18,293 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:18,304 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:18,392 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,402 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,412 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/44be2351-05ce-4fef-b301-b5a52fdf6f8f
2025-08-10 21:35:18,419 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/44be2351-05ce-4fef-b301-b5a52fdf6f8f
2025-08-10 21:35:18,425 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/80ab480e-0305-468c-bbb9-e3e6013b0bbd
2025-08-10 21:35:18,431 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:18,431 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,440 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/44be2351-05ce-4fef-b301-b5a52fdf6f8f
2025-08-10 21:35:18,453 - INFO - Verified VN network id 12 for VN 9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,453 - INFO - Verifications in API Server for VN service-left-vn-17999627 passed
2025-08-10 21:35:18,453 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,462 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/44be2351-05ce-4fef-b301-b5a52fdf6f8f
2025-08-10 21:35:18,469 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/44be2351-05ce-4fef-b301-b5a52fdf6f8f
2025-08-10 21:35:18,475 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/80ab480e-0305-468c-bbb9-e3e6013b0bbd
2025-08-10 21:35:18,487 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10542926915342192624', 'uuid-lslong': '13080805095607441212'}, 'enable': 'true', 'created': '2025-08-10T21:35:17', 'last-modified': '2025-08-10T21:35:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.340261'}
2025-08-10 21:35:18,498 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:18,504 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10542926915342192624', 'uuid-lslong': '13080805095607441212'}, 'enable': 'true', 'created': '2025-08-10T21:35:17', 'last-modified': '2025-08-10T21:35:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.358175'}
2025-08-10 21:35:18,510 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:18,516 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10542926915342192624', 'uuid-lslong': '13080805095607441212'}, 'enable': 'true', 'created': '2025-08-10T21:35:17', 'last-modified': '2025-08-10T21:35:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364126'}
2025-08-10 21:35:18,522 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:18,523 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-17999627 passed
2025-08-10 21:35:18,523 - DEBUG - ====Verifying policy data for service-left-vn-17999627 in API_Server ======
2025-08-10 21:35:18,523 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:18,528 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:18,539 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:18,638 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/9250002b-b1e0-4ff0-b588-5aedebcd173c
2025-08-10 21:35:18,650 - DEBUG - =>VN service-left-vn-17999627 has no policy to be verified
2025-08-10 21:35:18,650 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:18,650 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:18,650 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:18,712 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627?flat'}
2025-08-10 21:35:18,712 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:18,712 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:18,763 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627?flat'}
2025-08-10 21:35:18,763 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:18,763 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:18,809 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627?flat'}
2025-08-10 21:35:18,809 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-07540207:service-left-vn-17999627 is found in opserver
2025-08-10 21:35:18,831 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:18,841 - DEBUG - VRF ids for VN service-left-vn-17999627: {}
2025-08-10 21:35:19,108 - DEBUG - Response for create_network : {'network': {'id': '14bead63-0da2-43ba-9bab-c86f08813e3a', 'name': 'service-right-vn-61984395', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'project_id': 'eda8c400a7f04f2082e991ba068fd53b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-07540207', 'service-right-vn-61984395'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:19,353 - DEBUG - Response for create_subnet : {'subnet': {'id': '86b7c436-7659-43ac-bfb0-095aa86506e5', 'name': '', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'network_id': '14bead63-0da2-43ba-9bab-c86f08813e3a', 'ip_version': 4, 'cidr': '171.10.177.128/26', 'allocation_pools': [{'start': '171.10.177.130', 'end': '171.10.177.190'}], 'gateway_ip': '171.10.177.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '171.10.177.130', 'tags': [], 'project_id': 'eda8c400a7f04f2082e991ba068fd53b'}}
2025-08-10 21:35:19,371 - INFO - Created VN service-right-vn-61984395
2025-08-10 21:35:19,382 - DEBUG - VN service-right-vn-61984395 UUID is 14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,437 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:19,442 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:19,453 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:19,543 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,556 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,566 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ebabaf28-17f1-436c-8508-c39dc38a0a62
2025-08-10 21:35:19,576 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ebabaf28-17f1-436c-8508-c39dc38a0a62
2025-08-10 21:35:19,583 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/fa27a0f5-0be9-48d9-bf25-3a8b71bbad62
2025-08-10 21:35:19,589 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:19,589 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,597 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ebabaf28-17f1-436c-8508-c39dc38a0a62
2025-08-10 21:35:19,611 - INFO - Verified VN network id 13 for VN 14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,611 - INFO - Verifications in API Server for VN service-right-vn-61984395 passed
2025-08-10 21:35:19,611 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,620 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ebabaf28-17f1-436c-8508-c39dc38a0a62
2025-08-10 21:35:19,626 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ebabaf28-17f1-436c-8508-c39dc38a0a62
2025-08-10 21:35:19,632 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/fa27a0f5-0be9-48d9-bf25-3a8b71bbad62
2025-08-10 21:35:19,648 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1494822767275688890', 'uuid-lslong': '11217279676105965114'}, 'enable': 'true', 'created': '2025-08-10T21:35:19', 'last-modified': '2025-08-10T21:35:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.349376'}
2025-08-10 21:35:19,654 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:19,660 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1494822767275688890', 'uuid-lslong': '11217279676105965114'}, 'enable': 'true', 'created': '2025-08-10T21:35:19', 'last-modified': '2025-08-10T21:35:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.365591'}
2025-08-10 21:35:19,666 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:19,671 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1494822767275688890', 'uuid-lslong': '11217279676105965114'}, 'enable': 'true', 'created': '2025-08-10T21:35:19', 'last-modified': '2025-08-10T21:35:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381345'}
2025-08-10 21:35:19,677 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:19,677 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-61984395 passed
2025-08-10 21:35:19,677 - DEBUG - ====Verifying policy data for service-right-vn-61984395 in API_Server ======
2025-08-10 21:35:19,677 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:19,683 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:19,693 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:19,787 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/14bead63-0da2-43ba-9bab-c86f08813e3a
2025-08-10 21:35:19,797 - DEBUG - =>VN service-right-vn-61984395 has no policy to be verified
2025-08-10 21:35:19,797 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:19,797 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:19,797 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:19,849 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395?flat'}
2025-08-10 21:35:19,849 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:19,849 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:19,861 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395?flat'}
2025-08-10 21:35:19,861 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:19,861 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:19,907 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395?flat'}
2025-08-10 21:35:19,908 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-07540207:service-right-vn-61984395 is found in opserver
2025-08-10 21:35:19,928 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:19,937 - DEBUG - VRF ids for VN service-right-vn-61984395: {}
2025-08-10 21:35:19,990 - DEBUG - Services list from nova: [, , , ]
2025-08-10 21:35:20,965 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-1)
2025-08-10 21:35:22,392 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-2)
2025-08-10 21:35:22,393 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-16134628']
2025-08-10 21:35:22,693 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16134628'] created succesfully
2025-08-10 21:35:23,057 - DEBUG - Image by name tiny_nat_fw not found
2025-08-10 21:35:23,057 - DEBUG - Installing image tiny_nat_fw
2025-08-10 21:35:23,057 - 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-08-10 21:35:23,058 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:35:23,058 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:35:23,058 - DEBUG - Unzip image
2025-08-10 21:35:23,058 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:35:23,122 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-10 21:35:23,123 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-16134628']
2025-08-10 21:35:23,224 - INFO - Service template: ['default-domain', 'ctest-service_template_1-16134628'] deleted successfully.
2025-08-10 21:35:23,224 - INFO - Skip interface_detach for VM ctest-right_vm-78487869 in state BUILD
2025-08-10 21:35:23,225 - INFO - Deleting VM ctest-right_vm-78487869
2025-08-10 21:35:23,254 - ERROR - VM ctest-right_vm-78487869 has failed to come up
2025-08-10 21:35:23,254 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:23,254 - ERROR - VM ctest-right_vm-78487869 has failed to come up
2025-08-10 21:35:23,254 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:23,310 - INFO - Skip interface_detach for VM ctest-left_vm-80484651 in state BUILD
2025-08-10 21:35:23,311 - INFO - Deleting VM ctest-left_vm-80484651
2025-08-10 21:35:23,311 - ERROR - VM ctest-left_vm-80484651 has failed to come up
2025-08-10 21:35:23,311 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:23,311 - ERROR - VM ctest-left_vm-80484651 has failed to come up
2025-08-10 21:35:23,311 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:23,369 - INFO - Deleting VN service-right-vn-61984395
2025-08-10 21:35:23,407 - DEBUG - VN 14bead63-0da2-43ba-9bab-c86f08813e3a still in use: Unable to complete operation on network 14bead63-0da2-43ba-9bab-c86f08813e3a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6e17aabb-8120-40f0-ae5e-925fbeaf61c9']
2025-08-10 21:35:23,408 - WARNING - Deleting VN service-right-vn-61984395 failed..Will retry
2025-08-10 21:35:25,558 - DEBUG - Response for deleting network ()
2025-08-10 21:35:25,559 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ebabaf28-17f1-436c-8508-c39dc38a0a62
2025-08-10 21:35:25,564 - DEBUG - Response Code: 404
2025-08-10 21:35:25,564 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:25,570 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:25,592 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:25,676 - INFO - Validated that VN service-right-vn-61984395 is not found in API Server
2025-08-10 21:35:25,689 - DEBUG - VN service-right-vn-61984395 is not present in Agent 10.0.0.254
2025-08-10 21:35:25,700 - DEBUG - VN service-right-vn-61984395 is not present in Agent 10.0.0.50
2025-08-10 21:35:25,700 - INFO - Validated that VN service-right-vn-61984395 is not in any agent
2025-08-10 21:35:25,732 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-61984395 info
2025-08-10 21:35:25,732 - INFO - Deleting VN service-left-vn-17999627
2025-08-10 21:35:25,870 - DEBUG - Response for deleting network ()
2025-08-10 21:35:25,870 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/44be2351-05ce-4fef-b301-b5a52fdf6f8f
2025-08-10 21:35:25,876 - DEBUG - Response Code: 404
2025-08-10 21:35:25,876 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:25,894 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:25,946 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:26,034 - INFO - Validated that VN service-left-vn-17999627 is not found in API Server
2025-08-10 21:35:26,045 - DEBUG - VN service-left-vn-17999627 is not present in Agent 10.0.0.254
2025-08-10 21:35:26,054 - DEBUG - VN service-left-vn-17999627 is not present in Agent 10.0.0.50
2025-08-10 21:35:26,055 - INFO - Validated that VN service-left-vn-17999627 is not in any agent
2025-08-10 21:35:26,085 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-17999627 info
2025-08-10 21:35:26,085 - INFO - Deleting VN ctest-mgmt_vn-87187079
2025-08-10 21:35:26,203 - DEBUG - Response for deleting network ()
2025-08-10 21:35:26,203 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9a33a4d8-8a49-4c45-a8b5-a5c5e78f1334
2025-08-10 21:35:26,209 - DEBUG - Response Code: 404
2025-08-10 21:35:26,209 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:26,216 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:26,230 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:26,318 - INFO - Validated that VN ctest-mgmt_vn-87187079 is not found in API Server
2025-08-10 21:35:26,330 - DEBUG - VN ctest-mgmt_vn-87187079 is not present in Agent 10.0.0.254
2025-08-10 21:35:26,340 - DEBUG - VN ctest-mgmt_vn-87187079 is not present in Agent 10.0.0.50
2025-08-10 21:35:26,340 - INFO - Validated that VN ctest-mgmt_vn-87187079 is not in any agent
2025-08-10 21:35:26,384 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-87187079 info
2025-08-10 21:35:27,635 - DEBUG - No XMPP flaps were noticed during the test
2025-08-10 21:35:27,636 - INFO -
2025-08-10 21:35:27,636 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2025-08-10 21:35:27,636 - INFO - --------------------------------------------------------------------------------
2025-08-10 21:35:27,639 - INFO - ================================================================================
2025-08-10 21:35:27,639 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-10 21:35:28,886 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.50': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-08-10 21:35:28,886 - INFO - Initial checks done. Running the testcase now
2025-08-10 21:35:28,886 - INFO -
2025-08-10 21:35:29,108 - DEBUG - Response for create_network : {'network': {'id': '93b74c31-fef3-4936-b062-f01e01ff18b6', 'name': 'ctest-mgmt_vn-37995331', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'project_id': 'eda8c400a7f04f2082e991ba068fd53b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-07540207', 'ctest-mgmt_vn-37995331'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:29,345 - DEBUG - Response for create_subnet : {'subnet': {'id': '98485470-b891-48da-b3f4-55ad7652270a', 'name': '', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'network_id': '93b74c31-fef3-4936-b062-f01e01ff18b6', 'ip_version': 4, 'cidr': '124.226.148.128/26', 'allocation_pools': [{'start': '124.226.148.130', 'end': '124.226.148.190'}], 'gateway_ip': '124.226.148.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '124.226.148.130', 'tags': [], 'project_id': 'eda8c400a7f04f2082e991ba068fd53b'}}
2025-08-10 21:35:29,364 - INFO - Created VN ctest-mgmt_vn-37995331
2025-08-10 21:35:29,375 - DEBUG - VN ctest-mgmt_vn-37995331 UUID is 93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:29,451 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:29,461 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:29,471 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:29,560 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:29,577 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:29,588 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:29,598 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:29,641 - DEBUG - Route Targets: []
2025-08-10 21:35:29,641 - DEBUG - RT names not yet present for VN ctest-mgmt_vn-37995331
2025-08-10 21:35:34,641 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:34,650 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:34,665 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:34,751 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:34,761 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:34,770 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:34,779 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:34,785 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/ada33c74-b30a-4ded-9855-e2f07bd0feff
2025-08-10 21:35:34,794 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:34,794 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:34,803 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:34,818 - INFO - Verified VN network id 11 for VN 93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:34,818 - INFO - Verifications in API Server for VN ctest-mgmt_vn-37995331 passed
2025-08-10 21:35:34,818 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:34,827 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:34,833 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:34,839 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/ada33c74-b30a-4ded-9855-e2f07bd0feff
2025-08-10 21:35:34,851 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10644060021927921974', 'uuid-lslong': '12709985110066141366'}, 'enable': 'true', 'created': '2025-08-10T21:35:29', 'last-modified': '2025-08-10T21:35:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.560167'}
2025-08-10 21:35:34,858 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:34,864 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10644060021927921974', 'uuid-lslong': '12709985110066141366'}, 'enable': 'true', 'created': '2025-08-10T21:35:29', 'last-modified': '2025-08-10T21:35:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.574647'}
2025-08-10 21:35:34,871 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:34,876 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10644060021927921974', 'uuid-lslong': '12709985110066141366'}, 'enable': 'true', 'created': '2025-08-10T21:35:29', 'last-modified': '2025-08-10T21:35:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.580907'}
2025-08-10 21:35:34,881 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-10 21:35:34,881 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-37995331 passed
2025-08-10 21:35:34,882 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-37995331 in API_Server ======
2025-08-10 21:35:34,882 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:34,887 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:34,898 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:34,981 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/93b74c31-fef3-4936-b062-f01e01ff18b6
2025-08-10 21:35:34,992 - DEBUG - =>VN ctest-mgmt_vn-37995331 has no policy to be verified
2025-08-10 21:35:34,992 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:34,992 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:34,992 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:35,005 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331?flat'}
2025-08-10 21:35:35,005 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:35,005 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:35,016 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331?flat'}
2025-08-10 21:35:35,016 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:35,016 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:35,027 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331?flat'}
2025-08-10 21:35:35,027 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-07540207:ctest-mgmt_vn-37995331 is found in opserver
2025-08-10 21:35:35,048 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:35,057 - DEBUG - VRF ids for VN ctest-mgmt_vn-37995331: {}
2025-08-10 21:35:35,282 - DEBUG - Response for create_network : {'network': {'id': '0d65e155-e393-494e-88f6-2c752350eaa5', 'name': 'ctest-left_vn-81688396', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'project_id': 'eda8c400a7f04f2082e991ba068fd53b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-07540207', 'ctest-left_vn-81688396'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:35,463 - DEBUG - Response for create_subnet : {'subnet': {'id': '66f4b354-4599-4050-8129-09f208bebee7', 'name': '', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'network_id': '0d65e155-e393-494e-88f6-2c752350eaa5', 'ip_version': 4, 'cidr': '35.83.14.192/26', 'allocation_pools': [{'start': '35.83.14.194', 'end': '35.83.14.254'}], 'gateway_ip': '35.83.14.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '35.83.14.194', 'tags': [], 'project_id': 'eda8c400a7f04f2082e991ba068fd53b'}}
2025-08-10 21:35:35,486 - INFO - Created VN ctest-left_vn-81688396
2025-08-10 21:35:35,495 - DEBUG - VN ctest-left_vn-81688396 UUID is 0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,559 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:35,565 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:35,577 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:35,672 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,683 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,694 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/86a4a4e2-688c-4466-b123-5cb5eb109850
2025-08-10 21:35:35,702 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/86a4a4e2-688c-4466-b123-5cb5eb109850
2025-08-10 21:35:35,709 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/4339ab2f-93d2-45bf-bff5-01c1c3105112
2025-08-10 21:35:35,716 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:35,716 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,725 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/86a4a4e2-688c-4466-b123-5cb5eb109850
2025-08-10 21:35:35,741 - INFO - Verified VN network id 12 for VN 0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,741 - INFO - Verifications in API Server for VN ctest-left_vn-81688396 passed
2025-08-10 21:35:35,741 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,751 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/86a4a4e2-688c-4466-b123-5cb5eb109850
2025-08-10 21:35:35,757 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/86a4a4e2-688c-4466-b123-5cb5eb109850
2025-08-10 21:35:35,765 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/4339ab2f-93d2-45bf-bff5-01c1c3105112
2025-08-10 21:35:35,777 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '965425454147389774', 'uuid-lslong': '9869124515044321957'}, 'enable': 'true', 'created': '2025-08-10T21:35:35', 'last-modified': '2025-08-10T21:35:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364923'}
2025-08-10 21:35:35,784 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:35,789 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '965425454147389774', 'uuid-lslong': '9869124515044321957'}, 'enable': 'true', 'created': '2025-08-10T21:35:35', 'last-modified': '2025-08-10T21:35:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.377335'}
2025-08-10 21:35:35,795 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:35,800 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '965425454147389774', 'uuid-lslong': '9869124515044321957'}, 'enable': 'true', 'created': '2025-08-10T21:35:35', 'last-modified': '2025-08-10T21:35:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383746'}
2025-08-10 21:35:35,806 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-10 21:35:35,806 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-81688396 passed
2025-08-10 21:35:35,806 - DEBUG - ====Verifying policy data for ctest-left_vn-81688396 in API_Server ======
2025-08-10 21:35:35,806 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:35,812 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:35,823 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:35,913 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/0d65e155-e393-494e-88f6-2c752350eaa5
2025-08-10 21:35:35,926 - DEBUG - =>VN ctest-left_vn-81688396 has no policy to be verified
2025-08-10 21:35:35,926 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:35,926 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:35,926 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:35,939 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396?flat'}
2025-08-10 21:35:35,939 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:35,939 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:35,991 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396?flat'}
2025-08-10 21:35:35,991 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:35,991 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:36,002 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396?flat'}
2025-08-10 21:35:36,002 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-07540207:ctest-left_vn-81688396 is found in opserver
2025-08-10 21:35:36,023 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:36,034 - DEBUG - VRF ids for VN ctest-left_vn-81688396: {}
2025-08-10 21:35:36,263 - DEBUG - Response for create_network : {'network': {'id': '34f516ca-185e-4e04-b34c-e699040e3dd5', 'name': 'ctest-right_vn-51825274', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'project_id': 'eda8c400a7f04f2082e991ba068fd53b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-07540207', 'ctest-right_vn-51825274'], 'port_security_enabled': True, 'description': ''}}
2025-08-10 21:35:36,458 - DEBUG - Response for create_subnet : {'subnet': {'id': '818137f2-0ad4-41fe-a140-eaecf8835c8d', 'name': '', 'tenant_id': 'eda8c400a7f04f2082e991ba068fd53b', 'network_id': '34f516ca-185e-4e04-b34c-e699040e3dd5', 'ip_version': 4, 'cidr': '44.14.193.0/26', 'allocation_pools': [{'start': '44.14.193.2', 'end': '44.14.193.62'}], 'gateway_ip': '44.14.193.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '44.14.193.2', 'tags': [], 'project_id': 'eda8c400a7f04f2082e991ba068fd53b'}}
2025-08-10 21:35:36,485 - INFO - Created VN ctest-right_vn-51825274
2025-08-10 21:35:36,495 - DEBUG - VN ctest-right_vn-51825274 UUID is 34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,570 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:36,578 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:36,590 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:36,692 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,714 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,725 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c7ad6fe9-36b3-4ed8-add5-43492c508571
2025-08-10 21:35:36,735 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c7ad6fe9-36b3-4ed8-add5-43492c508571
2025-08-10 21:35:36,743 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/38a62d6b-4875-437c-8a56-7ee63801e48c
2025-08-10 21:35:36,751 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:36,751 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,762 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c7ad6fe9-36b3-4ed8-add5-43492c508571
2025-08-10 21:35:36,776 - INFO - Verified VN network id 13 for VN 34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,776 - INFO - Verifications in API Server for VN ctest-right_vn-51825274 passed
2025-08-10 21:35:36,776 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,786 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c7ad6fe9-36b3-4ed8-add5-43492c508571
2025-08-10 21:35:36,795 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c7ad6fe9-36b3-4ed8-add5-43492c508571
2025-08-10 21:35:36,801 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/38a62d6b-4875-437c-8a56-7ee63801e48c
2025-08-10 21:35:36,814 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3815981316514401796', 'uuid-lslong': '12919954975891537365'}, 'enable': 'true', 'created': '2025-08-10T21:35:36', 'last-modified': '2025-08-10T21:35:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.404204'}
2025-08-10 21:35:36,820 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:36,825 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3815981316514401796', 'uuid-lslong': '12919954975891537365'}, 'enable': 'true', 'created': '2025-08-10T21:35:36', 'last-modified': '2025-08-10T21:35:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416930'}
2025-08-10 21:35:36,831 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:36,837 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-07540207', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3815981316514401796', 'uuid-lslong': '12919954975891537365'}, 'enable': 'true', 'created': '2025-08-10T21:35:36', 'last-modified': '2025-08-10T21:35:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427494'}
2025-08-10 21:35:36,843 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-10 21:35:36,843 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-51825274 passed
2025-08-10 21:35:36,843 - DEBUG - ====Verifying policy data for ctest-right_vn-51825274 in API_Server ======
2025-08-10 21:35:36,843 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:36,848 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:36,859 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:36,952 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/34f516ca-185e-4e04-b34c-e699040e3dd5
2025-08-10 21:35:36,961 - DEBUG - =>VN ctest-right_vn-51825274 has no policy to be verified
2025-08-10 21:35:36,961 - DEBUG - Verifying the vn in opserver
2025-08-10 21:35:36,961 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274 virtual network link through opserver 10.0.0.48
2025-08-10 21:35:36,961 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-10 21:35:36,973 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274?flat'}
2025-08-10 21:35:36,973 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274 virtual network link through opserver 10.0.0.38
2025-08-10 21:35:36,973 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-10 21:35:36,984 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274?flat'}
2025-08-10 21:35:36,984 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274 virtual network link through opserver 10.0.0.49
2025-08-10 21:35:36,984 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-10 21:35:36,994 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274?flat'}
2025-08-10 21:35:36,994 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-07540207:ctest-right_vn-51825274 is found in opserver
2025-08-10 21:35:37,015 - DEBUG - Do not have enough data to verify VN in agent
2025-08-10 21:35:37,025 - DEBUG - VRF ids for VN ctest-right_vn-51825274: {}
2025-08-10 21:35:37,998 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-2)
2025-08-10 21:35:39,193 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3790-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3790-1)
2025-08-10 21:35:39,193 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-48984320']
2025-08-10 21:35:39,465 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-48984320'] created succesfully
2025-08-10 21:35:39,763 - DEBUG - Image by name tiny_nat_fw not found
2025-08-10 21:35:39,764 - DEBUG - Installing image tiny_nat_fw
2025-08-10 21:35:39,764 - 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-08-10 21:35:39,764 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:35:39,764 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:35:39,764 - DEBUG - Unzip image
2025-08-10 21:35:39,764 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-10 21:35:39,786 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-10 21:35:39,786 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-48984320']
2025-08-10 21:35:39,884 - INFO - Service template: ['default-domain', 'ctest-service_template_1-48984320'] deleted successfully.
2025-08-10 21:35:39,884 - INFO - Skip interface_detach for VM ctest-right_vm-87237615 in state BUILD
2025-08-10 21:35:39,885 - INFO - Deleting VM ctest-right_vm-87237615
2025-08-10 21:35:39,886 - ERROR - VM ctest-right_vm-87237615 has failed to come up
2025-08-10 21:35:39,886 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:39,886 - ERROR - VM ctest-right_vm-87237615 has failed to come up
2025-08-10 21:35:39,886 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:39,970 - INFO - Skip interface_detach for VM ctest-left_vm-60948779 in state BUILD
2025-08-10 21:35:39,970 - INFO - Deleting VM ctest-left_vm-60948779
2025-08-10 21:35:39,970 - ERROR - VM ctest-left_vm-60948779 has failed to come up
2025-08-10 21:35:39,970 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:39,970 - ERROR - VM ctest-left_vm-60948779 has failed to come up
2025-08-10 21:35:39,970 - ERROR - Nova failed to get host of the VM
2025-08-10 21:35:40,038 - INFO - Deleting VN ctest-right_vn-51825274
2025-08-10 21:35:40,078 - DEBUG - VN 34f516ca-185e-4e04-b34c-e699040e3dd5 still in use: Unable to complete operation on network 34f516ca-185e-4e04-b34c-e699040e3dd5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1a1674a6-6f8f-4a3e-869e-cc30dad1b690']
2025-08-10 21:35:40,078 - WARNING - Deleting VN ctest-right_vn-51825274 failed..Will retry
2025-08-10 21:35:42,235 - DEBUG - Response for deleting network ()
2025-08-10 21:35:42,235 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c7ad6fe9-36b3-4ed8-add5-43492c508571
2025-08-10 21:35:42,243 - DEBUG - Response Code: 404
2025-08-10 21:35:42,246 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:42,255 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:42,311 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:42,395 - INFO - Validated that VN ctest-right_vn-51825274 is not found in API Server
2025-08-10 21:35:42,406 - DEBUG - VN ctest-right_vn-51825274 is not present in Agent 10.0.0.254
2025-08-10 21:35:42,416 - DEBUG - VN ctest-right_vn-51825274 is not present in Agent 10.0.0.50
2025-08-10 21:35:42,416 - INFO - Validated that VN ctest-right_vn-51825274 is not in any agent
2025-08-10 21:35:42,447 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-51825274 info
2025-08-10 21:35:42,447 - INFO - Deleting VN ctest-left_vn-81688396
2025-08-10 21:35:42,590 - DEBUG - Response for deleting network ()
2025-08-10 21:35:42,590 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/86a4a4e2-688c-4466-b123-5cb5eb109850
2025-08-10 21:35:42,600 - DEBUG - Response Code: 404
2025-08-10 21:35:42,601 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:42,608 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:42,623 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:42,715 - INFO - Validated that VN ctest-left_vn-81688396 is not found in API Server
2025-08-10 21:35:42,727 - DEBUG - VN ctest-left_vn-81688396 is not present in Agent 10.0.0.254
2025-08-10 21:35:42,741 - DEBUG - VN ctest-left_vn-81688396 is not present in Agent 10.0.0.50
2025-08-10 21:35:42,741 - INFO - Validated that VN ctest-left_vn-81688396 is not in any agent
2025-08-10 21:35:42,774 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-81688396 info
2025-08-10 21:35:42,774 - INFO - Deleting VN ctest-mgmt_vn-37995331
2025-08-10 21:35:42,885 - DEBUG - Response for deleting network ()
2025-08-10 21:35:42,885 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/618acfd8-0ee9-49b2-807c-df7cc8644e6e
2025-08-10 21:35:42,891 - DEBUG - Response Code: 404
2025-08-10 21:35:42,891 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:35:42,897 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:35:42,921 - DEBUG - Requesting: http://10.0.0.48:8082/project/eda8c400-a7f0-4f20-82e9-91ba068fd53b
2025-08-10 21:35:43,001 - INFO - Validated that VN ctest-mgmt_vn-37995331 is not found in API Server
2025-08-10 21:35:43,012 - DEBUG - VN ctest-mgmt_vn-37995331 is not present in Agent 10.0.0.254
2025-08-10 21:35:43,023 - DEBUG - VN ctest-mgmt_vn-37995331 is not present in Agent 10.0.0.50
2025-08-10 21:35:43,023 - INFO - Validated that VN ctest-mgmt_vn-37995331 is not in any agent
2025-08-10 21:35:43,052 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-37995331 info
2025-08-10 21:35:44,311 - DEBUG - No XMPP flaps were noticed during the test
2025-08-10 21:35:44,311 - INFO -
2025-08-10 21:35:44,311 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:17]
2025-08-10 21:35:44,311 - INFO - --------------------------------------------------------------------------------
2025-08-10 21:35:44,980 - INFO - Deleted project: ctest-TestSvcRegr-07540207, ID : eda8c400-a7f0-4f20-82e9-91ba068fd53b