]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-2)
2026-01-15 03:18:00,721 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-07752616']
2026-01-15 03:18:01,077 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-07752616'] created succesfully
2026-01-15 03:18:01,688 - DEBUG - Image by name tiny_nat_fw not found
2026-01-15 03:18:01,688 - DEBUG - Installing image tiny_nat_fw
2026-01-15 03:18:01,688 - 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-15 03:18:01,689 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:01,689 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:01,722 - 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-15 03:18:01,871 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:01,871 - DEBUG - Unzip image
2026-01-15 03:18:01,871 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:01,935 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-15 03:18:01,936 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-07752616']
2026-01-15 03:18:02,041 - INFO - Service template: ['default-domain', 'ctest-service_template_1-07752616'] deleted successfully.
2026-01-15 03:18:02,041 - INFO - Skip interface_detach for VM ctest-right_vm-17841028 in state BUILD
2026-01-15 03:18:02,041 - INFO - Deleting VM ctest-right_vm-17841028
2026-01-15 03:18:02,081 - ERROR - VM ctest-right_vm-17841028 has failed to come up
2026-01-15 03:18:02,082 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:02,082 - ERROR - VM ctest-right_vm-17841028 has failed to come up
2026-01-15 03:18:02,082 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:02,186 - INFO - Skip interface_detach for VM ctest-left_vm-94100885 in state BUILD
2026-01-15 03:18:02,186 - INFO - Deleting VM ctest-left_vm-94100885
2026-01-15 03:18:02,186 - ERROR - VM ctest-left_vm-94100885 has failed to come up
2026-01-15 03:18:02,186 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:02,186 - ERROR - VM ctest-left_vm-94100885 has failed to come up
2026-01-15 03:18:02,186 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:02,265 - INFO - Deleting VN ctest-right_vn-24316475
2026-01-15 03:18:02,318 - DEBUG - VN fada5e5b-59b3-43eb-abff-0a85b986bb9d still in use: Unable to complete operation on network fada5e5b-59b3-43eb-abff-0a85b986bb9d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-04adb63d-b2b5-4897-a69d-24f26e75ff1b']
2026-01-15 03:18:02,319 - WARNING - Deleting VN ctest-right_vn-24316475 failed..Will retry
2026-01-15 03:18:04,487 - DEBUG - Response for deleting network ()
2026-01-15 03:18:04,487 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/577f2e06-e973-4337-9c4f-f51afbf22e99
2026-01-15 03:18:04,504 - DEBUG - Response Code: 404
2026-01-15 03:18:04,504 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:04,513 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:04,536 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:04,637 - INFO - Validated that VN ctest-right_vn-24316475 is not found in API Server
2026-01-15 03:18:04,650 - DEBUG - VN ctest-right_vn-24316475 is not present in Agent 10.0.0.141
2026-01-15 03:18:04,662 - DEBUG - VN ctest-right_vn-24316475 is not present in Agent 10.0.0.133
2026-01-15 03:18:04,662 - INFO - Validated that VN ctest-right_vn-24316475 is not in any agent
2026-01-15 03:18:04,703 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-24316475 info
2026-01-15 03:18:04,704 - INFO - Deleting VN ctest-left_vn-26811840
2026-01-15 03:18:04,936 - DEBUG - Response for deleting network ()
2026-01-15 03:18:04,936 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/41398f7c-07f9-4463-8a98-970d1fa94121
2026-01-15 03:18:04,950 - DEBUG - Response Code: 404
2026-01-15 03:18:04,951 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:04,959 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:05,020 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:05,161 - INFO - Validated that VN ctest-left_vn-26811840 is not found in API Server
2026-01-15 03:18:05,175 - DEBUG - VN ctest-left_vn-26811840 is not present in Agent 10.0.0.141
2026-01-15 03:18:05,188 - DEBUG - VN ctest-left_vn-26811840 is not present in Agent 10.0.0.133
2026-01-15 03:18:05,188 - INFO - Validated that VN ctest-left_vn-26811840 is not in any agent
2026-01-15 03:18:05,226 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-26811840 info
2026-01-15 03:18:05,226 - INFO - Deleting VN ctest-mgmt_vn-31435678
2026-01-15 03:18:05,405 - DEBUG - Response for deleting network ()
2026-01-15 03:18:05,405 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/2d0db260-1797-4083-b546-cdb25ff74b9d
2026-01-15 03:18:05,423 - DEBUG - Response Code: 404
2026-01-15 03:18:05,423 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:05,435 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:05,455 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:05,570 - INFO - Validated that VN ctest-mgmt_vn-31435678 is not found in API Server
2026-01-15 03:18:05,582 - DEBUG - VN ctest-mgmt_vn-31435678 is not present in Agent 10.0.0.141
2026-01-15 03:18:05,599 - DEBUG - VN ctest-mgmt_vn-31435678 is not present in Agent 10.0.0.133
2026-01-15 03:18:05,599 - INFO - Validated that VN ctest-mgmt_vn-31435678 is not in any agent
2026-01-15 03:18:05,642 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-31435678 info
2026-01-15 03:18:05,642 - INFO - Deleting VM ctest-vm-in-third-vn-45156599
2026-01-15 03:18:05,726 - INFO - Deleting VN ctest-third-vn-51986876
2026-01-15 03:18:05,772 - DEBUG - VN 6cb6d4a1-302b-4e1c-b6a0-95a4e1ec8ce1 still in use: Unable to complete operation on network 6cb6d4a1-302b-4e1c-b6a0-95a4e1ec8ce1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2e2e8022-61bd-4ed2-9a35-79dcb084f50e']
2026-01-15 03:18:05,772 - WARNING - Deleting VN ctest-third-vn-51986876 failed..Will retry
2026-01-15 03:18:07,926 - DEBUG - Response for deleting network ()
2026-01-15 03:18:09,237 - DEBUG - No XMPP flaps were noticed during the test
2026-01-15 03:18:09,237 - INFO -
2026-01-15 03:18:09,237 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:28]
2026-01-15 03:18:09,237 - INFO - --------------------------------------------------------------------------------
2026-01-15 03:18:09,240 - INFO - ================================================================================
2026-01-15 03:18:09,241 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-01-15 03:18:10,571 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.249': '0', '10.20.0.201': '0'}, '10.0.0.133': {'10.20.0.249': '0', '10.20.0.201': '0'}} with
2026-01-15 03:18:10,571 - INFO - Initial checks done. Running the testcase now
2026-01-15 03:18:10,571 - INFO -
2026-01-15 03:18:10,900 - DEBUG - Response for create_network : {'network': {'id': 'afae6e4a-ce11-43f0-8a49-4bbb0850e4b7', 'name': 'ctest-mgmt_vn-58529271', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'project_id': 'c8352b67e9ac4f709b6c939c520ab778', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34554456', 'ctest-mgmt_vn-58529271'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:11,215 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a0e36494-10ac-423a-9e6a-c7cab41132cb', 'name': '', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'network_id': 'afae6e4a-ce11-43f0-8a49-4bbb0850e4b7', 'ip_version': 4, 'cidr': '56.15.193.128/26', 'allocation_pools': [{'start': '56.15.193.130', 'end': '56.15.193.190'}], 'gateway_ip': '56.15.193.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.15.193.130', 'tags': [], 'project_id': 'c8352b67e9ac4f709b6c939c520ab778'}}
2026-01-15 03:18:11,247 - INFO - Created VN ctest-mgmt_vn-58529271
2026-01-15 03:18:11,260 - DEBUG - VN ctest-mgmt_vn-58529271 UUID is afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,369 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:11,382 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:11,398 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:11,531 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,555 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,570 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1e3af040-83dd-4152-98e5-cbee989d1afb
2026-01-15 03:18:11,586 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1e3af040-83dd-4152-98e5-cbee989d1afb
2026-01-15 03:18:11,598 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/62bf29d1-ebd0-4e54-af72-ebcab243fb03
2026-01-15 03:18:11,609 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:11,609 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,622 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1e3af040-83dd-4152-98e5-cbee989d1afb
2026-01-15 03:18:11,644 - INFO - Verified VN network id 13 for VN afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,644 - INFO - Verifications in API Server for VN ctest-mgmt_vn-58529271 passed
2026-01-15 03:18:11,644 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,669 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1e3af040-83dd-4152-98e5-cbee989d1afb
2026-01-15 03:18:11,679 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1e3af040-83dd-4152-98e5-cbee989d1afb
2026-01-15 03:18:11,688 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/62bf29d1-ebd0-4e54-af72-ebcab243fb03
2026-01-15 03:18:11,707 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12659176870148916208', 'uuid-lslong': '9964578917204419767'}, 'enable': 'true', 'created': '2026-01-15T03:18:10', 'last-modified': '2026-01-15T03:18:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.555601'}
2026-01-15 03:18:11,720 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:11,729 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12659176870148916208', 'uuid-lslong': '9964578917204419767'}, 'enable': 'true', 'created': '2026-01-15T03:18:10', 'last-modified': '2026-01-15T03:18:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.583358'}
2026-01-15 03:18:11,739 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:11,746 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12659176870148916208', 'uuid-lslong': '9964578917204419767'}, 'enable': 'true', 'created': '2026-01-15T03:18:10', 'last-modified': '2026-01-15T03:18:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.600507'}
2026-01-15 03:18:11,754 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:11,754 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-58529271 passed
2026-01-15 03:18:11,754 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-58529271 in API_Server ======
2026-01-15 03:18:11,754 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:11,764 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:11,783 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:11,916 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afae6e4a-ce11-43f0-8a49-4bbb0850e4b7
2026-01-15 03:18:11,939 - DEBUG - =>VN ctest-mgmt_vn-58529271 has no policy to be verified
2026-01-15 03:18:11,939 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:11,939 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:11,939 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:11,961 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271?flat'}
2026-01-15 03:18:11,961 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:11,961 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:11,977 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271?flat'}
2026-01-15 03:18:11,977 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:11,977 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:11,991 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271?flat'}
2026-01-15 03:18:11,991 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34554456:ctest-mgmt_vn-58529271 is found in opserver
2026-01-15 03:18:12,020 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:12,037 - DEBUG - VRF ids for VN ctest-mgmt_vn-58529271: {}
2026-01-15 03:18:12,347 - DEBUG - Response for create_network : {'network': {'id': 'd91a7f82-fa53-4d49-9a94-919327911566', 'name': 'ctest-left_vn-91456213', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'project_id': 'c8352b67e9ac4f709b6c939c520ab778', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34554456', 'ctest-left_vn-91456213'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:12,636 - DEBUG - Response for create_subnet : {'subnet': {'id': '607d64c7-2455-4504-a54d-625c967ef467', 'name': '', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'network_id': 'd91a7f82-fa53-4d49-9a94-919327911566', 'ip_version': 4, 'cidr': '80.19.242.192/26', 'allocation_pools': [{'start': '80.19.242.194', 'end': '80.19.242.254'}], 'gateway_ip': '80.19.242.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '80.19.242.194', 'tags': [], 'project_id': 'c8352b67e9ac4f709b6c939c520ab778'}}
2026-01-15 03:18:12,667 - INFO - Created VN ctest-left_vn-91456213
2026-01-15 03:18:12,680 - DEBUG - VN ctest-left_vn-91456213 UUID is d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:12,789 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:12,798 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:12,814 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:12,941 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:12,965 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:12,978 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cc85640-e940-4723-80ca-3902cdaa9aeb
2026-01-15 03:18:12,988 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cc85640-e940-4723-80ca-3902cdaa9aeb
2026-01-15 03:18:12,997 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/b0f91841-20ba-421d-aaf2-cad13d954861
2026-01-15 03:18:13,006 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:13,007 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:13,026 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cc85640-e940-4723-80ca-3902cdaa9aeb
2026-01-15 03:18:13,064 - INFO - Verified VN network id 14 for VN d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:13,065 - INFO - Verifications in API Server for VN ctest-left_vn-91456213 passed
2026-01-15 03:18:13,065 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:13,078 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cc85640-e940-4723-80ca-3902cdaa9aeb
2026-01-15 03:18:13,087 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cc85640-e940-4723-80ca-3902cdaa9aeb
2026-01-15 03:18:13,095 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/b0f91841-20ba-421d-aaf2-cad13d954861
2026-01-15 03:18:13,113 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15643956456147078473', 'uuid-lslong': '11138687839604118886'}, 'enable': 'true', 'created': '2026-01-15T03:18:12', 'last-modified': '2026-01-15T03:18:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.530868'}
2026-01-15 03:18:13,120 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:13,133 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15643956456147078473', 'uuid-lslong': '11138687839604118886'}, 'enable': 'true', 'created': '2026-01-15T03:18:12', 'last-modified': '2026-01-15T03:18:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.546408'}
2026-01-15 03:18:13,151 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:13,163 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15643956456147078473', 'uuid-lslong': '11138687839604118886'}, 'enable': 'true', 'created': '2026-01-15T03:18:12', 'last-modified': '2026-01-15T03:18:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.579654'}
2026-01-15 03:18:13,172 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:13,172 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-91456213 passed
2026-01-15 03:18:13,173 - DEBUG - ====Verifying policy data for ctest-left_vn-91456213 in API_Server ======
2026-01-15 03:18:13,173 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:13,182 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:13,205 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:13,304 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d91a7f82-fa53-4d49-9a94-919327911566
2026-01-15 03:18:13,341 - DEBUG - =>VN ctest-left_vn-91456213 has no policy to be verified
2026-01-15 03:18:13,341 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:13,341 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:13,341 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:13,358 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213?flat'}
2026-01-15 03:18:13,358 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:13,358 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:13,377 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213?flat'}
2026-01-15 03:18:13,377 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:13,377 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:13,404 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213?flat'}
2026-01-15 03:18:13,404 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34554456:ctest-left_vn-91456213 is found in opserver
2026-01-15 03:18:13,430 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:13,441 - DEBUG - VRF ids for VN ctest-left_vn-91456213: {}
2026-01-15 03:18:13,770 - DEBUG - Response for create_network : {'network': {'id': '643c7098-5b54-42e3-a7d3-c170e0dd4da7', 'name': 'ctest-right_vn-03026888', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'project_id': 'c8352b67e9ac4f709b6c939c520ab778', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34554456', 'ctest-right_vn-03026888'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:14,079 - DEBUG - Response for create_subnet : {'subnet': {'id': '0568b662-ab0f-478e-977a-c89e6086ca2e', 'name': '', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'network_id': '643c7098-5b54-42e3-a7d3-c170e0dd4da7', 'ip_version': 4, 'cidr': '18.205.166.0/26', 'allocation_pools': [{'start': '18.205.166.2', 'end': '18.205.166.62'}], 'gateway_ip': '18.205.166.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '18.205.166.2', 'tags': [], 'project_id': 'c8352b67e9ac4f709b6c939c520ab778'}}
2026-01-15 03:18:14,111 - INFO - Created VN ctest-right_vn-03026888
2026-01-15 03:18:14,129 - DEBUG - VN ctest-right_vn-03026888 UUID is 643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,232 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:14,239 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:14,252 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:14,360 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,381 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,399 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e3c3cb27-2383-49a8-a769-61e106f01e7e
2026-01-15 03:18:14,411 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e3c3cb27-2383-49a8-a769-61e106f01e7e
2026-01-15 03:18:14,424 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f53f0d80-4584-482b-aec9-b0fd14a3e97e
2026-01-15 03:18:14,440 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:14,440 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,455 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e3c3cb27-2383-49a8-a769-61e106f01e7e
2026-01-15 03:18:14,487 - INFO - Verified VN network id 15 for VN 643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,487 - INFO - Verifications in API Server for VN ctest-right_vn-03026888 passed
2026-01-15 03:18:14,487 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,504 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e3c3cb27-2383-49a8-a769-61e106f01e7e
2026-01-15 03:18:14,514 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e3c3cb27-2383-49a8-a769-61e106f01e7e
2026-01-15 03:18:14,523 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f53f0d80-4584-482b-aec9-b0fd14a3e97e
2026-01-15 03:18:14,547 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7222771702065021667', 'uuid-lslong': '12093222114973011367'}, 'enable': 'true', 'created': '2026-01-15T03:18:13', 'last-modified': '2026-01-15T03:18:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.559480'}
2026-01-15 03:18:14,565 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:14,571 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7222771702065021667', 'uuid-lslong': '12093222114973011367'}, 'enable': 'true', 'created': '2026-01-15T03:18:13', 'last-modified': '2026-01-15T03:18:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.581764'}
2026-01-15 03:18:14,578 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:14,584 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7222771702065021667', 'uuid-lslong': '12093222114973011367'}, 'enable': 'true', 'created': '2026-01-15T03:18:13', 'last-modified': '2026-01-15T03:18:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.595478'}
2026-01-15 03:18:14,591 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:14,591 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-03026888 passed
2026-01-15 03:18:14,592 - DEBUG - ====Verifying policy data for ctest-right_vn-03026888 in API_Server ======
2026-01-15 03:18:14,592 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:14,599 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:14,616 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:14,751 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/643c7098-5b54-42e3-a7d3-c170e0dd4da7
2026-01-15 03:18:14,771 - DEBUG - =>VN ctest-right_vn-03026888 has no policy to be verified
2026-01-15 03:18:14,772 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:14,772 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:14,772 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:14,789 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888?flat'}
2026-01-15 03:18:14,789 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:14,789 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:14,807 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888?flat'}
2026-01-15 03:18:14,807 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:14,807 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:14,824 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888?flat'}
2026-01-15 03:18:14,825 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34554456:ctest-right_vn-03026888 is found in opserver
2026-01-15 03:18:14,854 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:14,870 - DEBUG - VRF ids for VN ctest-right_vn-03026888: {}
2026-01-15 03:18:15,269 - DEBUG - Response for create_network : {'network': {'id': '1f385a0a-5ba0-4b44-a965-6295d9d0c9f2', 'name': 'ctest-trans_left_vn-05300359', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'project_id': 'c8352b67e9ac4f709b6c939c520ab778', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34554456', 'ctest-trans_left_vn-05300359'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:15,568 - DEBUG - Response for create_subnet : {'subnet': {'id': '6ee89523-8354-4ac8-87e3-d38449caba32', 'name': '', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'network_id': '1f385a0a-5ba0-4b44-a965-6295d9d0c9f2', 'ip_version': 4, 'cidr': '88.106.103.192/26', 'allocation_pools': [{'start': '88.106.103.194', 'end': '88.106.103.254'}], 'gateway_ip': '88.106.103.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.106.103.194', 'tags': [], 'project_id': 'c8352b67e9ac4f709b6c939c520ab778'}}
2026-01-15 03:18:15,601 - INFO - Created VN ctest-trans_left_vn-05300359
2026-01-15 03:18:15,612 - DEBUG - VN ctest-trans_left_vn-05300359 UUID is 1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:15,707 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:15,714 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:15,731 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:15,870 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:15,886 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:15,898 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c1f174b5-24ed-4602-bef5-029a75c3ecb7
2026-01-15 03:18:15,912 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c1f174b5-24ed-4602-bef5-029a75c3ecb7
2026-01-15 03:18:15,922 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/4352ee55-ff57-4208-ae52-ab182cad03cb
2026-01-15 03:18:15,932 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-15 03:18:15,932 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:15,944 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c1f174b5-24ed-4602-bef5-029a75c3ecb7
2026-01-15 03:18:15,964 - INFO - Verified VN network id 16 for VN 1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:15,965 - INFO - Verifications in API Server for VN ctest-trans_left_vn-05300359 passed
2026-01-15 03:18:15,965 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:15,978 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c1f174b5-24ed-4602-bef5-029a75c3ecb7
2026-01-15 03:18:15,988 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c1f174b5-24ed-4602-bef5-029a75c3ecb7
2026-01-15 03:18:15,999 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/4352ee55-ff57-4208-ae52-ab182cad03cb
2026-01-15 03:18:16,022 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2249647014404967236', 'uuid-lslong': '12206270760801585650'}, 'enable': 'true', 'created': '2026-01-15T03:18:15', 'last-modified': '2026-01-15T03:18:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.524713'}
2026-01-15 03:18:16,032 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-15 03:18:16,039 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2249647014404967236', 'uuid-lslong': '12206270760801585650'}, 'enable': 'true', 'created': '2026-01-15T03:18:15', 'last-modified': '2026-01-15T03:18:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532389'}
2026-01-15 03:18:16,047 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-15 03:18:16,058 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2249647014404967236', 'uuid-lslong': '12206270760801585650'}, 'enable': 'true', 'created': '2026-01-15T03:18:15', 'last-modified': '2026-01-15T03:18:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.551742'}
2026-01-15 03:18:16,065 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-15 03:18:16,065 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-05300359 passed
2026-01-15 03:18:16,065 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-05300359 in API_Server ======
2026-01-15 03:18:16,065 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:16,072 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:16,089 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:16,202 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/1f385a0a-5ba0-4b44-a965-6295d9d0c9f2
2026-01-15 03:18:16,226 - DEBUG - =>VN ctest-trans_left_vn-05300359 has no policy to be verified
2026-01-15 03:18:16,226 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:16,226 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:16,226 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:16,240 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359?flat'}
2026-01-15 03:18:16,241 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:16,241 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:16,253 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359?flat'}
2026-01-15 03:18:16,253 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:16,254 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:16,267 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359?flat'}
2026-01-15 03:18:16,267 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34554456:ctest-trans_left_vn-05300359 is found in opserver
2026-01-15 03:18:16,294 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:16,310 - DEBUG - VRF ids for VN ctest-trans_left_vn-05300359: {}
2026-01-15 03:18:16,601 - DEBUG - Response for create_network : {'network': {'id': 'b41cce96-a2dd-41bd-ae74-bf982f642d34', 'name': 'ctest-trans_right_vn-10672594', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'project_id': 'c8352b67e9ac4f709b6c939c520ab778', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34554456', 'ctest-trans_right_vn-10672594'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:16,805 - DEBUG - Response for create_subnet : {'subnet': {'id': '5807e259-4041-4c34-815e-040bc27fd369', 'name': '', 'tenant_id': 'c8352b67e9ac4f709b6c939c520ab778', 'network_id': 'b41cce96-a2dd-41bd-ae74-bf982f642d34', 'ip_version': 4, 'cidr': '49.44.169.64/26', 'allocation_pools': [{'start': '49.44.169.66', 'end': '49.44.169.126'}], 'gateway_ip': '49.44.169.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '49.44.169.66', 'tags': [], 'project_id': 'c8352b67e9ac4f709b6c939c520ab778'}}
2026-01-15 03:18:16,835 - INFO - Created VN ctest-trans_right_vn-10672594
2026-01-15 03:18:16,849 - DEBUG - VN ctest-trans_right_vn-10672594 UUID is b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:16,934 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:16,941 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:16,955 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:17,065 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:17,081 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:17,093 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/afde172d-2f90-4a06-a71a-ae26e8f22e46
2026-01-15 03:18:17,102 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/afde172d-2f90-4a06-a71a-ae26e8f22e46
2026-01-15 03:18:17,111 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/a0715733-2b11-42d2-89b3-6fb8f41bf0ea
2026-01-15 03:18:17,121 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-15 03:18:17,121 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:17,132 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/afde172d-2f90-4a06-a71a-ae26e8f22e46
2026-01-15 03:18:17,150 - INFO - Verified VN network id 17 for VN b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:17,150 - INFO - Verifications in API Server for VN ctest-trans_right_vn-10672594 passed
2026-01-15 03:18:17,150 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:17,162 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/afde172d-2f90-4a06-a71a-ae26e8f22e46
2026-01-15 03:18:17,172 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/afde172d-2f90-4a06-a71a-ae26e8f22e46
2026-01-15 03:18:17,190 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/a0715733-2b11-42d2-89b3-6fb8f41bf0ea
2026-01-15 03:18:17,205 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12978475372547752381', 'uuid-lslong': '12570883120248925492'}, 'enable': 'true', 'created': '2026-01-15T03:18:16', 'last-modified': '2026-01-15T03:18:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453509'}
2026-01-15 03:18:17,212 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-15 03:18:17,218 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12978475372547752381', 'uuid-lslong': '12570883120248925492'}, 'enable': 'true', 'created': '2026-01-15T03:18:16', 'last-modified': '2026-01-15T03:18:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466231'}
2026-01-15 03:18:17,225 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-15 03:18:17,232 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34554456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12978475372547752381', 'uuid-lslong': '12570883120248925492'}, 'enable': 'true', 'created': '2026-01-15T03:18:16', 'last-modified': '2026-01-15T03:18:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481669'}
2026-01-15 03:18:17,239 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-15 03:18:17,239 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-10672594 passed
2026-01-15 03:18:17,239 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-10672594 in API_Server ======
2026-01-15 03:18:17,239 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:17,246 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:17,261 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:17,357 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b41cce96-a2dd-41bd-ae74-bf982f642d34
2026-01-15 03:18:17,371 - DEBUG - =>VN ctest-trans_right_vn-10672594 has no policy to be verified
2026-01-15 03:18:17,371 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:17,371 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:17,371 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:17,391 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594?flat'}
2026-01-15 03:18:17,391 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:17,391 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:17,406 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594?flat'}
2026-01-15 03:18:17,407 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:17,407 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:17,420 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594?flat'}
2026-01-15 03:18:17,420 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34554456:ctest-trans_right_vn-10672594 is found in opserver
2026-01-15 03:18:17,446 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:17,459 - DEBUG - VRF ids for VN ctest-trans_right_vn-10672594: {}
2026-01-15 03:18:18,717 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-2)
2026-01-15 03:18:20,301 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-1)
2026-01-15 03:18:20,301 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-51075771']
2026-01-15 03:18:20,595 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-51075771'] created succesfully
2026-01-15 03:18:21,133 - DEBUG - Image by name tiny_trans_fw not found
2026-01-15 03:18:21,133 - DEBUG - Installing image tiny_trans_fw
2026-01-15 03:18:21,133 - 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-15 03:18:21,134 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-15 03:18:21,134 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-15 03:18:21,154 - 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-15 03:18:21,190 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-15 03:18:21,190 - DEBUG - Unzip image
2026-01-15 03:18:21,190 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-15 03:18:21,217 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-01-15 03:18:21,218 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-51075771']
2026-01-15 03:18:21,305 - INFO - Service template: ['default-domain', 'ctest-service_template_1-51075771'] deleted successfully.
2026-01-15 03:18:21,305 - INFO - Skip interface_detach for VM ctest-right_vm-54818877 in state BUILD
2026-01-15 03:18:21,306 - INFO - Deleting VM ctest-right_vm-54818877
2026-01-15 03:18:21,306 - ERROR - VM ctest-right_vm-54818877 has failed to come up
2026-01-15 03:18:21,306 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:21,306 - ERROR - VM ctest-right_vm-54818877 has failed to come up
2026-01-15 03:18:21,307 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:21,466 - INFO - Skip interface_detach for VM ctest-left_vm-93856431 in state BUILD
2026-01-15 03:18:21,466 - INFO - Deleting VM ctest-left_vm-93856431
2026-01-15 03:18:21,466 - ERROR - VM ctest-left_vm-93856431 has failed to come up
2026-01-15 03:18:21,466 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:21,466 - ERROR - VM ctest-left_vm-93856431 has failed to come up
2026-01-15 03:18:21,466 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:21,563 - INFO - Deleting VN ctest-trans_right_vn-10672594
2026-01-15 03:18:21,724 - DEBUG - Response for deleting network ()
2026-01-15 03:18:21,725 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/afde172d-2f90-4a06-a71a-ae26e8f22e46
2026-01-15 03:18:21,735 - DEBUG - Response Code: 404
2026-01-15 03:18:21,735 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:21,747 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:21,767 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:21,886 - INFO - Validated that VN ctest-trans_right_vn-10672594 is not found in API Server
2026-01-15 03:18:21,909 - DEBUG - VN ctest-trans_right_vn-10672594 is not present in Agent 10.0.0.141
2026-01-15 03:18:21,928 - DEBUG - VN ctest-trans_right_vn-10672594 is not present in Agent 10.0.0.133
2026-01-15 03:18:21,928 - INFO - Validated that VN ctest-trans_right_vn-10672594 is not in any agent
2026-01-15 03:18:21,998 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-10672594 info
2026-01-15 03:18:21,998 - INFO - Deleting VN ctest-trans_left_vn-05300359
2026-01-15 03:18:22,316 - DEBUG - Response for deleting network ()
2026-01-15 03:18:22,316 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c1f174b5-24ed-4602-bef5-029a75c3ecb7
2026-01-15 03:18:22,328 - DEBUG - Response Code: 404
2026-01-15 03:18:22,329 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:22,336 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:22,351 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:22,472 - INFO - Validated that VN ctest-trans_left_vn-05300359 is not found in API Server
2026-01-15 03:18:22,519 - DEBUG - VN ctest-trans_left_vn-05300359 is not present in Agent 10.0.0.141
2026-01-15 03:18:22,531 - DEBUG - VN ctest-trans_left_vn-05300359 is not present in Agent 10.0.0.133
2026-01-15 03:18:22,531 - INFO - Validated that VN ctest-trans_left_vn-05300359 is not in any agent
2026-01-15 03:18:22,587 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-05300359 info
2026-01-15 03:18:22,588 - INFO - Deleting VN ctest-right_vn-03026888
2026-01-15 03:18:22,653 - DEBUG - VN 643c7098-5b54-42e3-a7d3-c170e0dd4da7 still in use: Unable to complete operation on network 643c7098-5b54-42e3-a7d3-c170e0dd4da7. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7882256e-f037-4594-82b9-0b08cfb8e980']
2026-01-15 03:18:22,654 - WARNING - Deleting VN ctest-right_vn-03026888 failed..Will retry
2026-01-15 03:18:24,887 - DEBUG - Response for deleting network ()
2026-01-15 03:18:24,887 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/e3c3cb27-2383-49a8-a769-61e106f01e7e
2026-01-15 03:18:24,896 - DEBUG - Response Code: 404
2026-01-15 03:18:24,897 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:24,906 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:24,924 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:25,043 - INFO - Validated that VN ctest-right_vn-03026888 is not found in API Server
2026-01-15 03:18:25,055 - DEBUG - VN ctest-right_vn-03026888 is not present in Agent 10.0.0.141
2026-01-15 03:18:25,068 - DEBUG - VN ctest-right_vn-03026888 is not present in Agent 10.0.0.133
2026-01-15 03:18:25,068 - INFO - Validated that VN ctest-right_vn-03026888 is not in any agent
2026-01-15 03:18:25,124 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-03026888 info
2026-01-15 03:18:25,125 - INFO - Deleting VN ctest-left_vn-91456213
2026-01-15 03:18:25,305 - DEBUG - Response for deleting network ()
2026-01-15 03:18:25,305 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/8cc85640-e940-4723-80ca-3902cdaa9aeb
2026-01-15 03:18:25,313 - DEBUG - Response Code: 404
2026-01-15 03:18:25,313 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:25,320 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:25,345 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:25,448 - INFO - Validated that VN ctest-left_vn-91456213 is not found in API Server
2026-01-15 03:18:25,466 - DEBUG - VN ctest-left_vn-91456213 is not present in Agent 10.0.0.141
2026-01-15 03:18:25,480 - DEBUG - VN ctest-left_vn-91456213 is not present in Agent 10.0.0.133
2026-01-15 03:18:25,480 - INFO - Validated that VN ctest-left_vn-91456213 is not in any agent
2026-01-15 03:18:25,516 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-91456213 info
2026-01-15 03:18:25,516 - INFO - Deleting VN ctest-mgmt_vn-58529271
2026-01-15 03:18:25,669 - DEBUG - Response for deleting network ()
2026-01-15 03:18:25,669 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/1e3af040-83dd-4152-98e5-cbee989d1afb
2026-01-15 03:18:25,683 - DEBUG - Response Code: 404
2026-01-15 03:18:25,683 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:25,695 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:25,711 - DEBUG - Requesting: http://10.0.0.242:8082/project/c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:25,809 - INFO - Validated that VN ctest-mgmt_vn-58529271 is not found in API Server
2026-01-15 03:18:25,823 - DEBUG - VN ctest-mgmt_vn-58529271 is not present in Agent 10.0.0.141
2026-01-15 03:18:25,837 - DEBUG - VN ctest-mgmt_vn-58529271 is not present in Agent 10.0.0.133
2026-01-15 03:18:25,837 - INFO - Validated that VN ctest-mgmt_vn-58529271 is not in any agent
2026-01-15 03:18:25,873 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-58529271 info
2026-01-15 03:18:27,161 - DEBUG - No XMPP flaps were noticed during the test
2026-01-15 03:18:27,161 - INFO -
2026-01-15 03:18:27,161 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:18]
2026-01-15 03:18:27,161 - INFO - --------------------------------------------------------------------------------
2026-01-15 03:18:27,997 - INFO - Deleted project: ctest-TestSvcRegr-34554456, ID : c8352b67-e9ac-4f70-9b6c-939c520ab778
2026-01-15 03:18:28,252 - INFO - Domain Default found not creating
2026-01-15 03:18:28,466 - INFO - Project ctest-TestSvcRegr-96913650 not found, creating it
2026-01-15 03:18:29,022 - INFO - Created Project:ctest-TestSvcRegr-96913650, ID : 89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:30,938 - INFO - ================================================================================
2026-01-15 03:18:30,938 - INFO - STARTING TEST : test_svc_CEM_22032
2026-01-15 03:18:32,238 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.249': '0', '10.20.0.201': '0'}, '10.0.0.133': {'10.20.0.249': '0', '10.20.0.201': '0'}} with
2026-01-15 03:18:32,238 - INFO - Initial checks done. Running the testcase now
2026-01-15 03:18:32,238 - INFO -
2026-01-15 03:18:33,084 - DEBUG - Response for create_network : {'network': {'id': 'a78566e8-723a-405e-941d-ab9f6f85558f', 'name': 'ctest-mgmt_vn-26563076', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96913650', 'ctest-mgmt_vn-26563076'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:33,384 - DEBUG - Response for create_subnet : {'subnet': {'id': '0058a410-8c52-469e-bace-f6c99da2a936', 'name': '', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'network_id': 'a78566e8-723a-405e-941d-ab9f6f85558f', 'ip_version': 4, 'cidr': '2.166.117.128/26', 'allocation_pools': [{'start': '2.166.117.130', 'end': '2.166.117.190'}], 'gateway_ip': '2.166.117.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2.166.117.130', 'tags': [], 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a'}}
2026-01-15 03:18:33,411 - INFO - Created VN ctest-mgmt_vn-26563076
2026-01-15 03:18:33,422 - DEBUG - VN ctest-mgmt_vn-26563076 UUID is a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:33,513 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:33,885 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:33,924 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:33,942 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:34,063 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:34,077 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:34,090 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/befec510-73e3-4322-96aa-955f039ece59
2026-01-15 03:18:34,104 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/befec510-73e3-4322-96aa-955f039ece59
2026-01-15 03:18:34,113 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/16b1055c-8aae-464b-b7eb-d9eda9fd76f9
2026-01-15 03:18:34,122 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:34,122 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:34,135 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/befec510-73e3-4322-96aa-955f039ece59
2026-01-15 03:18:34,154 - INFO - Verified VN network id 13 for VN a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:34,155 - INFO - Verifications in API Server for VN ctest-mgmt_vn-26563076 passed
2026-01-15 03:18:34,155 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:34,169 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/befec510-73e3-4322-96aa-955f039ece59
2026-01-15 03:18:34,179 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/befec510-73e3-4322-96aa-955f039ece59
2026-01-15 03:18:34,188 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/16b1055c-8aae-464b-b7eb-d9eda9fd76f9
2026-01-15 03:18:34,204 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12071167524771348574', 'uuid-lslong': '10672875393197102479'}, 'enable': 'true', 'created': '2026-01-15T03:18:33', 'last-modified': '2026-01-15T03:18:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.873486'}
2026-01-15 03:18:34,212 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:34,218 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12071167524771348574', 'uuid-lslong': '10672875393197102479'}, 'enable': 'true', 'created': '2026-01-15T03:18:33', 'last-modified': '2026-01-15T03:18:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.887208'}
2026-01-15 03:18:34,226 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:34,233 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12071167524771348574', 'uuid-lslong': '10672875393197102479'}, 'enable': 'true', 'created': '2026-01-15T03:18:33', 'last-modified': '2026-01-15T03:18:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.898852'}
2026-01-15 03:18:34,241 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:34,241 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-26563076 passed
2026-01-15 03:18:34,241 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-26563076 in API_Server ======
2026-01-15 03:18:34,241 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:34,250 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:34,265 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:34,352 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78566e8-723a-405e-941d-ab9f6f85558f
2026-01-15 03:18:34,365 - DEBUG - =>VN ctest-mgmt_vn-26563076 has no policy to be verified
2026-01-15 03:18:34,365 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:34,365 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:34,365 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:34,770 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:34,871 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076?flat'}
2026-01-15 03:18:34,871 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:34,871 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:35,259 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:35,341 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076?flat'}
2026-01-15 03:18:35,341 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:35,341 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:35,722 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:35,799 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076?flat'}
2026-01-15 03:18:35,799 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-26563076 is found in opserver
2026-01-15 03:18:35,824 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:35,860 - DEBUG - VRF ids for VN ctest-mgmt_vn-26563076: {}
2026-01-15 03:18:36,146 - DEBUG - Response for create_network : {'network': {'id': '703a4b6b-86ef-4bd9-ad5c-821f8aceb94c', 'name': 'service-left-vn-23965828', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96913650', 'service-left-vn-23965828'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:36,369 - DEBUG - Response for create_subnet : {'subnet': {'id': '7a2cc828-8500-4d14-aada-d2e65138ddb9', 'name': '', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'network_id': '703a4b6b-86ef-4bd9-ad5c-821f8aceb94c', 'ip_version': 4, 'cidr': '16.3.47.128/26', 'allocation_pools': [{'start': '16.3.47.130', 'end': '16.3.47.190'}], 'gateway_ip': '16.3.47.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '16.3.47.130', 'tags': [], 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a'}}
2026-01-15 03:18:36,403 - INFO - Created VN service-left-vn-23965828
2026-01-15 03:18:36,415 - DEBUG - VN service-left-vn-23965828 UUID is 703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,524 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:36,531 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:36,550 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:36,679 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,698 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,711 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5f669326-9aa3-48db-8952-fa2ea07ea7c0
2026-01-15 03:18:36,719 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5f669326-9aa3-48db-8952-fa2ea07ea7c0
2026-01-15 03:18:36,728 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/9858a30f-8ffc-407a-854e-0b37152f47e5
2026-01-15 03:18:36,737 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:36,738 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,750 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5f669326-9aa3-48db-8952-fa2ea07ea7c0
2026-01-15 03:18:36,768 - INFO - Verified VN network id 14 for VN 703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,768 - INFO - Verifications in API Server for VN service-left-vn-23965828 passed
2026-01-15 03:18:36,769 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,782 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5f669326-9aa3-48db-8952-fa2ea07ea7c0
2026-01-15 03:18:36,792 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5f669326-9aa3-48db-8952-fa2ea07ea7c0
2026-01-15 03:18:36,801 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/9858a30f-8ffc-407a-854e-0b37152f47e5
2026-01-15 03:18:36,816 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8086859006094560217', 'uuid-lslong': '12492002538403313996'}, 'enable': 'true', 'created': '2026-01-15T03:18:36', 'last-modified': '2026-01-15T03:18:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.492360'}
2026-01-15 03:18:36,827 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:36,833 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8086859006094560217', 'uuid-lslong': '12492002538403313996'}, 'enable': 'true', 'created': '2026-01-15T03:18:36', 'last-modified': '2026-01-15T03:18:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512099'}
2026-01-15 03:18:36,839 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:36,845 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8086859006094560217', 'uuid-lslong': '12492002538403313996'}, 'enable': 'true', 'created': '2026-01-15T03:18:36', 'last-modified': '2026-01-15T03:18:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.523683'}
2026-01-15 03:18:36,853 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:36,854 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-23965828 passed
2026-01-15 03:18:36,854 - DEBUG - ====Verifying policy data for service-left-vn-23965828 in API_Server ======
2026-01-15 03:18:36,854 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:36,862 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:36,875 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:36,977 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/703a4b6b-86ef-4bd9-ad5c-821f8aceb94c
2026-01-15 03:18:36,989 - DEBUG - =>VN service-left-vn-23965828 has no policy to be verified
2026-01-15 03:18:36,989 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:36,989 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:36,989 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:37,078 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828?flat'}
2026-01-15 03:18:37,078 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:37,078 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:37,152 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828?flat'}
2026-01-15 03:18:37,152 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:37,152 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:37,239 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828?flat'}
2026-01-15 03:18:37,239 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96913650:service-left-vn-23965828 is found in opserver
2026-01-15 03:18:37,277 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:37,300 - DEBUG - VRF ids for VN service-left-vn-23965828: {}
2026-01-15 03:18:37,584 - DEBUG - Response for create_network : {'network': {'id': 'f73cae31-d39e-414b-83b5-a99440755b20', 'name': 'service-right-vn-50406256', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96913650', 'service-right-vn-50406256'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:37,944 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f5245194-b8f3-48a0-829a-75ce0acc3c56', 'name': '', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'network_id': 'f73cae31-d39e-414b-83b5-a99440755b20', 'ip_version': 4, 'cidr': '104.130.191.0/26', 'allocation_pools': [{'start': '104.130.191.2', 'end': '104.130.191.62'}], 'gateway_ip': '104.130.191.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '104.130.191.2', 'tags': [], 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a'}}
2026-01-15 03:18:37,976 - INFO - Created VN service-right-vn-50406256
2026-01-15 03:18:37,992 - DEBUG - VN service-right-vn-50406256 UUID is f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,091 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:38,099 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:38,113 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:38,214 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,251 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,261 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae454021-cf04-4449-9803-b5e5c260b8b4
2026-01-15 03:18:38,271 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae454021-cf04-4449-9803-b5e5c260b8b4
2026-01-15 03:18:38,283 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/1f062173-666a-42d6-a643-4aa501ede985
2026-01-15 03:18:38,293 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:38,293 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,312 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae454021-cf04-4449-9803-b5e5c260b8b4
2026-01-15 03:18:38,338 - INFO - Verified VN network id 15 for VN f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,339 - INFO - Verifications in API Server for VN service-right-vn-50406256 passed
2026-01-15 03:18:38,339 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,357 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae454021-cf04-4449-9803-b5e5c260b8b4
2026-01-15 03:18:38,368 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae454021-cf04-4449-9803-b5e5c260b8b4
2026-01-15 03:18:38,380 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/1f062173-666a-42d6-a643-4aa501ede985
2026-01-15 03:18:38,399 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17815305754997834059', 'uuid-lslong': '9490678243954875168'}, 'enable': 'true', 'created': '2026-01-15T03:18:37', 'last-modified': '2026-01-15T03:18:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.552877'}
2026-01-15 03:18:38,406 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:38,412 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17815305754997834059', 'uuid-lslong': '9490678243954875168'}, 'enable': 'true', 'created': '2026-01-15T03:18:37', 'last-modified': '2026-01-15T03:18:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.570621'}
2026-01-15 03:18:38,419 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:38,427 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17815305754997834059', 'uuid-lslong': '9490678243954875168'}, 'enable': 'true', 'created': '2026-01-15T03:18:37', 'last-modified': '2026-01-15T03:18:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.586295'}
2026-01-15 03:18:38,434 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:38,434 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-50406256 passed
2026-01-15 03:18:38,434 - DEBUG - ====Verifying policy data for service-right-vn-50406256 in API_Server ======
2026-01-15 03:18:38,434 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:38,442 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:38,457 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:38,562 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f73cae31-d39e-414b-83b5-a99440755b20
2026-01-15 03:18:38,577 - DEBUG - =>VN service-right-vn-50406256 has no policy to be verified
2026-01-15 03:18:38,577 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:38,577 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:38,577 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:38,665 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256?flat'}
2026-01-15 03:18:38,665 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:38,665 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:38,743 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256?flat'}
2026-01-15 03:18:38,743 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:38,743 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:38,815 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256?flat'}
2026-01-15 03:18:38,815 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96913650:service-right-vn-50406256 is found in opserver
2026-01-15 03:18:38,843 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:38,855 - DEBUG - VRF ids for VN service-right-vn-50406256: {}
2026-01-15 03:18:38,921 - DEBUG - Services list from nova: [, , , ]
2026-01-15 03:18:40,267 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-2)
2026-01-15 03:18:41,940 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-1)
2026-01-15 03:18:41,941 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-70676908']
2026-01-15 03:18:42,289 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-70676908'] created succesfully
2026-01-15 03:18:42,808 - DEBUG - Image by name tiny_nat_fw not found
2026-01-15 03:18:42,808 - DEBUG - Installing image tiny_nat_fw
2026-01-15 03:18:42,808 - 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-15 03:18:42,808 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:42,808 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:42,808 - DEBUG - Unzip image
2026-01-15 03:18:42,808 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:42,861 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-15 03:18:42,861 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-70676908']
2026-01-15 03:18:42,960 - INFO - Service template: ['default-domain', 'ctest-service_template_1-70676908'] deleted successfully.
2026-01-15 03:18:42,961 - INFO - Skip interface_detach for VM ctest-right_vm-08805314 in state BUILD
2026-01-15 03:18:42,961 - INFO - Deleting VM ctest-right_vm-08805314
2026-01-15 03:18:42,999 - ERROR - VM ctest-right_vm-08805314 has failed to come up
2026-01-15 03:18:42,999 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:42,999 - ERROR - VM ctest-right_vm-08805314 has failed to come up
2026-01-15 03:18:42,999 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:43,088 - INFO - Skip interface_detach for VM ctest-left_vm-95476813 in state BUILD
2026-01-15 03:18:43,088 - INFO - Deleting VM ctest-left_vm-95476813
2026-01-15 03:18:43,088 - ERROR - VM ctest-left_vm-95476813 has failed to come up
2026-01-15 03:18:43,088 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:43,088 - ERROR - VM ctest-left_vm-95476813 has failed to come up
2026-01-15 03:18:43,089 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:43,193 - INFO - Deleting VN service-right-vn-50406256
2026-01-15 03:18:43,243 - DEBUG - VN f73cae31-d39e-414b-83b5-a99440755b20 still in use: Unable to complete operation on network f73cae31-d39e-414b-83b5-a99440755b20. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ee781f7f-c78e-413e-8689-d5d4a5b7a9e7']
2026-01-15 03:18:43,243 - WARNING - Deleting VN service-right-vn-50406256 failed..Will retry
2026-01-15 03:18:45,441 - DEBUG - Response for deleting network ()
2026-01-15 03:18:45,442 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae454021-cf04-4449-9803-b5e5c260b8b4
2026-01-15 03:18:45,449 - DEBUG - Response Code: 404
2026-01-15 03:18:45,450 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:45,458 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:45,496 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:45,669 - INFO - Validated that VN service-right-vn-50406256 is not found in API Server
2026-01-15 03:18:45,685 - DEBUG - VN service-right-vn-50406256 is not present in Agent 10.0.0.141
2026-01-15 03:18:45,727 - DEBUG - VN service-right-vn-50406256 is not present in Agent 10.0.0.133
2026-01-15 03:18:45,727 - INFO - Validated that VN service-right-vn-50406256 is not in any agent
2026-01-15 03:18:45,861 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-50406256 info
2026-01-15 03:18:45,861 - INFO - Deleting VN service-left-vn-23965828
2026-01-15 03:18:46,094 - DEBUG - Response for deleting network ()
2026-01-15 03:18:46,094 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5f669326-9aa3-48db-8952-fa2ea07ea7c0
2026-01-15 03:18:46,101 - DEBUG - Response Code: 404
2026-01-15 03:18:46,101 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:46,108 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:46,135 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:46,250 - INFO - Validated that VN service-left-vn-23965828 is not found in API Server
2026-01-15 03:18:46,265 - DEBUG - VN service-left-vn-23965828 is not present in Agent 10.0.0.141
2026-01-15 03:18:46,278 - DEBUG - VN service-left-vn-23965828 is not present in Agent 10.0.0.133
2026-01-15 03:18:46,279 - INFO - Validated that VN service-left-vn-23965828 is not in any agent
2026-01-15 03:18:46,317 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-23965828 info
2026-01-15 03:18:46,317 - INFO - Deleting VN ctest-mgmt_vn-26563076
2026-01-15 03:18:46,530 - DEBUG - Response for deleting network ()
2026-01-15 03:18:46,530 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/befec510-73e3-4322-96aa-955f039ece59
2026-01-15 03:18:46,547 - DEBUG - Response Code: 404
2026-01-15 03:18:46,547 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:46,558 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:46,578 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:46,690 - INFO - Validated that VN ctest-mgmt_vn-26563076 is not found in API Server
2026-01-15 03:18:46,703 - DEBUG - VN ctest-mgmt_vn-26563076 is not present in Agent 10.0.0.141
2026-01-15 03:18:46,716 - DEBUG - VN ctest-mgmt_vn-26563076 is not present in Agent 10.0.0.133
2026-01-15 03:18:46,716 - INFO - Validated that VN ctest-mgmt_vn-26563076 is not in any agent
2026-01-15 03:18:46,750 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-26563076 info
2026-01-15 03:18:48,048 - DEBUG - No XMPP flaps were noticed during the test
2026-01-15 03:18:48,048 - INFO -
2026-01-15 03:18:48,048 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:18]
2026-01-15 03:18:48,048 - INFO - --------------------------------------------------------------------------------
2026-01-15 03:18:48,052 - INFO - ================================================================================
2026-01-15 03:18:48,052 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-01-15 03:18:49,341 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.249': '0', '10.20.0.201': '0'}, '10.0.0.133': {'10.20.0.249': '0', '10.20.0.201': '0'}} with
2026-01-15 03:18:49,342 - INFO - Initial checks done. Running the testcase now
2026-01-15 03:18:49,342 - INFO -
2026-01-15 03:18:49,648 - DEBUG - Response for create_network : {'network': {'id': '9d841c9b-6bac-4469-a580-6f7c1c278db9', 'name': 'ctest-mgmt_vn-49848877', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96913650', 'ctest-mgmt_vn-49848877'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:49,961 - DEBUG - Response for create_subnet : {'subnet': {'id': '6990dbe1-920f-4fc0-b06e-24c5653f6075', 'name': '', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'network_id': '9d841c9b-6bac-4469-a580-6f7c1c278db9', 'ip_version': 4, 'cidr': '214.191.132.64/26', 'allocation_pools': [{'start': '214.191.132.66', 'end': '214.191.132.126'}], 'gateway_ip': '214.191.132.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.191.132.66', 'tags': [], 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a'}}
2026-01-15 03:18:49,984 - INFO - Created VN ctest-mgmt_vn-49848877
2026-01-15 03:18:50,000 - DEBUG - VN ctest-mgmt_vn-49848877 UUID is 9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,078 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:50,085 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:50,100 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:50,224 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,242 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,254 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/42be8f63-ca45-4f79-8370-dc99d5c1420f
2026-01-15 03:18:50,264 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/42be8f63-ca45-4f79-8370-dc99d5c1420f
2026-01-15 03:18:50,276 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f89889d9-efd4-4a21-8f34-9016022c1d0c
2026-01-15 03:18:50,287 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:50,287 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,301 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/42be8f63-ca45-4f79-8370-dc99d5c1420f
2026-01-15 03:18:50,325 - INFO - Verified VN network id 13 for VN 9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,325 - INFO - Verifications in API Server for VN ctest-mgmt_vn-49848877 passed
2026-01-15 03:18:50,325 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,339 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/42be8f63-ca45-4f79-8370-dc99d5c1420f
2026-01-15 03:18:50,348 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/42be8f63-ca45-4f79-8370-dc99d5c1420f
2026-01-15 03:18:50,358 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f89889d9-efd4-4a21-8f34-9016022c1d0c
2026-01-15 03:18:50,388 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11350228414732452969', 'uuid-lslong': '11925654392116055481'}, 'enable': 'true', 'created': '2026-01-15T03:18:49', 'last-modified': '2026-01-15T03:18:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500121'}
2026-01-15 03:18:50,405 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:50,411 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11350228414732452969', 'uuid-lslong': '11925654392116055481'}, 'enable': 'true', 'created': '2026-01-15T03:18:49', 'last-modified': '2026-01-15T03:18:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.535260'}
2026-01-15 03:18:50,418 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:50,425 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11350228414732452969', 'uuid-lslong': '11925654392116055481'}, 'enable': 'true', 'created': '2026-01-15T03:18:49', 'last-modified': '2026-01-15T03:18:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.546932'}
2026-01-15 03:18:50,432 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:18:50,432 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-49848877 passed
2026-01-15 03:18:50,433 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-49848877 in API_Server ======
2026-01-15 03:18:50,433 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:50,440 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:50,453 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:50,557 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/9d841c9b-6bac-4469-a580-6f7c1c278db9
2026-01-15 03:18:50,576 - DEBUG - =>VN ctest-mgmt_vn-49848877 has no policy to be verified
2026-01-15 03:18:50,576 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:50,576 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:50,576 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:50,605 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877?flat'}
2026-01-15 03:18:50,605 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:50,605 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:50,621 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877?flat'}
2026-01-15 03:18:50,621 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:50,621 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:50,635 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877?flat'}
2026-01-15 03:18:50,635 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96913650:ctest-mgmt_vn-49848877 is found in opserver
2026-01-15 03:18:50,665 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:50,680 - DEBUG - VRF ids for VN ctest-mgmt_vn-49848877: {}
2026-01-15 03:18:51,006 - DEBUG - Response for create_network : {'network': {'id': '5e101987-8386-4fd5-bb6b-37019742227f', 'name': 'ctest-left_vn-61101720', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96913650', 'ctest-left_vn-61101720'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:51,245 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cf7fcb7e-9228-4b9f-83e2-4540f2780661', 'name': '', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'network_id': '5e101987-8386-4fd5-bb6b-37019742227f', 'ip_version': 4, 'cidr': '102.240.210.64/26', 'allocation_pools': [{'start': '102.240.210.66', 'end': '102.240.210.126'}], 'gateway_ip': '102.240.210.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.240.210.66', 'tags': [], 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a'}}
2026-01-15 03:18:51,276 - INFO - Created VN ctest-left_vn-61101720
2026-01-15 03:18:51,290 - DEBUG - VN ctest-left_vn-61101720 UUID is 5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,378 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:51,387 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:51,401 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:51,525 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,552 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,564 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/071926cb-a793-474a-9adb-243bfc18425a
2026-01-15 03:18:51,577 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/071926cb-a793-474a-9adb-243bfc18425a
2026-01-15 03:18:51,590 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/5f4e42ee-4248-487f-8699-b7e85f96539e
2026-01-15 03:18:51,602 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:51,602 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,615 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/071926cb-a793-474a-9adb-243bfc18425a
2026-01-15 03:18:51,635 - INFO - Verified VN network id 14 for VN 5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,636 - INFO - Verifications in API Server for VN ctest-left_vn-61101720 passed
2026-01-15 03:18:51,637 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,650 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/071926cb-a793-474a-9adb-243bfc18425a
2026-01-15 03:18:51,659 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/071926cb-a793-474a-9adb-243bfc18425a
2026-01-15 03:18:51,667 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/5f4e42ee-4248-487f-8699-b7e85f96539e
2026-01-15 03:18:51,682 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6777945509010493397', 'uuid-lslong': '13504948387572753023'}, 'enable': 'true', 'created': '2026-01-15T03:18:50', 'last-modified': '2026-01-15T03:18:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.488424'}
2026-01-15 03:18:51,691 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:51,698 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6777945509010493397', 'uuid-lslong': '13504948387572753023'}, 'enable': 'true', 'created': '2026-01-15T03:18:50', 'last-modified': '2026-01-15T03:18:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.507171'}
2026-01-15 03:18:51,706 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:51,712 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6777945509010493397', 'uuid-lslong': '13504948387572753023'}, 'enable': 'true', 'created': '2026-01-15T03:18:50', 'last-modified': '2026-01-15T03:18:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.523429'}
2026-01-15 03:18:51,719 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:18:51,719 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-61101720 passed
2026-01-15 03:18:51,719 - DEBUG - ====Verifying policy data for ctest-left_vn-61101720 in API_Server ======
2026-01-15 03:18:51,719 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:51,727 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:51,740 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:51,849 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/5e101987-8386-4fd5-bb6b-37019742227f
2026-01-15 03:18:51,865 - DEBUG - =>VN ctest-left_vn-61101720 has no policy to be verified
2026-01-15 03:18:51,865 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:51,865 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:51,865 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:51,879 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720?flat'}
2026-01-15 03:18:51,879 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:51,879 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:51,895 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720?flat'}
2026-01-15 03:18:51,896 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:51,896 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:51,911 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720?flat'}
2026-01-15 03:18:51,911 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96913650:ctest-left_vn-61101720 is found in opserver
2026-01-15 03:18:51,940 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:51,952 - DEBUG - VRF ids for VN ctest-left_vn-61101720: {}
2026-01-15 03:18:52,253 - DEBUG - Response for create_network : {'network': {'id': '96f27626-0006-4f09-bddd-6749ba9cac67', 'name': 'ctest-right_vn-32861925', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96913650', 'ctest-right_vn-32861925'], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:18:52,493 - DEBUG - Response for create_subnet : {'subnet': {'id': '18e86b31-13ca-48c8-95d7-ebd0dcf5bc6f', 'name': '', 'tenant_id': '89ad525b61d64f5ca6ad8e8d2231e81a', 'network_id': '96f27626-0006-4f09-bddd-6749ba9cac67', 'ip_version': 4, 'cidr': '124.43.45.128/26', 'allocation_pools': [{'start': '124.43.45.130', 'end': '124.43.45.190'}], 'gateway_ip': '124.43.45.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '124.43.45.130', 'tags': [], 'project_id': '89ad525b61d64f5ca6ad8e8d2231e81a'}}
2026-01-15 03:18:52,520 - INFO - Created VN ctest-right_vn-32861925
2026-01-15 03:18:52,535 - DEBUG - VN ctest-right_vn-32861925 UUID is 96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:52,618 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:52,624 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:52,638 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:52,767 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:52,790 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:52,808 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/89c709ea-9b9c-4b95-80f3-a5a341bfc4af
2026-01-15 03:18:52,847 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/89c709ea-9b9c-4b95-80f3-a5a341bfc4af
2026-01-15 03:18:52,867 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/5a229bbc-556b-4ae2-9c5f-84565c7412f2
2026-01-15 03:18:52,891 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:52,891 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:52,909 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/89c709ea-9b9c-4b95-80f3-a5a341bfc4af
2026-01-15 03:18:52,942 - INFO - Verified VN network id 15 for VN 96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:52,943 - INFO - Verifications in API Server for VN ctest-right_vn-32861925 passed
2026-01-15 03:18:52,943 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:52,963 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/89c709ea-9b9c-4b95-80f3-a5a341bfc4af
2026-01-15 03:18:52,974 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/89c709ea-9b9c-4b95-80f3-a5a341bfc4af
2026-01-15 03:18:52,984 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/5a229bbc-556b-4ae2-9c5f-84565c7412f2
2026-01-15 03:18:53,007 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10876885955634417417', 'uuid-lslong': '13681204809382538343'}, 'enable': 'true', 'created': '2026-01-15T03:18:52', 'last-modified': '2026-01-15T03:18:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.559818'}
2026-01-15 03:18:53,016 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:53,026 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10876885955634417417', 'uuid-lslong': '13681204809382538343'}, 'enable': 'true', 'created': '2026-01-15T03:18:52', 'last-modified': '2026-01-15T03:18:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.584572'}
2026-01-15 03:18:53,041 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:53,052 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96913650', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10876885955634417417', 'uuid-lslong': '13681204809382538343'}, 'enable': 'true', 'created': '2026-01-15T03:18:52', 'last-modified': '2026-01-15T03:18:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.611136'}
2026-01-15 03:18:53,061 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:18:53,061 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-32861925 passed
2026-01-15 03:18:53,061 - DEBUG - ====Verifying policy data for ctest-right_vn-32861925 in API_Server ======
2026-01-15 03:18:53,061 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:53,071 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:53,095 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:53,197 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/96f27626-0006-4f09-bddd-6749ba9cac67
2026-01-15 03:18:53,209 - DEBUG - =>VN ctest-right_vn-32861925 has no policy to be verified
2026-01-15 03:18:53,209 - DEBUG - Verifying the vn in opserver
2026-01-15 03:18:53,209 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925 virtual network link through opserver 10.0.0.242
2026-01-15 03:18:53,209 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:18:53,224 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925?flat'}
2026-01-15 03:18:53,224 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925 virtual network link through opserver 10.0.0.33
2026-01-15 03:18:53,224 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:18:53,241 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925?flat'}
2026-01-15 03:18:53,241 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925 virtual network link through opserver 10.0.0.73
2026-01-15 03:18:53,241 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:18:53,257 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925?flat'}
2026-01-15 03:18:53,257 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96913650:ctest-right_vn-32861925 is found in opserver
2026-01-15 03:18:53,282 - DEBUG - Do not have enough data to verify VN in agent
2026-01-15 03:18:53,295 - DEBUG - VRF ids for VN ctest-right_vn-32861925: {}
2026-01-15 03:18:54,545 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-1)
2026-01-15 03:18:56,275 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5009-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5009-2)
2026-01-15 03:18:56,275 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-63030087']
2026-01-15 03:18:56,579 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-63030087'] created succesfully
2026-01-15 03:18:57,057 - DEBUG - Image by name tiny_nat_fw not found
2026-01-15 03:18:57,057 - DEBUG - Installing image tiny_nat_fw
2026-01-15 03:18:57,057 - 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-15 03:18:57,058 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:57,058 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:57,058 - DEBUG - Unzip image
2026-01-15 03:18:57,058 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-15 03:18:57,085 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-15 03:18:57,086 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-63030087']
2026-01-15 03:18:57,205 - INFO - Service template: ['default-domain', 'ctest-service_template_1-63030087'] deleted successfully.
2026-01-15 03:18:57,205 - INFO - Skip interface_detach for VM ctest-right_vm-00507234 in state BUILD
2026-01-15 03:18:57,205 - INFO - Deleting VM ctest-right_vm-00507234
2026-01-15 03:18:57,205 - ERROR - VM ctest-right_vm-00507234 has failed to come up
2026-01-15 03:18:57,205 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:57,205 - ERROR - VM ctest-right_vm-00507234 has failed to come up
2026-01-15 03:18:57,206 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:57,353 - INFO - Skip interface_detach for VM ctest-left_vm-89164201 in state BUILD
2026-01-15 03:18:57,353 - INFO - Deleting VM ctest-left_vm-89164201
2026-01-15 03:18:57,353 - ERROR - VM ctest-left_vm-89164201 has failed to come up
2026-01-15 03:18:57,353 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:57,353 - ERROR - VM ctest-left_vm-89164201 has failed to come up
2026-01-15 03:18:57,353 - ERROR - Nova failed to get host of the VM
2026-01-15 03:18:57,441 - INFO - Deleting VN ctest-right_vn-32861925
2026-01-15 03:18:57,486 - DEBUG - VN 96f27626-0006-4f09-bddd-6749ba9cac67 still in use: Unable to complete operation on network 96f27626-0006-4f09-bddd-6749ba9cac67. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c923da07-25cc-4e79-b69b-2707d31be4fb']
2026-01-15 03:18:57,487 - WARNING - Deleting VN ctest-right_vn-32861925 failed..Will retry
2026-01-15 03:18:59,692 - DEBUG - Response for deleting network ()
2026-01-15 03:18:59,692 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/89c709ea-9b9c-4b95-80f3-a5a341bfc4af
2026-01-15 03:18:59,719 - DEBUG - Response Code: 404
2026-01-15 03:18:59,719 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:18:59,733 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:18:59,761 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:18:59,862 - INFO - Validated that VN ctest-right_vn-32861925 is not found in API Server
2026-01-15 03:18:59,881 - DEBUG - VN ctest-right_vn-32861925 is not present in Agent 10.0.0.141
2026-01-15 03:18:59,894 - DEBUG - VN ctest-right_vn-32861925 is not present in Agent 10.0.0.133
2026-01-15 03:18:59,894 - INFO - Validated that VN ctest-right_vn-32861925 is not in any agent
2026-01-15 03:18:59,927 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-32861925 info
2026-01-15 03:18:59,927 - INFO - Deleting VN ctest-left_vn-61101720
2026-01-15 03:19:00,150 - DEBUG - Response for deleting network ()
2026-01-15 03:19:00,150 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/071926cb-a793-474a-9adb-243bfc18425a
2026-01-15 03:19:00,161 - DEBUG - Response Code: 404
2026-01-15 03:19:00,161 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:19:00,171 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:19:00,185 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:19:00,377 - INFO - Validated that VN ctest-left_vn-61101720 is not found in API Server
2026-01-15 03:19:00,399 - DEBUG - VN ctest-left_vn-61101720 is not present in Agent 10.0.0.141
2026-01-15 03:19:00,418 - DEBUG - VN ctest-left_vn-61101720 is not present in Agent 10.0.0.133
2026-01-15 03:19:00,418 - INFO - Validated that VN ctest-left_vn-61101720 is not in any agent
2026-01-15 03:19:00,469 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-61101720 info
2026-01-15 03:19:00,470 - INFO - Deleting VN ctest-mgmt_vn-49848877
2026-01-15 03:19:00,631 - DEBUG - Response for deleting network ()
2026-01-15 03:19:00,631 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/42be8f63-ca45-4f79-8370-dc99d5c1420f
2026-01-15 03:19:00,638 - DEBUG - Response Code: 404
2026-01-15 03:19:00,639 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:19:00,650 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:19:00,678 - DEBUG - Requesting: http://10.0.0.242:8082/project/89ad525b-61d6-4f5c-a6ad-8e8d2231e81a
2026-01-15 03:19:00,782 - INFO - Validated that VN ctest-mgmt_vn-49848877 is not found in API Server
2026-01-15 03:19:00,797 - DEBUG - VN ctest-mgmt_vn-49848877 is not present in Agent 10.0.0.141
2026-01-15 03:19:00,810 - DEBUG - VN ctest-mgmt_vn-49848877 is not present in Agent 10.0.0.133
2026-01-15 03:19:00,810 - INFO - Validated that VN ctest-mgmt_vn-49848877 is not in any agent
2026-01-15 03:19:00,855 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-49848877 info
2026-01-15 03:19:02,198 - DEBUG - No XMPP flaps were noticed during the test
2026-01-15 03:19:02,198 - INFO -
2026-01-15 03:19:02,198 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2026-01-15 03:19:02,198 - INFO - --------------------------------------------------------------------------------
2026-01-15 03:19:03,129 - INFO - Deleted project: ctest-TestSvcRegr-96913650, ID : 89ad525b-61d6-4f5c-a6ad-8e8d2231e81a