]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 03:05:20,342 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-01230734']
2026-01-08 03:05:20,638 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-01230734'] created succesfully
2026-01-08 03:05:21,084 - DEBUG - Image by name tiny_nat_fw not found
2026-01-08 03:05:21,085 - DEBUG - Installing image tiny_nat_fw
2026-01-08 03:05:21,085 - 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-01-08 03:05:21,086 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:21,087 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:21,108 - 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-01-08 03:05:21,230 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:21,230 - DEBUG - Unzip image
2026-01-08 03:05:21,230 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:21,275 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-08 03:05:21,276 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-01230734']
2026-01-08 03:05:21,391 - INFO - Service template: ['default-domain', 'ctest-service_template_1-01230734'] deleted successfully.
2026-01-08 03:05:21,391 - INFO - Skip interface_detach for VM ctest-right_vm-94038468 in state BUILD
2026-01-08 03:05:21,391 - INFO - Deleting VM ctest-right_vm-94038468
2026-01-08 03:05:21,425 - ERROR - VM ctest-right_vm-94038468 has failed to come up
2026-01-08 03:05:21,425 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:21,425 - ERROR - VM ctest-right_vm-94038468 has failed to come up
2026-01-08 03:05:21,425 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:21,514 - INFO - Skip interface_detach for VM ctest-left_vm-63848484 in state BUILD
2026-01-08 03:05:21,514 - INFO - Deleting VM ctest-left_vm-63848484
2026-01-08 03:05:21,514 - ERROR - VM ctest-left_vm-63848484 has failed to come up
2026-01-08 03:05:21,514 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:21,514 - ERROR - VM ctest-left_vm-63848484 has failed to come up
2026-01-08 03:05:21,514 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:21,596 - INFO - Deleting VN ctest-right_vn-00964883
2026-01-08 03:05:21,639 - DEBUG - VN 0bae5b90-7034-4c71-9ce1-a781ef360d3b still in use: Unable to complete operation on network 0bae5b90-7034-4c71-9ce1-a781ef360d3b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cc4a76ca-bbf7-461d-8a4b-138e3109e4b3']
2026-01-08 03:05:21,639 - WARNING - Deleting VN ctest-right_vn-00964883 failed..Will retry
2026-01-08 03:05:23,781 - DEBUG - Response for deleting network ()
2026-01-08 03:05:23,781 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/358355cc-37cb-4d76-a7bf-64d9587145cf
2026-01-08 03:05:23,788 - DEBUG - Response Code: 404
2026-01-08 03:05:23,788 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:23,810 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:23,832 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:23,943 - INFO - Validated that VN ctest-right_vn-00964883 is not found in API Server
2026-01-08 03:05:23,965 - DEBUG - VN ctest-right_vn-00964883 is not present in Agent 10.0.0.57
2026-01-08 03:05:23,993 - DEBUG - VN ctest-right_vn-00964883 is not present in Agent 10.0.0.56
2026-01-08 03:05:23,993 - INFO - Validated that VN ctest-right_vn-00964883 is not in any agent
2026-01-08 03:05:24,036 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-00964883 info
2026-01-08 03:05:24,036 - INFO - Deleting VN ctest-left_vn-29951243
2026-01-08 03:05:24,207 - DEBUG - Response for deleting network ()
2026-01-08 03:05:24,207 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0dd8ca9f-6f1a-4833-84d1-2ffe4e190bc8
2026-01-08 03:05:24,215 - DEBUG - Response Code: 404
2026-01-08 03:05:24,215 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:24,221 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:24,248 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:24,352 - INFO - Validated that VN ctest-left_vn-29951243 is not found in API Server
2026-01-08 03:05:24,364 - DEBUG - VN ctest-left_vn-29951243 is not present in Agent 10.0.0.57
2026-01-08 03:05:24,376 - DEBUG - VN ctest-left_vn-29951243 is not present in Agent 10.0.0.56
2026-01-08 03:05:24,376 - INFO - Validated that VN ctest-left_vn-29951243 is not in any agent
2026-01-08 03:05:24,415 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-29951243 info
2026-01-08 03:05:24,415 - INFO - Deleting VN ctest-mgmt_vn-68254072
2026-01-08 03:05:24,595 - DEBUG - Response for deleting network ()
2026-01-08 03:05:24,595 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/475e7f6e-f894-45c8-9bbc-c95ae056c006
2026-01-08 03:05:24,601 - DEBUG - Response Code: 404
2026-01-08 03:05:24,601 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:24,615 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:24,681 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:24,775 - INFO - Validated that VN ctest-mgmt_vn-68254072 is not found in API Server
2026-01-08 03:05:24,789 - DEBUG - VN ctest-mgmt_vn-68254072 is not present in Agent 10.0.0.57
2026-01-08 03:05:24,803 - DEBUG - VN ctest-mgmt_vn-68254072 is not present in Agent 10.0.0.56
2026-01-08 03:05:24,803 - INFO - Validated that VN ctest-mgmt_vn-68254072 is not in any agent
2026-01-08 03:05:24,843 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-68254072 info
2026-01-08 03:05:24,843 - INFO - Deleting VM ctest-vm-in-third-vn-19107336
2026-01-08 03:05:24,927 - INFO - Deleting VN ctest-third-vn-36404619
2026-01-08 03:05:24,981 - DEBUG - VN d6dce817-bdc8-4b08-8dce-f4c004e8d46e still in use: Unable to complete operation on network d6dce817-bdc8-4b08-8dce-f4c004e8d46e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a313b63e-64d2-4476-99c7-5e52a055742f']
2026-01-08 03:05:24,981 - WARNING - Deleting VN ctest-third-vn-36404619 failed..Will retry
2026-01-08 03:05:27,152 - DEBUG - Response for deleting network ()
2026-01-08 03:05:28,435 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 03:05:28,435 - INFO -
2026-01-08 03:05:28,435 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2026-01-08 03:05:28,435 - INFO - --------------------------------------------------------------------------------
2026-01-08 03:05:28,439 - INFO - ================================================================================
2026-01-08 03:05:28,439 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-01-08 03:05:29,726 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.56': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-01-08 03:05:29,726 - INFO - Initial checks done. Running the testcase now
2026-01-08 03:05:29,726 - INFO -
2026-01-08 03:05:30,071 - DEBUG - Response for create_network : {'network': {'id': 'b617b46f-a890-437e-82f2-da9326e419ee', 'name': 'ctest-mgmt_vn-05055119', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'project_id': '06986ffa77f74485a410675b755ea351', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-74952017', 'ctest-mgmt_vn-05055119'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:30,316 - DEBUG - Response for create_subnet : {'subnet': {'id': '8dade30f-a673-4aac-b993-dc033dc27316', 'name': '', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'network_id': 'b617b46f-a890-437e-82f2-da9326e419ee', 'ip_version': 4, 'cidr': '38.146.207.192/26', 'allocation_pools': [{'start': '38.146.207.194', 'end': '38.146.207.254'}], 'gateway_ip': '38.146.207.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '38.146.207.194', 'tags': [], 'project_id': '06986ffa77f74485a410675b755ea351'}}
2026-01-08 03:05:30,344 - INFO - Created VN ctest-mgmt_vn-05055119
2026-01-08 03:05:30,358 - DEBUG - VN ctest-mgmt_vn-05055119 UUID is b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,442 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:30,449 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:30,464 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:30,552 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,568 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,579 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0125f80c-341a-4b84-ac32-71ce31713e39
2026-01-08 03:05:30,588 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0125f80c-341a-4b84-ac32-71ce31713e39
2026-01-08 03:05:30,597 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/3c98fd3c-239b-407a-845a-d9be5aa7f866
2026-01-08 03:05:30,605 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:30,605 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,619 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0125f80c-341a-4b84-ac32-71ce31713e39
2026-01-08 03:05:30,636 - INFO - Verified VN network id 13 for VN b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,637 - INFO - Verifications in API Server for VN ctest-mgmt_vn-05055119 passed
2026-01-08 03:05:30,637 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,655 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0125f80c-341a-4b84-ac32-71ce31713e39
2026-01-08 03:05:30,664 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0125f80c-341a-4b84-ac32-71ce31713e39
2026-01-08 03:05:30,674 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/3c98fd3c-239b-407a-845a-d9be5aa7f866
2026-01-08 03:05:30,694 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13121154431029625726', 'uuid-lslong': '9435844494842141166'}, 'enable': 'true', 'created': '2026-01-08T03:05:29', 'last-modified': '2026-01-08T03:05:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449096'}
2026-01-08 03:05:30,701 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:30,708 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13121154431029625726', 'uuid-lslong': '9435844494842141166'}, 'enable': 'true', 'created': '2026-01-08T03:05:29', 'last-modified': '2026-01-08T03:05:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.459047'}
2026-01-08 03:05:30,720 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:30,727 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13121154431029625726', 'uuid-lslong': '9435844494842141166'}, 'enable': 'true', 'created': '2026-01-08T03:05:29', 'last-modified': '2026-01-08T03:05:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.482930'}
2026-01-08 03:05:30,735 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:30,736 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-05055119 passed
2026-01-08 03:05:30,736 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-05055119 in API_Server ======
2026-01-08 03:05:30,736 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:30,743 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:30,756 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:30,868 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b617b46f-a890-437e-82f2-da9326e419ee
2026-01-08 03:05:30,879 - DEBUG - =>VN ctest-mgmt_vn-05055119 has no policy to be verified
2026-01-08 03:05:30,879 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:30,880 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:30,880 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:30,896 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119?flat'}
2026-01-08 03:05:30,896 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:30,896 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:30,909 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119?flat'}
2026-01-08 03:05:30,909 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:30,909 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:30,923 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119?flat'}
2026-01-08 03:05:30,924 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-74952017:ctest-mgmt_vn-05055119 is found in opserver
2026-01-08 03:05:30,948 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:30,962 - DEBUG - VRF ids for VN ctest-mgmt_vn-05055119: {}
2026-01-08 03:05:31,281 - DEBUG - Response for create_network : {'network': {'id': '09e3b87d-4c24-428a-ae7a-9bf6e0d814d1', 'name': 'ctest-left_vn-62481080', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'project_id': '06986ffa77f74485a410675b755ea351', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-74952017', 'ctest-left_vn-62481080'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:31,513 - DEBUG - Response for create_subnet : {'subnet': {'id': '02b362f5-0668-4041-99e1-63a1f83922f6', 'name': '', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'network_id': '09e3b87d-4c24-428a-ae7a-9bf6e0d814d1', 'ip_version': 4, 'cidr': '203.213.138.128/26', 'allocation_pools': [{'start': '203.213.138.130', 'end': '203.213.138.190'}], 'gateway_ip': '203.213.138.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '203.213.138.130', 'tags': [], 'project_id': '06986ffa77f74485a410675b755ea351'}}
2026-01-08 03:05:31,549 - INFO - Created VN ctest-left_vn-62481080
2026-01-08 03:05:31,562 - DEBUG - VN ctest-left_vn-62481080 UUID is 09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:31,655 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:31,663 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:31,677 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:31,779 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:31,796 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:31,811 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/7df929e2-0af8-45ee-8fdb-d4153f0f5c7d
2026-01-08 03:05:31,824 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/7df929e2-0af8-45ee-8fdb-d4153f0f5c7d
2026-01-08 03:05:31,831 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/4a7a8cd9-602b-4e96-bcd6-74184b34b381
2026-01-08 03:05:31,838 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:31,838 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:31,848 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/7df929e2-0af8-45ee-8fdb-d4153f0f5c7d
2026-01-08 03:05:31,872 - INFO - Verified VN network id 14 for VN 09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:31,872 - INFO - Verifications in API Server for VN ctest-left_vn-62481080 passed
2026-01-08 03:05:31,872 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:31,888 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/7df929e2-0af8-45ee-8fdb-d4153f0f5c7d
2026-01-08 03:05:31,900 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/7df929e2-0af8-45ee-8fdb-d4153f0f5c7d
2026-01-08 03:05:31,912 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/4a7a8cd9-602b-4e96-bcd6-74184b34b381
2026-01-08 03:05:31,935 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '712616014342537866', 'uuid-lslong': '12572532794394678481'}, 'enable': 'true', 'created': '2026-01-08T03:05:31', 'last-modified': '2026-01-08T03:05:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471141'}
2026-01-08 03:05:31,944 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:31,951 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '712616014342537866', 'uuid-lslong': '12572532794394678481'}, 'enable': 'true', 'created': '2026-01-08T03:05:31', 'last-modified': '2026-01-08T03:05:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493476'}
2026-01-08 03:05:31,959 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:31,968 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '712616014342537866', 'uuid-lslong': '12572532794394678481'}, 'enable': 'true', 'created': '2026-01-08T03:05:31', 'last-modified': '2026-01-08T03:05:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.503635'}
2026-01-08 03:05:31,976 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:31,976 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-62481080 passed
2026-01-08 03:05:31,977 - DEBUG - ====Verifying policy data for ctest-left_vn-62481080 in API_Server ======
2026-01-08 03:05:31,977 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:31,983 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:31,998 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:32,088 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/09e3b87d-4c24-428a-ae7a-9bf6e0d814d1
2026-01-08 03:05:32,103 - DEBUG - =>VN ctest-left_vn-62481080 has no policy to be verified
2026-01-08 03:05:32,103 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:32,103 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:32,103 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:32,117 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080?flat'}
2026-01-08 03:05:32,117 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:32,117 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:32,130 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080?flat'}
2026-01-08 03:05:32,130 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:32,130 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:32,144 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080?flat'}
2026-01-08 03:05:32,144 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-74952017:ctest-left_vn-62481080 is found in opserver
2026-01-08 03:05:32,166 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:32,176 - DEBUG - VRF ids for VN ctest-left_vn-62481080: {}
2026-01-08 03:05:32,491 - DEBUG - Response for create_network : {'network': {'id': 'f2e7ed31-a5e5-4a83-a522-a82345c2caa1', 'name': 'ctest-right_vn-24361619', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'project_id': '06986ffa77f74485a410675b755ea351', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-74952017', 'ctest-right_vn-24361619'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:32,780 - DEBUG - Response for create_subnet : {'subnet': {'id': '066cd274-6ea6-496b-9c1b-1deb8604d8f4', 'name': '', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'network_id': 'f2e7ed31-a5e5-4a83-a522-a82345c2caa1', 'ip_version': 4, 'cidr': '46.175.42.128/26', 'allocation_pools': [{'start': '46.175.42.130', 'end': '46.175.42.190'}], 'gateway_ip': '46.175.42.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.175.42.130', 'tags': [], 'project_id': '06986ffa77f74485a410675b755ea351'}}
2026-01-08 03:05:32,812 - INFO - Created VN ctest-right_vn-24361619
2026-01-08 03:05:32,828 - DEBUG - VN ctest-right_vn-24361619 UUID is f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:32,927 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:32,935 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:32,952 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:33,067 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:33,085 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:33,098 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cfc3938-30bc-41ee-b6b4-1030ab3336c5
2026-01-08 03:05:33,108 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cfc3938-30bc-41ee-b6b4-1030ab3336c5
2026-01-08 03:05:33,122 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/e5b6ccf5-37a3-4ac8-8e79-e575298f4a35
2026-01-08 03:05:33,132 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:33,132 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:33,148 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cfc3938-30bc-41ee-b6b4-1030ab3336c5
2026-01-08 03:05:33,167 - INFO - Verified VN network id 15 for VN f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:33,167 - INFO - Verifications in API Server for VN ctest-right_vn-24361619 passed
2026-01-08 03:05:33,167 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:33,179 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cfc3938-30bc-41ee-b6b4-1030ab3336c5
2026-01-08 03:05:33,191 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cfc3938-30bc-41ee-b6b4-1030ab3336c5
2026-01-08 03:05:33,199 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/e5b6ccf5-37a3-4ac8-8e79-e575298f4a35
2026-01-08 03:05:33,216 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17503219274291169923', 'uuid-lslong': '11899258034913987233'}, 'enable': 'true', 'created': '2026-01-08T03:05:32', 'last-modified': '2026-01-08T03:05:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.496127'}
2026-01-08 03:05:33,223 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:33,229 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17503219274291169923', 'uuid-lslong': '11899258034913987233'}, 'enable': 'true', 'created': '2026-01-08T03:05:32', 'last-modified': '2026-01-08T03:05:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.516414'}
2026-01-08 03:05:33,238 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:33,244 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17503219274291169923', 'uuid-lslong': '11899258034913987233'}, 'enable': 'true', 'created': '2026-01-08T03:05:32', 'last-modified': '2026-01-08T03:05:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.528782'}
2026-01-08 03:05:33,250 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:33,250 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-24361619 passed
2026-01-08 03:05:33,251 - DEBUG - ====Verifying policy data for ctest-right_vn-24361619 in API_Server ======
2026-01-08 03:05:33,251 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:33,258 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:33,271 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:33,379 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f2e7ed31-a5e5-4a83-a522-a82345c2caa1
2026-01-08 03:05:33,390 - DEBUG - =>VN ctest-right_vn-24361619 has no policy to be verified
2026-01-08 03:05:33,390 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:33,390 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:33,390 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:33,405 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619?flat'}
2026-01-08 03:05:33,405 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:33,406 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:33,417 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619?flat'}
2026-01-08 03:05:33,417 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:33,417 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:33,432 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619?flat'}
2026-01-08 03:05:33,432 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-74952017:ctest-right_vn-24361619 is found in opserver
2026-01-08 03:05:33,464 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:33,475 - DEBUG - VRF ids for VN ctest-right_vn-24361619: {}
2026-01-08 03:05:33,784 - DEBUG - Response for create_network : {'network': {'id': '248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282', 'name': 'ctest-trans_left_vn-51770920', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'project_id': '06986ffa77f74485a410675b755ea351', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-74952017', 'ctest-trans_left_vn-51770920'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:34,015 - DEBUG - Response for create_subnet : {'subnet': {'id': '8350892b-7f85-4895-b7ef-910375a15acb', 'name': '', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'network_id': '248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282', 'ip_version': 4, 'cidr': '90.158.12.128/26', 'allocation_pools': [{'start': '90.158.12.130', 'end': '90.158.12.190'}], 'gateway_ip': '90.158.12.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.158.12.130', 'tags': [], 'project_id': '06986ffa77f74485a410675b755ea351'}}
2026-01-08 03:05:34,041 - INFO - Created VN ctest-trans_left_vn-51770920
2026-01-08 03:05:34,052 - DEBUG - VN ctest-trans_left_vn-51770920 UUID is 248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,140 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:34,149 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:34,164 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:34,279 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,293 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,306 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/863de56d-8709-4928-a5cd-afed7698eca5
2026-01-08 03:05:34,314 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/863de56d-8709-4928-a5cd-afed7698eca5
2026-01-08 03:05:34,322 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/08267850-a183-4fa4-ab2a-62163c883499
2026-01-08 03:05:34,329 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-08 03:05:34,329 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,339 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/863de56d-8709-4928-a5cd-afed7698eca5
2026-01-08 03:05:34,355 - INFO - Verified VN network id 16 for VN 248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,355 - INFO - Verifications in API Server for VN ctest-trans_left_vn-51770920 passed
2026-01-08 03:05:34,355 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,366 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/863de56d-8709-4928-a5cd-afed7698eca5
2026-01-08 03:05:34,376 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/863de56d-8709-4928-a5cd-afed7698eca5
2026-01-08 03:05:34,384 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/08267850-a183-4fa4-ab2a-62163c883499
2026-01-08 03:05:34,397 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2633246686122363994', 'uuid-lslong': '11719986505320465026'}, 'enable': 'true', 'created': '2026-01-08T03:05:33', 'last-modified': '2026-01-08T03:05:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.431258'}
2026-01-08 03:05:34,405 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-08 03:05:34,411 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2633246686122363994', 'uuid-lslong': '11719986505320465026'}, 'enable': 'true', 'created': '2026-01-08T03:05:33', 'last-modified': '2026-01-08T03:05:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449548'}
2026-01-08 03:05:34,417 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-08 03:05:34,423 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2633246686122363994', 'uuid-lslong': '11719986505320465026'}, 'enable': 'true', 'created': '2026-01-08T03:05:33', 'last-modified': '2026-01-08T03:05:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466876'}
2026-01-08 03:05:34,429 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-08 03:05:34,429 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-51770920 passed
2026-01-08 03:05:34,429 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-51770920 in API_Server ======
2026-01-08 03:05:34,429 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:34,436 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:34,448 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:34,547 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/248b2be8-d44c-4c5a-a2a5-c1a1a5ff8282
2026-01-08 03:05:34,564 - DEBUG - =>VN ctest-trans_left_vn-51770920 has no policy to be verified
2026-01-08 03:05:34,564 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:34,564 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:34,564 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:34,579 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920?flat'}
2026-01-08 03:05:34,579 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:34,579 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:34,593 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920?flat'}
2026-01-08 03:05:34,593 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:34,593 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:34,607 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920?flat'}
2026-01-08 03:05:34,607 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-74952017:ctest-trans_left_vn-51770920 is found in opserver
2026-01-08 03:05:34,629 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:34,641 - DEBUG - VRF ids for VN ctest-trans_left_vn-51770920: {}
2026-01-08 03:05:34,916 - DEBUG - Response for create_network : {'network': {'id': '7c60d1d9-9858-49cf-8766-6a6ecf8bfee7', 'name': 'ctest-trans_right_vn-58198137', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'project_id': '06986ffa77f74485a410675b755ea351', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-74952017', 'ctest-trans_right_vn-58198137'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:35,162 - DEBUG - Response for create_subnet : {'subnet': {'id': '5bf5b7a8-fb82-49e6-884e-72dfd5229bc0', 'name': '', 'tenant_id': '06986ffa77f74485a410675b755ea351', 'network_id': '7c60d1d9-9858-49cf-8766-6a6ecf8bfee7', 'ip_version': 4, 'cidr': '177.91.152.192/26', 'allocation_pools': [{'start': '177.91.152.194', 'end': '177.91.152.254'}], 'gateway_ip': '177.91.152.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '177.91.152.194', 'tags': [], 'project_id': '06986ffa77f74485a410675b755ea351'}}
2026-01-08 03:05:35,200 - INFO - Created VN ctest-trans_right_vn-58198137
2026-01-08 03:05:35,211 - DEBUG - VN ctest-trans_right_vn-58198137 UUID is 7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,304 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:35,312 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:35,327 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:35,433 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,452 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,467 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d8f3523d-2b5f-4225-80ee-c4f75d463157
2026-01-08 03:05:35,479 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d8f3523d-2b5f-4225-80ee-c4f75d463157
2026-01-08 03:05:35,488 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/8957fa9d-5eb8-4a78-afce-3074a7587e87
2026-01-08 03:05:35,498 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-08 03:05:35,498 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,509 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d8f3523d-2b5f-4225-80ee-c4f75d463157
2026-01-08 03:05:35,529 - INFO - Verified VN network id 17 for VN 7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,530 - INFO - Verifications in API Server for VN ctest-trans_right_vn-58198137 passed
2026-01-08 03:05:35,530 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,543 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d8f3523d-2b5f-4225-80ee-c4f75d463157
2026-01-08 03:05:35,552 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d8f3523d-2b5f-4225-80ee-c4f75d463157
2026-01-08 03:05:35,561 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/8957fa9d-5eb8-4a78-afce-3074a7587e87
2026-01-08 03:05:35,577 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8962393990961318351', 'uuid-lslong': '9756602666905763559'}, 'enable': 'true', 'created': '2026-01-08T03:05:34', 'last-modified': '2026-01-08T03:05:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462659'}
2026-01-08 03:05:35,584 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-08 03:05:35,589 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8962393990961318351', 'uuid-lslong': '9756602666905763559'}, 'enable': 'true', 'created': '2026-01-08T03:05:34', 'last-modified': '2026-01-08T03:05:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483089'}
2026-01-08 03:05:35,597 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-08 03:05:35,603 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-74952017', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8962393990961318351', 'uuid-lslong': '9756602666905763559'}, 'enable': 'true', 'created': '2026-01-08T03:05:34', 'last-modified': '2026-01-08T03:05:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.490480'}
2026-01-08 03:05:35,611 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-08 03:05:35,611 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-58198137 passed
2026-01-08 03:05:35,611 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-58198137 in API_Server ======
2026-01-08 03:05:35,611 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:35,620 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:35,632 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:35,730 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/7c60d1d9-9858-49cf-8766-6a6ecf8bfee7
2026-01-08 03:05:35,751 - DEBUG - =>VN ctest-trans_right_vn-58198137 has no policy to be verified
2026-01-08 03:05:35,751 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:35,751 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:35,751 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:35,764 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137?flat'}
2026-01-08 03:05:35,764 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:35,764 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:35,778 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137?flat'}
2026-01-08 03:05:35,778 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:35,778 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:35,794 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137?flat'}
2026-01-08 03:05:35,794 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-74952017:ctest-trans_right_vn-58198137 is found in opserver
2026-01-08 03:05:35,816 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:35,825 - DEBUG - VRF ids for VN ctest-trans_right_vn-58198137: {}
2026-01-08 03:05:36,973 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 03:05:38,447 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-1)
2026-01-08 03:05:38,447 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-16796520']
2026-01-08 03:05:38,746 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16796520'] created succesfully
2026-01-08 03:05:39,171 - DEBUG - Image by name tiny_trans_fw not found
2026-01-08 03:05:39,171 - DEBUG - Installing image tiny_trans_fw
2026-01-08 03:05:39,172 - 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-01-08 03:05:39,172 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-08 03:05:39,172 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-08 03:05:39,192 - 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-01-08 03:05:39,222 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-08 03:05:39,222 - DEBUG - Unzip image
2026-01-08 03:05:39,222 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-08 03:05:39,243 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-01-08 03:05:39,243 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-16796520']
2026-01-08 03:05:39,375 - INFO - Service template: ['default-domain', 'ctest-service_template_1-16796520'] deleted successfully.
2026-01-08 03:05:39,375 - INFO - Skip interface_detach for VM ctest-right_vm-51001819 in state BUILD
2026-01-08 03:05:39,375 - INFO - Deleting VM ctest-right_vm-51001819
2026-01-08 03:05:39,375 - ERROR - VM ctest-right_vm-51001819 has failed to come up
2026-01-08 03:05:39,375 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:39,375 - ERROR - VM ctest-right_vm-51001819 has failed to come up
2026-01-08 03:05:39,375 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:39,470 - INFO - Skip interface_detach for VM ctest-left_vm-58481157 in state BUILD
2026-01-08 03:05:39,471 - INFO - Deleting VM ctest-left_vm-58481157
2026-01-08 03:05:39,471 - ERROR - VM ctest-left_vm-58481157 has failed to come up
2026-01-08 03:05:39,471 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:39,471 - ERROR - VM ctest-left_vm-58481157 has failed to come up
2026-01-08 03:05:39,471 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:39,548 - INFO - Deleting VN ctest-trans_right_vn-58198137
2026-01-08 03:05:39,708 - DEBUG - Response for deleting network ()
2026-01-08 03:05:39,708 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/d8f3523d-2b5f-4225-80ee-c4f75d463157
2026-01-08 03:05:39,721 - DEBUG - Response Code: 404
2026-01-08 03:05:39,721 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:39,729 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:39,757 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:39,866 - INFO - Validated that VN ctest-trans_right_vn-58198137 is not found in API Server
2026-01-08 03:05:39,879 - DEBUG - VN ctest-trans_right_vn-58198137 is not present in Agent 10.0.0.57
2026-01-08 03:05:39,890 - DEBUG - VN ctest-trans_right_vn-58198137 is not present in Agent 10.0.0.56
2026-01-08 03:05:39,890 - INFO - Validated that VN ctest-trans_right_vn-58198137 is not in any agent
2026-01-08 03:05:39,923 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-58198137 info
2026-01-08 03:05:39,923 - INFO - Deleting VN ctest-trans_left_vn-51770920
2026-01-08 03:05:40,098 - DEBUG - Response for deleting network ()
2026-01-08 03:05:40,099 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/863de56d-8709-4928-a5cd-afed7698eca5
2026-01-08 03:05:40,115 - DEBUG - Response Code: 404
2026-01-08 03:05:40,116 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:40,125 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:40,146 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:40,239 - INFO - Validated that VN ctest-trans_left_vn-51770920 is not found in API Server
2026-01-08 03:05:40,258 - DEBUG - VN ctest-trans_left_vn-51770920 is not present in Agent 10.0.0.57
2026-01-08 03:05:40,273 - DEBUG - VN ctest-trans_left_vn-51770920 is not present in Agent 10.0.0.56
2026-01-08 03:05:40,274 - INFO - Validated that VN ctest-trans_left_vn-51770920 is not in any agent
2026-01-08 03:05:40,318 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-51770920 info
2026-01-08 03:05:40,318 - INFO - Deleting VN ctest-right_vn-24361619
2026-01-08 03:05:40,365 - DEBUG - VN f2e7ed31-a5e5-4a83-a522-a82345c2caa1 still in use: Unable to complete operation on network f2e7ed31-a5e5-4a83-a522-a82345c2caa1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5dc35283-a68c-4d3e-86f1-aa60b34aac11']
2026-01-08 03:05:40,366 - WARNING - Deleting VN ctest-right_vn-24361619 failed..Will retry
2026-01-08 03:05:42,545 - DEBUG - Response for deleting network ()
2026-01-08 03:05:42,545 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cfc3938-30bc-41ee-b6b4-1030ab3336c5
2026-01-08 03:05:42,552 - DEBUG - Response Code: 404
2026-01-08 03:05:42,552 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:42,574 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:42,589 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:42,695 - INFO - Validated that VN ctest-right_vn-24361619 is not found in API Server
2026-01-08 03:05:42,708 - DEBUG - VN ctest-right_vn-24361619 is not present in Agent 10.0.0.57
2026-01-08 03:05:42,717 - DEBUG - VN ctest-right_vn-24361619 is not present in Agent 10.0.0.56
2026-01-08 03:05:42,718 - INFO - Validated that VN ctest-right_vn-24361619 is not in any agent
2026-01-08 03:05:42,756 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-24361619 info
2026-01-08 03:05:42,756 - INFO - Deleting VN ctest-left_vn-62481080
2026-01-08 03:05:42,903 - DEBUG - Response for deleting network ()
2026-01-08 03:05:42,903 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/7df929e2-0af8-45ee-8fdb-d4153f0f5c7d
2026-01-08 03:05:42,909 - DEBUG - Response Code: 404
2026-01-08 03:05:42,909 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:42,916 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:42,930 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:43,027 - INFO - Validated that VN ctest-left_vn-62481080 is not found in API Server
2026-01-08 03:05:43,040 - DEBUG - VN ctest-left_vn-62481080 is not present in Agent 10.0.0.57
2026-01-08 03:05:43,052 - DEBUG - VN ctest-left_vn-62481080 is not present in Agent 10.0.0.56
2026-01-08 03:05:43,052 - INFO - Validated that VN ctest-left_vn-62481080 is not in any agent
2026-01-08 03:05:43,099 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-62481080 info
2026-01-08 03:05:43,100 - INFO - Deleting VN ctest-mgmt_vn-05055119
2026-01-08 03:05:43,244 - DEBUG - Response for deleting network ()
2026-01-08 03:05:43,245 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0125f80c-341a-4b84-ac32-71ce31713e39
2026-01-08 03:05:43,251 - DEBUG - Response Code: 404
2026-01-08 03:05:43,251 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:43,257 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:43,286 - DEBUG - Requesting: http://10.0.0.242:8082/project/06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:43,384 - INFO - Validated that VN ctest-mgmt_vn-05055119 is not found in API Server
2026-01-08 03:05:43,397 - DEBUG - VN ctest-mgmt_vn-05055119 is not present in Agent 10.0.0.57
2026-01-08 03:05:43,410 - DEBUG - VN ctest-mgmt_vn-05055119 is not present in Agent 10.0.0.56
2026-01-08 03:05:43,411 - INFO - Validated that VN ctest-mgmt_vn-05055119 is not in any agent
2026-01-08 03:05:43,447 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-05055119 info
2026-01-08 03:05:44,723 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 03:05:44,724 - INFO -
2026-01-08 03:05:44,724 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2026-01-08 03:05:44,724 - INFO - --------------------------------------------------------------------------------
2026-01-08 03:05:45,448 - INFO - Deleted project: ctest-TestSvcRegr-74952017, ID : 06986ffa-77f7-4485-a410-675b755ea351
2026-01-08 03:05:45,641 - INFO - Domain Default found not creating
2026-01-08 03:05:45,814 - INFO - Project ctest-TestSvcRegr-54479984 not found, creating it
2026-01-08 03:05:46,344 - INFO - Created Project:ctest-TestSvcRegr-54479984, ID : 80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:47,940 - INFO - ================================================================================
2026-01-08 03:05:47,941 - INFO - STARTING TEST : test_svc_CEM_22032
2026-01-08 03:05:49,223 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.56': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-01-08 03:05:49,223 - INFO - Initial checks done. Running the testcase now
2026-01-08 03:05:49,224 - INFO -
2026-01-08 03:05:49,931 - DEBUG - Response for create_network : {'network': {'id': '78b8c1e1-98c6-411a-b068-5c3aeb53a3af', 'name': 'ctest-mgmt_vn-39972246', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'project_id': '80d496a3330647a69f64353414f9dfdb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54479984', 'ctest-mgmt_vn-39972246'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:50,234 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ee9e0855-720c-4933-872f-c2063dcbca28', 'name': '', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'network_id': '78b8c1e1-98c6-411a-b068-5c3aeb53a3af', 'ip_version': 4, 'cidr': '59.43.232.192/26', 'allocation_pools': [{'start': '59.43.232.194', 'end': '59.43.232.254'}], 'gateway_ip': '59.43.232.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.43.232.194', 'tags': [], 'project_id': '80d496a3330647a69f64353414f9dfdb'}}
2026-01-08 03:05:50,258 - INFO - Created VN ctest-mgmt_vn-39972246
2026-01-08 03:05:50,271 - DEBUG - VN ctest-mgmt_vn-39972246 UUID is 78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:50,347 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:50,658 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:50,700 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:50,718 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:50,814 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:50,828 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:50,837 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1ce3cdc8-1e3e-434f-a79f-7cb4a7dded42
2026-01-08 03:05:50,845 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1ce3cdc8-1e3e-434f-a79f-7cb4a7dded42
2026-01-08 03:05:50,851 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/e999e40b-d008-4955-ae34-4319a962c0e8
2026-01-08 03:05:50,859 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:50,859 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:50,869 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1ce3cdc8-1e3e-434f-a79f-7cb4a7dded42
2026-01-08 03:05:50,884 - INFO - Verified VN network id 13 for VN 78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:50,884 - INFO - Verifications in API Server for VN ctest-mgmt_vn-39972246 passed
2026-01-08 03:05:50,884 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:50,893 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1ce3cdc8-1e3e-434f-a79f-7cb4a7dded42
2026-01-08 03:05:50,900 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1ce3cdc8-1e3e-434f-a79f-7cb4a7dded42
2026-01-08 03:05:50,907 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/e999e40b-d008-4955-ae34-4319a962c0e8
2026-01-08 03:05:50,920 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8698915854941045018', 'uuid-lslong': '12711511356379210671'}, 'enable': 'true', 'created': '2026-01-08T03:05:49', 'last-modified': '2026-01-08T03:05:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.726946'}
2026-01-08 03:05:50,927 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:50,932 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8698915854941045018', 'uuid-lslong': '12711511356379210671'}, 'enable': 'true', 'created': '2026-01-08T03:05:49', 'last-modified': '2026-01-08T03:05:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.750952'}
2026-01-08 03:05:50,938 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:50,943 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8698915854941045018', 'uuid-lslong': '12711511356379210671'}, 'enable': 'true', 'created': '2026-01-08T03:05:49', 'last-modified': '2026-01-08T03:05:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.753344'}
2026-01-08 03:05:50,949 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:05:50,949 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-39972246 passed
2026-01-08 03:05:50,949 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-39972246 in API_Server ======
2026-01-08 03:05:50,949 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:50,956 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:50,968 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:51,066 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/78b8c1e1-98c6-411a-b068-5c3aeb53a3af
2026-01-08 03:05:51,077 - DEBUG - =>VN ctest-mgmt_vn-39972246 has no policy to be verified
2026-01-08 03:05:51,077 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:51,078 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:51,078 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:51,394 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:51,467 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246?flat'}
2026-01-08 03:05:51,467 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:51,467 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:51,795 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:51,864 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246?flat'}
2026-01-08 03:05:51,864 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:51,864 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:52,165 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:52,231 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246?flat'}
2026-01-08 03:05:52,231 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-39972246 is found in opserver
2026-01-08 03:05:52,252 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:52,262 - DEBUG - VRF ids for VN ctest-mgmt_vn-39972246: {}
2026-01-08 03:05:52,519 - DEBUG - Response for create_network : {'network': {'id': '912056c5-9bf5-426f-b95d-dee7137d6a16', 'name': 'service-left-vn-32698060', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'project_id': '80d496a3330647a69f64353414f9dfdb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54479984', 'service-left-vn-32698060'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:52,750 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b1fa4c4f-7c42-4124-89ec-ef96457eb516', 'name': '', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'network_id': '912056c5-9bf5-426f-b95d-dee7137d6a16', 'ip_version': 4, 'cidr': '200.166.84.64/26', 'allocation_pools': [{'start': '200.166.84.66', 'end': '200.166.84.126'}], 'gateway_ip': '200.166.84.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '200.166.84.66', 'tags': [], 'project_id': '80d496a3330647a69f64353414f9dfdb'}}
2026-01-08 03:05:52,784 - INFO - Created VN service-left-vn-32698060
2026-01-08 03:05:52,799 - DEBUG - VN service-left-vn-32698060 UUID is 912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:52,893 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:52,901 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:52,916 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:53,022 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:53,037 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:53,049 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e062e5eb-ab6b-4070-8d87-4092325de749
2026-01-08 03:05:53,059 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e062e5eb-ab6b-4070-8d87-4092325de749
2026-01-08 03:05:53,066 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/ce6bd6a4-4ca3-4350-9b11-b0828ab723f1
2026-01-08 03:05:53,073 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:53,073 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:53,081 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e062e5eb-ab6b-4070-8d87-4092325de749
2026-01-08 03:05:53,097 - INFO - Verified VN network id 14 for VN 912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:53,098 - INFO - Verifications in API Server for VN service-left-vn-32698060 passed
2026-01-08 03:05:53,098 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:53,108 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e062e5eb-ab6b-4070-8d87-4092325de749
2026-01-08 03:05:53,116 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e062e5eb-ab6b-4070-8d87-4092325de749
2026-01-08 03:05:53,123 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/ce6bd6a4-4ca3-4350-9b11-b0828ab723f1
2026-01-08 03:05:53,138 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10457453741479379567', 'uuid-lslong': '13357077153896557078'}, 'enable': 'true', 'created': '2026-01-08T03:05:52', 'last-modified': '2026-01-08T03:05:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.450340'}
2026-01-08 03:05:53,147 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:53,152 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10457453741479379567', 'uuid-lslong': '13357077153896557078'}, 'enable': 'true', 'created': '2026-01-08T03:05:52', 'last-modified': '2026-01-08T03:05:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464670'}
2026-01-08 03:05:53,159 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:53,166 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10457453741479379567', 'uuid-lslong': '13357077153896557078'}, 'enable': 'true', 'created': '2026-01-08T03:05:52', 'last-modified': '2026-01-08T03:05:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.480974'}
2026-01-08 03:05:53,172 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:05:53,173 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-32698060 passed
2026-01-08 03:05:53,173 - DEBUG - ====Verifying policy data for service-left-vn-32698060 in API_Server ======
2026-01-08 03:05:53,173 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:53,180 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:53,192 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:53,288 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/912056c5-9bf5-426f-b95d-dee7137d6a16
2026-01-08 03:05:53,299 - DEBUG - =>VN service-left-vn-32698060 has no policy to be verified
2026-01-08 03:05:53,299 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:53,299 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:53,299 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:53,363 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060?flat'}
2026-01-08 03:05:53,363 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:53,363 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:53,427 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060?flat'}
2026-01-08 03:05:53,427 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:53,427 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:53,485 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060?flat'}
2026-01-08 03:05:53,485 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54479984:service-left-vn-32698060 is found in opserver
2026-01-08 03:05:53,508 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:53,518 - DEBUG - VRF ids for VN service-left-vn-32698060: {}
2026-01-08 03:05:53,813 - DEBUG - Response for create_network : {'network': {'id': '22ea7010-634a-438c-a3fb-1ad8b49c7275', 'name': 'service-right-vn-24404824', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'project_id': '80d496a3330647a69f64353414f9dfdb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54479984', 'service-right-vn-24404824'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:05:54,028 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cb187c32-8ad8-49f2-9c6f-9ee556df3e76', 'name': '', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'network_id': '22ea7010-634a-438c-a3fb-1ad8b49c7275', 'ip_version': 4, 'cidr': '178.183.209.128/26', 'allocation_pools': [{'start': '178.183.209.130', 'end': '178.183.209.190'}], 'gateway_ip': '178.183.209.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '178.183.209.130', 'tags': [], 'project_id': '80d496a3330647a69f64353414f9dfdb'}}
2026-01-08 03:05:54,051 - INFO - Created VN service-right-vn-24404824
2026-01-08 03:05:54,065 - DEBUG - VN service-right-vn-24404824 UUID is 22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,141 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:54,148 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:54,159 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:54,262 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,280 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,289 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/fdf35a90-6094-4f55-acc2-ea5ce0e7c866
2026-01-08 03:05:54,296 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/fdf35a90-6094-4f55-acc2-ea5ce0e7c866
2026-01-08 03:05:54,304 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/d2eef921-54a8-4f34-aef7-ff2245074763
2026-01-08 03:05:54,313 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:54,313 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,323 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/fdf35a90-6094-4f55-acc2-ea5ce0e7c866
2026-01-08 03:05:54,340 - INFO - Verified VN network id 15 for VN 22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,340 - INFO - Verifications in API Server for VN service-right-vn-24404824 passed
2026-01-08 03:05:54,340 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,349 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/fdf35a90-6094-4f55-acc2-ea5ce0e7c866
2026-01-08 03:05:54,357 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/fdf35a90-6094-4f55-acc2-ea5ce0e7c866
2026-01-08 03:05:54,364 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/d2eef921-54a8-4f34-aef7-ff2245074763
2026-01-08 03:05:54,375 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2515946557527442316', 'uuid-lslong': '11816067565382038133'}, 'enable': 'true', 'created': '2026-01-08T03:05:53', 'last-modified': '2026-01-08T03:05:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407019'}
2026-01-08 03:05:54,381 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:54,387 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2515946557527442316', 'uuid-lslong': '11816067565382038133'}, 'enable': 'true', 'created': '2026-01-08T03:05:53', 'last-modified': '2026-01-08T03:05:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424244'}
2026-01-08 03:05:54,396 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:54,401 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2515946557527442316', 'uuid-lslong': '11816067565382038133'}, 'enable': 'true', 'created': '2026-01-08T03:05:53', 'last-modified': '2026-01-08T03:05:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435635'}
2026-01-08 03:05:54,407 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:05:54,407 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-24404824 passed
2026-01-08 03:05:54,407 - DEBUG - ====Verifying policy data for service-right-vn-24404824 in API_Server ======
2026-01-08 03:05:54,407 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:05:54,413 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:05:54,431 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:05:54,535 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/22ea7010-634a-438c-a3fb-1ad8b49c7275
2026-01-08 03:05:54,549 - DEBUG - =>VN service-right-vn-24404824 has no policy to be verified
2026-01-08 03:05:54,549 - DEBUG - Verifying the vn in opserver
2026-01-08 03:05:54,550 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824 virtual network link through opserver 10.0.0.242
2026-01-08 03:05:54,550 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:05:54,632 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824?flat'}
2026-01-08 03:05:54,632 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824 virtual network link through opserver 10.0.0.50
2026-01-08 03:05:54,632 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:05:54,714 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824?flat'}
2026-01-08 03:05:54,714 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824 virtual network link through opserver 10.0.0.27
2026-01-08 03:05:54,714 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:05:54,786 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824?flat'}
2026-01-08 03:05:54,786 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54479984:service-right-vn-24404824 is found in opserver
2026-01-08 03:05:54,810 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:05:54,821 - DEBUG - VRF ids for VN service-right-vn-24404824: {}
2026-01-08 03:05:54,884 - DEBUG - Services list from nova: [, , , ]
2026-01-08 03:05:56,072 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 03:05:57,847 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-1)
2026-01-08 03:05:57,847 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-21635933']
2026-01-08 03:05:58,159 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-21635933'] created succesfully
2026-01-08 03:05:58,603 - DEBUG - Image by name tiny_nat_fw not found
2026-01-08 03:05:58,603 - DEBUG - Installing image tiny_nat_fw
2026-01-08 03:05:58,603 - 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-01-08 03:05:58,603 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:58,603 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:58,603 - DEBUG - Unzip image
2026-01-08 03:05:58,603 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:05:58,631 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-08 03:05:58,631 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-21635933']
2026-01-08 03:05:58,747 - INFO - Service template: ['default-domain', 'ctest-service_template_1-21635933'] deleted successfully.
2026-01-08 03:05:58,747 - INFO - Skip interface_detach for VM ctest-right_vm-56197130 in state BUILD
2026-01-08 03:05:58,747 - INFO - Deleting VM ctest-right_vm-56197130
2026-01-08 03:05:58,823 - ERROR - VM ctest-right_vm-56197130 has failed to come up
2026-01-08 03:05:58,823 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:58,823 - ERROR - VM ctest-right_vm-56197130 has failed to come up
2026-01-08 03:05:58,823 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:58,899 - INFO - Skip interface_detach for VM ctest-left_vm-32802995 in state BUILD
2026-01-08 03:05:58,900 - INFO - Deleting VM ctest-left_vm-32802995
2026-01-08 03:05:58,900 - ERROR - VM ctest-left_vm-32802995 has failed to come up
2026-01-08 03:05:58,900 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:58,900 - ERROR - VM ctest-left_vm-32802995 has failed to come up
2026-01-08 03:05:58,900 - ERROR - Nova failed to get host of the VM
2026-01-08 03:05:58,968 - INFO - Deleting VN service-right-vn-24404824
2026-01-08 03:05:59,022 - DEBUG - VN 22ea7010-634a-438c-a3fb-1ad8b49c7275 still in use: Unable to complete operation on network 22ea7010-634a-438c-a3fb-1ad8b49c7275. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2afe3f71-b750-4a2c-a59e-0ec2dfef21be']
2026-01-08 03:05:59,022 - WARNING - Deleting VN service-right-vn-24404824 failed..Will retry
2026-01-08 03:06:01,180 - DEBUG - Response for deleting network ()
2026-01-08 03:06:01,181 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/fdf35a90-6094-4f55-acc2-ea5ce0e7c866
2026-01-08 03:06:01,189 - DEBUG - Response Code: 404
2026-01-08 03:06:01,189 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:01,197 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:01,243 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:01,352 - INFO - Validated that VN service-right-vn-24404824 is not found in API Server
2026-01-08 03:06:01,368 - DEBUG - VN service-right-vn-24404824 is not present in Agent 10.0.0.57
2026-01-08 03:06:01,380 - DEBUG - VN service-right-vn-24404824 is not present in Agent 10.0.0.56
2026-01-08 03:06:01,380 - INFO - Validated that VN service-right-vn-24404824 is not in any agent
2026-01-08 03:06:01,414 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-24404824 info
2026-01-08 03:06:01,414 - INFO - Deleting VN service-left-vn-32698060
2026-01-08 03:06:01,586 - DEBUG - Response for deleting network ()
2026-01-08 03:06:01,586 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e062e5eb-ab6b-4070-8d87-4092325de749
2026-01-08 03:06:01,592 - DEBUG - Response Code: 404
2026-01-08 03:06:01,592 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:01,604 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:01,615 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:01,715 - INFO - Validated that VN service-left-vn-32698060 is not found in API Server
2026-01-08 03:06:01,727 - DEBUG - VN service-left-vn-32698060 is not present in Agent 10.0.0.57
2026-01-08 03:06:01,736 - DEBUG - VN service-left-vn-32698060 is not present in Agent 10.0.0.56
2026-01-08 03:06:01,736 - INFO - Validated that VN service-left-vn-32698060 is not in any agent
2026-01-08 03:06:01,768 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-32698060 info
2026-01-08 03:06:01,768 - INFO - Deleting VN ctest-mgmt_vn-39972246
2026-01-08 03:06:01,954 - DEBUG - Response for deleting network ()
2026-01-08 03:06:01,954 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1ce3cdc8-1e3e-434f-a79f-7cb4a7dded42
2026-01-08 03:06:01,966 - DEBUG - Response Code: 404
2026-01-08 03:06:01,966 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:01,973 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:02,033 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:02,147 - INFO - Validated that VN ctest-mgmt_vn-39972246 is not found in API Server
2026-01-08 03:06:02,158 - DEBUG - VN ctest-mgmt_vn-39972246 is not present in Agent 10.0.0.57
2026-01-08 03:06:02,170 - DEBUG - VN ctest-mgmt_vn-39972246 is not present in Agent 10.0.0.56
2026-01-08 03:06:02,170 - INFO - Validated that VN ctest-mgmt_vn-39972246 is not in any agent
2026-01-08 03:06:02,203 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-39972246 info
2026-01-08 03:06:03,511 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 03:06:03,511 - INFO -
2026-01-08 03:06:03,511 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2026-01-08 03:06:03,512 - INFO - --------------------------------------------------------------------------------
2026-01-08 03:06:03,515 - INFO - ================================================================================
2026-01-08 03:06:03,515 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-01-08 03:06:04,800 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.56': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-01-08 03:06:04,800 - INFO - Initial checks done. Running the testcase now
2026-01-08 03:06:04,800 - INFO -
2026-01-08 03:06:05,045 - DEBUG - Response for create_network : {'network': {'id': '83259f2e-caf8-4d7a-842e-7f70bbeda104', 'name': 'ctest-mgmt_vn-99245180', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'project_id': '80d496a3330647a69f64353414f9dfdb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54479984', 'ctest-mgmt_vn-99245180'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:06:05,301 - DEBUG - Response for create_subnet : {'subnet': {'id': '18e33a8a-0870-4b81-8b99-b4e6425fcdf0', 'name': '', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'network_id': '83259f2e-caf8-4d7a-842e-7f70bbeda104', 'ip_version': 4, 'cidr': '134.210.41.128/26', 'allocation_pools': [{'start': '134.210.41.130', 'end': '134.210.41.190'}], 'gateway_ip': '134.210.41.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '134.210.41.130', 'tags': [], 'project_id': '80d496a3330647a69f64353414f9dfdb'}}
2026-01-08 03:06:05,324 - INFO - Created VN ctest-mgmt_vn-99245180
2026-01-08 03:06:05,337 - DEBUG - VN ctest-mgmt_vn-99245180 UUID is 83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,409 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:05,417 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:05,430 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:05,542 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,557 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,569 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/f27f3003-bcdf-4882-ba38-53be65366d34
2026-01-08 03:06:05,649 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/f27f3003-bcdf-4882-ba38-53be65366d34
2026-01-08 03:06:05,659 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/1ae8dd2b-37ec-45ee-8ec1-2a97d2729c0a
2026-01-08 03:06:05,666 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:06:05,666 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,677 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/f27f3003-bcdf-4882-ba38-53be65366d34
2026-01-08 03:06:05,695 - INFO - Verified VN network id 13 for VN 83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,695 - INFO - Verifications in API Server for VN ctest-mgmt_vn-99245180 passed
2026-01-08 03:06:05,696 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,707 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/f27f3003-bcdf-4882-ba38-53be65366d34
2026-01-08 03:06:05,715 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/f27f3003-bcdf-4882-ba38-53be65366d34
2026-01-08 03:06:05,723 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/1ae8dd2b-37ec-45ee-8ec1-2a97d2729c0a
2026-01-08 03:06:05,738 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9450134416429436282', 'uuid-lslong': '9524690384101155076'}, 'enable': 'true', 'created': '2026-01-08T03:06:04', 'last-modified': '2026-01-08T03:06:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512187'}
2026-01-08 03:06:05,744 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:06:05,750 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9450134416429436282', 'uuid-lslong': '9524690384101155076'}, 'enable': 'true', 'created': '2026-01-08T03:06:04', 'last-modified': '2026-01-08T03:06:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.507138'}
2026-01-08 03:06:05,755 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:06:05,761 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9450134416429436282', 'uuid-lslong': '9524690384101155076'}, 'enable': 'true', 'created': '2026-01-08T03:06:04', 'last-modified': '2026-01-08T03:06:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.536283'}
2026-01-08 03:06:05,767 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-08 03:06:05,767 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-99245180 passed
2026-01-08 03:06:05,767 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-99245180 in API_Server ======
2026-01-08 03:06:05,767 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:05,777 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:05,792 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:05,897 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/83259f2e-caf8-4d7a-842e-7f70bbeda104
2026-01-08 03:06:05,912 - DEBUG - =>VN ctest-mgmt_vn-99245180 has no policy to be verified
2026-01-08 03:06:05,912 - DEBUG - Verifying the vn in opserver
2026-01-08 03:06:05,912 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180 virtual network link through opserver 10.0.0.242
2026-01-08 03:06:05,912 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:06:05,925 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180?flat'}
2026-01-08 03:06:05,925 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180 virtual network link through opserver 10.0.0.50
2026-01-08 03:06:05,925 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:06:05,938 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180?flat'}
2026-01-08 03:06:05,939 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180 virtual network link through opserver 10.0.0.27
2026-01-08 03:06:05,939 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:06:05,953 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180?flat'}
2026-01-08 03:06:05,953 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54479984:ctest-mgmt_vn-99245180 is found in opserver
2026-01-08 03:06:05,980 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:06:05,993 - DEBUG - VRF ids for VN ctest-mgmt_vn-99245180: {}
2026-01-08 03:06:06,294 - DEBUG - Response for create_network : {'network': {'id': '983e42a4-8fdf-4cba-943c-8762e677c4bc', 'name': 'ctest-left_vn-18225082', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'project_id': '80d496a3330647a69f64353414f9dfdb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54479984', 'ctest-left_vn-18225082'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:06:06,549 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dfd53e4a-0c5d-4e73-8c81-ee3327bbb7a2', 'name': '', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'network_id': '983e42a4-8fdf-4cba-943c-8762e677c4bc', 'ip_version': 4, 'cidr': '153.179.38.0/26', 'allocation_pools': [{'start': '153.179.38.2', 'end': '153.179.38.62'}], 'gateway_ip': '153.179.38.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '153.179.38.2', 'tags': [], 'project_id': '80d496a3330647a69f64353414f9dfdb'}}
2026-01-08 03:06:06,583 - INFO - Created VN ctest-left_vn-18225082
2026-01-08 03:06:06,594 - DEBUG - VN ctest-left_vn-18225082 UUID is 983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:06,677 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:06,684 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:06,698 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:06,806 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:06,821 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:06,831 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9b1d598c-529f-4c04-a437-0143cf362632
2026-01-08 03:06:06,842 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9b1d598c-529f-4c04-a437-0143cf362632
2026-01-08 03:06:06,851 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/65707f7f-9464-425a-aa56-94cab4c566ea
2026-01-08 03:06:06,859 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:06:06,859 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:06,871 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9b1d598c-529f-4c04-a437-0143cf362632
2026-01-08 03:06:06,888 - INFO - Verified VN network id 14 for VN 983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:06,888 - INFO - Verifications in API Server for VN ctest-left_vn-18225082 passed
2026-01-08 03:06:06,888 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:06,900 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9b1d598c-529f-4c04-a437-0143cf362632
2026-01-08 03:06:06,908 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9b1d598c-529f-4c04-a437-0143cf362632
2026-01-08 03:06:06,915 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/65707f7f-9464-425a-aa56-94cab4c566ea
2026-01-08 03:06:06,926 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10970279016876952762', 'uuid-lslong': '10681561275059127484'}, 'enable': 'true', 'created': '2026-01-08T03:06:06', 'last-modified': '2026-01-08T03:06:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421310'}
2026-01-08 03:06:06,933 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:06:06,939 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10970279016876952762', 'uuid-lslong': '10681561275059127484'}, 'enable': 'true', 'created': '2026-01-08T03:06:06', 'last-modified': '2026-01-08T03:06:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440376'}
2026-01-08 03:06:06,945 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:06:06,950 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10970279016876952762', 'uuid-lslong': '10681561275059127484'}, 'enable': 'true', 'created': '2026-01-08T03:06:06', 'last-modified': '2026-01-08T03:06:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449964'}
2026-01-08 03:06:06,955 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-08 03:06:06,955 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-18225082 passed
2026-01-08 03:06:06,956 - DEBUG - ====Verifying policy data for ctest-left_vn-18225082 in API_Server ======
2026-01-08 03:06:06,956 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:06,961 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:06,972 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:07,080 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/983e42a4-8fdf-4cba-943c-8762e677c4bc
2026-01-08 03:06:07,092 - DEBUG - =>VN ctest-left_vn-18225082 has no policy to be verified
2026-01-08 03:06:07,092 - DEBUG - Verifying the vn in opserver
2026-01-08 03:06:07,092 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082 virtual network link through opserver 10.0.0.242
2026-01-08 03:06:07,092 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:06:07,106 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082?flat'}
2026-01-08 03:06:07,106 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082 virtual network link through opserver 10.0.0.50
2026-01-08 03:06:07,106 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:06:07,120 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082?flat'}
2026-01-08 03:06:07,120 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082 virtual network link through opserver 10.0.0.27
2026-01-08 03:06:07,120 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:06:07,143 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082?flat'}
2026-01-08 03:06:07,143 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54479984:ctest-left_vn-18225082 is found in opserver
2026-01-08 03:06:07,166 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:06:07,176 - DEBUG - VRF ids for VN ctest-left_vn-18225082: {}
2026-01-08 03:06:07,467 - DEBUG - Response for create_network : {'network': {'id': 'bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd', 'name': 'ctest-right_vn-89200839', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'project_id': '80d496a3330647a69f64353414f9dfdb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-54479984', 'ctest-right_vn-89200839'], 'port_security_enabled': True, 'description': ''}}
2026-01-08 03:06:07,754 - DEBUG - Response for create_subnet : {'subnet': {'id': '1fb872ff-3f20-4958-8cfb-729a06f2e4c7', 'name': '', 'tenant_id': '80d496a3330647a69f64353414f9dfdb', 'network_id': 'bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd', 'ip_version': 4, 'cidr': '13.151.254.192/26', 'allocation_pools': [{'start': '13.151.254.194', 'end': '13.151.254.254'}], 'gateway_ip': '13.151.254.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.151.254.194', 'tags': [], 'project_id': '80d496a3330647a69f64353414f9dfdb'}}
2026-01-08 03:06:07,776 - INFO - Created VN ctest-right_vn-89200839
2026-01-08 03:06:07,789 - DEBUG - VN ctest-right_vn-89200839 UUID is bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:07,860 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:07,866 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:07,878 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:07,995 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:08,012 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:08,029 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/46cf823d-4b2d-41e9-93bc-5280f4221ab6
2026-01-08 03:06:08,041 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/46cf823d-4b2d-41e9-93bc-5280f4221ab6
2026-01-08 03:06:08,049 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/86e1eedd-91b8-4044-a260-31ef024085b4
2026-01-08 03:06:08,059 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:06:08,059 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:08,068 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/46cf823d-4b2d-41e9-93bc-5280f4221ab6
2026-01-08 03:06:08,084 - INFO - Verified VN network id 15 for VN bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:08,085 - INFO - Verifications in API Server for VN ctest-right_vn-89200839 passed
2026-01-08 03:06:08,085 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:08,096 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/46cf823d-4b2d-41e9-93bc-5280f4221ab6
2026-01-08 03:06:08,104 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/46cf823d-4b2d-41e9-93bc-5280f4221ab6
2026-01-08 03:06:08,111 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/86e1eedd-91b8-4044-a260-31ef024085b4
2026-01-08 03:06:08,124 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13796501101397560244', 'uuid-lslong': '10062236841572837629'}, 'enable': 'true', 'created': '2026-01-08T03:06:07', 'last-modified': '2026-01-08T03:06:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428426'}
2026-01-08 03:06:08,129 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:06:08,135 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13796501101397560244', 'uuid-lslong': '10062236841572837629'}, 'enable': 'true', 'created': '2026-01-08T03:06:07', 'last-modified': '2026-01-08T03:06:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436509'}
2026-01-08 03:06:08,140 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:06:08,146 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-54479984', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13796501101397560244', 'uuid-lslong': '10062236841572837629'}, 'enable': 'true', 'created': '2026-01-08T03:06:07', 'last-modified': '2026-01-08T03:06:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.450579'}
2026-01-08 03:06:08,151 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-08 03:06:08,152 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-89200839 passed
2026-01-08 03:06:08,152 - DEBUG - ====Verifying policy data for ctest-right_vn-89200839 in API_Server ======
2026-01-08 03:06:08,152 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:08,159 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:08,172 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:08,266 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd
2026-01-08 03:06:08,280 - DEBUG - =>VN ctest-right_vn-89200839 has no policy to be verified
2026-01-08 03:06:08,281 - DEBUG - Verifying the vn in opserver
2026-01-08 03:06:08,281 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839 virtual network link through opserver 10.0.0.242
2026-01-08 03:06:08,281 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-08 03:06:08,293 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839?flat'}
2026-01-08 03:06:08,293 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839 virtual network link through opserver 10.0.0.50
2026-01-08 03:06:08,294 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-08 03:06:08,310 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839?flat'}
2026-01-08 03:06:08,310 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839 virtual network link through opserver 10.0.0.27
2026-01-08 03:06:08,310 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-08 03:06:08,326 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839?flat'}
2026-01-08 03:06:08,326 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-54479984:ctest-right_vn-89200839 is found in opserver
2026-01-08 03:06:08,348 - DEBUG - Do not have enough data to verify VN in agent
2026-01-08 03:06:08,359 - DEBUG - VRF ids for VN ctest-right_vn-89200839: {}
2026-01-08 03:06:09,459 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-1)
2026-01-08 03:06:10,791 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4976-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4976-2)
2026-01-08 03:06:10,791 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-10677289']
2026-01-08 03:06:11,095 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-10677289'] created succesfully
2026-01-08 03:06:11,465 - DEBUG - Image by name tiny_nat_fw not found
2026-01-08 03:06:11,465 - DEBUG - Installing image tiny_nat_fw
2026-01-08 03:06:11,465 - 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-01-08 03:06:11,465 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:06:11,465 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:06:11,465 - DEBUG - Unzip image
2026-01-08 03:06:11,465 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-08 03:06:11,501 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-08 03:06:11,502 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-10677289']
2026-01-08 03:06:11,603 - INFO - Service template: ['default-domain', 'ctest-service_template_1-10677289'] deleted successfully.
2026-01-08 03:06:11,604 - INFO - Skip interface_detach for VM ctest-right_vm-47082828 in state BUILD
2026-01-08 03:06:11,605 - INFO - Deleting VM ctest-right_vm-47082828
2026-01-08 03:06:11,605 - ERROR - VM ctest-right_vm-47082828 has failed to come up
2026-01-08 03:06:11,605 - ERROR - Nova failed to get host of the VM
2026-01-08 03:06:11,606 - ERROR - VM ctest-right_vm-47082828 has failed to come up
2026-01-08 03:06:11,606 - ERROR - Nova failed to get host of the VM
2026-01-08 03:06:11,737 - INFO - Skip interface_detach for VM ctest-left_vm-61589890 in state BUILD
2026-01-08 03:06:11,738 - INFO - Deleting VM ctest-left_vm-61589890
2026-01-08 03:06:11,738 - ERROR - VM ctest-left_vm-61589890 has failed to come up
2026-01-08 03:06:11,738 - ERROR - Nova failed to get host of the VM
2026-01-08 03:06:11,738 - ERROR - VM ctest-left_vm-61589890 has failed to come up
2026-01-08 03:06:11,738 - ERROR - Nova failed to get host of the VM
2026-01-08 03:06:11,813 - INFO - Deleting VN ctest-right_vn-89200839
2026-01-08 03:06:11,858 - DEBUG - VN bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd still in use: Unable to complete operation on network bf7704a7-9c16-4bb4-8ba4-3f186bcc28fd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-56898b12-4ca5-4b31-9452-4c14aeaff232']
2026-01-08 03:06:11,858 - WARNING - Deleting VN ctest-right_vn-89200839 failed..Will retry
2026-01-08 03:06:14,059 - DEBUG - Response for deleting network ()
2026-01-08 03:06:14,059 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/46cf823d-4b2d-41e9-93bc-5280f4221ab6
2026-01-08 03:06:14,072 - DEBUG - Response Code: 404
2026-01-08 03:06:14,072 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:14,080 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:14,140 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:14,252 - INFO - Validated that VN ctest-right_vn-89200839 is not found in API Server
2026-01-08 03:06:14,263 - DEBUG - VN ctest-right_vn-89200839 is not present in Agent 10.0.0.57
2026-01-08 03:06:14,275 - DEBUG - VN ctest-right_vn-89200839 is not present in Agent 10.0.0.56
2026-01-08 03:06:14,275 - INFO - Validated that VN ctest-right_vn-89200839 is not in any agent
2026-01-08 03:06:14,312 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-89200839 info
2026-01-08 03:06:14,313 - INFO - Deleting VN ctest-left_vn-18225082
2026-01-08 03:06:14,495 - DEBUG - Response for deleting network ()
2026-01-08 03:06:14,495 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9b1d598c-529f-4c04-a437-0143cf362632
2026-01-08 03:06:14,503 - DEBUG - Response Code: 404
2026-01-08 03:06:14,503 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:14,510 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:14,565 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:14,676 - INFO - Validated that VN ctest-left_vn-18225082 is not found in API Server
2026-01-08 03:06:14,687 - DEBUG - VN ctest-left_vn-18225082 is not present in Agent 10.0.0.57
2026-01-08 03:06:14,700 - DEBUG - VN ctest-left_vn-18225082 is not present in Agent 10.0.0.56
2026-01-08 03:06:14,700 - INFO - Validated that VN ctest-left_vn-18225082 is not in any agent
2026-01-08 03:06:14,734 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-18225082 info
2026-01-08 03:06:14,734 - INFO - Deleting VN ctest-mgmt_vn-99245180
2026-01-08 03:06:14,886 - DEBUG - Response for deleting network ()
2026-01-08 03:06:14,886 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/f27f3003-bcdf-4882-ba38-53be65366d34
2026-01-08 03:06:14,895 - DEBUG - Response Code: 404
2026-01-08 03:06:14,896 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-08 03:06:14,903 - DEBUG - Requesting: http://10.0.0.242:8082/domain/01640a83-288f-426f-a2f2-0c0b4b93ac50
2026-01-08 03:06:14,913 - DEBUG - Requesting: http://10.0.0.242:8082/project/80d496a3-3306-47a6-9f64-353414f9dfdb
2026-01-08 03:06:14,995 - INFO - Validated that VN ctest-mgmt_vn-99245180 is not found in API Server
2026-01-08 03:06:15,007 - DEBUG - VN ctest-mgmt_vn-99245180 is not present in Agent 10.0.0.57
2026-01-08 03:06:15,019 - DEBUG - VN ctest-mgmt_vn-99245180 is not present in Agent 10.0.0.56
2026-01-08 03:06:15,019 - INFO - Validated that VN ctest-mgmt_vn-99245180 is not in any agent
2026-01-08 03:06:15,058 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-99245180 info
2026-01-08 03:06:16,342 - DEBUG - No XMPP flaps were noticed during the test
2026-01-08 03:06:16,343 - INFO -
2026-01-08 03:06:16,343 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2026-01-08 03:06:16,343 - INFO - --------------------------------------------------------------------------------
2026-01-08 03:06:17,073 - INFO - Deleted project: ctest-TestSvcRegr-54479984, ID : 80d496a3-3306-47a6-9f64-353414f9dfdb