]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-2)
2025-11-27 03:03:52,373 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-17930559']
2025-11-27 03:03:52,663 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-17930559'] created succesfully
2025-11-27 03:03:53,011 - DEBUG - Image by name tiny_nat_fw not found
2025-11-27 03:03:53,011 - DEBUG - Installing image tiny_nat_fw
2025-11-27 03:03:53,011 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-11-27 03:03:53,011 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-11-27 03:03:53,011 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-11-27 03:03:53,030 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:03:53,093 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:03:53,094 - DEBUG - Unzip image
2025-11-27 03:03:53,094 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:03:53,126 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-27 03:03:53,127 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-17930559']
2025-11-27 03:03:53,216 - INFO - Service template: ['default-domain', 'ctest-service_template_1-17930559'] deleted successfully.
2025-11-27 03:03:53,216 - INFO - Skip interface_detach for VM ctest-right_vm-98913083 in state BUILD
2025-11-27 03:03:53,216 - INFO - Deleting VM ctest-right_vm-98913083
2025-11-27 03:03:53,251 - ERROR - VM ctest-right_vm-98913083 has failed to come up
2025-11-27 03:03:53,251 - ERROR - Nova failed to get host of the VM
2025-11-27 03:03:53,251 - ERROR - VM ctest-right_vm-98913083 has failed to come up
2025-11-27 03:03:53,251 - ERROR - Nova failed to get host of the VM
2025-11-27 03:03:53,321 - INFO - Skip interface_detach for VM ctest-left_vm-99402685 in state BUILD
2025-11-27 03:03:53,321 - INFO - Deleting VM ctest-left_vm-99402685
2025-11-27 03:03:53,321 - ERROR - VM ctest-left_vm-99402685 has failed to come up
2025-11-27 03:03:53,321 - ERROR - Nova failed to get host of the VM
2025-11-27 03:03:53,321 - ERROR - VM ctest-left_vm-99402685 has failed to come up
2025-11-27 03:03:53,321 - ERROR - Nova failed to get host of the VM
2025-11-27 03:03:53,395 - INFO - Deleting VN ctest-right_vn-96059109
2025-11-27 03:03:53,437 - DEBUG - VN 169146e1-f05d-4408-9c8d-647b0243d00b still in use: Unable to complete operation on network 169146e1-f05d-4408-9c8d-647b0243d00b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-51830380-101e-4d2e-9472-e7436849497c']
2025-11-27 03:03:53,437 - WARNING - Deleting VN ctest-right_vn-96059109 failed..Will retry
2025-11-27 03:03:55,605 - DEBUG - Response for deleting network ()
2025-11-27 03:03:55,605 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c74e7418-ca65-47c4-9fe5-73251a6bd8ff
2025-11-27 03:03:55,610 - DEBUG - Response Code: 404
2025-11-27 03:03:55,610 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:03:55,615 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:03:55,657 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:03:55,756 - INFO - Validated that VN ctest-right_vn-96059109 is not found in API Server
2025-11-27 03:03:55,767 - DEBUG - VN ctest-right_vn-96059109 is not present in Agent 10.0.0.19
2025-11-27 03:03:55,776 - DEBUG - VN ctest-right_vn-96059109 is not present in Agent 10.0.0.130
2025-11-27 03:03:55,776 - INFO - Validated that VN ctest-right_vn-96059109 is not in any agent
2025-11-27 03:03:55,806 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-96059109 info
2025-11-27 03:03:55,807 - INFO - Deleting VN ctest-left_vn-10736172
2025-11-27 03:03:55,982 - DEBUG - Response for deleting network ()
2025-11-27 03:03:55,982 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c57bc791-9f92-4cec-9f7f-629d5889c564
2025-11-27 03:03:55,988 - DEBUG - Response Code: 404
2025-11-27 03:03:55,988 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:03:56,000 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:03:56,012 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:03:56,111 - INFO - Validated that VN ctest-left_vn-10736172 is not found in API Server
2025-11-27 03:03:56,126 - DEBUG - VN ctest-left_vn-10736172 is not present in Agent 10.0.0.19
2025-11-27 03:03:56,138 - DEBUG - VN ctest-left_vn-10736172 is not present in Agent 10.0.0.130
2025-11-27 03:03:56,138 - INFO - Validated that VN ctest-left_vn-10736172 is not in any agent
2025-11-27 03:03:56,179 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-10736172 info
2025-11-27 03:03:56,180 - INFO - Deleting VN ctest-mgmt_vn-49167800
2025-11-27 03:03:56,337 - DEBUG - Response for deleting network ()
2025-11-27 03:03:56,337 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bfa047d0-e53f-4c07-ab77-6f5db6c638bd
2025-11-27 03:03:56,342 - DEBUG - Response Code: 404
2025-11-27 03:03:56,342 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:03:56,347 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:03:56,367 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:03:56,476 - INFO - Validated that VN ctest-mgmt_vn-49167800 is not found in API Server
2025-11-27 03:03:56,490 - DEBUG - VN ctest-mgmt_vn-49167800 is not present in Agent 10.0.0.19
2025-11-27 03:03:56,503 - DEBUG - VN ctest-mgmt_vn-49167800 is not present in Agent 10.0.0.130
2025-11-27 03:03:56,503 - INFO - Validated that VN ctest-mgmt_vn-49167800 is not in any agent
2025-11-27 03:03:56,537 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-49167800 info
2025-11-27 03:03:56,537 - INFO - Deleting VM ctest-vm-in-third-vn-41409812
2025-11-27 03:03:56,602 - INFO - Deleting VN ctest-third-vn-42637994
2025-11-27 03:03:56,641 - DEBUG - VN 9dc9e03f-3665-4ad6-b160-08c5c2ea568a still in use: Unable to complete operation on network 9dc9e03f-3665-4ad6-b160-08c5c2ea568a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a8aa7c04-dc9a-4897-852d-ff868a9f62ab']
2025-11-27 03:03:56,641 - WARNING - Deleting VN ctest-third-vn-42637994 failed..Will retry
2025-11-27 03:03:58,812 - DEBUG - Response for deleting network ()
2025-11-27 03:04:00,096 - DEBUG - No XMPP flaps were noticed during the test
2025-11-27 03:04:00,096 - INFO -
2025-11-27 03:04:00,096 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-11-27 03:04:00,096 - INFO - --------------------------------------------------------------------------------
2025-11-27 03:04:00,099 - INFO - ================================================================================
2025-11-27 03:04:00,099 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-11-27 03:04:01,392 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.192': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.129': '0'}} with
2025-11-27 03:04:01,392 - INFO - Initial checks done. Running the testcase now
2025-11-27 03:04:01,392 - INFO -
2025-11-27 03:04:01,661 - DEBUG - Response for create_network : {'network': {'id': '34ad701d-7dc5-4cf6-9853-bb9591a0faed', 'name': 'ctest-mgmt_vn-58942286', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'project_id': '28648fb2657b431188a44811d548fe10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-31957895', 'ctest-mgmt_vn-58942286'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:01,917 - DEBUG - Response for create_subnet : {'subnet': {'id': '55716a03-d2e1-4ab0-bdd5-696431da1f02', 'name': '', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'network_id': '34ad701d-7dc5-4cf6-9853-bb9591a0faed', 'ip_version': 4, 'cidr': '137.250.189.192/26', 'allocation_pools': [{'start': '137.250.189.194', 'end': '137.250.189.254'}], 'gateway_ip': '137.250.189.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '137.250.189.194', 'tags': [], 'project_id': '28648fb2657b431188a44811d548fe10'}}
2025-11-27 03:04:01,942 - INFO - Created VN ctest-mgmt_vn-58942286
2025-11-27 03:04:01,953 - DEBUG - VN ctest-mgmt_vn-58942286 UUID is 34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,035 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:02,042 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:02,055 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:02,155 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,167 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,177 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/038264a0-5d0f-4cee-9285-5333dae18cd5
2025-11-27 03:04:02,186 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/038264a0-5d0f-4cee-9285-5333dae18cd5
2025-11-27 03:04:02,194 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/737ecd07-e85b-4c5a-9a07-67cc64a110ca
2025-11-27 03:04:02,202 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:02,202 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,211 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/038264a0-5d0f-4cee-9285-5333dae18cd5
2025-11-27 03:04:02,226 - INFO - Verified VN network id 14 for VN 34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,226 - INFO - Verifications in API Server for VN ctest-mgmt_vn-58942286 passed
2025-11-27 03:04:02,226 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,236 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/038264a0-5d0f-4cee-9285-5333dae18cd5
2025-11-27 03:04:02,244 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/038264a0-5d0f-4cee-9285-5333dae18cd5
2025-11-27 03:04:02,252 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/737ecd07-e85b-4c5a-9a07-67cc64a110ca
2025-11-27 03:04:02,265 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3795813332909640950', 'uuid-lslong': '10976322967899798253'}, 'enable': 'true', 'created': '2025-11-27T03:04:01', 'last-modified': '2025-11-27T03:04:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409921'}
2025-11-27 03:04:02,271 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:02,281 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3795813332909640950', 'uuid-lslong': '10976322967899798253'}, 'enable': 'true', 'created': '2025-11-27T03:04:01', 'last-modified': '2025-11-27T03:04:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430130'}
2025-11-27 03:04:02,287 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:02,293 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3795813332909640950', 'uuid-lslong': '10976322967899798253'}, 'enable': 'true', 'created': '2025-11-27T03:04:01', 'last-modified': '2025-11-27T03:04:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441952'}
2025-11-27 03:04:02,299 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:02,299 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-58942286 passed
2025-11-27 03:04:02,299 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-58942286 in API_Server ======
2025-11-27 03:04:02,299 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:02,305 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:02,318 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:02,412 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/34ad701d-7dc5-4cf6-9853-bb9591a0faed
2025-11-27 03:04:02,424 - DEBUG - =>VN ctest-mgmt_vn-58942286 has no policy to be verified
2025-11-27 03:04:02,424 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:02,424 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:02,424 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:02,437 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286?flat'}
2025-11-27 03:04:02,437 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:02,437 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:02,449 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286?flat'}
2025-11-27 03:04:02,449 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:02,449 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:02,462 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286?flat'}
2025-11-27 03:04:02,462 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-31957895:ctest-mgmt_vn-58942286 is found in opserver
2025-11-27 03:04:02,488 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:02,499 - DEBUG - VRF ids for VN ctest-mgmt_vn-58942286: {}
2025-11-27 03:04:02,733 - DEBUG - Response for create_network : {'network': {'id': 'd85b8502-0898-4abe-b319-343c5139946c', 'name': 'ctest-left_vn-53862939', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'project_id': '28648fb2657b431188a44811d548fe10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-31957895', 'ctest-left_vn-53862939'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:02,963 - DEBUG - Response for create_subnet : {'subnet': {'id': '4378b666-71c0-4377-b3ea-52ec913410e2', 'name': '', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'network_id': 'd85b8502-0898-4abe-b319-343c5139946c', 'ip_version': 4, 'cidr': '36.176.49.0/26', 'allocation_pools': [{'start': '36.176.49.2', 'end': '36.176.49.62'}], 'gateway_ip': '36.176.49.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '36.176.49.2', 'tags': [], 'project_id': '28648fb2657b431188a44811d548fe10'}}
2025-11-27 03:04:02,988 - INFO - Created VN ctest-left_vn-53862939
2025-11-27 03:04:03,001 - DEBUG - VN ctest-left_vn-53862939 UUID is d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,065 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:03,071 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:03,083 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:03,177 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,200 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,212 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ef0b21c4-bfb8-4cca-9e5d-3798a00f2bc9
2025-11-27 03:04:03,219 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ef0b21c4-bfb8-4cca-9e5d-3798a00f2bc9
2025-11-27 03:04:03,228 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/c3391760-0d08-4699-8ddf-ecb0d17ec810
2025-11-27 03:04:03,236 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:03,236 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,246 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ef0b21c4-bfb8-4cca-9e5d-3798a00f2bc9
2025-11-27 03:04:03,265 - INFO - Verified VN network id 15 for VN d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,266 - INFO - Verifications in API Server for VN ctest-left_vn-53862939 passed
2025-11-27 03:04:03,267 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,276 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ef0b21c4-bfb8-4cca-9e5d-3798a00f2bc9
2025-11-27 03:04:03,286 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ef0b21c4-bfb8-4cca-9e5d-3798a00f2bc9
2025-11-27 03:04:03,294 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/c3391760-0d08-4699-8ddf-ecb0d17ec810
2025-11-27 03:04:03,312 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15590200778853731006', 'uuid-lslong': '12905403640872277100'}, 'enable': 'true', 'created': '2025-11-27T03:04:02', 'last-modified': '2025-11-27T03:04:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409107'}
2025-11-27 03:04:03,318 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:03,324 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15590200778853731006', 'uuid-lslong': '12905403640872277100'}, 'enable': 'true', 'created': '2025-11-27T03:04:02', 'last-modified': '2025-11-27T03:04:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.434397'}
2025-11-27 03:04:03,330 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:03,336 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15590200778853731006', 'uuid-lslong': '12905403640872277100'}, 'enable': 'true', 'created': '2025-11-27T03:04:02', 'last-modified': '2025-11-27T03:04:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443398'}
2025-11-27 03:04:03,343 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:03,343 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-53862939 passed
2025-11-27 03:04:03,343 - DEBUG - ====Verifying policy data for ctest-left_vn-53862939 in API_Server ======
2025-11-27 03:04:03,343 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:03,350 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:03,364 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:03,460 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d85b8502-0898-4abe-b319-343c5139946c
2025-11-27 03:04:03,471 - DEBUG - =>VN ctest-left_vn-53862939 has no policy to be verified
2025-11-27 03:04:03,471 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:03,471 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:03,471 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:03,483 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939?flat'}
2025-11-27 03:04:03,483 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:03,483 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:03,496 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939?flat'}
2025-11-27 03:04:03,496 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:03,496 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:03,509 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939?flat'}
2025-11-27 03:04:03,509 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-31957895:ctest-left_vn-53862939 is found in opserver
2025-11-27 03:04:03,530 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:03,539 - DEBUG - VRF ids for VN ctest-left_vn-53862939: {}
2025-11-27 03:04:03,842 - DEBUG - Response for create_network : {'network': {'id': '32989541-ff35-4cee-b6ea-2dbd282e5ab6', 'name': 'ctest-right_vn-95555396', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'project_id': '28648fb2657b431188a44811d548fe10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-31957895', 'ctest-right_vn-95555396'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:04,049 - DEBUG - Response for create_subnet : {'subnet': {'id': '39a3ca0f-59e8-451c-b144-3e3d4140ba1f', 'name': '', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'network_id': '32989541-ff35-4cee-b6ea-2dbd282e5ab6', 'ip_version': 4, 'cidr': '112.6.39.192/26', 'allocation_pools': [{'start': '112.6.39.194', 'end': '112.6.39.254'}], 'gateway_ip': '112.6.39.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.6.39.194', 'tags': [], 'project_id': '28648fb2657b431188a44811d548fe10'}}
2025-11-27 03:04:04,071 - INFO - Created VN ctest-right_vn-95555396
2025-11-27 03:04:04,082 - DEBUG - VN ctest-right_vn-95555396 UUID is 32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,157 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:04,163 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:04,175 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:04,277 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,294 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,304 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/071262f3-cbab-444f-80d9-526b5393ab45
2025-11-27 03:04:04,317 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/071262f3-cbab-444f-80d9-526b5393ab45
2025-11-27 03:04:04,331 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/845f58d2-c12a-432e-bc6a-c638ec093067
2025-11-27 03:04:04,343 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:04,343 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,355 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/071262f3-cbab-444f-80d9-526b5393ab45
2025-11-27 03:04:04,377 - INFO - Verified VN network id 16 for VN 32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,378 - INFO - Verifications in API Server for VN ctest-right_vn-95555396 passed
2025-11-27 03:04:04,378 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,391 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/071262f3-cbab-444f-80d9-526b5393ab45
2025-11-27 03:04:04,402 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/071262f3-cbab-444f-80d9-526b5393ab45
2025-11-27 03:04:04,411 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/845f58d2-c12a-432e-bc6a-c638ec093067
2025-11-27 03:04:04,425 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3645828009043512558', 'uuid-lslong': '13180397549899373238'}, 'enable': 'true', 'created': '2025-11-27T03:04:03', 'last-modified': '2025-11-27T03:04:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425945'}
2025-11-27 03:04:04,432 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:04,439 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3645828009043512558', 'uuid-lslong': '13180397549899373238'}, 'enable': 'true', 'created': '2025-11-27T03:04:03', 'last-modified': '2025-11-27T03:04:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437768'}
2025-11-27 03:04:04,446 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:04,453 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3645828009043512558', 'uuid-lslong': '13180397549899373238'}, 'enable': 'true', 'created': '2025-11-27T03:04:03', 'last-modified': '2025-11-27T03:04:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447597'}
2025-11-27 03:04:04,459 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:04,459 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-95555396 passed
2025-11-27 03:04:04,460 - DEBUG - ====Verifying policy data for ctest-right_vn-95555396 in API_Server ======
2025-11-27 03:04:04,460 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:04,466 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:04,477 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:04,564 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/32989541-ff35-4cee-b6ea-2dbd282e5ab6
2025-11-27 03:04:04,575 - DEBUG - =>VN ctest-right_vn-95555396 has no policy to be verified
2025-11-27 03:04:04,576 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:04,576 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:04,576 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:04,589 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396?flat'}
2025-11-27 03:04:04,589 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:04,589 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:04,601 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396?flat'}
2025-11-27 03:04:04,601 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:04,601 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:04,613 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396?flat'}
2025-11-27 03:04:04,613 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-31957895:ctest-right_vn-95555396 is found in opserver
2025-11-27 03:04:04,636 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:04,649 - DEBUG - VRF ids for VN ctest-right_vn-95555396: {}
2025-11-27 03:04:04,891 - DEBUG - Response for create_network : {'network': {'id': '6dc20f14-2847-40eb-995d-e82e1de3f9cd', 'name': 'ctest-trans_left_vn-34090800', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'project_id': '28648fb2657b431188a44811d548fe10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-31957895', 'ctest-trans_left_vn-34090800'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:05,111 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4eb31f4-b427-42ea-939d-9c820548c3eb', 'name': '', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'network_id': '6dc20f14-2847-40eb-995d-e82e1de3f9cd', 'ip_version': 4, 'cidr': '204.101.119.0/26', 'allocation_pools': [{'start': '204.101.119.2', 'end': '204.101.119.62'}], 'gateway_ip': '204.101.119.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '204.101.119.2', 'tags': [], 'project_id': '28648fb2657b431188a44811d548fe10'}}
2025-11-27 03:04:05,137 - INFO - Created VN ctest-trans_left_vn-34090800
2025-11-27 03:04:05,148 - DEBUG - VN ctest-trans_left_vn-34090800 UUID is 6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,227 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:05,243 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:05,256 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:05,359 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,374 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,387 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0bbee2e3-5c6c-4d0d-9508-81cea3024e2a
2025-11-27 03:04:05,396 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0bbee2e3-5c6c-4d0d-9508-81cea3024e2a
2025-11-27 03:04:05,406 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/c97bd2e9-8b9b-4159-ab30-f5442f02239f
2025-11-27 03:04:05,415 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-27 03:04:05,415 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,425 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0bbee2e3-5c6c-4d0d-9508-81cea3024e2a
2025-11-27 03:04:05,440 - INFO - Verified VN network id 17 for VN 6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,440 - INFO - Verifications in API Server for VN ctest-trans_left_vn-34090800 passed
2025-11-27 03:04:05,441 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,453 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0bbee2e3-5c6c-4d0d-9508-81cea3024e2a
2025-11-27 03:04:05,460 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0bbee2e3-5c6c-4d0d-9508-81cea3024e2a
2025-11-27 03:04:05,467 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/c97bd2e9-8b9b-4159-ab30-f5442f02239f
2025-11-27 03:04:05,481 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7908900474865533163', 'uuid-lslong': '11051244345404684749'}, 'enable': 'true', 'created': '2025-11-27T03:04:04', 'last-modified': '2025-11-27T03:04:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406854'}
2025-11-27 03:04:05,488 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-27 03:04:05,494 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7908900474865533163', 'uuid-lslong': '11051244345404684749'}, 'enable': 'true', 'created': '2025-11-27T03:04:04', 'last-modified': '2025-11-27T03:04:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429802'}
2025-11-27 03:04:05,499 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-27 03:04:05,504 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7908900474865533163', 'uuid-lslong': '11051244345404684749'}, 'enable': 'true', 'created': '2025-11-27T03:04:04', 'last-modified': '2025-11-27T03:04:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440338'}
2025-11-27 03:04:05,510 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-27 03:04:05,510 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-34090800 passed
2025-11-27 03:04:05,510 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-34090800 in API_Server ======
2025-11-27 03:04:05,510 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:05,516 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:05,529 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:05,627 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6dc20f14-2847-40eb-995d-e82e1de3f9cd
2025-11-27 03:04:05,636 - DEBUG - =>VN ctest-trans_left_vn-34090800 has no policy to be verified
2025-11-27 03:04:05,636 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:05,636 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:05,636 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:05,648 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800?flat'}
2025-11-27 03:04:05,648 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:05,648 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:05,661 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800?flat'}
2025-11-27 03:04:05,661 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:05,661 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:05,673 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800?flat'}
2025-11-27 03:04:05,673 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-31957895:ctest-trans_left_vn-34090800 is found in opserver
2025-11-27 03:04:05,696 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:05,708 - DEBUG - VRF ids for VN ctest-trans_left_vn-34090800: {}
2025-11-27 03:04:05,953 - DEBUG - Response for create_network : {'network': {'id': '04235b23-cc9f-49fd-8774-3c438d48b1ce', 'name': 'ctest-trans_right_vn-27955466', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'project_id': '28648fb2657b431188a44811d548fe10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-31957895', 'ctest-trans_right_vn-27955466'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:06,245 - DEBUG - Response for create_subnet : {'subnet': {'id': '053ee02a-e7bb-4346-ba4e-fff71013effa', 'name': '', 'tenant_id': '28648fb2657b431188a44811d548fe10', 'network_id': '04235b23-cc9f-49fd-8774-3c438d48b1ce', 'ip_version': 4, 'cidr': '102.202.52.192/26', 'allocation_pools': [{'start': '102.202.52.194', 'end': '102.202.52.254'}], 'gateway_ip': '102.202.52.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.202.52.194', 'tags': [], 'project_id': '28648fb2657b431188a44811d548fe10'}}
2025-11-27 03:04:06,270 - INFO - Created VN ctest-trans_right_vn-27955466
2025-11-27 03:04:06,287 - DEBUG - VN ctest-trans_right_vn-27955466 UUID is 04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,368 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:06,374 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:06,397 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:06,490 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,504 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,513 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcdcddbc-518a-4441-9d60-9b61d5a42f93
2025-11-27 03:04:06,519 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcdcddbc-518a-4441-9d60-9b61d5a42f93
2025-11-27 03:04:06,525 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/22c92259-b341-44ba-8545-e701c0ed572f
2025-11-27 03:04:06,531 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-27 03:04:06,532 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,540 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcdcddbc-518a-4441-9d60-9b61d5a42f93
2025-11-27 03:04:06,556 - INFO - Verified VN network id 18 for VN 04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,556 - INFO - Verifications in API Server for VN ctest-trans_right_vn-27955466 passed
2025-11-27 03:04:06,556 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,566 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcdcddbc-518a-4441-9d60-9b61d5a42f93
2025-11-27 03:04:06,573 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcdcddbc-518a-4441-9d60-9b61d5a42f93
2025-11-27 03:04:06,580 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/22c92259-b341-44ba-8545-e701c0ed572f
2025-11-27 03:04:06,592 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '298182209651558909', 'uuid-lslong': '9760492553249534414'}, 'enable': 'true', 'created': '2025-11-27T03:04:05', 'last-modified': '2025-11-27T03:04:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419969'}
2025-11-27 03:04:06,599 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-27 03:04:06,605 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '298182209651558909', 'uuid-lslong': '9760492553249534414'}, 'enable': 'true', 'created': '2025-11-27T03:04:05', 'last-modified': '2025-11-27T03:04:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.434378'}
2025-11-27 03:04:06,611 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-27 03:04:06,617 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-31957895', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '298182209651558909', 'uuid-lslong': '9760492553249534414'}, 'enable': 'true', 'created': '2025-11-27T03:04:05', 'last-modified': '2025-11-27T03:04:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447804'}
2025-11-27 03:04:06,623 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-27 03:04:06,623 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-27955466 passed
2025-11-27 03:04:06,623 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-27955466 in API_Server ======
2025-11-27 03:04:06,623 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:06,629 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:06,642 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:06,731 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/04235b23-cc9f-49fd-8774-3c438d48b1ce
2025-11-27 03:04:06,740 - DEBUG - =>VN ctest-trans_right_vn-27955466 has no policy to be verified
2025-11-27 03:04:06,740 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:06,740 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:06,741 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:06,752 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466?flat'}
2025-11-27 03:04:06,752 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:06,752 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:06,765 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466?flat'}
2025-11-27 03:04:06,765 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:06,765 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:06,781 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466?flat'}
2025-11-27 03:04:06,781 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-31957895:ctest-trans_right_vn-27955466 is found in opserver
2025-11-27 03:04:06,803 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:06,813 - DEBUG - VRF ids for VN ctest-trans_right_vn-27955466: {}
2025-11-27 03:04:07,840 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-2)
2025-11-27 03:04:09,265 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-1)
2025-11-27 03:04:09,265 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-86868822']
2025-11-27 03:04:09,559 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-86868822'] created succesfully
2025-11-27 03:04:10,021 - DEBUG - Image by name tiny_trans_fw not found
2025-11-27 03:04:10,021 - DEBUG - Installing image tiny_trans_fw
2025-11-27 03:04:10,021 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-11-27 03:04:10,021 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-11-27 03:04:10,021 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-11-27 03:04:10,040 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-27 03:04:10,077 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-27 03:04:10,077 - DEBUG - Unzip image
2025-11-27 03:04:10,077 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-27 03:04:10,099 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-11-27 03:04:10,100 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-86868822']
2025-11-27 03:04:10,212 - INFO - Service template: ['default-domain', 'ctest-service_template_1-86868822'] deleted successfully.
2025-11-27 03:04:10,212 - INFO - Skip interface_detach for VM ctest-right_vm-63848047 in state BUILD
2025-11-27 03:04:10,212 - INFO - Deleting VM ctest-right_vm-63848047
2025-11-27 03:04:10,213 - ERROR - VM ctest-right_vm-63848047 has failed to come up
2025-11-27 03:04:10,213 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:10,213 - ERROR - VM ctest-right_vm-63848047 has failed to come up
2025-11-27 03:04:10,213 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:10,285 - INFO - Skip interface_detach for VM ctest-left_vm-74604707 in state BUILD
2025-11-27 03:04:10,285 - INFO - Deleting VM ctest-left_vm-74604707
2025-11-27 03:04:10,285 - ERROR - VM ctest-left_vm-74604707 has failed to come up
2025-11-27 03:04:10,285 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:10,285 - ERROR - VM ctest-left_vm-74604707 has failed to come up
2025-11-27 03:04:10,285 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:10,370 - INFO - Deleting VN ctest-trans_right_vn-27955466
2025-11-27 03:04:10,512 - DEBUG - Response for deleting network ()
2025-11-27 03:04:10,512 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcdcddbc-518a-4441-9d60-9b61d5a42f93
2025-11-27 03:04:10,518 - DEBUG - Response Code: 404
2025-11-27 03:04:10,518 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:10,525 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:10,549 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:10,644 - INFO - Validated that VN ctest-trans_right_vn-27955466 is not found in API Server
2025-11-27 03:04:10,661 - DEBUG - VN ctest-trans_right_vn-27955466 is not present in Agent 10.0.0.19
2025-11-27 03:04:10,677 - DEBUG - VN ctest-trans_right_vn-27955466 is not present in Agent 10.0.0.130
2025-11-27 03:04:10,677 - INFO - Validated that VN ctest-trans_right_vn-27955466 is not in any agent
2025-11-27 03:04:10,715 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-27955466 info
2025-11-27 03:04:10,715 - INFO - Deleting VN ctest-trans_left_vn-34090800
2025-11-27 03:04:10,868 - DEBUG - Response for deleting network ()
2025-11-27 03:04:10,869 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0bbee2e3-5c6c-4d0d-9508-81cea3024e2a
2025-11-27 03:04:10,874 - DEBUG - Response Code: 404
2025-11-27 03:04:10,874 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:10,885 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:10,902 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:10,994 - INFO - Validated that VN ctest-trans_left_vn-34090800 is not found in API Server
2025-11-27 03:04:11,007 - DEBUG - VN ctest-trans_left_vn-34090800 is not present in Agent 10.0.0.19
2025-11-27 03:04:11,022 - DEBUG - VN ctest-trans_left_vn-34090800 is not present in Agent 10.0.0.130
2025-11-27 03:04:11,022 - INFO - Validated that VN ctest-trans_left_vn-34090800 is not in any agent
2025-11-27 03:04:11,084 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-34090800 info
2025-11-27 03:04:11,085 - INFO - Deleting VN ctest-right_vn-95555396
2025-11-27 03:04:11,136 - DEBUG - VN 32989541-ff35-4cee-b6ea-2dbd282e5ab6 still in use: Unable to complete operation on network 32989541-ff35-4cee-b6ea-2dbd282e5ab6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b702d11c-b1bc-48f3-8525-d30968f6b08b']
2025-11-27 03:04:11,136 - WARNING - Deleting VN ctest-right_vn-95555396 failed..Will retry
2025-11-27 03:04:13,319 - DEBUG - Response for deleting network ()
2025-11-27 03:04:13,319 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/071262f3-cbab-444f-80d9-526b5393ab45
2025-11-27 03:04:13,324 - DEBUG - Response Code: 404
2025-11-27 03:04:13,324 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:13,329 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:13,381 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:13,476 - INFO - Validated that VN ctest-right_vn-95555396 is not found in API Server
2025-11-27 03:04:13,488 - DEBUG - VN ctest-right_vn-95555396 is not present in Agent 10.0.0.19
2025-11-27 03:04:13,499 - DEBUG - VN ctest-right_vn-95555396 is not present in Agent 10.0.0.130
2025-11-27 03:04:13,499 - INFO - Validated that VN ctest-right_vn-95555396 is not in any agent
2025-11-27 03:04:13,529 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-95555396 info
2025-11-27 03:04:13,529 - INFO - Deleting VN ctest-left_vn-53862939
2025-11-27 03:04:13,662 - DEBUG - Response for deleting network ()
2025-11-27 03:04:13,662 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ef0b21c4-bfb8-4cca-9e5d-3798a00f2bc9
2025-11-27 03:04:13,667 - DEBUG - Response Code: 404
2025-11-27 03:04:13,667 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:13,673 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:13,688 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:13,857 - INFO - Validated that VN ctest-left_vn-53862939 is not found in API Server
2025-11-27 03:04:13,870 - DEBUG - VN ctest-left_vn-53862939 is not present in Agent 10.0.0.19
2025-11-27 03:04:13,883 - DEBUG - VN ctest-left_vn-53862939 is not present in Agent 10.0.0.130
2025-11-27 03:04:13,884 - INFO - Validated that VN ctest-left_vn-53862939 is not in any agent
2025-11-27 03:04:13,923 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-53862939 info
2025-11-27 03:04:13,923 - INFO - Deleting VN ctest-mgmt_vn-58942286
2025-11-27 03:04:14,064 - DEBUG - Response for deleting network ()
2025-11-27 03:04:14,064 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/038264a0-5d0f-4cee-9285-5333dae18cd5
2025-11-27 03:04:14,070 - DEBUG - Response Code: 404
2025-11-27 03:04:14,070 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:14,076 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:14,086 - DEBUG - Requesting: http://10.0.0.27:8082/project/28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:14,200 - INFO - Validated that VN ctest-mgmt_vn-58942286 is not found in API Server
2025-11-27 03:04:14,212 - DEBUG - VN ctest-mgmt_vn-58942286 is not present in Agent 10.0.0.19
2025-11-27 03:04:14,234 - DEBUG - VN ctest-mgmt_vn-58942286 is not present in Agent 10.0.0.130
2025-11-27 03:04:14,234 - INFO - Validated that VN ctest-mgmt_vn-58942286 is not in any agent
2025-11-27 03:04:14,275 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-58942286 info
2025-11-27 03:04:15,542 - DEBUG - No XMPP flaps were noticed during the test
2025-11-27 03:04:15,542 - INFO -
2025-11-27 03:04:15,542 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-11-27 03:04:15,542 - INFO - --------------------------------------------------------------------------------
2025-11-27 03:04:16,275 - INFO - Deleted project: ctest-TestSvcRegr-31957895, ID : 28648fb2-657b-4311-88a4-4811d548fe10
2025-11-27 03:04:16,461 - INFO - Domain Default found not creating
2025-11-27 03:04:16,636 - INFO - Project ctest-TestSvcRegr-27047752 not found, creating it
2025-11-27 03:04:17,132 - INFO - Created Project:ctest-TestSvcRegr-27047752, ID : 48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:18,594 - INFO - ================================================================================
2025-11-27 03:04:18,594 - INFO - STARTING TEST : test_svc_CEM_22032
2025-11-27 03:04:19,859 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.192': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.129': '0'}} with
2025-11-27 03:04:19,859 - INFO - Initial checks done. Running the testcase now
2025-11-27 03:04:19,859 - INFO -
2025-11-27 03:04:20,564 - DEBUG - Response for create_network : {'network': {'id': 'edf89b10-880b-4ccf-941e-c00b4c9d3fb5', 'name': 'ctest-mgmt_vn-02593888', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'project_id': '48962ba4c99d4e94b0bf276ad04479f1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27047752', 'ctest-mgmt_vn-02593888'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:20,817 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eab8ad44-4376-4765-9a23-a9148f1c1983', 'name': '', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'network_id': 'edf89b10-880b-4ccf-941e-c00b4c9d3fb5', 'ip_version': 4, 'cidr': '63.120.226.128/26', 'allocation_pools': [{'start': '63.120.226.130', 'end': '63.120.226.190'}], 'gateway_ip': '63.120.226.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.120.226.130', 'tags': [], 'project_id': '48962ba4c99d4e94b0bf276ad04479f1'}}
2025-11-27 03:04:20,839 - INFO - Created VN ctest-mgmt_vn-02593888
2025-11-27 03:04:20,849 - DEBUG - VN ctest-mgmt_vn-02593888 UUID is edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:20,918 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:21,220 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:21,251 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:21,264 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:21,358 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:21,368 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:21,376 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e01d3a3e-50ef-4a70-967b-a54c1b0c96d0
2025-11-27 03:04:21,382 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e01d3a3e-50ef-4a70-967b-a54c1b0c96d0
2025-11-27 03:04:21,388 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/ab67d5fa-533d-458d-a3ad-aeb8b48e7ce0
2025-11-27 03:04:21,394 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:21,394 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:21,402 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e01d3a3e-50ef-4a70-967b-a54c1b0c96d0
2025-11-27 03:04:21,415 - INFO - Verified VN network id 14 for VN edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:21,415 - INFO - Verifications in API Server for VN ctest-mgmt_vn-02593888 passed
2025-11-27 03:04:21,415 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:21,424 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e01d3a3e-50ef-4a70-967b-a54c1b0c96d0
2025-11-27 03:04:21,431 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e01d3a3e-50ef-4a70-967b-a54c1b0c96d0
2025-11-27 03:04:21,437 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/ab67d5fa-533d-458d-a3ad-aeb8b48e7ce0
2025-11-27 03:04:21,449 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17147626076517387471', 'uuid-lslong': '10673179321677201333'}, 'enable': 'true', 'created': '2025-11-27T03:04:20', 'last-modified': '2025-11-27T03:04:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.681682'}
2025-11-27 03:04:21,456 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:21,462 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17147626076517387471', 'uuid-lslong': '10673179321677201333'}, 'enable': 'true', 'created': '2025-11-27T03:04:20', 'last-modified': '2025-11-27T03:04:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.693767'}
2025-11-27 03:04:21,468 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:21,473 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17147626076517387471', 'uuid-lslong': '10673179321677201333'}, 'enable': 'true', 'created': '2025-11-27T03:04:20', 'last-modified': '2025-11-27T03:04:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.705254'}
2025-11-27 03:04:21,478 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:21,478 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-02593888 passed
2025-11-27 03:04:21,479 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-02593888 in API_Server ======
2025-11-27 03:04:21,479 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:21,486 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:21,497 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:21,586 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/edf89b10-880b-4ccf-941e-c00b4c9d3fb5
2025-11-27 03:04:21,598 - DEBUG - =>VN ctest-mgmt_vn-02593888 has no policy to be verified
2025-11-27 03:04:21,598 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:21,598 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:21,598 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:21,901 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:21,973 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888?flat'}
2025-11-27 03:04:21,973 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:21,973 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:22,266 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:22,324 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888?flat'}
2025-11-27 03:04:22,324 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:22,325 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:22,624 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:22,680 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888?flat'}
2025-11-27 03:04:22,680 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-02593888 is found in opserver
2025-11-27 03:04:22,699 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:22,709 - DEBUG - VRF ids for VN ctest-mgmt_vn-02593888: {}
2025-11-27 03:04:22,942 - DEBUG - Response for create_network : {'network': {'id': '48623cc2-38d6-4017-8bcf-0f5edae9bfcf', 'name': 'service-left-vn-33786020', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'project_id': '48962ba4c99d4e94b0bf276ad04479f1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27047752', 'service-left-vn-33786020'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:23,231 - DEBUG - Response for create_subnet : {'subnet': {'id': '03ac12bb-977f-4621-8787-5eab8affedd7', 'name': '', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'network_id': '48623cc2-38d6-4017-8bcf-0f5edae9bfcf', 'ip_version': 4, 'cidr': '68.178.238.128/26', 'allocation_pools': [{'start': '68.178.238.130', 'end': '68.178.238.190'}], 'gateway_ip': '68.178.238.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '68.178.238.130', 'tags': [], 'project_id': '48962ba4c99d4e94b0bf276ad04479f1'}}
2025-11-27 03:04:23,253 - INFO - Created VN service-left-vn-33786020
2025-11-27 03:04:23,262 - DEBUG - VN service-left-vn-33786020 UUID is 48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,335 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:23,341 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:23,351 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:23,443 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,461 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,470 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/750b5706-5f37-48c0-b5b2-c98bf1d88056
2025-11-27 03:04:23,479 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/750b5706-5f37-48c0-b5b2-c98bf1d88056
2025-11-27 03:04:23,485 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7c8031c2-7cd2-4bc2-8be2-bbb997df50fc
2025-11-27 03:04:23,492 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:23,492 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,501 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/750b5706-5f37-48c0-b5b2-c98bf1d88056
2025-11-27 03:04:23,519 - INFO - Verified VN network id 15 for VN 48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,519 - INFO - Verifications in API Server for VN service-left-vn-33786020 passed
2025-11-27 03:04:23,519 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,529 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/750b5706-5f37-48c0-b5b2-c98bf1d88056
2025-11-27 03:04:23,536 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/750b5706-5f37-48c0-b5b2-c98bf1d88056
2025-11-27 03:04:23,544 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7c8031c2-7cd2-4bc2-8be2-bbb997df50fc
2025-11-27 03:04:23,555 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5215798123323342871', 'uuid-lslong': '10074287791525183439'}, 'enable': 'true', 'created': '2025-11-27T03:04:22', 'last-modified': '2025-11-27T03:04:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.390456'}
2025-11-27 03:04:23,560 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:23,567 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5215798123323342871', 'uuid-lslong': '10074287791525183439'}, 'enable': 'true', 'created': '2025-11-27T03:04:22', 'last-modified': '2025-11-27T03:04:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402446'}
2025-11-27 03:04:23,577 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:23,586 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5215798123323342871', 'uuid-lslong': '10074287791525183439'}, 'enable': 'true', 'created': '2025-11-27T03:04:22', 'last-modified': '2025-11-27T03:04:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421530'}
2025-11-27 03:04:23,592 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:23,592 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-33786020 passed
2025-11-27 03:04:23,592 - DEBUG - ====Verifying policy data for service-left-vn-33786020 in API_Server ======
2025-11-27 03:04:23,592 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:23,598 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:23,611 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:23,706 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48623cc2-38d6-4017-8bcf-0f5edae9bfcf
2025-11-27 03:04:23,716 - DEBUG - =>VN service-left-vn-33786020 has no policy to be verified
2025-11-27 03:04:23,716 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:23,716 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:23,716 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:23,790 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020?flat'}
2025-11-27 03:04:23,790 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:23,790 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:23,856 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020?flat'}
2025-11-27 03:04:23,856 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:23,856 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:23,916 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020?flat'}
2025-11-27 03:04:23,916 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27047752:service-left-vn-33786020 is found in opserver
2025-11-27 03:04:23,936 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:23,945 - DEBUG - VRF ids for VN service-left-vn-33786020: {}
2025-11-27 03:04:24,192 - DEBUG - Response for create_network : {'network': {'id': '82de5ab3-fcc2-4984-93ba-5b111ee0158c', 'name': 'service-right-vn-82960264', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'project_id': '48962ba4c99d4e94b0bf276ad04479f1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27047752', 'service-right-vn-82960264'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:24,406 - DEBUG - Response for create_subnet : {'subnet': {'id': '68cbe3ef-b526-4a7a-97fa-2509c97026e5', 'name': '', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'network_id': '82de5ab3-fcc2-4984-93ba-5b111ee0158c', 'ip_version': 4, 'cidr': '142.83.49.192/26', 'allocation_pools': [{'start': '142.83.49.194', 'end': '142.83.49.254'}], 'gateway_ip': '142.83.49.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.83.49.194', 'tags': [], 'project_id': '48962ba4c99d4e94b0bf276ad04479f1'}}
2025-11-27 03:04:24,427 - INFO - Created VN service-right-vn-82960264
2025-11-27 03:04:24,440 - DEBUG - VN service-right-vn-82960264 UUID is 82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,504 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:24,510 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:24,520 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:24,614 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,634 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,643 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/320ef214-b71e-4847-8cc2-bbee0e24011c
2025-11-27 03:04:24,651 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/320ef214-b71e-4847-8cc2-bbee0e24011c
2025-11-27 03:04:24,657 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/d6dbee29-c47d-4700-94b6-2c5b5b920a62
2025-11-27 03:04:24,664 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:24,664 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,671 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/320ef214-b71e-4847-8cc2-bbee0e24011c
2025-11-27 03:04:24,686 - INFO - Verified VN network id 16 for VN 82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,686 - INFO - Verifications in API Server for VN service-right-vn-82960264 passed
2025-11-27 03:04:24,686 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,697 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/320ef214-b71e-4847-8cc2-bbee0e24011c
2025-11-27 03:04:24,706 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/320ef214-b71e-4847-8cc2-bbee0e24011c
2025-11-27 03:04:24,716 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/d6dbee29-c47d-4700-94b6-2c5b5b920a62
2025-11-27 03:04:24,729 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9430074398846634372', 'uuid-lslong': '10644920798334162316'}, 'enable': 'true', 'created': '2025-11-27T03:04:24', 'last-modified': '2025-11-27T03:04:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370935'}
2025-11-27 03:04:24,736 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:24,742 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9430074398846634372', 'uuid-lslong': '10644920798334162316'}, 'enable': 'true', 'created': '2025-11-27T03:04:24', 'last-modified': '2025-11-27T03:04:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384112'}
2025-11-27 03:04:24,747 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:24,753 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9430074398846634372', 'uuid-lslong': '10644920798334162316'}, 'enable': 'true', 'created': '2025-11-27T03:04:24', 'last-modified': '2025-11-27T03:04:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389466'}
2025-11-27 03:04:24,758 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:24,758 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-82960264 passed
2025-11-27 03:04:24,758 - DEBUG - ====Verifying policy data for service-right-vn-82960264 in API_Server ======
2025-11-27 03:04:24,758 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:24,764 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:24,775 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:24,869 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/82de5ab3-fcc2-4984-93ba-5b111ee0158c
2025-11-27 03:04:24,881 - DEBUG - =>VN service-right-vn-82960264 has no policy to be verified
2025-11-27 03:04:24,881 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:24,881 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:24,881 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:24,949 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264?flat'}
2025-11-27 03:04:24,949 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:24,949 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:25,012 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264?flat'}
2025-11-27 03:04:25,012 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:25,012 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:25,072 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264?flat'}
2025-11-27 03:04:25,072 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27047752:service-right-vn-82960264 is found in opserver
2025-11-27 03:04:25,095 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:25,106 - DEBUG - VRF ids for VN service-right-vn-82960264: {}
2025-11-27 03:04:25,170 - DEBUG - Services list from nova: [, , , ]
2025-11-27 03:04:26,205 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-2)
2025-11-27 03:04:27,519 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-1)
2025-11-27 03:04:27,520 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-32852692']
2025-11-27 03:04:27,842 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-32852692'] created succesfully
2025-11-27 03:04:28,280 - DEBUG - Image by name tiny_nat_fw not found
2025-11-27 03:04:28,280 - DEBUG - Installing image tiny_nat_fw
2025-11-27 03:04:28,280 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-11-27 03:04:28,280 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:04:28,280 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:04:28,280 - DEBUG - Unzip image
2025-11-27 03:04:28,280 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:04:28,311 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-27 03:04:28,312 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-32852692']
2025-11-27 03:04:28,504 - INFO - Service template: ['default-domain', 'ctest-service_template_1-32852692'] deleted successfully.
2025-11-27 03:04:28,504 - INFO - Skip interface_detach for VM ctest-right_vm-15323519 in state BUILD
2025-11-27 03:04:28,504 - INFO - Deleting VM ctest-right_vm-15323519
2025-11-27 03:04:28,556 - ERROR - VM ctest-right_vm-15323519 has failed to come up
2025-11-27 03:04:28,556 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:28,557 - ERROR - VM ctest-right_vm-15323519 has failed to come up
2025-11-27 03:04:28,557 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:28,680 - INFO - Skip interface_detach for VM ctest-left_vm-10957468 in state BUILD
2025-11-27 03:04:28,680 - INFO - Deleting VM ctest-left_vm-10957468
2025-11-27 03:04:28,680 - ERROR - VM ctest-left_vm-10957468 has failed to come up
2025-11-27 03:04:28,680 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:28,680 - ERROR - VM ctest-left_vm-10957468 has failed to come up
2025-11-27 03:04:28,680 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:28,853 - INFO - Deleting VN service-right-vn-82960264
2025-11-27 03:04:28,933 - DEBUG - VN 82de5ab3-fcc2-4984-93ba-5b111ee0158c still in use: Unable to complete operation on network 82de5ab3-fcc2-4984-93ba-5b111ee0158c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-30c6b054-90be-4826-a2fd-f7799adde430']
2025-11-27 03:04:28,934 - WARNING - Deleting VN service-right-vn-82960264 failed..Will retry
2025-11-27 03:04:31,085 - DEBUG - Response for deleting network ()
2025-11-27 03:04:31,085 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/320ef214-b71e-4847-8cc2-bbee0e24011c
2025-11-27 03:04:31,091 - DEBUG - Response Code: 404
2025-11-27 03:04:31,091 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:31,104 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:31,122 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:31,223 - INFO - Validated that VN service-right-vn-82960264 is not found in API Server
2025-11-27 03:04:31,234 - DEBUG - VN service-right-vn-82960264 is not present in Agent 10.0.0.19
2025-11-27 03:04:31,245 - DEBUG - VN service-right-vn-82960264 is not present in Agent 10.0.0.130
2025-11-27 03:04:31,245 - INFO - Validated that VN service-right-vn-82960264 is not in any agent
2025-11-27 03:04:31,278 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-82960264 info
2025-11-27 03:04:31,278 - INFO - Deleting VN service-left-vn-33786020
2025-11-27 03:04:31,422 - DEBUG - Response for deleting network ()
2025-11-27 03:04:31,423 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/750b5706-5f37-48c0-b5b2-c98bf1d88056
2025-11-27 03:04:31,432 - DEBUG - Response Code: 404
2025-11-27 03:04:31,433 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:31,445 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:31,467 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:31,552 - INFO - Validated that VN service-left-vn-33786020 is not found in API Server
2025-11-27 03:04:31,566 - DEBUG - VN service-left-vn-33786020 is not present in Agent 10.0.0.19
2025-11-27 03:04:31,578 - DEBUG - VN service-left-vn-33786020 is not present in Agent 10.0.0.130
2025-11-27 03:04:31,578 - INFO - Validated that VN service-left-vn-33786020 is not in any agent
2025-11-27 03:04:31,615 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-33786020 info
2025-11-27 03:04:31,615 - INFO - Deleting VN ctest-mgmt_vn-02593888
2025-11-27 03:04:31,777 - DEBUG - Response for deleting network ()
2025-11-27 03:04:31,778 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e01d3a3e-50ef-4a70-967b-a54c1b0c96d0
2025-11-27 03:04:31,783 - DEBUG - Response Code: 404
2025-11-27 03:04:31,783 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:31,788 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:31,846 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:31,934 - INFO - Validated that VN ctest-mgmt_vn-02593888 is not found in API Server
2025-11-27 03:04:31,944 - DEBUG - VN ctest-mgmt_vn-02593888 is not present in Agent 10.0.0.19
2025-11-27 03:04:31,954 - DEBUG - VN ctest-mgmt_vn-02593888 is not present in Agent 10.0.0.130
2025-11-27 03:04:31,954 - INFO - Validated that VN ctest-mgmt_vn-02593888 is not in any agent
2025-11-27 03:04:31,983 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-02593888 info
2025-11-27 03:04:33,289 - DEBUG - No XMPP flaps were noticed during the test
2025-11-27 03:04:33,289 - INFO -
2025-11-27 03:04:33,289 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-11-27 03:04:33,290 - INFO - --------------------------------------------------------------------------------
2025-11-27 03:04:33,293 - INFO - ================================================================================
2025-11-27 03:04:33,293 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-11-27 03:04:34,612 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.192': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.129': '0'}} with
2025-11-27 03:04:34,612 - INFO - Initial checks done. Running the testcase now
2025-11-27 03:04:34,612 - INFO -
2025-11-27 03:04:34,859 - DEBUG - Response for create_network : {'network': {'id': '79b82de1-4648-4860-b08b-73e274817e99', 'name': 'ctest-mgmt_vn-27730395', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'project_id': '48962ba4c99d4e94b0bf276ad04479f1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27047752', 'ctest-mgmt_vn-27730395'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:35,130 - DEBUG - Response for create_subnet : {'subnet': {'id': '12e55a16-30a2-49db-a345-2f47790c48ae', 'name': '', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'network_id': '79b82de1-4648-4860-b08b-73e274817e99', 'ip_version': 4, 'cidr': '135.96.6.128/26', 'allocation_pools': [{'start': '135.96.6.130', 'end': '135.96.6.190'}], 'gateway_ip': '135.96.6.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.96.6.130', 'tags': [], 'project_id': '48962ba4c99d4e94b0bf276ad04479f1'}}
2025-11-27 03:04:35,153 - INFO - Created VN ctest-mgmt_vn-27730395
2025-11-27 03:04:35,165 - DEBUG - VN ctest-mgmt_vn-27730395 UUID is 79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,234 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:35,240 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:35,251 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:35,342 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,354 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,363 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7c760f9b-bc6f-4073-8757-22f24bcfb74a
2025-11-27 03:04:35,371 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7c760f9b-bc6f-4073-8757-22f24bcfb74a
2025-11-27 03:04:35,379 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e32af7f7-3da1-445b-83f3-02b7d2a472ea
2025-11-27 03:04:35,386 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:35,386 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,396 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7c760f9b-bc6f-4073-8757-22f24bcfb74a
2025-11-27 03:04:35,420 - INFO - Verified VN network id 14 for VN 79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,421 - INFO - Verifications in API Server for VN ctest-mgmt_vn-27730395 passed
2025-11-27 03:04:35,421 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,441 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7c760f9b-bc6f-4073-8757-22f24bcfb74a
2025-11-27 03:04:35,462 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7c760f9b-bc6f-4073-8757-22f24bcfb74a
2025-11-27 03:04:35,469 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e32af7f7-3da1-445b-83f3-02b7d2a472ea
2025-11-27 03:04:35,483 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8770810719874074720', 'uuid-lslong': '12721388988892544665'}, 'enable': 'true', 'created': '2025-11-27T03:04:34', 'last-modified': '2025-11-27T03:04:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397472'}
2025-11-27 03:04:35,488 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:35,496 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8770810719874074720', 'uuid-lslong': '12721388988892544665'}, 'enable': 'true', 'created': '2025-11-27T03:04:34', 'last-modified': '2025-11-27T03:04:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.404860'}
2025-11-27 03:04:35,504 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:35,511 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8770810719874074720', 'uuid-lslong': '12721388988892544665'}, 'enable': 'true', 'created': '2025-11-27T03:04:34', 'last-modified': '2025-11-27T03:04:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426287'}
2025-11-27 03:04:35,517 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-27 03:04:35,517 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-27730395 passed
2025-11-27 03:04:35,517 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-27730395 in API_Server ======
2025-11-27 03:04:35,517 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:35,525 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:35,536 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:35,621 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/79b82de1-4648-4860-b08b-73e274817e99
2025-11-27 03:04:35,632 - DEBUG - =>VN ctest-mgmt_vn-27730395 has no policy to be verified
2025-11-27 03:04:35,632 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:35,633 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:35,633 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:35,645 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395?flat'}
2025-11-27 03:04:35,645 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:35,645 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:35,658 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395?flat'}
2025-11-27 03:04:35,658 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:35,658 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:35,671 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395?flat'}
2025-11-27 03:04:35,671 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27047752:ctest-mgmt_vn-27730395 is found in opserver
2025-11-27 03:04:35,695 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:35,705 - DEBUG - VRF ids for VN ctest-mgmt_vn-27730395: {}
2025-11-27 03:04:35,980 - DEBUG - Response for create_network : {'network': {'id': '27dceabf-2105-4a20-8b9a-8dee1a5e2c9b', 'name': 'ctest-left_vn-81776770', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'project_id': '48962ba4c99d4e94b0bf276ad04479f1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27047752', 'ctest-left_vn-81776770'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:36,255 - DEBUG - Response for create_subnet : {'subnet': {'id': '002402c2-7004-4b62-8021-b66fc5e34602', 'name': '', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'network_id': '27dceabf-2105-4a20-8b9a-8dee1a5e2c9b', 'ip_version': 4, 'cidr': '168.125.198.64/26', 'allocation_pools': [{'start': '168.125.198.66', 'end': '168.125.198.126'}], 'gateway_ip': '168.125.198.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.125.198.66', 'tags': [], 'project_id': '48962ba4c99d4e94b0bf276ad04479f1'}}
2025-11-27 03:04:36,279 - INFO - Created VN ctest-left_vn-81776770
2025-11-27 03:04:36,290 - DEBUG - VN ctest-left_vn-81776770 UUID is 27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,361 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:36,367 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:36,377 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:36,466 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,478 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,487 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6283c27c-647b-44e6-b772-d1b781f705cb
2025-11-27 03:04:36,494 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6283c27c-647b-44e6-b772-d1b781f705cb
2025-11-27 03:04:36,502 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/37b4f99f-84ee-4163-9d47-6a20f58ff3a7
2025-11-27 03:04:36,509 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:36,509 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,518 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6283c27c-647b-44e6-b772-d1b781f705cb
2025-11-27 03:04:36,533 - INFO - Verified VN network id 15 for VN 27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,533 - INFO - Verifications in API Server for VN ctest-left_vn-81776770 passed
2025-11-27 03:04:36,533 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,544 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6283c27c-647b-44e6-b772-d1b781f705cb
2025-11-27 03:04:36,551 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6283c27c-647b-44e6-b772-d1b781f705cb
2025-11-27 03:04:36,559 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/37b4f99f-84ee-4163-9d47-6a20f58ff3a7
2025-11-27 03:04:36,571 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2872428768969181728', 'uuid-lslong': '10059508771469536411'}, 'enable': 'true', 'created': '2025-11-27T03:04:35', 'last-modified': '2025-11-27T03:04:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.373411'}
2025-11-27 03:04:36,578 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:36,584 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2872428768969181728', 'uuid-lslong': '10059508771469536411'}, 'enable': 'true', 'created': '2025-11-27T03:04:35', 'last-modified': '2025-11-27T03:04:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380718'}
2025-11-27 03:04:36,590 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:36,595 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2872428768969181728', 'uuid-lslong': '10059508771469536411'}, 'enable': 'true', 'created': '2025-11-27T03:04:35', 'last-modified': '2025-11-27T03:04:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396629'}
2025-11-27 03:04:36,604 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-27 03:04:36,604 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-81776770 passed
2025-11-27 03:04:36,604 - DEBUG - ====Verifying policy data for ctest-left_vn-81776770 in API_Server ======
2025-11-27 03:04:36,604 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:36,610 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:36,621 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:36,709 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/27dceabf-2105-4a20-8b9a-8dee1a5e2c9b
2025-11-27 03:04:36,723 - DEBUG - =>VN ctest-left_vn-81776770 has no policy to be verified
2025-11-27 03:04:36,723 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:36,723 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:36,723 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:36,735 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770?flat'}
2025-11-27 03:04:36,735 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:36,735 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:36,750 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770?flat'}
2025-11-27 03:04:36,750 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:36,750 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:36,775 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770?flat'}
2025-11-27 03:04:36,779 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27047752:ctest-left_vn-81776770 is found in opserver
2025-11-27 03:04:36,801 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:36,811 - DEBUG - VRF ids for VN ctest-left_vn-81776770: {}
2025-11-27 03:04:37,055 - DEBUG - Response for create_network : {'network': {'id': '2c1544eb-e296-46c2-a6a5-1f361ff24c6b', 'name': 'ctest-right_vn-15203831', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'project_id': '48962ba4c99d4e94b0bf276ad04479f1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27047752', 'ctest-right_vn-15203831'], 'port_security_enabled': True, 'description': ''}}
2025-11-27 03:04:37,248 - DEBUG - Response for create_subnet : {'subnet': {'id': '5bdbe9ff-c523-48ba-ac0f-e196b9425009', 'name': '', 'tenant_id': '48962ba4c99d4e94b0bf276ad04479f1', 'network_id': '2c1544eb-e296-46c2-a6a5-1f361ff24c6b', 'ip_version': 4, 'cidr': '101.216.8.128/26', 'allocation_pools': [{'start': '101.216.8.130', 'end': '101.216.8.190'}], 'gateway_ip': '101.216.8.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '101.216.8.130', 'tags': [], 'project_id': '48962ba4c99d4e94b0bf276ad04479f1'}}
2025-11-27 03:04:37,269 - INFO - Created VN ctest-right_vn-15203831
2025-11-27 03:04:37,279 - DEBUG - VN ctest-right_vn-15203831 UUID is 2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,349 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:37,354 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:37,364 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:37,458 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,468 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,477 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0c72a51b-3207-4f9a-a78b-cfc68899bdff
2025-11-27 03:04:37,489 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0c72a51b-3207-4f9a-a78b-cfc68899bdff
2025-11-27 03:04:37,499 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/34b9ec6d-e1ba-4f10-b440-e971df3b44a1
2025-11-27 03:04:37,508 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:37,508 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,517 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0c72a51b-3207-4f9a-a78b-cfc68899bdff
2025-11-27 03:04:37,533 - INFO - Verified VN network id 16 for VN 2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,533 - INFO - Verifications in API Server for VN ctest-right_vn-15203831 passed
2025-11-27 03:04:37,533 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,545 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0c72a51b-3207-4f9a-a78b-cfc68899bdff
2025-11-27 03:04:37,558 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0c72a51b-3207-4f9a-a78b-cfc68899bdff
2025-11-27 03:04:37,566 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/34b9ec6d-e1ba-4f10-b440-e971df3b44a1
2025-11-27 03:04:37,579 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3176520892089255618', 'uuid-lslong': '12008038298777963627'}, 'enable': 'true', 'created': '2025-11-27T03:04:37', 'last-modified': '2025-11-27T03:04:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378493'}
2025-11-27 03:04:37,584 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:37,589 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3176520892089255618', 'uuid-lslong': '12008038298777963627'}, 'enable': 'true', 'created': '2025-11-27T03:04:37', 'last-modified': '2025-11-27T03:04:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388787'}
2025-11-27 03:04:37,597 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:37,601 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27047752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3176520892089255618', 'uuid-lslong': '12008038298777963627'}, 'enable': 'true', 'created': '2025-11-27T03:04:37', 'last-modified': '2025-11-27T03:04:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403627'}
2025-11-27 03:04:37,607 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-27 03:04:37,607 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-15203831 passed
2025-11-27 03:04:37,607 - DEBUG - ====Verifying policy data for ctest-right_vn-15203831 in API_Server ======
2025-11-27 03:04:37,607 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:37,613 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:37,625 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:37,719 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2c1544eb-e296-46c2-a6a5-1f361ff24c6b
2025-11-27 03:04:37,729 - DEBUG - =>VN ctest-right_vn-15203831 has no policy to be verified
2025-11-27 03:04:37,729 - DEBUG - Verifying the vn in opserver
2025-11-27 03:04:37,729 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831 virtual network link through opserver 10.0.0.27
2025-11-27 03:04:37,729 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-11-27 03:04:37,740 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831?flat'}
2025-11-27 03:04:37,740 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831 virtual network link through opserver 10.0.0.33
2025-11-27 03:04:37,740 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-11-27 03:04:37,752 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831?flat'}
2025-11-27 03:04:37,752 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831 virtual network link through opserver 10.0.0.23
2025-11-27 03:04:37,752 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-27 03:04:37,763 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831?flat'}
2025-11-27 03:04:37,763 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27047752:ctest-right_vn-15203831 is found in opserver
2025-11-27 03:04:37,790 - DEBUG - Do not have enough data to verify VN in agent
2025-11-27 03:04:37,799 - DEBUG - VRF ids for VN ctest-right_vn-15203831: {}
2025-11-27 03:04:38,881 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-1)
2025-11-27 03:04:40,149 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4820-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4820-2)
2025-11-27 03:04:40,149 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-96770871']
2025-11-27 03:04:40,444 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-96770871'] created succesfully
2025-11-27 03:04:40,773 - DEBUG - Image by name tiny_nat_fw not found
2025-11-27 03:04:40,773 - DEBUG - Installing image tiny_nat_fw
2025-11-27 03:04:40,774 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-11-27 03:04:40,774 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:04:40,774 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:04:40,774 - DEBUG - Unzip image
2025-11-27 03:04:40,774 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-27 03:04:40,808 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-27 03:04:40,808 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-96770871']
2025-11-27 03:04:40,916 - INFO - Service template: ['default-domain', 'ctest-service_template_1-96770871'] deleted successfully.
2025-11-27 03:04:40,916 - INFO - Skip interface_detach for VM ctest-right_vm-26602509 in state BUILD
2025-11-27 03:04:40,916 - INFO - Deleting VM ctest-right_vm-26602509
2025-11-27 03:04:40,916 - ERROR - VM ctest-right_vm-26602509 has failed to come up
2025-11-27 03:04:40,916 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:40,916 - ERROR - VM ctest-right_vm-26602509 has failed to come up
2025-11-27 03:04:40,916 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:40,995 - INFO - Skip interface_detach for VM ctest-left_vm-62164161 in state BUILD
2025-11-27 03:04:40,996 - INFO - Deleting VM ctest-left_vm-62164161
2025-11-27 03:04:40,996 - ERROR - VM ctest-left_vm-62164161 has failed to come up
2025-11-27 03:04:40,996 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:40,996 - ERROR - VM ctest-left_vm-62164161 has failed to come up
2025-11-27 03:04:40,996 - ERROR - Nova failed to get host of the VM
2025-11-27 03:04:41,090 - INFO - Deleting VN ctest-right_vn-15203831
2025-11-27 03:04:41,145 - DEBUG - VN 2c1544eb-e296-46c2-a6a5-1f361ff24c6b still in use: Unable to complete operation on network 2c1544eb-e296-46c2-a6a5-1f361ff24c6b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-181c099b-c996-4561-b55c-a78e8532a1de']
2025-11-27 03:04:41,145 - WARNING - Deleting VN ctest-right_vn-15203831 failed..Will retry
2025-11-27 03:04:43,322 - DEBUG - Response for deleting network ()
2025-11-27 03:04:43,322 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0c72a51b-3207-4f9a-a78b-cfc68899bdff
2025-11-27 03:04:43,329 - DEBUG - Response Code: 404
2025-11-27 03:04:43,329 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:43,340 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:43,359 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:43,454 - INFO - Validated that VN ctest-right_vn-15203831 is not found in API Server
2025-11-27 03:04:43,466 - DEBUG - VN ctest-right_vn-15203831 is not present in Agent 10.0.0.19
2025-11-27 03:04:43,477 - DEBUG - VN ctest-right_vn-15203831 is not present in Agent 10.0.0.130
2025-11-27 03:04:43,477 - INFO - Validated that VN ctest-right_vn-15203831 is not in any agent
2025-11-27 03:04:43,507 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-15203831 info
2025-11-27 03:04:43,508 - INFO - Deleting VN ctest-left_vn-81776770
2025-11-27 03:04:43,671 - DEBUG - Response for deleting network ()
2025-11-27 03:04:43,671 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6283c27c-647b-44e6-b772-d1b781f705cb
2025-11-27 03:04:43,677 - DEBUG - Response Code: 404
2025-11-27 03:04:43,677 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:43,690 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:43,745 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:43,831 - INFO - Validated that VN ctest-left_vn-81776770 is not found in API Server
2025-11-27 03:04:43,842 - DEBUG - VN ctest-left_vn-81776770 is not present in Agent 10.0.0.19
2025-11-27 03:04:43,852 - DEBUG - VN ctest-left_vn-81776770 is not present in Agent 10.0.0.130
2025-11-27 03:04:43,852 - INFO - Validated that VN ctest-left_vn-81776770 is not in any agent
2025-11-27 03:04:43,883 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-81776770 info
2025-11-27 03:04:43,883 - INFO - Deleting VN ctest-mgmt_vn-27730395
2025-11-27 03:04:44,022 - DEBUG - Response for deleting network ()
2025-11-27 03:04:44,022 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7c760f9b-bc6f-4073-8757-22f24bcfb74a
2025-11-27 03:04:44,028 - DEBUG - Response Code: 404
2025-11-27 03:04:44,028 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-11-27 03:04:44,038 - DEBUG - Requesting: http://10.0.0.27:8082/domain/ea95f9c6-3b7f-4f10-9ecb-80826711f59a
2025-11-27 03:04:44,052 - DEBUG - Requesting: http://10.0.0.27:8082/project/48962ba4-c99d-4e94-b0bf-276ad04479f1
2025-11-27 03:04:44,152 - INFO - Validated that VN ctest-mgmt_vn-27730395 is not found in API Server
2025-11-27 03:04:44,169 - DEBUG - VN ctest-mgmt_vn-27730395 is not present in Agent 10.0.0.19
2025-11-27 03:04:44,180 - DEBUG - VN ctest-mgmt_vn-27730395 is not present in Agent 10.0.0.130
2025-11-27 03:04:44,181 - INFO - Validated that VN ctest-mgmt_vn-27730395 is not in any agent
2025-11-27 03:04:44,214 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-27730395 info
2025-11-27 03:04:45,486 - DEBUG - No XMPP flaps were noticed during the test
2025-11-27 03:04:45,486 - INFO -
2025-11-27 03:04:45,486 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-11-27 03:04:45,486 - INFO - --------------------------------------------------------------------------------
2025-11-27 03:04:46,193 - INFO - Deleted project: ctest-TestSvcRegr-27047752, ID : 48962ba4-c99d-4e94-b0bf-276ad04479f1