]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-2)
2026-05-27 17:13:14,887 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-50681590']
2026-05-27 17:13:15,230 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-50681590'] created succesfully
2026-05-27 17:13:15,680 - DEBUG - Image by name tiny_nat_fw not found
2026-05-27 17:13:15,680 - DEBUG - Installing image tiny_nat_fw
2026-05-27 17:13:15,680 - 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'"}
2026-05-27 17:13:15,680 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:15,681 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:15,707 - 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
2026-05-27 17:13:15,778 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:15,779 - DEBUG - Unzip image
2026-05-27 17:13:15,779 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:15,819 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-05-27 17:13:15,820 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-50681590']
2026-05-27 17:13:15,943 - INFO - Service template: ['default-domain', 'ctest-service_template_1-50681590'] deleted successfully.
2026-05-27 17:13:15,944 - INFO - Skip interface_detach for VM ctest-right_vm-67943081 in state BUILD
2026-05-27 17:13:15,944 - INFO - Deleting VM ctest-right_vm-67943081
2026-05-27 17:13:16,011 - ERROR - VM ctest-right_vm-67943081 has failed to come up
2026-05-27 17:13:16,012 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:16,012 - ERROR - VM ctest-right_vm-67943081 has failed to come up
2026-05-27 17:13:16,012 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:16,099 - INFO - Skip interface_detach for VM ctest-left_vm-63954032 in state BUILD
2026-05-27 17:13:16,099 - INFO - Deleting VM ctest-left_vm-63954032
2026-05-27 17:13:16,099 - ERROR - VM ctest-left_vm-63954032 has failed to come up
2026-05-27 17:13:16,099 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:16,099 - ERROR - VM ctest-left_vm-63954032 has failed to come up
2026-05-27 17:13:16,100 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:16,195 - INFO - Deleting VN ctest-right_vn-78019132
2026-05-27 17:13:16,249 - DEBUG - VN 422f699f-cfc6-4a3c-a593-6f913961ddfb still in use: Unable to complete operation on network 422f699f-cfc6-4a3c-a593-6f913961ddfb. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-96d348ba-2d5b-494d-9c3d-b1aaad724f66']
2026-05-27 17:13:16,249 - WARNING - Deleting VN ctest-right_vn-78019132 failed..Will retry
2026-05-27 17:13:18,472 - DEBUG - Response for deleting network ()
2026-05-27 17:13:18,472 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ef27b1b0-7197-4a74-bc1a-fb315fb250ea
2026-05-27 17:13:18,479 - DEBUG - Response Code: 404
2026-05-27 17:13:18,479 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:18,501 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:18,522 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:18,622 - INFO - Validated that VN ctest-right_vn-78019132 is not found in API Server
2026-05-27 17:13:18,635 - DEBUG - VN ctest-right_vn-78019132 is not present in Agent 10.0.0.241
2026-05-27 17:13:18,683 - DEBUG - VN ctest-right_vn-78019132 is not present in Agent 10.0.0.242
2026-05-27 17:13:18,683 - INFO - Validated that VN ctest-right_vn-78019132 is not in any agent
2026-05-27 17:13:18,734 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-78019132 info
2026-05-27 17:13:18,734 - INFO - Deleting VN ctest-left_vn-22191291
2026-05-27 17:13:18,913 - DEBUG - Response for deleting network ()
2026-05-27 17:13:18,914 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/152ab78b-858d-4839-9d6e-fe2d74c38c9c
2026-05-27 17:13:18,922 - DEBUG - Response Code: 404
2026-05-27 17:13:18,922 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:18,928 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:18,966 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:19,086 - INFO - Validated that VN ctest-left_vn-22191291 is not found in API Server
2026-05-27 17:13:19,099 - DEBUG - VN ctest-left_vn-22191291 is not present in Agent 10.0.0.241
2026-05-27 17:13:19,116 - DEBUG - VN ctest-left_vn-22191291 is not present in Agent 10.0.0.242
2026-05-27 17:13:19,116 - INFO - Validated that VN ctest-left_vn-22191291 is not in any agent
2026-05-27 17:13:19,157 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-22191291 info
2026-05-27 17:13:19,157 - INFO - Deleting VN ctest-mgmt_vn-99460452
2026-05-27 17:13:19,335 - DEBUG - Response for deleting network ()
2026-05-27 17:13:19,336 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d32308c1-3e93-4df8-9aec-79e6d48f9b2e
2026-05-27 17:13:19,345 - DEBUG - Response Code: 404
2026-05-27 17:13:19,345 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:19,354 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:19,370 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:19,479 - INFO - Validated that VN ctest-mgmt_vn-99460452 is not found in API Server
2026-05-27 17:13:19,492 - DEBUG - VN ctest-mgmt_vn-99460452 is not present in Agent 10.0.0.241
2026-05-27 17:13:19,517 - DEBUG - VN ctest-mgmt_vn-99460452 is not present in Agent 10.0.0.242
2026-05-27 17:13:19,517 - INFO - Validated that VN ctest-mgmt_vn-99460452 is not in any agent
2026-05-27 17:13:19,557 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-99460452 info
2026-05-27 17:13:19,557 - INFO - Deleting VM ctest-vm-in-third-vn-21975824
2026-05-27 17:13:19,634 - INFO - Deleting VN ctest-third-vn-17972951
2026-05-27 17:13:19,688 - DEBUG - VN afa1400a-c863-40ef-929f-b552e4c370c9 still in use: Unable to complete operation on network afa1400a-c863-40ef-929f-b552e4c370c9. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e1269a0c-ff93-48db-9855-8f5c043c7ef6']
2026-05-27 17:13:19,688 - WARNING - Deleting VN ctest-third-vn-17972951 failed..Will retry
2026-05-27 17:13:21,885 - DEBUG - Response for deleting network ()
2026-05-27 17:13:23,199 - DEBUG - No XMPP flaps were noticed during the test
2026-05-27 17:13:23,199 - INFO -
2026-05-27 17:13:23,199 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:27]
2026-05-27 17:13:23,199 - INFO - --------------------------------------------------------------------------------
2026-05-27 17:13:23,203 - INFO - ================================================================================
2026-05-27 17:13:23,203 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-05-27 17:13:24,491 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.242': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2026-05-27 17:13:24,491 - INFO - Initial checks done. Running the testcase now
2026-05-27 17:13:24,491 - INFO -
2026-05-27 17:13:24,771 - DEBUG - Response for create_network : {'network': {'id': '36928888-20ff-48eb-b667-b266a30c6382', 'name': 'ctest-mgmt_vn-80988702', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'project_id': '6614684d62104e3886d12bf3171ef822', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-52418504', 'ctest-mgmt_vn-80988702'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:25,012 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aa667ea8-aa9b-4a19-981b-003756dac1ac', 'name': '', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'network_id': '36928888-20ff-48eb-b667-b266a30c6382', 'ip_version': 4, 'cidr': '13.175.153.128/26', 'allocation_pools': [{'start': '13.175.153.130', 'end': '13.175.153.190'}], 'gateway_ip': '13.175.153.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.175.153.130', 'tags': [], 'project_id': '6614684d62104e3886d12bf3171ef822'}}
2026-05-27 17:13:25,040 - INFO - Created VN ctest-mgmt_vn-80988702
2026-05-27 17:13:25,056 - DEBUG - VN ctest-mgmt_vn-80988702 UUID is 36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,137 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:25,145 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:25,160 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:25,256 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,279 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,292 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/0176c5c1-b67c-4f1a-ab69-57123ba35156
2026-05-27 17:13:25,301 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/0176c5c1-b67c-4f1a-ab69-57123ba35156
2026-05-27 17:13:25,310 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/8d710fc7-64ca-48e8-b76e-72fc156e749b
2026-05-27 17:13:25,320 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:25,320 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,332 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/0176c5c1-b67c-4f1a-ab69-57123ba35156
2026-05-27 17:13:25,452 - INFO - Verified VN network id 13 for VN 36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,452 - INFO - Verifications in API Server for VN ctest-mgmt_vn-80988702 passed
2026-05-27 17:13:25,452 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,463 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/0176c5c1-b67c-4f1a-ab69-57123ba35156
2026-05-27 17:13:25,472 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/0176c5c1-b67c-4f1a-ab69-57123ba35156
2026-05-27 17:13:25,480 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/8d710fc7-64ca-48e8-b76e-72fc156e749b
2026-05-27 17:13:25,498 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3932355542898395371', 'uuid-lslong': '13143670191395988354'}, 'enable': 'true', 'created': '2026-05-27T17:13:24', 'last-modified': '2026-05-27T17:13:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.560167'}
2026-05-27 17:13:25,504 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:25,511 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3932355542898395371', 'uuid-lslong': '13143670191395988354'}, 'enable': 'true', 'created': '2026-05-27T17:13:24', 'last-modified': '2026-05-27T17:13:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.570594'}
2026-05-27 17:13:25,519 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:25,527 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3932355542898395371', 'uuid-lslong': '13143670191395988354'}, 'enable': 'true', 'created': '2026-05-27T17:13:24', 'last-modified': '2026-05-27T17:13:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.587814'}
2026-05-27 17:13:25,534 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:25,534 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-80988702 passed
2026-05-27 17:13:25,535 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-80988702 in API_Server ======
2026-05-27 17:13:25,535 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:25,542 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:25,556 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:25,655 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/36928888-20ff-48eb-b667-b266a30c6382
2026-05-27 17:13:25,667 - DEBUG - =>VN ctest-mgmt_vn-80988702 has no policy to be verified
2026-05-27 17:13:25,667 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:25,667 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:25,668 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:25,682 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702?flat'}
2026-05-27 17:13:25,682 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:25,682 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:25,713 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702?flat'}
2026-05-27 17:13:25,713 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:25,714 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:25,728 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702?flat'}
2026-05-27 17:13:25,728 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-52418504:ctest-mgmt_vn-80988702 is found in opserver
2026-05-27 17:13:25,751 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:25,762 - DEBUG - VRF ids for VN ctest-mgmt_vn-80988702: {}
2026-05-27 17:13:26,081 - DEBUG - Response for create_network : {'network': {'id': '81b916e5-f0f7-4921-86af-56725db52342', 'name': 'ctest-left_vn-18554160', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'project_id': '6614684d62104e3886d12bf3171ef822', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-52418504', 'ctest-left_vn-18554160'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:26,374 - DEBUG - Response for create_subnet : {'subnet': {'id': '1d63b34f-b0e2-449a-b9ca-0a5f74fd565a', 'name': '', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'network_id': '81b916e5-f0f7-4921-86af-56725db52342', 'ip_version': 4, 'cidr': '66.145.46.192/26', 'allocation_pools': [{'start': '66.145.46.194', 'end': '66.145.46.254'}], 'gateway_ip': '66.145.46.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '66.145.46.194', 'tags': [], 'project_id': '6614684d62104e3886d12bf3171ef822'}}
2026-05-27 17:13:26,400 - INFO - Created VN ctest-left_vn-18554160
2026-05-27 17:13:26,415 - DEBUG - VN ctest-left_vn-18554160 UUID is 81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,503 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:26,510 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:26,523 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:26,621 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,635 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,646 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d6d52ddd-19b7-444a-8d48-47d81cf254d4
2026-05-27 17:13:26,658 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d6d52ddd-19b7-444a-8d48-47d81cf254d4
2026-05-27 17:13:26,666 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b0a750fa-2fa5-4e58-baee-53d4372c0375
2026-05-27 17:13:26,676 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:26,676 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,687 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d6d52ddd-19b7-444a-8d48-47d81cf254d4
2026-05-27 17:13:26,708 - INFO - Verified VN network id 14 for VN 81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,708 - INFO - Verifications in API Server for VN ctest-left_vn-18554160 passed
2026-05-27 17:13:26,708 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,723 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d6d52ddd-19b7-444a-8d48-47d81cf254d4
2026-05-27 17:13:26,732 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d6d52ddd-19b7-444a-8d48-47d81cf254d4
2026-05-27 17:13:26,742 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b0a750fa-2fa5-4e58-baee-53d4372c0375
2026-05-27 17:13:26,759 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9347527678430234913', 'uuid-lslong': '9705070771205120834'}, 'enable': 'true', 'created': '2026-05-27T17:13:25', 'last-modified': '2026-05-27T17:13:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456565'}
2026-05-27 17:13:26,767 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:26,776 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9347527678430234913', 'uuid-lslong': '9705070771205120834'}, 'enable': 'true', 'created': '2026-05-27T17:13:25', 'last-modified': '2026-05-27T17:13:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.467849'}
2026-05-27 17:13:26,783 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:26,789 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9347527678430234913', 'uuid-lslong': '9705070771205120834'}, 'enable': 'true', 'created': '2026-05-27T17:13:25', 'last-modified': '2026-05-27T17:13:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.489765'}
2026-05-27 17:13:26,797 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:26,797 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-18554160 passed
2026-05-27 17:13:26,797 - DEBUG - ====Verifying policy data for ctest-left_vn-18554160 in API_Server ======
2026-05-27 17:13:26,797 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:26,805 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:26,817 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:26,917 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/81b916e5-f0f7-4921-86af-56725db52342
2026-05-27 17:13:26,927 - DEBUG - =>VN ctest-left_vn-18554160 has no policy to be verified
2026-05-27 17:13:26,927 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:26,927 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:26,927 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:26,941 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160?flat'}
2026-05-27 17:13:26,941 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:26,941 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:26,955 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160?flat'}
2026-05-27 17:13:26,955 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:26,955 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:26,969 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160?flat'}
2026-05-27 17:13:26,970 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-52418504:ctest-left_vn-18554160 is found in opserver
2026-05-27 17:13:26,996 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:27,013 - DEBUG - VRF ids for VN ctest-left_vn-18554160: {}
2026-05-27 17:13:27,312 - DEBUG - Response for create_network : {'network': {'id': 'a7be05f2-1a7d-491c-a82b-94a3f4926235', 'name': 'ctest-right_vn-96863687', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'project_id': '6614684d62104e3886d12bf3171ef822', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-52418504', 'ctest-right_vn-96863687'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:27,615 - DEBUG - Response for create_subnet : {'subnet': {'id': 'efd42c84-af2a-4484-b9a8-90862de18a03', 'name': '', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'network_id': 'a7be05f2-1a7d-491c-a82b-94a3f4926235', 'ip_version': 4, 'cidr': '146.104.140.128/26', 'allocation_pools': [{'start': '146.104.140.130', 'end': '146.104.140.190'}], 'gateway_ip': '146.104.140.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.104.140.130', 'tags': [], 'project_id': '6614684d62104e3886d12bf3171ef822'}}
2026-05-27 17:13:27,639 - INFO - Created VN ctest-right_vn-96863687
2026-05-27 17:13:27,651 - DEBUG - VN ctest-right_vn-96863687 UUID is a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:27,740 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:27,747 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:27,761 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:27,859 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:27,872 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:27,884 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5834c50b-dcc1-4942-9c16-b993ca54eb42
2026-05-27 17:13:27,896 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5834c50b-dcc1-4942-9c16-b993ca54eb42
2026-05-27 17:13:27,904 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/5859a6af-8793-424e-8af2-ae9497c2f37e
2026-05-27 17:13:27,914 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:27,914 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:27,925 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5834c50b-dcc1-4942-9c16-b993ca54eb42
2026-05-27 17:13:27,944 - INFO - Verified VN network id 15 for VN a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:27,944 - INFO - Verifications in API Server for VN ctest-right_vn-96863687 passed
2026-05-27 17:13:27,944 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:27,955 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5834c50b-dcc1-4942-9c16-b993ca54eb42
2026-05-27 17:13:27,966 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5834c50b-dcc1-4942-9c16-b993ca54eb42
2026-05-27 17:13:27,976 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/5859a6af-8793-424e-8af2-ae9497c2f37e
2026-05-27 17:13:27,992 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12087104987293632796', 'uuid-lslong': '12117942654274265653'}, 'enable': 'true', 'created': '2026-05-27T17:13:27', 'last-modified': '2026-05-27T17:13:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442940'}
2026-05-27 17:13:28,000 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:28,009 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12087104987293632796', 'uuid-lslong': '12117942654274265653'}, 'enable': 'true', 'created': '2026-05-27T17:13:27', 'last-modified': '2026-05-27T17:13:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458198'}
2026-05-27 17:13:28,017 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:28,024 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12087104987293632796', 'uuid-lslong': '12117942654274265653'}, 'enable': 'true', 'created': '2026-05-27T17:13:27', 'last-modified': '2026-05-27T17:13:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471622'}
2026-05-27 17:13:28,032 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:28,032 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-96863687 passed
2026-05-27 17:13:28,032 - DEBUG - ====Verifying policy data for ctest-right_vn-96863687 in API_Server ======
2026-05-27 17:13:28,032 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:28,039 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:28,052 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:28,158 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a7be05f2-1a7d-491c-a82b-94a3f4926235
2026-05-27 17:13:28,173 - DEBUG - =>VN ctest-right_vn-96863687 has no policy to be verified
2026-05-27 17:13:28,173 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:28,174 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:28,174 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:28,189 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687?flat'}
2026-05-27 17:13:28,190 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:28,190 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:28,214 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687?flat'}
2026-05-27 17:13:28,214 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:28,214 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:28,230 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687?flat'}
2026-05-27 17:13:28,230 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-52418504:ctest-right_vn-96863687 is found in opserver
2026-05-27 17:13:28,260 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:28,275 - DEBUG - VRF ids for VN ctest-right_vn-96863687: {}
2026-05-27 17:13:28,588 - DEBUG - Response for create_network : {'network': {'id': '1561cdb7-8d37-4b7c-bb06-2426567b1634', 'name': 'ctest-trans_left_vn-03349595', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'project_id': '6614684d62104e3886d12bf3171ef822', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-52418504', 'ctest-trans_left_vn-03349595'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:28,847 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ac588629-f69a-4126-bd1f-d8bcf94f172c', 'name': '', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'network_id': '1561cdb7-8d37-4b7c-bb06-2426567b1634', 'ip_version': 4, 'cidr': '193.64.26.128/26', 'allocation_pools': [{'start': '193.64.26.130', 'end': '193.64.26.190'}], 'gateway_ip': '193.64.26.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.64.26.130', 'tags': [], 'project_id': '6614684d62104e3886d12bf3171ef822'}}
2026-05-27 17:13:28,871 - INFO - Created VN ctest-trans_left_vn-03349595
2026-05-27 17:13:28,889 - DEBUG - VN ctest-trans_left_vn-03349595 UUID is 1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:28,968 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:28,976 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:28,988 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:29,089 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:29,106 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:29,117 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/215f6aab-2435-43a7-8c94-30d7d629914b
2026-05-27 17:13:29,126 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/215f6aab-2435-43a7-8c94-30d7d629914b
2026-05-27 17:13:29,135 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/102d779a-9753-450d-b85b-f1e8b131d4ec
2026-05-27 17:13:29,144 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-27 17:13:29,144 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:29,155 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/215f6aab-2435-43a7-8c94-30d7d629914b
2026-05-27 17:13:29,172 - INFO - Verified VN network id 16 for VN 1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:29,172 - INFO - Verifications in API Server for VN ctest-trans_left_vn-03349595 passed
2026-05-27 17:13:29,172 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:29,184 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/215f6aab-2435-43a7-8c94-30d7d629914b
2026-05-27 17:13:29,193 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/215f6aab-2435-43a7-8c94-30d7d629914b
2026-05-27 17:13:29,202 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/102d779a-9753-450d-b85b-f1e8b131d4ec
2026-05-27 17:13:29,215 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1540738735769340796', 'uuid-lslong': '13476498682031052340'}, 'enable': 'true', 'created': '2026-05-27T17:13:28', 'last-modified': '2026-05-27T17:13:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453931'}
2026-05-27 17:13:29,222 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-27 17:13:29,229 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1540738735769340796', 'uuid-lslong': '13476498682031052340'}, 'enable': 'true', 'created': '2026-05-27T17:13:28', 'last-modified': '2026-05-27T17:13:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470183'}
2026-05-27 17:13:29,235 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-27 17:13:29,243 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1540738735769340796', 'uuid-lslong': '13476498682031052340'}, 'enable': 'true', 'created': '2026-05-27T17:13:28', 'last-modified': '2026-05-27T17:13:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.482648'}
2026-05-27 17:13:29,251 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-27 17:13:29,251 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-03349595 passed
2026-05-27 17:13:29,251 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-03349595 in API_Server ======
2026-05-27 17:13:29,251 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:29,258 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:29,271 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:29,383 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/1561cdb7-8d37-4b7c-bb06-2426567b1634
2026-05-27 17:13:29,401 - DEBUG - =>VN ctest-trans_left_vn-03349595 has no policy to be verified
2026-05-27 17:13:29,401 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:29,401 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:29,401 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:29,426 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595?flat'}
2026-05-27 17:13:29,426 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:29,426 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:29,443 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595?flat'}
2026-05-27 17:13:29,443 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:29,443 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:29,459 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595?flat'}
2026-05-27 17:13:29,459 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-52418504:ctest-trans_left_vn-03349595 is found in opserver
2026-05-27 17:13:29,485 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:29,499 - DEBUG - VRF ids for VN ctest-trans_left_vn-03349595: {}
2026-05-27 17:13:29,798 - DEBUG - Response for create_network : {'network': {'id': 'f2c40d6b-e3ef-43a0-966f-09d50785d858', 'name': 'ctest-trans_right_vn-91200199', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'project_id': '6614684d62104e3886d12bf3171ef822', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-52418504', 'ctest-trans_right_vn-91200199'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:30,026 - DEBUG - Response for create_subnet : {'subnet': {'id': '065ddcf0-5cca-4e8c-acc4-18cd34fad1cc', 'name': '', 'tenant_id': '6614684d62104e3886d12bf3171ef822', 'network_id': 'f2c40d6b-e3ef-43a0-966f-09d50785d858', 'ip_version': 4, 'cidr': '169.120.37.0/26', 'allocation_pools': [{'start': '169.120.37.2', 'end': '169.120.37.62'}], 'gateway_ip': '169.120.37.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '169.120.37.2', 'tags': [], 'project_id': '6614684d62104e3886d12bf3171ef822'}}
2026-05-27 17:13:30,051 - INFO - Created VN ctest-trans_right_vn-91200199
2026-05-27 17:13:30,064 - DEBUG - VN ctest-trans_right_vn-91200199 UUID is f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,152 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:30,160 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:30,176 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:30,279 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,296 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,307 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/94e9757c-9bcb-4333-9a02-2cdf57197996
2026-05-27 17:13:30,318 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/94e9757c-9bcb-4333-9a02-2cdf57197996
2026-05-27 17:13:30,327 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/7af86247-62b0-4c5b-825d-23e047b40220
2026-05-27 17:13:30,335 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-27 17:13:30,335 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,345 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/94e9757c-9bcb-4333-9a02-2cdf57197996
2026-05-27 17:13:30,361 - INFO - Verified VN network id 17 for VN f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,361 - INFO - Verifications in API Server for VN ctest-trans_right_vn-91200199 passed
2026-05-27 17:13:30,361 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,373 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/94e9757c-9bcb-4333-9a02-2cdf57197996
2026-05-27 17:13:30,383 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/94e9757c-9bcb-4333-9a02-2cdf57197996
2026-05-27 17:13:30,393 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/7af86247-62b0-4c5b-825d-23e047b40220
2026-05-27 17:13:30,409 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17493121609650619296', 'uuid-lslong': '10839893638662969432'}, 'enable': 'true', 'created': '2026-05-27T17:13:29', 'last-modified': '2026-05-27T17:13:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446043'}
2026-05-27 17:13:30,418 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-27 17:13:30,426 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17493121609650619296', 'uuid-lslong': '10839893638662969432'}, 'enable': 'true', 'created': '2026-05-27T17:13:29', 'last-modified': '2026-05-27T17:13:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462262'}
2026-05-27 17:13:30,434 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-27 17:13:30,442 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-52418504', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17493121609650619296', 'uuid-lslong': '10839893638662969432'}, 'enable': 'true', 'created': '2026-05-27T17:13:29', 'last-modified': '2026-05-27T17:13:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.479750'}
2026-05-27 17:13:30,451 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-27 17:13:30,451 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-91200199 passed
2026-05-27 17:13:30,451 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-91200199 in API_Server ======
2026-05-27 17:13:30,451 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:30,459 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:30,474 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:30,575 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f2c40d6b-e3ef-43a0-966f-09d50785d858
2026-05-27 17:13:30,592 - DEBUG - =>VN ctest-trans_right_vn-91200199 has no policy to be verified
2026-05-27 17:13:30,592 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:30,592 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:30,592 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:30,604 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199?flat'}
2026-05-27 17:13:30,604 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:30,604 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:30,620 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199?flat'}
2026-05-27 17:13:30,620 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:30,620 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:30,635 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199?flat'}
2026-05-27 17:13:30,635 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-52418504:ctest-trans_right_vn-91200199 is found in opserver
2026-05-27 17:13:30,661 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:30,672 - DEBUG - VRF ids for VN ctest-trans_right_vn-91200199: {}
2026-05-27 17:13:32,112 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-2)
2026-05-27 17:13:33,530 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-1)
2026-05-27 17:13:33,531 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-91389517']
2026-05-27 17:13:33,840 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-91389517'] created succesfully
2026-05-27 17:13:34,226 - DEBUG - Image by name tiny_trans_fw not found
2026-05-27 17:13:34,226 - DEBUG - Installing image tiny_trans_fw
2026-05-27 17:13:34,226 - 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'}
2026-05-27 17:13:34,227 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-05-27 17:13:34,227 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-05-27 17:13:34,251 - 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
2026-05-27 17:13:34,290 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-05-27 17:13:34,291 - DEBUG - Unzip image
2026-05-27 17:13:34,291 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-05-27 17:13:34,314 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-05-27 17:13:34,314 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-91389517']
2026-05-27 17:13:34,430 - INFO - Service template: ['default-domain', 'ctest-service_template_1-91389517'] deleted successfully.
2026-05-27 17:13:34,430 - INFO - Skip interface_detach for VM ctest-right_vm-67030057 in state BUILD
2026-05-27 17:13:34,430 - INFO - Deleting VM ctest-right_vm-67030057
2026-05-27 17:13:34,430 - ERROR - VM ctest-right_vm-67030057 has failed to come up
2026-05-27 17:13:34,430 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:34,430 - ERROR - VM ctest-right_vm-67030057 has failed to come up
2026-05-27 17:13:34,431 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:34,522 - INFO - Skip interface_detach for VM ctest-left_vm-44894872 in state BUILD
2026-05-27 17:13:34,522 - INFO - Deleting VM ctest-left_vm-44894872
2026-05-27 17:13:34,522 - ERROR - VM ctest-left_vm-44894872 has failed to come up
2026-05-27 17:13:34,522 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:34,522 - ERROR - VM ctest-left_vm-44894872 has failed to come up
2026-05-27 17:13:34,522 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:34,626 - INFO - Deleting VN ctest-trans_right_vn-91200199
2026-05-27 17:13:34,835 - DEBUG - Response for deleting network ()
2026-05-27 17:13:34,836 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/94e9757c-9bcb-4333-9a02-2cdf57197996
2026-05-27 17:13:34,843 - DEBUG - Response Code: 404
2026-05-27 17:13:34,844 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:34,852 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:34,880 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:34,987 - INFO - Validated that VN ctest-trans_right_vn-91200199 is not found in API Server
2026-05-27 17:13:35,010 - DEBUG - VN ctest-trans_right_vn-91200199 is not present in Agent 10.0.0.241
2026-05-27 17:13:35,021 - DEBUG - VN ctest-trans_right_vn-91200199 is not present in Agent 10.0.0.242
2026-05-27 17:13:35,021 - INFO - Validated that VN ctest-trans_right_vn-91200199 is not in any agent
2026-05-27 17:13:35,067 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-91200199 info
2026-05-27 17:13:35,067 - INFO - Deleting VN ctest-trans_left_vn-03349595
2026-05-27 17:13:35,271 - DEBUG - Response for deleting network ()
2026-05-27 17:13:35,272 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/215f6aab-2435-43a7-8c94-30d7d629914b
2026-05-27 17:13:35,280 - DEBUG - Response Code: 404
2026-05-27 17:13:35,280 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:35,289 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:35,303 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:35,408 - INFO - Validated that VN ctest-trans_left_vn-03349595 is not found in API Server
2026-05-27 17:13:35,421 - DEBUG - VN ctest-trans_left_vn-03349595 is not present in Agent 10.0.0.241
2026-05-27 17:13:35,438 - DEBUG - VN ctest-trans_left_vn-03349595 is not present in Agent 10.0.0.242
2026-05-27 17:13:35,438 - INFO - Validated that VN ctest-trans_left_vn-03349595 is not in any agent
2026-05-27 17:13:35,492 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-03349595 info
2026-05-27 17:13:35,492 - INFO - Deleting VN ctest-right_vn-96863687
2026-05-27 17:13:35,540 - DEBUG - VN a7be05f2-1a7d-491c-a82b-94a3f4926235 still in use: Unable to complete operation on network a7be05f2-1a7d-491c-a82b-94a3f4926235. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f529922d-f184-4794-8d4d-037d444e8723']
2026-05-27 17:13:35,540 - WARNING - Deleting VN ctest-right_vn-96863687 failed..Will retry
2026-05-27 17:13:37,729 - DEBUG - Response for deleting network ()
2026-05-27 17:13:37,729 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5834c50b-dcc1-4942-9c16-b993ca54eb42
2026-05-27 17:13:37,737 - DEBUG - Response Code: 404
2026-05-27 17:13:37,737 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:37,749 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:37,766 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:37,869 - INFO - Validated that VN ctest-right_vn-96863687 is not found in API Server
2026-05-27 17:13:37,884 - DEBUG - VN ctest-right_vn-96863687 is not present in Agent 10.0.0.241
2026-05-27 17:13:37,895 - DEBUG - VN ctest-right_vn-96863687 is not present in Agent 10.0.0.242
2026-05-27 17:13:37,895 - INFO - Validated that VN ctest-right_vn-96863687 is not in any agent
2026-05-27 17:13:37,931 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-96863687 info
2026-05-27 17:13:37,931 - INFO - Deleting VN ctest-left_vn-18554160
2026-05-27 17:13:38,105 - DEBUG - Response for deleting network ()
2026-05-27 17:13:38,105 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d6d52ddd-19b7-444a-8d48-47d81cf254d4
2026-05-27 17:13:38,121 - DEBUG - Response Code: 404
2026-05-27 17:13:38,121 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:38,129 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:38,184 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:38,279 - INFO - Validated that VN ctest-left_vn-18554160 is not found in API Server
2026-05-27 17:13:38,301 - DEBUG - VN ctest-left_vn-18554160 is not present in Agent 10.0.0.241
2026-05-27 17:13:38,313 - DEBUG - VN ctest-left_vn-18554160 is not present in Agent 10.0.0.242
2026-05-27 17:13:38,313 - INFO - Validated that VN ctest-left_vn-18554160 is not in any agent
2026-05-27 17:13:38,352 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-18554160 info
2026-05-27 17:13:38,352 - INFO - Deleting VN ctest-mgmt_vn-80988702
2026-05-27 17:13:38,522 - DEBUG - Response for deleting network ()
2026-05-27 17:13:38,522 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/0176c5c1-b67c-4f1a-ab69-57123ba35156
2026-05-27 17:13:38,528 - DEBUG - Response Code: 404
2026-05-27 17:13:38,529 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:38,536 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:38,555 - DEBUG - Requesting: http://10.0.0.50:8082/project/6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:38,651 - INFO - Validated that VN ctest-mgmt_vn-80988702 is not found in API Server
2026-05-27 17:13:38,666 - DEBUG - VN ctest-mgmt_vn-80988702 is not present in Agent 10.0.0.241
2026-05-27 17:13:38,677 - DEBUG - VN ctest-mgmt_vn-80988702 is not present in Agent 10.0.0.242
2026-05-27 17:13:38,677 - INFO - Validated that VN ctest-mgmt_vn-80988702 is not in any agent
2026-05-27 17:13:38,717 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-80988702 info
2026-05-27 17:13:39,993 - DEBUG - No XMPP flaps were noticed during the test
2026-05-27 17:13:39,994 - INFO -
2026-05-27 17:13:39,994 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2026-05-27 17:13:39,994 - INFO - --------------------------------------------------------------------------------
2026-05-27 17:13:40,828 - INFO - Deleted project: ctest-TestSvcRegr-52418504, ID : 6614684d-6210-4e38-86d1-2bf3171ef822
2026-05-27 17:13:41,034 - INFO - Domain Default found not creating
2026-05-27 17:13:41,231 - INFO - Project ctest-TestSvcRegr-79378545 not found, creating it
2026-05-27 17:13:41,804 - INFO - Created Project:ctest-TestSvcRegr-79378545, ID : e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:43,626 - INFO - ================================================================================
2026-05-27 17:13:43,627 - INFO - STARTING TEST : test_svc_CEM_22032
2026-05-27 17:13:44,891 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.242': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2026-05-27 17:13:44,891 - INFO - Initial checks done. Running the testcase now
2026-05-27 17:13:44,891 - INFO -
2026-05-27 17:13:45,637 - DEBUG - Response for create_network : {'network': {'id': '928b15f0-85c4-4972-9b32-6ac7131e6cc2', 'name': 'ctest-mgmt_vn-08881097', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'project_id': 'e06762432a724a628f835ad6f580cbf3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79378545', 'ctest-mgmt_vn-08881097'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:45,916 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c17ac4fd-8806-4862-9373-a4191250568c', 'name': '', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'network_id': '928b15f0-85c4-4972-9b32-6ac7131e6cc2', 'ip_version': 4, 'cidr': '54.239.165.128/26', 'allocation_pools': [{'start': '54.239.165.130', 'end': '54.239.165.190'}], 'gateway_ip': '54.239.165.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '54.239.165.130', 'tags': [], 'project_id': 'e06762432a724a628f835ad6f580cbf3'}}
2026-05-27 17:13:45,945 - INFO - Created VN ctest-mgmt_vn-08881097
2026-05-27 17:13:45,963 - DEBUG - VN ctest-mgmt_vn-08881097 UUID is 928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,055 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:46,360 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:46,394 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:46,410 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:46,517 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,532 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,542 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/18d13fad-2fcf-4692-9349-27e85cab850a
2026-05-27 17:13:46,551 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/18d13fad-2fcf-4692-9349-27e85cab850a
2026-05-27 17:13:46,559 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/bb5d9cb0-570a-4ba8-ab8c-a1e99fd436d5
2026-05-27 17:13:46,567 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:46,567 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,580 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/18d13fad-2fcf-4692-9349-27e85cab850a
2026-05-27 17:13:46,597 - INFO - Verified VN network id 13 for VN 928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,598 - INFO - Verifications in API Server for VN ctest-mgmt_vn-08881097 passed
2026-05-27 17:13:46,598 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,609 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/18d13fad-2fcf-4692-9349-27e85cab850a
2026-05-27 17:13:46,618 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/18d13fad-2fcf-4692-9349-27e85cab850a
2026-05-27 17:13:46,625 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/bb5d9cb0-570a-4ba8-ab8c-a1e99fd436d5
2026-05-27 17:13:46,638 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10559557874080827762', 'uuid-lslong': '11183118227966160066'}, 'enable': 'true', 'created': '2026-05-27T17:13:45', 'last-modified': '2026-05-27T17:13:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.795307'}
2026-05-27 17:13:46,645 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:46,651 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10559557874080827762', 'uuid-lslong': '11183118227966160066'}, 'enable': 'true', 'created': '2026-05-27T17:13:45', 'last-modified': '2026-05-27T17:13:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.808233'}
2026-05-27 17:13:46,657 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:46,663 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10559557874080827762', 'uuid-lslong': '11183118227966160066'}, 'enable': 'true', 'created': '2026-05-27T17:13:45', 'last-modified': '2026-05-27T17:13:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.823362'}
2026-05-27 17:13:46,671 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:13:46,671 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-08881097 passed
2026-05-27 17:13:46,671 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-08881097 in API_Server ======
2026-05-27 17:13:46,671 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:46,679 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:46,690 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:46,786 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/928b15f0-85c4-4972-9b32-6ac7131e6cc2
2026-05-27 17:13:46,797 - DEBUG - =>VN ctest-mgmt_vn-08881097 has no policy to be verified
2026-05-27 17:13:46,797 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:46,797 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:46,797 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:47,129 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:47,243 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097?flat'}
2026-05-27 17:13:47,243 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:47,243 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:47,569 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:47,652 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097?flat'}
2026-05-27 17:13:47,652 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:47,653 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:47,957 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:48,034 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097?flat'}
2026-05-27 17:13:48,034 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-08881097 is found in opserver
2026-05-27 17:13:48,056 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:48,069 - DEBUG - VRF ids for VN ctest-mgmt_vn-08881097: {}
2026-05-27 17:13:48,352 - DEBUG - Response for create_network : {'network': {'id': 'a18c762a-ecf0-46c3-bca9-c2cee5a659fc', 'name': 'service-left-vn-56844881', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'project_id': 'e06762432a724a628f835ad6f580cbf3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79378545', 'service-left-vn-56844881'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:48,643 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b4a0fe7d-c574-4d89-a354-a05ab0d89926', 'name': '', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'network_id': 'a18c762a-ecf0-46c3-bca9-c2cee5a659fc', 'ip_version': 4, 'cidr': '34.8.89.0/26', 'allocation_pools': [{'start': '34.8.89.2', 'end': '34.8.89.62'}], 'gateway_ip': '34.8.89.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '34.8.89.2', 'tags': [], 'project_id': 'e06762432a724a628f835ad6f580cbf3'}}
2026-05-27 17:13:48,669 - INFO - Created VN service-left-vn-56844881
2026-05-27 17:13:48,688 - DEBUG - VN service-left-vn-56844881 UUID is a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:48,772 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:48,779 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:48,793 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:48,898 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:48,910 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:48,921 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87e72a24-85ab-4906-abaa-79fda31395d0
2026-05-27 17:13:48,930 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87e72a24-85ab-4906-abaa-79fda31395d0
2026-05-27 17:13:48,938 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/4fb56c3b-ab4f-48a8-9b5d-89c533b10fc7
2026-05-27 17:13:48,947 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:48,947 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:48,959 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87e72a24-85ab-4906-abaa-79fda31395d0
2026-05-27 17:13:48,977 - INFO - Verified VN network id 14 for VN a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:48,977 - INFO - Verifications in API Server for VN service-left-vn-56844881 passed
2026-05-27 17:13:48,978 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:48,992 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87e72a24-85ab-4906-abaa-79fda31395d0
2026-05-27 17:13:49,002 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87e72a24-85ab-4906-abaa-79fda31395d0
2026-05-27 17:13:49,009 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/4fb56c3b-ab4f-48a8-9b5d-89c533b10fc7
2026-05-27 17:13:49,025 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11640809063581763267', 'uuid-lslong': '13594611144066488828'}, 'enable': 'true', 'created': '2026-05-27T17:13:48', 'last-modified': '2026-05-27T17:13:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.445802'}
2026-05-27 17:13:49,032 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:49,038 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11640809063581763267', 'uuid-lslong': '13594611144066488828'}, 'enable': 'true', 'created': '2026-05-27T17:13:48', 'last-modified': '2026-05-27T17:13:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458693'}
2026-05-27 17:13:49,045 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:49,051 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11640809063581763267', 'uuid-lslong': '13594611144066488828'}, 'enable': 'true', 'created': '2026-05-27T17:13:48', 'last-modified': '2026-05-27T17:13:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.465382'}
2026-05-27 17:13:49,058 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:13:49,058 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-56844881 passed
2026-05-27 17:13:49,058 - DEBUG - ====Verifying policy data for service-left-vn-56844881 in API_Server ======
2026-05-27 17:13:49,058 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:49,064 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:49,078 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:49,205 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/a18c762a-ecf0-46c3-bca9-c2cee5a659fc
2026-05-27 17:13:49,220 - DEBUG - =>VN service-left-vn-56844881 has no policy to be verified
2026-05-27 17:13:49,220 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:49,220 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:49,220 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:49,301 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881?flat'}
2026-05-27 17:13:49,301 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:49,301 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:49,372 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881?flat'}
2026-05-27 17:13:49,372 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:49,372 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:49,444 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881?flat'}
2026-05-27 17:13:49,444 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79378545:service-left-vn-56844881 is found in opserver
2026-05-27 17:13:49,475 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:49,487 - DEBUG - VRF ids for VN service-left-vn-56844881: {}
2026-05-27 17:13:49,794 - DEBUG - Response for create_network : {'network': {'id': '64597b58-8a9e-4394-b120-dda122dd14c0', 'name': 'service-right-vn-62373874', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'project_id': 'e06762432a724a628f835ad6f580cbf3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79378545', 'service-right-vn-62373874'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:13:50,055 - DEBUG - Response for create_subnet : {'subnet': {'id': '17bbbcdf-7346-4e50-8492-5cdfad1b45db', 'name': '', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'network_id': '64597b58-8a9e-4394-b120-dda122dd14c0', 'ip_version': 4, 'cidr': '139.73.91.128/26', 'allocation_pools': [{'start': '139.73.91.130', 'end': '139.73.91.190'}], 'gateway_ip': '139.73.91.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '139.73.91.130', 'tags': [], 'project_id': 'e06762432a724a628f835ad6f580cbf3'}}
2026-05-27 17:13:50,081 - INFO - Created VN service-right-vn-62373874
2026-05-27 17:13:50,095 - DEBUG - VN service-right-vn-62373874 UUID is 64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,181 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:50,188 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:50,203 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:50,297 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,311 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,321 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b94ebab9-5cb7-4d89-aa8d-9366aa187870
2026-05-27 17:13:50,331 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b94ebab9-5cb7-4d89-aa8d-9366aa187870
2026-05-27 17:13:50,340 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/e610c274-ba4b-4159-aa09-bb34d3bb0c96
2026-05-27 17:13:50,349 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:50,349 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,359 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b94ebab9-5cb7-4d89-aa8d-9366aa187870
2026-05-27 17:13:50,377 - INFO - Verified VN network id 15 for VN 64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,377 - INFO - Verifications in API Server for VN service-right-vn-62373874 passed
2026-05-27 17:13:50,377 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,389 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b94ebab9-5cb7-4d89-aa8d-9366aa187870
2026-05-27 17:13:50,399 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b94ebab9-5cb7-4d89-aa8d-9366aa187870
2026-05-27 17:13:50,407 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/e610c274-ba4b-4159-aa09-bb34d3bb0c96
2026-05-27 17:13:50,423 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7230946296933008276', 'uuid-lslong': '12763445028112372928'}, 'enable': 'true', 'created': '2026-05-27T17:13:49', 'last-modified': '2026-05-27T17:13:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.448827'}
2026-05-27 17:13:50,429 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:50,436 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7230946296933008276', 'uuid-lslong': '12763445028112372928'}, 'enable': 'true', 'created': '2026-05-27T17:13:49', 'last-modified': '2026-05-27T17:13:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464607'}
2026-05-27 17:13:50,443 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:50,449 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7230946296933008276', 'uuid-lslong': '12763445028112372928'}, 'enable': 'true', 'created': '2026-05-27T17:13:49', 'last-modified': '2026-05-27T17:13:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470575'}
2026-05-27 17:13:50,456 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:13:50,456 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-62373874 passed
2026-05-27 17:13:50,456 - DEBUG - ====Verifying policy data for service-right-vn-62373874 in API_Server ======
2026-05-27 17:13:50,456 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:50,463 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:50,475 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:50,574 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/64597b58-8a9e-4394-b120-dda122dd14c0
2026-05-27 17:13:50,586 - DEBUG - =>VN service-right-vn-62373874 has no policy to be verified
2026-05-27 17:13:50,586 - DEBUG - Verifying the vn in opserver
2026-05-27 17:13:50,586 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874 virtual network link through opserver 10.0.0.50
2026-05-27 17:13:50,586 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:13:50,660 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874?flat'}
2026-05-27 17:13:50,660 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874 virtual network link through opserver 10.0.0.22
2026-05-27 17:13:50,660 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:13:50,727 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874?flat'}
2026-05-27 17:13:50,727 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874 virtual network link through opserver 10.0.0.129
2026-05-27 17:13:50,727 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:13:50,804 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874?flat'}
2026-05-27 17:13:50,804 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79378545:service-right-vn-62373874 is found in opserver
2026-05-27 17:13:50,837 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:13:50,855 - DEBUG - VRF ids for VN service-right-vn-62373874: {}
2026-05-27 17:13:50,928 - DEBUG - Services list from nova: [, , , ]
2026-05-27 17:13:52,080 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-2)
2026-05-27 17:13:53,689 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-1)
2026-05-27 17:13:53,689 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-68841909']
2026-05-27 17:13:53,995 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-68841909'] created succesfully
2026-05-27 17:13:54,473 - DEBUG - Image by name tiny_nat_fw not found
2026-05-27 17:13:54,473 - DEBUG - Installing image tiny_nat_fw
2026-05-27 17:13:54,473 - 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'"}
2026-05-27 17:13:54,473 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:54,473 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:54,473 - DEBUG - Unzip image
2026-05-27 17:13:54,473 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:13:54,494 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-05-27 17:13:54,494 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-68841909']
2026-05-27 17:13:54,606 - INFO - Service template: ['default-domain', 'ctest-service_template_1-68841909'] deleted successfully.
2026-05-27 17:13:54,606 - INFO - Skip interface_detach for VM ctest-right_vm-50221833 in state BUILD
2026-05-27 17:13:54,607 - INFO - Deleting VM ctest-right_vm-50221833
2026-05-27 17:13:54,651 - ERROR - VM ctest-right_vm-50221833 has failed to come up
2026-05-27 17:13:54,651 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:54,651 - ERROR - VM ctest-right_vm-50221833 has failed to come up
2026-05-27 17:13:54,651 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:54,763 - INFO - Skip interface_detach for VM ctest-left_vm-10648011 in state BUILD
2026-05-27 17:13:54,763 - INFO - Deleting VM ctest-left_vm-10648011
2026-05-27 17:13:54,764 - ERROR - VM ctest-left_vm-10648011 has failed to come up
2026-05-27 17:13:54,764 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:54,764 - ERROR - VM ctest-left_vm-10648011 has failed to come up
2026-05-27 17:13:54,764 - ERROR - Nova failed to get host of the VM
2026-05-27 17:13:54,861 - INFO - Deleting VN service-right-vn-62373874
2026-05-27 17:13:54,921 - DEBUG - VN 64597b58-8a9e-4394-b120-dda122dd14c0 still in use: Unable to complete operation on network 64597b58-8a9e-4394-b120-dda122dd14c0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6b5c0911-a4da-45f3-b726-fb80fbbe78cd']
2026-05-27 17:13:54,921 - WARNING - Deleting VN service-right-vn-62373874 failed..Will retry
2026-05-27 17:13:57,085 - DEBUG - Response for deleting network ()
2026-05-27 17:13:57,085 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b94ebab9-5cb7-4d89-aa8d-9366aa187870
2026-05-27 17:13:57,093 - DEBUG - Response Code: 404
2026-05-27 17:13:57,093 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:57,100 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:57,135 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:57,322 - INFO - Validated that VN service-right-vn-62373874 is not found in API Server
2026-05-27 17:13:57,337 - DEBUG - VN service-right-vn-62373874 is not present in Agent 10.0.0.241
2026-05-27 17:13:57,366 - DEBUG - VN service-right-vn-62373874 is not present in Agent 10.0.0.242
2026-05-27 17:13:57,366 - INFO - Validated that VN service-right-vn-62373874 is not in any agent
2026-05-27 17:13:57,406 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-62373874 info
2026-05-27 17:13:57,406 - INFO - Deleting VN service-left-vn-56844881
2026-05-27 17:13:57,600 - DEBUG - Response for deleting network ()
2026-05-27 17:13:57,600 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87e72a24-85ab-4906-abaa-79fda31395d0
2026-05-27 17:13:57,607 - DEBUG - Response Code: 404
2026-05-27 17:13:57,607 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:57,614 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:57,644 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:57,772 - INFO - Validated that VN service-left-vn-56844881 is not found in API Server
2026-05-27 17:13:57,785 - DEBUG - VN service-left-vn-56844881 is not present in Agent 10.0.0.241
2026-05-27 17:13:57,796 - DEBUG - VN service-left-vn-56844881 is not present in Agent 10.0.0.242
2026-05-27 17:13:57,796 - INFO - Validated that VN service-left-vn-56844881 is not in any agent
2026-05-27 17:13:57,833 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-56844881 info
2026-05-27 17:13:57,833 - INFO - Deleting VN ctest-mgmt_vn-08881097
2026-05-27 17:13:58,017 - DEBUG - Response for deleting network ()
2026-05-27 17:13:58,017 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/18d13fad-2fcf-4692-9349-27e85cab850a
2026-05-27 17:13:58,023 - DEBUG - Response Code: 404
2026-05-27 17:13:58,023 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:13:58,039 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:13:58,057 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:13:58,157 - INFO - Validated that VN ctest-mgmt_vn-08881097 is not found in API Server
2026-05-27 17:13:58,170 - DEBUG - VN ctest-mgmt_vn-08881097 is not present in Agent 10.0.0.241
2026-05-27 17:13:58,181 - DEBUG - VN ctest-mgmt_vn-08881097 is not present in Agent 10.0.0.242
2026-05-27 17:13:58,181 - INFO - Validated that VN ctest-mgmt_vn-08881097 is not in any agent
2026-05-27 17:13:58,218 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-08881097 info
2026-05-27 17:13:59,512 - DEBUG - No XMPP flaps were noticed during the test
2026-05-27 17:13:59,512 - INFO -
2026-05-27 17:13:59,512 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2026-05-27 17:13:59,512 - INFO - --------------------------------------------------------------------------------
2026-05-27 17:13:59,516 - INFO - ================================================================================
2026-05-27 17:13:59,516 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-05-27 17:14:00,814 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.242': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2026-05-27 17:14:00,814 - INFO - Initial checks done. Running the testcase now
2026-05-27 17:14:00,814 - INFO -
2026-05-27 17:14:01,129 - DEBUG - Response for create_network : {'network': {'id': '483d857c-81da-4baf-a481-23d925d4f4fc', 'name': 'ctest-mgmt_vn-00513357', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'project_id': 'e06762432a724a628f835ad6f580cbf3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79378545', 'ctest-mgmt_vn-00513357'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:14:01,427 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b07022d4-dd91-46c0-8138-d48e18b71775', 'name': '', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'network_id': '483d857c-81da-4baf-a481-23d925d4f4fc', 'ip_version': 4, 'cidr': '187.164.198.192/26', 'allocation_pools': [{'start': '187.164.198.194', 'end': '187.164.198.254'}], 'gateway_ip': '187.164.198.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '187.164.198.194', 'tags': [], 'project_id': 'e06762432a724a628f835ad6f580cbf3'}}
2026-05-27 17:14:01,453 - INFO - Created VN ctest-mgmt_vn-00513357
2026-05-27 17:14:01,469 - DEBUG - VN ctest-mgmt_vn-00513357 UUID is 483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,565 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:01,573 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:01,588 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:01,689 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,704 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,714 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/fb299116-1267-437f-8979-8cbf3274e34d
2026-05-27 17:14:01,726 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/fb299116-1267-437f-8979-8cbf3274e34d
2026-05-27 17:14:01,733 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3c3d57ba-583d-4183-8c3b-106c3241844a
2026-05-27 17:14:01,742 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:14:01,742 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,752 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/fb299116-1267-437f-8979-8cbf3274e34d
2026-05-27 17:14:01,770 - INFO - Verified VN network id 13 for VN 483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,770 - INFO - Verifications in API Server for VN ctest-mgmt_vn-00513357 passed
2026-05-27 17:14:01,770 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,780 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/fb299116-1267-437f-8979-8cbf3274e34d
2026-05-27 17:14:01,788 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/fb299116-1267-437f-8979-8cbf3274e34d
2026-05-27 17:14:01,795 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3c3d57ba-583d-4183-8c3b-106c3241844a
2026-05-27 17:14:01,808 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5205463514111167407', 'uuid-lslong': '11853795109765444860'}, 'enable': 'true', 'created': '2026-05-27T17:14:01', 'last-modified': '2026-05-27T17:14:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446411'}
2026-05-27 17:14:01,813 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:14:01,820 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5205463514111167407', 'uuid-lslong': '11853795109765444860'}, 'enable': 'true', 'created': '2026-05-27T17:14:01', 'last-modified': '2026-05-27T17:14:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.465621'}
2026-05-27 17:14:01,826 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:14:01,832 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5205463514111167407', 'uuid-lslong': '11853795109765444860'}, 'enable': 'true', 'created': '2026-05-27T17:14:01', 'last-modified': '2026-05-27T17:14:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.468974'}
2026-05-27 17:14:01,839 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-27 17:14:01,839 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-00513357 passed
2026-05-27 17:14:01,839 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-00513357 in API_Server ======
2026-05-27 17:14:01,839 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:01,847 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:01,859 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:01,955 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/483d857c-81da-4baf-a481-23d925d4f4fc
2026-05-27 17:14:01,970 - DEBUG - =>VN ctest-mgmt_vn-00513357 has no policy to be verified
2026-05-27 17:14:01,970 - DEBUG - Verifying the vn in opserver
2026-05-27 17:14:01,970 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357 virtual network link through opserver 10.0.0.50
2026-05-27 17:14:01,970 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:14:01,984 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357?flat'}
2026-05-27 17:14:01,984 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357 virtual network link through opserver 10.0.0.22
2026-05-27 17:14:01,984 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:14:02,007 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357?flat'}
2026-05-27 17:14:02,007 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357 virtual network link through opserver 10.0.0.129
2026-05-27 17:14:02,007 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:14:02,025 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357?flat'}
2026-05-27 17:14:02,025 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79378545:ctest-mgmt_vn-00513357 is found in opserver
2026-05-27 17:14:02,056 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:14:02,071 - DEBUG - VRF ids for VN ctest-mgmt_vn-00513357: {}
2026-05-27 17:14:02,357 - DEBUG - Response for create_network : {'network': {'id': 'b81c5bfd-9a0e-40a0-a124-282b9d9ebe38', 'name': 'ctest-left_vn-11274948', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'project_id': 'e06762432a724a628f835ad6f580cbf3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79378545', 'ctest-left_vn-11274948'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:14:02,609 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b05a5719-7796-4ce6-a875-03195a228df4', 'name': '', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'network_id': 'b81c5bfd-9a0e-40a0-a124-282b9d9ebe38', 'ip_version': 4, 'cidr': '156.141.49.64/26', 'allocation_pools': [{'start': '156.141.49.66', 'end': '156.141.49.126'}], 'gateway_ip': '156.141.49.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '156.141.49.66', 'tags': [], 'project_id': 'e06762432a724a628f835ad6f580cbf3'}}
2026-05-27 17:14:02,632 - INFO - Created VN ctest-left_vn-11274948
2026-05-27 17:14:02,645 - DEBUG - VN ctest-left_vn-11274948 UUID is b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:02,729 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:02,737 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:02,749 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:02,848 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:02,860 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:02,872 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a362063b-ee22-4eab-93ab-446b8a4ccfd0
2026-05-27 17:14:02,884 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a362063b-ee22-4eab-93ab-446b8a4ccfd0
2026-05-27 17:14:02,891 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/209022e0-7d6e-4b9e-a7e4-df4d6846b72f
2026-05-27 17:14:02,900 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:14:02,900 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:02,912 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a362063b-ee22-4eab-93ab-446b8a4ccfd0
2026-05-27 17:14:02,929 - INFO - Verified VN network id 14 for VN b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:02,929 - INFO - Verifications in API Server for VN ctest-left_vn-11274948 passed
2026-05-27 17:14:02,929 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:02,940 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a362063b-ee22-4eab-93ab-446b8a4ccfd0
2026-05-27 17:14:02,948 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a362063b-ee22-4eab-93ab-446b8a4ccfd0
2026-05-27 17:14:02,957 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/209022e0-7d6e-4b9e-a7e4-df4d6846b72f
2026-05-27 17:14:02,970 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13266579747096117408', 'uuid-lslong': '11611449907061112376'}, 'enable': 'true', 'created': '2026-05-27T17:14:02', 'last-modified': '2026-05-27T17:14:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424416'}
2026-05-27 17:14:02,976 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:14:02,983 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13266579747096117408', 'uuid-lslong': '11611449907061112376'}, 'enable': 'true', 'created': '2026-05-27T17:14:02', 'last-modified': '2026-05-27T17:14:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430357'}
2026-05-27 17:14:02,990 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:14:02,996 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13266579747096117408', 'uuid-lslong': '11611449907061112376'}, 'enable': 'true', 'created': '2026-05-27T17:14:02', 'last-modified': '2026-05-27T17:14:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.452999'}
2026-05-27 17:14:03,004 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-27 17:14:03,005 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-11274948 passed
2026-05-27 17:14:03,005 - DEBUG - ====Verifying policy data for ctest-left_vn-11274948 in API_Server ======
2026-05-27 17:14:03,005 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:03,012 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:03,026 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:03,126 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b81c5bfd-9a0e-40a0-a124-282b9d9ebe38
2026-05-27 17:14:03,138 - DEBUG - =>VN ctest-left_vn-11274948 has no policy to be verified
2026-05-27 17:14:03,138 - DEBUG - Verifying the vn in opserver
2026-05-27 17:14:03,138 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948 virtual network link through opserver 10.0.0.50
2026-05-27 17:14:03,138 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:14:03,153 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948?flat'}
2026-05-27 17:14:03,153 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948 virtual network link through opserver 10.0.0.22
2026-05-27 17:14:03,153 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:14:03,170 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948?flat'}
2026-05-27 17:14:03,170 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948 virtual network link through opserver 10.0.0.129
2026-05-27 17:14:03,170 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:14:03,185 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948?flat'}
2026-05-27 17:14:03,185 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79378545:ctest-left_vn-11274948 is found in opserver
2026-05-27 17:14:03,210 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:14:03,223 - DEBUG - VRF ids for VN ctest-left_vn-11274948: {}
2026-05-27 17:14:03,546 - DEBUG - Response for create_network : {'network': {'id': '871fed02-f66d-4128-bd81-c2718bec8d4a', 'name': 'ctest-right_vn-78127962', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'project_id': 'e06762432a724a628f835ad6f580cbf3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79378545', 'ctest-right_vn-78127962'], 'port_security_enabled': True, 'description': ''}}
2026-05-27 17:14:03,803 - DEBUG - Response for create_subnet : {'subnet': {'id': '3d939e32-0239-481c-a47c-742352efe188', 'name': '', 'tenant_id': 'e06762432a724a628f835ad6f580cbf3', 'network_id': '871fed02-f66d-4128-bd81-c2718bec8d4a', 'ip_version': 4, 'cidr': '2.146.131.0/26', 'allocation_pools': [{'start': '2.146.131.2', 'end': '2.146.131.62'}], 'gateway_ip': '2.146.131.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2.146.131.2', 'tags': [], 'project_id': 'e06762432a724a628f835ad6f580cbf3'}}
2026-05-27 17:14:03,827 - INFO - Created VN ctest-right_vn-78127962
2026-05-27 17:14:03,845 - DEBUG - VN ctest-right_vn-78127962 UUID is 871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:03,924 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:03,931 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:03,944 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:04,035 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:04,048 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:04,058 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/93101c91-c8bb-48e8-ac2f-b472e8298122
2026-05-27 17:14:04,066 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/93101c91-c8bb-48e8-ac2f-b472e8298122
2026-05-27 17:14:04,073 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/110ee042-f52b-4896-ac43-42620bfde68b
2026-05-27 17:14:04,083 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:14:04,083 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:04,094 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/93101c91-c8bb-48e8-ac2f-b472e8298122
2026-05-27 17:14:04,113 - INFO - Verified VN network id 15 for VN 871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:04,113 - INFO - Verifications in API Server for VN ctest-right_vn-78127962 passed
2026-05-27 17:14:04,113 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:04,124 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/93101c91-c8bb-48e8-ac2f-b472e8298122
2026-05-27 17:14:04,133 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/93101c91-c8bb-48e8-ac2f-b472e8298122
2026-05-27 17:14:04,141 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/110ee042-f52b-4896-ac43-42620bfde68b
2026-05-27 17:14:04,155 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9736761516378374440', 'uuid-lslong': '13655409338098683210'}, 'enable': 'true', 'created': '2026-05-27T17:14:03', 'last-modified': '2026-05-27T17:14:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413906'}
2026-05-27 17:14:04,161 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:14:04,168 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9736761516378374440', 'uuid-lslong': '13655409338098683210'}, 'enable': 'true', 'created': '2026-05-27T17:14:03', 'last-modified': '2026-05-27T17:14:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425585'}
2026-05-27 17:14:04,174 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:14:04,180 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79378545', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9736761516378374440', 'uuid-lslong': '13655409338098683210'}, 'enable': 'true', 'created': '2026-05-27T17:14:03', 'last-modified': '2026-05-27T17:14:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432621'}
2026-05-27 17:14:04,186 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-27 17:14:04,186 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-78127962 passed
2026-05-27 17:14:04,186 - DEBUG - ====Verifying policy data for ctest-right_vn-78127962 in API_Server ======
2026-05-27 17:14:04,186 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:04,195 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:04,208 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:04,313 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/871fed02-f66d-4128-bd81-c2718bec8d4a
2026-05-27 17:14:04,327 - DEBUG - =>VN ctest-right_vn-78127962 has no policy to be verified
2026-05-27 17:14:04,327 - DEBUG - Verifying the vn in opserver
2026-05-27 17:14:04,327 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962 virtual network link through opserver 10.0.0.50
2026-05-27 17:14:04,327 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-05-27 17:14:04,342 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962?flat'}
2026-05-27 17:14:04,342 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962 virtual network link through opserver 10.0.0.22
2026-05-27 17:14:04,342 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-27 17:14:04,359 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962?flat'}
2026-05-27 17:14:04,359 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962 virtual network link through opserver 10.0.0.129
2026-05-27 17:14:04,359 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks
2026-05-27 17:14:04,477 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962?flat'}
2026-05-27 17:14:04,477 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79378545:ctest-right_vn-78127962 is found in opserver
2026-05-27 17:14:04,504 - DEBUG - Do not have enough data to verify VN in agent
2026-05-27 17:14:04,515 - DEBUG - VRF ids for VN ctest-right_vn-78127962: {}
2026-05-27 17:14:05,629 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-1)
2026-05-27 17:14:07,064 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5832-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5832-2)
2026-05-27 17:14:07,065 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-77778664']
2026-05-27 17:14:07,410 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-77778664'] created succesfully
2026-05-27 17:14:07,806 - DEBUG - Image by name tiny_nat_fw not found
2026-05-27 17:14:07,806 - DEBUG - Installing image tiny_nat_fw
2026-05-27 17:14:07,806 - 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'"}
2026-05-27 17:14:07,806 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:14:07,807 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:14:07,807 - DEBUG - Unzip image
2026-05-27 17:14:07,807 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-27 17:14:07,824 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-05-27 17:14:07,824 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-77778664']
2026-05-27 17:14:07,946 - INFO - Service template: ['default-domain', 'ctest-service_template_1-77778664'] deleted successfully.
2026-05-27 17:14:07,946 - INFO - Skip interface_detach for VM ctest-right_vm-23203970 in state BUILD
2026-05-27 17:14:07,946 - INFO - Deleting VM ctest-right_vm-23203970
2026-05-27 17:14:07,946 - ERROR - VM ctest-right_vm-23203970 has failed to come up
2026-05-27 17:14:07,946 - ERROR - Nova failed to get host of the VM
2026-05-27 17:14:07,946 - ERROR - VM ctest-right_vm-23203970 has failed to come up
2026-05-27 17:14:07,947 - ERROR - Nova failed to get host of the VM
2026-05-27 17:14:08,028 - INFO - Skip interface_detach for VM ctest-left_vm-37807387 in state BUILD
2026-05-27 17:14:08,028 - INFO - Deleting VM ctest-left_vm-37807387
2026-05-27 17:14:08,028 - ERROR - VM ctest-left_vm-37807387 has failed to come up
2026-05-27 17:14:08,028 - ERROR - Nova failed to get host of the VM
2026-05-27 17:14:08,028 - ERROR - VM ctest-left_vm-37807387 has failed to come up
2026-05-27 17:14:08,028 - ERROR - Nova failed to get host of the VM
2026-05-27 17:14:08,179 - INFO - Deleting VN ctest-right_vn-78127962
2026-05-27 17:14:08,232 - DEBUG - VN 871fed02-f66d-4128-bd81-c2718bec8d4a still in use: Unable to complete operation on network 871fed02-f66d-4128-bd81-c2718bec8d4a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d83f04d3-c348-4d71-b1c0-6e32b71bcb9d']
2026-05-27 17:14:08,232 - WARNING - Deleting VN ctest-right_vn-78127962 failed..Will retry
2026-05-27 17:14:10,440 - DEBUG - Response for deleting network ()
2026-05-27 17:14:10,440 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/93101c91-c8bb-48e8-ac2f-b472e8298122
2026-05-27 17:14:10,447 - DEBUG - Response Code: 404
2026-05-27 17:14:10,447 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:10,460 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:10,500 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:10,630 - INFO - Validated that VN ctest-right_vn-78127962 is not found in API Server
2026-05-27 17:14:10,647 - DEBUG - VN ctest-right_vn-78127962 is not present in Agent 10.0.0.241
2026-05-27 17:14:10,658 - DEBUG - VN ctest-right_vn-78127962 is not present in Agent 10.0.0.242
2026-05-27 17:14:10,658 - INFO - Validated that VN ctest-right_vn-78127962 is not in any agent
2026-05-27 17:14:10,714 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-78127962 info
2026-05-27 17:14:10,714 - INFO - Deleting VN ctest-left_vn-11274948
2026-05-27 17:14:10,928 - DEBUG - Response for deleting network ()
2026-05-27 17:14:10,928 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a362063b-ee22-4eab-93ab-446b8a4ccfd0
2026-05-27 17:14:10,936 - DEBUG - Response Code: 404
2026-05-27 17:14:10,936 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:10,944 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:10,969 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:11,086 - INFO - Validated that VN ctest-left_vn-11274948 is not found in API Server
2026-05-27 17:14:11,102 - DEBUG - VN ctest-left_vn-11274948 is not present in Agent 10.0.0.241
2026-05-27 17:14:11,115 - DEBUG - VN ctest-left_vn-11274948 is not present in Agent 10.0.0.242
2026-05-27 17:14:11,115 - INFO - Validated that VN ctest-left_vn-11274948 is not in any agent
2026-05-27 17:14:11,163 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-11274948 info
2026-05-27 17:14:11,163 - INFO - Deleting VN ctest-mgmt_vn-00513357
2026-05-27 17:14:11,318 - DEBUG - Response for deleting network ()
2026-05-27 17:14:11,318 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/fb299116-1267-437f-8979-8cbf3274e34d
2026-05-27 17:14:11,326 - DEBUG - Response Code: 404
2026-05-27 17:14:11,326 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-05-27 17:14:11,332 - DEBUG - Requesting: http://10.0.0.50:8082/domain/dd011215-fca5-4e74-b1da-d431e9fe9325
2026-05-27 17:14:11,346 - DEBUG - Requesting: http://10.0.0.50:8082/project/e0676243-2a72-4a62-8f83-5ad6f580cbf3
2026-05-27 17:14:11,458 - INFO - Validated that VN ctest-mgmt_vn-00513357 is not found in API Server
2026-05-27 17:14:11,470 - DEBUG - VN ctest-mgmt_vn-00513357 is not present in Agent 10.0.0.241
2026-05-27 17:14:11,482 - DEBUG - VN ctest-mgmt_vn-00513357 is not present in Agent 10.0.0.242
2026-05-27 17:14:11,482 - INFO - Validated that VN ctest-mgmt_vn-00513357 is not in any agent
2026-05-27 17:14:11,521 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-00513357 info
2026-05-27 17:14:12,853 - DEBUG - No XMPP flaps were noticed during the test
2026-05-27 17:14:12,853 - INFO -
2026-05-27 17:14:12,853 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2026-05-27 17:14:12,853 - INFO - --------------------------------------------------------------------------------
2026-05-27 17:14:13,700 - INFO - Deleted project: ctest-TestSvcRegr-79378545, ID : e0676243-2a72-4a62-8f83-5ad6f580cbf3