]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-1)
2025-12-18 03:23:35,837 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-25914315']
2025-12-18 03:23:36,144 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-25914315'] created succesfully
2025-12-18 03:23:36,571 - DEBUG - Image by name tiny_nat_fw not found
2025-12-18 03:23:36,571 - DEBUG - Installing image tiny_nat_fw
2025-12-18 03:23:36,571 - 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-12-18 03:23:36,571 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-12-18 03:23:36,571 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-12-18 03:23:36,606 - 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-12-18 03:23:36,759 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:23:36,759 - DEBUG - Unzip image
2025-12-18 03:23:36,759 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:23:36,783 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-18 03:23:36,785 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-25914315']
2025-12-18 03:23:36,878 - INFO - Service template: ['default-domain', 'ctest-service_template_1-25914315'] deleted successfully.
2025-12-18 03:23:36,878 - INFO - Skip interface_detach for VM ctest-right_vm-49458318 in state BUILD
2025-12-18 03:23:36,878 - INFO - Deleting VM ctest-right_vm-49458318
2025-12-18 03:23:36,909 - ERROR - VM ctest-right_vm-49458318 has failed to come up
2025-12-18 03:23:36,909 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:36,909 - ERROR - VM ctest-right_vm-49458318 has failed to come up
2025-12-18 03:23:36,909 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:36,986 - INFO - Skip interface_detach for VM ctest-left_vm-25862418 in state BUILD
2025-12-18 03:23:36,986 - INFO - Deleting VM ctest-left_vm-25862418
2025-12-18 03:23:36,986 - ERROR - VM ctest-left_vm-25862418 has failed to come up
2025-12-18 03:23:36,986 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:36,986 - ERROR - VM ctest-left_vm-25862418 has failed to come up
2025-12-18 03:23:36,986 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:37,056 - INFO - Deleting VN ctest-right_vn-52955719
2025-12-18 03:23:37,108 - DEBUG - VN fa808bf9-258b-4d8a-af40-b1f5a24d0dbf still in use: Unable to complete operation on network fa808bf9-258b-4d8a-af40-b1f5a24d0dbf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-42d010a9-4468-4df9-90c0-bfeef9911a1f']
2025-12-18 03:23:37,109 - WARNING - Deleting VN ctest-right_vn-52955719 failed..Will retry
2025-12-18 03:23:39,272 - DEBUG - Response for deleting network ()
2025-12-18 03:23:39,272 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cbb1d9cf-77db-4bfa-b546-26bb382a6dae
2025-12-18 03:23:39,282 - DEBUG - Response Code: 404
2025-12-18 03:23:39,282 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:39,295 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:39,312 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:39,412 - INFO - Validated that VN ctest-right_vn-52955719 is not found in API Server
2025-12-18 03:23:39,428 - DEBUG - VN ctest-right_vn-52955719 is not present in Agent 10.0.0.249
2025-12-18 03:23:39,444 - DEBUG - VN ctest-right_vn-52955719 is not present in Agent 10.0.0.129
2025-12-18 03:23:39,445 - INFO - Validated that VN ctest-right_vn-52955719 is not in any agent
2025-12-18 03:23:39,485 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-52955719 info
2025-12-18 03:23:39,485 - INFO - Deleting VN ctest-left_vn-94000102
2025-12-18 03:23:39,688 - DEBUG - Response for deleting network ()
2025-12-18 03:23:39,688 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/090c4a8b-e0f3-4500-8bfa-2a227c1e8a1c
2025-12-18 03:23:39,694 - DEBUG - Response Code: 404
2025-12-18 03:23:39,694 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:39,702 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:39,719 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:39,852 - INFO - Validated that VN ctest-left_vn-94000102 is not found in API Server
2025-12-18 03:23:39,870 - DEBUG - VN ctest-left_vn-94000102 is not present in Agent 10.0.0.249
2025-12-18 03:23:39,881 - DEBUG - VN ctest-left_vn-94000102 is not present in Agent 10.0.0.129
2025-12-18 03:23:39,881 - INFO - Validated that VN ctest-left_vn-94000102 is not in any agent
2025-12-18 03:23:39,927 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-94000102 info
2025-12-18 03:23:39,927 - INFO - Deleting VN ctest-mgmt_vn-20192220
2025-12-18 03:23:40,092 - DEBUG - Response for deleting network ()
2025-12-18 03:23:40,092 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/63de8c45-8b93-4e0f-b727-f68db183f6e6
2025-12-18 03:23:40,100 - DEBUG - Response Code: 404
2025-12-18 03:23:40,100 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:40,112 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:40,128 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:40,224 - INFO - Validated that VN ctest-mgmt_vn-20192220 is not found in API Server
2025-12-18 03:23:40,245 - DEBUG - VN ctest-mgmt_vn-20192220 is not present in Agent 10.0.0.249
2025-12-18 03:23:40,257 - DEBUG - VN ctest-mgmt_vn-20192220 is not present in Agent 10.0.0.129
2025-12-18 03:23:40,257 - INFO - Validated that VN ctest-mgmt_vn-20192220 is not in any agent
2025-12-18 03:23:40,295 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-20192220 info
2025-12-18 03:23:40,295 - INFO - Deleting VM ctest-vm-in-third-vn-87329936
2025-12-18 03:23:40,378 - INFO - Deleting VN ctest-third-vn-30585292
2025-12-18 03:23:40,429 - DEBUG - VN 1f4c8404-9f3d-4e72-9a34-e417e1b1e041 still in use: Unable to complete operation on network 1f4c8404-9f3d-4e72-9a34-e417e1b1e041. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-62e4dbe1-71ad-46d5-9935-cfdfdc86aa29']
2025-12-18 03:23:40,429 - WARNING - Deleting VN ctest-third-vn-30585292 failed..Will retry
2025-12-18 03:23:42,575 - DEBUG - Response for deleting network ()
2025-12-18 03:23:43,908 - DEBUG - No XMPP flaps were noticed during the test
2025-12-18 03:23:43,908 - INFO -
2025-12-18 03:23:43,909 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-12-18 03:23:43,910 - INFO - --------------------------------------------------------------------------------
2025-12-18 03:23:43,915 - INFO - ================================================================================
2025-12-18 03:23:43,915 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-12-18 03:23:45,205 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.129': '0', '10.20.0.252': '0'}, '10.0.0.129': {'10.20.0.129': '0', '10.20.0.252': '0'}} with
2025-12-18 03:23:45,205 - INFO - Initial checks done. Running the testcase now
2025-12-18 03:23:45,206 - INFO -
2025-12-18 03:23:45,469 - DEBUG - Response for create_network : {'network': {'id': '3589561a-afd5-4f82-b29b-b6a19f23e291', 'name': 'ctest-mgmt_vn-69395025', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'project_id': 'c322ee27fc24452cb47e996938cdad79', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-97473872', 'ctest-mgmt_vn-69395025'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:23:45,706 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b2a32b69-3176-4d18-b289-e0cba7df5bf7', 'name': '', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'network_id': '3589561a-afd5-4f82-b29b-b6a19f23e291', 'ip_version': 4, 'cidr': '28.199.221.0/26', 'allocation_pools': [{'start': '28.199.221.2', 'end': '28.199.221.62'}], 'gateway_ip': '28.199.221.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '28.199.221.2', 'tags': [], 'project_id': 'c322ee27fc24452cb47e996938cdad79'}}
2025-12-18 03:23:45,738 - INFO - Created VN ctest-mgmt_vn-69395025
2025-12-18 03:23:45,749 - DEBUG - VN ctest-mgmt_vn-69395025 UUID is 3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:45,835 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:45,842 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:45,857 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:45,942 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:45,959 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:45,972 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cf930e14-f47f-4a9c-831a-e2a20d2ea665
2025-12-18 03:23:45,985 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cf930e14-f47f-4a9c-831a-e2a20d2ea665
2025-12-18 03:23:45,999 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5e940d23-c2f6-45b0-acc0-76abaea5c3ae
2025-12-18 03:23:46,007 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:23:46,007 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:46,017 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cf930e14-f47f-4a9c-831a-e2a20d2ea665
2025-12-18 03:23:46,031 - INFO - Verified VN network id 14 for VN 3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:46,031 - INFO - Verifications in API Server for VN ctest-mgmt_vn-69395025 passed
2025-12-18 03:23:46,032 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:46,043 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cf930e14-f47f-4a9c-831a-e2a20d2ea665
2025-12-18 03:23:46,052 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cf930e14-f47f-4a9c-831a-e2a20d2ea665
2025-12-18 03:23:46,058 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5e940d23-c2f6-45b0-acc0-76abaea5c3ae
2025-12-18 03:23:46,070 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3857709228438671234', 'uuid-lslong': '12870081165417243281'}, 'enable': 'true', 'created': '2025-12-18T03:23:45', 'last-modified': '2025-12-18T03:23:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407430'}
2025-12-18 03:23:46,076 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:23:46,082 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3857709228438671234', 'uuid-lslong': '12870081165417243281'}, 'enable': 'true', 'created': '2025-12-18T03:23:45', 'last-modified': '2025-12-18T03:23:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429157'}
2025-12-18 03:23:46,090 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:23:46,095 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3857709228438671234', 'uuid-lslong': '12870081165417243281'}, 'enable': 'true', 'created': '2025-12-18T03:23:45', 'last-modified': '2025-12-18T03:23:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442649'}
2025-12-18 03:23:46,101 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:23:46,101 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-69395025 passed
2025-12-18 03:23:46,101 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-69395025 in API_Server ======
2025-12-18 03:23:46,102 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:46,109 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:46,120 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:46,220 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3589561a-afd5-4f82-b29b-b6a19f23e291
2025-12-18 03:23:46,235 - DEBUG - =>VN ctest-mgmt_vn-69395025 has no policy to be verified
2025-12-18 03:23:46,235 - DEBUG - Verifying the vn in opserver
2025-12-18 03:23:46,235 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025 virtual network link through opserver 10.0.0.23
2025-12-18 03:23:46,235 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:23:46,249 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025?flat'}
2025-12-18 03:23:46,250 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025 virtual network link through opserver 10.0.0.50
2025-12-18 03:23:46,250 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:23:46,264 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025?flat'}
2025-12-18 03:23:46,264 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025 virtual network link through opserver 10.0.0.65
2025-12-18 03:23:46,264 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:23:46,277 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025?flat'}
2025-12-18 03:23:46,277 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-97473872:ctest-mgmt_vn-69395025 is found in opserver
2025-12-18 03:23:46,297 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:23:46,308 - DEBUG - VRF ids for VN ctest-mgmt_vn-69395025: {}
2025-12-18 03:23:46,572 - DEBUG - Response for create_network : {'network': {'id': '8cf55ae0-d1a6-465f-b82f-5ce447bb8f38', 'name': 'ctest-left_vn-20085685', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'project_id': 'c322ee27fc24452cb47e996938cdad79', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-97473872', 'ctest-left_vn-20085685'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:23:46,809 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bf1bbc13-6351-4a8c-982f-6d708c273ba5', 'name': '', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'network_id': '8cf55ae0-d1a6-465f-b82f-5ce447bb8f38', 'ip_version': 4, 'cidr': '71.8.241.192/26', 'allocation_pools': [{'start': '71.8.241.194', 'end': '71.8.241.254'}], 'gateway_ip': '71.8.241.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '71.8.241.194', 'tags': [], 'project_id': 'c322ee27fc24452cb47e996938cdad79'}}
2025-12-18 03:23:46,831 - INFO - Created VN ctest-left_vn-20085685
2025-12-18 03:23:46,845 - DEBUG - VN ctest-left_vn-20085685 UUID is 8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:46,922 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:46,930 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:46,941 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:47,040 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:47,055 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:47,067 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bf74f8f5-d480-4eca-82cd-9c06572d0edc
2025-12-18 03:23:47,076 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bf74f8f5-d480-4eca-82cd-9c06572d0edc
2025-12-18 03:23:47,084 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/c1775379-1db0-4303-8139-032990453108
2025-12-18 03:23:47,093 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:23:47,093 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:47,104 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bf74f8f5-d480-4eca-82cd-9c06572d0edc
2025-12-18 03:23:47,120 - INFO - Verified VN network id 15 for VN 8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:47,121 - INFO - Verifications in API Server for VN ctest-left_vn-20085685 passed
2025-12-18 03:23:47,121 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:47,130 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bf74f8f5-d480-4eca-82cd-9c06572d0edc
2025-12-18 03:23:47,138 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bf74f8f5-d480-4eca-82cd-9c06572d0edc
2025-12-18 03:23:47,146 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/c1775379-1db0-4303-8139-032990453108
2025-12-18 03:23:47,162 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10157124456240531039', 'uuid-lslong': '13271928762409914168'}, 'enable': 'true', 'created': '2025-12-18T03:23:46', 'last-modified': '2025-12-18T03:23:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414424'}
2025-12-18 03:23:47,170 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:23:47,176 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10157124456240531039', 'uuid-lslong': '13271928762409914168'}, 'enable': 'true', 'created': '2025-12-18T03:23:46', 'last-modified': '2025-12-18T03:23:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426888'}
2025-12-18 03:23:47,182 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:23:47,187 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10157124456240531039', 'uuid-lslong': '13271928762409914168'}, 'enable': 'true', 'created': '2025-12-18T03:23:46', 'last-modified': '2025-12-18T03:23:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442621'}
2025-12-18 03:23:47,192 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:23:47,193 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-20085685 passed
2025-12-18 03:23:47,193 - DEBUG - ====Verifying policy data for ctest-left_vn-20085685 in API_Server ======
2025-12-18 03:23:47,193 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:47,198 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:47,210 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:47,303 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/8cf55ae0-d1a6-465f-b82f-5ce447bb8f38
2025-12-18 03:23:47,315 - DEBUG - =>VN ctest-left_vn-20085685 has no policy to be verified
2025-12-18 03:23:47,315 - DEBUG - Verifying the vn in opserver
2025-12-18 03:23:47,315 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685 virtual network link through opserver 10.0.0.23
2025-12-18 03:23:47,315 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:23:47,328 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685?flat'}
2025-12-18 03:23:47,328 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685 virtual network link through opserver 10.0.0.50
2025-12-18 03:23:47,328 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:23:47,341 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685?flat'}
2025-12-18 03:23:47,341 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685 virtual network link through opserver 10.0.0.65
2025-12-18 03:23:47,341 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:23:47,354 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685?flat'}
2025-12-18 03:23:47,354 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-97473872:ctest-left_vn-20085685 is found in opserver
2025-12-18 03:23:47,378 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:23:47,390 - DEBUG - VRF ids for VN ctest-left_vn-20085685: {}
2025-12-18 03:23:47,654 - DEBUG - Response for create_network : {'network': {'id': '97479cfe-beb6-4f39-92c6-5bfa0b7af388', 'name': 'ctest-right_vn-58013073', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'project_id': 'c322ee27fc24452cb47e996938cdad79', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-97473872', 'ctest-right_vn-58013073'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:23:47,913 - DEBUG - Response for create_subnet : {'subnet': {'id': '404295ce-3afb-4a8f-bbf9-7e5584c388ca', 'name': '', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'network_id': '97479cfe-beb6-4f39-92c6-5bfa0b7af388', 'ip_version': 4, 'cidr': '133.10.142.0/26', 'allocation_pools': [{'start': '133.10.142.2', 'end': '133.10.142.62'}], 'gateway_ip': '133.10.142.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '133.10.142.2', 'tags': [], 'project_id': 'c322ee27fc24452cb47e996938cdad79'}}
2025-12-18 03:23:47,937 - INFO - Created VN ctest-right_vn-58013073
2025-12-18 03:23:47,948 - DEBUG - VN ctest-right_vn-58013073 UUID is 97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,027 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:48,033 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:48,044 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:48,132 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,147 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,157 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/91ec0e0a-1e3b-4da5-b40b-0f163a9a8b7f
2025-12-18 03:23:48,169 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/91ec0e0a-1e3b-4da5-b40b-0f163a9a8b7f
2025-12-18 03:23:48,177 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6d5a6f3e-dae1-419a-a2e5-9bd62ef6cb81
2025-12-18 03:23:48,184 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:23:48,184 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,192 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/91ec0e0a-1e3b-4da5-b40b-0f163a9a8b7f
2025-12-18 03:23:48,208 - INFO - Verified VN network id 16 for VN 97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,208 - INFO - Verifications in API Server for VN ctest-right_vn-58013073 passed
2025-12-18 03:23:48,208 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,220 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/91ec0e0a-1e3b-4da5-b40b-0f163a9a8b7f
2025-12-18 03:23:48,229 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/91ec0e0a-1e3b-4da5-b40b-0f163a9a8b7f
2025-12-18 03:23:48,237 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6d5a6f3e-dae1-419a-a2e5-9bd62ef6cb81
2025-12-18 03:23:48,250 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10900854041008820025', 'uuid-lslong': '10576241904418747272'}, 'enable': 'true', 'created': '2025-12-18T03:23:47', 'last-modified': '2025-12-18T03:23:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405042'}
2025-12-18 03:23:48,258 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:23:48,265 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10900854041008820025', 'uuid-lslong': '10576241904418747272'}, 'enable': 'true', 'created': '2025-12-18T03:23:47', 'last-modified': '2025-12-18T03:23:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413522'}
2025-12-18 03:23:48,271 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:23:48,277 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10900854041008820025', 'uuid-lslong': '10576241904418747272'}, 'enable': 'true', 'created': '2025-12-18T03:23:47', 'last-modified': '2025-12-18T03:23:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433800'}
2025-12-18 03:23:48,282 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:23:48,282 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-58013073 passed
2025-12-18 03:23:48,282 - DEBUG - ====Verifying policy data for ctest-right_vn-58013073 in API_Server ======
2025-12-18 03:23:48,283 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:48,289 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:48,301 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:48,397 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/97479cfe-beb6-4f39-92c6-5bfa0b7af388
2025-12-18 03:23:48,411 - DEBUG - =>VN ctest-right_vn-58013073 has no policy to be verified
2025-12-18 03:23:48,412 - DEBUG - Verifying the vn in opserver
2025-12-18 03:23:48,412 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073 virtual network link through opserver 10.0.0.23
2025-12-18 03:23:48,412 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:23:48,426 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073?flat'}
2025-12-18 03:23:48,426 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073 virtual network link through opserver 10.0.0.50
2025-12-18 03:23:48,426 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:23:48,442 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073?flat'}
2025-12-18 03:23:48,442 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073 virtual network link through opserver 10.0.0.65
2025-12-18 03:23:48,442 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:23:48,456 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073?flat'}
2025-12-18 03:23:48,456 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-97473872:ctest-right_vn-58013073 is found in opserver
2025-12-18 03:23:48,480 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:23:48,492 - DEBUG - VRF ids for VN ctest-right_vn-58013073: {}
2025-12-18 03:23:48,784 - DEBUG - Response for create_network : {'network': {'id': '3328b649-1e0e-4bfd-a0ac-5666a31ee2d1', 'name': 'ctest-trans_left_vn-35035813', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'project_id': 'c322ee27fc24452cb47e996938cdad79', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-97473872', 'ctest-trans_left_vn-35035813'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:23:49,045 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c818e3df-74f4-40bf-9928-d15f5fdcf672', 'name': '', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'network_id': '3328b649-1e0e-4bfd-a0ac-5666a31ee2d1', 'ip_version': 4, 'cidr': '95.138.136.192/26', 'allocation_pools': [{'start': '95.138.136.194', 'end': '95.138.136.254'}], 'gateway_ip': '95.138.136.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.138.136.194', 'tags': [], 'project_id': 'c322ee27fc24452cb47e996938cdad79'}}
2025-12-18 03:23:49,066 - INFO - Created VN ctest-trans_left_vn-35035813
2025-12-18 03:23:49,078 - DEBUG - VN ctest-trans_left_vn-35035813 UUID is 3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,150 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:49,157 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:49,168 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:49,260 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,272 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,284 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/578e041f-b99d-4c9e-94de-61c334386dee
2025-12-18 03:23:49,299 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/578e041f-b99d-4c9e-94de-61c334386dee
2025-12-18 03:23:49,306 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/577fc618-07c1-4bbc-8928-c275b27632cc
2025-12-18 03:23:49,313 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-18 03:23:49,313 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,322 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/578e041f-b99d-4c9e-94de-61c334386dee
2025-12-18 03:23:49,339 - INFO - Verified VN network id 17 for VN 3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,339 - INFO - Verifications in API Server for VN ctest-trans_left_vn-35035813 passed
2025-12-18 03:23:49,339 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,348 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/578e041f-b99d-4c9e-94de-61c334386dee
2025-12-18 03:23:49,356 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/578e041f-b99d-4c9e-94de-61c334386dee
2025-12-18 03:23:49,364 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/577fc618-07c1-4bbc-8928-c275b27632cc
2025-12-18 03:23:49,376 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3686396720155872253', 'uuid-lslong': '11577723740886065873'}, 'enable': 'true', 'created': '2025-12-18T03:23:48', 'last-modified': '2025-12-18T03:23:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381363'}
2025-12-18 03:23:49,382 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-18 03:23:49,389 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3686396720155872253', 'uuid-lslong': '11577723740886065873'}, 'enable': 'true', 'created': '2025-12-18T03:23:48', 'last-modified': '2025-12-18T03:23:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396164'}
2025-12-18 03:23:49,396 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-18 03:23:49,403 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3686396720155872253', 'uuid-lslong': '11577723740886065873'}, 'enable': 'true', 'created': '2025-12-18T03:23:48', 'last-modified': '2025-12-18T03:23:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.412659'}
2025-12-18 03:23:49,409 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-18 03:23:49,409 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-35035813 passed
2025-12-18 03:23:49,409 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-35035813 in API_Server ======
2025-12-18 03:23:49,409 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:49,416 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:49,428 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:49,523 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3328b649-1e0e-4bfd-a0ac-5666a31ee2d1
2025-12-18 03:23:49,539 - DEBUG - =>VN ctest-trans_left_vn-35035813 has no policy to be verified
2025-12-18 03:23:49,539 - DEBUG - Verifying the vn in opserver
2025-12-18 03:23:49,539 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813 virtual network link through opserver 10.0.0.23
2025-12-18 03:23:49,539 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:23:49,551 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813?flat'}
2025-12-18 03:23:49,551 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813 virtual network link through opserver 10.0.0.50
2025-12-18 03:23:49,551 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:23:49,566 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813?flat'}
2025-12-18 03:23:49,566 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813 virtual network link through opserver 10.0.0.65
2025-12-18 03:23:49,566 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:23:49,578 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813?flat'}
2025-12-18 03:23:49,578 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-97473872:ctest-trans_left_vn-35035813 is found in opserver
2025-12-18 03:23:49,601 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:23:49,613 - DEBUG - VRF ids for VN ctest-trans_left_vn-35035813: {}
2025-12-18 03:23:49,875 - DEBUG - Response for create_network : {'network': {'id': '0c6a0585-c5f1-44ef-ade2-48b134c31ee8', 'name': 'ctest-trans_right_vn-20001714', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'project_id': 'c322ee27fc24452cb47e996938cdad79', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-97473872', 'ctest-trans_right_vn-20001714'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:23:50,214 - DEBUG - Response for create_subnet : {'subnet': {'id': '5317857f-378f-474f-94e4-386dd3b93388', 'name': '', 'tenant_id': 'c322ee27fc24452cb47e996938cdad79', 'network_id': '0c6a0585-c5f1-44ef-ade2-48b134c31ee8', 'ip_version': 4, 'cidr': '214.138.44.64/26', 'allocation_pools': [{'start': '214.138.44.66', 'end': '214.138.44.126'}], 'gateway_ip': '214.138.44.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.138.44.66', 'tags': [], 'project_id': 'c322ee27fc24452cb47e996938cdad79'}}
2025-12-18 03:23:50,246 - INFO - Created VN ctest-trans_right_vn-20001714
2025-12-18 03:23:50,260 - DEBUG - VN ctest-trans_right_vn-20001714 UUID is 0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,330 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:50,336 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:50,347 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:50,437 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,459 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,471 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5d7a33f2-bed5-4ae4-a8fa-50906710cab1
2025-12-18 03:23:50,478 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5d7a33f2-bed5-4ae4-a8fa-50906710cab1
2025-12-18 03:23:50,485 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/279a1cf1-7b9a-4ebc-81b2-c4d1f51d4e4e
2025-12-18 03:23:50,491 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-18 03:23:50,491 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,504 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5d7a33f2-bed5-4ae4-a8fa-50906710cab1
2025-12-18 03:23:50,521 - INFO - Verified VN network id 18 for VN 0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,521 - INFO - Verifications in API Server for VN ctest-trans_right_vn-20001714 passed
2025-12-18 03:23:50,521 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,531 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5d7a33f2-bed5-4ae4-a8fa-50906710cab1
2025-12-18 03:23:50,539 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5d7a33f2-bed5-4ae4-a8fa-50906710cab1
2025-12-18 03:23:50,547 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/279a1cf1-7b9a-4ebc-81b2-c4d1f51d4e4e
2025-12-18 03:23:50,559 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '894533548096177391', 'uuid-lslong': '12529657039229755112'}, 'enable': 'true', 'created': '2025-12-18T03:23:49', 'last-modified': '2025-12-18T03:23:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403305'}
2025-12-18 03:23:50,564 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-18 03:23:50,571 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '894533548096177391', 'uuid-lslong': '12529657039229755112'}, 'enable': 'true', 'created': '2025-12-18T03:23:49', 'last-modified': '2025-12-18T03:23:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421980'}
2025-12-18 03:23:50,578 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-18 03:23:50,583 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-97473872', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '894533548096177391', 'uuid-lslong': '12529657039229755112'}, 'enable': 'true', 'created': '2025-12-18T03:23:49', 'last-modified': '2025-12-18T03:23:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428028'}
2025-12-18 03:23:50,589 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-18 03:23:50,589 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-20001714 passed
2025-12-18 03:23:50,589 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-20001714 in API_Server ======
2025-12-18 03:23:50,589 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:50,596 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:50,606 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:50,705 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0c6a0585-c5f1-44ef-ade2-48b134c31ee8
2025-12-18 03:23:50,716 - DEBUG - =>VN ctest-trans_right_vn-20001714 has no policy to be verified
2025-12-18 03:23:50,716 - DEBUG - Verifying the vn in opserver
2025-12-18 03:23:50,716 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714 virtual network link through opserver 10.0.0.23
2025-12-18 03:23:50,716 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:23:50,728 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714?flat'}
2025-12-18 03:23:50,729 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714 virtual network link through opserver 10.0.0.50
2025-12-18 03:23:50,729 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:23:50,744 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714?flat'}
2025-12-18 03:23:50,744 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714 virtual network link through opserver 10.0.0.65
2025-12-18 03:23:50,744 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:23:50,758 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714?flat'}
2025-12-18 03:23:50,759 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-97473872:ctest-trans_right_vn-20001714 is found in opserver
2025-12-18 03:23:50,784 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:23:50,796 - DEBUG - VRF ids for VN ctest-trans_right_vn-20001714: {}
2025-12-18 03:23:51,975 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-1)
2025-12-18 03:23:53,384 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-2)
2025-12-18 03:23:53,384 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-11730111']
2025-12-18 03:23:53,673 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-11730111'] created succesfully
2025-12-18 03:23:54,039 - DEBUG - Image by name tiny_trans_fw not found
2025-12-18 03:23:54,040 - DEBUG - Installing image tiny_trans_fw
2025-12-18 03:23:54,040 - 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-12-18 03:23:54,040 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-12-18 03:23:54,040 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-12-18 03:23:54,071 - 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-12-18 03:23:54,103 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-18 03:23:54,103 - DEBUG - Unzip image
2025-12-18 03:23:54,103 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-18 03:23:54,132 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-12-18 03:23:54,132 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-11730111']
2025-12-18 03:23:54,237 - INFO - Service template: ['default-domain', 'ctest-service_template_1-11730111'] deleted successfully.
2025-12-18 03:23:54,238 - INFO - Skip interface_detach for VM ctest-right_vm-80533980 in state BUILD
2025-12-18 03:23:54,238 - INFO - Deleting VM ctest-right_vm-80533980
2025-12-18 03:23:54,238 - ERROR - VM ctest-right_vm-80533980 has failed to come up
2025-12-18 03:23:54,238 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:54,238 - ERROR - VM ctest-right_vm-80533980 has failed to come up
2025-12-18 03:23:54,238 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:54,386 - INFO - Skip interface_detach for VM ctest-left_vm-90788013 in state BUILD
2025-12-18 03:23:54,386 - INFO - Deleting VM ctest-left_vm-90788013
2025-12-18 03:23:54,387 - ERROR - VM ctest-left_vm-90788013 has failed to come up
2025-12-18 03:23:54,387 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:54,387 - ERROR - VM ctest-left_vm-90788013 has failed to come up
2025-12-18 03:23:54,387 - ERROR - Nova failed to get host of the VM
2025-12-18 03:23:54,520 - INFO - Deleting VN ctest-trans_right_vn-20001714
2025-12-18 03:23:54,777 - DEBUG - Response for deleting network ()
2025-12-18 03:23:54,777 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5d7a33f2-bed5-4ae4-a8fa-50906710cab1
2025-12-18 03:23:54,784 - DEBUG - Response Code: 404
2025-12-18 03:23:54,784 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:54,795 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:54,856 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:54,966 - INFO - Validated that VN ctest-trans_right_vn-20001714 is not found in API Server
2025-12-18 03:23:54,982 - DEBUG - VN ctest-trans_right_vn-20001714 is not present in Agent 10.0.0.249
2025-12-18 03:23:55,022 - DEBUG - VN ctest-trans_right_vn-20001714 is not present in Agent 10.0.0.129
2025-12-18 03:23:55,022 - INFO - Validated that VN ctest-trans_right_vn-20001714 is not in any agent
2025-12-18 03:23:55,104 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-20001714 info
2025-12-18 03:23:55,104 - INFO - Deleting VN ctest-trans_left_vn-35035813
2025-12-18 03:23:55,298 - DEBUG - Response for deleting network ()
2025-12-18 03:23:55,298 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/578e041f-b99d-4c9e-94de-61c334386dee
2025-12-18 03:23:55,305 - DEBUG - Response Code: 404
2025-12-18 03:23:55,305 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:55,319 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:55,382 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:55,471 - INFO - Validated that VN ctest-trans_left_vn-35035813 is not found in API Server
2025-12-18 03:23:55,482 - DEBUG - VN ctest-trans_left_vn-35035813 is not present in Agent 10.0.0.249
2025-12-18 03:23:55,501 - DEBUG - VN ctest-trans_left_vn-35035813 is not present in Agent 10.0.0.129
2025-12-18 03:23:55,501 - INFO - Validated that VN ctest-trans_left_vn-35035813 is not in any agent
2025-12-18 03:23:55,538 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-35035813 info
2025-12-18 03:23:55,538 - INFO - Deleting VN ctest-right_vn-58013073
2025-12-18 03:23:55,595 - DEBUG - VN 97479cfe-beb6-4f39-92c6-5bfa0b7af388 still in use: Unable to complete operation on network 97479cfe-beb6-4f39-92c6-5bfa0b7af388. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8aa744a0-f6ad-4e26-9bd0-1f9bc5effcd9']
2025-12-18 03:23:55,596 - WARNING - Deleting VN ctest-right_vn-58013073 failed..Will retry
2025-12-18 03:23:57,766 - DEBUG - Response for deleting network ()
2025-12-18 03:23:57,766 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/91ec0e0a-1e3b-4da5-b40b-0f163a9a8b7f
2025-12-18 03:23:57,776 - DEBUG - Response Code: 404
2025-12-18 03:23:57,777 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:57,786 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:57,839 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:57,930 - INFO - Validated that VN ctest-right_vn-58013073 is not found in API Server
2025-12-18 03:23:57,941 - DEBUG - VN ctest-right_vn-58013073 is not present in Agent 10.0.0.249
2025-12-18 03:23:57,956 - DEBUG - VN ctest-right_vn-58013073 is not present in Agent 10.0.0.129
2025-12-18 03:23:57,957 - INFO - Validated that VN ctest-right_vn-58013073 is not in any agent
2025-12-18 03:23:57,991 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-58013073 info
2025-12-18 03:23:57,991 - INFO - Deleting VN ctest-left_vn-20085685
2025-12-18 03:23:58,134 - DEBUG - Response for deleting network ()
2025-12-18 03:23:58,134 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bf74f8f5-d480-4eca-82cd-9c06572d0edc
2025-12-18 03:23:58,139 - DEBUG - Response Code: 404
2025-12-18 03:23:58,139 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:58,146 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:58,175 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:58,271 - INFO - Validated that VN ctest-left_vn-20085685 is not found in API Server
2025-12-18 03:23:58,284 - DEBUG - VN ctest-left_vn-20085685 is not present in Agent 10.0.0.249
2025-12-18 03:23:58,296 - DEBUG - VN ctest-left_vn-20085685 is not present in Agent 10.0.0.129
2025-12-18 03:23:58,296 - INFO - Validated that VN ctest-left_vn-20085685 is not in any agent
2025-12-18 03:23:58,331 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-20085685 info
2025-12-18 03:23:58,331 - INFO - Deleting VN ctest-mgmt_vn-69395025
2025-12-18 03:23:58,491 - DEBUG - Response for deleting network ()
2025-12-18 03:23:58,491 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/cf930e14-f47f-4a9c-831a-e2a20d2ea665
2025-12-18 03:23:58,499 - DEBUG - Response Code: 404
2025-12-18 03:23:58,499 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:23:58,510 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:23:58,528 - DEBUG - Requesting: http://10.0.0.23:8082/project/c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:23:58,620 - INFO - Validated that VN ctest-mgmt_vn-69395025 is not found in API Server
2025-12-18 03:23:58,632 - DEBUG - VN ctest-mgmt_vn-69395025 is not present in Agent 10.0.0.249
2025-12-18 03:23:58,645 - DEBUG - VN ctest-mgmt_vn-69395025 is not present in Agent 10.0.0.129
2025-12-18 03:23:58,645 - INFO - Validated that VN ctest-mgmt_vn-69395025 is not in any agent
2025-12-18 03:23:58,681 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-69395025 info
2025-12-18 03:23:59,993 - DEBUG - No XMPP flaps were noticed during the test
2025-12-18 03:23:59,993 - INFO -
2025-12-18 03:23:59,993 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-12-18 03:23:59,994 - INFO - --------------------------------------------------------------------------------
2025-12-18 03:24:00,767 - INFO - Deleted project: ctest-TestSvcRegr-97473872, ID : c322ee27-fc24-452c-b47e-996938cdad79
2025-12-18 03:24:01,000 - INFO - Domain Default found not creating
2025-12-18 03:24:01,245 - INFO - Project ctest-TestSvcRegr-47592433 not found, creating it
2025-12-18 03:24:01,852 - INFO - Created Project:ctest-TestSvcRegr-47592433, ID : 80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:03,508 - INFO - ================================================================================
2025-12-18 03:24:03,508 - INFO - STARTING TEST : test_svc_CEM_22032
2025-12-18 03:24:04,800 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.129': '0', '10.20.0.252': '0'}, '10.0.0.129': {'10.20.0.129': '0', '10.20.0.252': '0'}} with
2025-12-18 03:24:04,800 - INFO - Initial checks done. Running the testcase now
2025-12-18 03:24:04,800 - INFO -
2025-12-18 03:24:05,612 - DEBUG - Response for create_network : {'network': {'id': '36789776-714f-402e-b0e2-b09c88a7cd67', 'name': 'ctest-mgmt_vn-62983423', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'project_id': '80fc0415016147c3a717e018b88a5252', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47592433', 'ctest-mgmt_vn-62983423'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:24:05,941 - DEBUG - Response for create_subnet : {'subnet': {'id': '9a3a7f47-567f-4508-8803-0c89f94577eb', 'name': '', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'network_id': '36789776-714f-402e-b0e2-b09c88a7cd67', 'ip_version': 4, 'cidr': '29.228.6.192/26', 'allocation_pools': [{'start': '29.228.6.194', 'end': '29.228.6.254'}], 'gateway_ip': '29.228.6.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.228.6.194', 'tags': [], 'project_id': '80fc0415016147c3a717e018b88a5252'}}
2025-12-18 03:24:05,965 - INFO - Created VN ctest-mgmt_vn-62983423
2025-12-18 03:24:05,977 - DEBUG - VN ctest-mgmt_vn-62983423 UUID is 36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,059 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:06,360 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:06,395 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:06,411 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:06,530 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,543 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,556 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/865dbb28-78d7-466e-af42-bc7c29c27415
2025-12-18 03:24:06,567 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/865dbb28-78d7-466e-af42-bc7c29c27415
2025-12-18 03:24:06,577 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/233a47b8-057c-4553-aed9-d360ea006095
2025-12-18 03:24:06,585 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:06,585 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,594 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/865dbb28-78d7-466e-af42-bc7c29c27415
2025-12-18 03:24:06,612 - INFO - Verified VN network id 14 for VN 36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,612 - INFO - Verifications in API Server for VN ctest-mgmt_vn-62983423 passed
2025-12-18 03:24:06,613 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,625 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/865dbb28-78d7-466e-af42-bc7c29c27415
2025-12-18 03:24:06,634 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/865dbb28-78d7-466e-af42-bc7c29c27415
2025-12-18 03:24:06,645 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/233a47b8-057c-4553-aed9-d360ea006095
2025-12-18 03:24:06,661 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3925053610216341550', 'uuid-lslong': '12745944081766010215'}, 'enable': 'true', 'created': '2025-12-18T03:24:05', 'last-modified': '2025-12-18T03:24:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.777794'}
2025-12-18 03:24:06,670 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:06,676 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3925053610216341550', 'uuid-lslong': '12745944081766010215'}, 'enable': 'true', 'created': '2025-12-18T03:24:05', 'last-modified': '2025-12-18T03:24:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.796638'}
2025-12-18 03:24:06,690 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:06,697 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3925053610216341550', 'uuid-lslong': '12745944081766010215'}, 'enable': 'true', 'created': '2025-12-18T03:24:05', 'last-modified': '2025-12-18T03:24:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.816357'}
2025-12-18 03:24:06,704 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:06,704 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-62983423 passed
2025-12-18 03:24:06,704 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-62983423 in API_Server ======
2025-12-18 03:24:06,704 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:06,713 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:06,728 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:06,837 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/36789776-714f-402e-b0e2-b09c88a7cd67
2025-12-18 03:24:06,851 - DEBUG - =>VN ctest-mgmt_vn-62983423 has no policy to be verified
2025-12-18 03:24:06,851 - DEBUG - Verifying the vn in opserver
2025-12-18 03:24:06,851 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423 virtual network link through opserver 10.0.0.23
2025-12-18 03:24:06,851 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:07,160 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:07,224 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423?flat'}
2025-12-18 03:24:07,224 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423 virtual network link through opserver 10.0.0.50
2025-12-18 03:24:07,224 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:07,537 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:07,612 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423?flat'}
2025-12-18 03:24:07,612 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423 virtual network link through opserver 10.0.0.65
2025-12-18 03:24:07,612 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:07,933 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:08,001 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423?flat'}
2025-12-18 03:24:08,001 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-62983423 is found in opserver
2025-12-18 03:24:08,027 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:24:08,042 - DEBUG - VRF ids for VN ctest-mgmt_vn-62983423: {}
2025-12-18 03:24:08,345 - DEBUG - Response for create_network : {'network': {'id': '3f5e64ed-4976-4d14-9cfe-1d7e96aab452', 'name': 'service-left-vn-39891731', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'project_id': '80fc0415016147c3a717e018b88a5252', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47592433', 'service-left-vn-39891731'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:24:08,625 - DEBUG - Response for create_subnet : {'subnet': {'id': '4fd46076-9590-4df7-a96c-6072632d2a37', 'name': '', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'network_id': '3f5e64ed-4976-4d14-9cfe-1d7e96aab452', 'ip_version': 4, 'cidr': '25.202.206.192/26', 'allocation_pools': [{'start': '25.202.206.194', 'end': '25.202.206.254'}], 'gateway_ip': '25.202.206.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '25.202.206.194', 'tags': [], 'project_id': '80fc0415016147c3a717e018b88a5252'}}
2025-12-18 03:24:08,654 - INFO - Created VN service-left-vn-39891731
2025-12-18 03:24:08,665 - DEBUG - VN service-left-vn-39891731 UUID is 3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:08,750 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:08,759 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:08,772 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:08,887 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:08,901 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:08,911 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b3e28a80-4254-411a-aa3c-eecbd0d5544a
2025-12-18 03:24:08,921 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b3e28a80-4254-411a-aa3c-eecbd0d5544a
2025-12-18 03:24:08,930 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6814f26e-fb74-47ea-ab98-de056da03c54
2025-12-18 03:24:08,939 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:08,939 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:08,949 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b3e28a80-4254-411a-aa3c-eecbd0d5544a
2025-12-18 03:24:08,967 - INFO - Verified VN network id 15 for VN 3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:08,969 - INFO - Verifications in API Server for VN service-left-vn-39891731 passed
2025-12-18 03:24:08,969 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:08,981 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b3e28a80-4254-411a-aa3c-eecbd0d5544a
2025-12-18 03:24:09,001 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b3e28a80-4254-411a-aa3c-eecbd0d5544a
2025-12-18 03:24:09,011 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6814f26e-fb74-47ea-ab98-de056da03c54
2025-12-18 03:24:09,025 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4566198042502778132', 'uuid-lslong': '11312511743532119122'}, 'enable': 'true', 'created': '2025-12-18T03:24:08', 'last-modified': '2025-12-18T03:24:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443032'}
2025-12-18 03:24:09,032 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:09,038 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4566198042502778132', 'uuid-lslong': '11312511743532119122'}, 'enable': 'true', 'created': '2025-12-18T03:24:08', 'last-modified': '2025-12-18T03:24:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.473004'}
2025-12-18 03:24:09,046 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:09,051 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4566198042502778132', 'uuid-lslong': '11312511743532119122'}, 'enable': 'true', 'created': '2025-12-18T03:24:08', 'last-modified': '2025-12-18T03:24:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.472198'}
2025-12-18 03:24:09,057 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:09,058 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-39891731 passed
2025-12-18 03:24:09,058 - DEBUG - ====Verifying policy data for service-left-vn-39891731 in API_Server ======
2025-12-18 03:24:09,058 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:09,064 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:09,078 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:09,182 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3f5e64ed-4976-4d14-9cfe-1d7e96aab452
2025-12-18 03:24:09,194 - DEBUG - =>VN service-left-vn-39891731 has no policy to be verified
2025-12-18 03:24:09,194 - DEBUG - Verifying the vn in opserver
2025-12-18 03:24:09,194 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731 virtual network link through opserver 10.0.0.23
2025-12-18 03:24:09,194 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:09,264 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731?flat'}
2025-12-18 03:24:09,264 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731 virtual network link through opserver 10.0.0.50
2025-12-18 03:24:09,264 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:09,330 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731?flat'}
2025-12-18 03:24:09,330 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731 virtual network link through opserver 10.0.0.65
2025-12-18 03:24:09,330 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:09,390 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731?flat'}
2025-12-18 03:24:09,390 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47592433:service-left-vn-39891731 is found in opserver
2025-12-18 03:24:09,413 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:24:09,426 - DEBUG - VRF ids for VN service-left-vn-39891731: {}
2025-12-18 03:24:09,687 - DEBUG - Response for create_network : {'network': {'id': 'bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd', 'name': 'service-right-vn-02726110', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'project_id': '80fc0415016147c3a717e018b88a5252', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47592433', 'service-right-vn-02726110'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:24:09,941 - DEBUG - Response for create_subnet : {'subnet': {'id': '5a8d4eeb-bf80-4cdc-b472-90aa6feda393', 'name': '', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'network_id': 'bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd', 'ip_version': 4, 'cidr': '57.22.225.192/26', 'allocation_pools': [{'start': '57.22.225.194', 'end': '57.22.225.254'}], 'gateway_ip': '57.22.225.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '57.22.225.194', 'tags': [], 'project_id': '80fc0415016147c3a717e018b88a5252'}}
2025-12-18 03:24:09,969 - INFO - Created VN service-right-vn-02726110
2025-12-18 03:24:09,984 - DEBUG - VN service-right-vn-02726110 UUID is bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,070 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:10,076 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:10,089 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:10,179 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,200 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,213 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8277699c-1dbf-48b9-8f80-0948c93046e4
2025-12-18 03:24:10,224 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8277699c-1dbf-48b9-8f80-0948c93046e4
2025-12-18 03:24:10,231 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/385a8cd2-4017-47e8-866c-03eb094642fa
2025-12-18 03:24:10,238 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:10,238 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,248 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8277699c-1dbf-48b9-8f80-0948c93046e4
2025-12-18 03:24:10,262 - INFO - Verified VN network id 16 for VN bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,263 - INFO - Verifications in API Server for VN service-right-vn-02726110 passed
2025-12-18 03:24:10,263 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,275 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8277699c-1dbf-48b9-8f80-0948c93046e4
2025-12-18 03:24:10,283 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8277699c-1dbf-48b9-8f80-0948c93046e4
2025-12-18 03:24:10,292 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/385a8cd2-4017-47e8-866c-03eb094642fa
2025-12-18 03:24:10,306 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13576821224342963417', 'uuid-lslong': '12104417056712723133'}, 'enable': 'true', 'created': '2025-12-18T03:24:09', 'last-modified': '2025-12-18T03:24:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428874'}
2025-12-18 03:24:10,332 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:10,341 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13576821224342963417', 'uuid-lslong': '12104417056712723133'}, 'enable': 'true', 'created': '2025-12-18T03:24:09', 'last-modified': '2025-12-18T03:24:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.465183'}
2025-12-18 03:24:10,353 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:10,358 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13576821224342963417', 'uuid-lslong': '12104417056712723133'}, 'enable': 'true', 'created': '2025-12-18T03:24:09', 'last-modified': '2025-12-18T03:24:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483098'}
2025-12-18 03:24:10,370 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:10,370 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-02726110 passed
2025-12-18 03:24:10,370 - DEBUG - ====Verifying policy data for service-right-vn-02726110 in API_Server ======
2025-12-18 03:24:10,370 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:10,376 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:10,390 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:10,476 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd
2025-12-18 03:24:10,498 - DEBUG - =>VN service-right-vn-02726110 has no policy to be verified
2025-12-18 03:24:10,498 - DEBUG - Verifying the vn in opserver
2025-12-18 03:24:10,498 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110 virtual network link through opserver 10.0.0.23
2025-12-18 03:24:10,498 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:10,588 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110?flat'}
2025-12-18 03:24:10,588 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110 virtual network link through opserver 10.0.0.50
2025-12-18 03:24:10,588 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:10,648 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110?flat'}
2025-12-18 03:24:10,648 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110 virtual network link through opserver 10.0.0.65
2025-12-18 03:24:10,648 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:10,716 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110?flat'}
2025-12-18 03:24:10,716 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47592433:service-right-vn-02726110 is found in opserver
2025-12-18 03:24:10,742 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:24:10,754 - DEBUG - VRF ids for VN service-right-vn-02726110: {}
2025-12-18 03:24:10,823 - DEBUG - Services list from nova: [, , , ]
2025-12-18 03:24:11,989 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-1)
2025-12-18 03:24:13,574 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-2)
2025-12-18 03:24:13,575 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-68785836']
2025-12-18 03:24:13,873 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-68785836'] created succesfully
2025-12-18 03:24:14,414 - DEBUG - Image by name tiny_nat_fw not found
2025-12-18 03:24:14,414 - DEBUG - Installing image tiny_nat_fw
2025-12-18 03:24:14,414 - 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-12-18 03:24:14,414 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:24:14,414 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:24:14,414 - DEBUG - Unzip image
2025-12-18 03:24:14,414 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:24:14,437 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-18 03:24:14,437 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-68785836']
2025-12-18 03:24:14,557 - INFO - Service template: ['default-domain', 'ctest-service_template_1-68785836'] deleted successfully.
2025-12-18 03:24:14,557 - INFO - Skip interface_detach for VM ctest-right_vm-09647311 in state BUILD
2025-12-18 03:24:14,557 - INFO - Deleting VM ctest-right_vm-09647311
2025-12-18 03:24:14,588 - ERROR - VM ctest-right_vm-09647311 has failed to come up
2025-12-18 03:24:14,588 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:14,588 - ERROR - VM ctest-right_vm-09647311 has failed to come up
2025-12-18 03:24:14,588 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:14,665 - INFO - Skip interface_detach for VM ctest-left_vm-90608062 in state BUILD
2025-12-18 03:24:14,666 - INFO - Deleting VM ctest-left_vm-90608062
2025-12-18 03:24:14,666 - ERROR - VM ctest-left_vm-90608062 has failed to come up
2025-12-18 03:24:14,666 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:14,666 - ERROR - VM ctest-left_vm-90608062 has failed to come up
2025-12-18 03:24:14,667 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:14,757 - INFO - Deleting VN service-right-vn-02726110
2025-12-18 03:24:14,809 - DEBUG - VN bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd still in use: Unable to complete operation on network bc6a8ef8-6ff2-44d9-a7fb-872e5e8a8abd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ac0bd444-abb9-4ed1-9438-287999089ec8']
2025-12-18 03:24:14,809 - WARNING - Deleting VN service-right-vn-02726110 failed..Will retry
2025-12-18 03:24:17,036 - DEBUG - Response for deleting network ()
2025-12-18 03:24:17,036 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8277699c-1dbf-48b9-8f80-0948c93046e4
2025-12-18 03:24:17,046 - DEBUG - Response Code: 404
2025-12-18 03:24:17,046 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:17,053 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:17,131 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:17,252 - INFO - Validated that VN service-right-vn-02726110 is not found in API Server
2025-12-18 03:24:17,265 - DEBUG - VN service-right-vn-02726110 is not present in Agent 10.0.0.249
2025-12-18 03:24:17,276 - DEBUG - VN service-right-vn-02726110 is not present in Agent 10.0.0.129
2025-12-18 03:24:17,276 - INFO - Validated that VN service-right-vn-02726110 is not in any agent
2025-12-18 03:24:17,307 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-02726110 info
2025-12-18 03:24:17,307 - INFO - Deleting VN service-left-vn-39891731
2025-12-18 03:24:17,481 - DEBUG - Response for deleting network ()
2025-12-18 03:24:17,482 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b3e28a80-4254-411a-aa3c-eecbd0d5544a
2025-12-18 03:24:17,488 - DEBUG - Response Code: 404
2025-12-18 03:24:17,488 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:17,494 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:17,505 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:17,621 - INFO - Validated that VN service-left-vn-39891731 is not found in API Server
2025-12-18 03:24:17,635 - DEBUG - VN service-left-vn-39891731 is not present in Agent 10.0.0.249
2025-12-18 03:24:17,648 - DEBUG - VN service-left-vn-39891731 is not present in Agent 10.0.0.129
2025-12-18 03:24:17,648 - INFO - Validated that VN service-left-vn-39891731 is not in any agent
2025-12-18 03:24:17,679 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-39891731 info
2025-12-18 03:24:17,679 - INFO - Deleting VN ctest-mgmt_vn-62983423
2025-12-18 03:24:17,853 - DEBUG - Response for deleting network ()
2025-12-18 03:24:17,853 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/865dbb28-78d7-466e-af42-bc7c29c27415
2025-12-18 03:24:17,861 - DEBUG - Response Code: 404
2025-12-18 03:24:17,861 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:17,884 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:17,899 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:17,994 - INFO - Validated that VN ctest-mgmt_vn-62983423 is not found in API Server
2025-12-18 03:24:18,005 - DEBUG - VN ctest-mgmt_vn-62983423 is not present in Agent 10.0.0.249
2025-12-18 03:24:18,015 - DEBUG - VN ctest-mgmt_vn-62983423 is not present in Agent 10.0.0.129
2025-12-18 03:24:18,015 - INFO - Validated that VN ctest-mgmt_vn-62983423 is not in any agent
2025-12-18 03:24:18,047 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-62983423 info
2025-12-18 03:24:19,366 - DEBUG - No XMPP flaps were noticed during the test
2025-12-18 03:24:19,366 - INFO -
2025-12-18 03:24:19,366 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-12-18 03:24:19,366 - INFO - --------------------------------------------------------------------------------
2025-12-18 03:24:19,369 - INFO - ================================================================================
2025-12-18 03:24:19,369 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-12-18 03:24:20,650 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.129': '0', '10.20.0.252': '0'}, '10.0.0.129': {'10.20.0.129': '0', '10.20.0.252': '0'}} with
2025-12-18 03:24:20,651 - INFO - Initial checks done. Running the testcase now
2025-12-18 03:24:20,651 - INFO -
2025-12-18 03:24:20,961 - DEBUG - Response for create_network : {'network': {'id': '4873c65a-a1b6-4818-801c-5c0a222cc283', 'name': 'ctest-mgmt_vn-32060890', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'project_id': '80fc0415016147c3a717e018b88a5252', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47592433', 'ctest-mgmt_vn-32060890'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:24:21,224 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e0b41d87-3463-4299-8760-5d560ef739a4', 'name': '', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'network_id': '4873c65a-a1b6-4818-801c-5c0a222cc283', 'ip_version': 4, 'cidr': '186.106.167.192/26', 'allocation_pools': [{'start': '186.106.167.194', 'end': '186.106.167.254'}], 'gateway_ip': '186.106.167.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '186.106.167.194', 'tags': [], 'project_id': '80fc0415016147c3a717e018b88a5252'}}
2025-12-18 03:24:21,247 - INFO - Created VN ctest-mgmt_vn-32060890
2025-12-18 03:24:21,263 - DEBUG - VN ctest-mgmt_vn-32060890 UUID is 4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,340 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:21,346 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:21,358 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:21,471 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,483 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,493 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/be4c16f9-c0b4-4a38-8735-14a039ff0958
2025-12-18 03:24:21,502 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/be4c16f9-c0b4-4a38-8735-14a039ff0958
2025-12-18 03:24:21,511 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/ffc81693-d64d-481e-974a-f335af160f0f
2025-12-18 03:24:21,521 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:21,521 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,531 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/be4c16f9-c0b4-4a38-8735-14a039ff0958
2025-12-18 03:24:21,547 - INFO - Verified VN network id 14 for VN 4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,547 - INFO - Verifications in API Server for VN ctest-mgmt_vn-32060890 passed
2025-12-18 03:24:21,548 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,564 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/be4c16f9-c0b4-4a38-8735-14a039ff0958
2025-12-18 03:24:21,572 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/be4c16f9-c0b4-4a38-8735-14a039ff0958
2025-12-18 03:24:21,579 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/ffc81693-d64d-481e-974a-f335af160f0f
2025-12-18 03:24:21,592 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5220734485614970904', 'uuid-lslong': '9231354534795461251'}, 'enable': 'true', 'created': '2025-12-18T03:24:20', 'last-modified': '2025-12-18T03:24:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436215'}
2025-12-18 03:24:21,600 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:21,607 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5220734485614970904', 'uuid-lslong': '9231354534795461251'}, 'enable': 'true', 'created': '2025-12-18T03:24:20', 'last-modified': '2025-12-18T03:24:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.460944'}
2025-12-18 03:24:21,614 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:21,621 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5220734485614970904', 'uuid-lslong': '9231354534795461251'}, 'enable': 'true', 'created': '2025-12-18T03:24:20', 'last-modified': '2025-12-18T03:24:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.468091'}
2025-12-18 03:24:21,626 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-18 03:24:21,627 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-32060890 passed
2025-12-18 03:24:21,627 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-32060890 in API_Server ======
2025-12-18 03:24:21,627 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:21,633 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:21,645 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:21,738 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4873c65a-a1b6-4818-801c-5c0a222cc283
2025-12-18 03:24:21,748 - DEBUG - =>VN ctest-mgmt_vn-32060890 has no policy to be verified
2025-12-18 03:24:21,748 - DEBUG - Verifying the vn in opserver
2025-12-18 03:24:21,748 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890 virtual network link through opserver 10.0.0.23
2025-12-18 03:24:21,748 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:21,760 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890?flat'}
2025-12-18 03:24:21,760 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890 virtual network link through opserver 10.0.0.50
2025-12-18 03:24:21,760 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:21,773 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890?flat'}
2025-12-18 03:24:21,773 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890 virtual network link through opserver 10.0.0.65
2025-12-18 03:24:21,773 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:21,785 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890?flat'}
2025-12-18 03:24:21,785 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47592433:ctest-mgmt_vn-32060890 is found in opserver
2025-12-18 03:24:21,805 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:24:21,816 - DEBUG - VRF ids for VN ctest-mgmt_vn-32060890: {}
2025-12-18 03:24:22,124 - DEBUG - Response for create_network : {'network': {'id': 'c2493c21-dfc7-4bf2-8569-0631b4b7b83d', 'name': 'ctest-left_vn-25813121', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'project_id': '80fc0415016147c3a717e018b88a5252', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47592433', 'ctest-left_vn-25813121'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:24:22,422 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cf015901-bff4-4e0e-9828-f749943befbc', 'name': '', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'network_id': 'c2493c21-dfc7-4bf2-8569-0631b4b7b83d', 'ip_version': 4, 'cidr': '140.99.115.64/26', 'allocation_pools': [{'start': '140.99.115.66', 'end': '140.99.115.126'}], 'gateway_ip': '140.99.115.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '140.99.115.66', 'tags': [], 'project_id': '80fc0415016147c3a717e018b88a5252'}}
2025-12-18 03:24:22,450 - INFO - Created VN ctest-left_vn-25813121
2025-12-18 03:24:22,461 - DEBUG - VN ctest-left_vn-25813121 UUID is c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,552 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:22,557 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:22,569 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:22,673 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,686 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,696 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ed651fb1-58a6-446f-9b76-67db59d41de9
2025-12-18 03:24:22,708 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ed651fb1-58a6-446f-9b76-67db59d41de9
2025-12-18 03:24:22,716 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/142244f6-20ac-4358-9e41-10eafcbecbf0
2025-12-18 03:24:22,721 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:22,722 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,730 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ed651fb1-58a6-446f-9b76-67db59d41de9
2025-12-18 03:24:22,743 - INFO - Verified VN network id 15 for VN c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,743 - INFO - Verifications in API Server for VN ctest-left_vn-25813121 passed
2025-12-18 03:24:22,744 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,752 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ed651fb1-58a6-446f-9b76-67db59d41de9
2025-12-18 03:24:22,759 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ed651fb1-58a6-446f-9b76-67db59d41de9
2025-12-18 03:24:22,768 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/142244f6-20ac-4358-9e41-10eafcbecbf0
2025-12-18 03:24:22,782 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13999787032843865074', 'uuid-lslong': '9613221690154137661'}, 'enable': 'true', 'created': '2025-12-18T03:24:22', 'last-modified': '2025-12-18T03:24:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440237'}
2025-12-18 03:24:22,788 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:22,794 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13999787032843865074', 'uuid-lslong': '9613221690154137661'}, 'enable': 'true', 'created': '2025-12-18T03:24:22', 'last-modified': '2025-12-18T03:24:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436524'}
2025-12-18 03:24:22,800 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:22,805 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13999787032843865074', 'uuid-lslong': '9613221690154137661'}, 'enable': 'true', 'created': '2025-12-18T03:24:22', 'last-modified': '2025-12-18T03:24:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462777'}
2025-12-18 03:24:22,812 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-18 03:24:22,812 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-25813121 passed
2025-12-18 03:24:22,812 - DEBUG - ====Verifying policy data for ctest-left_vn-25813121 in API_Server ======
2025-12-18 03:24:22,812 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:22,819 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:22,831 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:22,915 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c2493c21-dfc7-4bf2-8569-0631b4b7b83d
2025-12-18 03:24:22,935 - DEBUG - =>VN ctest-left_vn-25813121 has no policy to be verified
2025-12-18 03:24:22,935 - DEBUG - Verifying the vn in opserver
2025-12-18 03:24:22,935 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121 virtual network link through opserver 10.0.0.23
2025-12-18 03:24:22,935 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:22,948 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121?flat'}
2025-12-18 03:24:22,948 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121 virtual network link through opserver 10.0.0.50
2025-12-18 03:24:22,948 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:22,961 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121?flat'}
2025-12-18 03:24:22,961 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121 virtual network link through opserver 10.0.0.65
2025-12-18 03:24:22,961 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:22,975 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121?flat'}
2025-12-18 03:24:22,975 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47592433:ctest-left_vn-25813121 is found in opserver
2025-12-18 03:24:22,999 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:24:23,010 - DEBUG - VRF ids for VN ctest-left_vn-25813121: {}
2025-12-18 03:24:23,279 - DEBUG - Response for create_network : {'network': {'id': 'ec900b8f-c3fa-4910-be54-104505bc1476', 'name': 'ctest-right_vn-89024700', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'project_id': '80fc0415016147c3a717e018b88a5252', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47592433', 'ctest-right_vn-89024700'], 'port_security_enabled': True, 'description': ''}}
2025-12-18 03:24:23,533 - DEBUG - Response for create_subnet : {'subnet': {'id': '9dd59044-04c0-496d-bf84-d493b3d63751', 'name': '', 'tenant_id': '80fc0415016147c3a717e018b88a5252', 'network_id': 'ec900b8f-c3fa-4910-be54-104505bc1476', 'ip_version': 4, 'cidr': '23.113.218.64/26', 'allocation_pools': [{'start': '23.113.218.66', 'end': '23.113.218.126'}], 'gateway_ip': '23.113.218.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '23.113.218.66', 'tags': [], 'project_id': '80fc0415016147c3a717e018b88a5252'}}
2025-12-18 03:24:23,558 - INFO - Created VN ctest-right_vn-89024700
2025-12-18 03:24:23,570 - DEBUG - VN ctest-right_vn-89024700 UUID is ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:23,651 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:23,656 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:23,667 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:23,778 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:23,794 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:23,803 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d3d32909-fb24-40cf-8c62-467332bb74e2
2025-12-18 03:24:23,810 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d3d32909-fb24-40cf-8c62-467332bb74e2
2025-12-18 03:24:23,817 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/b9b041d0-b911-45a3-afcb-e53b604fc2b3
2025-12-18 03:24:23,825 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:23,825 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:23,837 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d3d32909-fb24-40cf-8c62-467332bb74e2
2025-12-18 03:24:23,853 - INFO - Verified VN network id 16 for VN ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:23,853 - INFO - Verifications in API Server for VN ctest-right_vn-89024700 passed
2025-12-18 03:24:23,853 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:23,865 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d3d32909-fb24-40cf-8c62-467332bb74e2
2025-12-18 03:24:23,873 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d3d32909-fb24-40cf-8c62-467332bb74e2
2025-12-18 03:24:23,881 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/b9b041d0-b911-45a3-afcb-e53b604fc2b3
2025-12-18 03:24:23,894 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17046137301693516048', 'uuid-lslong': '13714604653885002870'}, 'enable': 'true', 'created': '2025-12-18T03:24:23', 'last-modified': '2025-12-18T03:24:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416793'}
2025-12-18 03:24:23,902 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:23,908 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17046137301693516048', 'uuid-lslong': '13714604653885002870'}, 'enable': 'true', 'created': '2025-12-18T03:24:23', 'last-modified': '2025-12-18T03:24:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430024'}
2025-12-18 03:24:23,915 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:23,921 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47592433', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17046137301693516048', 'uuid-lslong': '13714604653885002870'}, 'enable': 'true', 'created': '2025-12-18T03:24:23', 'last-modified': '2025-12-18T03:24:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430588'}
2025-12-18 03:24:23,927 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-18 03:24:23,927 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-89024700 passed
2025-12-18 03:24:23,927 - DEBUG - ====Verifying policy data for ctest-right_vn-89024700 in API_Server ======
2025-12-18 03:24:23,927 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:23,933 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:23,945 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:24,040 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec900b8f-c3fa-4910-be54-104505bc1476
2025-12-18 03:24:24,055 - DEBUG - =>VN ctest-right_vn-89024700 has no policy to be verified
2025-12-18 03:24:24,055 - DEBUG - Verifying the vn in opserver
2025-12-18 03:24:24,056 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700 virtual network link through opserver 10.0.0.23
2025-12-18 03:24:24,056 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-18 03:24:24,070 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700?flat'}
2025-12-18 03:24:24,070 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700 virtual network link through opserver 10.0.0.50
2025-12-18 03:24:24,070 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-12-18 03:24:24,084 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700?flat'}
2025-12-18 03:24:24,084 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700 virtual network link through opserver 10.0.0.65
2025-12-18 03:24:24,084 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-18 03:24:24,097 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700?flat'}
2025-12-18 03:24:24,097 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47592433:ctest-right_vn-89024700 is found in opserver
2025-12-18 03:24:24,122 - DEBUG - Do not have enough data to verify VN in agent
2025-12-18 03:24:24,134 - DEBUG - VRF ids for VN ctest-right_vn-89024700: {}
2025-12-18 03:24:25,195 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-2)
2025-12-18 03:24:26,704 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4903-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4903-1)
2025-12-18 03:24:26,706 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-16062500']
2025-12-18 03:24:27,002 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16062500'] created succesfully
2025-12-18 03:24:27,332 - DEBUG - Image by name tiny_nat_fw not found
2025-12-18 03:24:27,332 - DEBUG - Installing image tiny_nat_fw
2025-12-18 03:24:27,332 - 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-12-18 03:24:27,332 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:24:27,332 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:24:27,333 - DEBUG - Unzip image
2025-12-18 03:24:27,333 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-18 03:24:27,366 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-18 03:24:27,367 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-16062500']
2025-12-18 03:24:27,465 - INFO - Service template: ['default-domain', 'ctest-service_template_1-16062500'] deleted successfully.
2025-12-18 03:24:27,465 - INFO - Skip interface_detach for VM ctest-right_vm-65980734 in state BUILD
2025-12-18 03:24:27,466 - INFO - Deleting VM ctest-right_vm-65980734
2025-12-18 03:24:27,466 - ERROR - VM ctest-right_vm-65980734 has failed to come up
2025-12-18 03:24:27,466 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:27,466 - ERROR - VM ctest-right_vm-65980734 has failed to come up
2025-12-18 03:24:27,466 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:27,605 - INFO - Skip interface_detach for VM ctest-left_vm-48351802 in state BUILD
2025-12-18 03:24:27,606 - INFO - Deleting VM ctest-left_vm-48351802
2025-12-18 03:24:27,606 - ERROR - VM ctest-left_vm-48351802 has failed to come up
2025-12-18 03:24:27,606 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:27,606 - ERROR - VM ctest-left_vm-48351802 has failed to come up
2025-12-18 03:24:27,606 - ERROR - Nova failed to get host of the VM
2025-12-18 03:24:27,681 - INFO - Deleting VN ctest-right_vn-89024700
2025-12-18 03:24:27,730 - DEBUG - VN ec900b8f-c3fa-4910-be54-104505bc1476 still in use: Unable to complete operation on network ec900b8f-c3fa-4910-be54-104505bc1476. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8113219b-3867-4a26-98eb-9a14cf7a8834']
2025-12-18 03:24:27,730 - WARNING - Deleting VN ctest-right_vn-89024700 failed..Will retry
2025-12-18 03:24:29,902 - DEBUG - Response for deleting network ()
2025-12-18 03:24:29,902 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d3d32909-fb24-40cf-8c62-467332bb74e2
2025-12-18 03:24:29,909 - DEBUG - Response Code: 404
2025-12-18 03:24:29,909 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:29,915 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:29,985 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:30,107 - INFO - Validated that VN ctest-right_vn-89024700 is not found in API Server
2025-12-18 03:24:30,118 - DEBUG - VN ctest-right_vn-89024700 is not present in Agent 10.0.0.249
2025-12-18 03:24:30,130 - DEBUG - VN ctest-right_vn-89024700 is not present in Agent 10.0.0.129
2025-12-18 03:24:30,130 - INFO - Validated that VN ctest-right_vn-89024700 is not in any agent
2025-12-18 03:24:30,178 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-89024700 info
2025-12-18 03:24:30,178 - INFO - Deleting VN ctest-left_vn-25813121
2025-12-18 03:24:30,389 - DEBUG - Response for deleting network ()
2025-12-18 03:24:30,389 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ed651fb1-58a6-446f-9b76-67db59d41de9
2025-12-18 03:24:30,403 - DEBUG - Response Code: 404
2025-12-18 03:24:30,403 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:30,408 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:30,463 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:30,558 - INFO - Validated that VN ctest-left_vn-25813121 is not found in API Server
2025-12-18 03:24:30,570 - DEBUG - VN ctest-left_vn-25813121 is not present in Agent 10.0.0.249
2025-12-18 03:24:30,583 - DEBUG - VN ctest-left_vn-25813121 is not present in Agent 10.0.0.129
2025-12-18 03:24:30,583 - INFO - Validated that VN ctest-left_vn-25813121 is not in any agent
2025-12-18 03:24:30,615 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-25813121 info
2025-12-18 03:24:30,616 - INFO - Deleting VN ctest-mgmt_vn-32060890
2025-12-18 03:24:30,799 - DEBUG - Response for deleting network ()
2025-12-18 03:24:30,799 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/be4c16f9-c0b4-4a38-8735-14a039ff0958
2025-12-18 03:24:30,806 - DEBUG - Response Code: 404
2025-12-18 03:24:30,806 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-18 03:24:30,813 - DEBUG - Requesting: http://10.0.0.23:8082/domain/48c3cb03-f70f-4f5d-8ebd-0d23e5db0707
2025-12-18 03:24:30,828 - DEBUG - Requesting: http://10.0.0.23:8082/project/80fc0415-0161-47c3-a717-e018b88a5252
2025-12-18 03:24:30,938 - INFO - Validated that VN ctest-mgmt_vn-32060890 is not found in API Server
2025-12-18 03:24:30,951 - DEBUG - VN ctest-mgmt_vn-32060890 is not present in Agent 10.0.0.249
2025-12-18 03:24:30,964 - DEBUG - VN ctest-mgmt_vn-32060890 is not present in Agent 10.0.0.129
2025-12-18 03:24:30,964 - INFO - Validated that VN ctest-mgmt_vn-32060890 is not in any agent
2025-12-18 03:24:31,000 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-32060890 info
2025-12-18 03:24:32,296 - DEBUG - No XMPP flaps were noticed during the test
2025-12-18 03:24:32,297 - INFO -
2025-12-18 03:24:32,297 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-12-18 03:24:32,297 - INFO - --------------------------------------------------------------------------------
2025-12-18 03:24:33,096 - INFO - Deleted project: ctest-TestSvcRegr-47592433, ID : 80fc0415-0161-47c3-a717-e018b88a5252