]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2)
2025-10-30 03:14:37,338 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-20516966']
2025-10-30 03:14:37,661 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-20516966'] created succesfully
2025-10-30 03:14:38,114 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 03:14:38,114 - DEBUG - Installing image tiny_nat_fw
2025-10-30 03:14:38,114 - 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 03:14:38,115 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-30 03:14:38,115 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-30 03:14:38,136 - 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 03:14:38,220 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:14:38,220 - DEBUG - Unzip image
2025-10-30 03:14:38,220 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:14:38,259 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 03:14:38,260 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-20516966']
2025-10-30 03:14:38,368 - INFO - Service template: ['default-domain', 'ctest-service_template_1-20516966'] deleted successfully.
2025-10-30 03:14:38,368 - INFO - Skip interface_detach for VM ctest-right_vm-20985195 in state BUILD
2025-10-30 03:14:38,368 - INFO - Deleting VM ctest-right_vm-20985195
2025-10-30 03:14:38,403 - ERROR - VM ctest-right_vm-20985195 has failed to come up
2025-10-30 03:14:38,403 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:38,403 - ERROR - VM ctest-right_vm-20985195 has failed to come up
2025-10-30 03:14:38,403 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:38,481 - INFO - Skip interface_detach for VM ctest-left_vm-95091667 in state BUILD
2025-10-30 03:14:38,481 - INFO - Deleting VM ctest-left_vm-95091667
2025-10-30 03:14:38,481 - ERROR - VM ctest-left_vm-95091667 has failed to come up
2025-10-30 03:14:38,482 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:38,482 - ERROR - VM ctest-left_vm-95091667 has failed to come up
2025-10-30 03:14:38,482 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:38,564 - INFO - Deleting VN ctest-right_vn-27122848
2025-10-30 03:14:38,612 - DEBUG - VN 03b19efa-f00f-4f18-9d99-5aa9e52a2c6f still in use: Unable to complete operation on network 03b19efa-f00f-4f18-9d99-5aa9e52a2c6f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-213ddcc4-7323-4043-81f4-ef931118d9dc']
2025-10-30 03:14:38,612 - WARNING - Deleting VN ctest-right_vn-27122848 failed..Will retry
2025-10-30 03:14:40,787 - DEBUG - Response for deleting network ()
2025-10-30 03:14:40,787 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/effb7ae2-8c24-46de-9823-eadae0036674
2025-10-30 03:14:40,794 - DEBUG - Response Code: 404
2025-10-30 03:14:40,795 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:40,801 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:40,827 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:40,921 - INFO - Validated that VN ctest-right_vn-27122848 is not found in API Server
2025-10-30 03:14:40,933 - DEBUG - VN ctest-right_vn-27122848 is not present in Agent 10.0.0.129
2025-10-30 03:14:40,943 - DEBUG - VN ctest-right_vn-27122848 is not present in Agent 10.0.0.65
2025-10-30 03:14:40,943 - INFO - Validated that VN ctest-right_vn-27122848 is not in any agent
2025-10-30 03:14:40,983 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-27122848 info
2025-10-30 03:14:40,983 - INFO - Deleting VN ctest-left_vn-14439909
2025-10-30 03:14:41,128 - DEBUG - Response for deleting network ()
2025-10-30 03:14:41,128 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/bc239226-bd0c-497d-99b3-03e90c2a8b4f
2025-10-30 03:14:41,134 - DEBUG - Response Code: 404
2025-10-30 03:14:41,134 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:41,141 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:41,156 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:41,241 - INFO - Validated that VN ctest-left_vn-14439909 is not found in API Server
2025-10-30 03:14:41,252 - DEBUG - VN ctest-left_vn-14439909 is not present in Agent 10.0.0.129
2025-10-30 03:14:41,262 - DEBUG - VN ctest-left_vn-14439909 is not present in Agent 10.0.0.65
2025-10-30 03:14:41,262 - INFO - Validated that VN ctest-left_vn-14439909 is not in any agent
2025-10-30 03:14:41,292 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-14439909 info
2025-10-30 03:14:41,292 - INFO - Deleting VN ctest-mgmt_vn-12849147
2025-10-30 03:14:41,442 - DEBUG - Response for deleting network ()
2025-10-30 03:14:41,443 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/18440f8a-b5b2-4f27-936e-962b4e6f41e4
2025-10-30 03:14:41,448 - DEBUG - Response Code: 404
2025-10-30 03:14:41,448 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:41,460 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:41,514 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:41,600 - INFO - Validated that VN ctest-mgmt_vn-12849147 is not found in API Server
2025-10-30 03:14:41,612 - DEBUG - VN ctest-mgmt_vn-12849147 is not present in Agent 10.0.0.129
2025-10-30 03:14:41,623 - DEBUG - VN ctest-mgmt_vn-12849147 is not present in Agent 10.0.0.65
2025-10-30 03:14:41,623 - INFO - Validated that VN ctest-mgmt_vn-12849147 is not in any agent
2025-10-30 03:14:41,653 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-12849147 info
2025-10-30 03:14:41,653 - INFO - Deleting VM ctest-vm-in-third-vn-96822548
2025-10-30 03:14:41,741 - INFO - Deleting VN ctest-third-vn-43322403
2025-10-30 03:14:41,781 - DEBUG - VN 77314133-be51-4d5e-b7a7-8c4deeee6c4d still in use: Unable to complete operation on network 77314133-be51-4d5e-b7a7-8c4deeee6c4d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d0529611-7a9e-48d4-9b43-6cadca1149c4']
2025-10-30 03:14:41,781 - WARNING - Deleting VN ctest-third-vn-43322403 failed..Will retry
2025-10-30 03:14:43,928 - DEBUG - Response for deleting network ()
2025-10-30 03:14:45,207 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 03:14:45,207 - INFO -
2025-10-30 03:14:45,207 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-10-30 03:14:45,208 - INFO - --------------------------------------------------------------------------------
2025-10-30 03:14:45,210 - INFO - ================================================================================
2025-10-30 03:14:45,211 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-30 03:14:46,489 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.20.0.131': '0', '10.20.0.254': '0'}, '10.0.0.65': {'10.20.0.254': '0', '10.20.0.131': '0'}} with
2025-10-30 03:14:46,489 - INFO - Initial checks done. Running the testcase now
2025-10-30 03:14:46,489 - INFO -
2025-10-30 03:14:46,805 - DEBUG - Response for create_network : {'network': {'id': 'aa73060f-e549-48ff-a264-f0d53b1e2e04', 'name': 'ctest-mgmt_vn-03709934', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'project_id': '116fc43c7ed045bdadc20523e4e0e959', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38915055', 'ctest-mgmt_vn-03709934'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:14:47,037 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c2df86fa-2e79-42e4-bd11-3a8337f70f91', 'name': '', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'network_id': 'aa73060f-e549-48ff-a264-f0d53b1e2e04', 'ip_version': 4, 'cidr': '59.79.102.192/26', 'allocation_pools': [{'start': '59.79.102.194', 'end': '59.79.102.254'}], 'gateway_ip': '59.79.102.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.79.102.194', 'tags': [], 'project_id': '116fc43c7ed045bdadc20523e4e0e959'}}
2025-10-30 03:14:47,064 - INFO - Created VN ctest-mgmt_vn-03709934
2025-10-30 03:14:47,079 - DEBUG - VN ctest-mgmt_vn-03709934 UUID is aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,156 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:47,162 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:47,173 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:47,278 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,289 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,303 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/4fa95462-f4f1-4359-8d23-671736b21c57
2025-10-30 03:14:47,315 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/4fa95462-f4f1-4359-8d23-671736b21c57
2025-10-30 03:14:47,324 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/2d058dfd-c0c3-4117-8fe4-28b50ef327e9
2025-10-30 03:14:47,332 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:14:47,333 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,343 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/4fa95462-f4f1-4359-8d23-671736b21c57
2025-10-30 03:14:47,361 - INFO - Verified VN network id 13 for VN aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,361 - INFO - Verifications in API Server for VN ctest-mgmt_vn-03709934 passed
2025-10-30 03:14:47,361 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,377 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/4fa95462-f4f1-4359-8d23-671736b21c57
2025-10-30 03:14:47,385 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/4fa95462-f4f1-4359-8d23-671736b21c57
2025-10-30 03:14:47,392 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/2d058dfd-c0c3-4117-8fe4-28b50ef327e9
2025-10-30 03:14:47,404 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12282167274110535935', 'uuid-lslong': '11701742530425925124'}, 'enable': 'true', 'created': '2025-10-30T03:14:46', 'last-modified': '2025-10-30T03:14:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415967'}
2025-10-30 03:14:47,409 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:14:47,415 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12282167274110535935', 'uuid-lslong': '11701742530425925124'}, 'enable': 'true', 'created': '2025-10-30T03:14:46', 'last-modified': '2025-10-30T03:14:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425839'}
2025-10-30 03:14:47,420 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:14:47,425 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12282167274110535935', 'uuid-lslong': '11701742530425925124'}, 'enable': 'true', 'created': '2025-10-30T03:14:46', 'last-modified': '2025-10-30T03:14:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437570'}
2025-10-30 03:14:47,431 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:14:47,431 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-03709934 passed
2025-10-30 03:14:47,431 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-03709934 in API_Server ======
2025-10-30 03:14:47,431 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:47,437 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:47,448 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:47,533 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/aa73060f-e549-48ff-a264-f0d53b1e2e04
2025-10-30 03:14:47,545 - DEBUG - =>VN ctest-mgmt_vn-03709934 has no policy to be verified
2025-10-30 03:14:47,545 - DEBUG - Verifying the vn in opserver
2025-10-30 03:14:47,545 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934 virtual network link through opserver 10.0.0.242
2025-10-30 03:14:47,545 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:14:47,561 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934?flat'}
2025-10-30 03:14:47,561 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934 virtual network link through opserver 10.0.0.254
2025-10-30 03:14:47,561 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:14:47,574 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934?flat'}
2025-10-30 03:14:47,574 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934 virtual network link through opserver 10.0.0.240
2025-10-30 03:14:47,574 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:14:47,589 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934?flat'}
2025-10-30 03:14:47,589 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38915055:ctest-mgmt_vn-03709934 is found in opserver
2025-10-30 03:14:47,612 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:14:47,625 - DEBUG - VRF ids for VN ctest-mgmt_vn-03709934: {}
2025-10-30 03:14:47,876 - DEBUG - Response for create_network : {'network': {'id': '652e5854-465b-4f5c-b868-82ab0bd24afe', 'name': 'ctest-left_vn-45057270', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'project_id': '116fc43c7ed045bdadc20523e4e0e959', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38915055', 'ctest-left_vn-45057270'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:14:48,146 - DEBUG - Response for create_subnet : {'subnet': {'id': '09a2e035-0a79-4fb2-9cfc-bb4efd625118', 'name': '', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'network_id': '652e5854-465b-4f5c-b868-82ab0bd24afe', 'ip_version': 4, 'cidr': '9.178.23.128/26', 'allocation_pools': [{'start': '9.178.23.130', 'end': '9.178.23.190'}], 'gateway_ip': '9.178.23.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '9.178.23.130', 'tags': [], 'project_id': '116fc43c7ed045bdadc20523e4e0e959'}}
2025-10-30 03:14:48,170 - INFO - Created VN ctest-left_vn-45057270
2025-10-30 03:14:48,182 - DEBUG - VN ctest-left_vn-45057270 UUID is 652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,260 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:48,267 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:48,278 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:48,366 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,378 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,389 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e98b0f3a-bd16-43c8-8ea1-0151d5706b6a
2025-10-30 03:14:48,401 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e98b0f3a-bd16-43c8-8ea1-0151d5706b6a
2025-10-30 03:14:48,410 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/dcdb3285-e3fc-4c51-bfaa-c308bad3c5b0
2025-10-30 03:14:48,420 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:14:48,420 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,430 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e98b0f3a-bd16-43c8-8ea1-0151d5706b6a
2025-10-30 03:14:48,448 - INFO - Verified VN network id 14 for VN 652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,448 - INFO - Verifications in API Server for VN ctest-left_vn-45057270 passed
2025-10-30 03:14:48,448 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,457 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e98b0f3a-bd16-43c8-8ea1-0151d5706b6a
2025-10-30 03:14:48,467 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e98b0f3a-bd16-43c8-8ea1-0151d5706b6a
2025-10-30 03:14:48,474 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/dcdb3285-e3fc-4c51-bfaa-c308bad3c5b0
2025-10-30 03:14:48,485 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7290861965740298076', 'uuid-lslong': '13288014371705998078'}, 'enable': 'true', 'created': '2025-10-30T03:14:47', 'last-modified': '2025-10-30T03:14:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421125'}
2025-10-30 03:14:48,492 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:14:48,499 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7290861965740298076', 'uuid-lslong': '13288014371705998078'}, 'enable': 'true', 'created': '2025-10-30T03:14:47', 'last-modified': '2025-10-30T03:14:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439233'}
2025-10-30 03:14:48,504 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:14:48,510 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7290861965740298076', 'uuid-lslong': '13288014371705998078'}, 'enable': 'true', 'created': '2025-10-30T03:14:47', 'last-modified': '2025-10-30T03:14:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.450825'}
2025-10-30 03:14:48,515 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:14:48,515 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-45057270 passed
2025-10-30 03:14:48,515 - DEBUG - ====Verifying policy data for ctest-left_vn-45057270 in API_Server ======
2025-10-30 03:14:48,515 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:48,522 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:48,534 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:48,639 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/652e5854-465b-4f5c-b868-82ab0bd24afe
2025-10-30 03:14:48,651 - DEBUG - =>VN ctest-left_vn-45057270 has no policy to be verified
2025-10-30 03:14:48,651 - DEBUG - Verifying the vn in opserver
2025-10-30 03:14:48,651 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270 virtual network link through opserver 10.0.0.242
2025-10-30 03:14:48,651 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:14:48,665 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270?flat'}
2025-10-30 03:14:48,665 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270 virtual network link through opserver 10.0.0.254
2025-10-30 03:14:48,665 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:14:48,679 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270?flat'}
2025-10-30 03:14:48,679 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270 virtual network link through opserver 10.0.0.240
2025-10-30 03:14:48,679 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:14:48,693 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270?flat'}
2025-10-30 03:14:48,693 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38915055:ctest-left_vn-45057270 is found in opserver
2025-10-30 03:14:48,712 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:14:48,721 - DEBUG - VRF ids for VN ctest-left_vn-45057270: {}
2025-10-30 03:14:49,005 - DEBUG - Response for create_network : {'network': {'id': 'a7ea5ff7-398e-47d4-ac2f-7e0d8363f417', 'name': 'ctest-right_vn-90933785', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'project_id': '116fc43c7ed045bdadc20523e4e0e959', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38915055', 'ctest-right_vn-90933785'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:14:49,232 - DEBUG - Response for create_subnet : {'subnet': {'id': '345e3833-0b8c-4d81-9a2c-c58f025264a8', 'name': '', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'network_id': 'a7ea5ff7-398e-47d4-ac2f-7e0d8363f417', 'ip_version': 4, 'cidr': '121.211.16.128/26', 'allocation_pools': [{'start': '121.211.16.130', 'end': '121.211.16.190'}], 'gateway_ip': '121.211.16.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.211.16.130', 'tags': [], 'project_id': '116fc43c7ed045bdadc20523e4e0e959'}}
2025-10-30 03:14:49,254 - INFO - Created VN ctest-right_vn-90933785
2025-10-30 03:14:49,268 - DEBUG - VN ctest-right_vn-90933785 UUID is a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,346 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:49,355 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:49,368 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:49,467 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,483 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,494 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/13d6955c-a48a-4442-afe7-e7c56be90f10
2025-10-30 03:14:49,503 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/13d6955c-a48a-4442-afe7-e7c56be90f10
2025-10-30 03:14:49,511 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/90c01c17-8712-488a-906c-4603b8caa4cf
2025-10-30 03:14:49,521 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:14:49,521 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,530 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/13d6955c-a48a-4442-afe7-e7c56be90f10
2025-10-30 03:14:49,546 - INFO - Verified VN network id 15 for VN a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,546 - INFO - Verifications in API Server for VN ctest-right_vn-90933785 passed
2025-10-30 03:14:49,547 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,561 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/13d6955c-a48a-4442-afe7-e7c56be90f10
2025-10-30 03:14:49,570 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/13d6955c-a48a-4442-afe7-e7c56be90f10
2025-10-30 03:14:49,578 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/90c01c17-8712-488a-906c-4603b8caa4cf
2025-10-30 03:14:49,591 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12099588864311445460', 'uuid-lslong': '12407274094933046295'}, 'enable': 'true', 'created': '2025-10-30T03:14:48', 'last-modified': '2025-10-30T03:14:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408396'}
2025-10-30 03:14:49,597 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:14:49,602 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12099588864311445460', 'uuid-lslong': '12407274094933046295'}, 'enable': 'true', 'created': '2025-10-30T03:14:48', 'last-modified': '2025-10-30T03:14:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418830'}
2025-10-30 03:14:49,608 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:14:49,614 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12099588864311445460', 'uuid-lslong': '12407274094933046295'}, 'enable': 'true', 'created': '2025-10-30T03:14:48', 'last-modified': '2025-10-30T03:14:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433934'}
2025-10-30 03:14:49,621 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:14:49,621 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-90933785 passed
2025-10-30 03:14:49,621 - DEBUG - ====Verifying policy data for ctest-right_vn-90933785 in API_Server ======
2025-10-30 03:14:49,621 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:49,631 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:49,646 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:49,746 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a7ea5ff7-398e-47d4-ac2f-7e0d8363f417
2025-10-30 03:14:49,760 - DEBUG - =>VN ctest-right_vn-90933785 has no policy to be verified
2025-10-30 03:14:49,760 - DEBUG - Verifying the vn in opserver
2025-10-30 03:14:49,760 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785 virtual network link through opserver 10.0.0.242
2025-10-30 03:14:49,761 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:14:49,775 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785?flat'}
2025-10-30 03:14:49,775 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785 virtual network link through opserver 10.0.0.254
2025-10-30 03:14:49,775 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:14:49,793 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785?flat'}
2025-10-30 03:14:49,793 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785 virtual network link through opserver 10.0.0.240
2025-10-30 03:14:49,794 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:14:49,816 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785?flat'}
2025-10-30 03:14:49,816 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38915055:ctest-right_vn-90933785 is found in opserver
2025-10-30 03:14:49,847 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:14:49,858 - DEBUG - VRF ids for VN ctest-right_vn-90933785: {}
2025-10-30 03:14:50,126 - DEBUG - Response for create_network : {'network': {'id': '61980431-a743-43e9-8842-ba73bb71f5be', 'name': 'ctest-trans_left_vn-55637762', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'project_id': '116fc43c7ed045bdadc20523e4e0e959', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38915055', 'ctest-trans_left_vn-55637762'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:14:50,338 - DEBUG - Response for create_subnet : {'subnet': {'id': '32ff5718-1154-4331-b78d-8f10a50fe9d5', 'name': '', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'network_id': '61980431-a743-43e9-8842-ba73bb71f5be', 'ip_version': 4, 'cidr': '76.135.152.64/26', 'allocation_pools': [{'start': '76.135.152.66', 'end': '76.135.152.126'}], 'gateway_ip': '76.135.152.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.135.152.66', 'tags': [], 'project_id': '116fc43c7ed045bdadc20523e4e0e959'}}
2025-10-30 03:14:50,366 - INFO - Created VN ctest-trans_left_vn-55637762
2025-10-30 03:14:50,379 - DEBUG - VN ctest-trans_left_vn-55637762 UUID is 61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,478 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:50,486 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:50,499 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:50,600 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,615 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,627 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/362fbb3b-3b4b-49f5-a112-82baebcecb68
2025-10-30 03:14:50,637 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/362fbb3b-3b4b-49f5-a112-82baebcecb68
2025-10-30 03:14:50,645 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/8bf1c7d1-59c8-45a9-94a9-8a9553a3f3c7
2025-10-30 03:14:50,654 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 03:14:50,654 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,665 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/362fbb3b-3b4b-49f5-a112-82baebcecb68
2025-10-30 03:14:50,683 - INFO - Verified VN network id 16 for VN 61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,684 - INFO - Verifications in API Server for VN ctest-trans_left_vn-55637762 passed
2025-10-30 03:14:50,684 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,694 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/362fbb3b-3b4b-49f5-a112-82baebcecb68
2025-10-30 03:14:50,701 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/362fbb3b-3b4b-49f5-a112-82baebcecb68
2025-10-30 03:14:50,709 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/8bf1c7d1-59c8-45a9-94a9-8a9553a3f3c7
2025-10-30 03:14:50,726 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7032375429445141481', 'uuid-lslong': '9818615143849915838'}, 'enable': 'true', 'created': '2025-10-30T03:14:50', 'last-modified': '2025-10-30T03:14:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440100'}
2025-10-30 03:14:50,733 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 03:14:50,739 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7032375429445141481', 'uuid-lslong': '9818615143849915838'}, 'enable': 'true', 'created': '2025-10-30T03:14:50', 'last-modified': '2025-10-30T03:14:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456724'}
2025-10-30 03:14:50,745 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 03:14:50,750 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7032375429445141481', 'uuid-lslong': '9818615143849915838'}, 'enable': 'true', 'created': '2025-10-30T03:14:50', 'last-modified': '2025-10-30T03:14:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449676'}
2025-10-30 03:14:50,757 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-30 03:14:50,757 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-55637762 passed
2025-10-30 03:14:50,757 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-55637762 in API_Server ======
2025-10-30 03:14:50,758 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:50,764 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:50,778 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:50,897 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/61980431-a743-43e9-8842-ba73bb71f5be
2025-10-30 03:14:50,909 - DEBUG - =>VN ctest-trans_left_vn-55637762 has no policy to be verified
2025-10-30 03:14:50,909 - DEBUG - Verifying the vn in opserver
2025-10-30 03:14:50,909 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762 virtual network link through opserver 10.0.0.242
2025-10-30 03:14:50,909 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:14:50,922 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762?flat'}
2025-10-30 03:14:50,922 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762 virtual network link through opserver 10.0.0.254
2025-10-30 03:14:50,922 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:14:50,935 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762?flat'}
2025-10-30 03:14:50,935 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762 virtual network link through opserver 10.0.0.240
2025-10-30 03:14:50,935 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:14:50,952 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762?flat'}
2025-10-30 03:14:50,952 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38915055:ctest-trans_left_vn-55637762 is found in opserver
2025-10-30 03:14:50,973 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:14:50,986 - DEBUG - VRF ids for VN ctest-trans_left_vn-55637762: {}
2025-10-30 03:14:51,283 - DEBUG - Response for create_network : {'network': {'id': 'c803782e-a1fc-4267-9b7d-377a4db45daa', 'name': 'ctest-trans_right_vn-59266580', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'project_id': '116fc43c7ed045bdadc20523e4e0e959', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38915055', 'ctest-trans_right_vn-59266580'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:14:51,502 - DEBUG - Response for create_subnet : {'subnet': {'id': '41ba778a-78b2-490f-b8ec-79c569df80ba', 'name': '', 'tenant_id': '116fc43c7ed045bdadc20523e4e0e959', 'network_id': 'c803782e-a1fc-4267-9b7d-377a4db45daa', 'ip_version': 4, 'cidr': '78.9.252.0/26', 'allocation_pools': [{'start': '78.9.252.2', 'end': '78.9.252.62'}], 'gateway_ip': '78.9.252.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.9.252.2', 'tags': [], 'project_id': '116fc43c7ed045bdadc20523e4e0e959'}}
2025-10-30 03:14:51,533 - INFO - Created VN ctest-trans_right_vn-59266580
2025-10-30 03:14:51,550 - DEBUG - VN ctest-trans_right_vn-59266580 UUID is c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:51,634 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:51,640 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:51,651 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:51,764 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:51,776 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:51,785 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/55152a7b-f0e6-4eb0-908c-ce84101c6843
2025-10-30 03:14:51,795 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/55152a7b-f0e6-4eb0-908c-ce84101c6843
2025-10-30 03:14:51,803 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/7d2589ec-f92a-4286-9a6b-62c3ffb17298
2025-10-30 03:14:51,812 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 03:14:51,812 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:51,821 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/55152a7b-f0e6-4eb0-908c-ce84101c6843
2025-10-30 03:14:51,838 - INFO - Verified VN network id 17 for VN c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:51,838 - INFO - Verifications in API Server for VN ctest-trans_right_vn-59266580 passed
2025-10-30 03:14:51,838 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:51,848 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/55152a7b-f0e6-4eb0-908c-ce84101c6843
2025-10-30 03:14:51,856 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/55152a7b-f0e6-4eb0-908c-ce84101c6843
2025-10-30 03:14:51,863 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/7d2589ec-f92a-4286-9a6b-62c3ffb17298
2025-10-30 03:14:51,879 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14412495374197211751', 'uuid-lslong': '11204172446396865962'}, 'enable': 'true', 'created': '2025-10-30T03:14:51', 'last-modified': '2025-10-30T03:14:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443024'}
2025-10-30 03:14:51,887 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 03:14:51,893 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14412495374197211751', 'uuid-lslong': '11204172446396865962'}, 'enable': 'true', 'created': '2025-10-30T03:14:51', 'last-modified': '2025-10-30T03:14:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453667'}
2025-10-30 03:14:51,899 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 03:14:51,904 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38915055', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14412495374197211751', 'uuid-lslong': '11204172446396865962'}, 'enable': 'true', 'created': '2025-10-30T03:14:51', 'last-modified': '2025-10-30T03:14:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.469243'}
2025-10-30 03:14:51,911 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-30 03:14:51,911 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-59266580 passed
2025-10-30 03:14:51,911 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-59266580 in API_Server ======
2025-10-30 03:14:51,911 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:51,918 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:51,932 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:52,035 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c803782e-a1fc-4267-9b7d-377a4db45daa
2025-10-30 03:14:52,047 - DEBUG - =>VN ctest-trans_right_vn-59266580 has no policy to be verified
2025-10-30 03:14:52,047 - DEBUG - Verifying the vn in opserver
2025-10-30 03:14:52,047 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580 virtual network link through opserver 10.0.0.242
2025-10-30 03:14:52,047 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:14:52,060 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580?flat'}
2025-10-30 03:14:52,060 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580 virtual network link through opserver 10.0.0.254
2025-10-30 03:14:52,060 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:14:52,074 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580?flat'}
2025-10-30 03:14:52,074 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580 virtual network link through opserver 10.0.0.240
2025-10-30 03:14:52,075 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:14:52,088 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580?flat'}
2025-10-30 03:14:52,088 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38915055:ctest-trans_right_vn-59266580 is found in opserver
2025-10-30 03:14:52,110 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:14:52,120 - DEBUG - VRF ids for VN ctest-trans_right_vn-59266580: {}
2025-10-30 03:14:53,208 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2)
2025-10-30 03:14:54,601 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1)
2025-10-30 03:14:54,602 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-32968911']
2025-10-30 03:14:54,912 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-32968911'] created succesfully
2025-10-30 03:14:55,296 - DEBUG - Image by name tiny_trans_fw not found
2025-10-30 03:14:55,296 - DEBUG - Installing image tiny_trans_fw
2025-10-30 03:14:55,296 - 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 03:14:55,297 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-30 03:14:55,297 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-30 03:14:55,320 - 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 03:14:55,359 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 03:14:55,359 - DEBUG - Unzip image
2025-10-30 03:14:55,359 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-30 03:14:55,386 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-30 03:14:55,387 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-32968911']
2025-10-30 03:14:55,524 - INFO - Service template: ['default-domain', 'ctest-service_template_1-32968911'] deleted successfully.
2025-10-30 03:14:55,524 - INFO - Skip interface_detach for VM ctest-right_vm-31912587 in state BUILD
2025-10-30 03:14:55,525 - INFO - Deleting VM ctest-right_vm-31912587
2025-10-30 03:14:55,525 - ERROR - VM ctest-right_vm-31912587 has failed to come up
2025-10-30 03:14:55,525 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:55,525 - ERROR - VM ctest-right_vm-31912587 has failed to come up
2025-10-30 03:14:55,525 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:55,613 - INFO - Skip interface_detach for VM ctest-left_vm-62892297 in state BUILD
2025-10-30 03:14:55,614 - INFO - Deleting VM ctest-left_vm-62892297
2025-10-30 03:14:55,614 - ERROR - VM ctest-left_vm-62892297 has failed to come up
2025-10-30 03:14:55,614 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:55,614 - ERROR - VM ctest-left_vm-62892297 has failed to come up
2025-10-30 03:14:55,614 - ERROR - Nova failed to get host of the VM
2025-10-30 03:14:55,702 - INFO - Deleting VN ctest-trans_right_vn-59266580
2025-10-30 03:14:55,855 - DEBUG - Response for deleting network ()
2025-10-30 03:14:55,856 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/55152a7b-f0e6-4eb0-908c-ce84101c6843
2025-10-30 03:14:55,864 - DEBUG - Response Code: 404
2025-10-30 03:14:55,864 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:55,870 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:55,894 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:55,981 - INFO - Validated that VN ctest-trans_right_vn-59266580 is not found in API Server
2025-10-30 03:14:56,024 - DEBUG - VN ctest-trans_right_vn-59266580 is not present in Agent 10.0.0.129
2025-10-30 03:14:56,036 - DEBUG - VN ctest-trans_right_vn-59266580 is not present in Agent 10.0.0.65
2025-10-30 03:14:56,036 - INFO - Validated that VN ctest-trans_right_vn-59266580 is not in any agent
2025-10-30 03:14:56,070 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-59266580 info
2025-10-30 03:14:56,071 - INFO - Deleting VN ctest-trans_left_vn-55637762
2025-10-30 03:14:56,238 - DEBUG - Response for deleting network ()
2025-10-30 03:14:56,238 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/362fbb3b-3b4b-49f5-a112-82baebcecb68
2025-10-30 03:14:56,250 - DEBUG - Response Code: 404
2025-10-30 03:14:56,251 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:56,259 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:56,301 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:56,436 - INFO - Validated that VN ctest-trans_left_vn-55637762 is not found in API Server
2025-10-30 03:14:56,448 - DEBUG - VN ctest-trans_left_vn-55637762 is not present in Agent 10.0.0.129
2025-10-30 03:14:56,473 - DEBUG - VN ctest-trans_left_vn-55637762 is not present in Agent 10.0.0.65
2025-10-30 03:14:56,473 - INFO - Validated that VN ctest-trans_left_vn-55637762 is not in any agent
2025-10-30 03:14:56,567 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-55637762 info
2025-10-30 03:14:56,567 - INFO - Deleting VN ctest-right_vn-90933785
2025-10-30 03:14:56,617 - DEBUG - VN a7ea5ff7-398e-47d4-ac2f-7e0d8363f417 still in use: Unable to complete operation on network a7ea5ff7-398e-47d4-ac2f-7e0d8363f417. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-81b98f7e-b3a7-4e79-9743-9dab336968d5']
2025-10-30 03:14:56,617 - WARNING - Deleting VN ctest-right_vn-90933785 failed..Will retry
2025-10-30 03:14:58,873 - DEBUG - Response for deleting network ()
2025-10-30 03:14:58,874 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/13d6955c-a48a-4442-afe7-e7c56be90f10
2025-10-30 03:14:58,879 - DEBUG - Response Code: 404
2025-10-30 03:14:58,879 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:58,885 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:58,895 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:58,985 - INFO - Validated that VN ctest-right_vn-90933785 is not found in API Server
2025-10-30 03:14:58,995 - DEBUG - VN ctest-right_vn-90933785 is not present in Agent 10.0.0.129
2025-10-30 03:14:59,005 - DEBUG - VN ctest-right_vn-90933785 is not present in Agent 10.0.0.65
2025-10-30 03:14:59,005 - INFO - Validated that VN ctest-right_vn-90933785 is not in any agent
2025-10-30 03:14:59,033 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-90933785 info
2025-10-30 03:14:59,033 - INFO - Deleting VN ctest-left_vn-45057270
2025-10-30 03:14:59,169 - DEBUG - Response for deleting network ()
2025-10-30 03:14:59,169 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e98b0f3a-bd16-43c8-8ea1-0151d5706b6a
2025-10-30 03:14:59,175 - DEBUG - Response Code: 404
2025-10-30 03:14:59,175 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:59,180 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:59,192 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:59,292 - INFO - Validated that VN ctest-left_vn-45057270 is not found in API Server
2025-10-30 03:14:59,310 - DEBUG - VN ctest-left_vn-45057270 is not present in Agent 10.0.0.129
2025-10-30 03:14:59,325 - DEBUG - VN ctest-left_vn-45057270 is not present in Agent 10.0.0.65
2025-10-30 03:14:59,327 - INFO - Validated that VN ctest-left_vn-45057270 is not in any agent
2025-10-30 03:14:59,366 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-45057270 info
2025-10-30 03:14:59,366 - INFO - Deleting VN ctest-mgmt_vn-03709934
2025-10-30 03:14:59,531 - DEBUG - Response for deleting network ()
2025-10-30 03:14:59,531 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/4fa95462-f4f1-4359-8d23-671736b21c57
2025-10-30 03:14:59,537 - DEBUG - Response Code: 404
2025-10-30 03:14:59,537 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:14:59,551 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:14:59,606 - DEBUG - Requesting: http://10.0.0.242:8082/project/116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:14:59,704 - INFO - Validated that VN ctest-mgmt_vn-03709934 is not found in API Server
2025-10-30 03:14:59,718 - DEBUG - VN ctest-mgmt_vn-03709934 is not present in Agent 10.0.0.129
2025-10-30 03:14:59,731 - DEBUG - VN ctest-mgmt_vn-03709934 is not present in Agent 10.0.0.65
2025-10-30 03:14:59,731 - INFO - Validated that VN ctest-mgmt_vn-03709934 is not in any agent
2025-10-30 03:14:59,768 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-03709934 info
2025-10-30 03:15:01,061 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 03:15:01,061 - INFO -
2025-10-30 03:15:01,061 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-10-30 03:15:01,061 - INFO - --------------------------------------------------------------------------------
2025-10-30 03:15:01,832 - INFO - Deleted project: ctest-TestSvcRegr-38915055, ID : 116fc43c-7ed0-45bd-adc2-0523e4e0e959
2025-10-30 03:15:02,031 - INFO - Domain Default found not creating
2025-10-30 03:15:02,187 - INFO - Project ctest-TestSvcRegr-62376600 not found, creating it
2025-10-30 03:15:02,757 - INFO - Created Project:ctest-TestSvcRegr-62376600, ID : 3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:04,250 - INFO - ================================================================================
2025-10-30 03:15:04,250 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-30 03:15:05,529 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.20.0.131': '0', '10.20.0.254': '0'}, '10.0.0.65': {'10.20.0.254': '0', '10.20.0.131': '0'}} with
2025-10-30 03:15:05,529 - INFO - Initial checks done. Running the testcase now
2025-10-30 03:15:05,529 - INFO -
2025-10-30 03:15:06,196 - DEBUG - Response for create_network : {'network': {'id': '8c50eb18-b915-4dfc-8950-8496bcfbb425', 'name': 'ctest-mgmt_vn-47987753', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'project_id': '3673fdbeddd24ad58881117716dae232', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62376600', 'ctest-mgmt_vn-47987753'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:15:06,481 - DEBUG - Response for create_subnet : {'subnet': {'id': '2323d594-9669-45a3-96e1-b49b2734d7be', 'name': '', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'network_id': '8c50eb18-b915-4dfc-8950-8496bcfbb425', 'ip_version': 4, 'cidr': '12.141.24.0/26', 'allocation_pools': [{'start': '12.141.24.2', 'end': '12.141.24.62'}], 'gateway_ip': '12.141.24.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '12.141.24.2', 'tags': [], 'project_id': '3673fdbeddd24ad58881117716dae232'}}
2025-10-30 03:15:06,504 - INFO - Created VN ctest-mgmt_vn-47987753
2025-10-30 03:15:06,524 - DEBUG - VN ctest-mgmt_vn-47987753 UUID is 8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:06,606 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:06,907 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:06,950 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:06,973 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:07,133 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:07,148 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:07,161 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e47decb6-c1fc-49bf-9b9e-2d4ad6e54e6c
2025-10-30 03:15:07,168 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e47decb6-c1fc-49bf-9b9e-2d4ad6e54e6c
2025-10-30 03:15:07,175 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/fa437514-5a46-4f20-993f-8508fd163b7b
2025-10-30 03:15:07,182 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:07,182 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:07,206 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e47decb6-c1fc-49bf-9b9e-2d4ad6e54e6c
2025-10-30 03:15:07,244 - INFO - Verified VN network id 13 for VN 8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:07,244 - INFO - Verifications in API Server for VN ctest-mgmt_vn-47987753 passed
2025-10-30 03:15:07,244 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:07,262 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e47decb6-c1fc-49bf-9b9e-2d4ad6e54e6c
2025-10-30 03:15:07,274 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e47decb6-c1fc-49bf-9b9e-2d4ad6e54e6c
2025-10-30 03:15:07,288 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/fa437514-5a46-4f20-993f-8508fd163b7b
2025-10-30 03:15:07,309 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10110839654863687164', 'uuid-lslong': '9894554164283552805'}, 'enable': 'true', 'created': '2025-10-30T03:15:06', 'last-modified': '2025-10-30T03:15:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.884939'}
2025-10-30 03:15:07,319 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:07,326 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10110839654863687164', 'uuid-lslong': '9894554164283552805'}, 'enable': 'true', 'created': '2025-10-30T03:15:06', 'last-modified': '2025-10-30T03:15:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.891789'}
2025-10-30 03:15:07,331 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:07,338 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10110839654863687164', 'uuid-lslong': '9894554164283552805'}, 'enable': 'true', 'created': '2025-10-30T03:15:06', 'last-modified': '2025-10-30T03:15:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.911556'}
2025-10-30 03:15:07,347 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:07,347 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-47987753 passed
2025-10-30 03:15:07,347 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-47987753 in API_Server ======
2025-10-30 03:15:07,347 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:07,361 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:07,386 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:07,484 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/8c50eb18-b915-4dfc-8950-8496bcfbb425
2025-10-30 03:15:07,496 - DEBUG - =>VN ctest-mgmt_vn-47987753 has no policy to be verified
2025-10-30 03:15:07,496 - DEBUG - Verifying the vn in opserver
2025-10-30 03:15:07,496 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753 virtual network link through opserver 10.0.0.242
2025-10-30 03:15:07,497 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:07,882 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:07,937 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753?flat'}
2025-10-30 03:15:07,937 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753 virtual network link through opserver 10.0.0.254
2025-10-30 03:15:07,937 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:08,239 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:08,299 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753?flat'}
2025-10-30 03:15:08,299 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753 virtual network link through opserver 10.0.0.240
2025-10-30 03:15:08,299 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:08,604 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:08,672 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753?flat'}
2025-10-30 03:15:08,672 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-47987753 is found in opserver
2025-10-30 03:15:08,696 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:15:08,706 - DEBUG - VRF ids for VN ctest-mgmt_vn-47987753: {}
2025-10-30 03:15:08,978 - DEBUG - Response for create_network : {'network': {'id': 'ad24db4d-4b7d-451c-8992-ee109f6225bd', 'name': 'service-left-vn-81656502', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'project_id': '3673fdbeddd24ad58881117716dae232', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62376600', 'service-left-vn-81656502'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:15:09,260 - DEBUG - Response for create_subnet : {'subnet': {'id': '9e2b3940-e343-4e78-a616-91baab86099f', 'name': '', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'network_id': 'ad24db4d-4b7d-451c-8992-ee109f6225bd', 'ip_version': 4, 'cidr': '86.167.72.128/26', 'allocation_pools': [{'start': '86.167.72.130', 'end': '86.167.72.190'}], 'gateway_ip': '86.167.72.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '86.167.72.130', 'tags': [], 'project_id': '3673fdbeddd24ad58881117716dae232'}}
2025-10-30 03:15:09,288 - INFO - Created VN service-left-vn-81656502
2025-10-30 03:15:09,302 - DEBUG - VN service-left-vn-81656502 UUID is ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,377 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:09,384 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:09,397 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:09,485 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,497 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,505 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/91431a16-bcd8-4094-bb13-4cb943866aba
2025-10-30 03:15:09,514 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/91431a16-bcd8-4094-bb13-4cb943866aba
2025-10-30 03:15:09,521 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/ba4caa2a-545a-4b64-9725-8a4f28015c79
2025-10-30 03:15:09,527 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:09,527 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,536 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/91431a16-bcd8-4094-bb13-4cb943866aba
2025-10-30 03:15:09,551 - INFO - Verified VN network id 14 for VN ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,551 - INFO - Verifications in API Server for VN service-left-vn-81656502 passed
2025-10-30 03:15:09,551 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,559 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/91431a16-bcd8-4094-bb13-4cb943866aba
2025-10-30 03:15:09,568 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/91431a16-bcd8-4094-bb13-4cb943866aba
2025-10-30 03:15:09,575 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/ba4caa2a-545a-4b64-9725-8a4f28015c79
2025-10-30 03:15:09,588 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12476337992748582172', 'uuid-lslong': '9913247484956779965'}, 'enable': 'true', 'created': '2025-10-30T03:15:08', 'last-modified': '2025-10-30T03:15:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392992'}
2025-10-30 03:15:09,593 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:09,598 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12476337992748582172', 'uuid-lslong': '9913247484956779965'}, 'enable': 'true', 'created': '2025-10-30T03:15:08', 'last-modified': '2025-10-30T03:15:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408083'}
2025-10-30 03:15:09,603 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:09,608 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12476337992748582172', 'uuid-lslong': '9913247484956779965'}, 'enable': 'true', 'created': '2025-10-30T03:15:08', 'last-modified': '2025-10-30T03:15:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418441'}
2025-10-30 03:15:09,615 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:09,615 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-81656502 passed
2025-10-30 03:15:09,615 - DEBUG - ====Verifying policy data for service-left-vn-81656502 in API_Server ======
2025-10-30 03:15:09,615 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:09,622 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:09,634 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:09,726 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/ad24db4d-4b7d-451c-8992-ee109f6225bd
2025-10-30 03:15:09,750 - DEBUG - =>VN service-left-vn-81656502 has no policy to be verified
2025-10-30 03:15:09,750 - DEBUG - Verifying the vn in opserver
2025-10-30 03:15:09,750 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502 virtual network link through opserver 10.0.0.242
2025-10-30 03:15:09,750 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:09,806 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502?flat'}
2025-10-30 03:15:09,806 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502 virtual network link through opserver 10.0.0.254
2025-10-30 03:15:09,807 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:09,864 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502?flat'}
2025-10-30 03:15:09,864 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502 virtual network link through opserver 10.0.0.240
2025-10-30 03:15:09,865 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:09,924 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502?flat'}
2025-10-30 03:15:09,924 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62376600:service-left-vn-81656502 is found in opserver
2025-10-30 03:15:09,944 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:15:09,953 - DEBUG - VRF ids for VN service-left-vn-81656502: {}
2025-10-30 03:15:10,262 - DEBUG - Response for create_network : {'network': {'id': '94fa04d2-ef85-4303-ba08-6b61ab8b8462', 'name': 'service-right-vn-77332299', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'project_id': '3673fdbeddd24ad58881117716dae232', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62376600', 'service-right-vn-77332299'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:15:10,507 - DEBUG - Response for create_subnet : {'subnet': {'id': '4119f885-7a45-47a1-a450-87819ed31ff7', 'name': '', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'network_id': '94fa04d2-ef85-4303-ba08-6b61ab8b8462', 'ip_version': 4, 'cidr': '128.247.61.64/26', 'allocation_pools': [{'start': '128.247.61.66', 'end': '128.247.61.126'}], 'gateway_ip': '128.247.61.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.247.61.66', 'tags': [], 'project_id': '3673fdbeddd24ad58881117716dae232'}}
2025-10-30 03:15:10,530 - INFO - Created VN service-right-vn-77332299
2025-10-30 03:15:10,545 - DEBUG - VN service-right-vn-77332299 UUID is 94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:10,626 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:10,632 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:10,645 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:10,743 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:10,757 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:10,766 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/618bf395-8baa-4f2a-b4c5-102aa962eb3e
2025-10-30 03:15:10,775 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/618bf395-8baa-4f2a-b4c5-102aa962eb3e
2025-10-30 03:15:10,785 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/e54e8586-f4a8-491b-ba85-8f7eac555239
2025-10-30 03:15:10,796 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:10,796 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:10,806 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/618bf395-8baa-4f2a-b4c5-102aa962eb3e
2025-10-30 03:15:10,823 - INFO - Verified VN network id 15 for VN 94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:10,824 - INFO - Verifications in API Server for VN service-right-vn-77332299 passed
2025-10-30 03:15:10,824 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:10,836 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/618bf395-8baa-4f2a-b4c5-102aa962eb3e
2025-10-30 03:15:10,848 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/618bf395-8baa-4f2a-b4c5-102aa962eb3e
2025-10-30 03:15:10,857 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/e54e8586-f4a8-491b-ba85-8f7eac555239
2025-10-30 03:15:10,872 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10734897965799129859', 'uuid-lslong': '13405082358102328418'}, 'enable': 'true', 'created': '2025-10-30T03:15:10', 'last-modified': '2025-10-30T03:15:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436906'}
2025-10-30 03:15:10,879 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:10,884 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10734897965799129859', 'uuid-lslong': '13405082358102328418'}, 'enable': 'true', 'created': '2025-10-30T03:15:10', 'last-modified': '2025-10-30T03:15:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.452609'}
2025-10-30 03:15:10,890 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:10,895 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10734897965799129859', 'uuid-lslong': '13405082358102328418'}, 'enable': 'true', 'created': '2025-10-30T03:15:10', 'last-modified': '2025-10-30T03:15:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462115'}
2025-10-30 03:15:10,902 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:10,902 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-77332299 passed
2025-10-30 03:15:10,902 - DEBUG - ====Verifying policy data for service-right-vn-77332299 in API_Server ======
2025-10-30 03:15:10,902 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:10,910 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:10,923 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:11,014 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/94fa04d2-ef85-4303-ba08-6b61ab8b8462
2025-10-30 03:15:11,025 - DEBUG - =>VN service-right-vn-77332299 has no policy to be verified
2025-10-30 03:15:11,025 - DEBUG - Verifying the vn in opserver
2025-10-30 03:15:11,025 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299 virtual network link through opserver 10.0.0.242
2025-10-30 03:15:11,025 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:11,085 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299?flat'}
2025-10-30 03:15:11,085 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299 virtual network link through opserver 10.0.0.254
2025-10-30 03:15:11,085 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:11,145 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299?flat'}
2025-10-30 03:15:11,145 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299 virtual network link through opserver 10.0.0.240
2025-10-30 03:15:11,145 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:11,214 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299?flat'}
2025-10-30 03:15:11,214 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62376600:service-right-vn-77332299 is found in opserver
2025-10-30 03:15:11,235 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:15:11,244 - DEBUG - VRF ids for VN service-right-vn-77332299: {}
2025-10-30 03:15:11,305 - DEBUG - Services list from nova: [, , , ]
2025-10-30 03:15:12,421 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2)
2025-10-30 03:15:13,720 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1)
2025-10-30 03:15:13,720 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-95879332']
2025-10-30 03:15:14,040 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-95879332'] created succesfully
2025-10-30 03:15:14,426 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 03:15:14,426 - DEBUG - Installing image tiny_nat_fw
2025-10-30 03:15:14,426 - 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 03:15:14,426 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:15:14,426 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:15:14,426 - DEBUG - Unzip image
2025-10-30 03:15:14,426 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:15:14,456 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 03:15:14,457 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-95879332']
2025-10-30 03:15:14,608 - INFO - Service template: ['default-domain', 'ctest-service_template_1-95879332'] deleted successfully.
2025-10-30 03:15:14,609 - INFO - Skip interface_detach for VM ctest-right_vm-13320744 in state BUILD
2025-10-30 03:15:14,609 - INFO - Deleting VM ctest-right_vm-13320744
2025-10-30 03:15:14,645 - ERROR - VM ctest-right_vm-13320744 has failed to come up
2025-10-30 03:15:14,645 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:14,645 - ERROR - VM ctest-right_vm-13320744 has failed to come up
2025-10-30 03:15:14,645 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:14,739 - INFO - Skip interface_detach for VM ctest-left_vm-78048529 in state BUILD
2025-10-30 03:15:14,740 - INFO - Deleting VM ctest-left_vm-78048529
2025-10-30 03:15:14,740 - ERROR - VM ctest-left_vm-78048529 has failed to come up
2025-10-30 03:15:14,740 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:14,740 - ERROR - VM ctest-left_vm-78048529 has failed to come up
2025-10-30 03:15:14,740 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:14,813 - INFO - Deleting VN service-right-vn-77332299
2025-10-30 03:15:14,856 - DEBUG - VN 94fa04d2-ef85-4303-ba08-6b61ab8b8462 still in use: Unable to complete operation on network 94fa04d2-ef85-4303-ba08-6b61ab8b8462. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e39f6257-4f18-4bb5-9ed9-275f45eacb5b']
2025-10-30 03:15:14,856 - WARNING - Deleting VN service-right-vn-77332299 failed..Will retry
2025-10-30 03:15:17,045 - DEBUG - Response for deleting network ()
2025-10-30 03:15:17,045 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/618bf395-8baa-4f2a-b4c5-102aa962eb3e
2025-10-30 03:15:17,059 - DEBUG - Response Code: 404
2025-10-30 03:15:17,059 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:17,067 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:17,080 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:17,164 - INFO - Validated that VN service-right-vn-77332299 is not found in API Server
2025-10-30 03:15:17,175 - DEBUG - VN service-right-vn-77332299 is not present in Agent 10.0.0.129
2025-10-30 03:15:17,185 - DEBUG - VN service-right-vn-77332299 is not present in Agent 10.0.0.65
2025-10-30 03:15:17,185 - INFO - Validated that VN service-right-vn-77332299 is not in any agent
2025-10-30 03:15:17,220 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-77332299 info
2025-10-30 03:15:17,222 - INFO - Deleting VN service-left-vn-81656502
2025-10-30 03:15:17,382 - DEBUG - Response for deleting network ()
2025-10-30 03:15:17,382 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/91431a16-bcd8-4094-bb13-4cb943866aba
2025-10-30 03:15:17,397 - DEBUG - Response Code: 404
2025-10-30 03:15:17,397 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:17,403 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:17,458 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:17,545 - INFO - Validated that VN service-left-vn-81656502 is not found in API Server
2025-10-30 03:15:17,558 - DEBUG - VN service-left-vn-81656502 is not present in Agent 10.0.0.129
2025-10-30 03:15:17,567 - DEBUG - VN service-left-vn-81656502 is not present in Agent 10.0.0.65
2025-10-30 03:15:17,567 - INFO - Validated that VN service-left-vn-81656502 is not in any agent
2025-10-30 03:15:17,601 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-81656502 info
2025-10-30 03:15:17,601 - INFO - Deleting VN ctest-mgmt_vn-47987753
2025-10-30 03:15:17,772 - DEBUG - Response for deleting network ()
2025-10-30 03:15:17,772 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e47decb6-c1fc-49bf-9b9e-2d4ad6e54e6c
2025-10-30 03:15:17,785 - DEBUG - Response Code: 404
2025-10-30 03:15:17,785 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:17,794 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:17,805 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:17,895 - INFO - Validated that VN ctest-mgmt_vn-47987753 is not found in API Server
2025-10-30 03:15:17,907 - DEBUG - VN ctest-mgmt_vn-47987753 is not present in Agent 10.0.0.129
2025-10-30 03:15:17,916 - DEBUG - VN ctest-mgmt_vn-47987753 is not present in Agent 10.0.0.65
2025-10-30 03:15:17,916 - INFO - Validated that VN ctest-mgmt_vn-47987753 is not in any agent
2025-10-30 03:15:17,948 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-47987753 info
2025-10-30 03:15:19,214 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 03:15:19,214 - INFO -
2025-10-30 03:15:19,215 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-10-30 03:15:19,215 - INFO - --------------------------------------------------------------------------------
2025-10-30 03:15:19,219 - INFO - ================================================================================
2025-10-30 03:15:19,220 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-30 03:15:20,522 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.20.0.131': '0', '10.20.0.254': '0'}, '10.0.0.65': {'10.20.0.254': '0', '10.20.0.131': '0'}} with
2025-10-30 03:15:20,522 - INFO - Initial checks done. Running the testcase now
2025-10-30 03:15:20,522 - INFO -
2025-10-30 03:15:20,799 - DEBUG - Response for create_network : {'network': {'id': '3202ba20-7ed9-4719-a908-90504a20b319', 'name': 'ctest-mgmt_vn-44293277', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'project_id': '3673fdbeddd24ad58881117716dae232', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62376600', 'ctest-mgmt_vn-44293277'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:15:21,079 - DEBUG - Response for create_subnet : {'subnet': {'id': '8af7d65b-7d9d-418a-85ca-687951ccb807', 'name': '', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'network_id': '3202ba20-7ed9-4719-a908-90504a20b319', 'ip_version': 4, 'cidr': '166.189.187.0/26', 'allocation_pools': [{'start': '166.189.187.2', 'end': '166.189.187.62'}], 'gateway_ip': '166.189.187.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.189.187.2', 'tags': [], 'project_id': '3673fdbeddd24ad58881117716dae232'}}
2025-10-30 03:15:21,103 - INFO - Created VN ctest-mgmt_vn-44293277
2025-10-30 03:15:21,116 - DEBUG - VN ctest-mgmt_vn-44293277 UUID is 3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,192 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:21,198 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:21,209 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:21,310 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,322 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,331 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/adc744f1-652a-4236-bbd5-8b09323cceb8
2025-10-30 03:15:21,339 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/adc744f1-652a-4236-bbd5-8b09323cceb8
2025-10-30 03:15:21,346 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/79b2325d-6bed-46ec-ac91-24cbbba2eecf
2025-10-30 03:15:21,353 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:21,353 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,362 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/adc744f1-652a-4236-bbd5-8b09323cceb8
2025-10-30 03:15:21,377 - INFO - Verified VN network id 13 for VN 3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,378 - INFO - Verifications in API Server for VN ctest-mgmt_vn-44293277 passed
2025-10-30 03:15:21,378 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,387 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/adc744f1-652a-4236-bbd5-8b09323cceb8
2025-10-30 03:15:21,394 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/adc744f1-652a-4236-bbd5-8b09323cceb8
2025-10-30 03:15:21,400 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/79b2325d-6bed-46ec-ac91-24cbbba2eecf
2025-10-30 03:15:21,411 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3603647300579706649', 'uuid-lslong': '12180143866738946841'}, 'enable': 'true', 'created': '2025-10-30T03:15:20', 'last-modified': '2025-10-30T03:15:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393315'}
2025-10-30 03:15:21,417 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:21,422 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3603647300579706649', 'uuid-lslong': '12180143866738946841'}, 'enable': 'true', 'created': '2025-10-30T03:15:20', 'last-modified': '2025-10-30T03:15:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409403'}
2025-10-30 03:15:21,427 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:21,434 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3603647300579706649', 'uuid-lslong': '12180143866738946841'}, 'enable': 'true', 'created': '2025-10-30T03:15:20', 'last-modified': '2025-10-30T03:15:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419334'}
2025-10-30 03:15:21,440 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:15:21,440 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-44293277 passed
2025-10-30 03:15:21,441 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-44293277 in API_Server ======
2025-10-30 03:15:21,441 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:21,447 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:21,459 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:21,549 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/3202ba20-7ed9-4719-a908-90504a20b319
2025-10-30 03:15:21,560 - DEBUG - =>VN ctest-mgmt_vn-44293277 has no policy to be verified
2025-10-30 03:15:21,560 - DEBUG - Verifying the vn in opserver
2025-10-30 03:15:21,560 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277 virtual network link through opserver 10.0.0.242
2025-10-30 03:15:21,560 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:21,574 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277?flat'}
2025-10-30 03:15:21,574 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277 virtual network link through opserver 10.0.0.254
2025-10-30 03:15:21,574 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:21,586 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277?flat'}
2025-10-30 03:15:21,586 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277 virtual network link through opserver 10.0.0.240
2025-10-30 03:15:21,586 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:21,601 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277?flat'}
2025-10-30 03:15:21,601 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62376600:ctest-mgmt_vn-44293277 is found in opserver
2025-10-30 03:15:21,622 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:15:21,634 - DEBUG - VRF ids for VN ctest-mgmt_vn-44293277: {}
2025-10-30 03:15:21,924 - DEBUG - Response for create_network : {'network': {'id': '97c76e8f-d6f2-457e-ac13-a7656e84ba8b', 'name': 'ctest-left_vn-72014173', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'project_id': '3673fdbeddd24ad58881117716dae232', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62376600', 'ctest-left_vn-72014173'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:15:22,171 - DEBUG - Response for create_subnet : {'subnet': {'id': '1989a13b-c5e6-461a-8ade-e6ce93d38086', 'name': '', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'network_id': '97c76e8f-d6f2-457e-ac13-a7656e84ba8b', 'ip_version': 4, 'cidr': '31.128.6.128/26', 'allocation_pools': [{'start': '31.128.6.130', 'end': '31.128.6.190'}], 'gateway_ip': '31.128.6.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.128.6.130', 'tags': [], 'project_id': '3673fdbeddd24ad58881117716dae232'}}
2025-10-30 03:15:22,192 - INFO - Created VN ctest-left_vn-72014173
2025-10-30 03:15:22,207 - DEBUG - VN ctest-left_vn-72014173 UUID is 97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,290 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:22,298 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:22,314 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:22,411 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,424 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,434 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/af0d5b89-0881-47b9-a0c7-c6620336b24e
2025-10-30 03:15:22,442 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/af0d5b89-0881-47b9-a0c7-c6620336b24e
2025-10-30 03:15:22,454 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/62dfb5a8-75b3-4440-b68f-12a98386fe29
2025-10-30 03:15:22,462 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:22,462 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,473 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/af0d5b89-0881-47b9-a0c7-c6620336b24e
2025-10-30 03:15:22,489 - INFO - Verified VN network id 14 for VN 97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,489 - INFO - Verifications in API Server for VN ctest-left_vn-72014173 passed
2025-10-30 03:15:22,490 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,499 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/af0d5b89-0881-47b9-a0c7-c6620336b24e
2025-10-30 03:15:22,508 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/af0d5b89-0881-47b9-a0c7-c6620336b24e
2025-10-30 03:15:22,516 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/62dfb5a8-75b3-4440-b68f-12a98386fe29
2025-10-30 03:15:22,530 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10936831784158119294', 'uuid-lslong': '12399438253168835211'}, 'enable': 'true', 'created': '2025-10-30T03:15:21', 'last-modified': '2025-10-30T03:15:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406532'}
2025-10-30 03:15:22,538 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:22,543 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10936831784158119294', 'uuid-lslong': '12399438253168835211'}, 'enable': 'true', 'created': '2025-10-30T03:15:21', 'last-modified': '2025-10-30T03:15:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430840'}
2025-10-30 03:15:22,549 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:22,555 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10936831784158119294', 'uuid-lslong': '12399438253168835211'}, 'enable': 'true', 'created': '2025-10-30T03:15:21', 'last-modified': '2025-10-30T03:15:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443324'}
2025-10-30 03:15:22,561 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:15:22,561 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-72014173 passed
2025-10-30 03:15:22,562 - DEBUG - ====Verifying policy data for ctest-left_vn-72014173 in API_Server ======
2025-10-30 03:15:22,562 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:22,568 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:22,580 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:22,680 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/97c76e8f-d6f2-457e-ac13-a7656e84ba8b
2025-10-30 03:15:22,694 - DEBUG - =>VN ctest-left_vn-72014173 has no policy to be verified
2025-10-30 03:15:22,694 - DEBUG - Verifying the vn in opserver
2025-10-30 03:15:22,694 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173 virtual network link through opserver 10.0.0.242
2025-10-30 03:15:22,694 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:22,707 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173?flat'}
2025-10-30 03:15:22,708 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173 virtual network link through opserver 10.0.0.254
2025-10-30 03:15:22,708 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:22,720 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173?flat'}
2025-10-30 03:15:22,720 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173 virtual network link through opserver 10.0.0.240
2025-10-30 03:15:22,720 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:22,734 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173?flat'}
2025-10-30 03:15:22,734 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62376600:ctest-left_vn-72014173 is found in opserver
2025-10-30 03:15:22,755 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:15:22,765 - DEBUG - VRF ids for VN ctest-left_vn-72014173: {}
2025-10-30 03:15:23,082 - DEBUG - Response for create_network : {'network': {'id': '107c35d0-8026-48a4-8eb7-4ae715173538', 'name': 'ctest-right_vn-80583594', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'project_id': '3673fdbeddd24ad58881117716dae232', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62376600', 'ctest-right_vn-80583594'], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:15:23,328 - DEBUG - Response for create_subnet : {'subnet': {'id': '767bc499-31b3-4711-b4a9-73ed40213a3b', 'name': '', 'tenant_id': '3673fdbeddd24ad58881117716dae232', 'network_id': '107c35d0-8026-48a4-8eb7-4ae715173538', 'ip_version': 4, 'cidr': '166.69.38.192/26', 'allocation_pools': [{'start': '166.69.38.194', 'end': '166.69.38.254'}], 'gateway_ip': '166.69.38.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.69.38.194', 'tags': [], 'project_id': '3673fdbeddd24ad58881117716dae232'}}
2025-10-30 03:15:23,358 - INFO - Created VN ctest-right_vn-80583594
2025-10-30 03:15:23,372 - DEBUG - VN ctest-right_vn-80583594 UUID is 107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,458 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:23,464 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:23,475 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:23,572 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,584 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,593 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d389eb76-24c5-441c-9471-0ddd3d1d0271
2025-10-30 03:15:23,602 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d389eb76-24c5-441c-9471-0ddd3d1d0271
2025-10-30 03:15:23,608 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/aa96a9f6-d83b-46fc-bbdf-a96623c0dda8
2025-10-30 03:15:23,616 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:23,616 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,625 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d389eb76-24c5-441c-9471-0ddd3d1d0271
2025-10-30 03:15:23,643 - INFO - Verified VN network id 15 for VN 107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,643 - INFO - Verifications in API Server for VN ctest-right_vn-80583594 passed
2025-10-30 03:15:23,643 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,653 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d389eb76-24c5-441c-9471-0ddd3d1d0271
2025-10-30 03:15:23,738 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d389eb76-24c5-441c-9471-0ddd3d1d0271
2025-10-30 03:15:23,747 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/aa96a9f6-d83b-46fc-bbdf-a96623c0dda8
2025-10-30 03:15:23,761 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1187883571338430628', 'uuid-lslong': '10283770630475560248'}, 'enable': 'true', 'created': '2025-10-30T03:15:22', 'last-modified': '2025-10-30T03:15:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.504913'}
2025-10-30 03:15:23,767 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:23,772 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1187883571338430628', 'uuid-lslong': '10283770630475560248'}, 'enable': 'true', 'created': '2025-10-30T03:15:22', 'last-modified': '2025-10-30T03:15:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517268'}
2025-10-30 03:15:23,778 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:23,784 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62376600', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1187883571338430628', 'uuid-lslong': '10283770630475560248'}, 'enable': 'true', 'created': '2025-10-30T03:15:22', 'last-modified': '2025-10-30T03:15:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.522555'}
2025-10-30 03:15:23,791 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:15:23,791 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-80583594 passed
2025-10-30 03:15:23,791 - DEBUG - ====Verifying policy data for ctest-right_vn-80583594 in API_Server ======
2025-10-30 03:15:23,791 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:23,798 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:23,810 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:23,922 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/107c35d0-8026-48a4-8eb7-4ae715173538
2025-10-30 03:15:23,934 - DEBUG - =>VN ctest-right_vn-80583594 has no policy to be verified
2025-10-30 03:15:23,934 - DEBUG - Verifying the vn in opserver
2025-10-30 03:15:23,934 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594 virtual network link through opserver 10.0.0.242
2025-10-30 03:15:23,934 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:15:23,946 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594?flat'}
2025-10-30 03:15:23,946 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594 virtual network link through opserver 10.0.0.254
2025-10-30 03:15:23,946 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:15:23,962 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594?flat'}
2025-10-30 03:15:23,962 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594 virtual network link through opserver 10.0.0.240
2025-10-30 03:15:23,962 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:15:23,977 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594?flat'}
2025-10-30 03:15:23,977 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62376600:ctest-right_vn-80583594 is found in opserver
2025-10-30 03:15:23,999 - DEBUG - Do not have enough data to verify VN in agent
2025-10-30 03:15:24,009 - DEBUG - VRF ids for VN ctest-right_vn-80583594: {}
2025-10-30 03:15:25,109 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1)
2025-10-30 03:15:26,958 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2)
2025-10-30 03:15:26,958 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-53407033']
2025-10-30 03:15:27,265 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-53407033'] created succesfully
2025-10-30 03:15:27,564 - DEBUG - Image by name tiny_nat_fw not found
2025-10-30 03:15:27,564 - DEBUG - Installing image tiny_nat_fw
2025-10-30 03:15:27,564 - 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 03:15:27,564 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:15:27,565 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:15:27,565 - DEBUG - Unzip image
2025-10-30 03:15:27,565 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-30 03:15:27,587 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-30 03:15:27,587 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-53407033']
2025-10-30 03:15:27,700 - INFO - Service template: ['default-domain', 'ctest-service_template_1-53407033'] deleted successfully.
2025-10-30 03:15:27,701 - INFO - Skip interface_detach for VM ctest-right_vm-01817843 in state BUILD
2025-10-30 03:15:27,701 - INFO - Deleting VM ctest-right_vm-01817843
2025-10-30 03:15:27,701 - ERROR - VM ctest-right_vm-01817843 has failed to come up
2025-10-30 03:15:27,701 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:27,701 - ERROR - VM ctest-right_vm-01817843 has failed to come up
2025-10-30 03:15:27,702 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:27,803 - INFO - Skip interface_detach for VM ctest-left_vm-27196277 in state BUILD
2025-10-30 03:15:27,803 - INFO - Deleting VM ctest-left_vm-27196277
2025-10-30 03:15:27,803 - ERROR - VM ctest-left_vm-27196277 has failed to come up
2025-10-30 03:15:27,803 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:27,803 - ERROR - VM ctest-left_vm-27196277 has failed to come up
2025-10-30 03:15:27,804 - ERROR - Nova failed to get host of the VM
2025-10-30 03:15:27,931 - INFO - Deleting VN ctest-right_vn-80583594
2025-10-30 03:15:27,984 - DEBUG - VN 107c35d0-8026-48a4-8eb7-4ae715173538 still in use: Unable to complete operation on network 107c35d0-8026-48a4-8eb7-4ae715173538. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b6fd6774-cda2-4fdc-ab32-6b1919939585']
2025-10-30 03:15:27,984 - WARNING - Deleting VN ctest-right_vn-80583594 failed..Will retry
2025-10-30 03:15:30,134 - DEBUG - Response for deleting network ()
2025-10-30 03:15:30,134 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d389eb76-24c5-441c-9471-0ddd3d1d0271
2025-10-30 03:15:30,146 - DEBUG - Response Code: 404
2025-10-30 03:15:30,147 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:30,155 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:30,190 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:30,291 - INFO - Validated that VN ctest-right_vn-80583594 is not found in API Server
2025-10-30 03:15:30,302 - DEBUG - VN ctest-right_vn-80583594 is not present in Agent 10.0.0.129
2025-10-30 03:15:30,311 - DEBUG - VN ctest-right_vn-80583594 is not present in Agent 10.0.0.65
2025-10-30 03:15:30,311 - INFO - Validated that VN ctest-right_vn-80583594 is not in any agent
2025-10-30 03:15:30,340 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-80583594 info
2025-10-30 03:15:30,340 - INFO - Deleting VN ctest-left_vn-72014173
2025-10-30 03:15:30,497 - DEBUG - Response for deleting network ()
2025-10-30 03:15:30,498 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/af0d5b89-0881-47b9-a0c7-c6620336b24e
2025-10-30 03:15:30,504 - DEBUG - Response Code: 404
2025-10-30 03:15:30,504 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:30,519 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:30,540 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:30,642 - INFO - Validated that VN ctest-left_vn-72014173 is not found in API Server
2025-10-30 03:15:30,653 - DEBUG - VN ctest-left_vn-72014173 is not present in Agent 10.0.0.129
2025-10-30 03:15:30,663 - DEBUG - VN ctest-left_vn-72014173 is not present in Agent 10.0.0.65
2025-10-30 03:15:30,663 - INFO - Validated that VN ctest-left_vn-72014173 is not in any agent
2025-10-30 03:15:30,696 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-72014173 info
2025-10-30 03:15:30,696 - INFO - Deleting VN ctest-mgmt_vn-44293277
2025-10-30 03:15:30,854 - DEBUG - Response for deleting network ()
2025-10-30 03:15:30,855 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/adc744f1-652a-4236-bbd5-8b09323cceb8
2025-10-30 03:15:30,860 - DEBUG - Response Code: 404
2025-10-30 03:15:30,860 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:15:30,867 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:15:30,895 - DEBUG - Requesting: http://10.0.0.242:8082/project/3673fdbe-ddd2-4ad5-8881-117716dae232
2025-10-30 03:15:30,985 - INFO - Validated that VN ctest-mgmt_vn-44293277 is not found in API Server
2025-10-30 03:15:30,998 - DEBUG - VN ctest-mgmt_vn-44293277 is not present in Agent 10.0.0.129
2025-10-30 03:15:31,011 - DEBUG - VN ctest-mgmt_vn-44293277 is not present in Agent 10.0.0.65
2025-10-30 03:15:31,011 - INFO - Validated that VN ctest-mgmt_vn-44293277 is not in any agent
2025-10-30 03:15:31,046 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-44293277 info
2025-10-30 03:15:32,329 - DEBUG - No XMPP flaps were noticed during the test
2025-10-30 03:15:32,329 - INFO -
2025-10-30 03:15:32,331 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-10-30 03:15:32,331 - INFO - --------------------------------------------------------------------------------
2025-10-30 03:15:33,087 - INFO - Deleted project: ctest-TestSvcRegr-62376600, ID : 3673fdbe-ddd2-4ad5-8881-117716dae232