]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-2)
2025-10-29 18:56:11,794 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-99287658']
2025-10-29 18:56:12,104 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-99287658'] created succesfully
2025-10-29 18:56:12,530 - DEBUG - Image by name tiny_nat_fw not found
2025-10-29 18:56:12,530 - DEBUG - Installing image tiny_nat_fw
2025-10-29 18:56:12,530 - 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-10-29 18:56:12,530 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:12,530 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:12,563 - 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-10-29 18:56:12,630 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:12,630 - DEBUG - Unzip image
2025-10-29 18:56:12,630 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:12,655 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-29 18:56:12,656 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-99287658']
2025-10-29 18:56:12,757 - INFO - Service template: ['default-domain', 'ctest-service_template_1-99287658'] deleted successfully.
2025-10-29 18:56:12,757 - INFO - Skip interface_detach for VM ctest-right_vm-60905991 in state BUILD
2025-10-29 18:56:12,757 - INFO - Deleting VM ctest-right_vm-60905991
2025-10-29 18:56:12,812 - ERROR - VM ctest-right_vm-60905991 has failed to come up
2025-10-29 18:56:12,813 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:12,813 - ERROR - VM ctest-right_vm-60905991 has failed to come up
2025-10-29 18:56:12,813 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:12,893 - INFO - Skip interface_detach for VM ctest-left_vm-84585724 in state BUILD
2025-10-29 18:56:12,894 - INFO - Deleting VM ctest-left_vm-84585724
2025-10-29 18:56:12,894 - ERROR - VM ctest-left_vm-84585724 has failed to come up
2025-10-29 18:56:12,894 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:12,894 - ERROR - VM ctest-left_vm-84585724 has failed to come up
2025-10-29 18:56:12,894 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:12,973 - INFO - Deleting VN ctest-right_vn-13569048
2025-10-29 18:56:13,021 - DEBUG - VN 31c22524-d08e-4370-b4b2-bfbe1dbd7cb4 still in use: Unable to complete operation on network 31c22524-d08e-4370-b4b2-bfbe1dbd7cb4. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-57cdd0e1-bbda-4a55-9d1e-034886c274e7']
2025-10-29 18:56:13,021 - WARNING - Deleting VN ctest-right_vn-13569048 failed..Will retry
2025-10-29 18:56:15,205 - DEBUG - Response for deleting network ()
2025-10-29 18:56:15,206 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f9f5b5ee-fb86-41f4-9631-684ff48a5de5
2025-10-29 18:56:15,213 - DEBUG - Response Code: 404
2025-10-29 18:56:15,213 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:15,222 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:15,255 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:15,365 - INFO - Validated that VN ctest-right_vn-13569048 is not found in API Server
2025-10-29 18:56:15,388 - DEBUG - VN ctest-right_vn-13569048 is not present in Agent 10.0.0.73
2025-10-29 18:56:15,401 - DEBUG - VN ctest-right_vn-13569048 is not present in Agent 10.0.0.27
2025-10-29 18:56:15,401 - INFO - Validated that VN ctest-right_vn-13569048 is not in any agent
2025-10-29 18:56:15,462 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-13569048 info
2025-10-29 18:56:15,462 - INFO - Deleting VN ctest-left_vn-49669141
2025-10-29 18:56:15,664 - DEBUG - Response for deleting network ()
2025-10-29 18:56:15,664 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/027a51ec-8215-46c4-a2f1-73c6d52fcdbb
2025-10-29 18:56:15,671 - DEBUG - Response Code: 404
2025-10-29 18:56:15,671 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:15,684 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:15,742 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:15,850 - INFO - Validated that VN ctest-left_vn-49669141 is not found in API Server
2025-10-29 18:56:15,869 - DEBUG - VN ctest-left_vn-49669141 is not present in Agent 10.0.0.73
2025-10-29 18:56:15,883 - DEBUG - VN ctest-left_vn-49669141 is not present in Agent 10.0.0.27
2025-10-29 18:56:15,883 - INFO - Validated that VN ctest-left_vn-49669141 is not in any agent
2025-10-29 18:56:15,931 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-49669141 info
2025-10-29 18:56:15,931 - INFO - Deleting VN ctest-mgmt_vn-01192789
2025-10-29 18:56:16,158 - DEBUG - Response for deleting network ()
2025-10-29 18:56:16,158 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/845f6619-1066-432f-bfe2-68a7362b9bb0
2025-10-29 18:56:16,165 - DEBUG - Response Code: 404
2025-10-29 18:56:16,165 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:16,176 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:16,200 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:16,315 - INFO - Validated that VN ctest-mgmt_vn-01192789 is not found in API Server
2025-10-29 18:56:16,340 - DEBUG - VN ctest-mgmt_vn-01192789 is not present in Agent 10.0.0.73
2025-10-29 18:56:16,353 - DEBUG - VN ctest-mgmt_vn-01192789 is not present in Agent 10.0.0.27
2025-10-29 18:56:16,353 - INFO - Validated that VN ctest-mgmt_vn-01192789 is not in any agent
2025-10-29 18:56:16,395 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-01192789 info
2025-10-29 18:56:16,395 - INFO - Deleting VM ctest-vm-in-third-vn-29260348
2025-10-29 18:56:16,540 - INFO - Deleting VN ctest-third-vn-69413679
2025-10-29 18:56:16,594 - DEBUG - VN d930b7c1-62c8-4cf6-a092-ce3b12c647b5 still in use: Unable to complete operation on network d930b7c1-62c8-4cf6-a092-ce3b12c647b5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6784acc4-8a5f-4a79-acd9-6525d084e675']
2025-10-29 18:56:16,594 - WARNING - Deleting VN ctest-third-vn-69413679 failed..Will retry
2025-10-29 18:56:18,819 - DEBUG - Response for deleting network ()
2025-10-29 18:56:20,284 - DEBUG - No XMPP flaps were noticed during the test
2025-10-29 18:56:20,285 - INFO -
2025-10-29 18:56:20,285 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:27]
2025-10-29 18:56:20,286 - INFO - --------------------------------------------------------------------------------
2025-10-29 18:56:20,295 - INFO - ================================================================================
2025-10-29 18:56:20,295 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-29 18:56:21,735 - DEBUG - Nothing to compare xmpp stats {'10.0.0.73': {'10.20.0.201': '0', '10.20.0.200': '0'}, '10.0.0.27': {'10.20.0.201': '0', '10.20.0.200': '0'}} with
2025-10-29 18:56:21,735 - INFO - Initial checks done. Running the testcase now
2025-10-29 18:56:21,735 - INFO -
2025-10-29 18:56:22,082 - DEBUG - Response for create_network : {'network': {'id': '4b88ce1e-ce53-42dd-94c9-58da26e9512e', 'name': 'ctest-mgmt_vn-48390122', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'project_id': '55af3842cc2448e6b6e0682e33468aa9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-92566038', 'ctest-mgmt_vn-48390122'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:22,345 - DEBUG - Response for create_subnet : {'subnet': {'id': '09705ecb-e35e-4693-a26f-2d3eb4806a14', 'name': '', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'network_id': '4b88ce1e-ce53-42dd-94c9-58da26e9512e', 'ip_version': 4, 'cidr': '51.153.161.0/26', 'allocation_pools': [{'start': '51.153.161.2', 'end': '51.153.161.62'}], 'gateway_ip': '51.153.161.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '51.153.161.2', 'tags': [], 'project_id': '55af3842cc2448e6b6e0682e33468aa9'}}
2025-10-29 18:56:22,371 - INFO - Created VN ctest-mgmt_vn-48390122
2025-10-29 18:56:22,383 - DEBUG - VN ctest-mgmt_vn-48390122 UUID is 4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,464 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:22,474 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:22,498 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:22,601 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,616 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,630 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c7974856-bace-4914-9d0f-8515f1ec5282
2025-10-29 18:56:22,644 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c7974856-bace-4914-9d0f-8515f1ec5282
2025-10-29 18:56:22,658 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2adbd05b-9e79-4da1-9e0c-8522e604352b
2025-10-29 18:56:22,668 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:22,668 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,681 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c7974856-bace-4914-9d0f-8515f1ec5282
2025-10-29 18:56:22,700 - INFO - Verified VN network id 11 for VN 4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,700 - INFO - Verifications in API Server for VN ctest-mgmt_vn-48390122 passed
2025-10-29 18:56:22,700 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,715 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c7974856-bace-4914-9d0f-8515f1ec5282
2025-10-29 18:56:22,726 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c7974856-bace-4914-9d0f-8515f1ec5282
2025-10-29 18:56:22,739 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2adbd05b-9e79-4da1-9e0c-8522e604352b
2025-10-29 18:56:22,759 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5442826781383148253', 'uuid-lslong': '10721198081911116078'}, 'enable': 'true', 'created': '2025-10-29T18:56:22', 'last-modified': '2025-10-29T18:56:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464185'}
2025-10-29 18:56:22,766 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:22,772 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5442826781383148253', 'uuid-lslong': '10721198081911116078'}, 'enable': 'true', 'created': '2025-10-29T18:56:22', 'last-modified': '2025-10-29T18:56:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.479346'}
2025-10-29 18:56:22,781 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:22,786 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5442826781383148253', 'uuid-lslong': '10721198081911116078'}, 'enable': 'true', 'created': '2025-10-29T18:56:22', 'last-modified': '2025-10-29T18:56:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500797'}
2025-10-29 18:56:22,792 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:22,792 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-48390122 passed
2025-10-29 18:56:22,792 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-48390122 in API_Server ======
2025-10-29 18:56:22,793 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:22,803 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:22,827 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:22,951 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4b88ce1e-ce53-42dd-94c9-58da26e9512e
2025-10-29 18:56:22,977 - DEBUG - =>VN ctest-mgmt_vn-48390122 has no policy to be verified
2025-10-29 18:56:22,977 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:22,977 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:22,977 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:22,995 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122?flat'}
2025-10-29 18:56:22,995 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:22,995 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:23,010 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122?flat'}
2025-10-29 18:56:23,010 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:23,011 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:23,024 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122?flat'}
2025-10-29 18:56:23,024 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-92566038:ctest-mgmt_vn-48390122 is found in opserver
2025-10-29 18:56:23,058 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:23,080 - DEBUG - VRF ids for VN ctest-mgmt_vn-48390122: {}
2025-10-29 18:56:23,420 - DEBUG - Response for create_network : {'network': {'id': '5c8dbc1e-dc40-4adc-b083-83b8a89a52c6', 'name': 'ctest-left_vn-28849536', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'project_id': '55af3842cc2448e6b6e0682e33468aa9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-92566038', 'ctest-left_vn-28849536'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:23,639 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ee188538-7f61-4832-af47-22fd7ec9f817', 'name': '', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'network_id': '5c8dbc1e-dc40-4adc-b083-83b8a89a52c6', 'ip_version': 4, 'cidr': '104.234.146.128/26', 'allocation_pools': [{'start': '104.234.146.130', 'end': '104.234.146.190'}], 'gateway_ip': '104.234.146.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '104.234.146.130', 'tags': [], 'project_id': '55af3842cc2448e6b6e0682e33468aa9'}}
2025-10-29 18:56:23,663 - INFO - Created VN ctest-left_vn-28849536
2025-10-29 18:56:23,675 - DEBUG - VN ctest-left_vn-28849536 UUID is 5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:23,753 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:23,769 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:23,780 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:23,894 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:23,917 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:23,936 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/348db12e-d3fd-40da-a8c7-488fd26d2ee1
2025-10-29 18:56:23,945 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/348db12e-d3fd-40da-a8c7-488fd26d2ee1
2025-10-29 18:56:23,954 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/1544340b-f052-4bed-871a-d3ffddb25ccc
2025-10-29 18:56:23,963 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:23,963 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:23,976 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/348db12e-d3fd-40da-a8c7-488fd26d2ee1
2025-10-29 18:56:23,994 - INFO - Verified VN network id 12 for VN 5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:23,994 - INFO - Verifications in API Server for VN ctest-left_vn-28849536 passed
2025-10-29 18:56:23,994 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:24,013 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/348db12e-d3fd-40da-a8c7-488fd26d2ee1
2025-10-29 18:56:24,023 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/348db12e-d3fd-40da-a8c7-488fd26d2ee1
2025-10-29 18:56:24,031 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/1544340b-f052-4bed-871a-d3ffddb25ccc
2025-10-29 18:56:24,047 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6669193463935814364', 'uuid-lslong': '12719154601750319814'}, 'enable': 'true', 'created': '2025-10-29T18:56:23', 'last-modified': '2025-10-29T18:56:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442423'}
2025-10-29 18:56:24,055 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:24,061 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6669193463935814364', 'uuid-lslong': '12719154601750319814'}, 'enable': 'true', 'created': '2025-10-29T18:56:23', 'last-modified': '2025-10-29T18:56:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.461700'}
2025-10-29 18:56:24,068 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:24,074 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6669193463935814364', 'uuid-lslong': '12719154601750319814'}, 'enable': 'true', 'created': '2025-10-29T18:56:23', 'last-modified': '2025-10-29T18:56:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.474422'}
2025-10-29 18:56:24,079 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:24,079 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-28849536 passed
2025-10-29 18:56:24,080 - DEBUG - ====Verifying policy data for ctest-left_vn-28849536 in API_Server ======
2025-10-29 18:56:24,080 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:24,089 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:24,104 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:24,227 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5c8dbc1e-dc40-4adc-b083-83b8a89a52c6
2025-10-29 18:56:24,240 - DEBUG - =>VN ctest-left_vn-28849536 has no policy to be verified
2025-10-29 18:56:24,240 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:24,240 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:24,240 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:24,265 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536?flat'}
2025-10-29 18:56:24,265 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:24,266 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:24,287 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536?flat'}
2025-10-29 18:56:24,287 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:24,288 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:24,309 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536?flat'}
2025-10-29 18:56:24,309 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-92566038:ctest-left_vn-28849536 is found in opserver
2025-10-29 18:56:24,363 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:24,390 - DEBUG - VRF ids for VN ctest-left_vn-28849536: {}
2025-10-29 18:56:24,713 - DEBUG - Response for create_network : {'network': {'id': '1b7946c4-c741-4abf-979b-7fdacf4d88cb', 'name': 'ctest-right_vn-74616530', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'project_id': '55af3842cc2448e6b6e0682e33468aa9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-92566038', 'ctest-right_vn-74616530'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:24,979 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eeb88901-4aec-48b1-b5f4-380f33d826b7', 'name': '', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'network_id': '1b7946c4-c741-4abf-979b-7fdacf4d88cb', 'ip_version': 4, 'cidr': '82.93.178.64/26', 'allocation_pools': [{'start': '82.93.178.66', 'end': '82.93.178.126'}], 'gateway_ip': '82.93.178.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '82.93.178.66', 'tags': [], 'project_id': '55af3842cc2448e6b6e0682e33468aa9'}}
2025-10-29 18:56:25,002 - INFO - Created VN ctest-right_vn-74616530
2025-10-29 18:56:25,017 - DEBUG - VN ctest-right_vn-74616530 UUID is 1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,098 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:25,107 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:25,126 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:25,259 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,277 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,293 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/70cdba75-3e69-4e95-b19d-5ab9c1878b7e
2025-10-29 18:56:25,302 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/70cdba75-3e69-4e95-b19d-5ab9c1878b7e
2025-10-29 18:56:25,311 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2cbbd91b-0759-4e6e-b3a1-3866210a1e74
2025-10-29 18:56:25,320 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:25,320 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,336 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/70cdba75-3e69-4e95-b19d-5ab9c1878b7e
2025-10-29 18:56:25,359 - INFO - Verified VN network id 13 for VN 1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,359 - INFO - Verifications in API Server for VN ctest-right_vn-74616530 passed
2025-10-29 18:56:25,359 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,371 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/70cdba75-3e69-4e95-b19d-5ab9c1878b7e
2025-10-29 18:56:25,383 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/70cdba75-3e69-4e95-b19d-5ab9c1878b7e
2025-10-29 18:56:25,393 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2cbbd91b-0759-4e6e-b3a1-3866210a1e74
2025-10-29 18:56:25,407 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1979691322176522943', 'uuid-lslong': '10924465898874833099'}, 'enable': 'true', 'created': '2025-10-29T18:56:24', 'last-modified': '2025-10-29T18:56:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.487710'}
2025-10-29 18:56:25,412 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:25,418 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1979691322176522943', 'uuid-lslong': '10924465898874833099'}, 'enable': 'true', 'created': '2025-10-29T18:56:24', 'last-modified': '2025-10-29T18:56:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.498946'}
2025-10-29 18:56:25,425 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:25,430 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1979691322176522943', 'uuid-lslong': '10924465898874833099'}, 'enable': 'true', 'created': '2025-10-29T18:56:24', 'last-modified': '2025-10-29T18:56:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512607'}
2025-10-29 18:56:25,437 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:25,438 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-74616530 passed
2025-10-29 18:56:25,438 - DEBUG - ====Verifying policy data for ctest-right_vn-74616530 in API_Server ======
2025-10-29 18:56:25,438 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:25,444 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:25,454 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:25,555 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1b7946c4-c741-4abf-979b-7fdacf4d88cb
2025-10-29 18:56:25,567 - DEBUG - =>VN ctest-right_vn-74616530 has no policy to be verified
2025-10-29 18:56:25,568 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:25,568 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:25,568 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:25,583 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530?flat'}
2025-10-29 18:56:25,583 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:25,583 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:25,600 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530?flat'}
2025-10-29 18:56:25,600 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:25,600 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:25,616 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530?flat'}
2025-10-29 18:56:25,616 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-92566038:ctest-right_vn-74616530 is found in opserver
2025-10-29 18:56:25,656 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:25,677 - DEBUG - VRF ids for VN ctest-right_vn-74616530: {}
2025-10-29 18:56:26,001 - DEBUG - Response for create_network : {'network': {'id': '97898042-6a0e-4922-85bb-7eec8e4d22c8', 'name': 'ctest-trans_left_vn-69318792', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'project_id': '55af3842cc2448e6b6e0682e33468aa9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-92566038', 'ctest-trans_left_vn-69318792'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:26,232 - DEBUG - Response for create_subnet : {'subnet': {'id': '2a58acb5-8e54-4f32-a30e-5115cdde1f31', 'name': '', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'network_id': '97898042-6a0e-4922-85bb-7eec8e4d22c8', 'ip_version': 4, 'cidr': '109.17.168.64/26', 'allocation_pools': [{'start': '109.17.168.66', 'end': '109.17.168.126'}], 'gateway_ip': '109.17.168.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '109.17.168.66', 'tags': [], 'project_id': '55af3842cc2448e6b6e0682e33468aa9'}}
2025-10-29 18:56:26,257 - INFO - Created VN ctest-trans_left_vn-69318792
2025-10-29 18:56:26,269 - DEBUG - VN ctest-trans_left_vn-69318792 UUID is 97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,358 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:26,366 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:26,383 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:26,497 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,510 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,520 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a3210762-2acb-48bc-9274-058001248453
2025-10-29 18:56:26,530 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a3210762-2acb-48bc-9274-058001248453
2025-10-29 18:56:26,539 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/aae88de3-583d-44db-9f61-9d0353d2706a
2025-10-29 18:56:26,547 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-29 18:56:26,547 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,557 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a3210762-2acb-48bc-9274-058001248453
2025-10-29 18:56:26,576 - INFO - Verified VN network id 14 for VN 97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,576 - INFO - Verifications in API Server for VN ctest-trans_left_vn-69318792 passed
2025-10-29 18:56:26,576 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,591 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a3210762-2acb-48bc-9274-058001248453
2025-10-29 18:56:26,601 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a3210762-2acb-48bc-9274-058001248453
2025-10-29 18:56:26,613 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/aae88de3-583d-44db-9f61-9d0353d2706a
2025-10-29 18:56:26,630 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10919399794271996194', 'uuid-lslong': '9636435382154109640'}, 'enable': 'true', 'created': '2025-10-29T18:56:25', 'last-modified': '2025-10-29T18:56:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458559'}
2025-10-29 18:56:26,639 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-29 18:56:26,646 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10919399794271996194', 'uuid-lslong': '9636435382154109640'}, 'enable': 'true', 'created': '2025-10-29T18:56:25', 'last-modified': '2025-10-29T18:56:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.472610'}
2025-10-29 18:56:26,657 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-29 18:56:26,665 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10919399794271996194', 'uuid-lslong': '9636435382154109640'}, 'enable': 'true', 'created': '2025-10-29T18:56:25', 'last-modified': '2025-10-29T18:56:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.492666'}
2025-10-29 18:56:26,676 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-29 18:56:26,676 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-69318792 passed
2025-10-29 18:56:26,676 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-69318792 in API_Server ======
2025-10-29 18:56:26,676 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:26,687 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:26,714 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:26,859 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/97898042-6a0e-4922-85bb-7eec8e4d22c8
2025-10-29 18:56:26,877 - DEBUG - =>VN ctest-trans_left_vn-69318792 has no policy to be verified
2025-10-29 18:56:26,877 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:26,877 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:26,877 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:26,897 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792?flat'}
2025-10-29 18:56:26,897 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:26,897 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:26,913 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792?flat'}
2025-10-29 18:56:26,913 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:26,913 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:26,927 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792?flat'}
2025-10-29 18:56:26,927 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-92566038:ctest-trans_left_vn-69318792 is found in opserver
2025-10-29 18:56:26,966 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:26,988 - DEBUG - VRF ids for VN ctest-trans_left_vn-69318792: {}
2025-10-29 18:56:27,345 - DEBUG - Response for create_network : {'network': {'id': 'b2b37c69-a196-4a2e-93f7-e4659610d816', 'name': 'ctest-trans_right_vn-73161380', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'project_id': '55af3842cc2448e6b6e0682e33468aa9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-92566038', 'ctest-trans_right_vn-73161380'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:27,580 - DEBUG - Response for create_subnet : {'subnet': {'id': '6200291a-338f-4641-8c58-4621482cd149', 'name': '', 'tenant_id': '55af3842cc2448e6b6e0682e33468aa9', 'network_id': 'b2b37c69-a196-4a2e-93f7-e4659610d816', 'ip_version': 4, 'cidr': '151.92.175.128/26', 'allocation_pools': [{'start': '151.92.175.130', 'end': '151.92.175.190'}], 'gateway_ip': '151.92.175.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '151.92.175.130', 'tags': [], 'project_id': '55af3842cc2448e6b6e0682e33468aa9'}}
2025-10-29 18:56:27,605 - INFO - Created VN ctest-trans_right_vn-73161380
2025-10-29 18:56:27,618 - DEBUG - VN ctest-trans_right_vn-73161380 UUID is b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:27,693 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:27,700 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:27,715 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:27,848 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:27,861 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:27,875 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c8d134bf-01ad-45c2-b897-5334e0cfb6dd
2025-10-29 18:56:27,884 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c8d134bf-01ad-45c2-b897-5334e0cfb6dd
2025-10-29 18:56:27,893 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/cdf3fecc-6ece-434b-8d34-70d83c96e3d8
2025-10-29 18:56:27,905 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-29 18:56:27,905 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:27,919 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c8d134bf-01ad-45c2-b897-5334e0cfb6dd
2025-10-29 18:56:27,938 - INFO - Verified VN network id 15 for VN b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:27,938 - INFO - Verifications in API Server for VN ctest-trans_right_vn-73161380 passed
2025-10-29 18:56:27,938 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:27,955 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c8d134bf-01ad-45c2-b897-5334e0cfb6dd
2025-10-29 18:56:27,966 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c8d134bf-01ad-45c2-b897-5334e0cfb6dd
2025-10-29 18:56:27,977 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/cdf3fecc-6ece-434b-8d34-70d83c96e3d8
2025-10-29 18:56:27,993 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12876772552706771502', 'uuid-lslong': '10662241767783454742'}, 'enable': 'true', 'created': '2025-10-29T18:56:27', 'last-modified': '2025-10-29T18:56:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.457194'}
2025-10-29 18:56:28,001 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-29 18:56:28,008 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12876772552706771502', 'uuid-lslong': '10662241767783454742'}, 'enable': 'true', 'created': '2025-10-29T18:56:27', 'last-modified': '2025-10-29T18:56:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476654'}
2025-10-29 18:56:28,015 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-29 18:56:28,020 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-92566038', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12876772552706771502', 'uuid-lslong': '10662241767783454742'}, 'enable': 'true', 'created': '2025-10-29T18:56:27', 'last-modified': '2025-10-29T18:56:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.494903'}
2025-10-29 18:56:28,026 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-29 18:56:28,026 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-73161380 passed
2025-10-29 18:56:28,026 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-73161380 in API_Server ======
2025-10-29 18:56:28,026 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:28,032 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:28,044 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:28,171 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b2b37c69-a196-4a2e-93f7-e4659610d816
2025-10-29 18:56:28,182 - DEBUG - =>VN ctest-trans_right_vn-73161380 has no policy to be verified
2025-10-29 18:56:28,182 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:28,182 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:28,182 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:28,195 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380?flat'}
2025-10-29 18:56:28,195 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:28,195 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:28,211 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380?flat'}
2025-10-29 18:56:28,211 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:28,211 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:28,226 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380?flat'}
2025-10-29 18:56:28,227 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-92566038:ctest-trans_right_vn-73161380 is found in opserver
2025-10-29 18:56:28,270 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:28,296 - DEBUG - VRF ids for VN ctest-trans_right_vn-73161380: {}
2025-10-29 18:56:29,573 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-2)
2025-10-29 18:56:31,030 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-1)
2025-10-29 18:56:31,030 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-72075334']
2025-10-29 18:56:31,348 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-72075334'] created succesfully
2025-10-29 18:56:31,774 - DEBUG - Image by name tiny_trans_fw not found
2025-10-29 18:56:31,774 - DEBUG - Installing image tiny_trans_fw
2025-10-29 18:56:31,774 - 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-10-29 18:56:31,774 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-29 18:56:31,774 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-29 18:56:31,793 - 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-10-29 18:56:31,832 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-29 18:56:31,832 - DEBUG - Unzip image
2025-10-29 18:56:31,832 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-29 18:56:31,849 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-29 18:56:31,850 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-72075334']
2025-10-29 18:56:31,953 - INFO - Service template: ['default-domain', 'ctest-service_template_1-72075334'] deleted successfully.
2025-10-29 18:56:31,953 - INFO - Skip interface_detach for VM ctest-right_vm-96525106 in state BUILD
2025-10-29 18:56:31,953 - INFO - Deleting VM ctest-right_vm-96525106
2025-10-29 18:56:31,953 - ERROR - VM ctest-right_vm-96525106 has failed to come up
2025-10-29 18:56:31,953 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:31,954 - ERROR - VM ctest-right_vm-96525106 has failed to come up
2025-10-29 18:56:31,954 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:32,118 - INFO - Skip interface_detach for VM ctest-left_vm-81853897 in state BUILD
2025-10-29 18:56:32,118 - INFO - Deleting VM ctest-left_vm-81853897
2025-10-29 18:56:32,119 - ERROR - VM ctest-left_vm-81853897 has failed to come up
2025-10-29 18:56:32,119 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:32,119 - ERROR - VM ctest-left_vm-81853897 has failed to come up
2025-10-29 18:56:32,119 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:32,191 - INFO - Deleting VN ctest-trans_right_vn-73161380
2025-10-29 18:56:32,364 - DEBUG - Response for deleting network ()
2025-10-29 18:56:32,365 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c8d134bf-01ad-45c2-b897-5334e0cfb6dd
2025-10-29 18:56:32,374 - DEBUG - Response Code: 404
2025-10-29 18:56:32,374 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:32,382 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:32,398 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:32,523 - INFO - Validated that VN ctest-trans_right_vn-73161380 is not found in API Server
2025-10-29 18:56:32,559 - DEBUG - VN ctest-trans_right_vn-73161380 is not present in Agent 10.0.0.73
2025-10-29 18:56:32,570 - DEBUG - VN ctest-trans_right_vn-73161380 is not present in Agent 10.0.0.27
2025-10-29 18:56:32,570 - INFO - Validated that VN ctest-trans_right_vn-73161380 is not in any agent
2025-10-29 18:56:32,623 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-73161380 info
2025-10-29 18:56:32,623 - INFO - Deleting VN ctest-trans_left_vn-69318792
2025-10-29 18:56:32,836 - DEBUG - Response for deleting network ()
2025-10-29 18:56:32,836 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/a3210762-2acb-48bc-9274-058001248453
2025-10-29 18:56:32,860 - DEBUG - Response Code: 404
2025-10-29 18:56:32,861 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:32,868 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:32,878 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:32,979 - INFO - Validated that VN ctest-trans_left_vn-69318792 is not found in API Server
2025-10-29 18:56:32,991 - DEBUG - VN ctest-trans_left_vn-69318792 is not present in Agent 10.0.0.73
2025-10-29 18:56:32,999 - DEBUG - VN ctest-trans_left_vn-69318792 is not present in Agent 10.0.0.27
2025-10-29 18:56:32,999 - INFO - Validated that VN ctest-trans_left_vn-69318792 is not in any agent
2025-10-29 18:56:33,047 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-69318792 info
2025-10-29 18:56:33,047 - INFO - Deleting VN ctest-right_vn-74616530
2025-10-29 18:56:33,117 - DEBUG - VN 1b7946c4-c741-4abf-979b-7fdacf4d88cb still in use: Unable to complete operation on network 1b7946c4-c741-4abf-979b-7fdacf4d88cb. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-645582fa-30a0-4555-8ec0-2b30f7bf5cd5']
2025-10-29 18:56:33,117 - WARNING - Deleting VN ctest-right_vn-74616530 failed..Will retry
2025-10-29 18:56:35,301 - DEBUG - Response for deleting network ()
2025-10-29 18:56:35,302 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/70cdba75-3e69-4e95-b19d-5ab9c1878b7e
2025-10-29 18:56:35,315 - DEBUG - Response Code: 404
2025-10-29 18:56:35,315 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:35,322 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:35,338 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:35,442 - INFO - Validated that VN ctest-right_vn-74616530 is not found in API Server
2025-10-29 18:56:35,463 - DEBUG - VN ctest-right_vn-74616530 is not present in Agent 10.0.0.73
2025-10-29 18:56:35,472 - DEBUG - VN ctest-right_vn-74616530 is not present in Agent 10.0.0.27
2025-10-29 18:56:35,472 - INFO - Validated that VN ctest-right_vn-74616530 is not in any agent
2025-10-29 18:56:35,513 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-74616530 info
2025-10-29 18:56:35,513 - INFO - Deleting VN ctest-left_vn-28849536
2025-10-29 18:56:35,703 - DEBUG - Response for deleting network ()
2025-10-29 18:56:35,703 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/348db12e-d3fd-40da-a8c7-488fd26d2ee1
2025-10-29 18:56:35,718 - DEBUG - Response Code: 404
2025-10-29 18:56:35,718 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:35,742 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:35,756 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:35,862 - INFO - Validated that VN ctest-left_vn-28849536 is not found in API Server
2025-10-29 18:56:35,885 - DEBUG - VN ctest-left_vn-28849536 is not present in Agent 10.0.0.73
2025-10-29 18:56:35,895 - DEBUG - VN ctest-left_vn-28849536 is not present in Agent 10.0.0.27
2025-10-29 18:56:35,896 - INFO - Validated that VN ctest-left_vn-28849536 is not in any agent
2025-10-29 18:56:35,931 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-28849536 info
2025-10-29 18:56:35,932 - INFO - Deleting VN ctest-mgmt_vn-48390122
2025-10-29 18:56:36,119 - DEBUG - Response for deleting network ()
2025-10-29 18:56:36,119 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c7974856-bace-4914-9d0f-8515f1ec5282
2025-10-29 18:56:36,126 - DEBUG - Response Code: 404
2025-10-29 18:56:36,126 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:36,135 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:36,186 - DEBUG - Requesting: http://10.0.0.249:8082/project/55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:36,281 - INFO - Validated that VN ctest-mgmt_vn-48390122 is not found in API Server
2025-10-29 18:56:36,306 - DEBUG - VN ctest-mgmt_vn-48390122 is not present in Agent 10.0.0.73
2025-10-29 18:56:36,317 - DEBUG - VN ctest-mgmt_vn-48390122 is not present in Agent 10.0.0.27
2025-10-29 18:56:36,317 - INFO - Validated that VN ctest-mgmt_vn-48390122 is not in any agent
2025-10-29 18:56:36,360 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-48390122 info
2025-10-29 18:56:37,820 - DEBUG - No XMPP flaps were noticed during the test
2025-10-29 18:56:37,820 - INFO -
2025-10-29 18:56:37,820 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2025-10-29 18:56:37,820 - INFO - --------------------------------------------------------------------------------
2025-10-29 18:56:38,657 - INFO - Deleted project: ctest-TestSvcRegr-92566038, ID : 55af3842-cc24-48e6-b6e0-682e33468aa9
2025-10-29 18:56:38,836 - INFO - Domain Default found not creating
2025-10-29 18:56:39,025 - INFO - Project ctest-TestSvcRegr-72794792 not found, creating it
2025-10-29 18:56:39,531 - INFO - Created Project:ctest-TestSvcRegr-72794792, ID : 09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:41,044 - INFO - ================================================================================
2025-10-29 18:56:41,045 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-29 18:56:42,403 - DEBUG - Nothing to compare xmpp stats {'10.0.0.73': {'10.20.0.201': '0', '10.20.0.200': '0'}, '10.0.0.27': {'10.20.0.201': '0', '10.20.0.200': '0'}} with
2025-10-29 18:56:42,403 - INFO - Initial checks done. Running the testcase now
2025-10-29 18:56:42,403 - INFO -
2025-10-29 18:56:43,209 - DEBUG - Response for create_network : {'network': {'id': 'ebc78d75-14d0-4533-ac3d-f280caa37ab7', 'name': 'ctest-mgmt_vn-44347626', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'project_id': '09399d131edf4ad7a45229ba97666adf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72794792', 'ctest-mgmt_vn-44347626'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:43,546 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd6a7ae9e-1758-4c64-90a4-fb8994ba70e6', 'name': '', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'network_id': 'ebc78d75-14d0-4533-ac3d-f280caa37ab7', 'ip_version': 4, 'cidr': '137.176.213.128/26', 'allocation_pools': [{'start': '137.176.213.130', 'end': '137.176.213.190'}], 'gateway_ip': '137.176.213.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '137.176.213.130', 'tags': [], 'project_id': '09399d131edf4ad7a45229ba97666adf'}}
2025-10-29 18:56:43,578 - INFO - Created VN ctest-mgmt_vn-44347626
2025-10-29 18:56:43,594 - DEBUG - VN ctest-mgmt_vn-44347626 UUID is ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:43,687 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:44,021 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:44,051 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:44,075 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:44,175 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:44,197 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:44,207 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d85761bb-9692-450b-af0a-7db3b5423b92
2025-10-29 18:56:44,218 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d85761bb-9692-450b-af0a-7db3b5423b92
2025-10-29 18:56:44,229 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/67b0b576-b1e5-43d1-9f9d-3d4e4cc269ce
2025-10-29 18:56:44,237 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:44,237 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:44,247 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d85761bb-9692-450b-af0a-7db3b5423b92
2025-10-29 18:56:44,263 - INFO - Verified VN network id 11 for VN ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:44,263 - INFO - Verifications in API Server for VN ctest-mgmt_vn-44347626 passed
2025-10-29 18:56:44,263 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:44,276 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d85761bb-9692-450b-af0a-7db3b5423b92
2025-10-29 18:56:44,288 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d85761bb-9692-450b-af0a-7db3b5423b92
2025-10-29 18:56:44,302 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/67b0b576-b1e5-43d1-9f9d-3d4e4cc269ce
2025-10-29 18:56:44,324 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16989703653278369075', 'uuid-lslong': '12411342783072402103'}, 'enable': 'true', 'created': '2025-10-29T18:56:43', 'last-modified': '2025-10-29T18:56:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.845938'}
2025-10-29 18:56:44,332 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:44,340 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16989703653278369075', 'uuid-lslong': '12411342783072402103'}, 'enable': 'true', 'created': '2025-10-29T18:56:43', 'last-modified': '2025-10-29T18:56:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.854312'}
2025-10-29 18:56:44,348 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:44,354 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16989703653278369075', 'uuid-lslong': '12411342783072402103'}, 'enable': 'true', 'created': '2025-10-29T18:56:43', 'last-modified': '2025-10-29T18:56:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.880419'}
2025-10-29 18:56:44,360 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:44,360 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-44347626 passed
2025-10-29 18:56:44,361 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-44347626 in API_Server ======
2025-10-29 18:56:44,361 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:44,367 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:44,378 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:44,473 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/ebc78d75-14d0-4533-ac3d-f280caa37ab7
2025-10-29 18:56:44,487 - DEBUG - =>VN ctest-mgmt_vn-44347626 has no policy to be verified
2025-10-29 18:56:44,487 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:44,487 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:44,487 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:44,831 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:44,887 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626?flat'}
2025-10-29 18:56:44,887 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:44,887 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:45,181 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:45,243 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626?flat'}
2025-10-29 18:56:45,243 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:45,243 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:45,541 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:45,604 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626?flat'}
2025-10-29 18:56:45,604 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-44347626 is found in opserver
2025-10-29 18:56:45,633 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:45,652 - DEBUG - VRF ids for VN ctest-mgmt_vn-44347626: {}
2025-10-29 18:56:45,966 - DEBUG - Response for create_network : {'network': {'id': 'e60f2736-8652-4adf-9750-edc4c0a77459', 'name': 'service-left-vn-35602306', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'project_id': '09399d131edf4ad7a45229ba97666adf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72794792', 'service-left-vn-35602306'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:46,251 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd35edb1b-a2ef-4568-a2c1-7ff6b6410df1', 'name': '', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'network_id': 'e60f2736-8652-4adf-9750-edc4c0a77459', 'ip_version': 4, 'cidr': '1.157.212.192/26', 'allocation_pools': [{'start': '1.157.212.194', 'end': '1.157.212.254'}], 'gateway_ip': '1.157.212.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '1.157.212.194', 'tags': [], 'project_id': '09399d131edf4ad7a45229ba97666adf'}}
2025-10-29 18:56:46,281 - INFO - Created VN service-left-vn-35602306
2025-10-29 18:56:46,292 - DEBUG - VN service-left-vn-35602306 UUID is e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,370 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:46,375 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:46,387 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:46,497 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,515 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,525 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5f9d7869-369d-4bd2-a7f1-722ad2b47c2e
2025-10-29 18:56:46,533 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5f9d7869-369d-4bd2-a7f1-722ad2b47c2e
2025-10-29 18:56:46,541 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b20b79b6-1f7f-4e82-ab38-3cff772e041c
2025-10-29 18:56:46,548 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:46,548 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,559 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5f9d7869-369d-4bd2-a7f1-722ad2b47c2e
2025-10-29 18:56:46,580 - INFO - Verified VN network id 12 for VN e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,580 - INFO - Verifications in API Server for VN service-left-vn-35602306 passed
2025-10-29 18:56:46,580 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,592 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5f9d7869-369d-4bd2-a7f1-722ad2b47c2e
2025-10-29 18:56:46,603 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5f9d7869-369d-4bd2-a7f1-722ad2b47c2e
2025-10-29 18:56:46,611 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b20b79b6-1f7f-4e82-ab38-3cff772e041c
2025-10-29 18:56:46,629 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16577511868509342431', 'uuid-lslong': '10903476127165543513'}, 'enable': 'true', 'created': '2025-10-29T18:56:45', 'last-modified': '2025-10-29T18:56:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428974'}
2025-10-29 18:56:46,640 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:46,646 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16577511868509342431', 'uuid-lslong': '10903476127165543513'}, 'enable': 'true', 'created': '2025-10-29T18:56:45', 'last-modified': '2025-10-29T18:56:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447836'}
2025-10-29 18:56:46,653 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:46,659 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16577511868509342431', 'uuid-lslong': '10903476127165543513'}, 'enable': 'true', 'created': '2025-10-29T18:56:45', 'last-modified': '2025-10-29T18:56:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464268'}
2025-10-29 18:56:46,665 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:56:46,665 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-35602306 passed
2025-10-29 18:56:46,665 - DEBUG - ====Verifying policy data for service-left-vn-35602306 in API_Server ======
2025-10-29 18:56:46,665 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:46,673 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:46,685 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:46,797 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/e60f2736-8652-4adf-9750-edc4c0a77459
2025-10-29 18:56:46,809 - DEBUG - =>VN service-left-vn-35602306 has no policy to be verified
2025-10-29 18:56:46,809 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:46,809 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:46,809 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:46,890 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306?flat'}
2025-10-29 18:56:46,890 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:46,890 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:46,999 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306?flat'}
2025-10-29 18:56:46,999 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:46,999 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:47,102 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306?flat'}
2025-10-29 18:56:47,102 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72794792:service-left-vn-35602306 is found in opserver
2025-10-29 18:56:47,137 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:47,157 - DEBUG - VRF ids for VN service-left-vn-35602306: {}
2025-10-29 18:56:47,421 - DEBUG - Response for create_network : {'network': {'id': 'd86202f6-ed54-4951-9a40-fa4c15545068', 'name': 'service-right-vn-56575291', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'project_id': '09399d131edf4ad7a45229ba97666adf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72794792', 'service-right-vn-56575291'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:47,702 - DEBUG - Response for create_subnet : {'subnet': {'id': '6498613d-dda3-4e35-a827-7e029e10a6f2', 'name': '', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'network_id': 'd86202f6-ed54-4951-9a40-fa4c15545068', 'ip_version': 4, 'cidr': '56.161.8.192/26', 'allocation_pools': [{'start': '56.161.8.194', 'end': '56.161.8.254'}], 'gateway_ip': '56.161.8.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.161.8.194', 'tags': [], 'project_id': '09399d131edf4ad7a45229ba97666adf'}}
2025-10-29 18:56:47,733 - INFO - Created VN service-right-vn-56575291
2025-10-29 18:56:47,745 - DEBUG - VN service-right-vn-56575291 UUID is d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:47,832 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:47,840 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:47,860 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:48,002 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:48,024 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:48,037 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ded10c7f-67b6-40a7-9194-e5751813e094
2025-10-29 18:56:48,052 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ded10c7f-67b6-40a7-9194-e5751813e094
2025-10-29 18:56:48,064 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2c3dbb49-4d42-4089-a61b-7f8b9a2901cc
2025-10-29 18:56:48,072 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:48,072 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:48,087 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ded10c7f-67b6-40a7-9194-e5751813e094
2025-10-29 18:56:48,107 - INFO - Verified VN network id 13 for VN d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:48,107 - INFO - Verifications in API Server for VN service-right-vn-56575291 passed
2025-10-29 18:56:48,107 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:48,119 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ded10c7f-67b6-40a7-9194-e5751813e094
2025-10-29 18:56:48,128 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ded10c7f-67b6-40a7-9194-e5751813e094
2025-10-29 18:56:48,136 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2c3dbb49-4d42-4089-a61b-7f8b9a2901cc
2025-10-29 18:56:48,154 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15592028119477012817', 'uuid-lslong': '11115159085032689768'}, 'enable': 'true', 'created': '2025-10-29T18:56:47', 'last-modified': '2025-10-29T18:56:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.506248'}
2025-10-29 18:56:48,162 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:48,168 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15592028119477012817', 'uuid-lslong': '11115159085032689768'}, 'enable': 'true', 'created': '2025-10-29T18:56:47', 'last-modified': '2025-10-29T18:56:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517635'}
2025-10-29 18:56:48,174 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:48,180 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15592028119477012817', 'uuid-lslong': '11115159085032689768'}, 'enable': 'true', 'created': '2025-10-29T18:56:47', 'last-modified': '2025-10-29T18:56:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.531624'}
2025-10-29 18:56:48,186 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:56:48,186 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-56575291 passed
2025-10-29 18:56:48,187 - DEBUG - ====Verifying policy data for service-right-vn-56575291 in API_Server ======
2025-10-29 18:56:48,187 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:48,194 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:48,205 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:48,309 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d86202f6-ed54-4951-9a40-fa4c15545068
2025-10-29 18:56:48,323 - DEBUG - =>VN service-right-vn-56575291 has no policy to be verified
2025-10-29 18:56:48,323 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:48,323 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:48,323 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:48,406 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291?flat'}
2025-10-29 18:56:48,406 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:48,406 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:56:48,478 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291?flat'}
2025-10-29 18:56:48,478 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291 virtual network link through opserver 10.0.0.41
2025-10-29 18:56:48,478 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:56:48,558 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291?flat'}
2025-10-29 18:56:48,558 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72794792:service-right-vn-56575291 is found in opserver
2025-10-29 18:56:48,618 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:56:48,652 - DEBUG - VRF ids for VN service-right-vn-56575291: {}
2025-10-29 18:56:48,774 - DEBUG - Services list from nova: [, , , ]
2025-10-29 18:56:50,214 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-2)
2025-10-29 18:56:51,587 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-1)
2025-10-29 18:56:51,588 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-53995614']
2025-10-29 18:56:51,895 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-53995614'] created succesfully
2025-10-29 18:56:52,308 - DEBUG - Image by name tiny_nat_fw not found
2025-10-29 18:56:52,308 - DEBUG - Installing image tiny_nat_fw
2025-10-29 18:56:52,308 - 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-10-29 18:56:52,309 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:52,309 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:52,309 - DEBUG - Unzip image
2025-10-29 18:56:52,309 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:56:52,336 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-29 18:56:52,337 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-53995614']
2025-10-29 18:56:52,465 - INFO - Service template: ['default-domain', 'ctest-service_template_1-53995614'] deleted successfully.
2025-10-29 18:56:52,465 - INFO - Skip interface_detach for VM ctest-right_vm-39118011 in state BUILD
2025-10-29 18:56:52,465 - INFO - Deleting VM ctest-right_vm-39118011
2025-10-29 18:56:52,509 - ERROR - VM ctest-right_vm-39118011 has failed to come up
2025-10-29 18:56:52,509 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:52,509 - ERROR - VM ctest-right_vm-39118011 has failed to come up
2025-10-29 18:56:52,509 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:52,603 - INFO - Skip interface_detach for VM ctest-left_vm-77562975 in state BUILD
2025-10-29 18:56:52,603 - INFO - Deleting VM ctest-left_vm-77562975
2025-10-29 18:56:52,604 - ERROR - VM ctest-left_vm-77562975 has failed to come up
2025-10-29 18:56:52,604 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:52,604 - ERROR - VM ctest-left_vm-77562975 has failed to come up
2025-10-29 18:56:52,604 - ERROR - Nova failed to get host of the VM
2025-10-29 18:56:52,741 - INFO - Deleting VN service-right-vn-56575291
2025-10-29 18:56:52,787 - DEBUG - VN d86202f6-ed54-4951-9a40-fa4c15545068 still in use: Unable to complete operation on network d86202f6-ed54-4951-9a40-fa4c15545068. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a78ebc64-9e0c-46ce-a170-0045d8986b89']
2025-10-29 18:56:52,787 - WARNING - Deleting VN service-right-vn-56575291 failed..Will retry
2025-10-29 18:56:54,976 - DEBUG - Response for deleting network ()
2025-10-29 18:56:54,977 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ded10c7f-67b6-40a7-9194-e5751813e094
2025-10-29 18:56:54,985 - DEBUG - Response Code: 404
2025-10-29 18:56:54,985 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:54,995 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:55,022 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:55,130 - INFO - Validated that VN service-right-vn-56575291 is not found in API Server
2025-10-29 18:56:55,177 - DEBUG - VN service-right-vn-56575291 is not present in Agent 10.0.0.73
2025-10-29 18:56:55,189 - DEBUG - VN service-right-vn-56575291 is not present in Agent 10.0.0.27
2025-10-29 18:56:55,189 - INFO - Validated that VN service-right-vn-56575291 is not in any agent
2025-10-29 18:56:55,232 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-56575291 info
2025-10-29 18:56:55,232 - INFO - Deleting VN service-left-vn-35602306
2025-10-29 18:56:55,422 - DEBUG - Response for deleting network ()
2025-10-29 18:56:55,422 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5f9d7869-369d-4bd2-a7f1-722ad2b47c2e
2025-10-29 18:56:55,429 - DEBUG - Response Code: 404
2025-10-29 18:56:55,429 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:55,436 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:55,459 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:55,548 - INFO - Validated that VN service-left-vn-35602306 is not found in API Server
2025-10-29 18:56:55,571 - DEBUG - VN service-left-vn-35602306 is not present in Agent 10.0.0.73
2025-10-29 18:56:55,579 - DEBUG - VN service-left-vn-35602306 is not present in Agent 10.0.0.27
2025-10-29 18:56:55,580 - INFO - Validated that VN service-left-vn-35602306 is not in any agent
2025-10-29 18:56:55,613 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-35602306 info
2025-10-29 18:56:55,614 - INFO - Deleting VN ctest-mgmt_vn-44347626
2025-10-29 18:56:55,759 - DEBUG - Response for deleting network ()
2025-10-29 18:56:55,759 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d85761bb-9692-450b-af0a-7db3b5423b92
2025-10-29 18:56:55,766 - DEBUG - Response Code: 404
2025-10-29 18:56:55,766 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:55,771 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:55,788 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:55,875 - INFO - Validated that VN ctest-mgmt_vn-44347626 is not found in API Server
2025-10-29 18:56:55,886 - DEBUG - VN ctest-mgmt_vn-44347626 is not present in Agent 10.0.0.73
2025-10-29 18:56:55,896 - DEBUG - VN ctest-mgmt_vn-44347626 is not present in Agent 10.0.0.27
2025-10-29 18:56:55,896 - INFO - Validated that VN ctest-mgmt_vn-44347626 is not in any agent
2025-10-29 18:56:55,924 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-44347626 info
2025-10-29 18:56:57,340 - DEBUG - No XMPP flaps were noticed during the test
2025-10-29 18:56:57,341 - INFO -
2025-10-29 18:56:57,341 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-10-29 18:56:57,341 - INFO - --------------------------------------------------------------------------------
2025-10-29 18:56:57,350 - INFO - ================================================================================
2025-10-29 18:56:57,350 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-29 18:56:58,766 - DEBUG - Nothing to compare xmpp stats {'10.0.0.73': {'10.20.0.201': '0', '10.20.0.200': '0'}, '10.0.0.27': {'10.20.0.201': '0', '10.20.0.200': '0'}} with
2025-10-29 18:56:58,766 - INFO - Initial checks done. Running the testcase now
2025-10-29 18:56:58,767 - INFO -
2025-10-29 18:56:59,103 - DEBUG - Response for create_network : {'network': {'id': '6873303a-8af1-4580-82f6-292809188573', 'name': 'ctest-mgmt_vn-79948137', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'project_id': '09399d131edf4ad7a45229ba97666adf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72794792', 'ctest-mgmt_vn-79948137'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:56:59,356 - DEBUG - Response for create_subnet : {'subnet': {'id': '2e93bad0-0a16-49a1-89d8-4bc33b2a8253', 'name': '', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'network_id': '6873303a-8af1-4580-82f6-292809188573', 'ip_version': 4, 'cidr': '7.18.209.64/26', 'allocation_pools': [{'start': '7.18.209.66', 'end': '7.18.209.126'}], 'gateway_ip': '7.18.209.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '7.18.209.66', 'tags': [], 'project_id': '09399d131edf4ad7a45229ba97666adf'}}
2025-10-29 18:56:59,375 - INFO - Created VN ctest-mgmt_vn-79948137
2025-10-29 18:56:59,387 - DEBUG - VN ctest-mgmt_vn-79948137 UUID is 6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,546 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:59,553 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:59,570 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:59,678 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,694 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,705 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9bf712bf-c1e0-469e-ba3e-4b7f94df5955
2025-10-29 18:56:59,715 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9bf712bf-c1e0-469e-ba3e-4b7f94df5955
2025-10-29 18:56:59,723 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d7d5bc8d-ac03-45d4-be09-450c4f3d0650
2025-10-29 18:56:59,730 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:59,730 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,740 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9bf712bf-c1e0-469e-ba3e-4b7f94df5955
2025-10-29 18:56:59,771 - INFO - Verified VN network id 11 for VN 6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,771 - INFO - Verifications in API Server for VN ctest-mgmt_vn-79948137 passed
2025-10-29 18:56:59,771 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,785 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9bf712bf-c1e0-469e-ba3e-4b7f94df5955
2025-10-29 18:56:59,795 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9bf712bf-c1e0-469e-ba3e-4b7f94df5955
2025-10-29 18:56:59,807 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d7d5bc8d-ac03-45d4-be09-450c4f3d0650
2025-10-29 18:56:59,825 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7526412430263534976', 'uuid-lslong': '9436775321129485683'}, 'enable': 'true', 'created': '2025-10-29T18:56:59', 'last-modified': '2025-10-29T18:56:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.528896'}
2025-10-29 18:56:59,832 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:59,840 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7526412430263534976', 'uuid-lslong': '9436775321129485683'}, 'enable': 'true', 'created': '2025-10-29T18:56:59', 'last-modified': '2025-10-29T18:56:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.540650'}
2025-10-29 18:56:59,848 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:59,855 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7526412430263534976', 'uuid-lslong': '9436775321129485683'}, 'enable': 'true', 'created': '2025-10-29T18:56:59', 'last-modified': '2025-10-29T18:56:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.559356'}
2025-10-29 18:56:59,862 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 18:56:59,862 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-79948137 passed
2025-10-29 18:56:59,863 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-79948137 in API_Server ======
2025-10-29 18:56:59,863 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:56:59,869 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:56:59,881 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:56:59,977 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/6873303a-8af1-4580-82f6-292809188573
2025-10-29 18:56:59,987 - DEBUG - =>VN ctest-mgmt_vn-79948137 has no policy to be verified
2025-10-29 18:56:59,987 - DEBUG - Verifying the vn in opserver
2025-10-29 18:56:59,987 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137 virtual network link through opserver 10.0.0.249
2025-10-29 18:56:59,987 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:56:59,998 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137?flat'}
2025-10-29 18:56:59,998 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137 virtual network link through opserver 10.0.0.19
2025-10-29 18:56:59,998 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:57:00,013 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137?flat'}
2025-10-29 18:57:00,013 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137 virtual network link through opserver 10.0.0.41
2025-10-29 18:57:00,013 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:57:00,028 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137?flat'}
2025-10-29 18:57:00,028 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72794792:ctest-mgmt_vn-79948137 is found in opserver
2025-10-29 18:57:00,063 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:57:00,085 - DEBUG - VRF ids for VN ctest-mgmt_vn-79948137: {}
2025-10-29 18:57:00,374 - DEBUG - Response for create_network : {'network': {'id': '87edbf63-cee5-4ca2-a3a9-3ad9042189b4', 'name': 'ctest-left_vn-17533736', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'project_id': '09399d131edf4ad7a45229ba97666adf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72794792', 'ctest-left_vn-17533736'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:57:00,667 - DEBUG - Response for create_subnet : {'subnet': {'id': '0f87bc1f-9139-4b55-a480-b6e1b18db3e7', 'name': '', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'network_id': '87edbf63-cee5-4ca2-a3a9-3ad9042189b4', 'ip_version': 4, 'cidr': '7.164.209.128/26', 'allocation_pools': [{'start': '7.164.209.130', 'end': '7.164.209.190'}], 'gateway_ip': '7.164.209.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '7.164.209.130', 'tags': [], 'project_id': '09399d131edf4ad7a45229ba97666adf'}}
2025-10-29 18:57:00,696 - INFO - Created VN ctest-left_vn-17533736
2025-10-29 18:57:00,714 - DEBUG - VN ctest-left_vn-17533736 UUID is 87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:00,797 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:00,807 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:00,827 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:00,957 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:00,975 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:00,990 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3b472916-74a4-4594-ba2b-358776399688
2025-10-29 18:57:01,003 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3b472916-74a4-4594-ba2b-358776399688
2025-10-29 18:57:01,014 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d3e3a2e1-5fd7-45ba-999d-489ec6f26d07
2025-10-29 18:57:01,023 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:57:01,023 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:01,037 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3b472916-74a4-4594-ba2b-358776399688
2025-10-29 18:57:01,060 - INFO - Verified VN network id 12 for VN 87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:01,060 - INFO - Verifications in API Server for VN ctest-left_vn-17533736 passed
2025-10-29 18:57:01,060 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:01,081 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3b472916-74a4-4594-ba2b-358776399688
2025-10-29 18:57:01,091 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3b472916-74a4-4594-ba2b-358776399688
2025-10-29 18:57:01,100 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d3e3a2e1-5fd7-45ba-999d-489ec6f26d07
2025-10-29 18:57:01,118 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9794695199994498210', 'uuid-lslong': '11793021802997975476'}, 'enable': 'true', 'created': '2025-10-29T18:57:00', 'last-modified': '2025-10-29T18:57:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.510457'}
2025-10-29 18:57:01,124 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:57:01,131 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9794695199994498210', 'uuid-lslong': '11793021802997975476'}, 'enable': 'true', 'created': '2025-10-29T18:57:00', 'last-modified': '2025-10-29T18:57:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520422'}
2025-10-29 18:57:01,137 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:57:01,143 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9794695199994498210', 'uuid-lslong': '11793021802997975476'}, 'enable': 'true', 'created': '2025-10-29T18:57:00', 'last-modified': '2025-10-29T18:57:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.535082'}
2025-10-29 18:57:01,151 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 18:57:01,151 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-17533736 passed
2025-10-29 18:57:01,151 - DEBUG - ====Verifying policy data for ctest-left_vn-17533736 in API_Server ======
2025-10-29 18:57:01,151 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:01,161 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:01,180 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:01,300 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/87edbf63-cee5-4ca2-a3a9-3ad9042189b4
2025-10-29 18:57:01,314 - DEBUG - =>VN ctest-left_vn-17533736 has no policy to be verified
2025-10-29 18:57:01,315 - DEBUG - Verifying the vn in opserver
2025-10-29 18:57:01,315 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736 virtual network link through opserver 10.0.0.249
2025-10-29 18:57:01,315 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:57:01,335 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736?flat'}
2025-10-29 18:57:01,335 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736 virtual network link through opserver 10.0.0.19
2025-10-29 18:57:01,335 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:57:01,350 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736?flat'}
2025-10-29 18:57:01,350 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736 virtual network link through opserver 10.0.0.41
2025-10-29 18:57:01,350 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:57:01,363 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736?flat'}
2025-10-29 18:57:01,363 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72794792:ctest-left_vn-17533736 is found in opserver
2025-10-29 18:57:01,387 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:57:01,400 - DEBUG - VRF ids for VN ctest-left_vn-17533736: {}
2025-10-29 18:57:01,704 - DEBUG - Response for create_network : {'network': {'id': '8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43', 'name': 'ctest-right_vn-61941252', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'project_id': '09399d131edf4ad7a45229ba97666adf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72794792', 'ctest-right_vn-61941252'], 'port_security_enabled': True, 'description': ''}}
2025-10-29 18:57:01,938 - DEBUG - Response for create_subnet : {'subnet': {'id': '5bf951d1-b12c-4dcf-aa9d-8e3fb5c66db2', 'name': '', 'tenant_id': '09399d131edf4ad7a45229ba97666adf', 'network_id': '8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43', 'ip_version': 4, 'cidr': '37.155.144.128/26', 'allocation_pools': [{'start': '37.155.144.130', 'end': '37.155.144.190'}], 'gateway_ip': '37.155.144.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '37.155.144.130', 'tags': [], 'project_id': '09399d131edf4ad7a45229ba97666adf'}}
2025-10-29 18:57:01,960 - INFO - Created VN ctest-right_vn-61941252
2025-10-29 18:57:01,972 - DEBUG - VN ctest-right_vn-61941252 UUID is 8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,048 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:02,055 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:02,070 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:02,166 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,177 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,186 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9d4e3c0f-7143-4e3d-a167-cc27b92da479
2025-10-29 18:57:02,194 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9d4e3c0f-7143-4e3d-a167-cc27b92da479
2025-10-29 18:57:02,201 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d4885323-5f8f-4787-b1eb-844b970afcfd
2025-10-29 18:57:02,207 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:57:02,207 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,216 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9d4e3c0f-7143-4e3d-a167-cc27b92da479
2025-10-29 18:57:02,232 - INFO - Verified VN network id 13 for VN 8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,232 - INFO - Verifications in API Server for VN ctest-right_vn-61941252 passed
2025-10-29 18:57:02,232 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,242 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9d4e3c0f-7143-4e3d-a167-cc27b92da479
2025-10-29 18:57:02,250 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9d4e3c0f-7143-4e3d-a167-cc27b92da479
2025-10-29 18:57:02,257 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d4885323-5f8f-4787-b1eb-844b970afcfd
2025-10-29 18:57:02,272 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10189662376370522245', 'uuid-lslong': '13398976939197938243'}, 'enable': 'true', 'created': '2025-10-29T18:57:01', 'last-modified': '2025-10-29T18:57:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398068'}
2025-10-29 18:57:02,278 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:57:02,285 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10189662376370522245', 'uuid-lslong': '13398976939197938243'}, 'enable': 'true', 'created': '2025-10-29T18:57:01', 'last-modified': '2025-10-29T18:57:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411152'}
2025-10-29 18:57:02,293 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:57:02,299 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72794792', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10189662376370522245', 'uuid-lslong': '13398976939197938243'}, 'enable': 'true', 'created': '2025-10-29T18:57:01', 'last-modified': '2025-10-29T18:57:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420488'}
2025-10-29 18:57:02,306 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 18:57:02,306 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-61941252 passed
2025-10-29 18:57:02,306 - DEBUG - ====Verifying policy data for ctest-right_vn-61941252 in API_Server ======
2025-10-29 18:57:02,307 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:02,313 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:02,325 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:02,444 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43
2025-10-29 18:57:02,457 - DEBUG - =>VN ctest-right_vn-61941252 has no policy to be verified
2025-10-29 18:57:02,457 - DEBUG - Verifying the vn in opserver
2025-10-29 18:57:02,457 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252 virtual network link through opserver 10.0.0.249
2025-10-29 18:57:02,457 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 18:57:02,472 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252?flat'}
2025-10-29 18:57:02,472 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252 virtual network link through opserver 10.0.0.19
2025-10-29 18:57:02,472 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 18:57:02,489 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252?flat'}
2025-10-29 18:57:02,490 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252 virtual network link through opserver 10.0.0.41
2025-10-29 18:57:02,490 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 18:57:02,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252?flat'}
2025-10-29 18:57:02,505 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72794792:ctest-right_vn-61941252 is found in opserver
2025-10-29 18:57:02,533 - DEBUG - Do not have enough data to verify VN in agent
2025-10-29 18:57:02,547 - DEBUG - VRF ids for VN ctest-right_vn-61941252: {}
2025-10-29 18:57:03,753 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-1)
2025-10-29 18:57:05,170 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4526-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4526-2)
2025-10-29 18:57:05,171 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-51588341']
2025-10-29 18:57:05,491 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-51588341'] created succesfully
2025-10-29 18:57:05,891 - DEBUG - Image by name tiny_nat_fw not found
2025-10-29 18:57:05,891 - DEBUG - Installing image tiny_nat_fw
2025-10-29 18:57:05,891 - 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-10-29 18:57:05,891 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:57:05,891 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:57:05,891 - DEBUG - Unzip image
2025-10-29 18:57:05,891 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-29 18:57:05,914 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-29 18:57:05,914 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-51588341']
2025-10-29 18:57:06,029 - INFO - Service template: ['default-domain', 'ctest-service_template_1-51588341'] deleted successfully.
2025-10-29 18:57:06,030 - INFO - Skip interface_detach for VM ctest-right_vm-53998115 in state BUILD
2025-10-29 18:57:06,031 - INFO - Deleting VM ctest-right_vm-53998115
2025-10-29 18:57:06,031 - ERROR - VM ctest-right_vm-53998115 has failed to come up
2025-10-29 18:57:06,032 - ERROR - Nova failed to get host of the VM
2025-10-29 18:57:06,032 - ERROR - VM ctest-right_vm-53998115 has failed to come up
2025-10-29 18:57:06,032 - ERROR - Nova failed to get host of the VM
2025-10-29 18:57:06,145 - INFO - Skip interface_detach for VM ctest-left_vm-32846867 in state BUILD
2025-10-29 18:57:06,146 - INFO - Deleting VM ctest-left_vm-32846867
2025-10-29 18:57:06,146 - ERROR - VM ctest-left_vm-32846867 has failed to come up
2025-10-29 18:57:06,146 - ERROR - Nova failed to get host of the VM
2025-10-29 18:57:06,146 - ERROR - VM ctest-left_vm-32846867 has failed to come up
2025-10-29 18:57:06,146 - ERROR - Nova failed to get host of the VM
2025-10-29 18:57:06,271 - INFO - Deleting VN ctest-right_vn-61941252
2025-10-29 18:57:06,344 - DEBUG - VN 8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43 still in use: Unable to complete operation on network 8d68f3f1-b4ba-4885-b9f2-ba890e8b3a43. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cbe4b90b-768f-447f-8c37-24ff5d6f9b34']
2025-10-29 18:57:06,344 - WARNING - Deleting VN ctest-right_vn-61941252 failed..Will retry
2025-10-29 18:57:08,534 - DEBUG - Response for deleting network ()
2025-10-29 18:57:08,534 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9d4e3c0f-7143-4e3d-a167-cc27b92da479
2025-10-29 18:57:08,547 - DEBUG - Response Code: 404
2025-10-29 18:57:08,547 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:08,561 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:08,581 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:08,684 - INFO - Validated that VN ctest-right_vn-61941252 is not found in API Server
2025-10-29 18:57:08,708 - DEBUG - VN ctest-right_vn-61941252 is not present in Agent 10.0.0.73
2025-10-29 18:57:08,719 - DEBUG - VN ctest-right_vn-61941252 is not present in Agent 10.0.0.27
2025-10-29 18:57:08,719 - INFO - Validated that VN ctest-right_vn-61941252 is not in any agent
2025-10-29 18:57:08,761 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-61941252 info
2025-10-29 18:57:08,761 - INFO - Deleting VN ctest-left_vn-17533736
2025-10-29 18:57:08,940 - DEBUG - Response for deleting network ()
2025-10-29 18:57:08,940 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3b472916-74a4-4594-ba2b-358776399688
2025-10-29 18:57:08,949 - DEBUG - Response Code: 404
2025-10-29 18:57:08,949 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:08,958 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:08,987 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:09,087 - INFO - Validated that VN ctest-left_vn-17533736 is not found in API Server
2025-10-29 18:57:09,106 - DEBUG - VN ctest-left_vn-17533736 is not present in Agent 10.0.0.73
2025-10-29 18:57:09,120 - DEBUG - VN ctest-left_vn-17533736 is not present in Agent 10.0.0.27
2025-10-29 18:57:09,120 - INFO - Validated that VN ctest-left_vn-17533736 is not in any agent
2025-10-29 18:57:09,160 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-17533736 info
2025-10-29 18:57:09,160 - INFO - Deleting VN ctest-mgmt_vn-79948137
2025-10-29 18:57:09,310 - DEBUG - Response for deleting network ()
2025-10-29 18:57:09,311 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9bf712bf-c1e0-469e-ba3e-4b7f94df5955
2025-10-29 18:57:09,316 - DEBUG - Response Code: 404
2025-10-29 18:57:09,317 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 18:57:09,329 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 18:57:09,343 - DEBUG - Requesting: http://10.0.0.249:8082/project/09399d13-1edf-4ad7-a452-29ba97666adf
2025-10-29 18:57:09,430 - INFO - Validated that VN ctest-mgmt_vn-79948137 is not found in API Server
2025-10-29 18:57:09,452 - DEBUG - VN ctest-mgmt_vn-79948137 is not present in Agent 10.0.0.73
2025-10-29 18:57:09,462 - DEBUG - VN ctest-mgmt_vn-79948137 is not present in Agent 10.0.0.27
2025-10-29 18:57:09,463 - INFO - Validated that VN ctest-mgmt_vn-79948137 is not in any agent
2025-10-29 18:57:09,502 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-79948137 info
2025-10-29 18:57:10,960 - DEBUG - No XMPP flaps were noticed during the test
2025-10-29 18:57:10,960 - INFO -
2025-10-29 18:57:10,960 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-10-29 18:57:10,960 - INFO - --------------------------------------------------------------------------------
2025-10-29 18:57:11,749 - INFO - Deleted project: ctest-TestSvcRegr-72794792, ID : 09399d13-1edf-4ad7-a452-29ba97666adf