]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-1)
2025-10-30 11:10:36,085 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-23186371']
2025-10-30 11:10:36,381 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-23186371'] created succesfully
2025-10-30 11:10:36,756 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 11:10:36,757 - DEBUG - Installing image tiny_nat_fw
2025-10-30 11:10:36,757 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-30 11:10:36,757 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-30 11:10:36,757 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-30 11:10:36,804 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:10:36,893 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:10:36,894 - DEBUG - Unzip image
2025-10-30 11:10:36,894 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:10:36,927 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 11:10:36,928 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-23186371']
2025-10-30 11:10:37,044 - INFO - Service template: ['default-domain', 'ctest-service_template_1-23186371'] deleted successfully.
2025-10-30 11:10:37,045 - INFO - Skip interface_detach for VM ctest-right_vm-41679737 in state BUILD
2025-10-30 11:10:37,045 - INFO - Deleting VM ctest-right_vm-41679737
2025-10-30 11:10:37,092 - ERROR - VM ctest-right_vm-41679737 has failed to come up
2025-10-30 11:10:37,092 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:37,092 - ERROR - VM ctest-right_vm-41679737 has failed to come up
2025-10-30 11:10:37,092 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:37,195 - INFO - Skip interface_detach for VM ctest-left_vm-01635767 in state BUILD
2025-10-30 11:10:37,195 - INFO - Deleting VM ctest-left_vm-01635767
2025-10-30 11:10:37,195 - ERROR - VM ctest-left_vm-01635767 has failed to come up
2025-10-30 11:10:37,195 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:37,195 - ERROR - VM ctest-left_vm-01635767 has failed to come up
2025-10-30 11:10:37,196 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:37,278 - INFO - Deleting VN ctest-right_vn-68099866
2025-10-30 11:10:37,357 - DEBUG - VN 89ae889d-efe4-4151-847c-5a94a361b5e8 still in use: Unable to complete operation on network 89ae889d-efe4-4151-847c-5a94a361b5e8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f00421b8-f8a0-43b1-9ccb-8164171b7e3a']
2025-10-30 11:10:37,357 - WARNING - Deleting VN ctest-right_vn-68099866 failed..Will retry
2025-10-30 11:10:39,566 - DEBUG - Response for deleting network ()
2025-10-30 11:10:39,566 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c0991fd7-0520-48ea-a181-729eab6dc0a0
2025-10-30 11:10:39,584 - DEBUG - Response Code: 404
2025-10-30 11:10:39,585 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:39,592 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:39,613 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:39,707 - INFO - Validated that VN ctest-right_vn-68099866 is not found in API Server
2025-10-30 11:10:39,725 - DEBUG - VN ctest-right_vn-68099866 is not present in Agent 10.0.0.41
2025-10-30 11:10:39,738 - DEBUG - VN ctest-right_vn-68099866 is not present in Agent 10.0.0.27
2025-10-30 11:10:39,738 - INFO - Validated that VN ctest-right_vn-68099866 is not in any agent
2025-10-30 11:10:39,770 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-68099866 info
2025-10-30 11:10:39,771 - INFO - Deleting VN ctest-left_vn-45269948
2025-10-30 11:10:39,941 - DEBUG - Response for deleting network ()
2025-10-30 11:10:39,941 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/83e4387f-1efb-4015-ad23-c76acf224a7f
2025-10-30 11:10:39,949 - DEBUG - Response Code: 404
2025-10-30 11:10:39,949 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:39,956 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:39,970 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:40,087 - INFO - Validated that VN ctest-left_vn-45269948 is not found in API Server
2025-10-30 11:10:40,108 - DEBUG - VN ctest-left_vn-45269948 is not present in Agent 10.0.0.41
2025-10-30 11:10:40,121 - DEBUG - VN ctest-left_vn-45269948 is not present in Agent 10.0.0.27
2025-10-30 11:10:40,121 - INFO - Validated that VN ctest-left_vn-45269948 is not in any agent
2025-10-30 11:10:40,154 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-45269948 info
2025-10-30 11:10:40,155 - INFO - Deleting VN ctest-mgmt_vn-49362617
2025-10-30 11:10:40,365 - DEBUG - Response for deleting network ()
2025-10-30 11:10:40,365 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f91e930e-5e89-4200-a359-2b75d5a2cbac
2025-10-30 11:10:40,377 - DEBUG - Response Code: 404
2025-10-30 11:10:40,378 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:40,386 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:40,399 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:40,501 - INFO - Validated that VN ctest-mgmt_vn-49362617 is not found in API Server
2025-10-30 11:10:40,517 - DEBUG - VN ctest-mgmt_vn-49362617 is not present in Agent 10.0.0.41
2025-10-30 11:10:40,530 - DEBUG - VN ctest-mgmt_vn-49362617 is not present in Agent 10.0.0.27
2025-10-30 11:10:40,531 - INFO - Validated that VN ctest-mgmt_vn-49362617 is not in any agent
2025-10-30 11:10:40,567 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-49362617 info
2025-10-30 11:10:40,568 - INFO - Deleting VM ctest-vm-in-third-vn-09466234
2025-10-30 11:10:40,650 - INFO - Deleting VN ctest-third-vn-34690992
2025-10-30 11:10:40,698 - DEBUG - VN 75fb364b-472f-42d5-98b6-777c580ed2f2 still in use: Unable to complete operation on network 75fb364b-472f-42d5-98b6-777c580ed2f2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f2d38259-4ab7-4216-bd93-08a951a5b122']
2025-10-30 11:10:40,698 - WARNING - Deleting VN ctest-third-vn-34690992 failed..Will retry
2025-10-30 11:10:42,879 - DEBUG - Response for deleting network ()
2025-10-30 11:10:44,156 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 11:10:44,156 - INFO -
2025-10-30 11:10:44,156 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2025-10-30 11:10:44,156 - INFO - --------------------------------------------------------------------------------
2025-10-30 11:10:44,159 - INFO - ================================================================================
2025-10-30 11:10:44,159 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-30 11:10:45,431 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.131': '0', '10.20.0.128': '0'}, '10.0.0.27': {'10.20.0.145': '0', '10.20.0.128': '0'}} with
2025-10-30 11:10:45,431 - INFO - Initial checks done. Running the testcase now
2025-10-30 11:10:45,431 - INFO -
2025-10-30 11:10:45,720 - DEBUG - Response for create_network : {'network': {'id': '145ecb46-efb3-4d53-881f-9e160b266808', 'name': 'ctest-mgmt_vn-32842944', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'project_id': 'a549fdc5d0714d00857d923b8b798945', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43104062', 'ctest-mgmt_vn-32842944'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:10:45,979 - DEBUG - Response for create_subnet : {'subnet': {'id': '635b612c-904c-4474-9c6d-67ecc7632016', 'name': '', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'network_id': '145ecb46-efb3-4d53-881f-9e160b266808', 'ip_version': 4, 'cidr': '146.69.168.0/26', 'allocation_pools': [{'start': '146.69.168.2', 'end': '146.69.168.62'}], 'gateway_ip': '146.69.168.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.69.168.2', 'tags': [], 'project_id': 'a549fdc5d0714d00857d923b8b798945'}}
2025-10-30 11:10:46,000 - INFO - Created VN ctest-mgmt_vn-32842944
2025-10-30 11:10:46,012 - DEBUG - VN ctest-mgmt_vn-32842944 UUID is 145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,102 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:46,108 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:46,124 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:46,220 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,233 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,243 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/fa8f0eff-ecec-4ff0-a901-fbdfd6ae4c34
2025-10-30 11:10:46,252 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/fa8f0eff-ecec-4ff0-a901-fbdfd6ae4c34
2025-10-30 11:10:46,262 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/6a4bb95b-c266-43ee-9a83-832af4dd1a6f
2025-10-30 11:10:46,272 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:10:46,272 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,282 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/fa8f0eff-ecec-4ff0-a901-fbdfd6ae4c34
2025-10-30 11:10:46,299 - INFO - Verified VN network id 15 for VN 145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,299 - INFO - Verifications in API Server for VN ctest-mgmt_vn-32842944 passed
2025-10-30 11:10:46,299 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,311 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/fa8f0eff-ecec-4ff0-a901-fbdfd6ae4c34
2025-10-30 11:10:46,320 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/fa8f0eff-ecec-4ff0-a901-fbdfd6ae4c34
2025-10-30 11:10:46,328 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/6a4bb95b-c266-43ee-9a83-832af4dd1a6f
2025-10-30 11:10:46,342 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1467834034099014995', 'uuid-lslong': '9808732330949765128'}, 'enable': 'true', 'created': '2025-10-30T11:10:45', 'last-modified': '2025-10-30T11:10:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418563'}
2025-10-30 11:10:46,348 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:10:46,354 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1467834034099014995', 'uuid-lslong': '9808732330949765128'}, 'enable': 'true', 'created': '2025-10-30T11:10:45', 'last-modified': '2025-10-30T11:10:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.438492'}
2025-10-30 11:10:46,362 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:10:46,369 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1467834034099014995', 'uuid-lslong': '9808732330949765128'}, 'enable': 'true', 'created': '2025-10-30T11:10:45', 'last-modified': '2025-10-30T11:10:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.444392'}
2025-10-30 11:10:46,376 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:10:46,376 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-32842944 passed
2025-10-30 11:10:46,376 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-32842944 in API_Server ======
2025-10-30 11:10:46,376 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:46,383 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:46,397 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:46,510 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/145ecb46-efb3-4d53-881f-9e160b266808
2025-10-30 11:10:46,521 - DEBUG - =>VN ctest-mgmt_vn-32842944 has no policy to be verified
2025-10-30 11:10:46,521 - DEBUG - Verifying the vn in opserver
2025-10-30 11:10:46,521 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944 virtual network link through opserver 10.0.0.249
2025-10-30 11:10:46,521 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:10:46,537 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944?flat'}
2025-10-30 11:10:46,537 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944 virtual network link through opserver 10.0.0.33
2025-10-30 11:10:46,537 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:10:46,553 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944?flat'}
2025-10-30 11:10:46,554 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944 virtual network link through opserver 10.0.0.19
2025-10-30 11:10:46,554 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:10:46,568 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944?flat'}
2025-10-30 11:10:46,568 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43104062:ctest-mgmt_vn-32842944 is found in opserver
2025-10-30 11:10:46,590 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:10:46,604 - DEBUG - VRF ids for VN ctest-mgmt_vn-32842944: {}
2025-10-30 11:10:46,900 - DEBUG - Response for create_network : {'network': {'id': '3af23385-2986-4b68-b351-671839a9b7f4', 'name': 'ctest-left_vn-25568925', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'project_id': 'a549fdc5d0714d00857d923b8b798945', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43104062', 'ctest-left_vn-25568925'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:10:47,118 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eb3cddeb-d8d6-4ad9-b5a4-1e0ff30af26f', 'name': '', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'network_id': '3af23385-2986-4b68-b351-671839a9b7f4', 'ip_version': 4, 'cidr': '135.92.202.0/26', 'allocation_pools': [{'start': '135.92.202.2', 'end': '135.92.202.62'}], 'gateway_ip': '135.92.202.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.92.202.2', 'tags': [], 'project_id': 'a549fdc5d0714d00857d923b8b798945'}}
2025-10-30 11:10:47,144 - INFO - Created VN ctest-left_vn-25568925
2025-10-30 11:10:47,158 - DEBUG - VN ctest-left_vn-25568925 UUID is 3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,233 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:47,241 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:47,255 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:47,350 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,369 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,393 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/44409921-3054-4e28-92f8-24507ccbed25
2025-10-30 11:10:47,404 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/44409921-3054-4e28-92f8-24507ccbed25
2025-10-30 11:10:47,413 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/26b037d8-c1b0-4776-9975-6ec02249a870
2025-10-30 11:10:47,426 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:10:47,426 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,437 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/44409921-3054-4e28-92f8-24507ccbed25
2025-10-30 11:10:47,456 - INFO - Verified VN network id 16 for VN 3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,456 - INFO - Verifications in API Server for VN ctest-left_vn-25568925 passed
2025-10-30 11:10:47,456 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,468 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/44409921-3054-4e28-92f8-24507ccbed25
2025-10-30 11:10:47,478 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/44409921-3054-4e28-92f8-24507ccbed25
2025-10-30 11:10:47,487 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/26b037d8-c1b0-4776-9975-6ec02249a870
2025-10-30 11:10:47,500 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4247514045584132968', 'uuid-lslong': '12921222159646963700'}, 'enable': 'true', 'created': '2025-10-30T11:10:46', 'last-modified': '2025-10-30T11:10:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435522'}
2025-10-30 11:10:47,506 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:10:47,512 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4247514045584132968', 'uuid-lslong': '12921222159646963700'}, 'enable': 'true', 'created': '2025-10-30T11:10:46', 'last-modified': '2025-10-30T11:10:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443713'}
2025-10-30 11:10:47,518 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:10:47,524 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4247514045584132968', 'uuid-lslong': '12921222159646963700'}, 'enable': 'true', 'created': '2025-10-30T11:10:46', 'last-modified': '2025-10-30T11:10:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458251'}
2025-10-30 11:10:47,531 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:10:47,531 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-25568925 passed
2025-10-30 11:10:47,532 - DEBUG - ====Verifying policy data for ctest-left_vn-25568925 in API_Server ======
2025-10-30 11:10:47,532 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:47,539 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:47,552 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:47,647 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3af23385-2986-4b68-b351-671839a9b7f4
2025-10-30 11:10:47,664 - DEBUG - =>VN ctest-left_vn-25568925 has no policy to be verified
2025-10-30 11:10:47,664 - DEBUG - Verifying the vn in opserver
2025-10-30 11:10:47,664 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925 virtual network link through opserver 10.0.0.249
2025-10-30 11:10:47,664 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:10:47,680 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925?flat'}
2025-10-30 11:10:47,680 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925 virtual network link through opserver 10.0.0.33
2025-10-30 11:10:47,680 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:10:47,696 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925?flat'}
2025-10-30 11:10:47,696 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925 virtual network link through opserver 10.0.0.19
2025-10-30 11:10:47,696 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:10:47,715 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925?flat'}
2025-10-30 11:10:47,715 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43104062:ctest-left_vn-25568925 is found in opserver
2025-10-30 11:10:47,749 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:10:47,768 - DEBUG - VRF ids for VN ctest-left_vn-25568925: {}
2025-10-30 11:10:48,065 - DEBUG - Response for create_network : {'network': {'id': '0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4', 'name': 'ctest-right_vn-11952462', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'project_id': 'a549fdc5d0714d00857d923b8b798945', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43104062', 'ctest-right_vn-11952462'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:10:48,295 - DEBUG - Response for create_subnet : {'subnet': {'id': '7529b2cf-43c1-4589-893f-86af604e809d', 'name': '', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'network_id': '0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4', 'ip_version': 4, 'cidr': '120.121.89.0/26', 'allocation_pools': [{'start': '120.121.89.2', 'end': '120.121.89.62'}], 'gateway_ip': '120.121.89.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.121.89.2', 'tags': [], 'project_id': 'a549fdc5d0714d00857d923b8b798945'}}
2025-10-30 11:10:48,325 - INFO - Created VN ctest-right_vn-11952462
2025-10-30 11:10:48,337 - DEBUG - VN ctest-right_vn-11952462 UUID is 0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,416 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:48,424 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:48,436 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:48,537 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,549 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,560 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8f6bcf40-3215-4647-9809-433e9b050e9c
2025-10-30 11:10:48,570 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8f6bcf40-3215-4647-9809-433e9b050e9c
2025-10-30 11:10:48,578 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d3ed2503-3c6a-4d35-b961-4577801d2508
2025-10-30 11:10:48,586 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:10:48,587 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,597 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8f6bcf40-3215-4647-9809-433e9b050e9c
2025-10-30 11:10:48,617 - INFO - Verified VN network id 17 for VN 0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,617 - INFO - Verifications in API Server for VN ctest-right_vn-11952462 passed
2025-10-30 11:10:48,618 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,630 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8f6bcf40-3215-4647-9809-433e9b050e9c
2025-10-30 11:10:48,640 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8f6bcf40-3215-4647-9809-433e9b050e9c
2025-10-30 11:10:48,647 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d3ed2503-3c6a-4d35-b961-4577801d2508
2025-10-30 11:10:48,661 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '779679421484912630', 'uuid-lslong': '10927382467322297812'}, 'enable': 'true', 'created': '2025-10-30T11:10:48', 'last-modified': '2025-10-30T11:10:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429301'}
2025-10-30 11:10:48,670 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:10:48,677 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '779679421484912630', 'uuid-lslong': '10927382467322297812'}, 'enable': 'true', 'created': '2025-10-30T11:10:48', 'last-modified': '2025-10-30T11:10:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443274'}
2025-10-30 11:10:48,684 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:10:48,691 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '779679421484912630', 'uuid-lslong': '10927382467322297812'}, 'enable': 'true', 'created': '2025-10-30T11:10:48', 'last-modified': '2025-10-30T11:10:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.455581'}
2025-10-30 11:10:48,698 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:10:48,699 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-11952462 passed
2025-10-30 11:10:48,699 - DEBUG - ====Verifying policy data for ctest-right_vn-11952462 in API_Server ======
2025-10-30 11:10:48,699 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:48,705 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:48,719 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:48,822 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4
2025-10-30 11:10:48,838 - DEBUG - =>VN ctest-right_vn-11952462 has no policy to be verified
2025-10-30 11:10:48,838 - DEBUG - Verifying the vn in opserver
2025-10-30 11:10:48,838 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462 virtual network link through opserver 10.0.0.249
2025-10-30 11:10:48,838 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:10:48,853 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462?flat'}
2025-10-30 11:10:48,853 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462 virtual network link through opserver 10.0.0.33
2025-10-30 11:10:48,854 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:10:48,867 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462?flat'}
2025-10-30 11:10:48,867 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462 virtual network link through opserver 10.0.0.19
2025-10-30 11:10:48,867 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:10:48,882 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462?flat'}
2025-10-30 11:10:48,882 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43104062:ctest-right_vn-11952462 is found in opserver
2025-10-30 11:10:48,910 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:10:48,921 - DEBUG - VRF ids for VN ctest-right_vn-11952462: {}
2025-10-30 11:10:49,233 - DEBUG - Response for create_network : {'network': {'id': 'df0b4de4-c58d-4e48-a2aa-6f6071aa9357', 'name': 'ctest-trans_left_vn-40013336', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'project_id': 'a549fdc5d0714d00857d923b8b798945', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43104062', 'ctest-trans_left_vn-40013336'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:10:49,502 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd577b364-7a2e-41b7-aa17-ea34f4177e67', 'name': '', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'network_id': 'df0b4de4-c58d-4e48-a2aa-6f6071aa9357', 'ip_version': 4, 'cidr': '185.22.17.0/26', 'allocation_pools': [{'start': '185.22.17.2', 'end': '185.22.17.62'}], 'gateway_ip': '185.22.17.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '185.22.17.2', 'tags': [], 'project_id': 'a549fdc5d0714d00857d923b8b798945'}}
2025-10-30 11:10:49,526 - INFO - Created VN ctest-trans_left_vn-40013336
2025-10-30 11:10:49,538 - DEBUG - VN ctest-trans_left_vn-40013336 UUID is df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:49,622 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:49,629 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:49,642 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:49,754 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:49,767 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:49,778 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2e864f77-63fb-4817-a96d-5a26706e006f
2025-10-30 11:10:49,789 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2e864f77-63fb-4817-a96d-5a26706e006f
2025-10-30 11:10:49,798 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/94dd6f24-7dfe-4158-8067-97d2aaa617d3
2025-10-30 11:10:49,807 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 11:10:49,807 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:49,817 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2e864f77-63fb-4817-a96d-5a26706e006f
2025-10-30 11:10:49,836 - INFO - Verified VN network id 18 for VN df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:49,836 - INFO - Verifications in API Server for VN ctest-trans_left_vn-40013336 passed
2025-10-30 11:10:49,836 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:49,847 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2e864f77-63fb-4817-a96d-5a26706e006f
2025-10-30 11:10:49,855 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2e864f77-63fb-4817-a96d-5a26706e006f
2025-10-30 11:10:49,863 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/94dd6f24-7dfe-4158-8067-97d2aaa617d3
2025-10-30 11:10:49,877 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16072025340164001352', 'uuid-lslong': '11721303440199684951'}, 'enable': 'true', 'created': '2025-10-30T11:10:49', 'last-modified': '2025-10-30T11:10:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446216'}
2025-10-30 11:10:49,886 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 11:10:49,892 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16072025340164001352', 'uuid-lslong': '11721303440199684951'}, 'enable': 'true', 'created': '2025-10-30T11:10:49', 'last-modified': '2025-10-30T11:10:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.469463'}
2025-10-30 11:10:49,901 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 11:10:49,909 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16072025340164001352', 'uuid-lslong': '11721303440199684951'}, 'enable': 'true', 'created': '2025-10-30T11:10:49', 'last-modified': '2025-10-30T11:10:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.474196'}
2025-10-30 11:10:49,917 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 11:10:49,917 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-40013336 passed
2025-10-30 11:10:49,917 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-40013336 in API_Server ======
2025-10-30 11:10:49,917 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:49,925 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:49,938 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:50,034 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df0b4de4-c58d-4e48-a2aa-6f6071aa9357
2025-10-30 11:10:50,046 - DEBUG - =>VN ctest-trans_left_vn-40013336 has no policy to be verified
2025-10-30 11:10:50,046 - DEBUG - Verifying the vn in opserver
2025-10-30 11:10:50,046 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336 virtual network link through opserver 10.0.0.249
2025-10-30 11:10:50,046 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:10:50,061 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336?flat'}
2025-10-30 11:10:50,061 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336 virtual network link through opserver 10.0.0.33
2025-10-30 11:10:50,061 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:10:50,075 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336?flat'}
2025-10-30 11:10:50,075 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336 virtual network link through opserver 10.0.0.19
2025-10-30 11:10:50,075 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:10:50,090 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336?flat'}
2025-10-30 11:10:50,090 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43104062:ctest-trans_left_vn-40013336 is found in opserver
2025-10-30 11:10:50,117 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:10:50,131 - DEBUG - VRF ids for VN ctest-trans_left_vn-40013336: {}
2025-10-30 11:10:50,459 - DEBUG - Response for create_network : {'network': {'id': '39e22cee-3471-4583-bc2b-784a74a97ac6', 'name': 'ctest-trans_right_vn-95094111', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'project_id': 'a549fdc5d0714d00857d923b8b798945', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43104062', 'ctest-trans_right_vn-95094111'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:10:50,762 - DEBUG - Response for create_subnet : {'subnet': {'id': '251b414b-b576-4e1e-a755-d842f4107a82', 'name': '', 'tenant_id': 'a549fdc5d0714d00857d923b8b798945', 'network_id': '39e22cee-3471-4583-bc2b-784a74a97ac6', 'ip_version': 4, 'cidr': '150.10.44.64/26', 'allocation_pools': [{'start': '150.10.44.66', 'end': '150.10.44.126'}], 'gateway_ip': '150.10.44.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '150.10.44.66', 'tags': [], 'project_id': 'a549fdc5d0714d00857d923b8b798945'}}
2025-10-30 11:10:50,786 - INFO - Created VN ctest-trans_right_vn-95094111
2025-10-30 11:10:50,801 - DEBUG - VN ctest-trans_right_vn-95094111 UUID is 39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:50,877 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:50,883 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:50,897 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:51,003 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:51,016 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:51,025 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/145bc982-cb52-40eb-bf25-d5a06d0f4229
2025-10-30 11:10:51,033 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/145bc982-cb52-40eb-bf25-d5a06d0f4229
2025-10-30 11:10:51,041 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3be82062-58c0-4ff6-924b-46e4d101ccf9
2025-10-30 11:10:51,048 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 11:10:51,048 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:51,058 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/145bc982-cb52-40eb-bf25-d5a06d0f4229
2025-10-30 11:10:51,148 - INFO - Verified VN network id 19 for VN 39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:51,148 - INFO - Verifications in API Server for VN ctest-trans_right_vn-95094111 passed
2025-10-30 11:10:51,148 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:51,166 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/145bc982-cb52-40eb-bf25-d5a06d0f4229
2025-10-30 11:10:51,176 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/145bc982-cb52-40eb-bf25-d5a06d0f4229
2025-10-30 11:10:51,184 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3be82062-58c0-4ff6-924b-46e4d101ccf9
2025-10-30 11:10:51,199 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4170945606492177795', 'uuid-lslong': '13559063364309187270'}, 'enable': 'true', 'created': '2025-10-30T11:10:50', 'last-modified': '2025-10-30T11:10:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.511209'}
2025-10-30 11:10:51,207 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 11:10:51,214 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4170945606492177795', 'uuid-lslong': '13559063364309187270'}, 'enable': 'true', 'created': '2025-10-30T11:10:50', 'last-modified': '2025-10-30T11:10:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517294'}
2025-10-30 11:10:51,222 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 11:10:51,228 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43104062', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4170945606492177795', 'uuid-lslong': '13559063364309187270'}, 'enable': 'true', 'created': '2025-10-30T11:10:50', 'last-modified': '2025-10-30T11:10:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.537679'}
2025-10-30 11:10:51,235 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-30 11:10:51,236 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-95094111 passed
2025-10-30 11:10:51,236 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-95094111 in API_Server ======
2025-10-30 11:10:51,236 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:51,243 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:51,258 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:51,352 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39e22cee-3471-4583-bc2b-784a74a97ac6
2025-10-30 11:10:51,366 - DEBUG - =>VN ctest-trans_right_vn-95094111 has no policy to be verified
2025-10-30 11:10:51,367 - DEBUG - Verifying the vn in opserver
2025-10-30 11:10:51,367 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111 virtual network link through opserver 10.0.0.249
2025-10-30 11:10:51,367 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:10:51,381 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111?flat'}
2025-10-30 11:10:51,381 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111 virtual network link through opserver 10.0.0.33
2025-10-30 11:10:51,381 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:10:51,397 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111?flat'}
2025-10-30 11:10:51,397 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111 virtual network link through opserver 10.0.0.19
2025-10-30 11:10:51,398 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:10:51,413 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111?flat'}
2025-10-30 11:10:51,413 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43104062:ctest-trans_right_vn-95094111 is found in opserver
2025-10-30 11:10:51,438 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:10:51,451 - DEBUG - VRF ids for VN ctest-trans_right_vn-95094111: {}
2025-10-30 11:10:52,595 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-1)
2025-10-30 11:10:54,089 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-2)
2025-10-30 11:10:54,089 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-10493146']
2025-10-30 11:10:54,412 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-10493146'] created succesfully
2025-10-30 11:10:54,742 - DEBUG - Image by name tiny_trans_fw not found
2025-10-30 11:10:54,742 - DEBUG - Installing image tiny_trans_fw
2025-10-30 11:10:54,742 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-10-30 11:10:54,742 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-30 11:10:54,742 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-30 11:10:54,780 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 11:10:54,857 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 11:10:54,857 - DEBUG - Unzip image
2025-10-30 11:10:54,857 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 11:10:54,880 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-30 11:10:54,880 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-10493146']
2025-10-30 11:10:54,985 - INFO - Service template: ['default-domain', 'ctest-service_template_1-10493146'] deleted successfully.
2025-10-30 11:10:54,985 - INFO - Skip interface_detach for VM ctest-right_vm-10995089 in state BUILD
2025-10-30 11:10:54,985 - INFO - Deleting VM ctest-right_vm-10995089
2025-10-30 11:10:54,986 - ERROR - VM ctest-right_vm-10995089 has failed to come up
2025-10-30 11:10:54,986 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:54,986 - ERROR - VM ctest-right_vm-10995089 has failed to come up
2025-10-30 11:10:54,986 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:55,171 - INFO - Skip interface_detach for VM ctest-left_vm-56079256 in state BUILD
2025-10-30 11:10:55,171 - INFO - Deleting VM ctest-left_vm-56079256
2025-10-30 11:10:55,172 - ERROR - VM ctest-left_vm-56079256 has failed to come up
2025-10-30 11:10:55,172 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:55,172 - ERROR - VM ctest-left_vm-56079256 has failed to come up
2025-10-30 11:10:55,172 - ERROR - Nova failed to get host of the VM
2025-10-30 11:10:55,287 - INFO - Deleting VN ctest-trans_right_vn-95094111
2025-10-30 11:10:55,440 - DEBUG - Response for deleting network ()
2025-10-30 11:10:55,441 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/145bc982-cb52-40eb-bf25-d5a06d0f4229
2025-10-30 11:10:55,448 - DEBUG - Response Code: 404
2025-10-30 11:10:55,449 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:55,456 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:55,479 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:55,606 - INFO - Validated that VN ctest-trans_right_vn-95094111 is not found in API Server
2025-10-30 11:10:55,623 - DEBUG - VN ctest-trans_right_vn-95094111 is not present in Agent 10.0.0.41
2025-10-30 11:10:55,648 - DEBUG - VN ctest-trans_right_vn-95094111 is not present in Agent 10.0.0.27
2025-10-30 11:10:55,648 - INFO - Validated that VN ctest-trans_right_vn-95094111 is not in any agent
2025-10-30 11:10:55,691 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-95094111 info
2025-10-30 11:10:55,691 - INFO - Deleting VN ctest-trans_left_vn-40013336
2025-10-30 11:10:55,858 - DEBUG - Response for deleting network ()
2025-10-30 11:10:55,859 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2e864f77-63fb-4817-a96d-5a26706e006f
2025-10-30 11:10:55,866 - DEBUG - Response Code: 404
2025-10-30 11:10:55,867 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:55,887 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:55,903 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:56,026 - INFO - Validated that VN ctest-trans_left_vn-40013336 is not found in API Server
2025-10-30 11:10:56,050 - DEBUG - VN ctest-trans_left_vn-40013336 is not present in Agent 10.0.0.41
2025-10-30 11:10:56,071 - DEBUG - VN ctest-trans_left_vn-40013336 is not present in Agent 10.0.0.27
2025-10-30 11:10:56,071 - INFO - Validated that VN ctest-trans_left_vn-40013336 is not in any agent
2025-10-30 11:10:56,135 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-40013336 info
2025-10-30 11:10:56,136 - INFO - Deleting VN ctest-right_vn-11952462
2025-10-30 11:10:56,205 - DEBUG - VN 0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4 still in use: Unable to complete operation on network 0ad1fa4d-8c4a-4ff6-97a5-dc755193bdd4. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ef955a53-34e5-47f3-8d39-4248bfc59f84']
2025-10-30 11:10:56,205 - WARNING - Deleting VN ctest-right_vn-11952462 failed..Will retry
2025-10-30 11:10:58,417 - DEBUG - Response for deleting network ()
2025-10-30 11:10:58,417 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/8f6bcf40-3215-4647-9809-433e9b050e9c
2025-10-30 11:10:58,425 - DEBUG - Response Code: 404
2025-10-30 11:10:58,425 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:58,444 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:58,462 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:58,575 - INFO - Validated that VN ctest-right_vn-11952462 is not found in API Server
2025-10-30 11:10:58,590 - DEBUG - VN ctest-right_vn-11952462 is not present in Agent 10.0.0.41
2025-10-30 11:10:58,601 - DEBUG - VN ctest-right_vn-11952462 is not present in Agent 10.0.0.27
2025-10-30 11:10:58,601 - INFO - Validated that VN ctest-right_vn-11952462 is not in any agent
2025-10-30 11:10:58,643 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-11952462 info
2025-10-30 11:10:58,644 - INFO - Deleting VN ctest-left_vn-25568925
2025-10-30 11:10:58,819 - DEBUG - Response for deleting network ()
2025-10-30 11:10:58,819 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/44409921-3054-4e28-92f8-24507ccbed25
2025-10-30 11:10:58,826 - DEBUG - Response Code: 404
2025-10-30 11:10:58,826 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:58,838 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:58,854 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:58,952 - INFO - Validated that VN ctest-left_vn-25568925 is not found in API Server
2025-10-30 11:10:58,967 - DEBUG - VN ctest-left_vn-25568925 is not present in Agent 10.0.0.41
2025-10-30 11:10:58,983 - DEBUG - VN ctest-left_vn-25568925 is not present in Agent 10.0.0.27
2025-10-30 11:10:58,983 - INFO - Validated that VN ctest-left_vn-25568925 is not in any agent
2025-10-30 11:10:59,018 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-25568925 info
2025-10-30 11:10:59,018 - INFO - Deleting VN ctest-mgmt_vn-32842944
2025-10-30 11:10:59,188 - DEBUG - Response for deleting network ()
2025-10-30 11:10:59,188 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/fa8f0eff-ecec-4ff0-a901-fbdfd6ae4c34
2025-10-30 11:10:59,203 - DEBUG - Response Code: 404
2025-10-30 11:10:59,203 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:10:59,210 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:10:59,226 - DEBUG - Requesting: http://10.0.0.249:8082/project/a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:10:59,320 - INFO - Validated that VN ctest-mgmt_vn-32842944 is not found in API Server
2025-10-30 11:10:59,334 - DEBUG - VN ctest-mgmt_vn-32842944 is not present in Agent 10.0.0.41
2025-10-30 11:10:59,346 - DEBUG - VN ctest-mgmt_vn-32842944 is not present in Agent 10.0.0.27
2025-10-30 11:10:59,346 - INFO - Validated that VN ctest-mgmt_vn-32842944 is not in any agent
2025-10-30 11:10:59,382 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-32842944 info
2025-10-30 11:11:00,687 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 11:11:00,687 - INFO -
2025-10-30 11:11:00,687 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-10-30 11:11:00,687 - INFO - --------------------------------------------------------------------------------
2025-10-30 11:11:01,442 - INFO - Deleted project: ctest-TestSvcRegr-43104062, ID : a549fdc5-d071-4d00-857d-923b8b798945
2025-10-30 11:11:01,638 - INFO - Domain Default found not creating
2025-10-30 11:11:01,850 - INFO - Project ctest-TestSvcRegr-54913158 not found, creating it
2025-10-30 11:11:02,513 - INFO - Created Project:ctest-TestSvcRegr-54913158, ID : 45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:04,332 - INFO - ================================================================================
2025-10-30 11:11:04,332 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-30 11:11:05,621 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.131': '0', '10.20.0.128': '0'}, '10.0.0.27': {'10.20.0.145': '0', '10.20.0.128': '0'}} with
2025-10-30 11:11:05,621 - INFO - Initial checks done. Running the testcase now
2025-10-30 11:11:05,621 - INFO -
2025-10-30 11:11:06,327 - DEBUG - Response for create_network : {'network': {'id': '39b0b6c9-88f8-49ff-988e-fb1e3de24b44', 'name': 'ctest-mgmt_vn-40630157', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54913158', 'ctest-mgmt_vn-40630157'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:11:06,652 - DEBUG - Response for create_subnet : {'subnet': {'id': '16181512-aef2-45c3-a7f9-34f747ee81fd', 'name': '', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'network_id': '39b0b6c9-88f8-49ff-988e-fb1e3de24b44', 'ip_version': 4, 'cidr': '203.149.255.128/26', 'allocation_pools': [{'start': '203.149.255.130', 'end': '203.149.255.190'}], 'gateway_ip': '203.149.255.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '203.149.255.130', 'tags': [], 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1'}}
2025-10-30 11:11:06,678 - INFO - Created VN ctest-mgmt_vn-40630157
2025-10-30 11:11:06,691 - DEBUG - VN ctest-mgmt_vn-40630157 UUID is 39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:06,781 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:07,092 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:07,128 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:07,149 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:07,250 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:07,265 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:07,278 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ba735328-3eab-42e3-9309-f6cc61eeaa04
2025-10-30 11:11:07,290 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ba735328-3eab-42e3-9309-f6cc61eeaa04
2025-10-30 11:11:07,298 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/75e17c1c-c05e-4e3d-9a7e-b0136f278470
2025-10-30 11:11:07,306 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:07,306 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:07,316 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ba735328-3eab-42e3-9309-f6cc61eeaa04
2025-10-30 11:11:07,336 - INFO - Verified VN network id 15 for VN 39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:07,336 - INFO - Verifications in API Server for VN ctest-mgmt_vn-40630157 passed
2025-10-30 11:11:07,336 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:07,347 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ba735328-3eab-42e3-9309-f6cc61eeaa04
2025-10-30 11:11:07,357 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ba735328-3eab-42e3-9309-f6cc61eeaa04
2025-10-30 11:11:07,366 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/75e17c1c-c05e-4e3d-9a7e-b0136f278470
2025-10-30 11:11:07,387 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157023432765622783', 'uuid-lslong': '10992999847763790660'}, 'enable': 'true', 'created': '2025-10-30T11:11:06', 'last-modified': '2025-10-30T11:11:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.795425'}
2025-10-30 11:11:07,393 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:07,399 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157023432765622783', 'uuid-lslong': '10992999847763790660'}, 'enable': 'true', 'created': '2025-10-30T11:11:06', 'last-modified': '2025-10-30T11:11:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.808329'}
2025-10-30 11:11:07,405 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:07,411 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157023432765622783', 'uuid-lslong': '10992999847763790660'}, 'enable': 'true', 'created': '2025-10-30T11:11:06', 'last-modified': '2025-10-30T11:11:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.816509'}
2025-10-30 11:11:07,419 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:07,419 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-40630157 passed
2025-10-30 11:11:07,420 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-40630157 in API_Server ======
2025-10-30 11:11:07,420 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:07,430 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:07,450 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:07,555 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/39b0b6c9-88f8-49ff-988e-fb1e3de24b44
2025-10-30 11:11:07,568 - DEBUG - =>VN ctest-mgmt_vn-40630157 has no policy to be verified
2025-10-30 11:11:07,568 - DEBUG - Verifying the vn in opserver
2025-10-30 11:11:07,568 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157 virtual network link through opserver 10.0.0.249
2025-10-30 11:11:07,568 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:07,877 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:07,944 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157?flat'}
2025-10-30 11:11:07,944 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157 virtual network link through opserver 10.0.0.33
2025-10-30 11:11:07,944 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:08,256 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:08,326 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157?flat'}
2025-10-30 11:11:08,326 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157 virtual network link through opserver 10.0.0.19
2025-10-30 11:11:08,327 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:08,639 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:08,703 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157?flat'}
2025-10-30 11:11:08,703 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-40630157 is found in opserver
2025-10-30 11:11:08,729 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:11:08,740 - DEBUG - VRF ids for VN ctest-mgmt_vn-40630157: {}
2025-10-30 11:11:09,025 - DEBUG - Response for create_network : {'network': {'id': 'e86b78c6-a18a-4c32-b99b-f0f0dd10c77f', 'name': 'service-left-vn-58929081', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54913158', 'service-left-vn-58929081'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:11:09,290 - DEBUG - Response for create_subnet : {'subnet': {'id': '0f44b792-b177-4765-ad93-85ff592a8a95', 'name': '', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'network_id': 'e86b78c6-a18a-4c32-b99b-f0f0dd10c77f', 'ip_version': 4, 'cidr': '103.88.209.192/26', 'allocation_pools': [{'start': '103.88.209.194', 'end': '103.88.209.254'}], 'gateway_ip': '103.88.209.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '103.88.209.194', 'tags': [], 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1'}}
2025-10-30 11:11:09,315 - INFO - Created VN service-left-vn-58929081
2025-10-30 11:11:09,327 - DEBUG - VN service-left-vn-58929081 UUID is e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:09,412 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:09,420 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:09,435 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:09,550 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:09,566 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:09,599 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/256be16b-5629-4bc9-bda4-5cc48ae9522f
2025-10-30 11:11:09,624 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/256be16b-5629-4bc9-bda4-5cc48ae9522f
2025-10-30 11:11:09,638 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/1efe26b7-9460-4f27-a87e-02e595245cb6
2025-10-30 11:11:09,648 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:09,648 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:09,672 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/256be16b-5629-4bc9-bda4-5cc48ae9522f
2025-10-30 11:11:09,690 - INFO - Verified VN network id 16 for VN e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:09,690 - INFO - Verifications in API Server for VN service-left-vn-58929081 passed
2025-10-30 11:11:09,690 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:09,737 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/256be16b-5629-4bc9-bda4-5cc48ae9522f
2025-10-30 11:11:09,749 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/256be16b-5629-4bc9-bda4-5cc48ae9522f
2025-10-30 11:11:09,760 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/1efe26b7-9460-4f27-a87e-02e595245cb6
2025-10-30 11:11:09,786 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16747612433816374322', 'uuid-lslong': '13374548435698501503'}, 'enable': 'true', 'created': '2025-10-30T11:11:08', 'last-modified': '2025-10-30T11:11:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.557325'}
2025-10-30 11:11:09,795 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:09,809 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16747612433816374322', 'uuid-lslong': '13374548435698501503'}, 'enable': 'true', 'created': '2025-10-30T11:11:08', 'last-modified': '2025-10-30T11:11:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.575954'}
2025-10-30 11:11:09,835 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:09,850 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16747612433816374322', 'uuid-lslong': '13374548435698501503'}, 'enable': 'true', 'created': '2025-10-30T11:11:08', 'last-modified': '2025-10-30T11:11:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.617582'}
2025-10-30 11:11:09,878 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:09,878 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-58929081 passed
2025-10-30 11:11:09,879 - DEBUG - ====Verifying policy data for service-left-vn-58929081 in API_Server ======
2025-10-30 11:11:09,879 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:09,892 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:09,906 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:10,007 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e86b78c6-a18a-4c32-b99b-f0f0dd10c77f
2025-10-30 11:11:10,022 - DEBUG - =>VN service-left-vn-58929081 has no policy to be verified
2025-10-30 11:11:10,022 - DEBUG - Verifying the vn in opserver
2025-10-30 11:11:10,022 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081 virtual network link through opserver 10.0.0.249
2025-10-30 11:11:10,022 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:10,087 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081?flat'}
2025-10-30 11:11:10,087 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081 virtual network link through opserver 10.0.0.33
2025-10-30 11:11:10,087 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:10,151 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081?flat'}
2025-10-30 11:11:10,151 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081 virtual network link through opserver 10.0.0.19
2025-10-30 11:11:10,151 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:10,218 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081?flat'}
2025-10-30 11:11:10,218 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54913158:service-left-vn-58929081 is found in opserver
2025-10-30 11:11:10,242 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:11:10,255 - DEBUG - VRF ids for VN service-left-vn-58929081: {}
2025-10-30 11:11:10,597 - DEBUG - Response for create_network : {'network': {'id': '50fcc9e8-df90-4276-912c-bab3e37b6a03', 'name': 'service-right-vn-37033564', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54913158', 'service-right-vn-37033564'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:11:10,847 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd84ee753-5a40-4ff2-9f36-e86074dbc8d7', 'name': '', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'network_id': '50fcc9e8-df90-4276-912c-bab3e37b6a03', 'ip_version': 4, 'cidr': '20.169.96.192/26', 'allocation_pools': [{'start': '20.169.96.194', 'end': '20.169.96.254'}], 'gateway_ip': '20.169.96.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.169.96.194', 'tags': [], 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1'}}
2025-10-30 11:11:10,872 - INFO - Created VN service-right-vn-37033564
2025-10-30 11:11:10,890 - DEBUG - VN service-right-vn-37033564 UUID is 50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:10,977 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:10,988 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:11,013 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:11,124 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:11,140 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:11,153 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bdff5222-3f9e-468d-bde2-7d3ad77211fd
2025-10-30 11:11:11,166 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bdff5222-3f9e-468d-bde2-7d3ad77211fd
2025-10-30 11:11:11,175 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/dc90a81c-94aa-467b-9cc8-19d6f120340a
2025-10-30 11:11:11,184 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:11,184 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:11,196 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bdff5222-3f9e-468d-bde2-7d3ad77211fd
2025-10-30 11:11:11,219 - INFO - Verified VN network id 17 for VN 50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:11,219 - INFO - Verifications in API Server for VN service-right-vn-37033564 passed
2025-10-30 11:11:11,219 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:11,232 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bdff5222-3f9e-468d-bde2-7d3ad77211fd
2025-10-30 11:11:11,242 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bdff5222-3f9e-468d-bde2-7d3ad77211fd
2025-10-30 11:11:11,251 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/dc90a81c-94aa-467b-9cc8-19d6f120340a
2025-10-30 11:11:11,272 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5835761219185689206', 'uuid-lslong': '10460941316253248003'}, 'enable': 'true', 'created': '2025-10-30T11:11:10', 'last-modified': '2025-10-30T11:11:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481628'}
2025-10-30 11:11:11,281 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:11,288 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5835761219185689206', 'uuid-lslong': '10460941316253248003'}, 'enable': 'true', 'created': '2025-10-30T11:11:10', 'last-modified': '2025-10-30T11:11:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.503800'}
2025-10-30 11:11:11,296 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:11,303 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5835761219185689206', 'uuid-lslong': '10460941316253248003'}, 'enable': 'true', 'created': '2025-10-30T11:11:10', 'last-modified': '2025-10-30T11:11:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.511787'}
2025-10-30 11:11:11,313 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:11,313 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-37033564 passed
2025-10-30 11:11:11,314 - DEBUG - ====Verifying policy data for service-right-vn-37033564 in API_Server ======
2025-10-30 11:11:11,314 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:11,323 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:11,337 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:11,442 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/50fcc9e8-df90-4276-912c-bab3e37b6a03
2025-10-30 11:11:11,454 - DEBUG - =>VN service-right-vn-37033564 has no policy to be verified
2025-10-30 11:11:11,454 - DEBUG - Verifying the vn in opserver
2025-10-30 11:11:11,454 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564 virtual network link through opserver 10.0.0.249
2025-10-30 11:11:11,455 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:11,521 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564?flat'}
2025-10-30 11:11:11,521 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564 virtual network link through opserver 10.0.0.33
2025-10-30 11:11:11,521 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:11,594 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564?flat'}
2025-10-30 11:11:11,594 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564 virtual network link through opserver 10.0.0.19
2025-10-30 11:11:11,595 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:11,665 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564?flat'}
2025-10-30 11:11:11,665 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54913158:service-right-vn-37033564 is found in opserver
2025-10-30 11:11:11,688 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:11:11,700 - DEBUG - VRF ids for VN service-right-vn-37033564: {}
2025-10-30 11:11:11,767 - DEBUG - Services list from nova: [, , , ]
2025-10-30 11:11:12,925 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-1)
2025-10-30 11:11:14,430 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-2)
2025-10-30 11:11:14,431 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-01156338']
2025-10-30 11:11:14,749 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-01156338'] created succesfully
2025-10-30 11:11:15,145 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 11:11:15,145 - DEBUG - Installing image tiny_nat_fw
2025-10-30 11:11:15,145 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-30 11:11:15,146 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:11:15,146 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:11:15,146 - DEBUG - Unzip image
2025-10-30 11:11:15,146 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:11:15,169 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 11:11:15,171 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-01156338']
2025-10-30 11:11:15,353 - INFO - Service template: ['default-domain', 'ctest-service_template_1-01156338'] deleted successfully.
2025-10-30 11:11:15,353 - INFO - Skip interface_detach for VM ctest-right_vm-64872861 in state BUILD
2025-10-30 11:11:15,354 - INFO - Deleting VM ctest-right_vm-64872861
2025-10-30 11:11:15,388 - ERROR - VM ctest-right_vm-64872861 has failed to come up
2025-10-30 11:11:15,388 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:15,388 - ERROR - VM ctest-right_vm-64872861 has failed to come up
2025-10-30 11:11:15,388 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:15,505 - INFO - Skip interface_detach for VM ctest-left_vm-61689233 in state BUILD
2025-10-30 11:11:15,505 - INFO - Deleting VM ctest-left_vm-61689233
2025-10-30 11:11:15,505 - ERROR - VM ctest-left_vm-61689233 has failed to come up
2025-10-30 11:11:15,506 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:15,506 - ERROR - VM ctest-left_vm-61689233 has failed to come up
2025-10-30 11:11:15,506 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:15,623 - INFO - Deleting VN service-right-vn-37033564
2025-10-30 11:11:15,686 - DEBUG - VN 50fcc9e8-df90-4276-912c-bab3e37b6a03 still in use: Unable to complete operation on network 50fcc9e8-df90-4276-912c-bab3e37b6a03. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a1bddd6d-e7ef-4f3d-a403-06ff0392b3ee']
2025-10-30 11:11:15,686 - WARNING - Deleting VN service-right-vn-37033564 failed..Will retry
2025-10-30 11:11:17,868 - DEBUG - Response for deleting network ()
2025-10-30 11:11:17,869 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/bdff5222-3f9e-468d-bde2-7d3ad77211fd
2025-10-30 11:11:17,875 - DEBUG - Response Code: 404
2025-10-30 11:11:17,876 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:17,884 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:17,914 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:18,016 - INFO - Validated that VN service-right-vn-37033564 is not found in API Server
2025-10-30 11:11:18,035 - DEBUG - VN service-right-vn-37033564 is not present in Agent 10.0.0.41
2025-10-30 11:11:18,049 - DEBUG - VN service-right-vn-37033564 is not present in Agent 10.0.0.27
2025-10-30 11:11:18,050 - INFO - Validated that VN service-right-vn-37033564 is not in any agent
2025-10-30 11:11:18,089 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-37033564 info
2025-10-30 11:11:18,089 - INFO - Deleting VN service-left-vn-58929081
2025-10-30 11:11:18,274 - DEBUG - Response for deleting network ()
2025-10-30 11:11:18,274 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/256be16b-5629-4bc9-bda4-5cc48ae9522f
2025-10-30 11:11:18,289 - DEBUG - Response Code: 404
2025-10-30 11:11:18,289 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:18,299 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:18,334 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:18,440 - INFO - Validated that VN service-left-vn-58929081 is not found in API Server
2025-10-30 11:11:18,452 - DEBUG - VN service-left-vn-58929081 is not present in Agent 10.0.0.41
2025-10-30 11:11:18,472 - DEBUG - VN service-left-vn-58929081 is not present in Agent 10.0.0.27
2025-10-30 11:11:18,472 - INFO - Validated that VN service-left-vn-58929081 is not in any agent
2025-10-30 11:11:18,509 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-58929081 info
2025-10-30 11:11:18,509 - INFO - Deleting VN ctest-mgmt_vn-40630157
2025-10-30 11:11:18,665 - DEBUG - Response for deleting network ()
2025-10-30 11:11:18,665 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ba735328-3eab-42e3-9309-f6cc61eeaa04
2025-10-30 11:11:18,671 - DEBUG - Response Code: 404
2025-10-30 11:11:18,671 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:18,688 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:18,742 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:18,837 - INFO - Validated that VN ctest-mgmt_vn-40630157 is not found in API Server
2025-10-30 11:11:18,852 - DEBUG - VN ctest-mgmt_vn-40630157 is not present in Agent 10.0.0.41
2025-10-30 11:11:18,864 - DEBUG - VN ctest-mgmt_vn-40630157 is not present in Agent 10.0.0.27
2025-10-30 11:11:18,864 - INFO - Validated that VN ctest-mgmt_vn-40630157 is not in any agent
2025-10-30 11:11:18,899 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-40630157 info
2025-10-30 11:11:20,198 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 11:11:20,198 - INFO -
2025-10-30 11:11:20,198 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-10-30 11:11:20,198 - INFO - --------------------------------------------------------------------------------
2025-10-30 11:11:20,201 - INFO - ================================================================================
2025-10-30 11:11:20,201 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-30 11:11:21,484 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.131': '0', '10.20.0.128': '0'}, '10.0.0.27': {'10.20.0.145': '0', '10.20.0.128': '0'}} with
2025-10-30 11:11:21,484 - INFO - Initial checks done. Running the testcase now
2025-10-30 11:11:21,484 - INFO -
2025-10-30 11:11:21,824 - DEBUG - Response for create_network : {'network': {'id': 'd3e9fda3-064c-4f34-9331-bc8f0b67ac90', 'name': 'ctest-mgmt_vn-95652412', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54913158', 'ctest-mgmt_vn-95652412'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:11:22,158 - DEBUG - Response for create_subnet : {'subnet': {'id': '1f0f197e-7e21-485e-9da2-2e357f6ec25f', 'name': '', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'network_id': 'd3e9fda3-064c-4f34-9331-bc8f0b67ac90', 'ip_version': 4, 'cidr': '56.149.122.0/26', 'allocation_pools': [{'start': '56.149.122.2', 'end': '56.149.122.62'}], 'gateway_ip': '56.149.122.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.149.122.2', 'tags': [], 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1'}}
2025-10-30 11:11:22,189 - INFO - Created VN ctest-mgmt_vn-95652412
2025-10-30 11:11:22,206 - DEBUG - VN ctest-mgmt_vn-95652412 UUID is d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,306 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:22,313 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:22,327 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:22,450 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,465 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,476 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/546aec05-025a-47b0-8b17-ff4d6d27fc0f
2025-10-30 11:11:22,488 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/546aec05-025a-47b0-8b17-ff4d6d27fc0f
2025-10-30 11:11:22,501 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/9e1f8784-f5c6-4f08-ba61-88df8ebe39f1
2025-10-30 11:11:22,522 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:22,522 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,539 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/546aec05-025a-47b0-8b17-ff4d6d27fc0f
2025-10-30 11:11:22,561 - INFO - Verified VN network id 15 for VN d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,561 - INFO - Verifications in API Server for VN ctest-mgmt_vn-95652412 passed
2025-10-30 11:11:22,561 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,574 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/546aec05-025a-47b0-8b17-ff4d6d27fc0f
2025-10-30 11:11:22,586 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/546aec05-025a-47b0-8b17-ff4d6d27fc0f
2025-10-30 11:11:22,593 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/9e1f8784-f5c6-4f08-ba61-88df8ebe39f1
2025-10-30 11:11:22,606 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15270014888203538228', 'uuid-lslong': '10606465919991917712'}, 'enable': 'true', 'created': '2025-10-30T11:11:21', 'last-modified': '2025-10-30T11:11:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.552894'}
2025-10-30 11:11:22,615 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:22,622 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15270014888203538228', 'uuid-lslong': '10606465919991917712'}, 'enable': 'true', 'created': '2025-10-30T11:11:21', 'last-modified': '2025-10-30T11:11:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.552285'}
2025-10-30 11:11:22,629 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:22,639 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15270014888203538228', 'uuid-lslong': '10606465919991917712'}, 'enable': 'true', 'created': '2025-10-30T11:11:21', 'last-modified': '2025-10-30T11:11:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.570066'}
2025-10-30 11:11:22,649 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 11:11:22,649 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-95652412 passed
2025-10-30 11:11:22,650 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-95652412 in API_Server ======
2025-10-30 11:11:22,650 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:22,657 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:22,676 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:22,783 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d3e9fda3-064c-4f34-9331-bc8f0b67ac90
2025-10-30 11:11:22,792 - DEBUG - =>VN ctest-mgmt_vn-95652412 has no policy to be verified
2025-10-30 11:11:22,793 - DEBUG - Verifying the vn in opserver
2025-10-30 11:11:22,793 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412 virtual network link through opserver 10.0.0.249
2025-10-30 11:11:22,793 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:22,808 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412?flat'}
2025-10-30 11:11:22,808 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412 virtual network link through opserver 10.0.0.33
2025-10-30 11:11:22,808 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:22,822 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412?flat'}
2025-10-30 11:11:22,822 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412 virtual network link through opserver 10.0.0.19
2025-10-30 11:11:22,822 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:22,836 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412?flat'}
2025-10-30 11:11:22,836 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54913158:ctest-mgmt_vn-95652412 is found in opserver
2025-10-30 11:11:22,857 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:11:22,869 - DEBUG - VRF ids for VN ctest-mgmt_vn-95652412: {}
2025-10-30 11:11:23,208 - DEBUG - Response for create_network : {'network': {'id': '126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2', 'name': 'ctest-left_vn-78391727', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54913158', 'ctest-left_vn-78391727'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:11:23,506 - DEBUG - Response for create_subnet : {'subnet': {'id': '39bff4dd-135e-4d8d-b53c-7b686cf53e60', 'name': '', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'network_id': '126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2', 'ip_version': 4, 'cidr': '126.255.33.128/26', 'allocation_pools': [{'start': '126.255.33.130', 'end': '126.255.33.190'}], 'gateway_ip': '126.255.33.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '126.255.33.130', 'tags': [], 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1'}}
2025-10-30 11:11:23,534 - INFO - Created VN ctest-left_vn-78391727
2025-10-30 11:11:23,547 - DEBUG - VN ctest-left_vn-78391727 UUID is 126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:23,623 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:23,630 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:23,643 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:23,757 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:23,770 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:23,785 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5d8e9d1e-d8bc-4a12-9c5b-9f64c2dc090e
2025-10-30 11:11:23,795 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5d8e9d1e-d8bc-4a12-9c5b-9f64c2dc090e
2025-10-30 11:11:23,803 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/a59537a0-0bb6-45fd-a3b6-2fa4d22adb82
2025-10-30 11:11:23,816 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:23,816 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:23,826 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5d8e9d1e-d8bc-4a12-9c5b-9f64c2dc090e
2025-10-30 11:11:23,844 - INFO - Verified VN network id 16 for VN 126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:23,844 - INFO - Verifications in API Server for VN ctest-left_vn-78391727 passed
2025-10-30 11:11:23,844 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:23,856 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5d8e9d1e-d8bc-4a12-9c5b-9f64c2dc090e
2025-10-30 11:11:23,866 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5d8e9d1e-d8bc-4a12-9c5b-9f64c2dc090e
2025-10-30 11:11:23,875 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/a59537a0-0bb6-45fd-a3b6-2fa4d22adb82
2025-10-30 11:11:23,889 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1324382316905384665', 'uuid-lslong': '13097059982247452898'}, 'enable': 'true', 'created': '2025-10-30T11:11:23', 'last-modified': '2025-10-30T11:11:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.460753'}
2025-10-30 11:11:23,899 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:23,906 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1324382316905384665', 'uuid-lslong': '13097059982247452898'}, 'enable': 'true', 'created': '2025-10-30T11:11:23', 'last-modified': '2025-10-30T11:11:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.478039'}
2025-10-30 11:11:23,913 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:23,918 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1324382316905384665', 'uuid-lslong': '13097059982247452898'}, 'enable': 'true', 'created': '2025-10-30T11:11:23', 'last-modified': '2025-10-30T11:11:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481154'}
2025-10-30 11:11:23,925 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 11:11:23,925 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-78391727 passed
2025-10-30 11:11:23,925 - DEBUG - ====Verifying policy data for ctest-left_vn-78391727 in API_Server ======
2025-10-30 11:11:23,925 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:23,933 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:23,953 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:24,067 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/126126b3-49f4-4ad9-b5c2-1aa9a3d7e0e2
2025-10-30 11:11:24,078 - DEBUG - =>VN ctest-left_vn-78391727 has no policy to be verified
2025-10-30 11:11:24,078 - DEBUG - Verifying the vn in opserver
2025-10-30 11:11:24,078 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727 virtual network link through opserver 10.0.0.249
2025-10-30 11:11:24,078 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:24,091 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727?flat'}
2025-10-30 11:11:24,091 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727 virtual network link through opserver 10.0.0.33
2025-10-30 11:11:24,091 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:24,107 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727?flat'}
2025-10-30 11:11:24,107 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727 virtual network link through opserver 10.0.0.19
2025-10-30 11:11:24,107 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:24,121 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727?flat'}
2025-10-30 11:11:24,121 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54913158:ctest-left_vn-78391727 is found in opserver
2025-10-30 11:11:24,151 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:11:24,168 - DEBUG - VRF ids for VN ctest-left_vn-78391727: {}
2025-10-30 11:11:24,480 - DEBUG - Response for create_network : {'network': {'id': 'f180dbd1-0e00-4f21-8a37-05da67483467', 'name': 'ctest-right_vn-65802441', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54913158', 'ctest-right_vn-65802441'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 11:11:24,745 - DEBUG - Response for create_subnet : {'subnet': {'id': '5ac96b57-4560-4872-8e62-ccc526a6b52b', 'name': '', 'tenant_id': '45e2f06a765a431eb8f821a7bf5f6be1', 'network_id': 'f180dbd1-0e00-4f21-8a37-05da67483467', 'ip_version': 4, 'cidr': '181.5.108.0/26', 'allocation_pools': [{'start': '181.5.108.2', 'end': '181.5.108.62'}], 'gateway_ip': '181.5.108.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '181.5.108.2', 'tags': [], 'project_id': '45e2f06a765a431eb8f821a7bf5f6be1'}}
2025-10-30 11:11:24,769 - INFO - Created VN ctest-right_vn-65802441
2025-10-30 11:11:24,781 - DEBUG - VN ctest-right_vn-65802441 UUID is f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:24,868 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:24,875 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:24,888 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:24,988 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:25,010 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:25,023 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/67a1b2f2-558a-4d38-a2c1-eb5f7c3f5ae7
2025-10-30 11:11:25,036 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/67a1b2f2-558a-4d38-a2c1-eb5f7c3f5ae7
2025-10-30 11:11:25,046 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/a23d433b-caa9-41e5-b30b-55ed135fd653
2025-10-30 11:11:25,057 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:25,057 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:25,068 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/67a1b2f2-558a-4d38-a2c1-eb5f7c3f5ae7
2025-10-30 11:11:25,088 - INFO - Verified VN network id 17 for VN f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:25,088 - INFO - Verifications in API Server for VN ctest-right_vn-65802441 passed
2025-10-30 11:11:25,088 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:25,101 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/67a1b2f2-558a-4d38-a2c1-eb5f7c3f5ae7
2025-10-30 11:11:25,112 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/67a1b2f2-558a-4d38-a2c1-eb5f7c3f5ae7
2025-10-30 11:11:25,122 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/a23d433b-caa9-41e5-b30b-55ed135fd653
2025-10-30 11:11:25,136 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17402150651089145633', 'uuid-lslong': '9959435536546935911'}, 'enable': 'true', 'created': '2025-10-30T11:11:24', 'last-modified': '2025-10-30T11:11:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458529'}
2025-10-30 11:11:25,143 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:25,150 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17402150651089145633', 'uuid-lslong': '9959435536546935911'}, 'enable': 'true', 'created': '2025-10-30T11:11:24', 'last-modified': '2025-10-30T11:11:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470083'}
2025-10-30 11:11:25,157 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:25,164 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54913158', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17402150651089145633', 'uuid-lslong': '9959435536546935911'}, 'enable': 'true', 'created': '2025-10-30T11:11:24', 'last-modified': '2025-10-30T11:11:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483692'}
2025-10-30 11:11:25,171 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 11:11:25,171 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-65802441 passed
2025-10-30 11:11:25,171 - DEBUG - ====Verifying policy data for ctest-right_vn-65802441 in API_Server ======
2025-10-30 11:11:25,171 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:25,179 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:25,191 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:25,298 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f180dbd1-0e00-4f21-8a37-05da67483467
2025-10-30 11:11:25,308 - DEBUG - =>VN ctest-right_vn-65802441 has no policy to be verified
2025-10-30 11:11:25,308 - DEBUG - Verifying the vn in opserver
2025-10-30 11:11:25,308 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441 virtual network link through opserver 10.0.0.249
2025-10-30 11:11:25,308 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-30 11:11:25,321 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441?flat'}
2025-10-30 11:11:25,322 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441 virtual network link through opserver 10.0.0.33
2025-10-30 11:11:25,322 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-30 11:11:25,337 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441?flat'}
2025-10-30 11:11:25,337 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441 virtual network link through opserver 10.0.0.19
2025-10-30 11:11:25,337 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-30 11:11:25,352 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441?flat'}
2025-10-30 11:11:25,352 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54913158:ctest-right_vn-65802441 is found in opserver
2025-10-30 11:11:25,380 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 11:11:25,391 - DEBUG - VRF ids for VN ctest-right_vn-65802441: {}
2025-10-30 11:11:26,567 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-2)
2025-10-30 11:11:27,891 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4541-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4541-1)
2025-10-30 11:11:27,891 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-99314855']
2025-10-30 11:11:28,218 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-99314855'] created succesfully
2025-10-30 11:11:28,618 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 11:11:28,618 - DEBUG - Installing image tiny_nat_fw
2025-10-30 11:11:28,618 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-30 11:11:28,619 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:11:28,619 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:11:28,619 - DEBUG - Unzip image
2025-10-30 11:11:28,619 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 11:11:28,639 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 11:11:28,640 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-99314855']
2025-10-30 11:11:28,760 - INFO - Service template: ['default-domain', 'ctest-service_template_1-99314855'] deleted successfully.
2025-10-30 11:11:28,762 - INFO - Skip interface_detach for VM ctest-right_vm-58479645 in state BUILD
2025-10-30 11:11:28,762 - INFO - Deleting VM ctest-right_vm-58479645
2025-10-30 11:11:28,763 - ERROR - VM ctest-right_vm-58479645 has failed to come up
2025-10-30 11:11:28,764 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:28,764 - ERROR - VM ctest-right_vm-58479645 has failed to come up
2025-10-30 11:11:28,765 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:28,939 - INFO - Skip interface_detach for VM ctest-left_vm-30440757 in state BUILD
2025-10-30 11:11:28,940 - INFO - Deleting VM ctest-left_vm-30440757
2025-10-30 11:11:28,941 - ERROR - VM ctest-left_vm-30440757 has failed to come up
2025-10-30 11:11:28,941 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:28,942 - ERROR - VM ctest-left_vm-30440757 has failed to come up
2025-10-30 11:11:28,942 - ERROR - Nova failed to get host of the VM
2025-10-30 11:11:29,028 - INFO - Deleting VN ctest-right_vn-65802441
2025-10-30 11:11:29,113 - DEBUG - VN f180dbd1-0e00-4f21-8a37-05da67483467 still in use: Unable to complete operation on network f180dbd1-0e00-4f21-8a37-05da67483467. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9c92cef0-8fd5-4a3b-ab27-2a663491e3f9']
2025-10-30 11:11:29,113 - WARNING - Deleting VN ctest-right_vn-65802441 failed..Will retry
2025-10-30 11:11:31,314 - DEBUG - Response for deleting network ()
2025-10-30 11:11:31,314 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/67a1b2f2-558a-4d38-a2c1-eb5f7c3f5ae7
2025-10-30 11:11:31,322 - DEBUG - Response Code: 404
2025-10-30 11:11:31,323 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:31,329 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:31,403 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:31,519 - INFO - Validated that VN ctest-right_vn-65802441 is not found in API Server
2025-10-30 11:11:31,533 - DEBUG - VN ctest-right_vn-65802441 is not present in Agent 10.0.0.41
2025-10-30 11:11:31,545 - DEBUG - VN ctest-right_vn-65802441 is not present in Agent 10.0.0.27
2025-10-30 11:11:31,545 - INFO - Validated that VN ctest-right_vn-65802441 is not in any agent
2025-10-30 11:11:31,581 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-65802441 info
2025-10-30 11:11:31,581 - INFO - Deleting VN ctest-left_vn-78391727
2025-10-30 11:11:31,758 - DEBUG - Response for deleting network ()
2025-10-30 11:11:31,758 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5d8e9d1e-d8bc-4a12-9c5b-9f64c2dc090e
2025-10-30 11:11:31,765 - DEBUG - Response Code: 404
2025-10-30 11:11:31,765 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:31,775 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:31,793 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:31,888 - INFO - Validated that VN ctest-left_vn-78391727 is not found in API Server
2025-10-30 11:11:31,911 - DEBUG - VN ctest-left_vn-78391727 is not present in Agent 10.0.0.41
2025-10-30 11:11:31,929 - DEBUG - VN ctest-left_vn-78391727 is not present in Agent 10.0.0.27
2025-10-30 11:11:31,929 - INFO - Validated that VN ctest-left_vn-78391727 is not in any agent
2025-10-30 11:11:31,971 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-78391727 info
2025-10-30 11:11:31,971 - INFO - Deleting VN ctest-mgmt_vn-95652412
2025-10-30 11:11:32,129 - DEBUG - Response for deleting network ()
2025-10-30 11:11:32,129 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/546aec05-025a-47b0-8b17-ff4d6d27fc0f
2025-10-30 11:11:32,143 - DEBUG - Response Code: 404
2025-10-30 11:11:32,143 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-30 11:11:32,151 - DEBUG - Requesting: http://10.0.0.249:8082/domain/4bebd65a-549d-4c48-a839-bfb2f679c79a
2025-10-30 11:11:32,166 - DEBUG - Requesting: http://10.0.0.249:8082/project/45e2f06a-765a-431e-b8f8-21a7bf5f6be1
2025-10-30 11:11:32,265 - INFO - Validated that VN ctest-mgmt_vn-95652412 is not found in API Server
2025-10-30 11:11:32,282 - DEBUG - VN ctest-mgmt_vn-95652412 is not present in Agent 10.0.0.41
2025-10-30 11:11:32,299 - DEBUG - VN ctest-mgmt_vn-95652412 is not present in Agent 10.0.0.27
2025-10-30 11:11:32,299 - INFO - Validated that VN ctest-mgmt_vn-95652412 is not in any agent
2025-10-30 11:11:32,345 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-95652412 info
2025-10-30 11:11:33,653 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 11:11:33,653 - INFO -
2025-10-30 11:11:33,654 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-10-30 11:11:33,654 - INFO - --------------------------------------------------------------------------------
2025-10-30 11:11:34,487 - INFO - Deleted project: ctest-TestSvcRegr-54913158, ID : 45e2f06a-765a-431e-b8f8-21a7bf5f6be1