]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-1)
2025-10-30 00:02:17,543 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-94794630']
2025-10-30 00:02:17,830 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-94794630'] created succesfully
2025-10-30 00:02:18,275 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 00:02:18,275 - DEBUG - Installing image tiny_nat_fw
2025-10-30 00:02:18,275 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-30 00:02:18,279 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:18,279 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:18,298 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:18,368 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:18,368 - DEBUG - Unzip image
2025-10-30 00:02:18,368 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:18,390 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 00:02:18,390 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-94794630']
2025-10-30 00:02:18,542 - INFO - Service template: ['default-domain', 'ctest-service_template_1-94794630'] deleted successfully.
2025-10-30 00:02:18,542 - INFO - Skip interface_detach for VM ctest-right_vm-58379556 in state BUILD
2025-10-30 00:02:18,542 - INFO - Deleting VM ctest-right_vm-58379556
2025-10-30 00:02:18,568 - ERROR - VM ctest-right_vm-58379556 has failed to come up
2025-10-30 00:02:18,568 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:18,568 - ERROR - VM ctest-right_vm-58379556 has failed to come up
2025-10-30 00:02:18,568 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:18,688 - INFO - Skip interface_detach for VM ctest-left_vm-21531805 in state BUILD
2025-10-30 00:02:18,689 - INFO - Deleting VM ctest-left_vm-21531805
2025-10-30 00:02:18,689 - ERROR - VM ctest-left_vm-21531805 has failed to come up
2025-10-30 00:02:18,689 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:18,689 - ERROR - VM ctest-left_vm-21531805 has failed to come up
2025-10-30 00:02:18,689 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:18,761 - INFO - Deleting VN ctest-right_vn-44640405
2025-10-30 00:02:18,807 - DEBUG - VN 21540a76-9a48-424c-8ff7-6f9783e6ba54 still in use: Unable to complete operation on network 21540a76-9a48-424c-8ff7-6f9783e6ba54. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8378f9ad-f4f6-4cda-b385-8f1708dd40a7']
2025-10-30 00:02:18,807 - WARNING - Deleting VN ctest-right_vn-44640405 failed..Will retry
2025-10-30 00:02:20,985 - DEBUG - Response for deleting network ()
2025-10-30 00:02:20,985 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/01590949-b411-48a3-b763-aac0b278e1b0
2025-10-30 00:02:20,994 - DEBUG - Response Code: 404
2025-10-30 00:02:20,994 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:21,011 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:21,054 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:21,150 - INFO - Validated that VN ctest-right_vn-44640405 is not found in API Server
2025-10-30 00:02:21,161 - DEBUG - VN ctest-right_vn-44640405 is not present in Agent 10.0.0.65
2025-10-30 00:02:21,182 - DEBUG - VN ctest-right_vn-44640405 is not present in Agent 10.0.0.33
2025-10-30 00:02:21,182 - INFO - Validated that VN ctest-right_vn-44640405 is not in any agent
2025-10-30 00:02:21,220 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-44640405 info
2025-10-30 00:02:21,220 - INFO - Deleting VN ctest-left_vn-95783775
2025-10-30 00:02:21,354 - DEBUG - Response for deleting network ()
2025-10-30 00:02:21,354 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/5f7705b6-78b8-4844-b08f-8d4719edde39
2025-10-30 00:02:21,363 - DEBUG - Response Code: 404
2025-10-30 00:02:21,363 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:21,378 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:21,392 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:21,481 - INFO - Validated that VN ctest-left_vn-95783775 is not found in API Server
2025-10-30 00:02:21,505 - DEBUG - VN ctest-left_vn-95783775 is not present in Agent 10.0.0.65
2025-10-30 00:02:21,527 - DEBUG - VN ctest-left_vn-95783775 is not present in Agent 10.0.0.33
2025-10-30 00:02:21,527 - INFO - Validated that VN ctest-left_vn-95783775 is not in any agent
2025-10-30 00:02:21,566 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-95783775 info
2025-10-30 00:02:21,566 - INFO - Deleting VN ctest-mgmt_vn-73379113
2025-10-30 00:02:21,720 - DEBUG - Response for deleting network ()
2025-10-30 00:02:21,720 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b945464f-8d3a-4882-81a8-1d418df774e5
2025-10-30 00:02:21,727 - DEBUG - Response Code: 404
2025-10-30 00:02:21,727 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:21,734 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:21,762 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:21,851 - INFO - Validated that VN ctest-mgmt_vn-73379113 is not found in API Server
2025-10-30 00:02:21,866 - DEBUG - VN ctest-mgmt_vn-73379113 is not present in Agent 10.0.0.65
2025-10-30 00:02:21,881 - DEBUG - VN ctest-mgmt_vn-73379113 is not present in Agent 10.0.0.33
2025-10-30 00:02:21,881 - INFO - Validated that VN ctest-mgmt_vn-73379113 is not in any agent
2025-10-30 00:02:21,912 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-73379113 info
2025-10-30 00:02:21,912 - INFO - Deleting VM ctest-vm-in-third-vn-24911350
2025-10-30 00:02:21,972 - INFO - Deleting VN ctest-third-vn-65452137
2025-10-30 00:02:22,011 - DEBUG - VN 2a364e90-df89-4ce3-ac99-7b990b4163d5 still in use: Unable to complete operation on network 2a364e90-df89-4ce3-ac99-7b990b4163d5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ad89371b-605d-438b-aa0c-82f27eb94b4f']
2025-10-30 00:02:22,011 - WARNING - Deleting VN ctest-third-vn-65452137 failed..Will retry
2025-10-30 00:02:24,184 - DEBUG - Response for deleting network ()
2025-10-30 00:02:25,528 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 00:02:25,528 - INFO -
2025-10-30 00:02:25,528 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-10-30 00:02:25,528 - INFO - --------------------------------------------------------------------------------
2025-10-30 00:02:25,531 - INFO - ================================================================================
2025-10-30 00:02:25,531 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-30 00:02:26,842 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.254': '0', '10.20.0.5': '0'}, '10.0.0.33': {'10.20.0.14': '0', '10.20.0.254': '0'}} with
2025-10-30 00:02:26,843 - INFO - Initial checks done. Running the testcase now
2025-10-30 00:02:26,843 - INFO -
2025-10-30 00:02:27,104 - DEBUG - Response for create_network : {'network': {'id': '07b87a68-8c59-4c3a-b9e8-b47583df1efa', 'name': 'ctest-mgmt_vn-48642928', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'project_id': 'eb3298f017d941369c99749286ec1430', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-89732661', 'ctest-mgmt_vn-48642928'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:27,325 - DEBUG - Response for create_subnet : {'subnet': {'id': '8b750174-2c12-4a4c-bbba-3a6fb7e34f4e', 'name': '', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'network_id': '07b87a68-8c59-4c3a-b9e8-b47583df1efa', 'ip_version': 4, 'cidr': '159.186.13.192/26', 'allocation_pools': [{'start': '159.186.13.194', 'end': '159.186.13.254'}], 'gateway_ip': '159.186.13.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '159.186.13.194', 'tags': [], 'project_id': 'eb3298f017d941369c99749286ec1430'}}
2025-10-30 00:02:27,358 - INFO - Created VN ctest-mgmt_vn-48642928
2025-10-30 00:02:27,369 - DEBUG - VN ctest-mgmt_vn-48642928 UUID is 07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,450 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:27,456 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:27,466 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:27,580 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,595 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,606 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/645a0abe-23de-4208-9d92-9be90cb86ea3
2025-10-30 00:02:27,616 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/645a0abe-23de-4208-9d92-9be90cb86ea3
2025-10-30 00:02:27,623 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/6177532e-a998-493d-8513-eb8fde30fa99
2025-10-30 00:02:27,630 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:27,630 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,639 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/645a0abe-23de-4208-9d92-9be90cb86ea3
2025-10-30 00:02:27,654 - INFO - Verified VN network id 16 for VN 07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,655 - INFO - Verifications in API Server for VN ctest-mgmt_vn-48642928 passed
2025-10-30 00:02:27,655 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,665 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/645a0abe-23de-4208-9d92-9be90cb86ea3
2025-10-30 00:02:27,673 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/645a0abe-23de-4208-9d92-9be90cb86ea3
2025-10-30 00:02:27,681 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/6177532e-a998-493d-8513-eb8fde30fa99
2025-10-30 00:02:27,693 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '556329143430106170', 'uuid-lslong': '13396155508430151418'}, 'enable': 'true', 'created': '2025-10-30T00:02:27', 'last-modified': '2025-10-30T00:02:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411669'}
2025-10-30 00:02:27,698 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:27,704 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '556329143430106170', 'uuid-lslong': '13396155508430151418'}, 'enable': 'true', 'created': '2025-10-30T00:02:27', 'last-modified': '2025-10-30T00:02:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433087'}
2025-10-30 00:02:27,712 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:27,717 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '556329143430106170', 'uuid-lslong': '13396155508430151418'}, 'enable': 'true', 'created': '2025-10-30T00:02:27', 'last-modified': '2025-10-30T00:02:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446523'}
2025-10-30 00:02:27,723 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:27,723 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-48642928 passed
2025-10-30 00:02:27,723 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-48642928 in API_Server ======
2025-10-30 00:02:27,723 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:27,730 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:27,740 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:27,834 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/07b87a68-8c59-4c3a-b9e8-b47583df1efa
2025-10-30 00:02:27,847 - DEBUG - =>VN ctest-mgmt_vn-48642928 has no policy to be verified
2025-10-30 00:02:27,847 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:27,847 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:27,847 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:27,860 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928?flat'}
2025-10-30 00:02:27,860 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:27,860 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:27,873 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928?flat'}
2025-10-30 00:02:27,873 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:27,873 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:27,887 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928?flat'}
2025-10-30 00:02:27,887 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-89732661:ctest-mgmt_vn-48642928 is found in opserver
2025-10-30 00:02:27,923 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:27,939 - DEBUG - VRF ids for VN ctest-mgmt_vn-48642928: {}
2025-10-30 00:02:28,182 - DEBUG - Response for create_network : {'network': {'id': '06808969-52a3-4e4d-9dbe-9cb0501a5f5e', 'name': 'ctest-left_vn-16067986', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'project_id': 'eb3298f017d941369c99749286ec1430', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-89732661', 'ctest-left_vn-16067986'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:28,416 - DEBUG - Response for create_subnet : {'subnet': {'id': '9cb523f0-552f-46e1-9425-b7f77879ad44', 'name': '', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'network_id': '06808969-52a3-4e4d-9dbe-9cb0501a5f5e', 'ip_version': 4, 'cidr': '136.20.197.192/26', 'allocation_pools': [{'start': '136.20.197.194', 'end': '136.20.197.254'}], 'gateway_ip': '136.20.197.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '136.20.197.194', 'tags': [], 'project_id': 'eb3298f017d941369c99749286ec1430'}}
2025-10-30 00:02:28,438 - INFO - Created VN ctest-left_vn-16067986
2025-10-30 00:02:28,451 - DEBUG - VN ctest-left_vn-16067986 UUID is 06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,517 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:28,525 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:28,536 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:28,637 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,657 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,669 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b706abe2-5700-4bb4-b595-f15c758f122d
2025-10-30 00:02:28,677 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b706abe2-5700-4bb4-b595-f15c758f122d
2025-10-30 00:02:28,686 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/cc0286e6-1d30-4943-ac10-079b03c5be91
2025-10-30 00:02:28,693 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:28,693 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,703 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b706abe2-5700-4bb4-b595-f15c758f122d
2025-10-30 00:02:28,719 - INFO - Verified VN network id 17 for VN 06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,719 - INFO - Verifications in API Server for VN ctest-left_vn-16067986 passed
2025-10-30 00:02:28,719 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,733 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b706abe2-5700-4bb4-b595-f15c758f122d
2025-10-30 00:02:28,742 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b706abe2-5700-4bb4-b595-f15c758f122d
2025-10-30 00:02:28,748 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/cc0286e6-1d30-4943-ac10-079b03c5be91
2025-10-30 00:02:28,760 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '468525446697537101', 'uuid-lslong': '11366694790601793374'}, 'enable': 'true', 'created': '2025-10-30T00:02:28', 'last-modified': '2025-10-30T00:02:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398878'}
2025-10-30 00:02:28,766 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:28,772 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '468525446697537101', 'uuid-lslong': '11366694790601793374'}, 'enable': 'true', 'created': '2025-10-30T00:02:28', 'last-modified': '2025-10-30T00:02:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409048'}
2025-10-30 00:02:28,779 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:28,784 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '468525446697537101', 'uuid-lslong': '11366694790601793374'}, 'enable': 'true', 'created': '2025-10-30T00:02:28', 'last-modified': '2025-10-30T00:02:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422804'}
2025-10-30 00:02:28,789 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:28,790 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-16067986 passed
2025-10-30 00:02:28,790 - DEBUG - ====Verifying policy data for ctest-left_vn-16067986 in API_Server ======
2025-10-30 00:02:28,790 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:28,796 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:28,809 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:28,908 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/06808969-52a3-4e4d-9dbe-9cb0501a5f5e
2025-10-30 00:02:28,918 - DEBUG - =>VN ctest-left_vn-16067986 has no policy to be verified
2025-10-30 00:02:28,919 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:28,919 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:28,919 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:28,931 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986?flat'}
2025-10-30 00:02:28,932 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:28,932 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:28,946 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986?flat'}
2025-10-30 00:02:28,946 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:28,946 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:28,959 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986?flat'}
2025-10-30 00:02:28,959 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-89732661:ctest-left_vn-16067986 is found in opserver
2025-10-30 00:02:28,988 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:29,003 - DEBUG - VRF ids for VN ctest-left_vn-16067986: {}
2025-10-30 00:02:29,259 - DEBUG - Response for create_network : {'network': {'id': '8234c187-d417-48a5-9788-398dc7fe19b0', 'name': 'ctest-right_vn-42491913', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'project_id': 'eb3298f017d941369c99749286ec1430', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-89732661', 'ctest-right_vn-42491913'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:29,503 - DEBUG - Response for create_subnet : {'subnet': {'id': '8726abb7-ce0d-4797-8877-f2ea192ca36f', 'name': '', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'network_id': '8234c187-d417-48a5-9788-398dc7fe19b0', 'ip_version': 4, 'cidr': '78.248.125.128/26', 'allocation_pools': [{'start': '78.248.125.130', 'end': '78.248.125.190'}], 'gateway_ip': '78.248.125.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.248.125.130', 'tags': [], 'project_id': 'eb3298f017d941369c99749286ec1430'}}
2025-10-30 00:02:29,526 - INFO - Created VN ctest-right_vn-42491913
2025-10-30 00:02:29,536 - DEBUG - VN ctest-right_vn-42491913 UUID is 8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,608 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:29,615 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:29,626 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:29,712 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,724 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,733 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/cc3c622e-fccd-4515-9420-9b7761945c09
2025-10-30 00:02:29,744 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/cc3c622e-fccd-4515-9420-9b7761945c09
2025-10-30 00:02:29,760 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/fc962d9e-e198-4893-bf86-c79f38636817
2025-10-30 00:02:29,768 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:29,768 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,777 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/cc3c622e-fccd-4515-9420-9b7761945c09
2025-10-30 00:02:29,793 - INFO - Verified VN network id 18 for VN 8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,794 - INFO - Verifications in API Server for VN ctest-right_vn-42491913 passed
2025-10-30 00:02:29,794 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,805 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/cc3c622e-fccd-4515-9420-9b7761945c09
2025-10-30 00:02:29,813 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/cc3c622e-fccd-4515-9420-9b7761945c09
2025-10-30 00:02:29,820 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/fc962d9e-e198-4893-bf86-c79f38636817
2025-10-30 00:02:29,833 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9382336712842627237', 'uuid-lslong': '10919040577668258224'}, 'enable': 'true', 'created': '2025-10-30T00:02:29', 'last-modified': '2025-10-30T00:02:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372592'}
2025-10-30 00:02:29,839 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:29,844 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9382336712842627237', 'uuid-lslong': '10919040577668258224'}, 'enable': 'true', 'created': '2025-10-30T00:02:29', 'last-modified': '2025-10-30T00:02:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387873'}
2025-10-30 00:02:29,850 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:29,854 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9382336712842627237', 'uuid-lslong': '10919040577668258224'}, 'enable': 'true', 'created': '2025-10-30T00:02:29', 'last-modified': '2025-10-30T00:02:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396023'}
2025-10-30 00:02:29,859 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:29,859 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-42491913 passed
2025-10-30 00:02:29,859 - DEBUG - ====Verifying policy data for ctest-right_vn-42491913 in API_Server ======
2025-10-30 00:02:29,860 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:29,865 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:29,875 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:29,968 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8234c187-d417-48a5-9788-398dc7fe19b0
2025-10-30 00:02:29,978 - DEBUG - =>VN ctest-right_vn-42491913 has no policy to be verified
2025-10-30 00:02:29,978 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:29,978 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:29,978 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:29,991 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913?flat'}
2025-10-30 00:02:29,991 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:29,991 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:30,005 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913?flat'}
2025-10-30 00:02:30,005 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:30,005 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:30,017 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913?flat'}
2025-10-30 00:02:30,017 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-89732661:ctest-right_vn-42491913 is found in opserver
2025-10-30 00:02:30,049 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:30,063 - DEBUG - VRF ids for VN ctest-right_vn-42491913: {}
2025-10-30 00:02:30,304 - DEBUG - Response for create_network : {'network': {'id': '78840018-ee9b-4d45-94db-dc30c957e210', 'name': 'ctest-trans_left_vn-59678879', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'project_id': 'eb3298f017d941369c99749286ec1430', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-89732661', 'ctest-trans_left_vn-59678879'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:30,474 - DEBUG - Response for create_subnet : {'subnet': {'id': '8ae4c926-4a41-48e4-8765-166685813e10', 'name': '', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'network_id': '78840018-ee9b-4d45-94db-dc30c957e210', 'ip_version': 4, 'cidr': '74.151.194.0/26', 'allocation_pools': [{'start': '74.151.194.2', 'end': '74.151.194.62'}], 'gateway_ip': '74.151.194.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.151.194.2', 'tags': [], 'project_id': 'eb3298f017d941369c99749286ec1430'}}
2025-10-30 00:02:30,494 - INFO - Created VN ctest-trans_left_vn-59678879
2025-10-30 00:02:30,503 - DEBUG - VN ctest-trans_left_vn-59678879 UUID is 78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,575 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:30,582 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:30,593 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:30,677 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,694 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,710 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/eff7be21-70ec-475b-8c4f-4a5deb28d7b4
2025-10-30 00:02:30,719 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/eff7be21-70ec-475b-8c4f-4a5deb28d7b4
2025-10-30 00:02:30,726 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/07d3b30c-9cfb-4fa1-9ea6-ba6ca45dbc9e
2025-10-30 00:02:30,734 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 00:02:30,734 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,743 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/eff7be21-70ec-475b-8c4f-4a5deb28d7b4
2025-10-30 00:02:30,758 - INFO - Verified VN network id 19 for VN 78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,758 - INFO - Verifications in API Server for VN ctest-trans_left_vn-59678879 passed
2025-10-30 00:02:30,758 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,768 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/eff7be21-70ec-475b-8c4f-4a5deb28d7b4
2025-10-30 00:02:30,774 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/eff7be21-70ec-475b-8c4f-4a5deb28d7b4
2025-10-30 00:02:30,780 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/07d3b30c-9cfb-4fa1-9ea6-ba6ca45dbc9e
2025-10-30 00:02:30,792 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8684066088559529285', 'uuid-lslong': '10726409039607489040'}, 'enable': 'true', 'created': '2025-10-30T00:02:30', 'last-modified': '2025-10-30T00:02:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.352328'}
2025-10-30 00:02:30,797 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 00:02:30,802 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8684066088559529285', 'uuid-lslong': '10726409039607489040'}, 'enable': 'true', 'created': '2025-10-30T00:02:30', 'last-modified': '2025-10-30T00:02:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370207'}
2025-10-30 00:02:30,809 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 00:02:30,814 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8684066088559529285', 'uuid-lslong': '10726409039607489040'}, 'enable': 'true', 'created': '2025-10-30T00:02:30', 'last-modified': '2025-10-30T00:02:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378008'}
2025-10-30 00:02:30,820 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 00:02:30,820 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-59678879 passed
2025-10-30 00:02:30,820 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-59678879 in API_Server ======
2025-10-30 00:02:30,820 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:30,827 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:30,839 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:30,928 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/78840018-ee9b-4d45-94db-dc30c957e210
2025-10-30 00:02:30,942 - DEBUG - =>VN ctest-trans_left_vn-59678879 has no policy to be verified
2025-10-30 00:02:30,942 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:30,942 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:30,942 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:30,955 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879?flat'}
2025-10-30 00:02:30,956 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:30,956 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:30,968 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879?flat'}
2025-10-30 00:02:30,968 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:30,968 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:30,990 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879?flat'}
2025-10-30 00:02:30,990 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-89732661:ctest-trans_left_vn-59678879 is found in opserver
2025-10-30 00:02:31,017 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:31,032 - DEBUG - VRF ids for VN ctest-trans_left_vn-59678879: {}
2025-10-30 00:02:31,409 - DEBUG - Response for create_network : {'network': {'id': '03327929-6c97-41c4-ad2b-637d9a0f21ac', 'name': 'ctest-trans_right_vn-79955854', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'project_id': 'eb3298f017d941369c99749286ec1430', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-89732661', 'ctest-trans_right_vn-79955854'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:31,687 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd72c0fc9-b3be-404b-85f2-cbbbf1e3f680', 'name': '', 'tenant_id': 'eb3298f017d941369c99749286ec1430', 'network_id': '03327929-6c97-41c4-ad2b-637d9a0f21ac', 'ip_version': 4, 'cidr': '222.38.227.64/26', 'allocation_pools': [{'start': '222.38.227.66', 'end': '222.38.227.126'}], 'gateway_ip': '222.38.227.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.38.227.66', 'tags': [], 'project_id': 'eb3298f017d941369c99749286ec1430'}}
2025-10-30 00:02:31,713 - INFO - Created VN ctest-trans_right_vn-79955854
2025-10-30 00:02:31,725 - DEBUG - VN ctest-trans_right_vn-79955854 UUID is 03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:31,794 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:31,800 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:31,813 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:31,911 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:31,923 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:31,933 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fc23a162-4adc-4d29-8b87-837efc0ed6ba
2025-10-30 00:02:31,940 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fc23a162-4adc-4d29-8b87-837efc0ed6ba
2025-10-30 00:02:31,947 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/964f20de-410d-41c3-9c3d-45eddebf8305
2025-10-30 00:02:31,955 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 00:02:31,955 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:31,963 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fc23a162-4adc-4d29-8b87-837efc0ed6ba
2025-10-30 00:02:31,978 - INFO - Verified VN network id 20 for VN 03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:31,978 - INFO - Verifications in API Server for VN ctest-trans_right_vn-79955854 passed
2025-10-30 00:02:31,978 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:31,988 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fc23a162-4adc-4d29-8b87-837efc0ed6ba
2025-10-30 00:02:31,994 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fc23a162-4adc-4d29-8b87-837efc0ed6ba
2025-10-30 00:02:32,002 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/964f20de-410d-41c3-9c3d-45eddebf8305
2025-10-30 00:02:32,014 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '230379749771788740', 'uuid-lslong': '12478176583666835884'}, 'enable': 'true', 'created': '2025-10-30T00:02:31', 'last-modified': '2025-10-30T00:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380855'}
2025-10-30 00:02:32,020 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 00:02:32,024 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '230379749771788740', 'uuid-lslong': '12478176583666835884'}, 'enable': 'true', 'created': '2025-10-30T00:02:31', 'last-modified': '2025-10-30T00:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.391271'}
2025-10-30 00:02:32,030 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 00:02:32,035 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-89732661', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '230379749771788740', 'uuid-lslong': '12478176583666835884'}, 'enable': 'true', 'created': '2025-10-30T00:02:31', 'last-modified': '2025-10-30T00:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393528'}
2025-10-30 00:02:32,040 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 00:02:32,041 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-79955854 passed
2025-10-30 00:02:32,041 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-79955854 in API_Server ======
2025-10-30 00:02:32,041 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:32,046 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:32,056 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:32,144 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/03327929-6c97-41c4-ad2b-637d9a0f21ac
2025-10-30 00:02:32,153 - DEBUG - =>VN ctest-trans_right_vn-79955854 has no policy to be verified
2025-10-30 00:02:32,153 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:32,153 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:32,153 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:32,164 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854?flat'}
2025-10-30 00:02:32,164 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:32,164 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:32,179 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854?flat'}
2025-10-30 00:02:32,179 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:32,179 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:32,191 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854?flat'}
2025-10-30 00:02:32,191 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-89732661:ctest-trans_right_vn-79955854 is found in opserver
2025-10-30 00:02:32,245 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:32,261 - DEBUG - VRF ids for VN ctest-trans_right_vn-79955854: {}
2025-10-30 00:02:33,443 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-1)
2025-10-30 00:02:34,847 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-2)
2025-10-30 00:02:34,848 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-69409355']
2025-10-30 00:02:35,140 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-69409355'] created succesfully
2025-10-30 00:02:35,489 - DEBUG - Image by name tiny_trans_fw not found
2025-10-30 00:02:35,490 - DEBUG - Installing image tiny_trans_fw
2025-10-30 00:02:35,490 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-10-30 00:02:35,490 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-30 00:02:35,490 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-30 00:02:35,527 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 00:02:35,566 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 00:02:35,567 - DEBUG - Unzip image
2025-10-30 00:02:35,567 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 00:02:35,608 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-30 00:02:35,609 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-69409355']
2025-10-30 00:02:35,714 - INFO - Service template: ['default-domain', 'ctest-service_template_1-69409355'] deleted successfully.
2025-10-30 00:02:35,714 - INFO - Skip interface_detach for VM ctest-right_vm-31884209 in state BUILD
2025-10-30 00:02:35,714 - INFO - Deleting VM ctest-right_vm-31884209
2025-10-30 00:02:35,714 - ERROR - VM ctest-right_vm-31884209 has failed to come up
2025-10-30 00:02:35,715 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:35,715 - ERROR - VM ctest-right_vm-31884209 has failed to come up
2025-10-30 00:02:35,715 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:35,845 - INFO - Skip interface_detach for VM ctest-left_vm-84219617 in state BUILD
2025-10-30 00:02:35,846 - INFO - Deleting VM ctest-left_vm-84219617
2025-10-30 00:02:35,846 - ERROR - VM ctest-left_vm-84219617 has failed to come up
2025-10-30 00:02:35,846 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:35,846 - ERROR - VM ctest-left_vm-84219617 has failed to come up
2025-10-30 00:02:35,846 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:35,925 - INFO - Deleting VN ctest-trans_right_vn-79955854
2025-10-30 00:02:36,091 - DEBUG - Response for deleting network ()
2025-10-30 00:02:36,091 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fc23a162-4adc-4d29-8b87-837efc0ed6ba
2025-10-30 00:02:36,104 - DEBUG - Response Code: 404
2025-10-30 00:02:36,104 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:36,121 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:36,139 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:36,240 - INFO - Validated that VN ctest-trans_right_vn-79955854 is not found in API Server
2025-10-30 00:02:36,253 - DEBUG - VN ctest-trans_right_vn-79955854 is not present in Agent 10.0.0.65
2025-10-30 00:02:36,293 - DEBUG - VN ctest-trans_right_vn-79955854 is not present in Agent 10.0.0.33
2025-10-30 00:02:36,293 - INFO - Validated that VN ctest-trans_right_vn-79955854 is not in any agent
2025-10-30 00:02:36,359 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-79955854 info
2025-10-30 00:02:36,360 - INFO - Deleting VN ctest-trans_left_vn-59678879
2025-10-30 00:02:36,519 - DEBUG - Response for deleting network ()
2025-10-30 00:02:36,520 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/eff7be21-70ec-475b-8c4f-4a5deb28d7b4
2025-10-30 00:02:36,530 - DEBUG - Response Code: 404
2025-10-30 00:02:36,531 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:36,549 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:36,570 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:36,661 - INFO - Validated that VN ctest-trans_left_vn-59678879 is not found in API Server
2025-10-30 00:02:36,671 - DEBUG - VN ctest-trans_left_vn-59678879 is not present in Agent 10.0.0.65
2025-10-30 00:02:36,683 - DEBUG - VN ctest-trans_left_vn-59678879 is not present in Agent 10.0.0.33
2025-10-30 00:02:36,683 - INFO - Validated that VN ctest-trans_left_vn-59678879 is not in any agent
2025-10-30 00:02:36,719 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-59678879 info
2025-10-30 00:02:36,719 - INFO - Deleting VN ctest-right_vn-42491913
2025-10-30 00:02:36,780 - DEBUG - VN 8234c187-d417-48a5-9788-398dc7fe19b0 still in use: Unable to complete operation on network 8234c187-d417-48a5-9788-398dc7fe19b0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5c3610f7-b1f8-4b42-8a73-6f99f9f9c3a9']
2025-10-30 00:02:36,781 - WARNING - Deleting VN ctest-right_vn-42491913 failed..Will retry
2025-10-30 00:02:38,945 - DEBUG - Response for deleting network ()
2025-10-30 00:02:38,945 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/cc3c622e-fccd-4515-9420-9b7761945c09
2025-10-30 00:02:38,951 - DEBUG - Response Code: 404
2025-10-30 00:02:38,951 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:38,979 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:38,994 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:39,101 - INFO - Validated that VN ctest-right_vn-42491913 is not found in API Server
2025-10-30 00:02:39,113 - DEBUG - VN ctest-right_vn-42491913 is not present in Agent 10.0.0.65
2025-10-30 00:02:39,133 - DEBUG - VN ctest-right_vn-42491913 is not present in Agent 10.0.0.33
2025-10-30 00:02:39,133 - INFO - Validated that VN ctest-right_vn-42491913 is not in any agent
2025-10-30 00:02:39,173 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-42491913 info
2025-10-30 00:02:39,173 - INFO - Deleting VN ctest-left_vn-16067986
2025-10-30 00:02:39,321 - DEBUG - Response for deleting network ()
2025-10-30 00:02:39,322 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b706abe2-5700-4bb4-b595-f15c758f122d
2025-10-30 00:02:39,327 - DEBUG - Response Code: 404
2025-10-30 00:02:39,327 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:39,332 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:39,351 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:39,456 - INFO - Validated that VN ctest-left_vn-16067986 is not found in API Server
2025-10-30 00:02:39,470 - DEBUG - VN ctest-left_vn-16067986 is not present in Agent 10.0.0.65
2025-10-30 00:02:39,489 - DEBUG - VN ctest-left_vn-16067986 is not present in Agent 10.0.0.33
2025-10-30 00:02:39,489 - INFO - Validated that VN ctest-left_vn-16067986 is not in any agent
2025-10-30 00:02:39,533 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-16067986 info
2025-10-30 00:02:39,533 - INFO - Deleting VN ctest-mgmt_vn-48642928
2025-10-30 00:02:39,667 - DEBUG - Response for deleting network ()
2025-10-30 00:02:39,667 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/645a0abe-23de-4208-9d92-9be90cb86ea3
2025-10-30 00:02:39,672 - DEBUG - Response Code: 404
2025-10-30 00:02:39,672 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:39,678 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:39,689 - DEBUG - Requesting: http://10.0.0.254:8082/project/eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:39,785 - INFO - Validated that VN ctest-mgmt_vn-48642928 is not found in API Server
2025-10-30 00:02:39,795 - DEBUG - VN ctest-mgmt_vn-48642928 is not present in Agent 10.0.0.65
2025-10-30 00:02:39,808 - DEBUG - VN ctest-mgmt_vn-48642928 is not present in Agent 10.0.0.33
2025-10-30 00:02:39,808 - INFO - Validated that VN ctest-mgmt_vn-48642928 is not in any agent
2025-10-30 00:02:39,845 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-48642928 info
2025-10-30 00:02:41,195 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 00:02:41,196 - INFO -
2025-10-30 00:02:41,196 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-10-30 00:02:41,196 - INFO - --------------------------------------------------------------------------------
2025-10-30 00:02:41,947 - INFO - Deleted project: ctest-TestSvcRegr-89732661, ID : eb3298f0-17d9-4136-9c99-749286ec1430
2025-10-30 00:02:42,107 - INFO - Domain Default found not creating
2025-10-30 00:02:42,249 - INFO - Project ctest-TestSvcRegr-54486206 not found, creating it
2025-10-30 00:02:42,767 - INFO - Created Project:ctest-TestSvcRegr-54486206, ID : 10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:44,211 - INFO - ================================================================================
2025-10-30 00:02:44,211 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-30 00:02:45,567 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.254': '0', '10.20.0.5': '0'}, '10.0.0.33': {'10.20.0.14': '0', '10.20.0.254': '0'}} with
2025-10-30 00:02:45,567 - INFO - Initial checks done. Running the testcase now
2025-10-30 00:02:45,567 - INFO -
2025-10-30 00:02:46,282 - DEBUG - Response for create_network : {'network': {'id': '901230f4-84b8-407f-9868-43960c60183e', 'name': 'ctest-mgmt_vn-11982298', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'project_id': '10e208f9e43c4906ab5915cf34a2a2af', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54486206', 'ctest-mgmt_vn-11982298'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:46,558 - DEBUG - Response for create_subnet : {'subnet': {'id': '91096297-a295-40f7-b0e7-e0ee2b7b2aea', 'name': '', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'network_id': '901230f4-84b8-407f-9868-43960c60183e', 'ip_version': 4, 'cidr': '99.162.255.0/26', 'allocation_pools': [{'start': '99.162.255.2', 'end': '99.162.255.62'}], 'gateway_ip': '99.162.255.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.162.255.2', 'tags': [], 'project_id': '10e208f9e43c4906ab5915cf34a2a2af'}}
2025-10-30 00:02:46,581 - INFO - Created VN ctest-mgmt_vn-11982298
2025-10-30 00:02:46,591 - DEBUG - VN ctest-mgmt_vn-11982298 UUID is 901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:46,660 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:47,024 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:47,067 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:47,083 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:47,175 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:47,186 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:47,194 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/dcedb838-1883-4ce9-8992-1271c466a749
2025-10-30 00:02:47,202 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/dcedb838-1883-4ce9-8992-1271c466a749
2025-10-30 00:02:47,209 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/480ddf2c-70e5-45dc-8563-cc2c504238ee
2025-10-30 00:02:47,215 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:47,215 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:47,224 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/dcedb838-1883-4ce9-8992-1271c466a749
2025-10-30 00:02:47,238 - INFO - Verified VN network id 16 for VN 901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:47,238 - INFO - Verifications in API Server for VN ctest-mgmt_vn-11982298 passed
2025-10-30 00:02:47,238 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:47,247 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/dcedb838-1883-4ce9-8992-1271c466a749
2025-10-30 00:02:47,254 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/dcedb838-1883-4ce9-8992-1271c466a749
2025-10-30 00:02:47,261 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/480ddf2c-70e5-45dc-8563-cc2c504238ee
2025-10-30 00:02:47,274 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10381413917799235711', 'uuid-lslong': '10982102003074734142'}, 'enable': 'true', 'created': '2025-10-30T00:02:46', 'last-modified': '2025-10-30T00:02:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.765900'}
2025-10-30 00:02:47,279 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:47,284 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10381413917799235711', 'uuid-lslong': '10982102003074734142'}, 'enable': 'true', 'created': '2025-10-30T00:02:46', 'last-modified': '2025-10-30T00:02:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.786455'}
2025-10-30 00:02:47,291 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:47,296 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10381413917799235711', 'uuid-lslong': '10982102003074734142'}, 'enable': 'true', 'created': '2025-10-30T00:02:46', 'last-modified': '2025-10-30T00:02:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.795366'}
2025-10-30 00:02:47,302 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:02:47,302 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-11982298 passed
2025-10-30 00:02:47,302 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-11982298 in API_Server ======
2025-10-30 00:02:47,302 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:47,308 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:47,320 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:47,420 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/901230f4-84b8-407f-9868-43960c60183e
2025-10-30 00:02:47,431 - DEBUG - =>VN ctest-mgmt_vn-11982298 has no policy to be verified
2025-10-30 00:02:47,431 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:47,431 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:47,431 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:47,736 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:47,791 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298?flat'}
2025-10-30 00:02:47,791 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:47,792 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:48,091 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:48,146 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298?flat'}
2025-10-30 00:02:48,146 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:48,146 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:48,436 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:48,487 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298?flat'}
2025-10-30 00:02:48,487 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-11982298 is found in opserver
2025-10-30 00:02:48,513 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:48,522 - DEBUG - VRF ids for VN ctest-mgmt_vn-11982298: {}
2025-10-30 00:02:48,812 - DEBUG - Response for create_network : {'network': {'id': '9738db0f-4482-4357-88cb-ec0bba88f903', 'name': 'service-left-vn-17441905', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'project_id': '10e208f9e43c4906ab5915cf34a2a2af', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54486206', 'service-left-vn-17441905'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:49,085 - DEBUG - Response for create_subnet : {'subnet': {'id': '7cc39da8-e5c9-4645-b3f6-be82bb97739c', 'name': '', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'network_id': '9738db0f-4482-4357-88cb-ec0bba88f903', 'ip_version': 4, 'cidr': '52.76.7.0/26', 'allocation_pools': [{'start': '52.76.7.2', 'end': '52.76.7.62'}], 'gateway_ip': '52.76.7.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.76.7.2', 'tags': [], 'project_id': '10e208f9e43c4906ab5915cf34a2a2af'}}
2025-10-30 00:02:49,105 - INFO - Created VN service-left-vn-17441905
2025-10-30 00:02:49,120 - DEBUG - VN service-left-vn-17441905 UUID is 9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,196 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:49,201 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:49,211 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:49,301 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,313 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,321 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c866f9a5-0354-4056-8a8e-6b24585be07c
2025-10-30 00:02:49,331 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c866f9a5-0354-4056-8a8e-6b24585be07c
2025-10-30 00:02:49,338 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ae90239c-a526-4bae-8d42-be68c0d31236
2025-10-30 00:02:49,344 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:49,344 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,352 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c866f9a5-0354-4056-8a8e-6b24585be07c
2025-10-30 00:02:49,365 - INFO - Verified VN network id 17 for VN 9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,365 - INFO - Verifications in API Server for VN service-left-vn-17441905 passed
2025-10-30 00:02:49,365 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,376 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c866f9a5-0354-4056-8a8e-6b24585be07c
2025-10-30 00:02:49,384 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c866f9a5-0354-4056-8a8e-6b24585be07c
2025-10-30 00:02:49,395 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ae90239c-a526-4bae-8d42-be68c0d31236
2025-10-30 00:02:49,408 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10896700157043295063', 'uuid-lslong': '9857231744548796675'}, 'enable': 'true', 'created': '2025-10-30T00:02:48', 'last-modified': '2025-10-30T00:02:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383578'}
2025-10-30 00:02:49,414 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:49,420 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10896700157043295063', 'uuid-lslong': '9857231744548796675'}, 'enable': 'true', 'created': '2025-10-30T00:02:48', 'last-modified': '2025-10-30T00:02:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396713'}
2025-10-30 00:02:49,426 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:49,436 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10896700157043295063', 'uuid-lslong': '9857231744548796675'}, 'enable': 'true', 'created': '2025-10-30T00:02:48', 'last-modified': '2025-10-30T00:02:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408633'}
2025-10-30 00:02:49,442 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:02:49,442 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-17441905 passed
2025-10-30 00:02:49,443 - DEBUG - ====Verifying policy data for service-left-vn-17441905 in API_Server ======
2025-10-30 00:02:49,443 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:49,448 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:49,457 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:49,556 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9738db0f-4482-4357-88cb-ec0bba88f903
2025-10-30 00:02:49,568 - DEBUG - =>VN service-left-vn-17441905 has no policy to be verified
2025-10-30 00:02:49,568 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:49,568 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:49,568 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:49,618 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905?flat'}
2025-10-30 00:02:49,618 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:49,618 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:49,675 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905?flat'}
2025-10-30 00:02:49,675 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:49,675 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:49,726 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905?flat'}
2025-10-30 00:02:49,726 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54486206:service-left-vn-17441905 is found in opserver
2025-10-30 00:02:49,751 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:49,762 - DEBUG - VRF ids for VN service-left-vn-17441905: {}
2025-10-30 00:02:50,041 - DEBUG - Response for create_network : {'network': {'id': '9c16ea9e-e38f-4225-b9da-25aa556777da', 'name': 'service-right-vn-70299578', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'project_id': '10e208f9e43c4906ab5915cf34a2a2af', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54486206', 'service-right-vn-70299578'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:02:50,274 - DEBUG - Response for create_subnet : {'subnet': {'id': '47696878-af21-4d27-98f2-8710d87c6e8a', 'name': '', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'network_id': '9c16ea9e-e38f-4225-b9da-25aa556777da', 'ip_version': 4, 'cidr': '81.68.92.192/26', 'allocation_pools': [{'start': '81.68.92.194', 'end': '81.68.92.254'}], 'gateway_ip': '81.68.92.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '81.68.92.194', 'tags': [], 'project_id': '10e208f9e43c4906ab5915cf34a2a2af'}}
2025-10-30 00:02:50,299 - INFO - Created VN service-right-vn-70299578
2025-10-30 00:02:50,311 - DEBUG - VN service-right-vn-70299578 UUID is 9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,393 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:50,399 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:50,412 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:50,503 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,522 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,535 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0a726766-4761-41a0-bdcd-643bcc8c3002
2025-10-30 00:02:50,544 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0a726766-4761-41a0-bdcd-643bcc8c3002
2025-10-30 00:02:50,552 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/5fb2cdca-4329-494b-b270-51940cce9998
2025-10-30 00:02:50,560 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:50,560 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,572 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0a726766-4761-41a0-bdcd-643bcc8c3002
2025-10-30 00:02:50,589 - INFO - Verified VN network id 18 for VN 9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,589 - INFO - Verifications in API Server for VN service-right-vn-70299578 passed
2025-10-30 00:02:50,589 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,599 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0a726766-4761-41a0-bdcd-643bcc8c3002
2025-10-30 00:02:50,606 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0a726766-4761-41a0-bdcd-643bcc8c3002
2025-10-30 00:02:50,612 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/5fb2cdca-4329-494b-b270-51940cce9998
2025-10-30 00:02:50,624 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11247435087547941413', 'uuid-lslong': '13392057855447103450'}, 'enable': 'true', 'created': '2025-10-30T00:02:49', 'last-modified': '2025-10-30T00:02:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415636'}
2025-10-30 00:02:50,629 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:50,634 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11247435087547941413', 'uuid-lslong': '13392057855447103450'}, 'enable': 'true', 'created': '2025-10-30T00:02:49', 'last-modified': '2025-10-30T00:02:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428479'}
2025-10-30 00:02:50,642 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:50,648 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11247435087547941413', 'uuid-lslong': '13392057855447103450'}, 'enable': 'true', 'created': '2025-10-30T00:02:49', 'last-modified': '2025-10-30T00:02:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437059'}
2025-10-30 00:02:50,653 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:02:50,653 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-70299578 passed
2025-10-30 00:02:50,654 - DEBUG - ====Verifying policy data for service-right-vn-70299578 in API_Server ======
2025-10-30 00:02:50,654 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:50,659 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:50,674 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:50,781 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/9c16ea9e-e38f-4225-b9da-25aa556777da
2025-10-30 00:02:50,797 - DEBUG - =>VN service-right-vn-70299578 has no policy to be verified
2025-10-30 00:02:50,797 - DEBUG - Verifying the vn in opserver
2025-10-30 00:02:50,797 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578 virtual network link through opserver 10.0.0.254
2025-10-30 00:02:50,797 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:02:50,866 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578?flat'}
2025-10-30 00:02:50,866 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578 virtual network link through opserver 10.0.0.49
2025-10-30 00:02:50,866 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:02:50,926 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578?flat'}
2025-10-30 00:02:50,926 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578 virtual network link through opserver 10.0.0.38
2025-10-30 00:02:50,926 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:02:50,983 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578?flat'}
2025-10-30 00:02:50,983 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54486206:service-right-vn-70299578 is found in opserver
2025-10-30 00:02:51,031 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:02:51,049 - DEBUG - VRF ids for VN service-right-vn-70299578: {}
2025-10-30 00:02:51,119 - DEBUG - Services list from nova: [, , , ]
2025-10-30 00:02:52,173 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-1)
2025-10-30 00:02:53,608 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-2)
2025-10-30 00:02:53,608 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-04458013']
2025-10-30 00:02:53,898 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-04458013'] created succesfully
2025-10-30 00:02:54,284 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 00:02:54,284 - DEBUG - Installing image tiny_nat_fw
2025-10-30 00:02:54,285 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-30 00:02:54,285 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:54,285 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:54,285 - DEBUG - Unzip image
2025-10-30 00:02:54,285 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:02:54,303 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 00:02:54,304 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-04458013']
2025-10-30 00:02:54,418 - INFO - Service template: ['default-domain', 'ctest-service_template_1-04458013'] deleted successfully.
2025-10-30 00:02:54,418 - INFO - Skip interface_detach for VM ctest-right_vm-82996788 in state BUILD
2025-10-30 00:02:54,418 - INFO - Deleting VM ctest-right_vm-82996788
2025-10-30 00:02:54,457 - ERROR - VM ctest-right_vm-82996788 has failed to come up
2025-10-30 00:02:54,460 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:54,460 - ERROR - VM ctest-right_vm-82996788 has failed to come up
2025-10-30 00:02:54,460 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:54,561 - INFO - Skip interface_detach for VM ctest-left_vm-21777447 in state BUILD
2025-10-30 00:02:54,562 - INFO - Deleting VM ctest-left_vm-21777447
2025-10-30 00:02:54,562 - ERROR - VM ctest-left_vm-21777447 has failed to come up
2025-10-30 00:02:54,562 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:54,563 - ERROR - VM ctest-left_vm-21777447 has failed to come up
2025-10-30 00:02:54,563 - ERROR - Nova failed to get host of the VM
2025-10-30 00:02:54,644 - INFO - Deleting VN service-right-vn-70299578
2025-10-30 00:02:54,687 - DEBUG - VN 9c16ea9e-e38f-4225-b9da-25aa556777da still in use: Unable to complete operation on network 9c16ea9e-e38f-4225-b9da-25aa556777da. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-bedfdd43-b4a1-45de-8968-11e9f42642d7']
2025-10-30 00:02:54,687 - WARNING - Deleting VN service-right-vn-70299578 failed..Will retry
2025-10-30 00:02:56,845 - DEBUG - Response for deleting network ()
2025-10-30 00:02:56,845 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0a726766-4761-41a0-bdcd-643bcc8c3002
2025-10-30 00:02:56,850 - DEBUG - Response Code: 404
2025-10-30 00:02:56,851 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:56,862 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:56,879 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:56,981 - INFO - Validated that VN service-right-vn-70299578 is not found in API Server
2025-10-30 00:02:56,995 - DEBUG - VN service-right-vn-70299578 is not present in Agent 10.0.0.65
2025-10-30 00:02:57,009 - DEBUG - VN service-right-vn-70299578 is not present in Agent 10.0.0.33
2025-10-30 00:02:57,009 - INFO - Validated that VN service-right-vn-70299578 is not in any agent
2025-10-30 00:02:57,043 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-70299578 info
2025-10-30 00:02:57,044 - INFO - Deleting VN service-left-vn-17441905
2025-10-30 00:02:57,197 - DEBUG - Response for deleting network ()
2025-10-30 00:02:57,198 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c866f9a5-0354-4056-8a8e-6b24585be07c
2025-10-30 00:02:57,207 - DEBUG - Response Code: 404
2025-10-30 00:02:57,207 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:57,221 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:57,239 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:57,326 - INFO - Validated that VN service-left-vn-17441905 is not found in API Server
2025-10-30 00:02:57,338 - DEBUG - VN service-left-vn-17441905 is not present in Agent 10.0.0.65
2025-10-30 00:02:57,348 - DEBUG - VN service-left-vn-17441905 is not present in Agent 10.0.0.33
2025-10-30 00:02:57,349 - INFO - Validated that VN service-left-vn-17441905 is not in any agent
2025-10-30 00:02:57,377 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-17441905 info
2025-10-30 00:02:57,377 - INFO - Deleting VN ctest-mgmt_vn-11982298
2025-10-30 00:02:57,528 - DEBUG - Response for deleting network ()
2025-10-30 00:02:57,528 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/dcedb838-1883-4ce9-8992-1271c466a749
2025-10-30 00:02:57,535 - DEBUG - Response Code: 404
2025-10-30 00:02:57,535 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:02:57,543 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:02:57,595 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:02:57,692 - INFO - Validated that VN ctest-mgmt_vn-11982298 is not found in API Server
2025-10-30 00:02:57,702 - DEBUG - VN ctest-mgmt_vn-11982298 is not present in Agent 10.0.0.65
2025-10-30 00:02:57,723 - DEBUG - VN ctest-mgmt_vn-11982298 is not present in Agent 10.0.0.33
2025-10-30 00:02:57,723 - INFO - Validated that VN ctest-mgmt_vn-11982298 is not in any agent
2025-10-30 00:02:57,755 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-11982298 info
2025-10-30 00:02:59,089 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 00:02:59,089 - INFO -
2025-10-30 00:02:59,089 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-10-30 00:02:59,090 - INFO - --------------------------------------------------------------------------------
2025-10-30 00:02:59,093 - INFO - ================================================================================
2025-10-30 00:02:59,093 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-30 00:03:00,395 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.254': '0', '10.20.0.5': '0'}, '10.0.0.33': {'10.20.0.14': '0', '10.20.0.254': '0'}} with
2025-10-30 00:03:00,395 - INFO - Initial checks done. Running the testcase now
2025-10-30 00:03:00,395 - INFO -
2025-10-30 00:03:00,647 - DEBUG - Response for create_network : {'network': {'id': '76b19267-ebb7-4f7d-a63b-5b2d4bee2415', 'name': 'ctest-mgmt_vn-81769613', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'project_id': '10e208f9e43c4906ab5915cf34a2a2af', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54486206', 'ctest-mgmt_vn-81769613'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:03:00,875 - DEBUG - Response for create_subnet : {'subnet': {'id': '97e69974-2ca9-4ec7-a82d-2de289ee26d0', 'name': '', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'network_id': '76b19267-ebb7-4f7d-a63b-5b2d4bee2415', 'ip_version': 4, 'cidr': '17.44.112.64/26', 'allocation_pools': [{'start': '17.44.112.66', 'end': '17.44.112.126'}], 'gateway_ip': '17.44.112.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.44.112.66', 'tags': [], 'project_id': '10e208f9e43c4906ab5915cf34a2a2af'}}
2025-10-30 00:03:00,901 - INFO - Created VN ctest-mgmt_vn-81769613
2025-10-30 00:03:00,914 - DEBUG - VN ctest-mgmt_vn-81769613 UUID is 76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:00,983 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:00,990 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:01,002 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:01,096 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:01,110 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:01,119 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/d811ca52-0ea0-4030-b434-de94f11ec99b
2025-10-30 00:03:01,127 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/d811ca52-0ea0-4030-b434-de94f11ec99b
2025-10-30 00:03:01,134 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/b83c2bc1-4a9e-45e4-a55a-daf1e9fcc1a0
2025-10-30 00:03:01,143 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:03:01,143 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:01,152 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/d811ca52-0ea0-4030-b434-de94f11ec99b
2025-10-30 00:03:01,165 - INFO - Verified VN network id 16 for VN 76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:01,165 - INFO - Verifications in API Server for VN ctest-mgmt_vn-81769613 passed
2025-10-30 00:03:01,166 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:01,178 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/d811ca52-0ea0-4030-b434-de94f11ec99b
2025-10-30 00:03:01,184 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/d811ca52-0ea0-4030-b434-de94f11ec99b
2025-10-30 00:03:01,194 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/b83c2bc1-4a9e-45e4-a55a-daf1e9fcc1a0
2025-10-30 00:03:01,204 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8552778142387228541', 'uuid-lslong': '11978267884027520021'}, 'enable': 'true', 'created': '2025-10-30T00:03:00', 'last-modified': '2025-10-30T00:03:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.382199'}
2025-10-30 00:03:01,209 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:03:01,215 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8552778142387228541', 'uuid-lslong': '11978267884027520021'}, 'enable': 'true', 'created': '2025-10-30T00:03:00', 'last-modified': '2025-10-30T00:03:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399104'}
2025-10-30 00:03:01,222 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:03:01,227 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8552778142387228541', 'uuid-lslong': '11978267884027520021'}, 'enable': 'true', 'created': '2025-10-30T00:03:00', 'last-modified': '2025-10-30T00:03:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408950'}
2025-10-30 00:03:01,233 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 00:03:01,233 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-81769613 passed
2025-10-30 00:03:01,233 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-81769613 in API_Server ======
2025-10-30 00:03:01,233 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:01,239 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:01,249 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:01,341 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/76b19267-ebb7-4f7d-a63b-5b2d4bee2415
2025-10-30 00:03:01,352 - DEBUG - =>VN ctest-mgmt_vn-81769613 has no policy to be verified
2025-10-30 00:03:01,352 - DEBUG - Verifying the vn in opserver
2025-10-30 00:03:01,352 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613 virtual network link through opserver 10.0.0.254
2025-10-30 00:03:01,352 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:03:01,364 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613?flat'}
2025-10-30 00:03:01,364 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613 virtual network link through opserver 10.0.0.49
2025-10-30 00:03:01,364 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:03:01,378 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613?flat'}
2025-10-30 00:03:01,378 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613 virtual network link through opserver 10.0.0.38
2025-10-30 00:03:01,378 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:03:01,391 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613?flat'}
2025-10-30 00:03:01,391 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54486206:ctest-mgmt_vn-81769613 is found in opserver
2025-10-30 00:03:01,423 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:03:01,435 - DEBUG - VRF ids for VN ctest-mgmt_vn-81769613: {}
2025-10-30 00:03:01,716 - DEBUG - Response for create_network : {'network': {'id': 'aea8780f-d5f8-44e1-9887-5e4868ae8224', 'name': 'ctest-left_vn-07705172', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'project_id': '10e208f9e43c4906ab5915cf34a2a2af', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54486206', 'ctest-left_vn-07705172'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:03:01,986 - DEBUG - Response for create_subnet : {'subnet': {'id': '8f1fe683-e72f-4856-a990-0ba11d109bf6', 'name': '', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'network_id': 'aea8780f-d5f8-44e1-9887-5e4868ae8224', 'ip_version': 4, 'cidr': '181.100.0.128/26', 'allocation_pools': [{'start': '181.100.0.130', 'end': '181.100.0.190'}], 'gateway_ip': '181.100.0.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '181.100.0.130', 'tags': [], 'project_id': '10e208f9e43c4906ab5915cf34a2a2af'}}
2025-10-30 00:03:02,022 - INFO - Created VN ctest-left_vn-07705172
2025-10-30 00:03:02,033 - DEBUG - VN ctest-left_vn-07705172 UUID is aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,116 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:02,123 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:02,135 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:02,306 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,321 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,335 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/1dde0d3e-eac5-40c0-ac26-2d846bdf1d47
2025-10-30 00:03:02,345 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/1dde0d3e-eac5-40c0-ac26-2d846bdf1d47
2025-10-30 00:03:02,355 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/e31d42f3-c1fb-47b1-9418-6946c7f5dfa6
2025-10-30 00:03:02,362 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:03:02,362 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,374 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/1dde0d3e-eac5-40c0-ac26-2d846bdf1d47
2025-10-30 00:03:02,390 - INFO - Verified VN network id 17 for VN aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,390 - INFO - Verifications in API Server for VN ctest-left_vn-07705172 passed
2025-10-30 00:03:02,390 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,400 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/1dde0d3e-eac5-40c0-ac26-2d846bdf1d47
2025-10-30 00:03:02,408 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/1dde0d3e-eac5-40c0-ac26-2d846bdf1d47
2025-10-30 00:03:02,415 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/e31d42f3-c1fb-47b1-9418-6946c7f5dfa6
2025-10-30 00:03:02,427 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12585441168096511201', 'uuid-lslong': '10990857080707908132'}, 'enable': 'true', 'created': '2025-10-30T00:03:01', 'last-modified': '2025-10-30T00:03:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.503744'}
2025-10-30 00:03:02,434 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:03:02,440 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12585441168096511201', 'uuid-lslong': '10990857080707908132'}, 'enable': 'true', 'created': '2025-10-30T00:03:01', 'last-modified': '2025-10-30T00:03:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.516328'}
2025-10-30 00:03:02,447 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:03:02,453 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12585441168096511201', 'uuid-lslong': '10990857080707908132'}, 'enable': 'true', 'created': '2025-10-30T00:03:01', 'last-modified': '2025-10-30T00:03:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.530792'}
2025-10-30 00:03:02,459 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 00:03:02,459 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-07705172 passed
2025-10-30 00:03:02,459 - DEBUG - ====Verifying policy data for ctest-left_vn-07705172 in API_Server ======
2025-10-30 00:03:02,459 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:02,465 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:02,477 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:02,575 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aea8780f-d5f8-44e1-9887-5e4868ae8224
2025-10-30 00:03:02,586 - DEBUG - =>VN ctest-left_vn-07705172 has no policy to be verified
2025-10-30 00:03:02,586 - DEBUG - Verifying the vn in opserver
2025-10-30 00:03:02,586 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172 virtual network link through opserver 10.0.0.254
2025-10-30 00:03:02,586 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:03:02,598 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172?flat'}
2025-10-30 00:03:02,598 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172 virtual network link through opserver 10.0.0.49
2025-10-30 00:03:02,598 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:03:02,611 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172?flat'}
2025-10-30 00:03:02,611 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172 virtual network link through opserver 10.0.0.38
2025-10-30 00:03:02,611 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:03:02,626 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172?flat'}
2025-10-30 00:03:02,626 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54486206:ctest-left_vn-07705172 is found in opserver
2025-10-30 00:03:02,660 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:03:02,674 - DEBUG - VRF ids for VN ctest-left_vn-07705172: {}
2025-10-30 00:03:02,928 - DEBUG - Response for create_network : {'network': {'id': '5b178e11-6775-4c7b-9325-52ef7e1cff79', 'name': 'ctest-right_vn-01521085', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'project_id': '10e208f9e43c4906ab5915cf34a2a2af', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54486206', 'ctest-right_vn-01521085'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 00:03:03,131 - DEBUG - Response for create_subnet : {'subnet': {'id': '027e6dbf-c46d-4f11-bc65-77e03e554f4c', 'name': '', 'tenant_id': '10e208f9e43c4906ab5915cf34a2a2af', 'network_id': '5b178e11-6775-4c7b-9325-52ef7e1cff79', 'ip_version': 4, 'cidr': '25.207.22.64/26', 'allocation_pools': [{'start': '25.207.22.66', 'end': '25.207.22.126'}], 'gateway_ip': '25.207.22.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '25.207.22.66', 'tags': [], 'project_id': '10e208f9e43c4906ab5915cf34a2a2af'}}
2025-10-30 00:03:03,159 - INFO - Created VN ctest-right_vn-01521085
2025-10-30 00:03:03,173 - DEBUG - VN ctest-right_vn-01521085 UUID is 5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,251 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:03,258 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:03,272 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:03,376 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,395 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,408 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f0f72ffe-fe24-4ca2-935b-3ef4a1c3a7a3
2025-10-30 00:03:03,420 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f0f72ffe-fe24-4ca2-935b-3ef4a1c3a7a3
2025-10-30 00:03:03,429 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/91e28378-c9ff-454a-908a-10e066e3c09b
2025-10-30 00:03:03,437 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:03:03,437 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,450 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f0f72ffe-fe24-4ca2-935b-3ef4a1c3a7a3
2025-10-30 00:03:03,467 - INFO - Verified VN network id 18 for VN 5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,467 - INFO - Verifications in API Server for VN ctest-right_vn-01521085 passed
2025-10-30 00:03:03,468 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,478 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f0f72ffe-fe24-4ca2-935b-3ef4a1c3a7a3
2025-10-30 00:03:03,486 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f0f72ffe-fe24-4ca2-935b-3ef4a1c3a7a3
2025-10-30 00:03:03,496 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/91e28378-c9ff-454a-908a-10e066e3c09b
2025-10-30 00:03:03,510 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6563871187317116027', 'uuid-lslong': '10602972086280191865'}, 'enable': 'true', 'created': '2025-10-30T00:03:02', 'last-modified': '2025-10-30T00:03:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425024'}
2025-10-30 00:03:03,516 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:03:03,522 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6563871187317116027', 'uuid-lslong': '10602972086280191865'}, 'enable': 'true', 'created': '2025-10-30T00:03:02', 'last-modified': '2025-10-30T00:03:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426860'}
2025-10-30 00:03:03,528 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:03:03,535 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54486206', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6563871187317116027', 'uuid-lslong': '10602972086280191865'}, 'enable': 'true', 'created': '2025-10-30T00:03:02', 'last-modified': '2025-10-30T00:03:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449484'}
2025-10-30 00:03:03,541 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 00:03:03,541 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-01521085 passed
2025-10-30 00:03:03,542 - DEBUG - ====Verifying policy data for ctest-right_vn-01521085 in API_Server ======
2025-10-30 00:03:03,542 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:03,549 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:03,562 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:03,666 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5b178e11-6775-4c7b-9325-52ef7e1cff79
2025-10-30 00:03:03,678 - DEBUG - =>VN ctest-right_vn-01521085 has no policy to be verified
2025-10-30 00:03:03,678 - DEBUG - Verifying the vn in opserver
2025-10-30 00:03:03,678 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085 virtual network link through opserver 10.0.0.254
2025-10-30 00:03:03,678 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 00:03:03,694 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085?flat'}
2025-10-30 00:03:03,694 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085 virtual network link through opserver 10.0.0.49
2025-10-30 00:03:03,694 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-10-30 00:03:03,707 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085?flat'}
2025-10-30 00:03:03,707 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085 virtual network link through opserver 10.0.0.38
2025-10-30 00:03:03,707 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-30 00:03:03,720 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085?flat'}
2025-10-30 00:03:03,720 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54486206:ctest-right_vn-01521085 is found in opserver
2025-10-30 00:03:03,747 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 00:03:03,762 - DEBUG - VRF ids for VN ctest-right_vn-01521085: {}
2025-10-30 00:03:04,814 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-2)
2025-10-30 00:03:06,087 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4529-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4529-1)
2025-10-30 00:03:06,087 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-17696993']
2025-10-30 00:03:06,387 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-17696993'] created succesfully
2025-10-30 00:03:06,751 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 00:03:06,751 - DEBUG - Installing image tiny_nat_fw
2025-10-30 00:03:06,751 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-30 00:03:06,752 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:03:06,752 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:03:06,752 - DEBUG - Unzip image
2025-10-30 00:03:06,752 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 00:03:06,773 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 00:03:06,773 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-17696993']
2025-10-30 00:03:06,870 - INFO - Service template: ['default-domain', 'ctest-service_template_1-17696993'] deleted successfully.
2025-10-30 00:03:06,870 - INFO - Skip interface_detach for VM ctest-right_vm-95447307 in state BUILD
2025-10-30 00:03:06,870 - INFO - Deleting VM ctest-right_vm-95447307
2025-10-30 00:03:06,870 - ERROR - VM ctest-right_vm-95447307 has failed to come up
2025-10-30 00:03:06,870 - ERROR - Nova failed to get host of the VM
2025-10-30 00:03:06,870 - ERROR - VM ctest-right_vm-95447307 has failed to come up
2025-10-30 00:03:06,870 - ERROR - Nova failed to get host of the VM
2025-10-30 00:03:07,017 - INFO - Skip interface_detach for VM ctest-left_vm-87151992 in state BUILD
2025-10-30 00:03:07,017 - INFO - Deleting VM ctest-left_vm-87151992
2025-10-30 00:03:07,017 - ERROR - VM ctest-left_vm-87151992 has failed to come up
2025-10-30 00:03:07,017 - ERROR - Nova failed to get host of the VM
2025-10-30 00:03:07,017 - ERROR - VM ctest-left_vm-87151992 has failed to come up
2025-10-30 00:03:07,017 - ERROR - Nova failed to get host of the VM
2025-10-30 00:03:07,096 - INFO - Deleting VN ctest-right_vn-01521085
2025-10-30 00:03:07,153 - DEBUG - VN 5b178e11-6775-4c7b-9325-52ef7e1cff79 still in use: Unable to complete operation on network 5b178e11-6775-4c7b-9325-52ef7e1cff79. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7843d6fa-f7ac-4d4d-89e3-b9aac3ac981e']
2025-10-30 00:03:07,153 - WARNING - Deleting VN ctest-right_vn-01521085 failed..Will retry
2025-10-30 00:03:09,323 - DEBUG - Response for deleting network ()
2025-10-30 00:03:09,323 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f0f72ffe-fe24-4ca2-935b-3ef4a1c3a7a3
2025-10-30 00:03:09,333 - DEBUG - Response Code: 404
2025-10-30 00:03:09,333 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:09,343 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:09,366 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:09,472 - INFO - Validated that VN ctest-right_vn-01521085 is not found in API Server
2025-10-30 00:03:09,486 - DEBUG - VN ctest-right_vn-01521085 is not present in Agent 10.0.0.65
2025-10-30 00:03:09,509 - DEBUG - VN ctest-right_vn-01521085 is not present in Agent 10.0.0.33
2025-10-30 00:03:09,509 - INFO - Validated that VN ctest-right_vn-01521085 is not in any agent
2025-10-30 00:03:09,556 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-01521085 info
2025-10-30 00:03:09,556 - INFO - Deleting VN ctest-left_vn-07705172
2025-10-30 00:03:09,693 - DEBUG - Response for deleting network ()
2025-10-30 00:03:09,693 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/1dde0d3e-eac5-40c0-ac26-2d846bdf1d47
2025-10-30 00:03:09,700 - DEBUG - Response Code: 404
2025-10-30 00:03:09,700 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:09,707 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:09,720 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:09,822 - INFO - Validated that VN ctest-left_vn-07705172 is not found in API Server
2025-10-30 00:03:09,832 - DEBUG - VN ctest-left_vn-07705172 is not present in Agent 10.0.0.65
2025-10-30 00:03:09,851 - DEBUG - VN ctest-left_vn-07705172 is not present in Agent 10.0.0.33
2025-10-30 00:03:09,851 - INFO - Validated that VN ctest-left_vn-07705172 is not in any agent
2025-10-30 00:03:09,887 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-07705172 info
2025-10-30 00:03:09,887 - INFO - Deleting VN ctest-mgmt_vn-81769613
2025-10-30 00:03:10,028 - DEBUG - Response for deleting network ()
2025-10-30 00:03:10,028 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/d811ca52-0ea0-4030-b434-de94f11ec99b
2025-10-30 00:03:10,043 - DEBUG - Response Code: 404
2025-10-30 00:03:10,043 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-30 00:03:10,052 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0f965a2d-727a-40b2-a2d7-c44e752d08a7
2025-10-30 00:03:10,065 - DEBUG - Requesting: http://10.0.0.254:8082/project/10e208f9-e43c-4906-ab59-15cf34a2a2af
2025-10-30 00:03:10,171 - INFO - Validated that VN ctest-mgmt_vn-81769613 is not found in API Server
2025-10-30 00:03:10,184 - DEBUG - VN ctest-mgmt_vn-81769613 is not present in Agent 10.0.0.65
2025-10-30 00:03:10,205 - DEBUG - VN ctest-mgmt_vn-81769613 is not present in Agent 10.0.0.33
2025-10-30 00:03:10,205 - INFO - Validated that VN ctest-mgmt_vn-81769613 is not in any agent
2025-10-30 00:03:10,239 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-81769613 info
2025-10-30 00:03:11,571 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 00:03:11,571 - INFO -
2025-10-30 00:03:11,571 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-10-30 00:03:11,571 - INFO - --------------------------------------------------------------------------------
2025-10-30 00:03:12,282 - INFO - Deleted project: ctest-TestSvcRegr-54486206, ID : 10e208f9-e43c-4906-ab59-15cf34a2a2af