]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-1)
2025-08-26 00:58:11,721 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-67156292']
2025-08-26 00:58:12,039 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-67156292'] created succesfully
2025-08-26 00:58:12,481 - DEBUG - Image by name tiny_nat_fw not found
2025-08-26 00:58:12,481 - DEBUG - Installing image tiny_nat_fw
2025-08-26 00:58:12,481 - 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-08-26 00:58:12,481 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:12,481 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:12,528 - 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-08-26 00:58:12,591 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:12,591 - DEBUG - Unzip image
2025-08-26 00:58:12,591 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:12,609 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-26 00:58:12,609 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-67156292']
2025-08-26 00:58:12,735 - INFO - Service template: ['default-domain', 'ctest-service_template_1-67156292'] deleted successfully.
2025-08-26 00:58:12,735 - INFO - Skip interface_detach for VM ctest-right_vm-61731442 in state BUILD
2025-08-26 00:58:12,735 - INFO - Deleting VM ctest-right_vm-61731442
2025-08-26 00:58:12,800 - ERROR - VM ctest-right_vm-61731442 has failed to come up
2025-08-26 00:58:12,800 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:12,800 - ERROR - VM ctest-right_vm-61731442 has failed to come up
2025-08-26 00:58:12,800 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:12,917 - INFO - Skip interface_detach for VM ctest-left_vm-38830554 in state BUILD
2025-08-26 00:58:12,917 - INFO - Deleting VM ctest-left_vm-38830554
2025-08-26 00:58:12,917 - ERROR - VM ctest-left_vm-38830554 has failed to come up
2025-08-26 00:58:12,917 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:12,918 - ERROR - VM ctest-left_vm-38830554 has failed to come up
2025-08-26 00:58:12,918 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:12,997 - INFO - Deleting VN ctest-right_vn-84950087
2025-08-26 00:58:13,043 - DEBUG - VN b86b26c1-d847-4fa9-90dd-5c8958cc2094 still in use: Unable to complete operation on network b86b26c1-d847-4fa9-90dd-5c8958cc2094. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-741984ce-385a-4fce-9341-61a459b1c0d0']
2025-08-26 00:58:13,043 - WARNING - Deleting VN ctest-right_vn-84950087 failed..Will retry
2025-08-26 00:58:15,299 - DEBUG - Response for deleting network ()
2025-08-26 00:58:15,300 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/fc5bcdb3-d639-45b1-93f1-5571b3372a2c
2025-08-26 00:58:15,309 - DEBUG - Response Code: 404
2025-08-26 00:58:15,309 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:15,320 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:15,336 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:15,447 - INFO - Validated that VN ctest-right_vn-84950087 is not found in API Server
2025-08-26 00:58:15,478 - DEBUG - VN ctest-right_vn-84950087 is not present in Agent 10.0.0.137
2025-08-26 00:58:15,495 - DEBUG - VN ctest-right_vn-84950087 is not present in Agent 10.0.0.41
2025-08-26 00:58:15,495 - INFO - Validated that VN ctest-right_vn-84950087 is not in any agent
2025-08-26 00:58:15,542 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-84950087 info
2025-08-26 00:58:15,543 - INFO - Deleting VN ctest-left_vn-94804167
2025-08-26 00:58:15,703 - DEBUG - Response for deleting network ()
2025-08-26 00:58:15,703 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/df0d6da9-491e-4d2a-97b2-cf447351b944
2025-08-26 00:58:15,710 - DEBUG - Response Code: 404
2025-08-26 00:58:15,710 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:15,717 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:15,734 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:15,877 - INFO - Validated that VN ctest-left_vn-94804167 is not found in API Server
2025-08-26 00:58:15,901 - DEBUG - VN ctest-left_vn-94804167 is not present in Agent 10.0.0.137
2025-08-26 00:58:15,934 - DEBUG - VN ctest-left_vn-94804167 is not present in Agent 10.0.0.41
2025-08-26 00:58:15,934 - INFO - Validated that VN ctest-left_vn-94804167 is not in any agent
2025-08-26 00:58:15,983 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-94804167 info
2025-08-26 00:58:15,983 - INFO - Deleting VN ctest-mgmt_vn-53746532
2025-08-26 00:58:16,163 - DEBUG - Response for deleting network ()
2025-08-26 00:58:16,163 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/72a00d5d-fcff-4e62-92af-f13944536fdf
2025-08-26 00:58:16,176 - DEBUG - Response Code: 404
2025-08-26 00:58:16,176 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:16,184 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:16,206 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:16,317 - INFO - Validated that VN ctest-mgmt_vn-53746532 is not found in API Server
2025-08-26 00:58:16,343 - DEBUG - VN ctest-mgmt_vn-53746532 is not present in Agent 10.0.0.137
2025-08-26 00:58:16,382 - DEBUG - VN ctest-mgmt_vn-53746532 is not present in Agent 10.0.0.41
2025-08-26 00:58:16,382 - INFO - Validated that VN ctest-mgmt_vn-53746532 is not in any agent
2025-08-26 00:58:16,462 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-53746532 info
2025-08-26 00:58:16,462 - INFO - Deleting VM ctest-vm-in-third-vn-54150774
2025-08-26 00:58:16,555 - INFO - Deleting VN ctest-third-vn-40797425
2025-08-26 00:58:16,616 - DEBUG - VN 2cbf0ea6-ed15-4566-bd4c-2ea748f43aca still in use: Unable to complete operation on network 2cbf0ea6-ed15-4566-bd4c-2ea748f43aca. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d0e4f0c3-cd32-438f-8fdb-76e65da523f9']
2025-08-26 00:58:16,617 - WARNING - Deleting VN ctest-third-vn-40797425 failed..Will retry
2025-08-26 00:58:18,794 - DEBUG - Response for deleting network ()
2025-08-26 00:58:20,177 - DEBUG - No XMPP flaps were noticed during the test
2025-08-26 00:58:20,177 - INFO -
2025-08-26 00:58:20,177 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:28]
2025-08-26 00:58:20,177 - INFO - --------------------------------------------------------------------------------
2025-08-26 00:58:20,180 - INFO - ================================================================================
2025-08-26 00:58:20,181 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-26 00:58:21,567 - DEBUG - Nothing to compare xmpp stats {'10.0.0.137': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-26 00:58:21,567 - INFO - Initial checks done. Running the testcase now
2025-08-26 00:58:21,567 - INFO -
2025-08-26 00:58:21,965 - DEBUG - Response for create_network : {'network': {'id': '9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad', 'name': 'ctest-mgmt_vn-50666041', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'project_id': '6aabe554675e49e69354d45e9e02ecd7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38521069', 'ctest-mgmt_vn-50666041'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:22,245 - DEBUG - Response for create_subnet : {'subnet': {'id': '5b068f66-ce9e-4c45-8acb-82e513bd9c35', 'name': '', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'network_id': '9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad', 'ip_version': 4, 'cidr': '99.183.180.0/26', 'allocation_pools': [{'start': '99.183.180.2', 'end': '99.183.180.62'}], 'gateway_ip': '99.183.180.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.183.180.2', 'tags': [], 'project_id': '6aabe554675e49e69354d45e9e02ecd7'}}
2025-08-26 00:58:22,272 - INFO - Created VN ctest-mgmt_vn-50666041
2025-08-26 00:58:22,289 - DEBUG - VN ctest-mgmt_vn-50666041 UUID is 9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,371 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:22,380 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:22,403 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:22,506 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,530 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,549 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/29379946-8f09-481c-9856-e3488c25c5f7
2025-08-26 00:58:22,562 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/29379946-8f09-481c-9856-e3488c25c5f7
2025-08-26 00:58:22,577 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/4c512e17-c6e2-4d7a-804c-14d8d8a46d03
2025-08-26 00:58:22,585 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:22,586 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,596 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/29379946-8f09-481c-9856-e3488c25c5f7
2025-08-26 00:58:22,619 - INFO - Verified VN network id 11 for VN 9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,619 - INFO - Verifications in API Server for VN ctest-mgmt_vn-50666041 passed
2025-08-26 00:58:22,620 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,631 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/29379946-8f09-481c-9856-e3488c25c5f7
2025-08-26 00:58:22,639 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/29379946-8f09-481c-9856-e3488c25c5f7
2025-08-26 00:58:22,648 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/4c512e17-c6e2-4d7a-804c-14d8d8a46d03
2025-08-26 00:58:22,661 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11466315322846891283', 'uuid-lslong': '11885529351921651885'}, 'enable': 'true', 'created': '2025-08-26T00:58:21', 'last-modified': '2025-08-26T00:58:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493830'}
2025-08-26 00:58:22,668 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:22,679 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11466315322846891283', 'uuid-lslong': '11885529351921651885'}, 'enable': 'true', 'created': '2025-08-26T00:58:21', 'last-modified': '2025-08-26T00:58:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.515247'}
2025-08-26 00:58:22,688 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:22,695 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11466315322846891283', 'uuid-lslong': '11885529351921651885'}, 'enable': 'true', 'created': '2025-08-26T00:58:21', 'last-modified': '2025-08-26T00:58:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.524834'}
2025-08-26 00:58:22,704 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:22,704 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-50666041 passed
2025-08-26 00:58:22,704 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-50666041 in API_Server ======
2025-08-26 00:58:22,705 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:22,711 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:22,723 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:22,836 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9f208908-f4e8-4d13-a4f1-e1f92cc4d0ad
2025-08-26 00:58:22,848 - DEBUG - =>VN ctest-mgmt_vn-50666041 has no policy to be verified
2025-08-26 00:58:22,848 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:22,848 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:22,848 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:22,862 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041?flat'}
2025-08-26 00:58:22,862 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:22,862 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:22,876 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041?flat'}
2025-08-26 00:58:22,876 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:22,876 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:22,889 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041?flat'}
2025-08-26 00:58:22,889 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38521069:ctest-mgmt_vn-50666041 is found in opserver
2025-08-26 00:58:22,928 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:22,956 - DEBUG - VRF ids for VN ctest-mgmt_vn-50666041: {}
2025-08-26 00:58:23,316 - DEBUG - Response for create_network : {'network': {'id': '1828158c-b69e-41ad-8068-5163ed82404a', 'name': 'ctest-left_vn-50139824', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'project_id': '6aabe554675e49e69354d45e9e02ecd7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38521069', 'ctest-left_vn-50139824'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:23,616 - DEBUG - Response for create_subnet : {'subnet': {'id': '91891bf1-c810-4845-8ecc-c058772fb0f4', 'name': '', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'network_id': '1828158c-b69e-41ad-8068-5163ed82404a', 'ip_version': 4, 'cidr': '1.142.216.192/26', 'allocation_pools': [{'start': '1.142.216.194', 'end': '1.142.216.254'}], 'gateway_ip': '1.142.216.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '1.142.216.194', 'tags': [], 'project_id': '6aabe554675e49e69354d45e9e02ecd7'}}
2025-08-26 00:58:23,641 - INFO - Created VN ctest-left_vn-50139824
2025-08-26 00:58:23,656 - DEBUG - VN ctest-left_vn-50139824 UUID is 1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:23,731 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:23,738 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:23,753 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:23,857 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:23,876 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:23,899 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5887c3db-b2cf-45cd-a81f-b8127df8d45c
2025-08-26 00:58:23,908 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5887c3db-b2cf-45cd-a81f-b8127df8d45c
2025-08-26 00:58:23,917 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/84b0e371-dae8-4694-8082-dbd270932f0b
2025-08-26 00:58:23,926 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:23,926 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:23,937 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5887c3db-b2cf-45cd-a81f-b8127df8d45c
2025-08-26 00:58:23,959 - INFO - Verified VN network id 12 for VN 1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:23,959 - INFO - Verifications in API Server for VN ctest-left_vn-50139824 passed
2025-08-26 00:58:23,959 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:23,970 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5887c3db-b2cf-45cd-a81f-b8127df8d45c
2025-08-26 00:58:23,979 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5887c3db-b2cf-45cd-a81f-b8127df8d45c
2025-08-26 00:58:23,988 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/84b0e371-dae8-4694-8082-dbd270932f0b
2025-08-26 00:58:24,004 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1740664950082126253', 'uuid-lslong': '9252734924061032522'}, 'enable': 'true', 'created': '2025-08-26T00:58:23', 'last-modified': '2025-08-26T00:58:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466932'}
2025-08-26 00:58:24,011 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:24,018 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1740664950082126253', 'uuid-lslong': '9252734924061032522'}, 'enable': 'true', 'created': '2025-08-26T00:58:23', 'last-modified': '2025-08-26T00:58:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484771'}
2025-08-26 00:58:24,027 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:24,033 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1740664950082126253', 'uuid-lslong': '9252734924061032522'}, 'enable': 'true', 'created': '2025-08-26T00:58:23', 'last-modified': '2025-08-26T00:58:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.502082'}
2025-08-26 00:58:24,040 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:24,040 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-50139824 passed
2025-08-26 00:58:24,040 - DEBUG - ====Verifying policy data for ctest-left_vn-50139824 in API_Server ======
2025-08-26 00:58:24,040 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:24,047 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:24,060 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:24,183 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/1828158c-b69e-41ad-8068-5163ed82404a
2025-08-26 00:58:24,195 - DEBUG - =>VN ctest-left_vn-50139824 has no policy to be verified
2025-08-26 00:58:24,196 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:24,196 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:24,196 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:24,209 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824?flat'}
2025-08-26 00:58:24,209 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:24,209 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:24,225 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824?flat'}
2025-08-26 00:58:24,226 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:24,226 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:24,241 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824?flat'}
2025-08-26 00:58:24,241 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38521069:ctest-left_vn-50139824 is found in opserver
2025-08-26 00:58:24,284 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:24,303 - DEBUG - VRF ids for VN ctest-left_vn-50139824: {}
2025-08-26 00:58:24,663 - DEBUG - Response for create_network : {'network': {'id': '256872e4-0f28-4374-be53-19e1d4040f16', 'name': 'ctest-right_vn-47152418', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'project_id': '6aabe554675e49e69354d45e9e02ecd7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38521069', 'ctest-right_vn-47152418'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:24,965 - DEBUG - Response for create_subnet : {'subnet': {'id': '881d8431-6b07-49cb-9edb-5769f3835ff2', 'name': '', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'network_id': '256872e4-0f28-4374-be53-19e1d4040f16', 'ip_version': 4, 'cidr': '154.51.113.128/26', 'allocation_pools': [{'start': '154.51.113.130', 'end': '154.51.113.190'}], 'gateway_ip': '154.51.113.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '154.51.113.130', 'tags': [], 'project_id': '6aabe554675e49e69354d45e9e02ecd7'}}
2025-08-26 00:58:24,993 - INFO - Created VN ctest-right_vn-47152418
2025-08-26 00:58:25,011 - DEBUG - VN ctest-right_vn-47152418 UUID is 256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,124 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:25,133 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:25,152 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:25,274 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,288 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,299 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0023a8fb-f063-4fc5-a0c4-ebff932e4e21
2025-08-26 00:58:25,308 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0023a8fb-f063-4fc5-a0c4-ebff932e4e21
2025-08-26 00:58:25,316 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/b2bbcd81-dd69-4ad3-aee2-d6a4e93b6d7c
2025-08-26 00:58:25,323 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:25,323 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,334 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0023a8fb-f063-4fc5-a0c4-ebff932e4e21
2025-08-26 00:58:25,358 - INFO - Verified VN network id 13 for VN 256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,358 - INFO - Verifications in API Server for VN ctest-right_vn-47152418 passed
2025-08-26 00:58:25,359 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,375 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0023a8fb-f063-4fc5-a0c4-ebff932e4e21
2025-08-26 00:58:25,387 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0023a8fb-f063-4fc5-a0c4-ebff932e4e21
2025-08-26 00:58:25,397 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/b2bbcd81-dd69-4ad3-aee2-d6a4e93b6d7c
2025-08-26 00:58:25,420 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2695530700813648756', 'uuid-lslong': '13714333747988664086'}, 'enable': 'true', 'created': '2025-08-26T00:58:24', 'last-modified': '2025-08-26T00:58:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517952'}
2025-08-26 00:58:25,432 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:25,439 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2695530700813648756', 'uuid-lslong': '13714333747988664086'}, 'enable': 'true', 'created': '2025-08-26T00:58:24', 'last-modified': '2025-08-26T00:58:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549021'}
2025-08-26 00:58:25,450 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:25,457 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2695530700813648756', 'uuid-lslong': '13714333747988664086'}, 'enable': 'true', 'created': '2025-08-26T00:58:24', 'last-modified': '2025-08-26T00:58:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563336'}
2025-08-26 00:58:25,465 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:25,465 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-47152418 passed
2025-08-26 00:58:25,465 - DEBUG - ====Verifying policy data for ctest-right_vn-47152418 in API_Server ======
2025-08-26 00:58:25,465 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:25,472 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:25,487 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:25,637 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/256872e4-0f28-4374-be53-19e1d4040f16
2025-08-26 00:58:25,671 - DEBUG - =>VN ctest-right_vn-47152418 has no policy to be verified
2025-08-26 00:58:25,671 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:25,671 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:25,671 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:25,691 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418?flat'}
2025-08-26 00:58:25,691 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:25,691 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:25,710 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418?flat'}
2025-08-26 00:58:25,710 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:25,710 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:25,735 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418?flat'}
2025-08-26 00:58:25,735 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38521069:ctest-right_vn-47152418 is found in opserver
2025-08-26 00:58:25,774 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:25,792 - DEBUG - VRF ids for VN ctest-right_vn-47152418: {}
2025-08-26 00:58:26,120 - DEBUG - Response for create_network : {'network': {'id': 'd06f8c8f-ed44-4ba4-bb29-f1d90aa83370', 'name': 'ctest-trans_left_vn-09387701', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'project_id': '6aabe554675e49e69354d45e9e02ecd7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38521069', 'ctest-trans_left_vn-09387701'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:26,355 - DEBUG - Response for create_subnet : {'subnet': {'id': '0868cf46-10cf-4027-bdc8-d8f235c452ff', 'name': '', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'network_id': 'd06f8c8f-ed44-4ba4-bb29-f1d90aa83370', 'ip_version': 4, 'cidr': '20.249.9.192/26', 'allocation_pools': [{'start': '20.249.9.194', 'end': '20.249.9.254'}], 'gateway_ip': '20.249.9.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.249.9.194', 'tags': [], 'project_id': '6aabe554675e49e69354d45e9e02ecd7'}}
2025-08-26 00:58:26,378 - INFO - Created VN ctest-trans_left_vn-09387701
2025-08-26 00:58:26,391 - DEBUG - VN ctest-trans_left_vn-09387701 UUID is d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:26,470 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:26,477 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:26,499 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:26,641 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:26,655 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:26,675 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/7ad9e10a-14e3-43f3-b8c5-5911cd4f9ba7
2025-08-26 00:58:26,687 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/7ad9e10a-14e3-43f3-b8c5-5911cd4f9ba7
2025-08-26 00:58:26,699 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/eb4ec7ab-d1b9-49b5-9bb0-6daf2fcd1f7b
2025-08-26 00:58:26,715 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-26 00:58:26,715 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:26,727 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/7ad9e10a-14e3-43f3-b8c5-5911cd4f9ba7
2025-08-26 00:58:26,752 - INFO - Verified VN network id 14 for VN d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:26,753 - INFO - Verifications in API Server for VN ctest-trans_left_vn-09387701 passed
2025-08-26 00:58:26,753 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:26,765 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/7ad9e10a-14e3-43f3-b8c5-5911cd4f9ba7
2025-08-26 00:58:26,775 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/7ad9e10a-14e3-43f3-b8c5-5911cd4f9ba7
2025-08-26 00:58:26,785 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/eb4ec7ab-d1b9-49b5-9bb0-6daf2fcd1f7b
2025-08-26 00:58:26,802 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15019377832092781476', 'uuid-lslong': '13486576473626653552'}, 'enable': 'true', 'created': '2025-08-26T00:58:26', 'last-modified': '2025-08-26T00:58:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512050'}
2025-08-26 00:58:26,808 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-26 00:58:26,816 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15019377832092781476', 'uuid-lslong': '13486576473626653552'}, 'enable': 'true', 'created': '2025-08-26T00:58:26', 'last-modified': '2025-08-26T00:58:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.525859'}
2025-08-26 00:58:26,824 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-26 00:58:26,832 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15019377832092781476', 'uuid-lslong': '13486576473626653552'}, 'enable': 'true', 'created': '2025-08-26T00:58:26', 'last-modified': '2025-08-26T00:58:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534925'}
2025-08-26 00:58:26,842 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-26 00:58:26,842 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-09387701 passed
2025-08-26 00:58:26,842 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-09387701 in API_Server ======
2025-08-26 00:58:26,843 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:26,850 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:26,869 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:27,005 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/d06f8c8f-ed44-4ba4-bb29-f1d90aa83370
2025-08-26 00:58:27,019 - DEBUG - =>VN ctest-trans_left_vn-09387701 has no policy to be verified
2025-08-26 00:58:27,019 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:27,019 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:27,019 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:27,033 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701?flat'}
2025-08-26 00:58:27,033 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:27,033 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:27,047 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701?flat'}
2025-08-26 00:58:27,048 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:27,048 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:27,071 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701?flat'}
2025-08-26 00:58:27,071 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38521069:ctest-trans_left_vn-09387701 is found in opserver
2025-08-26 00:58:27,114 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:27,135 - DEBUG - VRF ids for VN ctest-trans_left_vn-09387701: {}
2025-08-26 00:58:27,486 - DEBUG - Response for create_network : {'network': {'id': '97db4299-c98c-4a62-819b-3ea095b4b38d', 'name': 'ctest-trans_right_vn-65150296', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'project_id': '6aabe554675e49e69354d45e9e02ecd7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38521069', 'ctest-trans_right_vn-65150296'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:27,727 - DEBUG - Response for create_subnet : {'subnet': {'id': '121de0db-031d-4e64-915d-5963d2e28f2b', 'name': '', 'tenant_id': '6aabe554675e49e69354d45e9e02ecd7', 'network_id': '97db4299-c98c-4a62-819b-3ea095b4b38d', 'ip_version': 4, 'cidr': '17.160.107.192/26', 'allocation_pools': [{'start': '17.160.107.194', 'end': '17.160.107.254'}], 'gateway_ip': '17.160.107.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.160.107.194', 'tags': [], 'project_id': '6aabe554675e49e69354d45e9e02ecd7'}}
2025-08-26 00:58:27,749 - INFO - Created VN ctest-trans_right_vn-65150296
2025-08-26 00:58:27,767 - DEBUG - VN ctest-trans_right_vn-65150296 UUID is 97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:27,851 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:27,858 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:27,875 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:27,995 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:28,010 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:28,027 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/941995bb-2c8b-4639-8db7-df311bfbf7be
2025-08-26 00:58:28,040 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/941995bb-2c8b-4639-8db7-df311bfbf7be
2025-08-26 00:58:28,051 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/13c0d781-5d33-4cae-8a1f-974d854aa91f
2025-08-26 00:58:28,063 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-26 00:58:28,063 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:28,075 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/941995bb-2c8b-4639-8db7-df311bfbf7be
2025-08-26 00:58:28,093 - INFO - Verified VN network id 15 for VN 97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:28,094 - INFO - Verifications in API Server for VN ctest-trans_right_vn-65150296 passed
2025-08-26 00:58:28,094 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:28,105 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/941995bb-2c8b-4639-8db7-df311bfbf7be
2025-08-26 00:58:28,123 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/941995bb-2c8b-4639-8db7-df311bfbf7be
2025-08-26 00:58:28,131 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/13c0d781-5d33-4cae-8a1f-974d854aa91f
2025-08-26 00:58:28,146 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10942412947905596002', 'uuid-lslong': '9339127111710192525'}, 'enable': 'true', 'created': '2025-08-26T00:58:27', 'last-modified': '2025-08-26T00:58:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486822'}
2025-08-26 00:58:28,152 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-26 00:58:28,158 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10942412947905596002', 'uuid-lslong': '9339127111710192525'}, 'enable': 'true', 'created': '2025-08-26T00:58:27', 'last-modified': '2025-08-26T00:58:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.496991'}
2025-08-26 00:58:28,172 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-26 00:58:28,179 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38521069', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10942412947905596002', 'uuid-lslong': '9339127111710192525'}, 'enable': 'true', 'created': '2025-08-26T00:58:27', 'last-modified': '2025-08-26T00:58:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.516598'}
2025-08-26 00:58:28,188 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-26 00:58:28,188 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-65150296 passed
2025-08-26 00:58:28,188 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-65150296 in API_Server ======
2025-08-26 00:58:28,188 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:28,196 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:28,209 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:28,323 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/97db4299-c98c-4a62-819b-3ea095b4b38d
2025-08-26 00:58:28,339 - DEBUG - =>VN ctest-trans_right_vn-65150296 has no policy to be verified
2025-08-26 00:58:28,339 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:28,339 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:28,339 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:28,353 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296?flat'}
2025-08-26 00:58:28,353 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:28,353 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:28,376 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296?flat'}
2025-08-26 00:58:28,376 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:28,376 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:28,401 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296?flat'}
2025-08-26 00:58:28,401 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38521069:ctest-trans_right_vn-65150296 is found in opserver
2025-08-26 00:58:28,442 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:28,463 - DEBUG - VRF ids for VN ctest-trans_right_vn-65150296: {}
2025-08-26 00:58:29,744 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-1)
2025-08-26 00:58:31,680 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-2)
2025-08-26 00:58:31,680 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-98688060']
2025-08-26 00:58:32,015 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-98688060'] created succesfully
2025-08-26 00:58:32,402 - DEBUG - Image by name tiny_trans_fw not found
2025-08-26 00:58:32,402 - DEBUG - Installing image tiny_trans_fw
2025-08-26 00:58:32,402 - 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-08-26 00:58:32,402 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-26 00:58:32,402 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-26 00:58:32,440 - 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-08-26 00:58:32,472 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-26 00:58:32,472 - DEBUG - Unzip image
2025-08-26 00:58:32,472 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-26 00:58:32,519 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-26 00:58:32,519 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-98688060']
2025-08-26 00:58:32,651 - INFO - Service template: ['default-domain', 'ctest-service_template_1-98688060'] deleted successfully.
2025-08-26 00:58:32,651 - INFO - Skip interface_detach for VM ctest-right_vm-43437634 in state BUILD
2025-08-26 00:58:32,651 - INFO - Deleting VM ctest-right_vm-43437634
2025-08-26 00:58:32,652 - ERROR - VM ctest-right_vm-43437634 has failed to come up
2025-08-26 00:58:32,652 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:32,652 - ERROR - VM ctest-right_vm-43437634 has failed to come up
2025-08-26 00:58:32,652 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:32,766 - INFO - Skip interface_detach for VM ctest-left_vm-95445016 in state BUILD
2025-08-26 00:58:32,766 - INFO - Deleting VM ctest-left_vm-95445016
2025-08-26 00:58:32,766 - ERROR - VM ctest-left_vm-95445016 has failed to come up
2025-08-26 00:58:32,766 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:32,767 - ERROR - VM ctest-left_vm-95445016 has failed to come up
2025-08-26 00:58:32,767 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:32,895 - INFO - Deleting VN ctest-trans_right_vn-65150296
2025-08-26 00:58:33,137 - DEBUG - Response for deleting network ()
2025-08-26 00:58:33,137 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/941995bb-2c8b-4639-8db7-df311bfbf7be
2025-08-26 00:58:33,169 - DEBUG - Response Code: 404
2025-08-26 00:58:33,169 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:33,193 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:33,212 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:33,339 - INFO - Validated that VN ctest-trans_right_vn-65150296 is not found in API Server
2025-08-26 00:58:33,353 - DEBUG - VN ctest-trans_right_vn-65150296 is not present in Agent 10.0.0.137
2025-08-26 00:58:33,384 - DEBUG - VN ctest-trans_right_vn-65150296 is not present in Agent 10.0.0.41
2025-08-26 00:58:33,385 - INFO - Validated that VN ctest-trans_right_vn-65150296 is not in any agent
2025-08-26 00:58:33,450 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-65150296 info
2025-08-26 00:58:33,450 - INFO - Deleting VN ctest-trans_left_vn-09387701
2025-08-26 00:58:33,796 - DEBUG - Response for deleting network ()
2025-08-26 00:58:33,796 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/7ad9e10a-14e3-43f3-b8c5-5911cd4f9ba7
2025-08-26 00:58:33,809 - DEBUG - Response Code: 404
2025-08-26 00:58:33,809 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:33,834 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:33,854 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:33,949 - INFO - Validated that VN ctest-trans_left_vn-09387701 is not found in API Server
2025-08-26 00:58:33,973 - DEBUG - VN ctest-trans_left_vn-09387701 is not present in Agent 10.0.0.137
2025-08-26 00:58:33,995 - DEBUG - VN ctest-trans_left_vn-09387701 is not present in Agent 10.0.0.41
2025-08-26 00:58:33,995 - INFO - Validated that VN ctest-trans_left_vn-09387701 is not in any agent
2025-08-26 00:58:34,041 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-09387701 info
2025-08-26 00:58:34,041 - INFO - Deleting VN ctest-right_vn-47152418
2025-08-26 00:58:34,085 - DEBUG - VN 256872e4-0f28-4374-be53-19e1d4040f16 still in use: Unable to complete operation on network 256872e4-0f28-4374-be53-19e1d4040f16. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4cdb9066-fbae-4ebb-ac1e-47fc68848416']
2025-08-26 00:58:34,085 - WARNING - Deleting VN ctest-right_vn-47152418 failed..Will retry
2025-08-26 00:58:36,285 - DEBUG - Response for deleting network ()
2025-08-26 00:58:36,285 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0023a8fb-f063-4fc5-a0c4-ebff932e4e21
2025-08-26 00:58:36,291 - DEBUG - Response Code: 404
2025-08-26 00:58:36,292 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:36,299 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:36,358 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:36,482 - INFO - Validated that VN ctest-right_vn-47152418 is not found in API Server
2025-08-26 00:58:36,497 - DEBUG - VN ctest-right_vn-47152418 is not present in Agent 10.0.0.137
2025-08-26 00:58:36,525 - DEBUG - VN ctest-right_vn-47152418 is not present in Agent 10.0.0.41
2025-08-26 00:58:36,525 - INFO - Validated that VN ctest-right_vn-47152418 is not in any agent
2025-08-26 00:58:36,579 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-47152418 info
2025-08-26 00:58:36,579 - INFO - Deleting VN ctest-left_vn-50139824
2025-08-26 00:58:36,778 - DEBUG - Response for deleting network ()
2025-08-26 00:58:36,778 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5887c3db-b2cf-45cd-a81f-b8127df8d45c
2025-08-26 00:58:36,784 - DEBUG - Response Code: 404
2025-08-26 00:58:36,784 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:36,793 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:36,812 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:36,911 - INFO - Validated that VN ctest-left_vn-50139824 is not found in API Server
2025-08-26 00:58:36,924 - DEBUG - VN ctest-left_vn-50139824 is not present in Agent 10.0.0.137
2025-08-26 00:58:36,954 - DEBUG - VN ctest-left_vn-50139824 is not present in Agent 10.0.0.41
2025-08-26 00:58:36,954 - INFO - Validated that VN ctest-left_vn-50139824 is not in any agent
2025-08-26 00:58:37,002 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-50139824 info
2025-08-26 00:58:37,002 - INFO - Deleting VN ctest-mgmt_vn-50666041
2025-08-26 00:58:37,221 - DEBUG - Response for deleting network ()
2025-08-26 00:58:37,221 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/29379946-8f09-481c-9856-e3488c25c5f7
2025-08-26 00:58:37,233 - DEBUG - Response Code: 404
2025-08-26 00:58:37,233 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:37,242 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:37,262 - DEBUG - Requesting: http://10.0.0.49:8082/project/6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:37,379 - INFO - Validated that VN ctest-mgmt_vn-50666041 is not found in API Server
2025-08-26 00:58:37,398 - DEBUG - VN ctest-mgmt_vn-50666041 is not present in Agent 10.0.0.137
2025-08-26 00:58:37,432 - DEBUG - VN ctest-mgmt_vn-50666041 is not present in Agent 10.0.0.41
2025-08-26 00:58:37,432 - INFO - Validated that VN ctest-mgmt_vn-50666041 is not in any agent
2025-08-26 00:58:37,481 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-50666041 info
2025-08-26 00:58:38,873 - DEBUG - No XMPP flaps were noticed during the test
2025-08-26 00:58:38,873 - INFO -
2025-08-26 00:58:38,873 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:18]
2025-08-26 00:58:38,873 - INFO - --------------------------------------------------------------------------------
2025-08-26 00:58:39,852 - INFO - Deleted project: ctest-TestSvcRegr-38521069, ID : 6aabe554-675e-49e6-9354-d45e9e02ecd7
2025-08-26 00:58:40,055 - INFO - Domain Default found not creating
2025-08-26 00:58:40,207 - INFO - Project ctest-TestSvcRegr-53231971 not found, creating it
2025-08-26 00:58:40,878 - INFO - Created Project:ctest-TestSvcRegr-53231971, ID : 35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:42,756 - INFO - ================================================================================
2025-08-26 00:58:42,756 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-26 00:58:44,125 - DEBUG - Nothing to compare xmpp stats {'10.0.0.137': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-26 00:58:44,125 - INFO - Initial checks done. Running the testcase now
2025-08-26 00:58:44,125 - INFO -
2025-08-26 00:58:44,960 - DEBUG - Response for create_network : {'network': {'id': '2567f396-f838-475d-bc3c-b56ababd4854', 'name': 'ctest-mgmt_vn-56336161', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-53231971', 'ctest-mgmt_vn-56336161'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:45,337 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd03f883e-c9b7-4aeb-b56f-dd205f170cb1', 'name': '', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'network_id': '2567f396-f838-475d-bc3c-b56ababd4854', 'ip_version': 4, 'cidr': '163.107.221.192/26', 'allocation_pools': [{'start': '163.107.221.194', 'end': '163.107.221.254'}], 'gateway_ip': '163.107.221.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '163.107.221.194', 'tags': [], 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee'}}
2025-08-26 00:58:45,365 - INFO - Created VN ctest-mgmt_vn-56336161
2025-08-26 00:58:45,379 - DEBUG - VN ctest-mgmt_vn-56336161 UUID is 2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:45,665 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:46,066 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:46,095 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:46,113 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:46,243 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:46,263 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:46,275 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/eaa7502f-3cdf-40ab-8251-36fc544a3511
2025-08-26 00:58:46,298 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/eaa7502f-3cdf-40ab-8251-36fc544a3511
2025-08-26 00:58:46,307 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/9fdf73fa-9c13-485a-9a62-e7c23c1f74b0
2025-08-26 00:58:46,318 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:46,318 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:46,331 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/eaa7502f-3cdf-40ab-8251-36fc544a3511
2025-08-26 00:58:46,354 - INFO - Verified VN network id 11 for VN 2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:46,354 - INFO - Verifications in API Server for VN ctest-mgmt_vn-56336161 passed
2025-08-26 00:58:46,354 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:46,369 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/eaa7502f-3cdf-40ab-8251-36fc544a3511
2025-08-26 00:58:46,380 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/eaa7502f-3cdf-40ab-8251-36fc544a3511
2025-08-26 00:58:46,391 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/9fdf73fa-9c13-485a-9a62-e7c23c1f74b0
2025-08-26 00:58:46,412 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2695390731739613021', 'uuid-lslong': '13563915647737219156'}, 'enable': 'true', 'created': '2025-08-26T00:58:44', 'last-modified': '2025-08-26T00:58:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.159138'}
2025-08-26 00:58:46,423 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:46,431 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2695390731739613021', 'uuid-lslong': '13563915647737219156'}, 'enable': 'true', 'created': '2025-08-26T00:58:44', 'last-modified': '2025-08-26T00:58:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.177181'}
2025-08-26 00:58:46,441 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:46,449 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2695390731739613021', 'uuid-lslong': '13563915647737219156'}, 'enable': 'true', 'created': '2025-08-26T00:58:44', 'last-modified': '2025-08-26T00:58:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.194679'}
2025-08-26 00:58:46,470 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:58:46,473 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-56336161 passed
2025-08-26 00:58:46,473 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-56336161 in API_Server ======
2025-08-26 00:58:46,474 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:46,483 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:46,511 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:46,632 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/2567f396-f838-475d-bc3c-b56ababd4854
2025-08-26 00:58:46,645 - DEBUG - =>VN ctest-mgmt_vn-56336161 has no policy to be verified
2025-08-26 00:58:46,645 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:46,646 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:46,646 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:47,007 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:47,083 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161?flat'}
2025-08-26 00:58:47,083 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:47,083 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:47,436 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:47,523 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161?flat'}
2025-08-26 00:58:47,523 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:47,524 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:47,905 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:47,975 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161?flat'}
2025-08-26 00:58:47,975 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-56336161 is found in opserver
2025-08-26 00:58:48,024 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:48,044 - DEBUG - VRF ids for VN ctest-mgmt_vn-56336161: {}
2025-08-26 00:58:48,447 - DEBUG - Response for create_network : {'network': {'id': '31d05227-4c48-429f-8f9d-b57a1e5e9768', 'name': 'service-left-vn-16057468', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-53231971', 'service-left-vn-16057468'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:48,747 - DEBUG - Response for create_subnet : {'subnet': {'id': '3c9cffdf-0354-4cc1-8dd4-82f942de9d64', 'name': '', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'network_id': '31d05227-4c48-429f-8f9d-b57a1e5e9768', 'ip_version': 4, 'cidr': '124.120.68.192/26', 'allocation_pools': [{'start': '124.120.68.194', 'end': '124.120.68.254'}], 'gateway_ip': '124.120.68.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '124.120.68.194', 'tags': [], 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee'}}
2025-08-26 00:58:48,771 - INFO - Created VN service-left-vn-16057468
2025-08-26 00:58:48,790 - DEBUG - VN service-left-vn-16057468 UUID is 31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:48,906 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:48,913 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:48,927 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:49,037 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:49,057 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:49,083 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5932861a-1439-494c-8d38-198528fd1af4
2025-08-26 00:58:49,097 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5932861a-1439-494c-8d38-198528fd1af4
2025-08-26 00:58:49,112 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/b92ba1ce-2840-48e4-bff5-3c35a9745b0e
2025-08-26 00:58:49,122 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:49,122 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:49,137 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5932861a-1439-494c-8d38-198528fd1af4
2025-08-26 00:58:49,162 - INFO - Verified VN network id 12 for VN 31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:49,162 - INFO - Verifications in API Server for VN service-left-vn-16057468 passed
2025-08-26 00:58:49,163 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:49,181 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5932861a-1439-494c-8d38-198528fd1af4
2025-08-26 00:58:49,194 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5932861a-1439-494c-8d38-198528fd1af4
2025-08-26 00:58:49,203 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/b92ba1ce-2840-48e4-bff5-3c35a9745b0e
2025-08-26 00:58:49,220 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3589459231751291551', 'uuid-lslong': '10348627054867421032'}, 'enable': 'true', 'created': '2025-08-26T00:58:48', 'last-modified': '2025-08-26T00:58:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563466'}
2025-08-26 00:58:49,228 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:49,236 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3589459231751291551', 'uuid-lslong': '10348627054867421032'}, 'enable': 'true', 'created': '2025-08-26T00:58:48', 'last-modified': '2025-08-26T00:58:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.592524'}
2025-08-26 00:58:49,244 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:49,250 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3589459231751291551', 'uuid-lslong': '10348627054867421032'}, 'enable': 'true', 'created': '2025-08-26T00:58:48', 'last-modified': '2025-08-26T00:58:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.581778'}
2025-08-26 00:58:49,262 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:58:49,262 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-16057468 passed
2025-08-26 00:58:49,262 - DEBUG - ====Verifying policy data for service-left-vn-16057468 in API_Server ======
2025-08-26 00:58:49,262 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:49,269 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:49,281 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:49,415 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/31d05227-4c48-429f-8f9d-b57a1e5e9768
2025-08-26 00:58:49,426 - DEBUG - =>VN service-left-vn-16057468 has no policy to be verified
2025-08-26 00:58:49,426 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:49,426 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:49,426 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:49,503 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468?flat'}
2025-08-26 00:58:49,503 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:49,504 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:49,578 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468?flat'}
2025-08-26 00:58:49,578 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:49,578 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:49,667 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468?flat'}
2025-08-26 00:58:49,667 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-53231971:service-left-vn-16057468 is found in opserver
2025-08-26 00:58:49,711 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:49,731 - DEBUG - VRF ids for VN service-left-vn-16057468: {}
2025-08-26 00:58:50,155 - DEBUG - Response for create_network : {'network': {'id': '048bfc82-896f-40f4-a1f9-8d3477197c20', 'name': 'service-right-vn-77162083', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-53231971', 'service-right-vn-77162083'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:58:50,464 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd333541f-7463-4e75-bffb-e6de1bb0840d', 'name': '', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'network_id': '048bfc82-896f-40f4-a1f9-8d3477197c20', 'ip_version': 4, 'cidr': '76.4.193.64/26', 'allocation_pools': [{'start': '76.4.193.66', 'end': '76.4.193.126'}], 'gateway_ip': '76.4.193.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.4.193.66', 'tags': [], 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee'}}
2025-08-26 00:58:50,490 - INFO - Created VN service-right-vn-77162083
2025-08-26 00:58:50,507 - DEBUG - VN service-right-vn-77162083 UUID is 048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:50,593 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:50,603 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:50,620 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:50,747 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:50,764 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:50,776 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/1ec59550-15ec-4653-b678-40e0dd2a87cb
2025-08-26 00:58:50,785 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/1ec59550-15ec-4653-b678-40e0dd2a87cb
2025-08-26 00:58:50,794 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/e7955134-7595-4de8-a98d-155695337dde
2025-08-26 00:58:50,804 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:50,804 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:50,836 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/1ec59550-15ec-4653-b678-40e0dd2a87cb
2025-08-26 00:58:50,854 - INFO - Verified VN network id 13 for VN 048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:50,855 - INFO - Verifications in API Server for VN service-right-vn-77162083 passed
2025-08-26 00:58:50,855 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:50,875 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/1ec59550-15ec-4653-b678-40e0dd2a87cb
2025-08-26 00:58:50,893 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/1ec59550-15ec-4653-b678-40e0dd2a87cb
2025-08-26 00:58:50,914 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/e7955134-7595-4de8-a98d-155695337dde
2025-08-26 00:58:50,933 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '327633035496210676', 'uuid-lslong': '11671515165783325728'}, 'enable': 'true', 'created': '2025-08-26T00:58:50', 'last-modified': '2025-08-26T00:58:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.568619'}
2025-08-26 00:58:50,943 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:50,950 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '327633035496210676', 'uuid-lslong': '11671515165783325728'}, 'enable': 'true', 'created': '2025-08-26T00:58:50', 'last-modified': '2025-08-26T00:58:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.587132'}
2025-08-26 00:58:50,958 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:50,970 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '327633035496210676', 'uuid-lslong': '11671515165783325728'}, 'enable': 'true', 'created': '2025-08-26T00:58:50', 'last-modified': '2025-08-26T00:58:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.600297'}
2025-08-26 00:58:50,986 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:58:50,986 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-77162083 passed
2025-08-26 00:58:50,986 - DEBUG - ====Verifying policy data for service-right-vn-77162083 in API_Server ======
2025-08-26 00:58:50,986 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:50,994 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:51,010 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:51,124 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/048bfc82-896f-40f4-a1f9-8d3477197c20
2025-08-26 00:58:51,147 - DEBUG - =>VN service-right-vn-77162083 has no policy to be verified
2025-08-26 00:58:51,147 - DEBUG - Verifying the vn in opserver
2025-08-26 00:58:51,147 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083 virtual network link through opserver 10.0.0.49
2025-08-26 00:58:51,147 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:58:51,214 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083?flat'}
2025-08-26 00:58:51,214 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083 virtual network link through opserver 10.0.0.33
2025-08-26 00:58:51,214 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:58:51,303 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083?flat'}
2025-08-26 00:58:51,303 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083 virtual network link through opserver 10.0.0.254
2025-08-26 00:58:51,303 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:58:51,376 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083?flat'}
2025-08-26 00:58:51,376 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-53231971:service-right-vn-77162083 is found in opserver
2025-08-26 00:58:51,417 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:58:51,436 - DEBUG - VRF ids for VN service-right-vn-77162083: {}
2025-08-26 00:58:51,533 - DEBUG - Services list from nova: [, , , ]
2025-08-26 00:58:52,768 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-1)
2025-08-26 00:58:54,700 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-2)
2025-08-26 00:58:54,701 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-20769869']
2025-08-26 00:58:55,048 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-20769869'] created succesfully
2025-08-26 00:58:55,484 - DEBUG - Image by name tiny_nat_fw not found
2025-08-26 00:58:55,484 - DEBUG - Installing image tiny_nat_fw
2025-08-26 00:58:55,484 - 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-08-26 00:58:55,484 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:55,485 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:55,485 - DEBUG - Unzip image
2025-08-26 00:58:55,485 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:58:55,503 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-26 00:58:55,505 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-20769869']
2025-08-26 00:58:55,727 - INFO - Service template: ['default-domain', 'ctest-service_template_1-20769869'] deleted successfully.
2025-08-26 00:58:55,727 - INFO - Skip interface_detach for VM ctest-right_vm-73664706 in state BUILD
2025-08-26 00:58:55,727 - INFO - Deleting VM ctest-right_vm-73664706
2025-08-26 00:58:55,777 - ERROR - VM ctest-right_vm-73664706 has failed to come up
2025-08-26 00:58:55,778 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:55,778 - ERROR - VM ctest-right_vm-73664706 has failed to come up
2025-08-26 00:58:55,779 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:55,951 - INFO - Skip interface_detach for VM ctest-left_vm-04864026 in state BUILD
2025-08-26 00:58:55,952 - INFO - Deleting VM ctest-left_vm-04864026
2025-08-26 00:58:55,952 - ERROR - VM ctest-left_vm-04864026 has failed to come up
2025-08-26 00:58:55,952 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:55,952 - ERROR - VM ctest-left_vm-04864026 has failed to come up
2025-08-26 00:58:55,952 - ERROR - Nova failed to get host of the VM
2025-08-26 00:58:56,080 - INFO - Deleting VN service-right-vn-77162083
2025-08-26 00:58:56,157 - DEBUG - VN 048bfc82-896f-40f4-a1f9-8d3477197c20 still in use: Unable to complete operation on network 048bfc82-896f-40f4-a1f9-8d3477197c20. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7fdf0a2c-4196-44e2-8cb0-ce73d0d26492']
2025-08-26 00:58:56,157 - WARNING - Deleting VN service-right-vn-77162083 failed..Will retry
2025-08-26 00:58:58,328 - DEBUG - Response for deleting network ()
2025-08-26 00:58:58,328 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/1ec59550-15ec-4653-b678-40e0dd2a87cb
2025-08-26 00:58:58,345 - DEBUG - Response Code: 404
2025-08-26 00:58:58,345 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:58,355 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:58,377 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:58,504 - INFO - Validated that VN service-right-vn-77162083 is not found in API Server
2025-08-26 00:58:58,517 - DEBUG - VN service-right-vn-77162083 is not present in Agent 10.0.0.137
2025-08-26 00:58:58,571 - DEBUG - VN service-right-vn-77162083 is not present in Agent 10.0.0.41
2025-08-26 00:58:58,571 - INFO - Validated that VN service-right-vn-77162083 is not in any agent
2025-08-26 00:58:58,645 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-77162083 info
2025-08-26 00:58:58,645 - INFO - Deleting VN service-left-vn-16057468
2025-08-26 00:58:58,876 - DEBUG - Response for deleting network ()
2025-08-26 00:58:58,876 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/5932861a-1439-494c-8d38-198528fd1af4
2025-08-26 00:58:58,883 - DEBUG - Response Code: 404
2025-08-26 00:58:58,883 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:58,894 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:58,911 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:59,046 - INFO - Validated that VN service-left-vn-16057468 is not found in API Server
2025-08-26 00:58:59,064 - DEBUG - VN service-left-vn-16057468 is not present in Agent 10.0.0.137
2025-08-26 00:58:59,106 - DEBUG - VN service-left-vn-16057468 is not present in Agent 10.0.0.41
2025-08-26 00:58:59,106 - INFO - Validated that VN service-left-vn-16057468 is not in any agent
2025-08-26 00:58:59,178 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-16057468 info
2025-08-26 00:58:59,178 - INFO - Deleting VN ctest-mgmt_vn-56336161
2025-08-26 00:58:59,448 - DEBUG - Response for deleting network ()
2025-08-26 00:58:59,448 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/eaa7502f-3cdf-40ab-8251-36fc544a3511
2025-08-26 00:58:59,456 - DEBUG - Response Code: 404
2025-08-26 00:58:59,456 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:58:59,464 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:58:59,485 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:58:59,624 - INFO - Validated that VN ctest-mgmt_vn-56336161 is not found in API Server
2025-08-26 00:58:59,638 - DEBUG - VN ctest-mgmt_vn-56336161 is not present in Agent 10.0.0.137
2025-08-26 00:58:59,664 - DEBUG - VN ctest-mgmt_vn-56336161 is not present in Agent 10.0.0.41
2025-08-26 00:58:59,664 - INFO - Validated that VN ctest-mgmt_vn-56336161 is not in any agent
2025-08-26 00:58:59,716 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-56336161 info
2025-08-26 00:59:01,132 - DEBUG - No XMPP flaps were noticed during the test
2025-08-26 00:59:01,132 - INFO -
2025-08-26 00:59:01,132 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:19]
2025-08-26 00:59:01,132 - INFO - --------------------------------------------------------------------------------
2025-08-26 00:59:01,138 - INFO - ================================================================================
2025-08-26 00:59:01,139 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-26 00:59:02,533 - DEBUG - Nothing to compare xmpp stats {'10.0.0.137': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-26 00:59:02,533 - INFO - Initial checks done. Running the testcase now
2025-08-26 00:59:02,533 - INFO -
2025-08-26 00:59:02,892 - DEBUG - Response for create_network : {'network': {'id': '502c1169-0ac3-4ab3-b30a-169c10138d4f', 'name': 'ctest-mgmt_vn-89882083', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-53231971', 'ctest-mgmt_vn-89882083'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:59:03,135 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dd172061-1941-4b16-b7b5-2a6ddaba7e1a', 'name': '', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'network_id': '502c1169-0ac3-4ab3-b30a-169c10138d4f', 'ip_version': 4, 'cidr': '112.149.228.128/26', 'allocation_pools': [{'start': '112.149.228.130', 'end': '112.149.228.190'}], 'gateway_ip': '112.149.228.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.149.228.130', 'tags': [], 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee'}}
2025-08-26 00:59:03,160 - INFO - Created VN ctest-mgmt_vn-89882083
2025-08-26 00:59:03,175 - DEBUG - VN ctest-mgmt_vn-89882083 UUID is 502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,258 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:03,266 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:03,279 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:03,403 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,420 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,435 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/4040a100-1f5f-4e2a-b858-d58610c810c5
2025-08-26 00:59:03,447 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/4040a100-1f5f-4e2a-b858-d58610c810c5
2025-08-26 00:59:03,461 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/4c93d822-20d2-47a1-bdad-35225fea48c1
2025-08-26 00:59:03,473 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:59:03,473 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,496 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/4040a100-1f5f-4e2a-b858-d58610c810c5
2025-08-26 00:59:03,521 - INFO - Verified VN network id 11 for VN 502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,521 - INFO - Verifications in API Server for VN ctest-mgmt_vn-89882083 passed
2025-08-26 00:59:03,522 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,541 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/4040a100-1f5f-4e2a-b858-d58610c810c5
2025-08-26 00:59:03,550 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/4040a100-1f5f-4e2a-b858-d58610c810c5
2025-08-26 00:59:03,560 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/4c93d822-20d2-47a1-bdad-35225fea48c1
2025-08-26 00:59:03,578 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5777011564859312819', 'uuid-lslong': '12901148942096633167'}, 'enable': 'true', 'created': '2025-08-26T00:59:02', 'last-modified': '2025-08-26T00:59:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.509304'}
2025-08-26 00:59:03,587 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:59:03,594 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5777011564859312819', 'uuid-lslong': '12901148942096633167'}, 'enable': 'true', 'created': '2025-08-26T00:59:02', 'last-modified': '2025-08-26T00:59:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.522902'}
2025-08-26 00:59:03,601 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:59:03,608 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5777011564859312819', 'uuid-lslong': '12901148942096633167'}, 'enable': 'true', 'created': '2025-08-26T00:59:02', 'last-modified': '2025-08-26T00:59:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.539559'}
2025-08-26 00:59:03,617 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-26 00:59:03,617 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-89882083 passed
2025-08-26 00:59:03,618 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-89882083 in API_Server ======
2025-08-26 00:59:03,618 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:03,625 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:03,640 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:03,774 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/502c1169-0ac3-4ab3-b30a-169c10138d4f
2025-08-26 00:59:03,791 - DEBUG - =>VN ctest-mgmt_vn-89882083 has no policy to be verified
2025-08-26 00:59:03,792 - DEBUG - Verifying the vn in opserver
2025-08-26 00:59:03,792 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083 virtual network link through opserver 10.0.0.49
2025-08-26 00:59:03,792 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:59:03,806 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083?flat'}
2025-08-26 00:59:03,806 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083 virtual network link through opserver 10.0.0.33
2025-08-26 00:59:03,806 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:59:03,823 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083?flat'}
2025-08-26 00:59:03,823 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083 virtual network link through opserver 10.0.0.254
2025-08-26 00:59:03,823 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:59:03,841 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083?flat'}
2025-08-26 00:59:03,841 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-53231971:ctest-mgmt_vn-89882083 is found in opserver
2025-08-26 00:59:03,886 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:59:03,908 - DEBUG - VRF ids for VN ctest-mgmt_vn-89882083: {}
2025-08-26 00:59:04,295 - DEBUG - Response for create_network : {'network': {'id': 'f74dbc70-915b-480f-985d-9fd9df677eac', 'name': 'ctest-left_vn-92805771', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-53231971', 'ctest-left_vn-92805771'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:59:04,605 - DEBUG - Response for create_subnet : {'subnet': {'id': '148f6a1f-e3d6-4521-9ef6-e862d37a24b5', 'name': '', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'network_id': 'f74dbc70-915b-480f-985d-9fd9df677eac', 'ip_version': 4, 'cidr': '164.160.76.0/26', 'allocation_pools': [{'start': '164.160.76.2', 'end': '164.160.76.62'}], 'gateway_ip': '164.160.76.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.160.76.2', 'tags': [], 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee'}}
2025-08-26 00:59:04,635 - INFO - Created VN ctest-left_vn-92805771
2025-08-26 00:59:04,649 - DEBUG - VN ctest-left_vn-92805771 UUID is f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:04,736 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:04,743 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:04,756 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:04,861 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:04,884 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:04,899 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/b4db8215-ccae-414f-ab04-45981258ec60
2025-08-26 00:59:04,917 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/b4db8215-ccae-414f-ab04-45981258ec60
2025-08-26 00:59:04,931 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/0b29a7bb-17c2-4c83-b553-13af578e7293
2025-08-26 00:59:04,943 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:59:04,943 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:04,959 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/b4db8215-ccae-414f-ab04-45981258ec60
2025-08-26 00:59:04,976 - INFO - Verified VN network id 12 for VN f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:04,976 - INFO - Verifications in API Server for VN ctest-left_vn-92805771 passed
2025-08-26 00:59:04,976 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:04,990 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/b4db8215-ccae-414f-ab04-45981258ec60
2025-08-26 00:59:05,000 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/b4db8215-ccae-414f-ab04-45981258ec60
2025-08-26 00:59:05,011 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/0b29a7bb-17c2-4c83-b553-13af578e7293
2025-08-26 00:59:05,028 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17820106492235958287', 'uuid-lslong': '10979107224703958700'}, 'enable': 'true', 'created': '2025-08-26T00:59:04', 'last-modified': '2025-08-26T00:59:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.494268'}
2025-08-26 00:59:05,046 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:59:05,054 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17820106492235958287', 'uuid-lslong': '10979107224703958700'}, 'enable': 'true', 'created': '2025-08-26T00:59:04', 'last-modified': '2025-08-26T00:59:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.521513'}
2025-08-26 00:59:05,061 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:59:05,069 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17820106492235958287', 'uuid-lslong': '10979107224703958700'}, 'enable': 'true', 'created': '2025-08-26T00:59:04', 'last-modified': '2025-08-26T00:59:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534103'}
2025-08-26 00:59:05,080 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-26 00:59:05,080 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-92805771 passed
2025-08-26 00:59:05,080 - DEBUG - ====Verifying policy data for ctest-left_vn-92805771 in API_Server ======
2025-08-26 00:59:05,081 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:05,091 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:05,110 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:05,223 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f74dbc70-915b-480f-985d-9fd9df677eac
2025-08-26 00:59:05,243 - DEBUG - =>VN ctest-left_vn-92805771 has no policy to be verified
2025-08-26 00:59:05,243 - DEBUG - Verifying the vn in opserver
2025-08-26 00:59:05,244 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771 virtual network link through opserver 10.0.0.49
2025-08-26 00:59:05,244 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:59:05,262 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771?flat'}
2025-08-26 00:59:05,263 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771 virtual network link through opserver 10.0.0.33
2025-08-26 00:59:05,263 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:59:05,279 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771?flat'}
2025-08-26 00:59:05,279 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771 virtual network link through opserver 10.0.0.254
2025-08-26 00:59:05,279 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:59:05,307 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771?flat'}
2025-08-26 00:59:05,307 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-53231971:ctest-left_vn-92805771 is found in opserver
2025-08-26 00:59:05,358 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:59:05,378 - DEBUG - VRF ids for VN ctest-left_vn-92805771: {}
2025-08-26 00:59:05,707 - DEBUG - Response for create_network : {'network': {'id': '9d4ad5ca-1dde-447b-8177-1066fc16ede3', 'name': 'ctest-right_vn-79480547', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-53231971', 'ctest-right_vn-79480547'], 'port_security_enabled': True, 'description': ''}}
2025-08-26 00:59:06,016 - DEBUG - Response for create_subnet : {'subnet': {'id': '44497d8b-6b2d-47d9-bc7e-599b61cdec75', 'name': '', 'tenant_id': '35771f81bb5e4e7b8bc4c4204c7a92ee', 'network_id': '9d4ad5ca-1dde-447b-8177-1066fc16ede3', 'ip_version': 4, 'cidr': '65.2.86.192/26', 'allocation_pools': [{'start': '65.2.86.194', 'end': '65.2.86.254'}], 'gateway_ip': '65.2.86.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '65.2.86.194', 'tags': [], 'project_id': '35771f81bb5e4e7b8bc4c4204c7a92ee'}}
2025-08-26 00:59:06,040 - INFO - Created VN ctest-right_vn-79480547
2025-08-26 00:59:06,057 - DEBUG - VN ctest-right_vn-79480547 UUID is 9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,145 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:06,153 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:06,168 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:06,305 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,321 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,333 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/8f246a9e-b0f4-43e0-b185-9ba8797fa280
2025-08-26 00:59:06,341 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/8f246a9e-b0f4-43e0-b185-9ba8797fa280
2025-08-26 00:59:06,351 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/a29d5842-6432-47dd-a280-0e5d6c437280
2025-08-26 00:59:06,359 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:59:06,359 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,370 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/8f246a9e-b0f4-43e0-b185-9ba8797fa280
2025-08-26 00:59:06,388 - INFO - Verified VN network id 13 for VN 9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,389 - INFO - Verifications in API Server for VN ctest-right_vn-79480547 passed
2025-08-26 00:59:06,390 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,409 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/8f246a9e-b0f4-43e0-b185-9ba8797fa280
2025-08-26 00:59:06,422 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/8f246a9e-b0f4-43e0-b185-9ba8797fa280
2025-08-26 00:59:06,432 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/a29d5842-6432-47dd-a280-0e5d6c437280
2025-08-26 00:59:06,447 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11334106476292490363', 'uuid-lslong': '9328943187623341539'}, 'enable': 'true', 'created': '2025-08-26T00:59:05', 'last-modified': '2025-08-26T00:59:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.510537'}
2025-08-26 00:59:06,453 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:59:06,460 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11334106476292490363', 'uuid-lslong': '9328943187623341539'}, 'enable': 'true', 'created': '2025-08-26T00:59:05', 'last-modified': '2025-08-26T00:59:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517192'}
2025-08-26 00:59:06,468 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:59:06,476 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-53231971', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11334106476292490363', 'uuid-lslong': '9328943187623341539'}, 'enable': 'true', 'created': '2025-08-26T00:59:05', 'last-modified': '2025-08-26T00:59:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.522251'}
2025-08-26 00:59:06,485 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-26 00:59:06,485 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-79480547 passed
2025-08-26 00:59:06,486 - DEBUG - ====Verifying policy data for ctest-right_vn-79480547 in API_Server ======
2025-08-26 00:59:06,486 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:06,493 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:06,519 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:06,650 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/9d4ad5ca-1dde-447b-8177-1066fc16ede3
2025-08-26 00:59:06,663 - DEBUG - =>VN ctest-right_vn-79480547 has no policy to be verified
2025-08-26 00:59:06,663 - DEBUG - Verifying the vn in opserver
2025-08-26 00:59:06,663 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547 virtual network link through opserver 10.0.0.49
2025-08-26 00:59:06,663 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks
2025-08-26 00:59:06,677 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547?flat'}
2025-08-26 00:59:06,677 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547 virtual network link through opserver 10.0.0.33
2025-08-26 00:59:06,677 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-08-26 00:59:06,700 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547?flat'}
2025-08-26 00:59:06,700 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547 virtual network link through opserver 10.0.0.254
2025-08-26 00:59:06,700 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-26 00:59:06,714 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547?flat'}
2025-08-26 00:59:06,714 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-53231971:ctest-right_vn-79480547 is found in opserver
2025-08-26 00:59:06,759 - DEBUG - Do not have enough data to verify VN in agent
2025-08-26 00:59:06,781 - DEBUG - VRF ids for VN ctest-right_vn-79480547: {}
2025-08-26 00:59:07,899 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-2)
2025-08-26 00:59:09,434 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3931-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3931-1)
2025-08-26 00:59:09,434 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-45912511']
2025-08-26 00:59:09,732 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-45912511'] created succesfully
2025-08-26 00:59:10,226 - DEBUG - Image by name tiny_nat_fw not found
2025-08-26 00:59:10,226 - DEBUG - Installing image tiny_nat_fw
2025-08-26 00:59:10,226 - 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-08-26 00:59:10,226 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:59:10,226 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:59:10,226 - DEBUG - Unzip image
2025-08-26 00:59:10,226 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-26 00:59:10,256 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-26 00:59:10,256 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-45912511']
2025-08-26 00:59:10,393 - INFO - Service template: ['default-domain', 'ctest-service_template_1-45912511'] deleted successfully.
2025-08-26 00:59:10,394 - INFO - Skip interface_detach for VM ctest-right_vm-19913409 in state BUILD
2025-08-26 00:59:10,394 - INFO - Deleting VM ctest-right_vm-19913409
2025-08-26 00:59:10,394 - ERROR - VM ctest-right_vm-19913409 has failed to come up
2025-08-26 00:59:10,394 - ERROR - Nova failed to get host of the VM
2025-08-26 00:59:10,394 - ERROR - VM ctest-right_vm-19913409 has failed to come up
2025-08-26 00:59:10,394 - ERROR - Nova failed to get host of the VM
2025-08-26 00:59:10,577 - INFO - Skip interface_detach for VM ctest-left_vm-08474742 in state BUILD
2025-08-26 00:59:10,577 - INFO - Deleting VM ctest-left_vm-08474742
2025-08-26 00:59:10,578 - ERROR - VM ctest-left_vm-08474742 has failed to come up
2025-08-26 00:59:10,578 - ERROR - Nova failed to get host of the VM
2025-08-26 00:59:10,578 - ERROR - VM ctest-left_vm-08474742 has failed to come up
2025-08-26 00:59:10,578 - ERROR - Nova failed to get host of the VM
2025-08-26 00:59:10,752 - INFO - Deleting VN ctest-right_vn-79480547
2025-08-26 00:59:10,824 - DEBUG - VN 9d4ad5ca-1dde-447b-8177-1066fc16ede3 still in use: Unable to complete operation on network 9d4ad5ca-1dde-447b-8177-1066fc16ede3. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9a91189f-4f87-412f-ba32-7c058e5ec644']
2025-08-26 00:59:10,824 - WARNING - Deleting VN ctest-right_vn-79480547 failed..Will retry
2025-08-26 00:59:13,015 - DEBUG - Response for deleting network ()
2025-08-26 00:59:13,016 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/8f246a9e-b0f4-43e0-b185-9ba8797fa280
2025-08-26 00:59:13,024 - DEBUG - Response Code: 404
2025-08-26 00:59:13,024 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:13,033 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:13,098 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:13,203 - INFO - Validated that VN ctest-right_vn-79480547 is not found in API Server
2025-08-26 00:59:13,218 - DEBUG - VN ctest-right_vn-79480547 is not present in Agent 10.0.0.137
2025-08-26 00:59:13,245 - DEBUG - VN ctest-right_vn-79480547 is not present in Agent 10.0.0.41
2025-08-26 00:59:13,245 - INFO - Validated that VN ctest-right_vn-79480547 is not in any agent
2025-08-26 00:59:13,298 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-79480547 info
2025-08-26 00:59:13,298 - INFO - Deleting VN ctest-left_vn-92805771
2025-08-26 00:59:13,542 - DEBUG - Response for deleting network ()
2025-08-26 00:59:13,542 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/b4db8215-ccae-414f-ab04-45981258ec60
2025-08-26 00:59:13,553 - DEBUG - Response Code: 404
2025-08-26 00:59:13,553 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:13,561 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:13,621 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:13,735 - INFO - Validated that VN ctest-left_vn-92805771 is not found in API Server
2025-08-26 00:59:13,748 - DEBUG - VN ctest-left_vn-92805771 is not present in Agent 10.0.0.137
2025-08-26 00:59:13,777 - DEBUG - VN ctest-left_vn-92805771 is not present in Agent 10.0.0.41
2025-08-26 00:59:13,777 - INFO - Validated that VN ctest-left_vn-92805771 is not in any agent
2025-08-26 00:59:13,830 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-92805771 info
2025-08-26 00:59:13,830 - INFO - Deleting VN ctest-mgmt_vn-89882083
2025-08-26 00:59:14,001 - DEBUG - Response for deleting network ()
2025-08-26 00:59:14,001 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/4040a100-1f5f-4e2a-b858-d58610c810c5
2025-08-26 00:59:14,012 - DEBUG - Response Code: 404
2025-08-26 00:59:14,012 - DEBUG - Requesting: http://10.0.0.49:8082/domains
2025-08-26 00:59:14,024 - DEBUG - Requesting: http://10.0.0.49:8082/domain/ef4dcfcd-b3c2-4159-a2f0-815ca79e3b02
2025-08-26 00:59:14,063 - DEBUG - Requesting: http://10.0.0.49:8082/project/35771f81-bb5e-4e7b-8bc4-c4204c7a92ee
2025-08-26 00:59:14,202 - INFO - Validated that VN ctest-mgmt_vn-89882083 is not found in API Server
2025-08-26 00:59:14,217 - DEBUG - VN ctest-mgmt_vn-89882083 is not present in Agent 10.0.0.137
2025-08-26 00:59:14,246 - DEBUG - VN ctest-mgmt_vn-89882083 is not present in Agent 10.0.0.41
2025-08-26 00:59:14,246 - INFO - Validated that VN ctest-mgmt_vn-89882083 is not in any agent
2025-08-26 00:59:14,296 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-89882083 info
2025-08-26 00:59:15,651 - DEBUG - No XMPP flaps were noticed during the test
2025-08-26 00:59:15,651 - INFO -
2025-08-26 00:59:15,652 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-08-26 00:59:15,652 - INFO - --------------------------------------------------------------------------------
2025-08-26 00:59:16,606 - INFO - Deleted project: ctest-TestSvcRegr-53231971, ID : 35771f81-bb5e-4e7b-8bc4-c4204c7a92ee