]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-1)
2025-11-17 03:12:04,672 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-57977395']
2025-11-17 03:12:04,984 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-57977395'] created succesfully
2025-11-17 03:12:05,526 - DEBUG - Image by name tiny_nat_fw not found
2025-11-17 03:12:05,527 - DEBUG - Installing image tiny_nat_fw
2025-11-17 03:12:05,527 - 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-11-17 03:12:05,527 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:05,527 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:05,546 - 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-11-17 03:12:05,611 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:05,611 - DEBUG - Unzip image
2025-11-17 03:12:05,611 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:05,658 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-17 03:12:05,658 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-57977395']
2025-11-17 03:12:05,789 - INFO - Service template: ['default-domain', 'ctest-service_template_1-57977395'] deleted successfully.
2025-11-17 03:12:05,789 - INFO - Skip interface_detach for VM ctest-right_vm-16676472 in state BUILD
2025-11-17 03:12:05,790 - INFO - Deleting VM ctest-right_vm-16676472
2025-11-17 03:12:05,853 - ERROR - VM ctest-right_vm-16676472 has failed to come up
2025-11-17 03:12:05,853 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:05,853 - ERROR - VM ctest-right_vm-16676472 has failed to come up
2025-11-17 03:12:05,853 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:05,996 - INFO - Skip interface_detach for VM ctest-left_vm-98878917 in state BUILD
2025-11-17 03:12:05,997 - INFO - Deleting VM ctest-left_vm-98878917
2025-11-17 03:12:05,997 - ERROR - VM ctest-left_vm-98878917 has failed to come up
2025-11-17 03:12:05,997 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:05,997 - ERROR - VM ctest-left_vm-98878917 has failed to come up
2025-11-17 03:12:05,997 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:06,096 - INFO - Deleting VN ctest-right_vn-92020346
2025-11-17 03:12:06,145 - DEBUG - VN c1f3f162-77d3-4115-a371-dd9cf22acfbc still in use: Unable to complete operation on network c1f3f162-77d3-4115-a371-dd9cf22acfbc. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c0bda834-1751-437e-a79c-a91865f721e3']
2025-11-17 03:12:06,145 - WARNING - Deleting VN ctest-right_vn-92020346 failed..Will retry
2025-11-17 03:12:08,328 - DEBUG - Response for deleting network ()
2025-11-17 03:12:08,328 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/bf1bb987-ad82-4fdb-ad50-97d53e18be3b
2025-11-17 03:12:08,348 - DEBUG - Response Code: 404
2025-11-17 03:12:08,348 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:08,363 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:08,396 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:08,521 - INFO - Validated that VN ctest-right_vn-92020346 is not found in API Server
2025-11-17 03:12:08,555 - DEBUG - VN ctest-right_vn-92020346 is not present in Agent 10.0.0.50
2025-11-17 03:12:08,572 - DEBUG - VN ctest-right_vn-92020346 is not present in Agent 10.0.0.73
2025-11-17 03:12:08,572 - INFO - Validated that VN ctest-right_vn-92020346 is not in any agent
2025-11-17 03:12:08,630 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-92020346 info
2025-11-17 03:12:08,630 - INFO - Deleting VN ctest-left_vn-86216405
2025-11-17 03:12:08,804 - DEBUG - Response for deleting network ()
2025-11-17 03:12:08,804 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/89f400af-a76b-4d42-bd1c-95aa05c52ab7
2025-11-17 03:12:08,811 - DEBUG - Response Code: 404
2025-11-17 03:12:08,812 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:08,824 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:08,851 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:09,033 - INFO - Validated that VN ctest-left_vn-86216405 is not found in API Server
2025-11-17 03:12:09,050 - DEBUG - VN ctest-left_vn-86216405 is not present in Agent 10.0.0.50
2025-11-17 03:12:09,063 - DEBUG - VN ctest-left_vn-86216405 is not present in Agent 10.0.0.73
2025-11-17 03:12:09,063 - INFO - Validated that VN ctest-left_vn-86216405 is not in any agent
2025-11-17 03:12:09,118 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-86216405 info
2025-11-17 03:12:09,118 - INFO - Deleting VN ctest-mgmt_vn-97754169
2025-11-17 03:12:09,294 - DEBUG - Response for deleting network ()
2025-11-17 03:12:09,294 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/50f00fc9-9346-412e-8ad8-9894babae65c
2025-11-17 03:12:09,301 - DEBUG - Response Code: 404
2025-11-17 03:12:09,301 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:09,320 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:09,350 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:09,462 - INFO - Validated that VN ctest-mgmt_vn-97754169 is not found in API Server
2025-11-17 03:12:09,481 - DEBUG - VN ctest-mgmt_vn-97754169 is not present in Agent 10.0.0.50
2025-11-17 03:12:09,495 - DEBUG - VN ctest-mgmt_vn-97754169 is not present in Agent 10.0.0.73
2025-11-17 03:12:09,495 - INFO - Validated that VN ctest-mgmt_vn-97754169 is not in any agent
2025-11-17 03:12:09,544 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-97754169 info
2025-11-17 03:12:09,545 - INFO - Deleting VM ctest-vm-in-third-vn-53391605
2025-11-17 03:12:09,649 - INFO - Deleting VN ctest-third-vn-96627814
2025-11-17 03:12:09,698 - DEBUG - VN c2df58ce-d74a-4688-ae0e-57d2e38cc183 still in use: Unable to complete operation on network c2df58ce-d74a-4688-ae0e-57d2e38cc183. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-07f189e7-983e-44b7-bba1-6b53dd27dd23']
2025-11-17 03:12:09,698 - WARNING - Deleting VN ctest-third-vn-96627814 failed..Will retry
2025-11-17 03:12:11,876 - DEBUG - Response for deleting network ()
2025-11-17 03:12:13,161 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 03:12:13,161 - INFO -
2025-11-17 03:12:13,161 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2025-11-17 03:12:13,161 - INFO - --------------------------------------------------------------------------------
2025-11-17 03:12:13,164 - INFO - ================================================================================
2025-11-17 03:12:13,164 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-11-17 03:12:14,449 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.73': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-11-17 03:12:14,449 - INFO - Initial checks done. Running the testcase now
2025-11-17 03:12:14,449 - INFO -
2025-11-17 03:12:14,722 - DEBUG - Response for create_network : {'network': {'id': 'f70810d9-2802-465d-bf5f-67e6ed275ab5', 'name': 'ctest-mgmt_vn-05241105', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'project_id': '12f8c26729464e21a609cc1372834a3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-91185332', 'ctest-mgmt_vn-05241105'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:14,976 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a234a672-74e4-4bde-9cf2-edcb5ba9a1b5', 'name': '', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'network_id': 'f70810d9-2802-465d-bf5f-67e6ed275ab5', 'ip_version': 4, 'cidr': '140.247.206.0/26', 'allocation_pools': [{'start': '140.247.206.2', 'end': '140.247.206.62'}], 'gateway_ip': '140.247.206.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '140.247.206.2', 'tags': [], 'project_id': '12f8c26729464e21a609cc1372834a3c'}}
2025-11-17 03:12:14,999 - INFO - Created VN ctest-mgmt_vn-05241105
2025-11-17 03:12:15,016 - DEBUG - VN ctest-mgmt_vn-05241105 UUID is f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,090 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:15,097 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:15,111 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:15,212 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,226 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,237 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0fc2b9c1-7e79-4718-8701-7743515df909
2025-11-17 03:12:15,244 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0fc2b9c1-7e79-4718-8701-7743515df909
2025-11-17 03:12:15,252 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/cb3c4a87-88c0-4cd6-a5c0-6166fc674b5b
2025-11-17 03:12:15,260 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:15,260 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,269 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0fc2b9c1-7e79-4718-8701-7743515df909
2025-11-17 03:12:15,290 - INFO - Verified VN network id 14 for VN f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,290 - INFO - Verifications in API Server for VN ctest-mgmt_vn-05241105 passed
2025-11-17 03:12:15,291 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,302 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0fc2b9c1-7e79-4718-8701-7743515df909
2025-11-17 03:12:15,311 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0fc2b9c1-7e79-4718-8701-7743515df909
2025-11-17 03:12:15,321 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/cb3c4a87-88c0-4cd6-a5c0-6166fc674b5b
2025-11-17 03:12:15,336 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17800496052047070813', 'uuid-lslong': '13789854825550666421'}, 'enable': 'true', 'created': '2025-11-17T03:12:14', 'last-modified': '2025-11-17T03:12:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443694'}
2025-11-17 03:12:15,345 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:15,351 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17800496052047070813', 'uuid-lslong': '13789854825550666421'}, 'enable': 'true', 'created': '2025-11-17T03:12:14', 'last-modified': '2025-11-17T03:12:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.461456'}
2025-11-17 03:12:15,358 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:15,364 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17800496052047070813', 'uuid-lslong': '13789854825550666421'}, 'enable': 'true', 'created': '2025-11-17T03:12:14', 'last-modified': '2025-11-17T03:12:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477483'}
2025-11-17 03:12:15,372 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:15,373 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-05241105 passed
2025-11-17 03:12:15,373 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-05241105 in API_Server ======
2025-11-17 03:12:15,373 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:15,380 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:15,391 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:15,498 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f70810d9-2802-465d-bf5f-67e6ed275ab5
2025-11-17 03:12:15,512 - DEBUG - =>VN ctest-mgmt_vn-05241105 has no policy to be verified
2025-11-17 03:12:15,512 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:15,512 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:15,512 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:15,525 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105?flat'}
2025-11-17 03:12:15,525 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:15,525 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:15,539 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105?flat'}
2025-11-17 03:12:15,539 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:15,539 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:15,551 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105?flat'}
2025-11-17 03:12:15,551 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-91185332:ctest-mgmt_vn-05241105 is found in opserver
2025-11-17 03:12:15,573 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:15,583 - DEBUG - VRF ids for VN ctest-mgmt_vn-05241105: {}
2025-11-17 03:12:15,877 - DEBUG - Response for create_network : {'network': {'id': 'd115775e-0afb-451a-b74b-cfde80b1c37f', 'name': 'ctest-left_vn-42100903', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'project_id': '12f8c26729464e21a609cc1372834a3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-91185332', 'ctest-left_vn-42100903'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:16,094 - DEBUG - Response for create_subnet : {'subnet': {'id': '2a1e8303-47ec-4bda-9b11-9019cba88a1b', 'name': '', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'network_id': 'd115775e-0afb-451a-b74b-cfde80b1c37f', 'ip_version': 4, 'cidr': '161.159.71.0/26', 'allocation_pools': [{'start': '161.159.71.2', 'end': '161.159.71.62'}], 'gateway_ip': '161.159.71.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '161.159.71.2', 'tags': [], 'project_id': '12f8c26729464e21a609cc1372834a3c'}}
2025-11-17 03:12:16,119 - INFO - Created VN ctest-left_vn-42100903
2025-11-17 03:12:16,131 - DEBUG - VN ctest-left_vn-42100903 UUID is d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,211 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:16,219 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:16,230 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:16,328 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,343 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,352 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fb773b7-06ec-465b-8c1b-e0b391aea68b
2025-11-17 03:12:16,363 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fb773b7-06ec-465b-8c1b-e0b391aea68b
2025-11-17 03:12:16,371 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d617be0b-1bbe-4a82-a19e-fb167e66cbae
2025-11-17 03:12:16,378 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:16,378 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,388 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fb773b7-06ec-465b-8c1b-e0b391aea68b
2025-11-17 03:12:16,404 - INFO - Verified VN network id 15 for VN d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,404 - INFO - Verifications in API Server for VN ctest-left_vn-42100903 passed
2025-11-17 03:12:16,404 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,414 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fb773b7-06ec-465b-8c1b-e0b391aea68b
2025-11-17 03:12:16,423 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fb773b7-06ec-465b-8c1b-e0b391aea68b
2025-11-17 03:12:16,432 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d617be0b-1bbe-4a82-a19e-fb167e66cbae
2025-11-17 03:12:16,449 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15066079374232732954', 'uuid-lslong': '13207878886742934399'}, 'enable': 'true', 'created': '2025-11-17T03:12:15', 'last-modified': '2025-11-17T03:12:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409713'}
2025-11-17 03:12:16,456 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:16,461 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15066079374232732954', 'uuid-lslong': '13207878886742934399'}, 'enable': 'true', 'created': '2025-11-17T03:12:15', 'last-modified': '2025-11-17T03:12:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420648'}
2025-11-17 03:12:16,469 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:16,475 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15066079374232732954', 'uuid-lslong': '13207878886742934399'}, 'enable': 'true', 'created': '2025-11-17T03:12:15', 'last-modified': '2025-11-17T03:12:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436936'}
2025-11-17 03:12:16,483 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:16,483 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-42100903 passed
2025-11-17 03:12:16,483 - DEBUG - ====Verifying policy data for ctest-left_vn-42100903 in API_Server ======
2025-11-17 03:12:16,483 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:16,493 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:16,510 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:16,618 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d115775e-0afb-451a-b74b-cfde80b1c37f
2025-11-17 03:12:16,630 - DEBUG - =>VN ctest-left_vn-42100903 has no policy to be verified
2025-11-17 03:12:16,630 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:16,630 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:16,630 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:16,645 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903?flat'}
2025-11-17 03:12:16,645 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:16,645 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:16,659 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903?flat'}
2025-11-17 03:12:16,659 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:16,659 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:16,672 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903?flat'}
2025-11-17 03:12:16,672 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-91185332:ctest-left_vn-42100903 is found in opserver
2025-11-17 03:12:16,693 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:16,703 - DEBUG - VRF ids for VN ctest-left_vn-42100903: {}
2025-11-17 03:12:16,966 - DEBUG - Response for create_network : {'network': {'id': '0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8', 'name': 'ctest-right_vn-70330680', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'project_id': '12f8c26729464e21a609cc1372834a3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-91185332', 'ctest-right_vn-70330680'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:17,184 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e2e9ccaf-7f5a-452b-8e36-51fb0a5ff492', 'name': '', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'network_id': '0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8', 'ip_version': 4, 'cidr': '16.119.248.128/26', 'allocation_pools': [{'start': '16.119.248.130', 'end': '16.119.248.190'}], 'gateway_ip': '16.119.248.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '16.119.248.130', 'tags': [], 'project_id': '12f8c26729464e21a609cc1372834a3c'}}
2025-11-17 03:12:17,215 - INFO - Created VN ctest-right_vn-70330680
2025-11-17 03:12:17,230 - DEBUG - VN ctest-right_vn-70330680 UUID is 0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,320 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:17,327 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:17,339 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:17,442 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,462 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,472 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7fc6c45c-0b91-4771-8ba5-990b8e20a06f
2025-11-17 03:12:17,480 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7fc6c45c-0b91-4771-8ba5-990b8e20a06f
2025-11-17 03:12:17,488 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f0f11621-ae35-4b9a-86fa-77f2abdc89c1
2025-11-17 03:12:17,498 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:17,498 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,507 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7fc6c45c-0b91-4771-8ba5-990b8e20a06f
2025-11-17 03:12:17,525 - INFO - Verified VN network id 16 for VN 0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,525 - INFO - Verifications in API Server for VN ctest-right_vn-70330680 passed
2025-11-17 03:12:17,525 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,537 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7fc6c45c-0b91-4771-8ba5-990b8e20a06f
2025-11-17 03:12:17,546 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7fc6c45c-0b91-4771-8ba5-990b8e20a06f
2025-11-17 03:12:17,554 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f0f11621-ae35-4b9a-86fa-77f2abdc89c1
2025-11-17 03:12:17,568 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '845462148659432624', 'uuid-lslong': '10726780045801210856'}, 'enable': 'true', 'created': '2025-11-17T03:12:16', 'last-modified': '2025-11-17T03:12:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432582'}
2025-11-17 03:12:17,574 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:17,583 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '845462148659432624', 'uuid-lslong': '10726780045801210856'}, 'enable': 'true', 'created': '2025-11-17T03:12:16', 'last-modified': '2025-11-17T03:12:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.455206'}
2025-11-17 03:12:17,591 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:17,597 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '845462148659432624', 'uuid-lslong': '10726780045801210856'}, 'enable': 'true', 'created': '2025-11-17T03:12:16', 'last-modified': '2025-11-17T03:12:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464067'}
2025-11-17 03:12:17,604 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:17,604 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-70330680 passed
2025-11-17 03:12:17,604 - DEBUG - ====Verifying policy data for ctest-right_vn-70330680 in API_Server ======
2025-11-17 03:12:17,605 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:17,610 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:17,621 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:17,714 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8
2025-11-17 03:12:17,728 - DEBUG - =>VN ctest-right_vn-70330680 has no policy to be verified
2025-11-17 03:12:17,728 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:17,728 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:17,728 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:17,742 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680?flat'}
2025-11-17 03:12:17,742 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:17,743 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:17,757 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680?flat'}
2025-11-17 03:12:17,758 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:17,758 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:17,770 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680?flat'}
2025-11-17 03:12:17,770 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-91185332:ctest-right_vn-70330680 is found in opserver
2025-11-17 03:12:17,796 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:17,809 - DEBUG - VRF ids for VN ctest-right_vn-70330680: {}
2025-11-17 03:12:18,089 - DEBUG - Response for create_network : {'network': {'id': '5c002fca-e2ec-4db6-8012-4d176e5c06b5', 'name': 'ctest-trans_left_vn-37258963', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'project_id': '12f8c26729464e21a609cc1372834a3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-91185332', 'ctest-trans_left_vn-37258963'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:18,366 - DEBUG - Response for create_subnet : {'subnet': {'id': '5d30b299-bc4a-4500-9688-29827a6713f8', 'name': '', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'network_id': '5c002fca-e2ec-4db6-8012-4d176e5c06b5', 'ip_version': 4, 'cidr': '130.179.250.64/26', 'allocation_pools': [{'start': '130.179.250.66', 'end': '130.179.250.126'}], 'gateway_ip': '130.179.250.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.179.250.66', 'tags': [], 'project_id': '12f8c26729464e21a609cc1372834a3c'}}
2025-11-17 03:12:18,396 - INFO - Created VN ctest-trans_left_vn-37258963
2025-11-17 03:12:18,411 - DEBUG - VN ctest-trans_left_vn-37258963 UUID is 5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,495 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:18,502 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:18,516 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:18,617 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,633 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,643 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d337caa-671c-4bf2-9524-e464b607d847
2025-11-17 03:12:18,651 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d337caa-671c-4bf2-9524-e464b607d847
2025-11-17 03:12:18,658 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/40322602-434b-4675-8779-e37e4e97140a
2025-11-17 03:12:18,666 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-17 03:12:18,666 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,675 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d337caa-671c-4bf2-9524-e464b607d847
2025-11-17 03:12:18,694 - INFO - Verified VN network id 17 for VN 5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,694 - INFO - Verifications in API Server for VN ctest-trans_left_vn-37258963 passed
2025-11-17 03:12:18,694 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,706 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d337caa-671c-4bf2-9524-e464b607d847
2025-11-17 03:12:18,715 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d337caa-671c-4bf2-9524-e464b607d847
2025-11-17 03:12:18,722 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/40322602-434b-4675-8779-e37e4e97140a
2025-11-17 03:12:18,735 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6629351199926406582', 'uuid-lslong': '9228523349466678965'}, 'enable': 'true', 'created': '2025-11-17T03:12:18', 'last-modified': '2025-11-17T03:12:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440275'}
2025-11-17 03:12:18,742 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-17 03:12:18,747 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6629351199926406582', 'uuid-lslong': '9228523349466678965'}, 'enable': 'true', 'created': '2025-11-17T03:12:18', 'last-modified': '2025-11-17T03:12:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453380'}
2025-11-17 03:12:18,753 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-17 03:12:18,759 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6629351199926406582', 'uuid-lslong': '9228523349466678965'}, 'enable': 'true', 'created': '2025-11-17T03:12:18', 'last-modified': '2025-11-17T03:12:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466882'}
2025-11-17 03:12:18,766 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-17 03:12:18,766 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-37258963 passed
2025-11-17 03:12:18,766 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-37258963 in API_Server ======
2025-11-17 03:12:18,766 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:18,772 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:18,783 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:18,886 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c002fca-e2ec-4db6-8012-4d176e5c06b5
2025-11-17 03:12:18,896 - DEBUG - =>VN ctest-trans_left_vn-37258963 has no policy to be verified
2025-11-17 03:12:18,896 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:18,896 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:18,896 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:18,909 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963?flat'}
2025-11-17 03:12:18,909 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:18,909 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:18,922 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963?flat'}
2025-11-17 03:12:18,922 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:18,923 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:18,936 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963?flat'}
2025-11-17 03:12:18,937 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-91185332:ctest-trans_left_vn-37258963 is found in opserver
2025-11-17 03:12:18,962 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:18,974 - DEBUG - VRF ids for VN ctest-trans_left_vn-37258963: {}
2025-11-17 03:12:19,246 - DEBUG - Response for create_network : {'network': {'id': '23bc878b-0aff-4e35-9886-4f60f05ed838', 'name': 'ctest-trans_right_vn-45562200', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'project_id': '12f8c26729464e21a609cc1372834a3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-91185332', 'ctest-trans_right_vn-45562200'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:19,496 - DEBUG - Response for create_subnet : {'subnet': {'id': '125ff761-e7a5-450e-87d8-64df5f100d90', 'name': '', 'tenant_id': '12f8c26729464e21a609cc1372834a3c', 'network_id': '23bc878b-0aff-4e35-9886-4f60f05ed838', 'ip_version': 4, 'cidr': '69.100.39.0/26', 'allocation_pools': [{'start': '69.100.39.2', 'end': '69.100.39.62'}], 'gateway_ip': '69.100.39.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '69.100.39.2', 'tags': [], 'project_id': '12f8c26729464e21a609cc1372834a3c'}}
2025-11-17 03:12:19,525 - INFO - Created VN ctest-trans_right_vn-45562200
2025-11-17 03:12:19,538 - DEBUG - VN ctest-trans_right_vn-45562200 UUID is 23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:19,612 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:19,618 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:19,632 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:19,727 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:19,747 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:19,758 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0b5686fa-78c6-4324-8533-99120bc0a804
2025-11-17 03:12:19,770 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0b5686fa-78c6-4324-8533-99120bc0a804
2025-11-17 03:12:19,778 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4c993435-fd25-4013-b1bc-7b5b0126cfe6
2025-11-17 03:12:19,785 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-17 03:12:19,785 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:19,796 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0b5686fa-78c6-4324-8533-99120bc0a804
2025-11-17 03:12:19,813 - INFO - Verified VN network id 18 for VN 23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:19,813 - INFO - Verifications in API Server for VN ctest-trans_right_vn-45562200 passed
2025-11-17 03:12:19,813 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:19,823 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0b5686fa-78c6-4324-8533-99120bc0a804
2025-11-17 03:12:19,832 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0b5686fa-78c6-4324-8533-99120bc0a804
2025-11-17 03:12:19,839 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4c993435-fd25-4013-b1bc-7b5b0126cfe6
2025-11-17 03:12:19,851 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2575082118203788853', 'uuid-lslong': '10990559218412476472'}, 'enable': 'true', 'created': '2025-11-17T03:12:19', 'last-modified': '2025-11-17T03:12:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402760'}
2025-11-17 03:12:19,858 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-17 03:12:19,865 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2575082118203788853', 'uuid-lslong': '10990559218412476472'}, 'enable': 'true', 'created': '2025-11-17T03:12:19', 'last-modified': '2025-11-17T03:12:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.417531'}
2025-11-17 03:12:19,873 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-17 03:12:19,878 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-91185332', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2575082118203788853', 'uuid-lslong': '10990559218412476472'}, 'enable': 'true', 'created': '2025-11-17T03:12:19', 'last-modified': '2025-11-17T03:12:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430931'}
2025-11-17 03:12:19,885 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-17 03:12:19,885 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-45562200 passed
2025-11-17 03:12:19,886 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-45562200 in API_Server ======
2025-11-17 03:12:19,886 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:19,892 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:19,905 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:20,013 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23bc878b-0aff-4e35-9886-4f60f05ed838
2025-11-17 03:12:20,033 - DEBUG - =>VN ctest-trans_right_vn-45562200 has no policy to be verified
2025-11-17 03:12:20,033 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:20,033 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:20,033 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:20,049 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200?flat'}
2025-11-17 03:12:20,049 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:20,049 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:20,065 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200?flat'}
2025-11-17 03:12:20,065 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:20,065 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:20,079 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200?flat'}
2025-11-17 03:12:20,080 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-91185332:ctest-trans_right_vn-45562200 is found in opserver
2025-11-17 03:12:20,106 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:20,117 - DEBUG - VRF ids for VN ctest-trans_right_vn-45562200: {}
2025-11-17 03:12:21,300 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-1)
2025-11-17 03:12:22,742 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-2)
2025-11-17 03:12:22,742 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-28091075']
2025-11-17 03:12:23,035 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-28091075'] created succesfully
2025-11-17 03:12:23,576 - DEBUG - Image by name tiny_trans_fw not found
2025-11-17 03:12:23,576 - DEBUG - Installing image tiny_trans_fw
2025-11-17 03:12:23,576 - 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-11-17 03:12:23,577 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-11-17 03:12:23,577 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-11-17 03:12:23,599 - 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-11-17 03:12:23,637 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-17 03:12:23,637 - DEBUG - Unzip image
2025-11-17 03:12:23,637 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-17 03:12:23,659 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-11-17 03:12:23,659 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-28091075']
2025-11-17 03:12:23,810 - INFO - Service template: ['default-domain', 'ctest-service_template_1-28091075'] deleted successfully.
2025-11-17 03:12:23,810 - INFO - Skip interface_detach for VM ctest-right_vm-40785659 in state BUILD
2025-11-17 03:12:23,810 - INFO - Deleting VM ctest-right_vm-40785659
2025-11-17 03:12:23,811 - ERROR - VM ctest-right_vm-40785659 has failed to come up
2025-11-17 03:12:23,811 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:23,811 - ERROR - VM ctest-right_vm-40785659 has failed to come up
2025-11-17 03:12:23,811 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:23,916 - INFO - Skip interface_detach for VM ctest-left_vm-81637415 in state BUILD
2025-11-17 03:12:23,916 - INFO - Deleting VM ctest-left_vm-81637415
2025-11-17 03:12:23,917 - ERROR - VM ctest-left_vm-81637415 has failed to come up
2025-11-17 03:12:23,917 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:23,917 - ERROR - VM ctest-left_vm-81637415 has failed to come up
2025-11-17 03:12:23,917 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:23,997 - INFO - Deleting VN ctest-trans_right_vn-45562200
2025-11-17 03:12:24,193 - DEBUG - Response for deleting network ()
2025-11-17 03:12:24,194 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0b5686fa-78c6-4324-8533-99120bc0a804
2025-11-17 03:12:24,214 - DEBUG - Response Code: 404
2025-11-17 03:12:24,215 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:24,234 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:24,252 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:24,372 - INFO - Validated that VN ctest-trans_right_vn-45562200 is not found in API Server
2025-11-17 03:12:24,383 - DEBUG - VN ctest-trans_right_vn-45562200 is not present in Agent 10.0.0.50
2025-11-17 03:12:24,395 - DEBUG - VN ctest-trans_right_vn-45562200 is not present in Agent 10.0.0.73
2025-11-17 03:12:24,395 - INFO - Validated that VN ctest-trans_right_vn-45562200 is not in any agent
2025-11-17 03:12:24,429 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-45562200 info
2025-11-17 03:12:24,429 - INFO - Deleting VN ctest-trans_left_vn-37258963
2025-11-17 03:12:24,584 - DEBUG - Response for deleting network ()
2025-11-17 03:12:24,585 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d337caa-671c-4bf2-9524-e464b607d847
2025-11-17 03:12:24,591 - DEBUG - Response Code: 404
2025-11-17 03:12:24,592 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:24,605 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:24,637 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:24,743 - INFO - Validated that VN ctest-trans_left_vn-37258963 is not found in API Server
2025-11-17 03:12:24,758 - DEBUG - VN ctest-trans_left_vn-37258963 is not present in Agent 10.0.0.50
2025-11-17 03:12:24,792 - DEBUG - VN ctest-trans_left_vn-37258963 is not present in Agent 10.0.0.73
2025-11-17 03:12:24,792 - INFO - Validated that VN ctest-trans_left_vn-37258963 is not in any agent
2025-11-17 03:12:24,872 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-37258963 info
2025-11-17 03:12:24,872 - INFO - Deleting VN ctest-right_vn-70330680
2025-11-17 03:12:24,925 - DEBUG - VN 0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8 still in use: Unable to complete operation on network 0bbbaf58-41e2-4cb0-94dd-2d9e65bac3e8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e8ec3abc-c022-4178-ae26-a1e31bf4c0ad']
2025-11-17 03:12:24,925 - WARNING - Deleting VN ctest-right_vn-70330680 failed..Will retry
2025-11-17 03:12:27,080 - DEBUG - Response for deleting network ()
2025-11-17 03:12:27,080 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7fc6c45c-0b91-4771-8ba5-990b8e20a06f
2025-11-17 03:12:27,086 - DEBUG - Response Code: 404
2025-11-17 03:12:27,086 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:27,096 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:27,121 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:27,219 - INFO - Validated that VN ctest-right_vn-70330680 is not found in API Server
2025-11-17 03:12:27,230 - DEBUG - VN ctest-right_vn-70330680 is not present in Agent 10.0.0.50
2025-11-17 03:12:27,242 - DEBUG - VN ctest-right_vn-70330680 is not present in Agent 10.0.0.73
2025-11-17 03:12:27,242 - INFO - Validated that VN ctest-right_vn-70330680 is not in any agent
2025-11-17 03:12:27,276 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-70330680 info
2025-11-17 03:12:27,277 - INFO - Deleting VN ctest-left_vn-42100903
2025-11-17 03:12:27,440 - DEBUG - Response for deleting network ()
2025-11-17 03:12:27,440 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fb773b7-06ec-465b-8c1b-e0b391aea68b
2025-11-17 03:12:27,448 - DEBUG - Response Code: 404
2025-11-17 03:12:27,449 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:27,468 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:27,485 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:27,595 - INFO - Validated that VN ctest-left_vn-42100903 is not found in API Server
2025-11-17 03:12:27,606 - DEBUG - VN ctest-left_vn-42100903 is not present in Agent 10.0.0.50
2025-11-17 03:12:27,617 - DEBUG - VN ctest-left_vn-42100903 is not present in Agent 10.0.0.73
2025-11-17 03:12:27,617 - INFO - Validated that VN ctest-left_vn-42100903 is not in any agent
2025-11-17 03:12:27,652 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-42100903 info
2025-11-17 03:12:27,652 - INFO - Deleting VN ctest-mgmt_vn-05241105
2025-11-17 03:12:27,817 - DEBUG - Response for deleting network ()
2025-11-17 03:12:27,817 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/0fc2b9c1-7e79-4718-8701-7743515df909
2025-11-17 03:12:27,823 - DEBUG - Response Code: 404
2025-11-17 03:12:27,823 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:27,830 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:27,852 - DEBUG - Requesting: http://10.0.0.22:8082/project/12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:27,957 - INFO - Validated that VN ctest-mgmt_vn-05241105 is not found in API Server
2025-11-17 03:12:27,968 - DEBUG - VN ctest-mgmt_vn-05241105 is not present in Agent 10.0.0.50
2025-11-17 03:12:27,978 - DEBUG - VN ctest-mgmt_vn-05241105 is not present in Agent 10.0.0.73
2025-11-17 03:12:27,978 - INFO - Validated that VN ctest-mgmt_vn-05241105 is not in any agent
2025-11-17 03:12:28,008 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-05241105 info
2025-11-17 03:12:29,328 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 03:12:29,328 - INFO -
2025-11-17 03:12:29,328 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-11-17 03:12:29,328 - INFO - --------------------------------------------------------------------------------
2025-11-17 03:12:30,155 - INFO - Deleted project: ctest-TestSvcRegr-91185332, ID : 12f8c267-2946-4e21-a609-cc1372834a3c
2025-11-17 03:12:30,353 - INFO - Domain Default found not creating
2025-11-17 03:12:30,542 - INFO - Project ctest-TestSvcRegr-38233894 not found, creating it
2025-11-17 03:12:31,061 - INFO - Created Project:ctest-TestSvcRegr-38233894, ID : da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:32,804 - INFO - ================================================================================
2025-11-17 03:12:32,804 - INFO - STARTING TEST : test_svc_CEM_22032
2025-11-17 03:12:34,075 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.73': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-11-17 03:12:34,075 - INFO - Initial checks done. Running the testcase now
2025-11-17 03:12:34,075 - INFO -
2025-11-17 03:12:34,829 - DEBUG - Response for create_network : {'network': {'id': '9021a515-d17f-43e2-b53e-f99ff48bc285', 'name': 'ctest-mgmt_vn-95662693', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'project_id': 'da1f181733f545fd9969a8f9de8262a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38233894', 'ctest-mgmt_vn-95662693'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:35,159 - DEBUG - Response for create_subnet : {'subnet': {'id': '9edd56e7-4294-406d-9889-1ac5af343ec5', 'name': '', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'network_id': '9021a515-d17f-43e2-b53e-f99ff48bc285', 'ip_version': 4, 'cidr': '179.65.108.128/26', 'allocation_pools': [{'start': '179.65.108.130', 'end': '179.65.108.190'}], 'gateway_ip': '179.65.108.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '179.65.108.130', 'tags': [], 'project_id': 'da1f181733f545fd9969a8f9de8262a1'}}
2025-11-17 03:12:35,182 - INFO - Created VN ctest-mgmt_vn-95662693
2025-11-17 03:12:35,194 - DEBUG - VN ctest-mgmt_vn-95662693 UUID is 9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:35,298 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:35,649 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:35,686 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:35,703 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:35,799 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:35,813 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:35,823 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/080c1540-c85b-4baa-9e10-5eb4808d1753
2025-11-17 03:12:35,841 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/080c1540-c85b-4baa-9e10-5eb4808d1753
2025-11-17 03:12:35,851 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/7d8d63fb-0fc0-4898-b8ea-5dde978562eb
2025-11-17 03:12:35,859 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:35,859 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:35,869 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/080c1540-c85b-4baa-9e10-5eb4808d1753
2025-11-17 03:12:35,886 - INFO - Verified VN network id 14 for VN 9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:35,886 - INFO - Verifications in API Server for VN ctest-mgmt_vn-95662693 passed
2025-11-17 03:12:35,886 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:35,899 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/080c1540-c85b-4baa-9e10-5eb4808d1753
2025-11-17 03:12:35,906 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/080c1540-c85b-4baa-9e10-5eb4808d1753
2025-11-17 03:12:35,913 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/7d8d63fb-0fc0-4898-b8ea-5dde978562eb
2025-11-17 03:12:35,927 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10385763728820749282', 'uuid-lslong': '13060150434818933381'}, 'enable': 'true', 'created': '2025-11-17T03:12:34', 'last-modified': '2025-11-17T03:12:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.821767'}
2025-11-17 03:12:35,933 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:35,938 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10385763728820749282', 'uuid-lslong': '13060150434818933381'}, 'enable': 'true', 'created': '2025-11-17T03:12:34', 'last-modified': '2025-11-17T03:12:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.834005'}
2025-11-17 03:12:35,945 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:35,951 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10385763728820749282', 'uuid-lslong': '13060150434818933381'}, 'enable': 'true', 'created': '2025-11-17T03:12:34', 'last-modified': '2025-11-17T03:12:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.851754'}
2025-11-17 03:12:35,959 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:35,959 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-95662693 passed
2025-11-17 03:12:35,960 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-95662693 in API_Server ======
2025-11-17 03:12:35,960 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:35,967 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:35,979 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:36,077 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9021a515-d17f-43e2-b53e-f99ff48bc285
2025-11-17 03:12:36,089 - DEBUG - =>VN ctest-mgmt_vn-95662693 has no policy to be verified
2025-11-17 03:12:36,089 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:36,089 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:36,090 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:36,433 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:36,845 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693?flat'}
2025-11-17 03:12:36,845 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:36,845 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:37,195 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:37,632 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693?flat'}
2025-11-17 03:12:37,632 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:37,632 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:38,017 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:38,080 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693?flat'}
2025-11-17 03:12:38,080 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-95662693 is found in opserver
2025-11-17 03:12:38,102 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:38,114 - DEBUG - VRF ids for VN ctest-mgmt_vn-95662693: {}
2025-11-17 03:12:38,394 - DEBUG - Response for create_network : {'network': {'id': '630874f7-7545-4da0-aa1a-021faf199501', 'name': 'service-left-vn-92508870', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'project_id': 'da1f181733f545fd9969a8f9de8262a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38233894', 'service-left-vn-92508870'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:38,624 - DEBUG - Response for create_subnet : {'subnet': {'id': '68a6ae4c-d8c4-4fb2-a261-2145c878e4f4', 'name': '', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'network_id': '630874f7-7545-4da0-aa1a-021faf199501', 'ip_version': 4, 'cidr': '101.78.44.0/26', 'allocation_pools': [{'start': '101.78.44.2', 'end': '101.78.44.62'}], 'gateway_ip': '101.78.44.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '101.78.44.2', 'tags': [], 'project_id': 'da1f181733f545fd9969a8f9de8262a1'}}
2025-11-17 03:12:38,653 - INFO - Created VN service-left-vn-92508870
2025-11-17 03:12:38,669 - DEBUG - VN service-left-vn-92508870 UUID is 630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:38,758 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:38,766 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:38,778 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:38,880 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:38,892 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:38,902 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b62dbd73-d832-4286-a474-d2436420ce58
2025-11-17 03:12:38,910 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b62dbd73-d832-4286-a474-d2436420ce58
2025-11-17 03:12:38,922 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/a98db63e-a87a-4d84-891d-f3ddd60d7676
2025-11-17 03:12:38,931 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:38,931 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:38,945 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b62dbd73-d832-4286-a474-d2436420ce58
2025-11-17 03:12:38,965 - INFO - Verified VN network id 15 for VN 630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:38,965 - INFO - Verifications in API Server for VN service-left-vn-92508870 passed
2025-11-17 03:12:38,965 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:38,981 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b62dbd73-d832-4286-a474-d2436420ce58
2025-11-17 03:12:38,991 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b62dbd73-d832-4286-a474-d2436420ce58
2025-11-17 03:12:39,001 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/a98db63e-a87a-4d84-891d-f3ddd60d7676
2025-11-17 03:12:39,017 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7136082215741771168', 'uuid-lslong': '12257111670947157249'}, 'enable': 'true', 'created': '2025-11-17T03:12:38', 'last-modified': '2025-11-17T03:12:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.461151'}
2025-11-17 03:12:39,023 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:39,029 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7136082215741771168', 'uuid-lslong': '12257111670947157249'}, 'enable': 'true', 'created': '2025-11-17T03:12:38', 'last-modified': '2025-11-17T03:12:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476356'}
2025-11-17 03:12:39,035 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:39,041 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7136082215741771168', 'uuid-lslong': '12257111670947157249'}, 'enable': 'true', 'created': '2025-11-17T03:12:38', 'last-modified': '2025-11-17T03:12:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.489539'}
2025-11-17 03:12:39,048 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:39,048 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-92508870 passed
2025-11-17 03:12:39,048 - DEBUG - ====Verifying policy data for service-left-vn-92508870 in API_Server ======
2025-11-17 03:12:39,048 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:39,054 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:39,066 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:39,167 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/630874f7-7545-4da0-aa1a-021faf199501
2025-11-17 03:12:39,186 - DEBUG - =>VN service-left-vn-92508870 has no policy to be verified
2025-11-17 03:12:39,187 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:39,187 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:39,187 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:39,265 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870?flat'}
2025-11-17 03:12:39,265 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:39,265 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:39,348 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870?flat'}
2025-11-17 03:12:39,348 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:39,348 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:39,415 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870?flat'}
2025-11-17 03:12:39,416 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38233894:service-left-vn-92508870 is found in opserver
2025-11-17 03:12:39,436 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:39,445 - DEBUG - VRF ids for VN service-left-vn-92508870: {}
2025-11-17 03:12:39,740 - DEBUG - Response for create_network : {'network': {'id': '1c8c2336-d29c-49a8-9e9f-697b909ed0c1', 'name': 'service-right-vn-40158796', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'project_id': 'da1f181733f545fd9969a8f9de8262a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38233894', 'service-right-vn-40158796'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:40,042 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4fc04d7-5cff-4365-860d-810624e2dca9', 'name': '', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'network_id': '1c8c2336-d29c-49a8-9e9f-697b909ed0c1', 'ip_version': 4, 'cidr': '96.137.90.0/26', 'allocation_pools': [{'start': '96.137.90.2', 'end': '96.137.90.62'}], 'gateway_ip': '96.137.90.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.137.90.2', 'tags': [], 'project_id': 'da1f181733f545fd9969a8f9de8262a1'}}
2025-11-17 03:12:40,067 - INFO - Created VN service-right-vn-40158796
2025-11-17 03:12:40,078 - DEBUG - VN service-right-vn-40158796 UUID is 1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,166 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:40,175 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:40,193 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:40,294 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,331 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,347 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a538cbec-76ce-44ea-9e41-4a5360b18198
2025-11-17 03:12:40,356 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a538cbec-76ce-44ea-9e41-4a5360b18198
2025-11-17 03:12:40,365 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f9803eae-9157-4402-9ca4-3b2f2f93c84b
2025-11-17 03:12:40,375 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:40,375 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,394 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a538cbec-76ce-44ea-9e41-4a5360b18198
2025-11-17 03:12:40,412 - INFO - Verified VN network id 16 for VN 1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,412 - INFO - Verifications in API Server for VN service-right-vn-40158796 passed
2025-11-17 03:12:40,412 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,426 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a538cbec-76ce-44ea-9e41-4a5360b18198
2025-11-17 03:12:40,435 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a538cbec-76ce-44ea-9e41-4a5360b18198
2025-11-17 03:12:40,442 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f9803eae-9157-4402-9ca4-3b2f2f93c84b
2025-11-17 03:12:40,457 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2057057848170138024', 'uuid-lslong': '11429970358717829313'}, 'enable': 'true', 'created': '2025-11-17T03:12:39', 'last-modified': '2025-11-17T03:12:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.547603'}
2025-11-17 03:12:40,466 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:40,472 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2057057848170138024', 'uuid-lslong': '11429970358717829313'}, 'enable': 'true', 'created': '2025-11-17T03:12:39', 'last-modified': '2025-11-17T03:12:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.558835'}
2025-11-17 03:12:40,478 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:40,484 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2057057848170138024', 'uuid-lslong': '11429970358717829313'}, 'enable': 'true', 'created': '2025-11-17T03:12:39', 'last-modified': '2025-11-17T03:12:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.570774'}
2025-11-17 03:12:40,491 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:40,491 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-40158796 passed
2025-11-17 03:12:40,491 - DEBUG - ====Verifying policy data for service-right-vn-40158796 in API_Server ======
2025-11-17 03:12:40,491 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:40,501 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:40,513 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:40,619 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1c8c2336-d29c-49a8-9e9f-697b909ed0c1
2025-11-17 03:12:40,634 - DEBUG - =>VN service-right-vn-40158796 has no policy to be verified
2025-11-17 03:12:40,634 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:40,634 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:40,634 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:40,704 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796?flat'}
2025-11-17 03:12:40,704 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:40,704 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:40,779 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796?flat'}
2025-11-17 03:12:40,779 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:40,779 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:40,864 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796?flat'}
2025-11-17 03:12:40,864 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38233894:service-right-vn-40158796 is found in opserver
2025-11-17 03:12:40,893 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:40,906 - DEBUG - VRF ids for VN service-right-vn-40158796: {}
2025-11-17 03:12:40,978 - DEBUG - Services list from nova: [, , , ]
2025-11-17 03:12:42,269 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-1)
2025-11-17 03:12:44,019 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-2)
2025-11-17 03:12:44,020 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-17887283']
2025-11-17 03:12:44,328 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-17887283'] created succesfully
2025-11-17 03:12:44,774 - DEBUG - Image by name tiny_nat_fw not found
2025-11-17 03:12:44,774 - DEBUG - Installing image tiny_nat_fw
2025-11-17 03:12:44,774 - 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-11-17 03:12:44,775 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:44,775 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:44,775 - DEBUG - Unzip image
2025-11-17 03:12:44,775 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:44,797 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-17 03:12:44,800 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-17887283']
2025-11-17 03:12:44,925 - INFO - Service template: ['default-domain', 'ctest-service_template_1-17887283'] deleted successfully.
2025-11-17 03:12:44,925 - INFO - Skip interface_detach for VM ctest-right_vm-20499032 in state BUILD
2025-11-17 03:12:44,926 - INFO - Deleting VM ctest-right_vm-20499032
2025-11-17 03:12:44,966 - ERROR - VM ctest-right_vm-20499032 has failed to come up
2025-11-17 03:12:44,966 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:44,966 - ERROR - VM ctest-right_vm-20499032 has failed to come up
2025-11-17 03:12:44,967 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:45,145 - INFO - Skip interface_detach for VM ctest-left_vm-28137956 in state BUILD
2025-11-17 03:12:45,145 - INFO - Deleting VM ctest-left_vm-28137956
2025-11-17 03:12:45,145 - ERROR - VM ctest-left_vm-28137956 has failed to come up
2025-11-17 03:12:45,146 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:45,146 - ERROR - VM ctest-left_vm-28137956 has failed to come up
2025-11-17 03:12:45,146 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:45,230 - INFO - Deleting VN service-right-vn-40158796
2025-11-17 03:12:45,290 - DEBUG - VN 1c8c2336-d29c-49a8-9e9f-697b909ed0c1 still in use: Unable to complete operation on network 1c8c2336-d29c-49a8-9e9f-697b909ed0c1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d314f110-052d-45f9-b941-c80689ea9433']
2025-11-17 03:12:45,290 - WARNING - Deleting VN service-right-vn-40158796 failed..Will retry
2025-11-17 03:12:47,458 - DEBUG - Response for deleting network ()
2025-11-17 03:12:47,458 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a538cbec-76ce-44ea-9e41-4a5360b18198
2025-11-17 03:12:47,464 - DEBUG - Response Code: 404
2025-11-17 03:12:47,464 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:47,473 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:47,523 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:47,643 - INFO - Validated that VN service-right-vn-40158796 is not found in API Server
2025-11-17 03:12:47,655 - DEBUG - VN service-right-vn-40158796 is not present in Agent 10.0.0.50
2025-11-17 03:12:47,665 - DEBUG - VN service-right-vn-40158796 is not present in Agent 10.0.0.73
2025-11-17 03:12:47,665 - INFO - Validated that VN service-right-vn-40158796 is not in any agent
2025-11-17 03:12:47,703 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-40158796 info
2025-11-17 03:12:47,703 - INFO - Deleting VN service-left-vn-92508870
2025-11-17 03:12:47,890 - DEBUG - Response for deleting network ()
2025-11-17 03:12:47,890 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b62dbd73-d832-4286-a474-d2436420ce58
2025-11-17 03:12:47,899 - DEBUG - Response Code: 404
2025-11-17 03:12:47,899 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:47,907 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:47,952 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:48,046 - INFO - Validated that VN service-left-vn-92508870 is not found in API Server
2025-11-17 03:12:48,060 - DEBUG - VN service-left-vn-92508870 is not present in Agent 10.0.0.50
2025-11-17 03:12:48,070 - DEBUG - VN service-left-vn-92508870 is not present in Agent 10.0.0.73
2025-11-17 03:12:48,070 - INFO - Validated that VN service-left-vn-92508870 is not in any agent
2025-11-17 03:12:48,102 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-92508870 info
2025-11-17 03:12:48,102 - INFO - Deleting VN ctest-mgmt_vn-95662693
2025-11-17 03:12:48,248 - DEBUG - Response for deleting network ()
2025-11-17 03:12:48,248 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/080c1540-c85b-4baa-9e10-5eb4808d1753
2025-11-17 03:12:48,254 - DEBUG - Response Code: 404
2025-11-17 03:12:48,254 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:48,268 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:48,298 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:48,402 - INFO - Validated that VN ctest-mgmt_vn-95662693 is not found in API Server
2025-11-17 03:12:48,413 - DEBUG - VN ctest-mgmt_vn-95662693 is not present in Agent 10.0.0.50
2025-11-17 03:12:48,423 - DEBUG - VN ctest-mgmt_vn-95662693 is not present in Agent 10.0.0.73
2025-11-17 03:12:48,423 - INFO - Validated that VN ctest-mgmt_vn-95662693 is not in any agent
2025-11-17 03:12:48,454 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-95662693 info
2025-11-17 03:12:49,719 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 03:12:49,719 - INFO -
2025-11-17 03:12:49,720 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:17]
2025-11-17 03:12:49,720 - INFO - --------------------------------------------------------------------------------
2025-11-17 03:12:49,725 - INFO - ================================================================================
2025-11-17 03:12:49,725 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-11-17 03:12:51,026 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.73': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-11-17 03:12:51,026 - INFO - Initial checks done. Running the testcase now
2025-11-17 03:12:51,026 - INFO -
2025-11-17 03:12:51,325 - DEBUG - Response for create_network : {'network': {'id': '2d118e7f-cd22-41f8-89fb-ea0f0b01687c', 'name': 'ctest-mgmt_vn-76386049', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'project_id': 'da1f181733f545fd9969a8f9de8262a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38233894', 'ctest-mgmt_vn-76386049'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:51,567 - DEBUG - Response for create_subnet : {'subnet': {'id': '914bea35-3f0d-4cc2-b5b7-8d7817df33fb', 'name': '', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'network_id': '2d118e7f-cd22-41f8-89fb-ea0f0b01687c', 'ip_version': 4, 'cidr': '20.134.93.192/26', 'allocation_pools': [{'start': '20.134.93.194', 'end': '20.134.93.254'}], 'gateway_ip': '20.134.93.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.134.93.194', 'tags': [], 'project_id': 'da1f181733f545fd9969a8f9de8262a1'}}
2025-11-17 03:12:51,596 - INFO - Created VN ctest-mgmt_vn-76386049
2025-11-17 03:12:51,609 - DEBUG - VN ctest-mgmt_vn-76386049 UUID is 2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:51,686 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:51,693 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:51,707 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:51,813 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:51,833 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:51,847 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/608bee62-9ed1-4a0f-8cca-00129dc06b8a
2025-11-17 03:12:51,860 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/608bee62-9ed1-4a0f-8cca-00129dc06b8a
2025-11-17 03:12:51,869 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/668f53c4-11ea-4f39-a36c-7b1ba2299e8e
2025-11-17 03:12:51,877 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:51,877 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:51,890 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/608bee62-9ed1-4a0f-8cca-00129dc06b8a
2025-11-17 03:12:51,906 - INFO - Verified VN network id 14 for VN 2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:51,906 - INFO - Verifications in API Server for VN ctest-mgmt_vn-76386049 passed
2025-11-17 03:12:51,906 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:51,916 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/608bee62-9ed1-4a0f-8cca-00129dc06b8a
2025-11-17 03:12:51,923 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/608bee62-9ed1-4a0f-8cca-00129dc06b8a
2025-11-17 03:12:51,930 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/668f53c4-11ea-4f39-a36c-7b1ba2299e8e
2025-11-17 03:12:51,944 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3247533485864403448', 'uuid-lslong': '9942797952680552572'}, 'enable': 'true', 'created': '2025-11-17T03:12:51', 'last-modified': '2025-11-17T03:12:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426323'}
2025-11-17 03:12:51,951 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:51,957 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3247533485864403448', 'uuid-lslong': '9942797952680552572'}, 'enable': 'true', 'created': '2025-11-17T03:12:51', 'last-modified': '2025-11-17T03:12:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.444503'}
2025-11-17 03:12:51,964 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:51,971 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3247533485864403448', 'uuid-lslong': '9942797952680552572'}, 'enable': 'true', 'created': '2025-11-17T03:12:51', 'last-modified': '2025-11-17T03:12:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449980'}
2025-11-17 03:12:51,978 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-17 03:12:51,978 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-76386049 passed
2025-11-17 03:12:51,978 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-76386049 in API_Server ======
2025-11-17 03:12:51,978 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:51,985 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:51,996 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:52,092 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/2d118e7f-cd22-41f8-89fb-ea0f0b01687c
2025-11-17 03:12:52,107 - DEBUG - =>VN ctest-mgmt_vn-76386049 has no policy to be verified
2025-11-17 03:12:52,107 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:52,107 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:52,107 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:52,119 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049?flat'}
2025-11-17 03:12:52,119 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:52,119 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:52,131 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049?flat'}
2025-11-17 03:12:52,131 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:52,132 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:52,144 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049?flat'}
2025-11-17 03:12:52,145 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38233894:ctest-mgmt_vn-76386049 is found in opserver
2025-11-17 03:12:52,167 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:52,179 - DEBUG - VRF ids for VN ctest-mgmt_vn-76386049: {}
2025-11-17 03:12:52,461 - DEBUG - Response for create_network : {'network': {'id': 'be26ae33-7237-43c6-937d-c5cb13ffe4a2', 'name': 'ctest-left_vn-41055633', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'project_id': 'da1f181733f545fd9969a8f9de8262a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38233894', 'ctest-left_vn-41055633'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:52,753 - DEBUG - Response for create_subnet : {'subnet': {'id': '63788526-ecb4-43a7-85aa-dea40c924595', 'name': '', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'network_id': 'be26ae33-7237-43c6-937d-c5cb13ffe4a2', 'ip_version': 4, 'cidr': '141.29.104.0/26', 'allocation_pools': [{'start': '141.29.104.2', 'end': '141.29.104.62'}], 'gateway_ip': '141.29.104.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '141.29.104.2', 'tags': [], 'project_id': 'da1f181733f545fd9969a8f9de8262a1'}}
2025-11-17 03:12:52,785 - INFO - Created VN ctest-left_vn-41055633
2025-11-17 03:12:52,798 - DEBUG - VN ctest-left_vn-41055633 UUID is be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:52,904 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:52,914 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:52,929 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:53,047 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:53,061 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:53,072 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c346ed42-98f0-4b92-9174-a74fe1c4dd05
2025-11-17 03:12:53,080 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c346ed42-98f0-4b92-9174-a74fe1c4dd05
2025-11-17 03:12:53,088 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/87242d10-789a-482a-83b0-b4ee986e79b3
2025-11-17 03:12:53,096 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:53,096 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:53,105 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c346ed42-98f0-4b92-9174-a74fe1c4dd05
2025-11-17 03:12:53,121 - INFO - Verified VN network id 15 for VN be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:53,121 - INFO - Verifications in API Server for VN ctest-left_vn-41055633 passed
2025-11-17 03:12:53,121 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:53,133 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c346ed42-98f0-4b92-9174-a74fe1c4dd05
2025-11-17 03:12:53,142 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c346ed42-98f0-4b92-9174-a74fe1c4dd05
2025-11-17 03:12:53,150 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/87242d10-789a-482a-83b0-b4ee986e79b3
2025-11-17 03:12:53,164 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13701830452304102342', 'uuid-lslong': '10627868171668808866'}, 'enable': 'true', 'created': '2025-11-17T03:12:52', 'last-modified': '2025-11-17T03:12:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471044'}
2025-11-17 03:12:53,171 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:53,177 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13701830452304102342', 'uuid-lslong': '10627868171668808866'}, 'enable': 'true', 'created': '2025-11-17T03:12:52', 'last-modified': '2025-11-17T03:12:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476574'}
2025-11-17 03:12:53,184 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:53,190 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13701830452304102342', 'uuid-lslong': '10627868171668808866'}, 'enable': 'true', 'created': '2025-11-17T03:12:52', 'last-modified': '2025-11-17T03:12:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.496502'}
2025-11-17 03:12:53,196 - DEBUG - Route Targets: ['target:64512:8000005']
2025-11-17 03:12:53,196 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-41055633 passed
2025-11-17 03:12:53,197 - DEBUG - ====Verifying policy data for ctest-left_vn-41055633 in API_Server ======
2025-11-17 03:12:53,197 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:53,203 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:53,216 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:53,321 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/be26ae33-7237-43c6-937d-c5cb13ffe4a2
2025-11-17 03:12:53,332 - DEBUG - =>VN ctest-left_vn-41055633 has no policy to be verified
2025-11-17 03:12:53,332 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:53,332 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:53,333 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:53,345 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633?flat'}
2025-11-17 03:12:53,345 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:53,345 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:53,358 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633?flat'}
2025-11-17 03:12:53,359 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:53,359 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:53,372 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633?flat'}
2025-11-17 03:12:53,372 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38233894:ctest-left_vn-41055633 is found in opserver
2025-11-17 03:12:53,394 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:53,405 - DEBUG - VRF ids for VN ctest-left_vn-41055633: {}
2025-11-17 03:12:53,689 - DEBUG - Response for create_network : {'network': {'id': '60243dac-42a3-411a-8ee8-30b90789ff40', 'name': 'ctest-right_vn-03511395', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'project_id': 'da1f181733f545fd9969a8f9de8262a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38233894', 'ctest-right_vn-03511395'], 'port_security_enabled': True, 'description': ''}}
2025-11-17 03:12:53,933 - DEBUG - Response for create_subnet : {'subnet': {'id': '7119f2b6-63cb-46c4-a2a2-beeb5678bda7', 'name': '', 'tenant_id': 'da1f181733f545fd9969a8f9de8262a1', 'network_id': '60243dac-42a3-411a-8ee8-30b90789ff40', 'ip_version': 4, 'cidr': '150.106.101.0/26', 'allocation_pools': [{'start': '150.106.101.2', 'end': '150.106.101.62'}], 'gateway_ip': '150.106.101.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '150.106.101.2', 'tags': [], 'project_id': 'da1f181733f545fd9969a8f9de8262a1'}}
2025-11-17 03:12:53,956 - INFO - Created VN ctest-right_vn-03511395
2025-11-17 03:12:53,970 - DEBUG - VN ctest-right_vn-03511395 UUID is 60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,046 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:54,052 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:54,064 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:54,170 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,192 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,203 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/18c9483c-dc74-4c16-8b2b-6dceac7dacec
2025-11-17 03:12:54,214 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/18c9483c-dc74-4c16-8b2b-6dceac7dacec
2025-11-17 03:12:54,224 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/1ef7e500-1125-4ef1-8e54-3f32f61f3ff2
2025-11-17 03:12:54,237 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:54,237 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,249 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/18c9483c-dc74-4c16-8b2b-6dceac7dacec
2025-11-17 03:12:54,267 - INFO - Verified VN network id 16 for VN 60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,267 - INFO - Verifications in API Server for VN ctest-right_vn-03511395 passed
2025-11-17 03:12:54,267 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,278 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/18c9483c-dc74-4c16-8b2b-6dceac7dacec
2025-11-17 03:12:54,286 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/18c9483c-dc74-4c16-8b2b-6dceac7dacec
2025-11-17 03:12:54,294 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/1ef7e500-1125-4ef1-8e54-3f32f61f3ff2
2025-11-17 03:12:54,308 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6927729936864330010', 'uuid-lslong': '10297534119236206400'}, 'enable': 'true', 'created': '2025-11-17T03:12:53', 'last-modified': '2025-11-17T03:12:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437988'}
2025-11-17 03:12:54,316 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:54,322 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6927729936864330010', 'uuid-lslong': '10297534119236206400'}, 'enable': 'true', 'created': '2025-11-17T03:12:53', 'last-modified': '2025-11-17T03:12:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.460020'}
2025-11-17 03:12:54,328 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:54,334 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38233894', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6927729936864330010', 'uuid-lslong': '10297534119236206400'}, 'enable': 'true', 'created': '2025-11-17T03:12:53', 'last-modified': '2025-11-17T03:12:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462557'}
2025-11-17 03:12:54,341 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-17 03:12:54,341 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-03511395 passed
2025-11-17 03:12:54,341 - DEBUG - ====Verifying policy data for ctest-right_vn-03511395 in API_Server ======
2025-11-17 03:12:54,341 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:12:54,348 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:12:54,359 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:12:54,458 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/60243dac-42a3-411a-8ee8-30b90789ff40
2025-11-17 03:12:54,472 - DEBUG - =>VN ctest-right_vn-03511395 has no policy to be verified
2025-11-17 03:12:54,472 - DEBUG - Verifying the vn in opserver
2025-11-17 03:12:54,472 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395 virtual network link through opserver 10.0.0.22
2025-11-17 03:12:54,472 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-17 03:12:54,487 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395?flat'}
2025-11-17 03:12:54,487 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395 virtual network link through opserver 10.0.0.33
2025-11-17 03:12:54,487 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-17 03:12:54,499 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395?flat'}
2025-11-17 03:12:54,499 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395 virtual network link through opserver 10.0.0.23
2025-11-17 03:12:54,499 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-17 03:12:54,511 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395?flat'}
2025-11-17 03:12:54,512 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38233894:ctest-right_vn-03511395 is found in opserver
2025-11-17 03:12:54,533 - DEBUG - Do not have enough data to verify VN in agent
2025-11-17 03:12:54,543 - DEBUG - VRF ids for VN ctest-right_vn-03511395: {}
2025-11-17 03:12:55,712 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-2)
2025-11-17 03:12:57,312 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4736-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4736-1)
2025-11-17 03:12:57,313 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-56938781']
2025-11-17 03:12:57,629 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-56938781'] created succesfully
2025-11-17 03:12:58,037 - DEBUG - Image by name tiny_nat_fw not found
2025-11-17 03:12:58,037 - DEBUG - Installing image tiny_nat_fw
2025-11-17 03:12:58,037 - 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-11-17 03:12:58,038 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:58,038 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:58,038 - DEBUG - Unzip image
2025-11-17 03:12:58,038 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-17 03:12:58,065 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-17 03:12:58,066 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-56938781']
2025-11-17 03:12:58,161 - INFO - Service template: ['default-domain', 'ctest-service_template_1-56938781'] deleted successfully.
2025-11-17 03:12:58,161 - INFO - Skip interface_detach for VM ctest-right_vm-02143468 in state BUILD
2025-11-17 03:12:58,161 - INFO - Deleting VM ctest-right_vm-02143468
2025-11-17 03:12:58,161 - ERROR - VM ctest-right_vm-02143468 has failed to come up
2025-11-17 03:12:58,162 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:58,162 - ERROR - VM ctest-right_vm-02143468 has failed to come up
2025-11-17 03:12:58,162 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:58,324 - INFO - Skip interface_detach for VM ctest-left_vm-48722132 in state BUILD
2025-11-17 03:12:58,324 - INFO - Deleting VM ctest-left_vm-48722132
2025-11-17 03:12:58,324 - ERROR - VM ctest-left_vm-48722132 has failed to come up
2025-11-17 03:12:58,324 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:58,324 - ERROR - VM ctest-left_vm-48722132 has failed to come up
2025-11-17 03:12:58,324 - ERROR - Nova failed to get host of the VM
2025-11-17 03:12:58,411 - INFO - Deleting VN ctest-right_vn-03511395
2025-11-17 03:12:58,475 - DEBUG - VN 60243dac-42a3-411a-8ee8-30b90789ff40 still in use: Unable to complete operation on network 60243dac-42a3-411a-8ee8-30b90789ff40. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cff90eee-78db-4db5-9779-3086deb1a62b']
2025-11-17 03:12:58,475 - WARNING - Deleting VN ctest-right_vn-03511395 failed..Will retry
2025-11-17 03:13:00,655 - DEBUG - Response for deleting network ()
2025-11-17 03:13:00,656 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/18c9483c-dc74-4c16-8b2b-6dceac7dacec
2025-11-17 03:13:00,662 - DEBUG - Response Code: 404
2025-11-17 03:13:00,662 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:13:00,673 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:13:00,694 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:13:00,804 - INFO - Validated that VN ctest-right_vn-03511395 is not found in API Server
2025-11-17 03:13:00,818 - DEBUG - VN ctest-right_vn-03511395 is not present in Agent 10.0.0.50
2025-11-17 03:13:00,831 - DEBUG - VN ctest-right_vn-03511395 is not present in Agent 10.0.0.73
2025-11-17 03:13:00,831 - INFO - Validated that VN ctest-right_vn-03511395 is not in any agent
2025-11-17 03:13:00,864 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-03511395 info
2025-11-17 03:13:00,864 - INFO - Deleting VN ctest-left_vn-41055633
2025-11-17 03:13:01,031 - DEBUG - Response for deleting network ()
2025-11-17 03:13:01,031 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c346ed42-98f0-4b92-9174-a74fe1c4dd05
2025-11-17 03:13:01,037 - DEBUG - Response Code: 404
2025-11-17 03:13:01,038 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:13:01,051 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:13:01,070 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:13:01,166 - INFO - Validated that VN ctest-left_vn-41055633 is not found in API Server
2025-11-17 03:13:01,179 - DEBUG - VN ctest-left_vn-41055633 is not present in Agent 10.0.0.50
2025-11-17 03:13:01,190 - DEBUG - VN ctest-left_vn-41055633 is not present in Agent 10.0.0.73
2025-11-17 03:13:01,190 - INFO - Validated that VN ctest-left_vn-41055633 is not in any agent
2025-11-17 03:13:01,222 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-41055633 info
2025-11-17 03:13:01,223 - INFO - Deleting VN ctest-mgmt_vn-76386049
2025-11-17 03:13:01,397 - DEBUG - Response for deleting network ()
2025-11-17 03:13:01,397 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/608bee62-9ed1-4a0f-8cca-00129dc06b8a
2025-11-17 03:13:01,407 - DEBUG - Response Code: 404
2025-11-17 03:13:01,407 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 03:13:01,415 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 03:13:01,471 - DEBUG - Requesting: http://10.0.0.22:8082/project/da1f1817-33f5-45fd-9969-a8f9de8262a1
2025-11-17 03:13:01,584 - INFO - Validated that VN ctest-mgmt_vn-76386049 is not found in API Server
2025-11-17 03:13:01,595 - DEBUG - VN ctest-mgmt_vn-76386049 is not present in Agent 10.0.0.50
2025-11-17 03:13:01,606 - DEBUG - VN ctest-mgmt_vn-76386049 is not present in Agent 10.0.0.73
2025-11-17 03:13:01,606 - INFO - Validated that VN ctest-mgmt_vn-76386049 is not in any agent
2025-11-17 03:13:01,638 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-76386049 info
2025-11-17 03:13:02,925 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 03:13:02,925 - INFO -
2025-11-17 03:13:02,925 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-11-17 03:13:02,925 - INFO - --------------------------------------------------------------------------------
2025-11-17 03:13:03,773 - INFO - Deleted project: ctest-TestSvcRegr-38233894, ID : da1f1817-33f5-45fd-9969-a8f9de8262a1