]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-1)
2025-10-31 15:47:25,486 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-89210570']
2025-10-31 15:47:25,798 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-89210570'] created succesfully
2025-10-31 15:47:26,183 - DEBUG - Image by name tiny_nat_fw not found
2025-10-31 15:47:26,183 - DEBUG - Installing image tiny_nat_fw
2025-10-31 15:47:26,183 - 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-31 15:47:26,183 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-31 15:47:26,183 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-31 15:47:26,202 - 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-31 15:47:26,261 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:47:26,261 - DEBUG - Unzip image
2025-10-31 15:47:26,261 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:47:26,286 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-31 15:47:26,287 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-89210570']
2025-10-31 15:47:26,387 - INFO - Service template: ['default-domain', 'ctest-service_template_1-89210570'] deleted successfully.
2025-10-31 15:47:26,387 - INFO - Skip interface_detach for VM ctest-right_vm-05579100 in state BUILD
2025-10-31 15:47:26,387 - INFO - Deleting VM ctest-right_vm-05579100
2025-10-31 15:47:26,444 - ERROR - VM ctest-right_vm-05579100 has failed to come up
2025-10-31 15:47:26,444 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:26,444 - ERROR - VM ctest-right_vm-05579100 has failed to come up
2025-10-31 15:47:26,445 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:26,538 - INFO - Skip interface_detach for VM ctest-left_vm-54318984 in state BUILD
2025-10-31 15:47:26,539 - INFO - Deleting VM ctest-left_vm-54318984
2025-10-31 15:47:26,539 - ERROR - VM ctest-left_vm-54318984 has failed to come up
2025-10-31 15:47:26,539 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:26,539 - ERROR - VM ctest-left_vm-54318984 has failed to come up
2025-10-31 15:47:26,539 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:26,615 - INFO - Deleting VN ctest-right_vn-81628293
2025-10-31 15:47:26,659 - DEBUG - VN 7a6bed76-7940-4019-9cd7-361995a1a2c6 still in use: Unable to complete operation on network 7a6bed76-7940-4019-9cd7-361995a1a2c6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3707204a-bca6-4785-a001-6efeb7a9a2ec']
2025-10-31 15:47:26,659 - WARNING - Deleting VN ctest-right_vn-81628293 failed..Will retry
2025-10-31 15:47:28,836 - DEBUG - Response for deleting network ()
2025-10-31 15:47:28,837 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c737b41c-b1dd-4f6d-af2a-6770af3f9f87
2025-10-31 15:47:28,845 - DEBUG - Response Code: 404
2025-10-31 15:47:28,845 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:28,852 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:28,872 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:28,970 - INFO - Validated that VN ctest-right_vn-81628293 is not found in API Server
2025-10-31 15:47:28,991 - DEBUG - VN ctest-right_vn-81628293 is not present in Agent 10.0.0.19
2025-10-31 15:47:29,002 - DEBUG - VN ctest-right_vn-81628293 is not present in Agent 10.0.0.22
2025-10-31 15:47:29,002 - INFO - Validated that VN ctest-right_vn-81628293 is not in any agent
2025-10-31 15:47:29,039 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-81628293 info
2025-10-31 15:47:29,039 - INFO - Deleting VN ctest-left_vn-90042664
2025-10-31 15:47:29,229 - DEBUG - Response for deleting network ()
2025-10-31 15:47:29,229 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1f51b2c4-a68a-4dbe-a83f-8389337da327
2025-10-31 15:47:29,238 - DEBUG - Response Code: 404
2025-10-31 15:47:29,238 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:29,244 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:29,270 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:29,385 - INFO - Validated that VN ctest-left_vn-90042664 is not found in API Server
2025-10-31 15:47:29,398 - DEBUG - VN ctest-left_vn-90042664 is not present in Agent 10.0.0.19
2025-10-31 15:47:29,410 - DEBUG - VN ctest-left_vn-90042664 is not present in Agent 10.0.0.22
2025-10-31 15:47:29,410 - INFO - Validated that VN ctest-left_vn-90042664 is not in any agent
2025-10-31 15:47:29,445 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-90042664 info
2025-10-31 15:47:29,445 - INFO - Deleting VN ctest-mgmt_vn-63711586
2025-10-31 15:47:29,622 - DEBUG - Response for deleting network ()
2025-10-31 15:47:29,622 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bdb45bff-4aaa-4690-b4bf-753ce06f8637
2025-10-31 15:47:29,628 - DEBUG - Response Code: 404
2025-10-31 15:47:29,628 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:29,634 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:29,652 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:29,751 - INFO - Validated that VN ctest-mgmt_vn-63711586 is not found in API Server
2025-10-31 15:47:29,781 - DEBUG - VN ctest-mgmt_vn-63711586 is not present in Agent 10.0.0.19
2025-10-31 15:47:29,796 - DEBUG - VN ctest-mgmt_vn-63711586 is not present in Agent 10.0.0.22
2025-10-31 15:47:29,796 - INFO - Validated that VN ctest-mgmt_vn-63711586 is not in any agent
2025-10-31 15:47:29,838 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-63711586 info
2025-10-31 15:47:29,838 - INFO - Deleting VM ctest-vm-in-third-vn-45705785
2025-10-31 15:47:29,912 - INFO - Deleting VN ctest-third-vn-88862097
2025-10-31 15:47:29,959 - DEBUG - VN 73d4f77c-e84b-4000-9afd-b22f25b247d6 still in use: Unable to complete operation on network 73d4f77c-e84b-4000-9afd-b22f25b247d6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1a2f5330-ee51-47fd-81dc-3df77f1d8dbc']
2025-10-31 15:47:29,959 - WARNING - Deleting VN ctest-third-vn-88862097 failed..Will retry
2025-10-31 15:47:32,159 - DEBUG - Response for deleting network ()
2025-10-31 15:47:33,459 - DEBUG - No XMPP flaps were noticed during the test
2025-10-31 15:47:33,459 - INFO -
2025-10-31 15:47:33,459 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-10-31 15:47:33,459 - INFO - --------------------------------------------------------------------------------
2025-10-31 15:47:33,463 - INFO - ================================================================================
2025-10-31 15:47:33,463 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-31 15:47:34,766 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.22': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2025-10-31 15:47:34,766 - INFO - Initial checks done. Running the testcase now
2025-10-31 15:47:34,766 - INFO -
2025-10-31 15:47:35,038 - DEBUG - Response for create_network : {'network': {'id': 'c612fa04-4611-4369-b92a-366f5641b0d7', 'name': 'ctest-mgmt_vn-04567400', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-73588183', 'ctest-mgmt_vn-04567400'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:35,315 - DEBUG - Response for create_subnet : {'subnet': {'id': 'db3c604c-b27c-4332-b1ad-4203d8e2e0b7', 'name': '', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'network_id': 'c612fa04-4611-4369-b92a-366f5641b0d7', 'ip_version': 4, 'cidr': '135.82.85.64/26', 'allocation_pools': [{'start': '135.82.85.66', 'end': '135.82.85.126'}], 'gateway_ip': '135.82.85.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.82.85.66', 'tags': [], 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec'}}
2025-10-31 15:47:35,338 - INFO - Created VN ctest-mgmt_vn-04567400
2025-10-31 15:47:35,361 - DEBUG - VN ctest-mgmt_vn-04567400 UUID is c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,559 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:35,566 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:35,576 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:35,674 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,687 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,697 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/8cb45232-0026-448a-ae78-ed62b7b58e69
2025-10-31 15:47:35,706 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/8cb45232-0026-448a-ae78-ed62b7b58e69
2025-10-31 15:47:35,721 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/b4f2d698-3311-4fdc-84bb-e8a5408972c0
2025-10-31 15:47:35,730 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:35,730 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,742 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/8cb45232-0026-448a-ae78-ed62b7b58e69
2025-10-31 15:47:35,761 - INFO - Verified VN network id 15 for VN c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,761 - INFO - Verifications in API Server for VN ctest-mgmt_vn-04567400 passed
2025-10-31 15:47:35,761 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,775 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/8cb45232-0026-448a-ae78-ed62b7b58e69
2025-10-31 15:47:35,784 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/8cb45232-0026-448a-ae78-ed62b7b58e69
2025-10-31 15:47:35,792 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/b4f2d698-3311-4fdc-84bb-e8a5408972c0
2025-10-31 15:47:35,809 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14272745065352872809', 'uuid-lslong': '13342536697854931159'}, 'enable': 'true', 'created': '2025-10-31T15:47:34', 'last-modified': '2025-10-31T15:47:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563661'}
2025-10-31 15:47:35,817 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:35,824 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14272745065352872809', 'uuid-lslong': '13342536697854931159'}, 'enable': 'true', 'created': '2025-10-31T15:47:34', 'last-modified': '2025-10-31T15:47:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.575284'}
2025-10-31 15:47:35,832 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:35,838 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14272745065352872809', 'uuid-lslong': '13342536697854931159'}, 'enable': 'true', 'created': '2025-10-31T15:47:34', 'last-modified': '2025-10-31T15:47:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.594694'}
2025-10-31 15:47:35,844 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:35,844 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-04567400 passed
2025-10-31 15:47:35,845 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-04567400 in API_Server ======
2025-10-31 15:47:35,845 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:35,851 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:35,862 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:35,956 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c612fa04-4611-4369-b92a-366f5641b0d7
2025-10-31 15:47:35,969 - DEBUG - =>VN ctest-mgmt_vn-04567400 has no policy to be verified
2025-10-31 15:47:35,969 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:35,969 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:35,969 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:35,982 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400?flat'}
2025-10-31 15:47:35,982 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:35,982 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:35,995 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400?flat'}
2025-10-31 15:47:35,996 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:35,996 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:36,008 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400?flat'}
2025-10-31 15:47:36,008 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-73588183:ctest-mgmt_vn-04567400 is found in opserver
2025-10-31 15:47:36,032 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:36,041 - DEBUG - VRF ids for VN ctest-mgmt_vn-04567400: {}
2025-10-31 15:47:36,321 - DEBUG - Response for create_network : {'network': {'id': 'd2d0dae0-3fa5-4839-9dd5-c277748fb96e', 'name': 'ctest-left_vn-18375231', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-73588183', 'ctest-left_vn-18375231'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:36,553 - DEBUG - Response for create_subnet : {'subnet': {'id': '3184fc37-4ef9-4aa2-b137-33a9548c6608', 'name': '', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'network_id': 'd2d0dae0-3fa5-4839-9dd5-c277748fb96e', 'ip_version': 4, 'cidr': '109.253.67.64/26', 'allocation_pools': [{'start': '109.253.67.66', 'end': '109.253.67.126'}], 'gateway_ip': '109.253.67.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '109.253.67.66', 'tags': [], 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec'}}
2025-10-31 15:47:36,582 - INFO - Created VN ctest-left_vn-18375231
2025-10-31 15:47:36,596 - DEBUG - VN ctest-left_vn-18375231 UUID is d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:36,668 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:36,674 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:36,686 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:36,780 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:36,794 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:36,802 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/28a29082-0729-425e-bc01-6567667bad09
2025-10-31 15:47:36,810 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/28a29082-0729-425e-bc01-6567667bad09
2025-10-31 15:47:36,816 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/3dabe92f-061b-4608-9393-a81f0951e32c
2025-10-31 15:47:36,823 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:36,823 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:36,833 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/28a29082-0729-425e-bc01-6567667bad09
2025-10-31 15:47:36,850 - INFO - Verified VN network id 16 for VN d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:36,851 - INFO - Verifications in API Server for VN ctest-left_vn-18375231 passed
2025-10-31 15:47:36,851 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:36,861 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/28a29082-0729-425e-bc01-6567667bad09
2025-10-31 15:47:36,871 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/28a29082-0729-425e-bc01-6567667bad09
2025-10-31 15:47:36,877 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/3dabe92f-061b-4608-9393-a81f0951e32c
2025-10-31 15:47:36,888 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15190882199796009017', 'uuid-lslong': '11373210252306528622'}, 'enable': 'true', 'created': '2025-10-31T15:47:36', 'last-modified': '2025-10-31T15:47:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409722'}
2025-10-31 15:47:36,900 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:36,908 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15190882199796009017', 'uuid-lslong': '11373210252306528622'}, 'enable': 'true', 'created': '2025-10-31T15:47:36', 'last-modified': '2025-10-31T15:47:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424848'}
2025-10-31 15:47:36,914 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:36,920 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15190882199796009017', 'uuid-lslong': '11373210252306528622'}, 'enable': 'true', 'created': '2025-10-31T15:47:36', 'last-modified': '2025-10-31T15:47:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432319'}
2025-10-31 15:47:36,927 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:36,927 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-18375231 passed
2025-10-31 15:47:36,927 - DEBUG - ====Verifying policy data for ctest-left_vn-18375231 in API_Server ======
2025-10-31 15:47:36,927 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:36,933 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:36,945 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:37,052 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d2d0dae0-3fa5-4839-9dd5-c277748fb96e
2025-10-31 15:47:37,061 - DEBUG - =>VN ctest-left_vn-18375231 has no policy to be verified
2025-10-31 15:47:37,061 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:37,061 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:37,061 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:37,073 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231?flat'}
2025-10-31 15:47:37,073 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:37,073 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:37,087 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231?flat'}
2025-10-31 15:47:37,087 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:37,087 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:37,101 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231?flat'}
2025-10-31 15:47:37,101 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-73588183:ctest-left_vn-18375231 is found in opserver
2025-10-31 15:47:37,123 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:37,137 - DEBUG - VRF ids for VN ctest-left_vn-18375231: {}
2025-10-31 15:47:37,393 - DEBUG - Response for create_network : {'network': {'id': '04486e5a-1061-4238-bbb2-e903ffb0701c', 'name': 'ctest-right_vn-45850021', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-73588183', 'ctest-right_vn-45850021'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:37,643 - DEBUG - Response for create_subnet : {'subnet': {'id': '64969740-03bf-4c8c-9fcb-e6869cfa8cf4', 'name': '', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'network_id': '04486e5a-1061-4238-bbb2-e903ffb0701c', 'ip_version': 4, 'cidr': '209.59.137.64/26', 'allocation_pools': [{'start': '209.59.137.66', 'end': '209.59.137.126'}], 'gateway_ip': '209.59.137.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '209.59.137.66', 'tags': [], 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec'}}
2025-10-31 15:47:37,672 - INFO - Created VN ctest-right_vn-45850021
2025-10-31 15:47:37,687 - DEBUG - VN ctest-right_vn-45850021 UUID is 04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:37,762 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:37,768 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:37,778 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:37,873 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:37,887 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:37,895 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e246bbb5-944a-4230-8618-65ba6690dd3f
2025-10-31 15:47:37,903 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e246bbb5-944a-4230-8618-65ba6690dd3f
2025-10-31 15:47:37,909 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/3e6a52a0-6502-48de-a771-11237c63d937
2025-10-31 15:47:37,915 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:37,915 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:37,922 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e246bbb5-944a-4230-8618-65ba6690dd3f
2025-10-31 15:47:37,938 - INFO - Verified VN network id 17 for VN 04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:37,938 - INFO - Verifications in API Server for VN ctest-right_vn-45850021 passed
2025-10-31 15:47:37,938 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:37,949 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e246bbb5-944a-4230-8618-65ba6690dd3f
2025-10-31 15:47:37,955 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e246bbb5-944a-4230-8618-65ba6690dd3f
2025-10-31 15:47:37,963 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/3e6a52a0-6502-48de-a771-11237c63d937
2025-10-31 15:47:37,976 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '308617907575800376', 'uuid-lslong': '13525128834330947612'}, 'enable': 'true', 'created': '2025-10-31T15:47:37', 'last-modified': '2025-10-31T15:47:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411673'}
2025-10-31 15:47:37,982 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:37,988 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '308617907575800376', 'uuid-lslong': '13525128834330947612'}, 'enable': 'true', 'created': '2025-10-31T15:47:37', 'last-modified': '2025-10-31T15:47:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415800'}
2025-10-31 15:47:37,996 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:38,002 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '308617907575800376', 'uuid-lslong': '13525128834330947612'}, 'enable': 'true', 'created': '2025-10-31T15:47:37', 'last-modified': '2025-10-31T15:47:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430424'}
2025-10-31 15:47:38,008 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:38,009 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-45850021 passed
2025-10-31 15:47:38,009 - DEBUG - ====Verifying policy data for ctest-right_vn-45850021 in API_Server ======
2025-10-31 15:47:38,009 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:38,017 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:38,029 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:38,112 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/04486e5a-1061-4238-bbb2-e903ffb0701c
2025-10-31 15:47:38,122 - DEBUG - =>VN ctest-right_vn-45850021 has no policy to be verified
2025-10-31 15:47:38,122 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:38,122 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:38,122 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:38,134 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021?flat'}
2025-10-31 15:47:38,134 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:38,134 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:38,148 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021?flat'}
2025-10-31 15:47:38,148 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:38,148 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:38,168 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021?flat'}
2025-10-31 15:47:38,168 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-73588183:ctest-right_vn-45850021 is found in opserver
2025-10-31 15:47:38,193 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:38,207 - DEBUG - VRF ids for VN ctest-right_vn-45850021: {}
2025-10-31 15:47:38,525 - DEBUG - Response for create_network : {'network': {'id': '371849b6-a652-492c-ae5e-785d9ba7fecc', 'name': 'ctest-trans_left_vn-38442469', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-73588183', 'ctest-trans_left_vn-38442469'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:38,789 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b723fd05-ad2f-425d-9679-d9c88e9915de', 'name': '', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'network_id': '371849b6-a652-492c-ae5e-785d9ba7fecc', 'ip_version': 4, 'cidr': '2.179.25.0/26', 'allocation_pools': [{'start': '2.179.25.2', 'end': '2.179.25.62'}], 'gateway_ip': '2.179.25.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2.179.25.2', 'tags': [], 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec'}}
2025-10-31 15:47:38,817 - INFO - Created VN ctest-trans_left_vn-38442469
2025-10-31 15:47:38,829 - DEBUG - VN ctest-trans_left_vn-38442469 UUID is 371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:38,916 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:38,923 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:38,933 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:39,035 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:39,048 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:39,060 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f09eae1e-7c3e-4613-802e-ed4e80517c65
2025-10-31 15:47:39,069 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f09eae1e-7c3e-4613-802e-ed4e80517c65
2025-10-31 15:47:39,078 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c24a7dd0-090b-42da-9139-5155a0aff62e
2025-10-31 15:47:39,088 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-31 15:47:39,088 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:39,098 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f09eae1e-7c3e-4613-802e-ed4e80517c65
2025-10-31 15:47:39,119 - INFO - Verified VN network id 18 for VN 371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:39,119 - INFO - Verifications in API Server for VN ctest-trans_left_vn-38442469 passed
2025-10-31 15:47:39,120 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:39,129 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f09eae1e-7c3e-4613-802e-ed4e80517c65
2025-10-31 15:47:39,136 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f09eae1e-7c3e-4613-802e-ed4e80517c65
2025-10-31 15:47:39,143 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c24a7dd0-090b-42da-9139-5155a0aff62e
2025-10-31 15:47:39,164 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3970004120350378284', 'uuid-lslong': '12564612353849032396'}, 'enable': 'true', 'created': '2025-10-31T15:47:38', 'last-modified': '2025-10-31T15:47:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.452094'}
2025-10-31 15:47:39,169 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-31 15:47:39,176 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3970004120350378284', 'uuid-lslong': '12564612353849032396'}, 'enable': 'true', 'created': '2025-10-31T15:47:38', 'last-modified': '2025-10-31T15:47:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.474231'}
2025-10-31 15:47:39,184 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-31 15:47:39,192 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3970004120350378284', 'uuid-lslong': '12564612353849032396'}, 'enable': 'true', 'created': '2025-10-31T15:47:38', 'last-modified': '2025-10-31T15:47:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.487472'}
2025-10-31 15:47:39,198 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-31 15:47:39,198 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-38442469 passed
2025-10-31 15:47:39,199 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-38442469 in API_Server ======
2025-10-31 15:47:39,199 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:39,205 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:39,218 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:39,317 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/371849b6-a652-492c-ae5e-785d9ba7fecc
2025-10-31 15:47:39,326 - DEBUG - =>VN ctest-trans_left_vn-38442469 has no policy to be verified
2025-10-31 15:47:39,326 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:39,326 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:39,326 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:39,340 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469?flat'}
2025-10-31 15:47:39,340 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:39,340 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:39,355 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469?flat'}
2025-10-31 15:47:39,355 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:39,355 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:39,367 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469?flat'}
2025-10-31 15:47:39,368 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-73588183:ctest-trans_left_vn-38442469 is found in opserver
2025-10-31 15:47:39,391 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:39,401 - DEBUG - VRF ids for VN ctest-trans_left_vn-38442469: {}
2025-10-31 15:47:39,805 - DEBUG - Response for create_network : {'network': {'id': 'db9ab771-b017-4c6a-97be-ada05fb566ec', 'name': 'ctest-trans_right_vn-98002157', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-73588183', 'ctest-trans_right_vn-98002157'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:40,170 - DEBUG - Response for create_subnet : {'subnet': {'id': '2776965a-7a0a-4d64-9754-aa19d10b665b', 'name': '', 'tenant_id': '94ef30eda6d74ca69deb2d1ffe71dfec', 'network_id': 'db9ab771-b017-4c6a-97be-ada05fb566ec', 'ip_version': 4, 'cidr': '27.205.32.192/26', 'allocation_pools': [{'start': '27.205.32.194', 'end': '27.205.32.254'}], 'gateway_ip': '27.205.32.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '27.205.32.194', 'tags': [], 'project_id': '94ef30eda6d74ca69deb2d1ffe71dfec'}}
2025-10-31 15:47:40,198 - INFO - Created VN ctest-trans_right_vn-98002157
2025-10-31 15:47:40,211 - DEBUG - VN ctest-trans_right_vn-98002157 UUID is db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,301 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:40,310 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:40,326 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:40,433 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,446 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,455 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/337f0615-6968-4f6d-b365-fe105f473847
2025-10-31 15:47:40,463 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/337f0615-6968-4f6d-b365-fe105f473847
2025-10-31 15:47:40,471 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9af3da5c-7518-4a22-bf9b-f483f0e5756e
2025-10-31 15:47:40,480 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-31 15:47:40,480 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,490 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/337f0615-6968-4f6d-b365-fe105f473847
2025-10-31 15:47:40,505 - INFO - Verified VN network id 19 for VN db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,505 - INFO - Verifications in API Server for VN ctest-trans_right_vn-98002157 passed
2025-10-31 15:47:40,505 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,516 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/337f0615-6968-4f6d-b365-fe105f473847
2025-10-31 15:47:40,523 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/337f0615-6968-4f6d-b365-fe105f473847
2025-10-31 15:47:40,532 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9af3da5c-7518-4a22-bf9b-f483f0e5756e
2025-10-31 15:47:40,545 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15824161939633163370', 'uuid-lslong': '10934367849614239468'}, 'enable': 'true', 'created': '2025-10-31T15:47:39', 'last-modified': '2025-10-31T15:47:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.451030'}
2025-10-31 15:47:40,556 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-31 15:47:40,563 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15824161939633163370', 'uuid-lslong': '10934367849614239468'}, 'enable': 'true', 'created': '2025-10-31T15:47:39', 'last-modified': '2025-10-31T15:47:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.480045'}
2025-10-31 15:47:40,572 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-31 15:47:40,580 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-73588183', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15824161939633163370', 'uuid-lslong': '10934367849614239468'}, 'enable': 'true', 'created': '2025-10-31T15:47:39', 'last-modified': '2025-10-31T15:47:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500010'}
2025-10-31 15:47:40,588 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-31 15:47:40,588 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-98002157 passed
2025-10-31 15:47:40,588 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-98002157 in API_Server ======
2025-10-31 15:47:40,588 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:40,593 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:40,605 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:40,709 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/db9ab771-b017-4c6a-97be-ada05fb566ec
2025-10-31 15:47:40,725 - DEBUG - =>VN ctest-trans_right_vn-98002157 has no policy to be verified
2025-10-31 15:47:40,725 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:40,725 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:40,725 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:40,740 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157?flat'}
2025-10-31 15:47:40,740 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:40,740 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:40,753 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157?flat'}
2025-10-31 15:47:40,753 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:40,753 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:40,767 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157?flat'}
2025-10-31 15:47:40,767 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-73588183:ctest-trans_right_vn-98002157 is found in opserver
2025-10-31 15:47:40,788 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:40,799 - DEBUG - VRF ids for VN ctest-trans_right_vn-98002157: {}
2025-10-31 15:47:41,873 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-1)
2025-10-31 15:47:43,166 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-2)
2025-10-31 15:47:43,166 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-54084393']
2025-10-31 15:47:43,474 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-54084393'] created succesfully
2025-10-31 15:47:43,834 - DEBUG - Image by name tiny_trans_fw not found
2025-10-31 15:47:43,834 - DEBUG - Installing image tiny_trans_fw
2025-10-31 15:47:43,834 - 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-31 15:47:43,835 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-31 15:47:43,835 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-31 15:47:43,855 - 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-31 15:47:43,882 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-31 15:47:43,883 - DEBUG - Unzip image
2025-10-31 15:47:43,884 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-31 15:47:43,901 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-31 15:47:43,902 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-54084393']
2025-10-31 15:47:44,011 - INFO - Service template: ['default-domain', 'ctest-service_template_1-54084393'] deleted successfully.
2025-10-31 15:47:44,011 - INFO - Skip interface_detach for VM ctest-right_vm-14098740 in state BUILD
2025-10-31 15:47:44,011 - INFO - Deleting VM ctest-right_vm-14098740
2025-10-31 15:47:44,012 - ERROR - VM ctest-right_vm-14098740 has failed to come up
2025-10-31 15:47:44,012 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:44,012 - ERROR - VM ctest-right_vm-14098740 has failed to come up
2025-10-31 15:47:44,012 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:44,118 - INFO - Skip interface_detach for VM ctest-left_vm-61183956 in state BUILD
2025-10-31 15:47:44,118 - INFO - Deleting VM ctest-left_vm-61183956
2025-10-31 15:47:44,119 - ERROR - VM ctest-left_vm-61183956 has failed to come up
2025-10-31 15:47:44,119 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:44,119 - ERROR - VM ctest-left_vm-61183956 has failed to come up
2025-10-31 15:47:44,119 - ERROR - Nova failed to get host of the VM
2025-10-31 15:47:44,195 - INFO - Deleting VN ctest-trans_right_vn-98002157
2025-10-31 15:47:44,417 - DEBUG - Response for deleting network ()
2025-10-31 15:47:44,417 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/337f0615-6968-4f6d-b365-fe105f473847
2025-10-31 15:47:44,423 - DEBUG - Response Code: 404
2025-10-31 15:47:44,423 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:44,447 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:44,509 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:44,609 - INFO - Validated that VN ctest-trans_right_vn-98002157 is not found in API Server
2025-10-31 15:47:44,620 - DEBUG - VN ctest-trans_right_vn-98002157 is not present in Agent 10.0.0.19
2025-10-31 15:47:44,655 - DEBUG - VN ctest-trans_right_vn-98002157 is not present in Agent 10.0.0.22
2025-10-31 15:47:44,655 - INFO - Validated that VN ctest-trans_right_vn-98002157 is not in any agent
2025-10-31 15:47:44,696 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-98002157 info
2025-10-31 15:47:44,696 - INFO - Deleting VN ctest-trans_left_vn-38442469
2025-10-31 15:47:44,918 - DEBUG - Response for deleting network ()
2025-10-31 15:47:44,919 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f09eae1e-7c3e-4613-802e-ed4e80517c65
2025-10-31 15:47:44,929 - DEBUG - Response Code: 404
2025-10-31 15:47:44,929 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:44,934 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:44,949 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:45,051 - INFO - Validated that VN ctest-trans_left_vn-38442469 is not found in API Server
2025-10-31 15:47:45,061 - DEBUG - VN ctest-trans_left_vn-38442469 is not present in Agent 10.0.0.19
2025-10-31 15:47:45,079 - DEBUG - VN ctest-trans_left_vn-38442469 is not present in Agent 10.0.0.22
2025-10-31 15:47:45,079 - INFO - Validated that VN ctest-trans_left_vn-38442469 is not in any agent
2025-10-31 15:47:45,118 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-38442469 info
2025-10-31 15:47:45,118 - INFO - Deleting VN ctest-right_vn-45850021
2025-10-31 15:47:45,168 - DEBUG - VN 04486e5a-1061-4238-bbb2-e903ffb0701c still in use: Unable to complete operation on network 04486e5a-1061-4238-bbb2-e903ffb0701c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ef94ad7f-b55e-497b-ae62-baceb3222173']
2025-10-31 15:47:45,168 - WARNING - Deleting VN ctest-right_vn-45850021 failed..Will retry
2025-10-31 15:47:47,361 - DEBUG - Response for deleting network ()
2025-10-31 15:47:47,361 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e246bbb5-944a-4230-8618-65ba6690dd3f
2025-10-31 15:47:47,366 - DEBUG - Response Code: 404
2025-10-31 15:47:47,366 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:47,379 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:47,396 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:47,490 - INFO - Validated that VN ctest-right_vn-45850021 is not found in API Server
2025-10-31 15:47:47,501 - DEBUG - VN ctest-right_vn-45850021 is not present in Agent 10.0.0.19
2025-10-31 15:47:47,512 - DEBUG - VN ctest-right_vn-45850021 is not present in Agent 10.0.0.22
2025-10-31 15:47:47,512 - INFO - Validated that VN ctest-right_vn-45850021 is not in any agent
2025-10-31 15:47:47,546 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-45850021 info
2025-10-31 15:47:47,547 - INFO - Deleting VN ctest-left_vn-18375231
2025-10-31 15:47:47,730 - DEBUG - Response for deleting network ()
2025-10-31 15:47:47,730 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/28a29082-0729-425e-bc01-6567667bad09
2025-10-31 15:47:47,759 - DEBUG - Response Code: 404
2025-10-31 15:47:47,759 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:47,773 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:47,787 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:47,884 - INFO - Validated that VN ctest-left_vn-18375231 is not found in API Server
2025-10-31 15:47:47,894 - DEBUG - VN ctest-left_vn-18375231 is not present in Agent 10.0.0.19
2025-10-31 15:47:47,906 - DEBUG - VN ctest-left_vn-18375231 is not present in Agent 10.0.0.22
2025-10-31 15:47:47,906 - INFO - Validated that VN ctest-left_vn-18375231 is not in any agent
2025-10-31 15:47:47,940 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-18375231 info
2025-10-31 15:47:47,940 - INFO - Deleting VN ctest-mgmt_vn-04567400
2025-10-31 15:47:48,098 - DEBUG - Response for deleting network ()
2025-10-31 15:47:48,098 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/8cb45232-0026-448a-ae78-ed62b7b58e69
2025-10-31 15:47:48,104 - DEBUG - Response Code: 404
2025-10-31 15:47:48,104 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:48,109 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:48,128 - DEBUG - Requesting: http://10.0.0.38:8082/project/94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:48,216 - INFO - Validated that VN ctest-mgmt_vn-04567400 is not found in API Server
2025-10-31 15:47:48,228 - DEBUG - VN ctest-mgmt_vn-04567400 is not present in Agent 10.0.0.19
2025-10-31 15:47:48,241 - DEBUG - VN ctest-mgmt_vn-04567400 is not present in Agent 10.0.0.22
2025-10-31 15:47:48,241 - INFO - Validated that VN ctest-mgmt_vn-04567400 is not in any agent
2025-10-31 15:47:48,277 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-04567400 info
2025-10-31 15:47:49,566 - DEBUG - No XMPP flaps were noticed during the test
2025-10-31 15:47:49,566 - INFO -
2025-10-31 15:47:49,566 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-10-31 15:47:49,566 - INFO - --------------------------------------------------------------------------------
2025-10-31 15:47:50,291 - INFO - Deleted project: ctest-TestSvcRegr-73588183, ID : 94ef30ed-a6d7-4ca6-9deb-2d1ffe71dfec
2025-10-31 15:47:50,462 - INFO - Domain Default found not creating
2025-10-31 15:47:50,624 - INFO - Project ctest-TestSvcRegr-13161126 not found, creating it
2025-10-31 15:47:51,199 - INFO - Created Project:ctest-TestSvcRegr-13161126, ID : 789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:52,613 - INFO - ================================================================================
2025-10-31 15:47:52,613 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-31 15:47:53,900 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.22': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2025-10-31 15:47:53,900 - INFO - Initial checks done. Running the testcase now
2025-10-31 15:47:53,900 - INFO -
2025-10-31 15:47:54,952 - DEBUG - Response for create_network : {'network': {'id': 'd403108e-9a29-4d27-8d4f-8ef755f61912', 'name': 'ctest-mgmt_vn-65793801', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'project_id': '789a7b5f49d54aea9f64525a3f278f17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-13161126', 'ctest-mgmt_vn-65793801'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:55,192 - DEBUG - Response for create_subnet : {'subnet': {'id': '84ed81b3-2e4b-4b1b-9db5-47508031df29', 'name': '', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'network_id': 'd403108e-9a29-4d27-8d4f-8ef755f61912', 'ip_version': 4, 'cidr': '65.38.5.64/26', 'allocation_pools': [{'start': '65.38.5.66', 'end': '65.38.5.126'}], 'gateway_ip': '65.38.5.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '65.38.5.66', 'tags': [], 'project_id': '789a7b5f49d54aea9f64525a3f278f17'}}
2025-10-31 15:47:55,215 - INFO - Created VN ctest-mgmt_vn-65793801
2025-10-31 15:47:55,227 - DEBUG - VN ctest-mgmt_vn-65793801 UUID is d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:55,302 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:55,606 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:55,633 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:55,650 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:55,747 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:55,761 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:55,772 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/699441a1-a44d-4793-b317-5cc9c43a6341
2025-10-31 15:47:55,782 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/699441a1-a44d-4793-b317-5cc9c43a6341
2025-10-31 15:47:55,790 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/408bf4d9-83da-433b-807a-85be2770c80d
2025-10-31 15:47:55,799 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:55,799 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:55,812 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/699441a1-a44d-4793-b317-5cc9c43a6341
2025-10-31 15:47:55,831 - INFO - Verified VN network id 15 for VN d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:55,831 - INFO - Verifications in API Server for VN ctest-mgmt_vn-65793801 passed
2025-10-31 15:47:55,831 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:55,841 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/699441a1-a44d-4793-b317-5cc9c43a6341
2025-10-31 15:47:55,851 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/699441a1-a44d-4793-b317-5cc9c43a6341
2025-10-31 15:47:55,859 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/408bf4d9-83da-433b-807a-85be2770c80d
2025-10-31 15:47:55,873 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15277072565628652839', 'uuid-lslong': '10182514475458238738'}, 'enable': 'true', 'created': '2025-10-31T15:47:54', 'last-modified': '2025-10-31T15:47:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.737591'}
2025-10-31 15:47:55,879 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:55,887 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15277072565628652839', 'uuid-lslong': '10182514475458238738'}, 'enable': 'true', 'created': '2025-10-31T15:47:54', 'last-modified': '2025-10-31T15:47:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.752364'}
2025-10-31 15:47:55,897 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:55,904 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15277072565628652839', 'uuid-lslong': '10182514475458238738'}, 'enable': 'true', 'created': '2025-10-31T15:47:54', 'last-modified': '2025-10-31T15:47:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.769800'}
2025-10-31 15:47:55,912 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:47:55,912 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-65793801 passed
2025-10-31 15:47:55,912 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-65793801 in API_Server ======
2025-10-31 15:47:55,913 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:55,919 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:55,932 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:56,030 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d403108e-9a29-4d27-8d4f-8ef755f61912
2025-10-31 15:47:56,040 - DEBUG - =>VN ctest-mgmt_vn-65793801 has no policy to be verified
2025-10-31 15:47:56,040 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:56,040 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:56,041 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:56,335 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:56,395 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801?flat'}
2025-10-31 15:47:56,396 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:56,396 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:56,696 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:56,754 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801?flat'}
2025-10-31 15:47:56,754 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:56,754 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:57,053 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:57,107 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801?flat'}
2025-10-31 15:47:57,107 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-65793801 is found in opserver
2025-10-31 15:47:57,131 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:57,143 - DEBUG - VRF ids for VN ctest-mgmt_vn-65793801: {}
2025-10-31 15:47:57,424 - DEBUG - Response for create_network : {'network': {'id': '203c3750-f89e-490c-9df7-79facf0f1837', 'name': 'service-left-vn-69372163', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'project_id': '789a7b5f49d54aea9f64525a3f278f17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-13161126', 'service-left-vn-69372163'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:57,738 - DEBUG - Response for create_subnet : {'subnet': {'id': '2f9f169a-4875-4f4d-b2cf-aa52eeffc1fb', 'name': '', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'network_id': '203c3750-f89e-490c-9df7-79facf0f1837', 'ip_version': 4, 'cidr': '90.111.5.0/26', 'allocation_pools': [{'start': '90.111.5.2', 'end': '90.111.5.62'}], 'gateway_ip': '90.111.5.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.111.5.2', 'tags': [], 'project_id': '789a7b5f49d54aea9f64525a3f278f17'}}
2025-10-31 15:47:57,763 - INFO - Created VN service-left-vn-69372163
2025-10-31 15:47:57,780 - DEBUG - VN service-left-vn-69372163 UUID is 203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:57,868 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:57,875 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:57,887 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:57,990 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:58,006 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:58,017 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/313966e4-bfaa-4adc-9c36-60b2bfd8015a
2025-10-31 15:47:58,026 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/313966e4-bfaa-4adc-9c36-60b2bfd8015a
2025-10-31 15:47:58,035 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f3850bb5-91ae-4d24-84df-9c5459e17bf5
2025-10-31 15:47:58,044 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:58,044 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:58,056 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/313966e4-bfaa-4adc-9c36-60b2bfd8015a
2025-10-31 15:47:58,073 - INFO - Verified VN network id 16 for VN 203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:58,073 - INFO - Verifications in API Server for VN service-left-vn-69372163 passed
2025-10-31 15:47:58,073 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:58,084 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/313966e4-bfaa-4adc-9c36-60b2bfd8015a
2025-10-31 15:47:58,093 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/313966e4-bfaa-4adc-9c36-60b2bfd8015a
2025-10-31 15:47:58,103 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f3850bb5-91ae-4d24-84df-9c5459e17bf5
2025-10-31 15:47:58,117 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2322792328724367628', 'uuid-lslong': '11382700701324875831'}, 'enable': 'true', 'created': '2025-10-31T15:47:57', 'last-modified': '2025-10-31T15:47:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443201'}
2025-10-31 15:47:58,123 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:58,129 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2322792328724367628', 'uuid-lslong': '11382700701324875831'}, 'enable': 'true', 'created': '2025-10-31T15:47:57', 'last-modified': '2025-10-31T15:47:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.457966'}
2025-10-31 15:47:58,135 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:58,141 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2322792328724367628', 'uuid-lslong': '11382700701324875831'}, 'enable': 'true', 'created': '2025-10-31T15:47:57', 'last-modified': '2025-10-31T15:47:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470251'}
2025-10-31 15:47:58,148 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:47:58,148 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-69372163 passed
2025-10-31 15:47:58,149 - DEBUG - ====Verifying policy data for service-left-vn-69372163 in API_Server ======
2025-10-31 15:47:58,149 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:58,156 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:58,171 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:58,284 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/203c3750-f89e-490c-9df7-79facf0f1837
2025-10-31 15:47:58,296 - DEBUG - =>VN service-left-vn-69372163 has no policy to be verified
2025-10-31 15:47:58,296 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:58,296 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:58,296 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:58,357 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163?flat'}
2025-10-31 15:47:58,357 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:58,357 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:58,423 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163?flat'}
2025-10-31 15:47:58,423 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:58,423 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:58,491 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163?flat'}
2025-10-31 15:47:58,491 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-13161126:service-left-vn-69372163 is found in opserver
2025-10-31 15:47:58,511 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:58,521 - DEBUG - VRF ids for VN service-left-vn-69372163: {}
2025-10-31 15:47:58,844 - DEBUG - Response for create_network : {'network': {'id': 'f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5', 'name': 'service-right-vn-67419403', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'project_id': '789a7b5f49d54aea9f64525a3f278f17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-13161126', 'service-right-vn-67419403'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:47:59,100 - DEBUG - Response for create_subnet : {'subnet': {'id': '06eac626-b32a-4435-84c1-3be9fa398447', 'name': '', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'network_id': 'f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5', 'ip_version': 4, 'cidr': '69.28.78.192/26', 'allocation_pools': [{'start': '69.28.78.194', 'end': '69.28.78.254'}], 'gateway_ip': '69.28.78.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '69.28.78.194', 'tags': [], 'project_id': '789a7b5f49d54aea9f64525a3f278f17'}}
2025-10-31 15:47:59,122 - INFO - Created VN service-right-vn-67419403
2025-10-31 15:47:59,133 - DEBUG - VN service-right-vn-67419403 UUID is f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,226 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:59,233 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:59,246 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:59,350 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,378 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,392 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/55a6c242-ef39-4120-b832-e7d4e9cc97f6
2025-10-31 15:47:59,404 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/55a6c242-ef39-4120-b832-e7d4e9cc97f6
2025-10-31 15:47:59,419 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/217fa25a-8c40-4e8c-8f25-e24699cf3433
2025-10-31 15:47:59,428 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:59,428 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,438 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/55a6c242-ef39-4120-b832-e7d4e9cc97f6
2025-10-31 15:47:59,458 - INFO - Verified VN network id 17 for VN f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,458 - INFO - Verifications in API Server for VN service-right-vn-67419403 passed
2025-10-31 15:47:59,458 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,469 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/55a6c242-ef39-4120-b832-e7d4e9cc97f6
2025-10-31 15:47:59,478 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/55a6c242-ef39-4120-b832-e7d4e9cc97f6
2025-10-31 15:47:59,486 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/217fa25a-8c40-4e8c-8f25-e24699cf3433
2025-10-31 15:47:59,505 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17697064100725148406', 'uuid-lslong': '10263343026293919685'}, 'enable': 'true', 'created': '2025-10-31T15:47:58', 'last-modified': '2025-10-31T15:47:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458252'}
2025-10-31 15:47:59,516 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:59,523 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17697064100725148406', 'uuid-lslong': '10263343026293919685'}, 'enable': 'true', 'created': '2025-10-31T15:47:58', 'last-modified': '2025-10-31T15:47:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493604'}
2025-10-31 15:47:59,530 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:59,537 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17697064100725148406', 'uuid-lslong': '10263343026293919685'}, 'enable': 'true', 'created': '2025-10-31T15:47:58', 'last-modified': '2025-10-31T15:47:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.506198'}
2025-10-31 15:47:59,549 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:47:59,549 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-67419403 passed
2025-10-31 15:47:59,550 - DEBUG - ====Verifying policy data for service-right-vn-67419403 in API_Server ======
2025-10-31 15:47:59,550 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:47:59,559 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:47:59,577 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:47:59,683 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5
2025-10-31 15:47:59,696 - DEBUG - =>VN service-right-vn-67419403 has no policy to be verified
2025-10-31 15:47:59,696 - DEBUG - Verifying the vn in opserver
2025-10-31 15:47:59,696 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403 virtual network link through opserver 10.0.0.38
2025-10-31 15:47:59,696 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:47:59,751 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403?flat'}
2025-10-31 15:47:59,751 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403 virtual network link through opserver 10.0.0.249
2025-10-31 15:47:59,751 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:47:59,810 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403?flat'}
2025-10-31 15:47:59,811 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403 virtual network link through opserver 10.0.0.65
2025-10-31 15:47:59,811 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:47:59,883 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403?flat'}
2025-10-31 15:47:59,883 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-13161126:service-right-vn-67419403 is found in opserver
2025-10-31 15:47:59,903 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:47:59,913 - DEBUG - VRF ids for VN service-right-vn-67419403: {}
2025-10-31 15:47:59,977 - DEBUG - Services list from nova: [, , , ]
2025-10-31 15:48:01,098 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-1)
2025-10-31 15:48:02,470 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-2)
2025-10-31 15:48:02,471 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-72516946']
2025-10-31 15:48:02,782 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-72516946'] created succesfully
2025-10-31 15:48:03,144 - DEBUG - Image by name tiny_nat_fw not found
2025-10-31 15:48:03,144 - DEBUG - Installing image tiny_nat_fw
2025-10-31 15:48:03,144 - 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-31 15:48:03,145 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:48:03,145 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:48:03,145 - DEBUG - Unzip image
2025-10-31 15:48:03,145 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:48:03,165 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-31 15:48:03,165 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-72516946']
2025-10-31 15:48:03,270 - INFO - Service template: ['default-domain', 'ctest-service_template_1-72516946'] deleted successfully.
2025-10-31 15:48:03,271 - INFO - Skip interface_detach for VM ctest-right_vm-37488420 in state BUILD
2025-10-31 15:48:03,271 - INFO - Deleting VM ctest-right_vm-37488420
2025-10-31 15:48:03,309 - ERROR - VM ctest-right_vm-37488420 has failed to come up
2025-10-31 15:48:03,309 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:03,309 - ERROR - VM ctest-right_vm-37488420 has failed to come up
2025-10-31 15:48:03,309 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:03,441 - INFO - Skip interface_detach for VM ctest-left_vm-43535282 in state BUILD
2025-10-31 15:48:03,442 - INFO - Deleting VM ctest-left_vm-43535282
2025-10-31 15:48:03,442 - ERROR - VM ctest-left_vm-43535282 has failed to come up
2025-10-31 15:48:03,442 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:03,442 - ERROR - VM ctest-left_vm-43535282 has failed to come up
2025-10-31 15:48:03,442 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:03,522 - INFO - Deleting VN service-right-vn-67419403
2025-10-31 15:48:03,572 - DEBUG - VN f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5 still in use: Unable to complete operation on network f5989a09-5b0a-4af6-8e6e-b81ada3b4fc5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-37b85f81-a86e-481d-ad6a-0431cb055f86']
2025-10-31 15:48:03,573 - WARNING - Deleting VN service-right-vn-67419403 failed..Will retry
2025-10-31 15:48:05,734 - DEBUG - Response for deleting network ()
2025-10-31 15:48:05,734 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/55a6c242-ef39-4120-b832-e7d4e9cc97f6
2025-10-31 15:48:05,739 - DEBUG - Response Code: 404
2025-10-31 15:48:05,739 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:05,758 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:05,781 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:05,880 - INFO - Validated that VN service-right-vn-67419403 is not found in API Server
2025-10-31 15:48:05,894 - DEBUG - VN service-right-vn-67419403 is not present in Agent 10.0.0.19
2025-10-31 15:48:05,907 - DEBUG - VN service-right-vn-67419403 is not present in Agent 10.0.0.22
2025-10-31 15:48:05,907 - INFO - Validated that VN service-right-vn-67419403 is not in any agent
2025-10-31 15:48:05,940 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-67419403 info
2025-10-31 15:48:05,940 - INFO - Deleting VN service-left-vn-69372163
2025-10-31 15:48:06,109 - DEBUG - Response for deleting network ()
2025-10-31 15:48:06,109 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/313966e4-bfaa-4adc-9c36-60b2bfd8015a
2025-10-31 15:48:06,115 - DEBUG - Response Code: 404
2025-10-31 15:48:06,115 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:06,125 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:06,141 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:06,228 - INFO - Validated that VN service-left-vn-69372163 is not found in API Server
2025-10-31 15:48:06,239 - DEBUG - VN service-left-vn-69372163 is not present in Agent 10.0.0.19
2025-10-31 15:48:06,251 - DEBUG - VN service-left-vn-69372163 is not present in Agent 10.0.0.22
2025-10-31 15:48:06,251 - INFO - Validated that VN service-left-vn-69372163 is not in any agent
2025-10-31 15:48:06,282 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-69372163 info
2025-10-31 15:48:06,282 - INFO - Deleting VN ctest-mgmt_vn-65793801
2025-10-31 15:48:06,464 - DEBUG - Response for deleting network ()
2025-10-31 15:48:06,464 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/699441a1-a44d-4793-b317-5cc9c43a6341
2025-10-31 15:48:06,470 - DEBUG - Response Code: 404
2025-10-31 15:48:06,470 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:06,478 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:06,490 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:06,578 - INFO - Validated that VN ctest-mgmt_vn-65793801 is not found in API Server
2025-10-31 15:48:06,591 - DEBUG - VN ctest-mgmt_vn-65793801 is not present in Agent 10.0.0.19
2025-10-31 15:48:06,602 - DEBUG - VN ctest-mgmt_vn-65793801 is not present in Agent 10.0.0.22
2025-10-31 15:48:06,602 - INFO - Validated that VN ctest-mgmt_vn-65793801 is not in any agent
2025-10-31 15:48:06,639 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-65793801 info
2025-10-31 15:48:07,940 - DEBUG - No XMPP flaps were noticed during the test
2025-10-31 15:48:07,940 - INFO -
2025-10-31 15:48:07,940 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-10-31 15:48:07,940 - INFO - --------------------------------------------------------------------------------
2025-10-31 15:48:07,943 - INFO - ================================================================================
2025-10-31 15:48:07,943 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-31 15:48:09,233 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.22': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2025-10-31 15:48:09,233 - INFO - Initial checks done. Running the testcase now
2025-10-31 15:48:09,233 - INFO -
2025-10-31 15:48:09,494 - DEBUG - Response for create_network : {'network': {'id': 'fcb521ab-b472-405a-9755-915ad4af9c51', 'name': 'ctest-mgmt_vn-39454226', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'project_id': '789a7b5f49d54aea9f64525a3f278f17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-13161126', 'ctest-mgmt_vn-39454226'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:48:09,780 - DEBUG - Response for create_subnet : {'subnet': {'id': '93c5c948-201c-453d-a42e-ffea003f42fd', 'name': '', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'network_id': 'fcb521ab-b472-405a-9755-915ad4af9c51', 'ip_version': 4, 'cidr': '185.50.221.192/26', 'allocation_pools': [{'start': '185.50.221.194', 'end': '185.50.221.254'}], 'gateway_ip': '185.50.221.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '185.50.221.194', 'tags': [], 'project_id': '789a7b5f49d54aea9f64525a3f278f17'}}
2025-10-31 15:48:09,805 - INFO - Created VN ctest-mgmt_vn-39454226
2025-10-31 15:48:09,817 - DEBUG - VN ctest-mgmt_vn-39454226 UUID is fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:09,894 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:09,900 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:09,911 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:10,004 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:10,015 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:10,025 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/b0908102-953e-49a7-a6a8-830b3bfa5b0c
2025-10-31 15:48:10,033 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/b0908102-953e-49a7-a6a8-830b3bfa5b0c
2025-10-31 15:48:10,040 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/d77a019b-2fa9-411a-812f-fadabfea421c
2025-10-31 15:48:10,047 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:48:10,047 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:10,055 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/b0908102-953e-49a7-a6a8-830b3bfa5b0c
2025-10-31 15:48:10,070 - INFO - Verified VN network id 15 for VN fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:10,071 - INFO - Verifications in API Server for VN ctest-mgmt_vn-39454226 passed
2025-10-31 15:48:10,071 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:10,080 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/b0908102-953e-49a7-a6a8-830b3bfa5b0c
2025-10-31 15:48:10,085 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/b0908102-953e-49a7-a6a8-830b3bfa5b0c
2025-10-31 15:48:10,092 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/d77a019b-2fa9-411a-812f-fadabfea421c
2025-10-31 15:48:10,105 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18209497689692979290', 'uuid-lslong': '10904781892048886865'}, 'enable': 'true', 'created': '2025-10-31T15:48:09', 'last-modified': '2025-10-31T15:48:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411412'}
2025-10-31 15:48:10,111 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:48:10,117 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18209497689692979290', 'uuid-lslong': '10904781892048886865'}, 'enable': 'true', 'created': '2025-10-31T15:48:09', 'last-modified': '2025-10-31T15:48:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427488'}
2025-10-31 15:48:10,125 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:48:10,131 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18209497689692979290', 'uuid-lslong': '10904781892048886865'}, 'enable': 'true', 'created': '2025-10-31T15:48:09', 'last-modified': '2025-10-31T15:48:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439696'}
2025-10-31 15:48:10,138 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-31 15:48:10,138 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-39454226 passed
2025-10-31 15:48:10,138 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-39454226 in API_Server ======
2025-10-31 15:48:10,138 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:10,146 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:10,158 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:10,254 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fcb521ab-b472-405a-9755-915ad4af9c51
2025-10-31 15:48:10,265 - DEBUG - =>VN ctest-mgmt_vn-39454226 has no policy to be verified
2025-10-31 15:48:10,265 - DEBUG - Verifying the vn in opserver
2025-10-31 15:48:10,265 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226 virtual network link through opserver 10.0.0.38
2025-10-31 15:48:10,265 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:48:10,278 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226?flat'}
2025-10-31 15:48:10,278 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226 virtual network link through opserver 10.0.0.249
2025-10-31 15:48:10,278 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:48:10,294 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226?flat'}
2025-10-31 15:48:10,294 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226 virtual network link through opserver 10.0.0.65
2025-10-31 15:48:10,294 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:48:10,308 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226?flat'}
2025-10-31 15:48:10,308 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-13161126:ctest-mgmt_vn-39454226 is found in opserver
2025-10-31 15:48:10,330 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:48:10,339 - DEBUG - VRF ids for VN ctest-mgmt_vn-39454226: {}
2025-10-31 15:48:10,649 - DEBUG - Response for create_network : {'network': {'id': '61b99142-69fa-4015-a2dc-6cd237825376', 'name': 'ctest-left_vn-72542827', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'project_id': '789a7b5f49d54aea9f64525a3f278f17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-13161126', 'ctest-left_vn-72542827'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:48:10,857 - DEBUG - Response for create_subnet : {'subnet': {'id': '87995922-9a9b-471e-bb15-6785f797cbe6', 'name': '', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'network_id': '61b99142-69fa-4015-a2dc-6cd237825376', 'ip_version': 4, 'cidr': '207.176.124.64/26', 'allocation_pools': [{'start': '207.176.124.66', 'end': '207.176.124.126'}], 'gateway_ip': '207.176.124.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '207.176.124.66', 'tags': [], 'project_id': '789a7b5f49d54aea9f64525a3f278f17'}}
2025-10-31 15:48:10,878 - INFO - Created VN ctest-left_vn-72542827
2025-10-31 15:48:10,891 - DEBUG - VN ctest-left_vn-72542827 UUID is 61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:10,969 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:10,975 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:10,987 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:11,087 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:11,104 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:11,114 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6bae316a-6d53-43e7-9dde-64c00f8909f8
2025-10-31 15:48:11,123 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6bae316a-6d53-43e7-9dde-64c00f8909f8
2025-10-31 15:48:11,131 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9c9aaefa-9112-4a77-aae4-43bb95e3764c
2025-10-31 15:48:11,137 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:48:11,137 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:11,147 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6bae316a-6d53-43e7-9dde-64c00f8909f8
2025-10-31 15:48:11,165 - INFO - Verified VN network id 16 for VN 61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:11,165 - INFO - Verifications in API Server for VN ctest-left_vn-72542827 passed
2025-10-31 15:48:11,165 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:11,175 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6bae316a-6d53-43e7-9dde-64c00f8909f8
2025-10-31 15:48:11,189 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6bae316a-6d53-43e7-9dde-64c00f8909f8
2025-10-31 15:48:11,197 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9c9aaefa-9112-4a77-aae4-43bb95e3764c
2025-10-31 15:48:11,209 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7041819206802358293', 'uuid-lslong': '11735374379150889846'}, 'enable': 'true', 'created': '2025-10-31T15:48:10', 'last-modified': '2025-10-31T15:48:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409821'}
2025-10-31 15:48:11,214 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:48:11,220 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7041819206802358293', 'uuid-lslong': '11735374379150889846'}, 'enable': 'true', 'created': '2025-10-31T15:48:10', 'last-modified': '2025-10-31T15:48:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418820'}
2025-10-31 15:48:11,226 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:48:11,231 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7041819206802358293', 'uuid-lslong': '11735374379150889846'}, 'enable': 'true', 'created': '2025-10-31T15:48:10', 'last-modified': '2025-10-31T15:48:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432506'}
2025-10-31 15:48:11,237 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-31 15:48:11,237 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-72542827 passed
2025-10-31 15:48:11,237 - DEBUG - ====Verifying policy data for ctest-left_vn-72542827 in API_Server ======
2025-10-31 15:48:11,237 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:11,244 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:11,256 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:11,348 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/61b99142-69fa-4015-a2dc-6cd237825376
2025-10-31 15:48:11,357 - DEBUG - =>VN ctest-left_vn-72542827 has no policy to be verified
2025-10-31 15:48:11,357 - DEBUG - Verifying the vn in opserver
2025-10-31 15:48:11,357 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827 virtual network link through opserver 10.0.0.38
2025-10-31 15:48:11,357 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:48:11,369 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827?flat'}
2025-10-31 15:48:11,369 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827 virtual network link through opserver 10.0.0.249
2025-10-31 15:48:11,369 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:48:11,385 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827?flat'}
2025-10-31 15:48:11,385 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827 virtual network link through opserver 10.0.0.65
2025-10-31 15:48:11,385 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:48:11,400 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827?flat'}
2025-10-31 15:48:11,400 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-13161126:ctest-left_vn-72542827 is found in opserver
2025-10-31 15:48:11,422 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:48:11,432 - DEBUG - VRF ids for VN ctest-left_vn-72542827: {}
2025-10-31 15:48:11,737 - DEBUG - Response for create_network : {'network': {'id': '2a91a658-83ea-4de3-83dd-184cbda7a894', 'name': 'ctest-right_vn-72404962', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'project_id': '789a7b5f49d54aea9f64525a3f278f17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-13161126', 'ctest-right_vn-72404962'], 'port_security_enabled': True, 'description': ''}}
2025-10-31 15:48:11,991 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8b6c80a-a17b-4399-a67c-9f42b927c4b9', 'name': '', 'tenant_id': '789a7b5f49d54aea9f64525a3f278f17', 'network_id': '2a91a658-83ea-4de3-83dd-184cbda7a894', 'ip_version': 4, 'cidr': '204.64.186.0/26', 'allocation_pools': [{'start': '204.64.186.2', 'end': '204.64.186.62'}], 'gateway_ip': '204.64.186.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '204.64.186.2', 'tags': [], 'project_id': '789a7b5f49d54aea9f64525a3f278f17'}}
2025-10-31 15:48:12,016 - INFO - Created VN ctest-right_vn-72404962
2025-10-31 15:48:12,032 - DEBUG - VN ctest-right_vn-72404962 UUID is 2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,111 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:12,118 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:12,129 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:12,219 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,232 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,242 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5478802d-f73b-47a0-aa10-326d7d48a359
2025-10-31 15:48:12,249 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5478802d-f73b-47a0-aa10-326d7d48a359
2025-10-31 15:48:12,256 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/82148851-b263-45ea-872f-241310fa9466
2025-10-31 15:48:12,262 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:48:12,262 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,271 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5478802d-f73b-47a0-aa10-326d7d48a359
2025-10-31 15:48:12,288 - INFO - Verified VN network id 17 for VN 2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,288 - INFO - Verifications in API Server for VN ctest-right_vn-72404962 passed
2025-10-31 15:48:12,288 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,298 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5478802d-f73b-47a0-aa10-326d7d48a359
2025-10-31 15:48:12,308 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5478802d-f73b-47a0-aa10-326d7d48a359
2025-10-31 15:48:12,315 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/82148851-b263-45ea-872f-241310fa9466
2025-10-31 15:48:12,326 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3067415720316521955', 'uuid-lslong': '9501777506700077204'}, 'enable': 'true', 'created': '2025-10-31T15:48:11', 'last-modified': '2025-10-31T15:48:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.391725'}
2025-10-31 15:48:12,332 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:48:12,338 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3067415720316521955', 'uuid-lslong': '9501777506700077204'}, 'enable': 'true', 'created': '2025-10-31T15:48:11', 'last-modified': '2025-10-31T15:48:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403411'}
2025-10-31 15:48:12,346 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:48:12,351 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-13161126', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3067415720316521955', 'uuid-lslong': '9501777506700077204'}, 'enable': 'true', 'created': '2025-10-31T15:48:11', 'last-modified': '2025-10-31T15:48:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415337'}
2025-10-31 15:48:12,357 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-31 15:48:12,357 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-72404962 passed
2025-10-31 15:48:12,357 - DEBUG - ====Verifying policy data for ctest-right_vn-72404962 in API_Server ======
2025-10-31 15:48:12,357 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:12,366 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:12,379 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:12,476 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2a91a658-83ea-4de3-83dd-184cbda7a894
2025-10-31 15:48:12,486 - DEBUG - =>VN ctest-right_vn-72404962 has no policy to be verified
2025-10-31 15:48:12,486 - DEBUG - Verifying the vn in opserver
2025-10-31 15:48:12,486 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962 virtual network link through opserver 10.0.0.38
2025-10-31 15:48:12,486 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-31 15:48:12,502 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962?flat'}
2025-10-31 15:48:12,502 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962 virtual network link through opserver 10.0.0.249
2025-10-31 15:48:12,502 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-31 15:48:12,518 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962?flat'}
2025-10-31 15:48:12,518 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962 virtual network link through opserver 10.0.0.65
2025-10-31 15:48:12,518 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-10-31 15:48:12,532 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962?flat'}
2025-10-31 15:48:12,532 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-13161126:ctest-right_vn-72404962 is found in opserver
2025-10-31 15:48:12,558 - DEBUG - Do not have enough data to verify VN in agent
2025-10-31 15:48:12,572 - DEBUG - VRF ids for VN ctest-right_vn-72404962: {}
2025-10-31 15:48:13,580 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-2)
2025-10-31 15:48:14,826 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-1)
2025-10-31 15:48:14,827 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-45852544']
2025-10-31 15:48:15,121 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-45852544'] created succesfully
2025-10-31 15:48:15,464 - DEBUG - Image by name tiny_nat_fw not found
2025-10-31 15:48:15,464 - DEBUG - Installing image tiny_nat_fw
2025-10-31 15:48:15,464 - 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-31 15:48:15,464 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:48:15,464 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:48:15,464 - DEBUG - Unzip image
2025-10-31 15:48:15,464 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-31 15:48:15,482 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-31 15:48:15,482 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-45852544']
2025-10-31 15:48:15,578 - INFO - Service template: ['default-domain', 'ctest-service_template_1-45852544'] deleted successfully.
2025-10-31 15:48:15,579 - INFO - Skip interface_detach for VM ctest-right_vm-20835714 in state BUILD
2025-10-31 15:48:15,579 - INFO - Deleting VM ctest-right_vm-20835714
2025-10-31 15:48:15,580 - ERROR - VM ctest-right_vm-20835714 has failed to come up
2025-10-31 15:48:15,580 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:15,580 - ERROR - VM ctest-right_vm-20835714 has failed to come up
2025-10-31 15:48:15,580 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:15,711 - INFO - Skip interface_detach for VM ctest-left_vm-57260595 in state BUILD
2025-10-31 15:48:15,711 - INFO - Deleting VM ctest-left_vm-57260595
2025-10-31 15:48:15,711 - ERROR - VM ctest-left_vm-57260595 has failed to come up
2025-10-31 15:48:15,711 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:15,711 - ERROR - VM ctest-left_vm-57260595 has failed to come up
2025-10-31 15:48:15,712 - ERROR - Nova failed to get host of the VM
2025-10-31 15:48:15,803 - INFO - Deleting VN ctest-right_vn-72404962
2025-10-31 15:48:15,853 - DEBUG - VN 2a91a658-83ea-4de3-83dd-184cbda7a894 still in use: Unable to complete operation on network 2a91a658-83ea-4de3-83dd-184cbda7a894. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-068b0491-4490-4f6e-ac50-11500248dad4']
2025-10-31 15:48:15,853 - WARNING - Deleting VN ctest-right_vn-72404962 failed..Will retry
2025-10-31 15:48:18,024 - DEBUG - Response for deleting network ()
2025-10-31 15:48:18,024 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5478802d-f73b-47a0-aa10-326d7d48a359
2025-10-31 15:48:18,033 - DEBUG - Response Code: 404
2025-10-31 15:48:18,033 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:18,044 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:18,071 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:18,175 - INFO - Validated that VN ctest-right_vn-72404962 is not found in API Server
2025-10-31 15:48:18,187 - DEBUG - VN ctest-right_vn-72404962 is not present in Agent 10.0.0.19
2025-10-31 15:48:18,198 - DEBUG - VN ctest-right_vn-72404962 is not present in Agent 10.0.0.22
2025-10-31 15:48:18,198 - INFO - Validated that VN ctest-right_vn-72404962 is not in any agent
2025-10-31 15:48:18,229 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-72404962 info
2025-10-31 15:48:18,230 - INFO - Deleting VN ctest-left_vn-72542827
2025-10-31 15:48:18,415 - DEBUG - Response for deleting network ()
2025-10-31 15:48:18,415 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6bae316a-6d53-43e7-9dde-64c00f8909f8
2025-10-31 15:48:18,421 - DEBUG - Response Code: 404
2025-10-31 15:48:18,422 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:18,428 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:18,442 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:18,532 - INFO - Validated that VN ctest-left_vn-72542827 is not found in API Server
2025-10-31 15:48:18,543 - DEBUG - VN ctest-left_vn-72542827 is not present in Agent 10.0.0.19
2025-10-31 15:48:18,553 - DEBUG - VN ctest-left_vn-72542827 is not present in Agent 10.0.0.22
2025-10-31 15:48:18,553 - INFO - Validated that VN ctest-left_vn-72542827 is not in any agent
2025-10-31 15:48:18,588 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-72542827 info
2025-10-31 15:48:18,588 - INFO - Deleting VN ctest-mgmt_vn-39454226
2025-10-31 15:48:18,759 - DEBUG - Response for deleting network ()
2025-10-31 15:48:18,759 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/b0908102-953e-49a7-a6a8-830b3bfa5b0c
2025-10-31 15:48:18,769 - DEBUG - Response Code: 404
2025-10-31 15:48:18,770 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-10-31 15:48:18,781 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69
2025-10-31 15:48:18,808 - DEBUG - Requesting: http://10.0.0.38:8082/project/789a7b5f-49d5-4aea-9f64-525a3f278f17
2025-10-31 15:48:18,912 - INFO - Validated that VN ctest-mgmt_vn-39454226 is not found in API Server
2025-10-31 15:48:18,924 - DEBUG - VN ctest-mgmt_vn-39454226 is not present in Agent 10.0.0.19
2025-10-31 15:48:18,936 - DEBUG - VN ctest-mgmt_vn-39454226 is not present in Agent 10.0.0.22
2025-10-31 15:48:18,937 - INFO - Validated that VN ctest-mgmt_vn-39454226 is not in any agent
2025-10-31 15:48:18,974 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-39454226 info
2025-10-31 15:48:20,273 - DEBUG - No XMPP flaps were noticed during the test
2025-10-31 15:48:20,273 - INFO -
2025-10-31 15:48:20,273 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-10-31 15:48:20,273 - INFO - --------------------------------------------------------------------------------
2025-10-31 15:48:21,244 - INFO - Deleted project: ctest-TestSvcRegr-13161126, ID : 789a7b5f-49d5-4aea-9f64-525a3f278f17