]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-1)
2025-12-22 03:10:41,605 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-87047003']
2025-12-22 03:10:41,925 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-87047003'] created succesfully
2025-12-22 03:10:42,305 - DEBUG - Image by name tiny_nat_fw not found
2025-12-22 03:10:42,305 - DEBUG - Installing image tiny_nat_fw
2025-12-22 03:10:42,305 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-12-22 03:10:42,306 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-12-22 03:10:42,306 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-12-22 03:10:42,328 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:10:42,506 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:10:42,507 - DEBUG - Unzip image
2025-12-22 03:10:42,507 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:10:42,530 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-22 03:10:42,530 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-87047003']
2025-12-22 03:10:42,638 - INFO - Service template: ['default-domain', 'ctest-service_template_1-87047003'] deleted successfully.
2025-12-22 03:10:42,638 - INFO - Skip interface_detach for VM ctest-right_vm-50099506 in state BUILD
2025-12-22 03:10:42,638 - INFO - Deleting VM ctest-right_vm-50099506
2025-12-22 03:10:42,662 - ERROR - VM ctest-right_vm-50099506 has failed to come up
2025-12-22 03:10:42,662 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:42,662 - ERROR - VM ctest-right_vm-50099506 has failed to come up
2025-12-22 03:10:42,662 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:42,725 - INFO - Skip interface_detach for VM ctest-left_vm-82857230 in state BUILD
2025-12-22 03:10:42,725 - INFO - Deleting VM ctest-left_vm-82857230
2025-12-22 03:10:42,725 - ERROR - VM ctest-left_vm-82857230 has failed to come up
2025-12-22 03:10:42,726 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:42,726 - ERROR - VM ctest-left_vm-82857230 has failed to come up
2025-12-22 03:10:42,726 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:42,792 - INFO - Deleting VN ctest-right_vn-20510507
2025-12-22 03:10:42,830 - DEBUG - VN d552eee5-5387-4ee4-b889-6182b44602ab still in use: Unable to complete operation on network d552eee5-5387-4ee4-b889-6182b44602ab. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5f36fc8f-c63a-4c52-92f5-4fa2800cd91a']
2025-12-22 03:10:42,830 - WARNING - Deleting VN ctest-right_vn-20510507 failed..Will retry
2025-12-22 03:10:44,992 - DEBUG - Response for deleting network ()
2025-12-22 03:10:44,992 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b78cc639-8aad-42a2-a519-2beef015a941
2025-12-22 03:10:44,998 - DEBUG - Response Code: 404
2025-12-22 03:10:44,998 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:45,004 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:45,062 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:45,159 - INFO - Validated that VN ctest-right_vn-20510507 is not found in API Server
2025-12-22 03:10:45,172 - DEBUG - VN ctest-right_vn-20510507 is not present in Agent 10.0.0.130
2025-12-22 03:10:45,183 - DEBUG - VN ctest-right_vn-20510507 is not present in Agent 10.0.0.19
2025-12-22 03:10:45,183 - INFO - Validated that VN ctest-right_vn-20510507 is not in any agent
2025-12-22 03:10:45,255 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-20510507 info
2025-12-22 03:10:45,256 - INFO - Deleting VN ctest-left_vn-46641996
2025-12-22 03:10:45,491 - DEBUG - Response for deleting network ()
2025-12-22 03:10:45,491 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/fbe7ba75-1a85-4c7a-aeec-fa9cb8572d53
2025-12-22 03:10:45,501 - DEBUG - Response Code: 404
2025-12-22 03:10:45,502 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:45,511 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:45,526 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:45,637 - INFO - Validated that VN ctest-left_vn-46641996 is not found in API Server
2025-12-22 03:10:45,650 - DEBUG - VN ctest-left_vn-46641996 is not present in Agent 10.0.0.130
2025-12-22 03:10:45,659 - DEBUG - VN ctest-left_vn-46641996 is not present in Agent 10.0.0.19
2025-12-22 03:10:45,660 - INFO - Validated that VN ctest-left_vn-46641996 is not in any agent
2025-12-22 03:10:45,694 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-46641996 info
2025-12-22 03:10:45,694 - INFO - Deleting VN ctest-mgmt_vn-46385805
2025-12-22 03:10:45,877 - DEBUG - Response for deleting network ()
2025-12-22 03:10:45,877 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/bbaa6fd0-c90f-4039-80f9-464b12f600d0
2025-12-22 03:10:45,882 - DEBUG - Response Code: 404
2025-12-22 03:10:45,882 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:45,887 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:45,910 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:46,010 - INFO - Validated that VN ctest-mgmt_vn-46385805 is not found in API Server
2025-12-22 03:10:46,023 - DEBUG - VN ctest-mgmt_vn-46385805 is not present in Agent 10.0.0.130
2025-12-22 03:10:46,033 - DEBUG - VN ctest-mgmt_vn-46385805 is not present in Agent 10.0.0.19
2025-12-22 03:10:46,034 - INFO - Validated that VN ctest-mgmt_vn-46385805 is not in any agent
2025-12-22 03:10:46,068 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-46385805 info
2025-12-22 03:10:46,069 - INFO - Deleting VM ctest-vm-in-third-vn-09673994
2025-12-22 03:10:46,135 - INFO - Deleting VN ctest-third-vn-31700388
2025-12-22 03:10:46,176 - DEBUG - VN e4adbd21-4358-4fb5-8f31-8e1cc31cf3fb still in use: Unable to complete operation on network e4adbd21-4358-4fb5-8f31-8e1cc31cf3fb. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ca7b3db0-0679-4b72-b4d9-d2779c50ec8b']
2025-12-22 03:10:46,177 - WARNING - Deleting VN ctest-third-vn-31700388 failed..Will retry
2025-12-22 03:10:48,344 - DEBUG - Response for deleting network ()
2025-12-22 03:10:49,631 - DEBUG - No XMPP flaps were noticed during the test
2025-12-22 03:10:49,631 - INFO -
2025-12-22 03:10:49,632 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-12-22 03:10:49,632 - INFO - --------------------------------------------------------------------------------
2025-12-22 03:10:49,635 - INFO - ================================================================================
2025-12-22 03:10:49,635 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-12-22 03:10:50,934 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.14': '0', '10.20.0.192': '0'}, '10.0.0.19': {'10.20.0.129': '0', '10.20.0.192': '0'}} with
2025-12-22 03:10:50,934 - INFO - Initial checks done. Running the testcase now
2025-12-22 03:10:50,934 - INFO -
2025-12-22 03:10:51,242 - DEBUG - Response for create_network : {'network': {'id': '3397bf98-72a7-4354-96f8-5bcca9f7e838', 'name': 'ctest-mgmt_vn-00641889', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'project_id': 'f3f9e331daf2400795c55d11b397c0b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-61346117', 'ctest-mgmt_vn-00641889'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:10:51,498 - DEBUG - Response for create_subnet : {'subnet': {'id': '33b79388-4d1c-4875-aa95-069c1161aceb', 'name': '', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'network_id': '3397bf98-72a7-4354-96f8-5bcca9f7e838', 'ip_version': 4, 'cidr': '88.179.152.192/26', 'allocation_pools': [{'start': '88.179.152.194', 'end': '88.179.152.254'}], 'gateway_ip': '88.179.152.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.179.152.194', 'tags': [], 'project_id': 'f3f9e331daf2400795c55d11b397c0b8'}}
2025-12-22 03:10:51,519 - INFO - Created VN ctest-mgmt_vn-00641889
2025-12-22 03:10:51,534 - DEBUG - VN ctest-mgmt_vn-00641889 UUID is 3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:51,602 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:51,609 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:51,618 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:51,708 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:51,722 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:51,733 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/848308a7-5b6f-44ae-b748-48fe310ddde1
2025-12-22 03:10:51,742 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/848308a7-5b6f-44ae-b748-48fe310ddde1
2025-12-22 03:10:51,748 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/12b8c7e1-399e-417c-a376-df4f505502fe
2025-12-22 03:10:51,759 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:10:51,759 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:51,770 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/848308a7-5b6f-44ae-b748-48fe310ddde1
2025-12-22 03:10:51,787 - INFO - Verified VN network id 10 for VN 3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:51,788 - INFO - Verifications in API Server for VN ctest-mgmt_vn-00641889 passed
2025-12-22 03:10:51,788 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:51,800 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/848308a7-5b6f-44ae-b748-48fe310ddde1
2025-12-22 03:10:51,808 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/848308a7-5b6f-44ae-b748-48fe310ddde1
2025-12-22 03:10:51,817 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/12b8c7e1-399e-417c-a376-df4f505502fe
2025-12-22 03:10:51,834 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3717650678897132372', 'uuid-lslong': '10878545834496485432'}, 'enable': 'true', 'created': '2025-12-22T03:10:51', 'last-modified': '2025-12-22T03:10:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381116'}
2025-12-22 03:10:51,845 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:10:51,852 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3717650678897132372', 'uuid-lslong': '10878545834496485432'}, 'enable': 'true', 'created': '2025-12-22T03:10:51', 'last-modified': '2025-12-22T03:10:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405098'}
2025-12-22 03:10:51,858 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:10:51,864 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3717650678897132372', 'uuid-lslong': '10878545834496485432'}, 'enable': 'true', 'created': '2025-12-22T03:10:51', 'last-modified': '2025-12-22T03:10:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416750'}
2025-12-22 03:10:51,871 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:10:51,871 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-00641889 passed
2025-12-22 03:10:51,871 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-00641889 in API_Server ======
2025-12-22 03:10:51,871 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:51,879 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:51,892 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:51,997 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3397bf98-72a7-4354-96f8-5bcca9f7e838
2025-12-22 03:10:52,009 - DEBUG - =>VN ctest-mgmt_vn-00641889 has no policy to be verified
2025-12-22 03:10:52,009 - DEBUG - Verifying the vn in opserver
2025-12-22 03:10:52,009 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889 virtual network link through opserver 10.0.0.23
2025-12-22 03:10:52,009 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:10:52,022 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889?flat'}
2025-12-22 03:10:52,022 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889 virtual network link through opserver 10.0.0.22
2025-12-22 03:10:52,022 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:10:52,035 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889?flat'}
2025-12-22 03:10:52,035 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889 virtual network link through opserver 10.0.0.65
2025-12-22 03:10:52,035 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:10:52,048 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889?flat'}
2025-12-22 03:10:52,048 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-61346117:ctest-mgmt_vn-00641889 is found in opserver
2025-12-22 03:10:52,073 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:10:52,084 - DEBUG - VRF ids for VN ctest-mgmt_vn-00641889: {}
2025-12-22 03:10:52,415 - DEBUG - Response for create_network : {'network': {'id': 'eb064e0c-ee59-4603-bd21-192b225b6b8b', 'name': 'ctest-left_vn-81125947', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'project_id': 'f3f9e331daf2400795c55d11b397c0b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-61346117', 'ctest-left_vn-81125947'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:10:52,651 - DEBUG - Response for create_subnet : {'subnet': {'id': '71428c46-cff8-4b1e-ad40-4cfab77d108c', 'name': '', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'network_id': 'eb064e0c-ee59-4603-bd21-192b225b6b8b', 'ip_version': 4, 'cidr': '59.176.209.128/26', 'allocation_pools': [{'start': '59.176.209.130', 'end': '59.176.209.190'}], 'gateway_ip': '59.176.209.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.176.209.130', 'tags': [], 'project_id': 'f3f9e331daf2400795c55d11b397c0b8'}}
2025-12-22 03:10:52,675 - INFO - Created VN ctest-left_vn-81125947
2025-12-22 03:10:52,689 - DEBUG - VN ctest-left_vn-81125947 UUID is eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:52,757 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:52,763 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:52,772 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:52,869 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:52,881 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:52,890 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/45b66b92-8006-4608-bf1a-a53874cc642a
2025-12-22 03:10:52,899 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/45b66b92-8006-4608-bf1a-a53874cc642a
2025-12-22 03:10:52,906 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/f1ff37d4-829f-48cb-88d6-bd2c8f5e701a
2025-12-22 03:10:52,914 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:10:52,914 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:52,923 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/45b66b92-8006-4608-bf1a-a53874cc642a
2025-12-22 03:10:52,938 - INFO - Verified VN network id 11 for VN eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:52,938 - INFO - Verifications in API Server for VN ctest-left_vn-81125947 passed
2025-12-22 03:10:52,938 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:52,948 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/45b66b92-8006-4608-bf1a-a53874cc642a
2025-12-22 03:10:52,961 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/45b66b92-8006-4608-bf1a-a53874cc642a
2025-12-22 03:10:52,969 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/f1ff37d4-829f-48cb-88d6-bd2c8f5e701a
2025-12-22 03:10:52,981 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16935309266218731011', 'uuid-lslong': '13628201620450536331'}, 'enable': 'true', 'created': '2025-12-22T03:10:52', 'last-modified': '2025-12-22T03:10:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393851'}
2025-12-22 03:10:52,986 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:10:52,992 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16935309266218731011', 'uuid-lslong': '13628201620450536331'}, 'enable': 'true', 'created': '2025-12-22T03:10:52', 'last-modified': '2025-12-22T03:10:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405037'}
2025-12-22 03:10:52,998 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:10:53,004 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16935309266218731011', 'uuid-lslong': '13628201620450536331'}, 'enable': 'true', 'created': '2025-12-22T03:10:52', 'last-modified': '2025-12-22T03:10:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413154'}
2025-12-22 03:10:53,010 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:10:53,010 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-81125947 passed
2025-12-22 03:10:53,010 - DEBUG - ====Verifying policy data for ctest-left_vn-81125947 in API_Server ======
2025-12-22 03:10:53,010 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:53,016 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:53,026 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:53,116 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/eb064e0c-ee59-4603-bd21-192b225b6b8b
2025-12-22 03:10:53,124 - DEBUG - =>VN ctest-left_vn-81125947 has no policy to be verified
2025-12-22 03:10:53,125 - DEBUG - Verifying the vn in opserver
2025-12-22 03:10:53,125 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947 virtual network link through opserver 10.0.0.23
2025-12-22 03:10:53,125 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:10:53,138 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947?flat'}
2025-12-22 03:10:53,138 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947 virtual network link through opserver 10.0.0.22
2025-12-22 03:10:53,138 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:10:53,153 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947?flat'}
2025-12-22 03:10:53,153 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947 virtual network link through opserver 10.0.0.65
2025-12-22 03:10:53,153 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:10:53,167 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947?flat'}
2025-12-22 03:10:53,167 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-61346117:ctest-left_vn-81125947 is found in opserver
2025-12-22 03:10:53,199 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:10:53,210 - DEBUG - VRF ids for VN ctest-left_vn-81125947: {}
2025-12-22 03:10:53,474 - DEBUG - Response for create_network : {'network': {'id': 'c0a157bb-ea77-4454-a06f-41abe220661e', 'name': 'ctest-right_vn-20846764', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'project_id': 'f3f9e331daf2400795c55d11b397c0b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-61346117', 'ctest-right_vn-20846764'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:10:53,726 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aa60e081-59e4-46bb-a46e-460b8432f683', 'name': '', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'network_id': 'c0a157bb-ea77-4454-a06f-41abe220661e', 'ip_version': 4, 'cidr': '63.202.47.64/26', 'allocation_pools': [{'start': '63.202.47.66', 'end': '63.202.47.126'}], 'gateway_ip': '63.202.47.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.202.47.66', 'tags': [], 'project_id': 'f3f9e331daf2400795c55d11b397c0b8'}}
2025-12-22 03:10:53,746 - INFO - Created VN ctest-right_vn-20846764
2025-12-22 03:10:53,757 - DEBUG - VN ctest-right_vn-20846764 UUID is c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:53,832 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:53,837 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:53,848 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:53,932 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:53,944 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:53,952 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/0b8e4100-ef0a-4109-931f-3cb21b7ac65c
2025-12-22 03:10:53,963 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/0b8e4100-ef0a-4109-931f-3cb21b7ac65c
2025-12-22 03:10:53,971 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/703f3386-d6d9-476b-be2a-01082c626589
2025-12-22 03:10:53,978 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:10:53,978 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:53,987 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/0b8e4100-ef0a-4109-931f-3cb21b7ac65c
2025-12-22 03:10:54,003 - INFO - Verified VN network id 12 for VN c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:54,003 - INFO - Verifications in API Server for VN ctest-right_vn-20846764 passed
2025-12-22 03:10:54,003 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:54,014 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/0b8e4100-ef0a-4109-931f-3cb21b7ac65c
2025-12-22 03:10:54,021 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/0b8e4100-ef0a-4109-931f-3cb21b7ac65c
2025-12-22 03:10:54,028 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/703f3386-d6d9-476b-be2a-01082c626589
2025-12-22 03:10:54,041 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13880471991136765012', 'uuid-lslong': '11560530974972339742'}, 'enable': 'true', 'created': '2025-12-22T03:10:53', 'last-modified': '2025-12-22T03:10:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.368838'}
2025-12-22 03:10:54,047 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:10:54,052 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13880471991136765012', 'uuid-lslong': '11560530974972339742'}, 'enable': 'true', 'created': '2025-12-22T03:10:53', 'last-modified': '2025-12-22T03:10:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381664'}
2025-12-22 03:10:54,058 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:10:54,063 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13880471991136765012', 'uuid-lslong': '11560530974972339742'}, 'enable': 'true', 'created': '2025-12-22T03:10:53', 'last-modified': '2025-12-22T03:10:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392039'}
2025-12-22 03:10:54,069 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:10:54,069 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-20846764 passed
2025-12-22 03:10:54,069 - DEBUG - ====Verifying policy data for ctest-right_vn-20846764 in API_Server ======
2025-12-22 03:10:54,069 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:54,076 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:54,087 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:54,184 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/c0a157bb-ea77-4454-a06f-41abe220661e
2025-12-22 03:10:54,193 - DEBUG - =>VN ctest-right_vn-20846764 has no policy to be verified
2025-12-22 03:10:54,193 - DEBUG - Verifying the vn in opserver
2025-12-22 03:10:54,193 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764 virtual network link through opserver 10.0.0.23
2025-12-22 03:10:54,193 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:10:54,212 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764?flat'}
2025-12-22 03:10:54,212 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764 virtual network link through opserver 10.0.0.22
2025-12-22 03:10:54,212 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:10:54,225 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764?flat'}
2025-12-22 03:10:54,225 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764 virtual network link through opserver 10.0.0.65
2025-12-22 03:10:54,225 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:10:54,240 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764?flat'}
2025-12-22 03:10:54,240 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-61346117:ctest-right_vn-20846764 is found in opserver
2025-12-22 03:10:54,261 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:10:54,273 - DEBUG - VRF ids for VN ctest-right_vn-20846764: {}
2025-12-22 03:10:54,653 - DEBUG - Response for create_network : {'network': {'id': '4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646', 'name': 'ctest-trans_left_vn-38586077', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'project_id': 'f3f9e331daf2400795c55d11b397c0b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-61346117', 'ctest-trans_left_vn-38586077'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:10:54,870 - DEBUG - Response for create_subnet : {'subnet': {'id': '61320e93-22a8-43d3-b1b5-3ce63fad48fc', 'name': '', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'network_id': '4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646', 'ip_version': 4, 'cidr': '210.84.32.128/26', 'allocation_pools': [{'start': '210.84.32.130', 'end': '210.84.32.190'}], 'gateway_ip': '210.84.32.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '210.84.32.130', 'tags': [], 'project_id': 'f3f9e331daf2400795c55d11b397c0b8'}}
2025-12-22 03:10:54,892 - INFO - Created VN ctest-trans_left_vn-38586077
2025-12-22 03:10:54,906 - DEBUG - VN ctest-trans_left_vn-38586077 UUID is 4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:54,976 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:54,983 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:54,993 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:55,085 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:55,095 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:55,104 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8e303189-527b-4c70-9500-9341bcfde2e7
2025-12-22 03:10:55,111 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8e303189-527b-4c70-9500-9341bcfde2e7
2025-12-22 03:10:55,117 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/f637678b-b5c4-4c6a-8851-93d08ec960a9
2025-12-22 03:10:55,124 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-22 03:10:55,124 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:55,131 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8e303189-527b-4c70-9500-9341bcfde2e7
2025-12-22 03:10:55,147 - INFO - Verified VN network id 13 for VN 4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:55,147 - INFO - Verifications in API Server for VN ctest-trans_left_vn-38586077 passed
2025-12-22 03:10:55,147 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:55,156 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8e303189-527b-4c70-9500-9341bcfde2e7
2025-12-22 03:10:55,163 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8e303189-527b-4c70-9500-9341bcfde2e7
2025-12-22 03:10:55,169 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/f637678b-b5c4-4c6a-8851-93d08ec960a9
2025-12-22 03:10:55,180 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5415207931105332507', 'uuid-lslong': '13100050370824783430'}, 'enable': 'true', 'created': '2025-12-22T03:10:54', 'last-modified': '2025-12-22T03:10:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.368810'}
2025-12-22 03:10:55,186 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-22 03:10:55,192 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5415207931105332507', 'uuid-lslong': '13100050370824783430'}, 'enable': 'true', 'created': '2025-12-22T03:10:54', 'last-modified': '2025-12-22T03:10:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384180'}
2025-12-22 03:10:55,197 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-22 03:10:55,203 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5415207931105332507', 'uuid-lslong': '13100050370824783430'}, 'enable': 'true', 'created': '2025-12-22T03:10:54', 'last-modified': '2025-12-22T03:10:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388756'}
2025-12-22 03:10:55,209 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-22 03:10:55,209 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-38586077 passed
2025-12-22 03:10:55,209 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-38586077 in API_Server ======
2025-12-22 03:10:55,209 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:55,216 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:55,226 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:55,317 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b26aeec-1e69-4d1b-b5cc-ba67bcbc4646
2025-12-22 03:10:55,325 - DEBUG - =>VN ctest-trans_left_vn-38586077 has no policy to be verified
2025-12-22 03:10:55,326 - DEBUG - Verifying the vn in opserver
2025-12-22 03:10:55,326 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077 virtual network link through opserver 10.0.0.23
2025-12-22 03:10:55,326 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:10:55,338 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077?flat'}
2025-12-22 03:10:55,338 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077 virtual network link through opserver 10.0.0.22
2025-12-22 03:10:55,338 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:10:55,350 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077?flat'}
2025-12-22 03:10:55,350 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077 virtual network link through opserver 10.0.0.65
2025-12-22 03:10:55,350 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:10:55,363 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077?flat'}
2025-12-22 03:10:55,363 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-61346117:ctest-trans_left_vn-38586077 is found in opserver
2025-12-22 03:10:55,389 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:10:55,400 - DEBUG - VRF ids for VN ctest-trans_left_vn-38586077: {}
2025-12-22 03:10:55,676 - DEBUG - Response for create_network : {'network': {'id': '2bc873d6-7994-448c-8d95-f89ff1a0b1c4', 'name': 'ctest-trans_right_vn-02041527', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'project_id': 'f3f9e331daf2400795c55d11b397c0b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-61346117', 'ctest-trans_right_vn-02041527'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:10:55,893 - DEBUG - Response for create_subnet : {'subnet': {'id': '64efa3d5-1c9d-40f6-9e38-7579be408a97', 'name': '', 'tenant_id': 'f3f9e331daf2400795c55d11b397c0b8', 'network_id': '2bc873d6-7994-448c-8d95-f89ff1a0b1c4', 'ip_version': 4, 'cidr': '185.133.121.192/26', 'allocation_pools': [{'start': '185.133.121.194', 'end': '185.133.121.254'}], 'gateway_ip': '185.133.121.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '185.133.121.194', 'tags': [], 'project_id': 'f3f9e331daf2400795c55d11b397c0b8'}}
2025-12-22 03:10:55,920 - INFO - Created VN ctest-trans_right_vn-02041527
2025-12-22 03:10:55,931 - DEBUG - VN ctest-trans_right_vn-02041527 UUID is 2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,013 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:56,019 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:56,031 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:56,116 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,128 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,136 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6d86bba1-57bf-455b-ac5f-d6f424978ad1
2025-12-22 03:10:56,143 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6d86bba1-57bf-455b-ac5f-d6f424978ad1
2025-12-22 03:10:56,151 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/fe355142-f948-4539-9fac-9e70c3e2062a
2025-12-22 03:10:56,157 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-22 03:10:56,157 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,167 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6d86bba1-57bf-455b-ac5f-d6f424978ad1
2025-12-22 03:10:56,184 - INFO - Verified VN network id 14 for VN 2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,184 - INFO - Verifications in API Server for VN ctest-trans_right_vn-02041527 passed
2025-12-22 03:10:56,185 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,196 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6d86bba1-57bf-455b-ac5f-d6f424978ad1
2025-12-22 03:10:56,204 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6d86bba1-57bf-455b-ac5f-d6f424978ad1
2025-12-22 03:10:56,215 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/fe355142-f948-4539-9fac-9e70c3e2062a
2025-12-22 03:10:56,231 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3154898903972988044', 'uuid-lslong': '10202333896715055556'}, 'enable': 'true', 'created': '2025-12-22T03:10:55', 'last-modified': '2025-12-22T03:10:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405966'}
2025-12-22 03:10:56,238 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-22 03:10:56,243 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3154898903972988044', 'uuid-lslong': '10202333896715055556'}, 'enable': 'true', 'created': '2025-12-22T03:10:55', 'last-modified': '2025-12-22T03:10:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.417011'}
2025-12-22 03:10:56,248 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-22 03:10:56,254 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-61346117', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3154898903972988044', 'uuid-lslong': '10202333896715055556'}, 'enable': 'true', 'created': '2025-12-22T03:10:55', 'last-modified': '2025-12-22T03:10:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428665'}
2025-12-22 03:10:56,261 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-22 03:10:56,261 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-02041527 passed
2025-12-22 03:10:56,261 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-02041527 in API_Server ======
2025-12-22 03:10:56,261 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:10:56,268 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:10:56,278 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:10:56,377 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2bc873d6-7994-448c-8d95-f89ff1a0b1c4
2025-12-22 03:10:56,388 - DEBUG - =>VN ctest-trans_right_vn-02041527 has no policy to be verified
2025-12-22 03:10:56,388 - DEBUG - Verifying the vn in opserver
2025-12-22 03:10:56,388 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527 virtual network link through opserver 10.0.0.23
2025-12-22 03:10:56,388 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:10:56,399 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527?flat'}
2025-12-22 03:10:56,399 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527 virtual network link through opserver 10.0.0.22
2025-12-22 03:10:56,399 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:10:56,412 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527?flat'}
2025-12-22 03:10:56,412 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527 virtual network link through opserver 10.0.0.65
2025-12-22 03:10:56,412 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:10:56,428 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527?flat'}
2025-12-22 03:10:56,428 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-61346117:ctest-trans_right_vn-02041527 is found in opserver
2025-12-22 03:10:56,449 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:10:56,460 - DEBUG - VRF ids for VN ctest-trans_right_vn-02041527: {}
2025-12-22 03:10:57,495 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-1)
2025-12-22 03:10:58,822 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-2)
2025-12-22 03:10:58,822 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-87347814']
2025-12-22 03:10:59,137 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-87347814'] created succesfully
2025-12-22 03:10:59,504 - DEBUG - Image by name tiny_trans_fw not found
2025-12-22 03:10:59,504 - DEBUG - Installing image tiny_trans_fw
2025-12-22 03:10:59,504 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-12-22 03:10:59,504 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-12-22 03:10:59,504 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-12-22 03:10:59,521 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-22 03:10:59,555 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-22 03:10:59,555 - DEBUG - Unzip image
2025-12-22 03:10:59,556 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-22 03:10:59,596 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-12-22 03:10:59,596 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-87347814']
2025-12-22 03:10:59,698 - INFO - Service template: ['default-domain', 'ctest-service_template_1-87347814'] deleted successfully.
2025-12-22 03:10:59,698 - INFO - Skip interface_detach for VM ctest-right_vm-88527153 in state BUILD
2025-12-22 03:10:59,698 - INFO - Deleting VM ctest-right_vm-88527153
2025-12-22 03:10:59,698 - ERROR - VM ctest-right_vm-88527153 has failed to come up
2025-12-22 03:10:59,698 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:59,698 - ERROR - VM ctest-right_vm-88527153 has failed to come up
2025-12-22 03:10:59,699 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:59,807 - INFO - Skip interface_detach for VM ctest-left_vm-30878554 in state BUILD
2025-12-22 03:10:59,807 - INFO - Deleting VM ctest-left_vm-30878554
2025-12-22 03:10:59,807 - ERROR - VM ctest-left_vm-30878554 has failed to come up
2025-12-22 03:10:59,807 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:59,807 - ERROR - VM ctest-left_vm-30878554 has failed to come up
2025-12-22 03:10:59,807 - ERROR - Nova failed to get host of the VM
2025-12-22 03:10:59,915 - INFO - Deleting VN ctest-trans_right_vn-02041527
2025-12-22 03:11:00,094 - DEBUG - Response for deleting network ()
2025-12-22 03:11:00,094 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6d86bba1-57bf-455b-ac5f-d6f424978ad1
2025-12-22 03:11:00,099 - DEBUG - Response Code: 404
2025-12-22 03:11:00,106 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:00,115 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:00,174 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:11:00,266 - INFO - Validated that VN ctest-trans_right_vn-02041527 is not found in API Server
2025-12-22 03:11:00,278 - DEBUG - VN ctest-trans_right_vn-02041527 is not present in Agent 10.0.0.130
2025-12-22 03:11:00,330 - DEBUG - VN ctest-trans_right_vn-02041527 is not present in Agent 10.0.0.19
2025-12-22 03:11:00,330 - INFO - Validated that VN ctest-trans_right_vn-02041527 is not in any agent
2025-12-22 03:11:00,369 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-02041527 info
2025-12-22 03:11:00,369 - INFO - Deleting VN ctest-trans_left_vn-38586077
2025-12-22 03:11:00,525 - DEBUG - Response for deleting network ()
2025-12-22 03:11:00,525 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/8e303189-527b-4c70-9500-9341bcfde2e7
2025-12-22 03:11:00,530 - DEBUG - Response Code: 404
2025-12-22 03:11:00,530 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:00,537 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:00,571 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:11:00,671 - INFO - Validated that VN ctest-trans_left_vn-38586077 is not found in API Server
2025-12-22 03:11:00,682 - DEBUG - VN ctest-trans_left_vn-38586077 is not present in Agent 10.0.0.130
2025-12-22 03:11:00,713 - DEBUG - VN ctest-trans_left_vn-38586077 is not present in Agent 10.0.0.19
2025-12-22 03:11:00,713 - INFO - Validated that VN ctest-trans_left_vn-38586077 is not in any agent
2025-12-22 03:11:00,768 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-38586077 info
2025-12-22 03:11:00,768 - INFO - Deleting VN ctest-right_vn-20846764
2025-12-22 03:11:00,816 - DEBUG - VN c0a157bb-ea77-4454-a06f-41abe220661e still in use: Unable to complete operation on network c0a157bb-ea77-4454-a06f-41abe220661e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0bddf817-ac2a-44d5-9743-51dd1337ff11']
2025-12-22 03:11:00,816 - WARNING - Deleting VN ctest-right_vn-20846764 failed..Will retry
2025-12-22 03:11:03,000 - DEBUG - Response for deleting network ()
2025-12-22 03:11:03,000 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/0b8e4100-ef0a-4109-931f-3cb21b7ac65c
2025-12-22 03:11:03,006 - DEBUG - Response Code: 404
2025-12-22 03:11:03,007 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:03,013 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:03,023 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:11:03,118 - INFO - Validated that VN ctest-right_vn-20846764 is not found in API Server
2025-12-22 03:11:03,130 - DEBUG - VN ctest-right_vn-20846764 is not present in Agent 10.0.0.130
2025-12-22 03:11:03,141 - DEBUG - VN ctest-right_vn-20846764 is not present in Agent 10.0.0.19
2025-12-22 03:11:03,141 - INFO - Validated that VN ctest-right_vn-20846764 is not in any agent
2025-12-22 03:11:03,174 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-20846764 info
2025-12-22 03:11:03,174 - INFO - Deleting VN ctest-left_vn-81125947
2025-12-22 03:11:03,340 - DEBUG - Response for deleting network ()
2025-12-22 03:11:03,340 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/45b66b92-8006-4608-bf1a-a53874cc642a
2025-12-22 03:11:03,345 - DEBUG - Response Code: 404
2025-12-22 03:11:03,345 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:03,350 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:03,359 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:11:03,444 - INFO - Validated that VN ctest-left_vn-81125947 is not found in API Server
2025-12-22 03:11:03,454 - DEBUG - VN ctest-left_vn-81125947 is not present in Agent 10.0.0.130
2025-12-22 03:11:03,472 - DEBUG - VN ctest-left_vn-81125947 is not present in Agent 10.0.0.19
2025-12-22 03:11:03,472 - INFO - Validated that VN ctest-left_vn-81125947 is not in any agent
2025-12-22 03:11:03,505 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-81125947 info
2025-12-22 03:11:03,505 - INFO - Deleting VN ctest-mgmt_vn-00641889
2025-12-22 03:11:03,648 - DEBUG - Response for deleting network ()
2025-12-22 03:11:03,649 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/848308a7-5b6f-44ae-b748-48fe310ddde1
2025-12-22 03:11:03,655 - DEBUG - Response Code: 404
2025-12-22 03:11:03,655 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:03,667 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:03,719 - DEBUG - Requesting: http://10.0.0.23:8082/project/f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:11:03,829 - INFO - Validated that VN ctest-mgmt_vn-00641889 is not found in API Server
2025-12-22 03:11:03,840 - DEBUG - VN ctest-mgmt_vn-00641889 is not present in Agent 10.0.0.130
2025-12-22 03:11:03,851 - DEBUG - VN ctest-mgmt_vn-00641889 is not present in Agent 10.0.0.19
2025-12-22 03:11:03,851 - INFO - Validated that VN ctest-mgmt_vn-00641889 is not in any agent
2025-12-22 03:11:03,881 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-00641889 info
2025-12-22 03:11:05,178 - DEBUG - No XMPP flaps were noticed during the test
2025-12-22 03:11:05,178 - INFO -
2025-12-22 03:11:05,178 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-12-22 03:11:05,178 - INFO - --------------------------------------------------------------------------------
2025-12-22 03:11:05,920 - INFO - Deleted project: ctest-TestSvcRegr-61346117, ID : f3f9e331-daf2-4007-95c5-5d11b397c0b8
2025-12-22 03:11:06,116 - INFO - Domain Default found not creating
2025-12-22 03:11:06,318 - INFO - Project ctest-TestSvcRegr-27795699 not found, creating it
2025-12-22 03:11:06,916 - INFO - Created Project:ctest-TestSvcRegr-27795699, ID : b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:08,494 - INFO - ================================================================================
2025-12-22 03:11:08,494 - INFO - STARTING TEST : test_svc_CEM_22032
2025-12-22 03:11:09,793 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.14': '0', '10.20.0.192': '0'}, '10.0.0.19': {'10.20.0.129': '0', '10.20.0.192': '0'}} with
2025-12-22 03:11:09,793 - INFO - Initial checks done. Running the testcase now
2025-12-22 03:11:09,794 - INFO -
2025-12-22 03:11:10,495 - DEBUG - Response for create_network : {'network': {'id': '09d5fa29-f6b5-4780-a44b-f98e8eccb1de', 'name': 'ctest-mgmt_vn-74884285', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27795699', 'ctest-mgmt_vn-74884285'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:11:10,776 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dd6f46ea-d13c-48ad-bb76-7509c31211c9', 'name': '', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'network_id': '09d5fa29-f6b5-4780-a44b-f98e8eccb1de', 'ip_version': 4, 'cidr': '168.193.96.192/26', 'allocation_pools': [{'start': '168.193.96.194', 'end': '168.193.96.254'}], 'gateway_ip': '168.193.96.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.193.96.194', 'tags': [], 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c'}}
2025-12-22 03:11:10,802 - INFO - Created VN ctest-mgmt_vn-74884285
2025-12-22 03:11:10,820 - DEBUG - VN ctest-mgmt_vn-74884285 UUID is 09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:10,894 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:11,197 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:11,233 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:11,251 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:11,344 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:11,354 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:11,363 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/273b393d-d7a2-44da-bf9c-b38f564cc2d8
2025-12-22 03:11:11,368 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/273b393d-d7a2-44da-bf9c-b38f564cc2d8
2025-12-22 03:11:11,374 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/b5a0988b-37a7-45aa-9e33-f7d27fd1ea7a
2025-12-22 03:11:11,380 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:11,380 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:11,387 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/273b393d-d7a2-44da-bf9c-b38f564cc2d8
2025-12-22 03:11:11,403 - INFO - Verified VN network id 10 for VN 09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:11,403 - INFO - Verifications in API Server for VN ctest-mgmt_vn-74884285 passed
2025-12-22 03:11:11,404 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:11,413 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/273b393d-d7a2-44da-bf9c-b38f564cc2d8
2025-12-22 03:11:11,418 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/273b393d-d7a2-44da-bf9c-b38f564cc2d8
2025-12-22 03:11:11,424 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/b5a0988b-37a7-45aa-9e33-f7d27fd1ea7a
2025-12-22 03:11:11,436 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '708747574520399744', 'uuid-lslong': '11838830436149932510'}, 'enable': 'true', 'created': '2025-12-22T03:11:10', 'last-modified': '2025-12-22T03:11:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.706315'}
2025-12-22 03:11:11,441 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:11,446 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '708747574520399744', 'uuid-lslong': '11838830436149932510'}, 'enable': 'true', 'created': '2025-12-22T03:11:10', 'last-modified': '2025-12-22T03:11:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.719087'}
2025-12-22 03:11:11,451 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:11,457 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '708747574520399744', 'uuid-lslong': '11838830436149932510'}, 'enable': 'true', 'created': '2025-12-22T03:11:10', 'last-modified': '2025-12-22T03:11:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.730366'}
2025-12-22 03:11:11,464 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:11,464 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-74884285 passed
2025-12-22 03:11:11,465 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-74884285 in API_Server ======
2025-12-22 03:11:11,465 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:11,480 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:11,492 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:11,581 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/09d5fa29-f6b5-4780-a44b-f98e8eccb1de
2025-12-22 03:11:11,589 - DEBUG - =>VN ctest-mgmt_vn-74884285 has no policy to be verified
2025-12-22 03:11:11,589 - DEBUG - Verifying the vn in opserver
2025-12-22 03:11:11,589 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285 virtual network link through opserver 10.0.0.23
2025-12-22 03:11:11,589 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:11,884 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:11,949 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285?flat'}
2025-12-22 03:11:11,949 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285 virtual network link through opserver 10.0.0.22
2025-12-22 03:11:11,949 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:12,241 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:12,306 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285?flat'}
2025-12-22 03:11:12,306 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285 virtual network link through opserver 10.0.0.65
2025-12-22 03:11:12,306 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:12,606 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:12,662 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285?flat'}
2025-12-22 03:11:12,662 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-74884285 is found in opserver
2025-12-22 03:11:12,685 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:11:12,696 - DEBUG - VRF ids for VN ctest-mgmt_vn-74884285: {}
2025-12-22 03:11:13,009 - DEBUG - Response for create_network : {'network': {'id': '0930f3c7-e397-45a3-b170-5ffeb8122f41', 'name': 'service-left-vn-38552038', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27795699', 'service-left-vn-38552038'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:11:13,292 - DEBUG - Response for create_subnet : {'subnet': {'id': '16f2412b-c152-4de6-8f88-37355f901c60', 'name': '', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'network_id': '0930f3c7-e397-45a3-b170-5ffeb8122f41', 'ip_version': 4, 'cidr': '121.220.173.64/26', 'allocation_pools': [{'start': '121.220.173.66', 'end': '121.220.173.126'}], 'gateway_ip': '121.220.173.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.220.173.66', 'tags': [], 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c'}}
2025-12-22 03:11:13,319 - INFO - Created VN service-left-vn-38552038
2025-12-22 03:11:13,333 - DEBUG - VN service-left-vn-38552038 UUID is 0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,426 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:13,434 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:13,448 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:13,552 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,566 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,576 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/84b63f77-8545-4140-aebe-172000b1d9e3
2025-12-22 03:11:13,583 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/84b63f77-8545-4140-aebe-172000b1d9e3
2025-12-22 03:11:13,593 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/76c51286-8d12-47dd-9db6-9c6ccc54ba41
2025-12-22 03:11:13,600 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:13,601 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,610 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/84b63f77-8545-4140-aebe-172000b1d9e3
2025-12-22 03:11:13,627 - INFO - Verified VN network id 11 for VN 0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,627 - INFO - Verifications in API Server for VN service-left-vn-38552038 passed
2025-12-22 03:11:13,627 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,637 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/84b63f77-8545-4140-aebe-172000b1d9e3
2025-12-22 03:11:13,645 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/84b63f77-8545-4140-aebe-172000b1d9e3
2025-12-22 03:11:13,656 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/76c51286-8d12-47dd-9db6-9c6ccc54ba41
2025-12-22 03:11:13,670 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '662297185065846179', 'uuid-lslong': '12785824889719369537'}, 'enable': 'true', 'created': '2025-12-22T03:11:12', 'last-modified': '2025-12-22T03:11:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441791'}
2025-12-22 03:11:13,675 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:13,680 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '662297185065846179', 'uuid-lslong': '12785824889719369537'}, 'enable': 'true', 'created': '2025-12-22T03:11:12', 'last-modified': '2025-12-22T03:11:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442247'}
2025-12-22 03:11:13,686 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:13,692 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '662297185065846179', 'uuid-lslong': '12785824889719369537'}, 'enable': 'true', 'created': '2025-12-22T03:11:12', 'last-modified': '2025-12-22T03:11:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453650'}
2025-12-22 03:11:13,698 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:13,698 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-38552038 passed
2025-12-22 03:11:13,698 - DEBUG - ====Verifying policy data for service-left-vn-38552038 in API_Server ======
2025-12-22 03:11:13,698 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:13,704 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:13,715 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:13,831 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0930f3c7-e397-45a3-b170-5ffeb8122f41
2025-12-22 03:11:13,847 - DEBUG - =>VN service-left-vn-38552038 has no policy to be verified
2025-12-22 03:11:13,847 - DEBUG - Verifying the vn in opserver
2025-12-22 03:11:13,847 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038 virtual network link through opserver 10.0.0.23
2025-12-22 03:11:13,847 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:13,924 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038?flat'}
2025-12-22 03:11:13,924 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038 virtual network link through opserver 10.0.0.22
2025-12-22 03:11:13,924 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:13,998 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038?flat'}
2025-12-22 03:11:13,998 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038 virtual network link through opserver 10.0.0.65
2025-12-22 03:11:13,998 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:14,082 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038?flat'}
2025-12-22 03:11:14,082 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27795699:service-left-vn-38552038 is found in opserver
2025-12-22 03:11:14,107 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:11:14,117 - DEBUG - VRF ids for VN service-left-vn-38552038: {}
2025-12-22 03:11:14,401 - DEBUG - Response for create_network : {'network': {'id': '76a2b737-e86e-4f9b-9d18-c08c0c9bb67d', 'name': 'service-right-vn-85106010', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27795699', 'service-right-vn-85106010'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:11:14,608 - DEBUG - Response for create_subnet : {'subnet': {'id': '2d6462f0-5f49-45d2-851a-9d37d4043a69', 'name': '', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'network_id': '76a2b737-e86e-4f9b-9d18-c08c0c9bb67d', 'ip_version': 4, 'cidr': '119.27.11.128/26', 'allocation_pools': [{'start': '119.27.11.130', 'end': '119.27.11.190'}], 'gateway_ip': '119.27.11.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '119.27.11.130', 'tags': [], 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c'}}
2025-12-22 03:11:14,627 - INFO - Created VN service-right-vn-85106010
2025-12-22 03:11:14,639 - DEBUG - VN service-right-vn-85106010 UUID is 76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:14,714 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:14,721 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:14,732 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:14,827 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:14,840 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:14,849 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b807cdf2-a7ff-47bb-a2f8-b0476863eea9
2025-12-22 03:11:14,858 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b807cdf2-a7ff-47bb-a2f8-b0476863eea9
2025-12-22 03:11:14,865 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6285647a-6199-4da2-b669-43f1c65dd4cb
2025-12-22 03:11:14,873 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:14,873 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:14,883 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b807cdf2-a7ff-47bb-a2f8-b0476863eea9
2025-12-22 03:11:14,900 - INFO - Verified VN network id 12 for VN 76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:14,900 - INFO - Verifications in API Server for VN service-right-vn-85106010 passed
2025-12-22 03:11:14,900 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:14,910 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b807cdf2-a7ff-47bb-a2f8-b0476863eea9
2025-12-22 03:11:14,917 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b807cdf2-a7ff-47bb-a2f8-b0476863eea9
2025-12-22 03:11:14,923 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6285647a-6199-4da2-b669-43f1c65dd4cb
2025-12-22 03:11:14,934 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8548596493453250459', 'uuid-lslong': '11320009371135227517'}, 'enable': 'true', 'created': '2025-12-22T03:11:14', 'last-modified': '2025-12-22T03:11:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370195'}
2025-12-22 03:11:14,939 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:14,943 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8548596493453250459', 'uuid-lslong': '11320009371135227517'}, 'enable': 'true', 'created': '2025-12-22T03:11:14', 'last-modified': '2025-12-22T03:11:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.391551'}
2025-12-22 03:11:14,949 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:14,954 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8548596493453250459', 'uuid-lslong': '11320009371135227517'}, 'enable': 'true', 'created': '2025-12-22T03:11:14', 'last-modified': '2025-12-22T03:11:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.390021'}
2025-12-22 03:11:14,961 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:14,961 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-85106010 passed
2025-12-22 03:11:14,961 - DEBUG - ====Verifying policy data for service-right-vn-85106010 in API_Server ======
2025-12-22 03:11:14,961 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:14,967 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:14,977 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:15,072 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/76a2b737-e86e-4f9b-9d18-c08c0c9bb67d
2025-12-22 03:11:15,085 - DEBUG - =>VN service-right-vn-85106010 has no policy to be verified
2025-12-22 03:11:15,085 - DEBUG - Verifying the vn in opserver
2025-12-22 03:11:15,085 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010 virtual network link through opserver 10.0.0.23
2025-12-22 03:11:15,085 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:15,144 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010?flat'}
2025-12-22 03:11:15,144 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010 virtual network link through opserver 10.0.0.22
2025-12-22 03:11:15,144 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:15,223 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010?flat'}
2025-12-22 03:11:15,223 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010 virtual network link through opserver 10.0.0.65
2025-12-22 03:11:15,223 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:15,285 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010?flat'}
2025-12-22 03:11:15,285 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27795699:service-right-vn-85106010 is found in opserver
2025-12-22 03:11:15,307 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:11:15,319 - DEBUG - VRF ids for VN service-right-vn-85106010: {}
2025-12-22 03:11:15,377 - DEBUG - Services list from nova: [, , , ]
2025-12-22 03:11:16,414 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-1)
2025-12-22 03:11:17,774 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-2)
2025-12-22 03:11:17,774 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-72547633']
2025-12-22 03:11:18,094 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-72547633'] created succesfully
2025-12-22 03:11:18,474 - DEBUG - Image by name tiny_nat_fw not found
2025-12-22 03:11:18,474 - DEBUG - Installing image tiny_nat_fw
2025-12-22 03:11:18,474 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-12-22 03:11:18,474 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:11:18,474 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:11:18,474 - DEBUG - Unzip image
2025-12-22 03:11:18,474 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:11:18,492 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-22 03:11:18,493 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-72547633']
2025-12-22 03:11:18,606 - INFO - Service template: ['default-domain', 'ctest-service_template_1-72547633'] deleted successfully.
2025-12-22 03:11:18,606 - INFO - Skip interface_detach for VM ctest-right_vm-60162663 in state BUILD
2025-12-22 03:11:18,606 - INFO - Deleting VM ctest-right_vm-60162663
2025-12-22 03:11:18,648 - ERROR - VM ctest-right_vm-60162663 has failed to come up
2025-12-22 03:11:18,648 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:18,648 - ERROR - VM ctest-right_vm-60162663 has failed to come up
2025-12-22 03:11:18,649 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:18,738 - INFO - Skip interface_detach for VM ctest-left_vm-45671608 in state BUILD
2025-12-22 03:11:18,738 - INFO - Deleting VM ctest-left_vm-45671608
2025-12-22 03:11:18,738 - ERROR - VM ctest-left_vm-45671608 has failed to come up
2025-12-22 03:11:18,738 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:18,738 - ERROR - VM ctest-left_vm-45671608 has failed to come up
2025-12-22 03:11:18,738 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:18,807 - INFO - Deleting VN service-right-vn-85106010
2025-12-22 03:11:18,853 - DEBUG - VN 76a2b737-e86e-4f9b-9d18-c08c0c9bb67d still in use: Unable to complete operation on network 76a2b737-e86e-4f9b-9d18-c08c0c9bb67d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-41584f2a-08ab-4ddf-b2df-f9ff7a041ed5']
2025-12-22 03:11:18,853 - WARNING - Deleting VN service-right-vn-85106010 failed..Will retry
2025-12-22 03:11:21,130 - DEBUG - Response for deleting network ()
2025-12-22 03:11:21,131 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/b807cdf2-a7ff-47bb-a2f8-b0476863eea9
2025-12-22 03:11:21,137 - DEBUG - Response Code: 404
2025-12-22 03:11:21,137 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:21,145 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:21,203 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:21,302 - INFO - Validated that VN service-right-vn-85106010 is not found in API Server
2025-12-22 03:11:21,315 - DEBUG - VN service-right-vn-85106010 is not present in Agent 10.0.0.130
2025-12-22 03:11:21,343 - DEBUG - VN service-right-vn-85106010 is not present in Agent 10.0.0.19
2025-12-22 03:11:21,343 - INFO - Validated that VN service-right-vn-85106010 is not in any agent
2025-12-22 03:11:21,382 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-85106010 info
2025-12-22 03:11:21,382 - INFO - Deleting VN service-left-vn-38552038
2025-12-22 03:11:21,594 - DEBUG - Response for deleting network ()
2025-12-22 03:11:21,594 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/84b63f77-8545-4140-aebe-172000b1d9e3
2025-12-22 03:11:21,601 - DEBUG - Response Code: 404
2025-12-22 03:11:21,601 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:21,607 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:21,620 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:21,726 - INFO - Validated that VN service-left-vn-38552038 is not found in API Server
2025-12-22 03:11:21,739 - DEBUG - VN service-left-vn-38552038 is not present in Agent 10.0.0.130
2025-12-22 03:11:21,752 - DEBUG - VN service-left-vn-38552038 is not present in Agent 10.0.0.19
2025-12-22 03:11:21,752 - INFO - Validated that VN service-left-vn-38552038 is not in any agent
2025-12-22 03:11:21,782 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-38552038 info
2025-12-22 03:11:21,783 - INFO - Deleting VN ctest-mgmt_vn-74884285
2025-12-22 03:11:21,958 - DEBUG - Response for deleting network ()
2025-12-22 03:11:21,958 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/273b393d-d7a2-44da-bf9c-b38f564cc2d8
2025-12-22 03:11:21,964 - DEBUG - Response Code: 404
2025-12-22 03:11:21,964 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:21,981 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:21,995 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:22,103 - INFO - Validated that VN ctest-mgmt_vn-74884285 is not found in API Server
2025-12-22 03:11:22,114 - DEBUG - VN ctest-mgmt_vn-74884285 is not present in Agent 10.0.0.130
2025-12-22 03:11:22,126 - DEBUG - VN ctest-mgmt_vn-74884285 is not present in Agent 10.0.0.19
2025-12-22 03:11:22,126 - INFO - Validated that VN ctest-mgmt_vn-74884285 is not in any agent
2025-12-22 03:11:22,158 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-74884285 info
2025-12-22 03:11:23,456 - DEBUG - No XMPP flaps were noticed during the test
2025-12-22 03:11:23,456 - INFO -
2025-12-22 03:11:23,456 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-12-22 03:11:23,456 - INFO - --------------------------------------------------------------------------------
2025-12-22 03:11:23,461 - INFO - ================================================================================
2025-12-22 03:11:23,461 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-12-22 03:11:24,764 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.14': '0', '10.20.0.192': '0'}, '10.0.0.19': {'10.20.0.129': '0', '10.20.0.192': '0'}} with
2025-12-22 03:11:24,764 - INFO - Initial checks done. Running the testcase now
2025-12-22 03:11:24,764 - INFO -
2025-12-22 03:11:25,022 - DEBUG - Response for create_network : {'network': {'id': '42804511-800b-4399-98af-491b8ddb79ab', 'name': 'ctest-mgmt_vn-50549518', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27795699', 'ctest-mgmt_vn-50549518'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:11:25,291 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e5ccc2ef-ef97-443f-a713-4c6f762a7b67', 'name': '', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'network_id': '42804511-800b-4399-98af-491b8ddb79ab', 'ip_version': 4, 'cidr': '19.176.227.64/26', 'allocation_pools': [{'start': '19.176.227.66', 'end': '19.176.227.126'}], 'gateway_ip': '19.176.227.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '19.176.227.66', 'tags': [], 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c'}}
2025-12-22 03:11:25,311 - INFO - Created VN ctest-mgmt_vn-50549518
2025-12-22 03:11:25,323 - DEBUG - VN ctest-mgmt_vn-50549518 UUID is 42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,396 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:25,402 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:25,415 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:25,502 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,513 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,521 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/2b4ca922-2cdd-4b7d-8a06-4ae2d0baaf63
2025-12-22 03:11:25,527 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/2b4ca922-2cdd-4b7d-8a06-4ae2d0baaf63
2025-12-22 03:11:25,534 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/4d27f6e8-cbcf-45bb-bb10-1ef6f8f864a1
2025-12-22 03:11:25,540 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:25,540 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,549 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/2b4ca922-2cdd-4b7d-8a06-4ae2d0baaf63
2025-12-22 03:11:25,566 - INFO - Verified VN network id 10 for VN 42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,566 - INFO - Verifications in API Server for VN ctest-mgmt_vn-50549518 passed
2025-12-22 03:11:25,566 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,575 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/2b4ca922-2cdd-4b7d-8a06-4ae2d0baaf63
2025-12-22 03:11:25,582 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/2b4ca922-2cdd-4b7d-8a06-4ae2d0baaf63
2025-12-22 03:11:25,589 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/4d27f6e8-cbcf-45bb-bb10-1ef6f8f864a1
2025-12-22 03:11:25,600 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4791905944987190169', 'uuid-lslong': '11002092797382326699'}, 'enable': 'true', 'created': '2025-12-22T03:11:24', 'last-modified': '2025-12-22T03:11:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.363506'}
2025-12-22 03:11:25,607 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:25,612 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4791905944987190169', 'uuid-lslong': '11002092797382326699'}, 'enable': 'true', 'created': '2025-12-22T03:11:24', 'last-modified': '2025-12-22T03:11:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.376111'}
2025-12-22 03:11:25,618 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:25,623 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4791905944987190169', 'uuid-lslong': '11002092797382326699'}, 'enable': 'true', 'created': '2025-12-22T03:11:24', 'last-modified': '2025-12-22T03:11:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387091'}
2025-12-22 03:11:25,630 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-22 03:11:25,630 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-50549518 passed
2025-12-22 03:11:25,630 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-50549518 in API_Server ======
2025-12-22 03:11:25,630 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:25,636 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:25,647 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:25,740 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/42804511-800b-4399-98af-491b8ddb79ab
2025-12-22 03:11:25,754 - DEBUG - =>VN ctest-mgmt_vn-50549518 has no policy to be verified
2025-12-22 03:11:25,754 - DEBUG - Verifying the vn in opserver
2025-12-22 03:11:25,754 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518 virtual network link through opserver 10.0.0.23
2025-12-22 03:11:25,754 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:25,766 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518?flat'}
2025-12-22 03:11:25,766 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518 virtual network link through opserver 10.0.0.22
2025-12-22 03:11:25,766 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:25,778 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518?flat'}
2025-12-22 03:11:25,778 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518 virtual network link through opserver 10.0.0.65
2025-12-22 03:11:25,778 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:25,792 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518?flat'}
2025-12-22 03:11:25,792 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27795699:ctest-mgmt_vn-50549518 is found in opserver
2025-12-22 03:11:25,814 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:11:25,825 - DEBUG - VRF ids for VN ctest-mgmt_vn-50549518: {}
2025-12-22 03:11:26,118 - DEBUG - Response for create_network : {'network': {'id': 'be744f86-e7c1-437e-8609-2ad5af2d0b89', 'name': 'ctest-left_vn-41296296', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27795699', 'ctest-left_vn-41296296'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:11:26,347 - DEBUG - Response for create_subnet : {'subnet': {'id': '527a50c5-8b1b-4f84-b712-0a9f83b11514', 'name': '', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'network_id': 'be744f86-e7c1-437e-8609-2ad5af2d0b89', 'ip_version': 4, 'cidr': '14.51.194.64/26', 'allocation_pools': [{'start': '14.51.194.66', 'end': '14.51.194.126'}], 'gateway_ip': '14.51.194.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '14.51.194.66', 'tags': [], 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c'}}
2025-12-22 03:11:26,369 - INFO - Created VN ctest-left_vn-41296296
2025-12-22 03:11:26,384 - DEBUG - VN ctest-left_vn-41296296 UUID is be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,455 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:26,460 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:26,470 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:26,562 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,584 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,593 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6af3c789-6424-4276-8840-ff6184300af8
2025-12-22 03:11:26,600 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6af3c789-6424-4276-8840-ff6184300af8
2025-12-22 03:11:26,607 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/de7043a5-af14-41b7-b072-941ef44f9f0e
2025-12-22 03:11:26,613 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:26,614 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,623 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6af3c789-6424-4276-8840-ff6184300af8
2025-12-22 03:11:26,639 - INFO - Verified VN network id 11 for VN be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,639 - INFO - Verifications in API Server for VN ctest-left_vn-41296296 passed
2025-12-22 03:11:26,639 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,647 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6af3c789-6424-4276-8840-ff6184300af8
2025-12-22 03:11:26,654 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6af3c789-6424-4276-8840-ff6184300af8
2025-12-22 03:11:26,661 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/de7043a5-af14-41b7-b072-941ef44f9f0e
2025-12-22 03:11:26,674 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13723681405337158526', 'uuid-lslong': '9658297973128104841'}, 'enable': 'true', 'created': '2025-12-22T03:11:26', 'last-modified': '2025-12-22T03:11:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.377762'}
2025-12-22 03:11:26,678 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:26,683 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13723681405337158526', 'uuid-lslong': '9658297973128104841'}, 'enable': 'true', 'created': '2025-12-22T03:11:26', 'last-modified': '2025-12-22T03:11:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395449'}
2025-12-22 03:11:26,689 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:26,696 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13723681405337158526', 'uuid-lslong': '9658297973128104841'}, 'enable': 'true', 'created': '2025-12-22T03:11:26', 'last-modified': '2025-12-22T03:11:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405427'}
2025-12-22 03:11:26,702 - DEBUG - Route Targets: ['target:64512:8000005']
2025-12-22 03:11:26,702 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-41296296 passed
2025-12-22 03:11:26,702 - DEBUG - ====Verifying policy data for ctest-left_vn-41296296 in API_Server ======
2025-12-22 03:11:26,702 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:26,708 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:26,718 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:26,813 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/be744f86-e7c1-437e-8609-2ad5af2d0b89
2025-12-22 03:11:26,825 - DEBUG - =>VN ctest-left_vn-41296296 has no policy to be verified
2025-12-22 03:11:26,825 - DEBUG - Verifying the vn in opserver
2025-12-22 03:11:26,825 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296 virtual network link through opserver 10.0.0.23
2025-12-22 03:11:26,825 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:26,838 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296?flat'}
2025-12-22 03:11:26,838 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296 virtual network link through opserver 10.0.0.22
2025-12-22 03:11:26,838 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:26,850 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296?flat'}
2025-12-22 03:11:26,850 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296 virtual network link through opserver 10.0.0.65
2025-12-22 03:11:26,851 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:26,865 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296?flat'}
2025-12-22 03:11:26,865 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27795699:ctest-left_vn-41296296 is found in opserver
2025-12-22 03:11:26,889 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:11:26,899 - DEBUG - VRF ids for VN ctest-left_vn-41296296: {}
2025-12-22 03:11:27,183 - DEBUG - Response for create_network : {'network': {'id': 'd4677dc1-72ae-4b2d-ada3-a1fb1aebfa78', 'name': 'ctest-right_vn-75832818', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27795699', 'ctest-right_vn-75832818'], 'port_security_enabled': True, 'description': ''}}
2025-12-22 03:11:27,419 - DEBUG - Response for create_subnet : {'subnet': {'id': 'af048410-b759-445b-b658-297c7185bba5', 'name': '', 'tenant_id': 'b04eb2266df04eb78800382f6f6e4c0c', 'network_id': 'd4677dc1-72ae-4b2d-ada3-a1fb1aebfa78', 'ip_version': 4, 'cidr': '58.68.169.128/26', 'allocation_pools': [{'start': '58.68.169.130', 'end': '58.68.169.190'}], 'gateway_ip': '58.68.169.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '58.68.169.130', 'tags': [], 'project_id': 'b04eb2266df04eb78800382f6f6e4c0c'}}
2025-12-22 03:11:27,448 - INFO - Created VN ctest-right_vn-75832818
2025-12-22 03:11:27,461 - DEBUG - VN ctest-right_vn-75832818 UUID is d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,529 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:27,536 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:27,550 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:27,640 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,654 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,663 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/16bbe9a4-e648-448f-8111-10f4abfba16f
2025-12-22 03:11:27,673 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/16bbe9a4-e648-448f-8111-10f4abfba16f
2025-12-22 03:11:27,681 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6ba9e321-1504-40b8-badb-380c62f1ae4e
2025-12-22 03:11:27,689 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:27,689 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,697 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/16bbe9a4-e648-448f-8111-10f4abfba16f
2025-12-22 03:11:27,711 - INFO - Verified VN network id 12 for VN d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,711 - INFO - Verifications in API Server for VN ctest-right_vn-75832818 passed
2025-12-22 03:11:27,711 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,721 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/16bbe9a4-e648-448f-8111-10f4abfba16f
2025-12-22 03:11:27,729 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/16bbe9a4-e648-448f-8111-10f4abfba16f
2025-12-22 03:11:27,738 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6ba9e321-1504-40b8-badb-380c62f1ae4e
2025-12-22 03:11:27,748 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15305340128448105261', 'uuid-lslong': '12512022289625905784'}, 'enable': 'true', 'created': '2025-12-22T03:11:27', 'last-modified': '2025-12-22T03:11:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415303'}
2025-12-22 03:11:27,753 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:27,758 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15305340128448105261', 'uuid-lslong': '12512022289625905784'}, 'enable': 'true', 'created': '2025-12-22T03:11:27', 'last-modified': '2025-12-22T03:11:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420583'}
2025-12-22 03:11:27,763 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:27,769 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27795699', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15305340128448105261', 'uuid-lslong': '12512022289625905784'}, 'enable': 'true', 'created': '2025-12-22T03:11:27', 'last-modified': '2025-12-22T03:11:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435524'}
2025-12-22 03:11:27,775 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-22 03:11:27,775 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-75832818 passed
2025-12-22 03:11:27,775 - DEBUG - ====Verifying policy data for ctest-right_vn-75832818 in API_Server ======
2025-12-22 03:11:27,775 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:27,782 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:27,792 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:27,892 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78
2025-12-22 03:11:27,906 - DEBUG - =>VN ctest-right_vn-75832818 has no policy to be verified
2025-12-22 03:11:27,906 - DEBUG - Verifying the vn in opserver
2025-12-22 03:11:27,906 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818 virtual network link through opserver 10.0.0.23
2025-12-22 03:11:27,906 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-22 03:11:27,919 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818?flat'}
2025-12-22 03:11:27,919 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818 virtual network link through opserver 10.0.0.22
2025-12-22 03:11:27,919 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-12-22 03:11:27,933 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818?flat'}
2025-12-22 03:11:27,933 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818 virtual network link through opserver 10.0.0.65
2025-12-22 03:11:27,933 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-12-22 03:11:27,947 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818?flat'}
2025-12-22 03:11:27,947 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27795699:ctest-right_vn-75832818 is found in opserver
2025-12-22 03:11:27,977 - DEBUG - Do not have enough data to verify VN in agent
2025-12-22 03:11:27,990 - DEBUG - VRF ids for VN ctest-right_vn-75832818: {}
2025-12-22 03:11:29,038 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-2)
2025-12-22 03:11:30,446 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-1)
2025-12-22 03:11:30,446 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-92569138']
2025-12-22 03:11:30,773 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92569138'] created succesfully
2025-12-22 03:11:31,126 - DEBUG - Image by name tiny_nat_fw not found
2025-12-22 03:11:31,127 - DEBUG - Installing image tiny_nat_fw
2025-12-22 03:11:31,127 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-12-22 03:11:31,127 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:11:31,127 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:11:31,127 - DEBUG - Unzip image
2025-12-22 03:11:31,127 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-22 03:11:31,144 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-22 03:11:31,145 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-92569138']
2025-12-22 03:11:31,302 - INFO - Service template: ['default-domain', 'ctest-service_template_1-92569138'] deleted successfully.
2025-12-22 03:11:31,302 - INFO - Skip interface_detach for VM ctest-right_vm-00302267 in state BUILD
2025-12-22 03:11:31,302 - INFO - Deleting VM ctest-right_vm-00302267
2025-12-22 03:11:31,302 - ERROR - VM ctest-right_vm-00302267 has failed to come up
2025-12-22 03:11:31,302 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:31,302 - ERROR - VM ctest-right_vm-00302267 has failed to come up
2025-12-22 03:11:31,302 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:31,372 - INFO - Skip interface_detach for VM ctest-left_vm-57415881 in state BUILD
2025-12-22 03:11:31,372 - INFO - Deleting VM ctest-left_vm-57415881
2025-12-22 03:11:31,372 - ERROR - VM ctest-left_vm-57415881 has failed to come up
2025-12-22 03:11:31,372 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:31,373 - ERROR - VM ctest-left_vm-57415881 has failed to come up
2025-12-22 03:11:31,373 - ERROR - Nova failed to get host of the VM
2025-12-22 03:11:31,451 - INFO - Deleting VN ctest-right_vn-75832818
2025-12-22 03:11:31,492 - DEBUG - VN d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78 still in use: Unable to complete operation on network d4677dc1-72ae-4b2d-ada3-a1fb1aebfa78. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9b09de4c-c28a-4e71-872a-70f419431f68']
2025-12-22 03:11:31,492 - WARNING - Deleting VN ctest-right_vn-75832818 failed..Will retry
2025-12-22 03:11:33,683 - DEBUG - Response for deleting network ()
2025-12-22 03:11:33,683 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/16bbe9a4-e648-448f-8111-10f4abfba16f
2025-12-22 03:11:33,690 - DEBUG - Response Code: 404
2025-12-22 03:11:33,690 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:33,705 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:33,763 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:33,859 - INFO - Validated that VN ctest-right_vn-75832818 is not found in API Server
2025-12-22 03:11:33,880 - DEBUG - VN ctest-right_vn-75832818 is not present in Agent 10.0.0.130
2025-12-22 03:11:33,892 - DEBUG - VN ctest-right_vn-75832818 is not present in Agent 10.0.0.19
2025-12-22 03:11:33,892 - INFO - Validated that VN ctest-right_vn-75832818 is not in any agent
2025-12-22 03:11:33,936 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-75832818 info
2025-12-22 03:11:33,936 - INFO - Deleting VN ctest-left_vn-41296296
2025-12-22 03:11:34,091 - DEBUG - Response for deleting network ()
2025-12-22 03:11:34,091 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/6af3c789-6424-4276-8840-ff6184300af8
2025-12-22 03:11:34,097 - DEBUG - Response Code: 404
2025-12-22 03:11:34,097 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:34,104 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:34,130 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:34,217 - INFO - Validated that VN ctest-left_vn-41296296 is not found in API Server
2025-12-22 03:11:34,230 - DEBUG - VN ctest-left_vn-41296296 is not present in Agent 10.0.0.130
2025-12-22 03:11:34,243 - DEBUG - VN ctest-left_vn-41296296 is not present in Agent 10.0.0.19
2025-12-22 03:11:34,243 - INFO - Validated that VN ctest-left_vn-41296296 is not in any agent
2025-12-22 03:11:34,277 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-41296296 info
2025-12-22 03:11:34,278 - INFO - Deleting VN ctest-mgmt_vn-50549518
2025-12-22 03:11:34,423 - DEBUG - Response for deleting network ()
2025-12-22 03:11:34,423 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/2b4ca922-2cdd-4b7d-8a06-4ae2d0baaf63
2025-12-22 03:11:34,429 - DEBUG - Response Code: 404
2025-12-22 03:11:34,429 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-12-22 03:11:34,434 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822
2025-12-22 03:11:34,455 - DEBUG - Requesting: http://10.0.0.23:8082/project/b04eb226-6df0-4eb7-8800-382f6f6e4c0c
2025-12-22 03:11:34,549 - INFO - Validated that VN ctest-mgmt_vn-50549518 is not found in API Server
2025-12-22 03:11:34,560 - DEBUG - VN ctest-mgmt_vn-50549518 is not present in Agent 10.0.0.130
2025-12-22 03:11:34,572 - DEBUG - VN ctest-mgmt_vn-50549518 is not present in Agent 10.0.0.19
2025-12-22 03:11:34,572 - INFO - Validated that VN ctest-mgmt_vn-50549518 is not in any agent
2025-12-22 03:11:34,602 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-50549518 info
2025-12-22 03:11:35,904 - DEBUG - No XMPP flaps were noticed during the test
2025-12-22 03:11:35,904 - INFO -
2025-12-22 03:11:35,904 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-12-22 03:11:35,904 - INFO - --------------------------------------------------------------------------------
2025-12-22 03:11:36,651 - INFO - Deleted project: ctest-TestSvcRegr-27795699, ID : b04eb226-6df0-4eb7-8800-382f6f6e4c0c