]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 14:43:34,011 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-77969534']
2025-10-08 14:43:34,351 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-77969534'] created succesfully
2025-10-08 14:43:34,764 - DEBUG - Image by name tiny_nat_fw not found
2025-10-08 14:43:34,764 - DEBUG - Installing image tiny_nat_fw
2025-10-08 14:43:34,764 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-08 14:43:34,765 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-08 14:43:34,765 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-08 14:43:34,793 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:43:34,939 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:43:34,939 - DEBUG - Unzip image
2025-10-08 14:43:34,939 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:43:34,967 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-08 14:43:34,967 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-77969534']
2025-10-08 14:43:35,076 - INFO - Service template: ['default-domain', 'ctest-service_template_1-77969534'] deleted successfully.
2025-10-08 14:43:35,076 - INFO - Skip interface_detach for VM ctest-right_vm-31149316 in state BUILD
2025-10-08 14:43:35,076 - INFO - Deleting VM ctest-right_vm-31149316
2025-10-08 14:43:35,106 - ERROR - VM ctest-right_vm-31149316 has failed to come up
2025-10-08 14:43:35,107 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:35,107 - ERROR - VM ctest-right_vm-31149316 has failed to come up
2025-10-08 14:43:35,107 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:35,186 - INFO - Skip interface_detach for VM ctest-left_vm-07968796 in state BUILD
2025-10-08 14:43:35,187 - INFO - Deleting VM ctest-left_vm-07968796
2025-10-08 14:43:35,187 - ERROR - VM ctest-left_vm-07968796 has failed to come up
2025-10-08 14:43:35,187 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:35,187 - ERROR - VM ctest-left_vm-07968796 has failed to come up
2025-10-08 14:43:35,187 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:35,279 - INFO - Deleting VN ctest-right_vn-59505495
2025-10-08 14:43:35,332 - DEBUG - VN 7bf00769-e8ee-4caa-8d8a-aa1340a4b854 still in use: Unable to complete operation on network 7bf00769-e8ee-4caa-8d8a-aa1340a4b854. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b11a9b35-82ba-48c3-8ed5-669b81091579']
2025-10-08 14:43:35,332 - WARNING - Deleting VN ctest-right_vn-59505495 failed..Will retry
2025-10-08 14:43:37,509 - DEBUG - Response for deleting network ()
2025-10-08 14:43:37,509 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/da17edb5-8496-420c-b590-5f12a9263c54
2025-10-08 14:43:37,532 - DEBUG - Response Code: 404
2025-10-08 14:43:37,532 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:37,545 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:37,594 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:37,672 - INFO - Validated that VN ctest-right_vn-59505495 is not found in API Server
2025-10-08 14:43:37,685 - DEBUG - VN ctest-right_vn-59505495 is not present in Agent 10.0.0.61
2025-10-08 14:43:37,697 - DEBUG - VN ctest-right_vn-59505495 is not present in Agent 10.0.0.241
2025-10-08 14:43:37,697 - INFO - Validated that VN ctest-right_vn-59505495 is not in any agent
2025-10-08 14:43:37,749 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-59505495 info
2025-10-08 14:43:37,749 - INFO - Deleting VN ctest-left_vn-79177802
2025-10-08 14:43:37,934 - DEBUG - Response for deleting network ()
2025-10-08 14:43:37,935 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/b03604de-3fa3-46d9-89cc-598682610c78
2025-10-08 14:43:37,957 - DEBUG - Response Code: 404
2025-10-08 14:43:37,958 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:37,969 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:38,003 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:38,105 - INFO - Validated that VN ctest-left_vn-79177802 is not found in API Server
2025-10-08 14:43:38,115 - DEBUG - VN ctest-left_vn-79177802 is not present in Agent 10.0.0.61
2025-10-08 14:43:38,129 - DEBUG - VN ctest-left_vn-79177802 is not present in Agent 10.0.0.241
2025-10-08 14:43:38,129 - INFO - Validated that VN ctest-left_vn-79177802 is not in any agent
2025-10-08 14:43:38,161 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-79177802 info
2025-10-08 14:43:38,162 - INFO - Deleting VN ctest-mgmt_vn-96518287
2025-10-08 14:43:38,373 - DEBUG - Response for deleting network ()
2025-10-08 14:43:38,373 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/1c208e2b-7fd4-4c19-b430-8ae46a70ae2e
2025-10-08 14:43:38,380 - DEBUG - Response Code: 404
2025-10-08 14:43:38,380 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:38,388 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:38,448 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:38,565 - INFO - Validated that VN ctest-mgmt_vn-96518287 is not found in API Server
2025-10-08 14:43:38,577 - DEBUG - VN ctest-mgmt_vn-96518287 is not present in Agent 10.0.0.61
2025-10-08 14:43:38,590 - DEBUG - VN ctest-mgmt_vn-96518287 is not present in Agent 10.0.0.241
2025-10-08 14:43:38,590 - INFO - Validated that VN ctest-mgmt_vn-96518287 is not in any agent
2025-10-08 14:43:38,626 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-96518287 info
2025-10-08 14:43:38,626 - INFO - Deleting VM ctest-vm-in-third-vn-34069357
2025-10-08 14:43:38,729 - INFO - Deleting VN ctest-third-vn-92563749
2025-10-08 14:43:38,782 - DEBUG - VN dcd1e48a-dd51-4e17-a744-caefa66aa452 still in use: Unable to complete operation on network dcd1e48a-dd51-4e17-a744-caefa66aa452. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5f928bce-b8d6-4e96-9738-6d1f8747eb95']
2025-10-08 14:43:38,782 - WARNING - Deleting VN ctest-third-vn-92563749 failed..Will retry
2025-10-08 14:43:40,964 - DEBUG - Response for deleting network ()
2025-10-08 14:43:42,251 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 14:43:42,251 - INFO -
2025-10-08 14:43:42,251 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:27]
2025-10-08 14:43:42,251 - INFO - --------------------------------------------------------------------------------
2025-10-08 14:43:42,258 - INFO - ================================================================================
2025-10-08 14:43:42,258 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-08 14:43:43,541 - DEBUG - Nothing to compare xmpp stats {'10.0.0.61': {'10.20.0.249': '0', '10.20.0.252': '0'}, '10.0.0.241': {'10.20.0.252': '0', '10.20.0.249': '0'}} with
2025-10-08 14:43:43,541 - INFO - Initial checks done. Running the testcase now
2025-10-08 14:43:43,541 - INFO -
2025-10-08 14:43:43,881 - DEBUG - Response for create_network : {'network': {'id': '8d7708e7-7ff1-40af-80c0-1ddb57944592', 'name': 'ctest-mgmt_vn-70246078', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'project_id': 'b0099da6a38840e2aa23b240ea4379bc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-59382824', 'ctest-mgmt_vn-70246078'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:43:44,151 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b4e0a09a-7f95-4d54-a8dc-27b0db37472b', 'name': '', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'network_id': '8d7708e7-7ff1-40af-80c0-1ddb57944592', 'ip_version': 4, 'cidr': '86.92.60.128/26', 'allocation_pools': [{'start': '86.92.60.130', 'end': '86.92.60.190'}], 'gateway_ip': '86.92.60.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '86.92.60.130', 'tags': [], 'project_id': 'b0099da6a38840e2aa23b240ea4379bc'}}
2025-10-08 14:43:44,178 - INFO - Created VN ctest-mgmt_vn-70246078
2025-10-08 14:43:44,193 - DEBUG - VN ctest-mgmt_vn-70246078 UUID is 8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,282 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:44,294 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:44,307 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:44,411 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,426 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,438 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/bc3828e4-97dd-442c-83b1-b170516108ca
2025-10-08 14:43:44,450 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/bc3828e4-97dd-442c-83b1-b170516108ca
2025-10-08 14:43:44,458 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/7f4a5b8f-2828-49db-b07f-0c8fbfc0b344
2025-10-08 14:43:44,467 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:43:44,467 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,477 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/bc3828e4-97dd-442c-83b1-b170516108ca
2025-10-08 14:43:44,499 - INFO - Verified VN network id 14 for VN 8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,500 - INFO - Verifications in API Server for VN ctest-mgmt_vn-70246078 passed
2025-10-08 14:43:44,500 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,511 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/bc3828e4-97dd-442c-83b1-b170516108ca
2025-10-08 14:43:44,523 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/bc3828e4-97dd-442c-83b1-b170516108ca
2025-10-08 14:43:44,537 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/7f4a5b8f-2828-49db-b07f-0c8fbfc0b344
2025-10-08 14:43:44,560 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10193626071953391791', 'uuid-lslong': '9277448060287600018'}, 'enable': 'true', 'created': '2025-10-08T14:43:43', 'last-modified': '2025-10-08T14:43:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.526089'}
2025-10-08 14:43:44,567 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:43:44,573 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10193626071953391791', 'uuid-lslong': '9277448060287600018'}, 'enable': 'true', 'created': '2025-10-08T14:43:43', 'last-modified': '2025-10-08T14:43:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.533680'}
2025-10-08 14:43:44,580 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:43:44,586 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10193626071953391791', 'uuid-lslong': '9277448060287600018'}, 'enable': 'true', 'created': '2025-10-08T14:43:43', 'last-modified': '2025-10-08T14:43:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.545554'}
2025-10-08 14:43:44,593 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:43:44,593 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-70246078 passed
2025-10-08 14:43:44,593 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-70246078 in API_Server ======
2025-10-08 14:43:44,593 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:44,602 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:44,616 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:44,723 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d7708e7-7ff1-40af-80c0-1ddb57944592
2025-10-08 14:43:44,740 - DEBUG - =>VN ctest-mgmt_vn-70246078 has no policy to be verified
2025-10-08 14:43:44,740 - DEBUG - Verifying the vn in opserver
2025-10-08 14:43:44,741 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078 virtual network link through opserver 10.0.0.133
2025-10-08 14:43:44,741 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:43:44,756 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078?flat'}
2025-10-08 14:43:44,756 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078 virtual network link through opserver 10.0.0.50
2025-10-08 14:43:44,756 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:43:44,772 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078?flat'}
2025-10-08 14:43:44,772 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078 virtual network link through opserver 10.0.0.242
2025-10-08 14:43:44,772 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:43:44,787 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078?flat'}
2025-10-08 14:43:44,787 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-59382824:ctest-mgmt_vn-70246078 is found in opserver
2025-10-08 14:43:44,812 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:43:44,825 - DEBUG - VRF ids for VN ctest-mgmt_vn-70246078: {}
2025-10-08 14:43:45,168 - DEBUG - Response for create_network : {'network': {'id': 'dd51c6c6-b675-474b-af9b-c4b3e78c4308', 'name': 'ctest-left_vn-59290584', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'project_id': 'b0099da6a38840e2aa23b240ea4379bc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-59382824', 'ctest-left_vn-59290584'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:43:45,435 - DEBUG - Response for create_subnet : {'subnet': {'id': '20555982-c0a5-4a97-9a9b-dd634ae2fd12', 'name': '', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'network_id': 'dd51c6c6-b675-474b-af9b-c4b3e78c4308', 'ip_version': 4, 'cidr': '96.91.129.0/26', 'allocation_pools': [{'start': '96.91.129.2', 'end': '96.91.129.62'}], 'gateway_ip': '96.91.129.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.91.129.2', 'tags': [], 'project_id': 'b0099da6a38840e2aa23b240ea4379bc'}}
2025-10-08 14:43:45,463 - INFO - Created VN ctest-left_vn-59290584
2025-10-08 14:43:45,477 - DEBUG - VN ctest-left_vn-59290584 UUID is dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:45,564 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:45,572 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:45,587 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:45,689 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:45,708 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:45,721 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4e86a880-6673-493e-9b23-455ef66d4169
2025-10-08 14:43:45,737 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4e86a880-6673-493e-9b23-455ef66d4169
2025-10-08 14:43:45,750 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/0dfe30dc-7e21-4529-b73f-5921868bcd15
2025-10-08 14:43:45,761 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:43:45,761 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:45,776 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4e86a880-6673-493e-9b23-455ef66d4169
2025-10-08 14:43:45,796 - INFO - Verified VN network id 15 for VN dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:45,796 - INFO - Verifications in API Server for VN ctest-left_vn-59290584 passed
2025-10-08 14:43:45,797 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:45,812 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4e86a880-6673-493e-9b23-455ef66d4169
2025-10-08 14:43:45,821 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4e86a880-6673-493e-9b23-455ef66d4169
2025-10-08 14:43:45,830 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/0dfe30dc-7e21-4529-b73f-5921868bcd15
2025-10-08 14:43:45,845 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15947746312262600523', 'uuid-lslong': '12653923854990459656'}, 'enable': 'true', 'created': '2025-10-08T14:43:45', 'last-modified': '2025-10-08T14:43:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476270'}
2025-10-08 14:43:45,852 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:43:45,858 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15947746312262600523', 'uuid-lslong': '12653923854990459656'}, 'enable': 'true', 'created': '2025-10-08T14:43:45', 'last-modified': '2025-10-08T14:43:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493006'}
2025-10-08 14:43:45,864 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:43:45,871 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15947746312262600523', 'uuid-lslong': '12653923854990459656'}, 'enable': 'true', 'created': '2025-10-08T14:43:45', 'last-modified': '2025-10-08T14:43:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.503055'}
2025-10-08 14:43:45,877 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:43:45,878 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-59290584 passed
2025-10-08 14:43:45,878 - DEBUG - ====Verifying policy data for ctest-left_vn-59290584 in API_Server ======
2025-10-08 14:43:45,878 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:45,889 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:45,904 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:45,997 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/dd51c6c6-b675-474b-af9b-c4b3e78c4308
2025-10-08 14:43:46,008 - DEBUG - =>VN ctest-left_vn-59290584 has no policy to be verified
2025-10-08 14:43:46,008 - DEBUG - Verifying the vn in opserver
2025-10-08 14:43:46,008 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584 virtual network link through opserver 10.0.0.133
2025-10-08 14:43:46,008 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:43:46,023 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584?flat'}
2025-10-08 14:43:46,023 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584 virtual network link through opserver 10.0.0.50
2025-10-08 14:43:46,023 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:43:46,037 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584?flat'}
2025-10-08 14:43:46,037 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584 virtual network link through opserver 10.0.0.242
2025-10-08 14:43:46,037 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:43:46,051 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584?flat'}
2025-10-08 14:43:46,051 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-59382824:ctest-left_vn-59290584 is found in opserver
2025-10-08 14:43:46,075 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:43:46,088 - DEBUG - VRF ids for VN ctest-left_vn-59290584: {}
2025-10-08 14:43:46,418 - DEBUG - Response for create_network : {'network': {'id': '3fdeec6c-15a1-4daa-953b-1a534dc5b42b', 'name': 'ctest-right_vn-63690984', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'project_id': 'b0099da6a38840e2aa23b240ea4379bc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-59382824', 'ctest-right_vn-63690984'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:43:46,659 - DEBUG - Response for create_subnet : {'subnet': {'id': '291277ee-5396-41e3-91d1-4580075afeaf', 'name': '', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'network_id': '3fdeec6c-15a1-4daa-953b-1a534dc5b42b', 'ip_version': 4, 'cidr': '82.111.216.0/26', 'allocation_pools': [{'start': '82.111.216.2', 'end': '82.111.216.62'}], 'gateway_ip': '82.111.216.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '82.111.216.2', 'tags': [], 'project_id': 'b0099da6a38840e2aa23b240ea4379bc'}}
2025-10-08 14:43:46,688 - INFO - Created VN ctest-right_vn-63690984
2025-10-08 14:43:46,701 - DEBUG - VN ctest-right_vn-63690984 UUID is 3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:46,797 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:46,806 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:46,821 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:46,925 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:46,945 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:46,960 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a0441fb7-14c6-42f9-8b20-96292d5999b7
2025-10-08 14:43:46,973 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a0441fb7-14c6-42f9-8b20-96292d5999b7
2025-10-08 14:43:46,987 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/b63dedde-da47-4c8f-a80b-f6d1c049726b
2025-10-08 14:43:46,997 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:43:46,997 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:47,015 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a0441fb7-14c6-42f9-8b20-96292d5999b7
2025-10-08 14:43:47,035 - INFO - Verified VN network id 16 for VN 3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:47,036 - INFO - Verifications in API Server for VN ctest-right_vn-63690984 passed
2025-10-08 14:43:47,036 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:47,050 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a0441fb7-14c6-42f9-8b20-96292d5999b7
2025-10-08 14:43:47,060 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a0441fb7-14c6-42f9-8b20-96292d5999b7
2025-10-08 14:43:47,069 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/b63dedde-da47-4c8f-a80b-f6d1c049726b
2025-10-08 14:43:47,085 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4602375818182741418', 'uuid-lslong': '10753217480366601259'}, 'enable': 'true', 'created': '2025-10-08T14:43:46', 'last-modified': '2025-10-08T14:43:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.491042'}
2025-10-08 14:43:47,092 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:43:47,099 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4602375818182741418', 'uuid-lslong': '10753217480366601259'}, 'enable': 'true', 'created': '2025-10-08T14:43:46', 'last-modified': '2025-10-08T14:43:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.505566'}
2025-10-08 14:43:47,105 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:43:47,111 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4602375818182741418', 'uuid-lslong': '10753217480366601259'}, 'enable': 'true', 'created': '2025-10-08T14:43:46', 'last-modified': '2025-10-08T14:43:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517731'}
2025-10-08 14:43:47,118 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:43:47,118 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-63690984 passed
2025-10-08 14:43:47,118 - DEBUG - ====Verifying policy data for ctest-right_vn-63690984 in API_Server ======
2025-10-08 14:43:47,118 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:47,126 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:47,147 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:47,259 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3fdeec6c-15a1-4daa-953b-1a534dc5b42b
2025-10-08 14:43:47,274 - DEBUG - =>VN ctest-right_vn-63690984 has no policy to be verified
2025-10-08 14:43:47,274 - DEBUG - Verifying the vn in opserver
2025-10-08 14:43:47,274 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984 virtual network link through opserver 10.0.0.133
2025-10-08 14:43:47,275 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:43:47,290 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984?flat'}
2025-10-08 14:43:47,290 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984 virtual network link through opserver 10.0.0.50
2025-10-08 14:43:47,290 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:43:47,306 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984?flat'}
2025-10-08 14:43:47,307 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984 virtual network link through opserver 10.0.0.242
2025-10-08 14:43:47,307 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:43:47,322 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984?flat'}
2025-10-08 14:43:47,322 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-59382824:ctest-right_vn-63690984 is found in opserver
2025-10-08 14:43:47,348 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:43:47,361 - DEBUG - VRF ids for VN ctest-right_vn-63690984: {}
2025-10-08 14:43:47,662 - DEBUG - Response for create_network : {'network': {'id': 'ef87d887-ed7b-4c14-a345-37df7a990828', 'name': 'ctest-trans_left_vn-05380917', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'project_id': 'b0099da6a38840e2aa23b240ea4379bc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-59382824', 'ctest-trans_left_vn-05380917'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:43:47,903 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e49c54ba-29bc-40b2-ba2a-db5c54fab961', 'name': '', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'network_id': 'ef87d887-ed7b-4c14-a345-37df7a990828', 'ip_version': 4, 'cidr': '220.30.65.0/26', 'allocation_pools': [{'start': '220.30.65.2', 'end': '220.30.65.62'}], 'gateway_ip': '220.30.65.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '220.30.65.2', 'tags': [], 'project_id': 'b0099da6a38840e2aa23b240ea4379bc'}}
2025-10-08 14:43:47,928 - INFO - Created VN ctest-trans_left_vn-05380917
2025-10-08 14:43:47,942 - DEBUG - VN ctest-trans_left_vn-05380917 UUID is ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,032 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:48,040 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:48,057 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:48,174 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,194 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,208 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a7dcb795-bdd7-42a0-ac7f-dcd92fd51cd7
2025-10-08 14:43:48,221 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a7dcb795-bdd7-42a0-ac7f-dcd92fd51cd7
2025-10-08 14:43:48,232 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/98aad488-be15-4e88-9710-e5aa088f18b3
2025-10-08 14:43:48,243 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-08 14:43:48,243 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,258 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a7dcb795-bdd7-42a0-ac7f-dcd92fd51cd7
2025-10-08 14:43:48,278 - INFO - Verified VN network id 17 for VN ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,278 - INFO - Verifications in API Server for VN ctest-trans_left_vn-05380917 passed
2025-10-08 14:43:48,279 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,292 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a7dcb795-bdd7-42a0-ac7f-dcd92fd51cd7
2025-10-08 14:43:48,302 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a7dcb795-bdd7-42a0-ac7f-dcd92fd51cd7
2025-10-08 14:43:48,312 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/98aad488-be15-4e88-9710-e5aa088f18b3
2025-10-08 14:43:48,328 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17260002175237180436', 'uuid-lslong': '11764871034549372968'}, 'enable': 'true', 'created': '2025-10-08T14:43:47', 'last-modified': '2025-10-08T14:43:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476616'}
2025-10-08 14:43:48,335 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-08 14:43:48,342 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17260002175237180436', 'uuid-lslong': '11764871034549372968'}, 'enable': 'true', 'created': '2025-10-08T14:43:47', 'last-modified': '2025-10-08T14:43:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484056'}
2025-10-08 14:43:48,349 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-08 14:43:48,356 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17260002175237180436', 'uuid-lslong': '11764871034549372968'}, 'enable': 'true', 'created': '2025-10-08T14:43:47', 'last-modified': '2025-10-08T14:43:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.501054'}
2025-10-08 14:43:48,363 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-08 14:43:48,363 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-05380917 passed
2025-10-08 14:43:48,363 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-05380917 in API_Server ======
2025-10-08 14:43:48,363 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:48,373 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:48,389 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:48,498 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/ef87d887-ed7b-4c14-a345-37df7a990828
2025-10-08 14:43:48,512 - DEBUG - =>VN ctest-trans_left_vn-05380917 has no policy to be verified
2025-10-08 14:43:48,512 - DEBUG - Verifying the vn in opserver
2025-10-08 14:43:48,512 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917 virtual network link through opserver 10.0.0.133
2025-10-08 14:43:48,512 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:43:48,533 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917?flat'}
2025-10-08 14:43:48,533 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917 virtual network link through opserver 10.0.0.50
2025-10-08 14:43:48,533 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:43:48,549 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917?flat'}
2025-10-08 14:43:48,549 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917 virtual network link through opserver 10.0.0.242
2025-10-08 14:43:48,549 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:43:48,566 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917?flat'}
2025-10-08 14:43:48,566 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-59382824:ctest-trans_left_vn-05380917 is found in opserver
2025-10-08 14:43:48,596 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:43:48,611 - DEBUG - VRF ids for VN ctest-trans_left_vn-05380917: {}
2025-10-08 14:43:48,919 - DEBUG - Response for create_network : {'network': {'id': '3f2e1d1c-c0ba-4aad-adb4-cf0945d21174', 'name': 'ctest-trans_right_vn-98042477', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'project_id': 'b0099da6a38840e2aa23b240ea4379bc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-59382824', 'ctest-trans_right_vn-98042477'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:43:49,218 - DEBUG - Response for create_subnet : {'subnet': {'id': '804bcc92-fef6-43a8-8c6b-2de14fc3dcf4', 'name': '', 'tenant_id': 'b0099da6a38840e2aa23b240ea4379bc', 'network_id': '3f2e1d1c-c0ba-4aad-adb4-cf0945d21174', 'ip_version': 4, 'cidr': '157.25.99.128/26', 'allocation_pools': [{'start': '157.25.99.130', 'end': '157.25.99.190'}], 'gateway_ip': '157.25.99.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '157.25.99.130', 'tags': [], 'project_id': 'b0099da6a38840e2aa23b240ea4379bc'}}
2025-10-08 14:43:49,244 - INFO - Created VN ctest-trans_right_vn-98042477
2025-10-08 14:43:49,260 - DEBUG - VN ctest-trans_right_vn-98042477 UUID is 3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,365 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:49,374 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:49,386 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:49,496 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,511 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,524 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/263ebb9f-251a-4556-9ae0-3bfc3aadf446
2025-10-08 14:43:49,535 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/263ebb9f-251a-4556-9ae0-3bfc3aadf446
2025-10-08 14:43:49,544 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/dd8368f2-36b4-4711-b43f-4c1fb118663e
2025-10-08 14:43:49,553 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-08 14:43:49,553 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,564 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/263ebb9f-251a-4556-9ae0-3bfc3aadf446
2025-10-08 14:43:49,586 - INFO - Verified VN network id 18 for VN 3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,586 - INFO - Verifications in API Server for VN ctest-trans_right_vn-98042477 passed
2025-10-08 14:43:49,586 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,598 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/263ebb9f-251a-4556-9ae0-3bfc3aadf446
2025-10-08 14:43:49,609 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/263ebb9f-251a-4556-9ae0-3bfc3aadf446
2025-10-08 14:43:49,618 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/dd8368f2-36b4-4711-b43f-4c1fb118663e
2025-10-08 14:43:49,634 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4552608282647874221', 'uuid-lslong': '12516856903102501236'}, 'enable': 'true', 'created': '2025-10-08T14:43:48', 'last-modified': '2025-10-08T14:43:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500176'}
2025-10-08 14:43:49,642 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-08 14:43:49,648 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4552608282647874221', 'uuid-lslong': '12516856903102501236'}, 'enable': 'true', 'created': '2025-10-08T14:43:48', 'last-modified': '2025-10-08T14:43:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.519223'}
2025-10-08 14:43:49,656 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-08 14:43:49,663 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-59382824', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4552608282647874221', 'uuid-lslong': '12516856903102501236'}, 'enable': 'true', 'created': '2025-10-08T14:43:48', 'last-modified': '2025-10-08T14:43:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532660'}
2025-10-08 14:43:49,672 - DEBUG - Route Targets: ['target:64512:8000009']
2025-10-08 14:43:49,672 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-98042477 passed
2025-10-08 14:43:49,672 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-98042477 in API_Server ======
2025-10-08 14:43:49,672 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:49,680 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:49,693 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:49,790 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/3f2e1d1c-c0ba-4aad-adb4-cf0945d21174
2025-10-08 14:43:49,802 - DEBUG - =>VN ctest-trans_right_vn-98042477 has no policy to be verified
2025-10-08 14:43:49,803 - DEBUG - Verifying the vn in opserver
2025-10-08 14:43:49,803 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477 virtual network link through opserver 10.0.0.133
2025-10-08 14:43:49,803 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:43:49,816 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477?flat'}
2025-10-08 14:43:49,816 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477 virtual network link through opserver 10.0.0.50
2025-10-08 14:43:49,817 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:43:49,831 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477?flat'}
2025-10-08 14:43:49,831 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477 virtual network link through opserver 10.0.0.242
2025-10-08 14:43:49,831 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:43:49,845 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477?flat'}
2025-10-08 14:43:49,845 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-59382824:ctest-trans_right_vn-98042477 is found in opserver
2025-10-08 14:43:49,870 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:43:49,881 - DEBUG - VRF ids for VN ctest-trans_right_vn-98042477: {}
2025-10-08 14:43:50,998 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 14:43:52,449 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1)
2025-10-08 14:43:52,450 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-57045390']
2025-10-08 14:43:52,769 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-57045390'] created succesfully
2025-10-08 14:43:53,229 - DEBUG - Image by name tiny_trans_fw not found
2025-10-08 14:43:53,229 - DEBUG - Installing image tiny_trans_fw
2025-10-08 14:43:53,229 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-10-08 14:43:53,230 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-08 14:43:53,230 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-08 14:43:53,256 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-08 14:43:53,295 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-08 14:43:53,295 - DEBUG - Unzip image
2025-10-08 14:43:53,295 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-08 14:43:53,319 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-08 14:43:53,319 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-57045390']
2025-10-08 14:43:53,436 - INFO - Service template: ['default-domain', 'ctest-service_template_1-57045390'] deleted successfully.
2025-10-08 14:43:53,437 - INFO - Skip interface_detach for VM ctest-right_vm-05919558 in state BUILD
2025-10-08 14:43:53,438 - INFO - Deleting VM ctest-right_vm-05919558
2025-10-08 14:43:53,439 - ERROR - VM ctest-right_vm-05919558 has failed to come up
2025-10-08 14:43:53,439 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:53,439 - ERROR - VM ctest-right_vm-05919558 has failed to come up
2025-10-08 14:43:53,440 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:53,607 - INFO - Skip interface_detach for VM ctest-left_vm-11438663 in state BUILD
2025-10-08 14:43:53,607 - INFO - Deleting VM ctest-left_vm-11438663
2025-10-08 14:43:53,607 - ERROR - VM ctest-left_vm-11438663 has failed to come up
2025-10-08 14:43:53,607 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:53,607 - ERROR - VM ctest-left_vm-11438663 has failed to come up
2025-10-08 14:43:53,607 - ERROR - Nova failed to get host of the VM
2025-10-08 14:43:53,698 - INFO - Deleting VN ctest-trans_right_vn-98042477
2025-10-08 14:43:53,884 - DEBUG - Response for deleting network ()
2025-10-08 14:43:53,884 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/263ebb9f-251a-4556-9ae0-3bfc3aadf446
2025-10-08 14:43:53,891 - DEBUG - Response Code: 404
2025-10-08 14:43:53,891 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:53,899 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:53,939 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:54,045 - INFO - Validated that VN ctest-trans_right_vn-98042477 is not found in API Server
2025-10-08 14:43:54,068 - DEBUG - VN ctest-trans_right_vn-98042477 is not present in Agent 10.0.0.61
2025-10-08 14:43:54,082 - DEBUG - VN ctest-trans_right_vn-98042477 is not present in Agent 10.0.0.241
2025-10-08 14:43:54,082 - INFO - Validated that VN ctest-trans_right_vn-98042477 is not in any agent
2025-10-08 14:43:54,140 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-98042477 info
2025-10-08 14:43:54,140 - INFO - Deleting VN ctest-trans_left_vn-05380917
2025-10-08 14:43:54,380 - DEBUG - Response for deleting network ()
2025-10-08 14:43:54,380 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a7dcb795-bdd7-42a0-ac7f-dcd92fd51cd7
2025-10-08 14:43:54,392 - DEBUG - Response Code: 404
2025-10-08 14:43:54,392 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:54,404 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:54,421 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:54,553 - INFO - Validated that VN ctest-trans_left_vn-05380917 is not found in API Server
2025-10-08 14:43:54,584 - DEBUG - VN ctest-trans_left_vn-05380917 is not present in Agent 10.0.0.61
2025-10-08 14:43:54,600 - DEBUG - VN ctest-trans_left_vn-05380917 is not present in Agent 10.0.0.241
2025-10-08 14:43:54,600 - INFO - Validated that VN ctest-trans_left_vn-05380917 is not in any agent
2025-10-08 14:43:54,666 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-05380917 info
2025-10-08 14:43:54,667 - INFO - Deleting VN ctest-right_vn-63690984
2025-10-08 14:43:54,718 - DEBUG - VN 3fdeec6c-15a1-4daa-953b-1a534dc5b42b still in use: Unable to complete operation on network 3fdeec6c-15a1-4daa-953b-1a534dc5b42b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-618d8403-4bab-42ee-a355-ae08c06c3556']
2025-10-08 14:43:54,719 - WARNING - Deleting VN ctest-right_vn-63690984 failed..Will retry
2025-10-08 14:43:57,015 - DEBUG - Response for deleting network ()
2025-10-08 14:43:57,015 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/a0441fb7-14c6-42f9-8b20-96292d5999b7
2025-10-08 14:43:57,038 - DEBUG - Response Code: 404
2025-10-08 14:43:57,038 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:57,064 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:57,086 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:57,210 - INFO - Validated that VN ctest-right_vn-63690984 is not found in API Server
2025-10-08 14:43:57,222 - DEBUG - VN ctest-right_vn-63690984 is not present in Agent 10.0.0.61
2025-10-08 14:43:57,233 - DEBUG - VN ctest-right_vn-63690984 is not present in Agent 10.0.0.241
2025-10-08 14:43:57,234 - INFO - Validated that VN ctest-right_vn-63690984 is not in any agent
2025-10-08 14:43:57,267 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-63690984 info
2025-10-08 14:43:57,267 - INFO - Deleting VN ctest-left_vn-59290584
2025-10-08 14:43:57,429 - DEBUG - Response for deleting network ()
2025-10-08 14:43:57,429 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4e86a880-6673-493e-9b23-455ef66d4169
2025-10-08 14:43:57,439 - DEBUG - Response Code: 404
2025-10-08 14:43:57,440 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:57,453 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:57,473 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:57,578 - INFO - Validated that VN ctest-left_vn-59290584 is not found in API Server
2025-10-08 14:43:57,594 - DEBUG - VN ctest-left_vn-59290584 is not present in Agent 10.0.0.61
2025-10-08 14:43:57,608 - DEBUG - VN ctest-left_vn-59290584 is not present in Agent 10.0.0.241
2025-10-08 14:43:57,608 - INFO - Validated that VN ctest-left_vn-59290584 is not in any agent
2025-10-08 14:43:57,641 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-59290584 info
2025-10-08 14:43:57,642 - INFO - Deleting VN ctest-mgmt_vn-70246078
2025-10-08 14:43:57,841 - DEBUG - Response for deleting network ()
2025-10-08 14:43:57,841 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/bc3828e4-97dd-442c-83b1-b170516108ca
2025-10-08 14:43:57,856 - DEBUG - Response Code: 404
2025-10-08 14:43:57,856 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:43:57,865 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:43:57,886 - DEBUG - Requesting: http://10.0.0.133:8082/project/b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:43:57,993 - INFO - Validated that VN ctest-mgmt_vn-70246078 is not found in API Server
2025-10-08 14:43:58,006 - DEBUG - VN ctest-mgmt_vn-70246078 is not present in Agent 10.0.0.61
2025-10-08 14:43:58,021 - DEBUG - VN ctest-mgmt_vn-70246078 is not present in Agent 10.0.0.241
2025-10-08 14:43:58,021 - INFO - Validated that VN ctest-mgmt_vn-70246078 is not in any agent
2025-10-08 14:43:58,065 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-70246078 info
2025-10-08 14:43:59,348 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 14:43:59,348 - INFO -
2025-10-08 14:43:59,348 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2025-10-08 14:43:59,348 - INFO - --------------------------------------------------------------------------------
2025-10-08 14:44:00,212 - INFO - Deleted project: ctest-TestSvcRegr-59382824, ID : b0099da6-a388-40e2-aa23-b240ea4379bc
2025-10-08 14:44:00,444 - INFO - Domain Default found not creating
2025-10-08 14:44:00,658 - INFO - Project ctest-TestSvcRegr-26991768 not found, creating it
2025-10-08 14:44:01,334 - INFO - Created Project:ctest-TestSvcRegr-26991768, ID : 3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:03,064 - INFO - ================================================================================
2025-10-08 14:44:03,064 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-08 14:44:04,363 - DEBUG - Nothing to compare xmpp stats {'10.0.0.61': {'10.20.0.249': '0', '10.20.0.252': '0'}, '10.0.0.241': {'10.20.0.252': '0', '10.20.0.249': '0'}} with
2025-10-08 14:44:04,364 - INFO - Initial checks done. Running the testcase now
2025-10-08 14:44:04,364 - INFO -
2025-10-08 14:44:05,137 - DEBUG - Response for create_network : {'network': {'id': '57bdfe38-5461-4da7-a209-e5f12148e331', 'name': 'ctest-mgmt_vn-50687886', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'project_id': '3765d555234944e0aac5f22861f208a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26991768', 'ctest-mgmt_vn-50687886'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:44:05,442 - DEBUG - Response for create_subnet : {'subnet': {'id': '97f428df-1467-4d8d-a079-f889cfa1a682', 'name': '', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'network_id': '57bdfe38-5461-4da7-a209-e5f12148e331', 'ip_version': 4, 'cidr': '83.131.23.128/26', 'allocation_pools': [{'start': '83.131.23.130', 'end': '83.131.23.190'}], 'gateway_ip': '83.131.23.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '83.131.23.130', 'tags': [], 'project_id': '3765d555234944e0aac5f22861f208a8'}}
2025-10-08 14:44:05,474 - INFO - Created VN ctest-mgmt_vn-50687886
2025-10-08 14:44:05,488 - DEBUG - VN ctest-mgmt_vn-50687886 UUID is 57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:05,623 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:06,087 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:06,128 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:06,147 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:06,270 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:06,287 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:06,300 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4f5bb439-8f4b-4ad6-8921-f9bff2bb1d4e
2025-10-08 14:44:06,310 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4f5bb439-8f4b-4ad6-8921-f9bff2bb1d4e
2025-10-08 14:44:06,320 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/4725fef4-4fa2-4135-9622-13b28b9d3dd1
2025-10-08 14:44:06,330 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:06,330 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:06,346 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4f5bb439-8f4b-4ad6-8921-f9bff2bb1d4e
2025-10-08 14:44:06,370 - INFO - Verified VN network id 14 for VN 57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:06,370 - INFO - Verifications in API Server for VN ctest-mgmt_vn-50687886 passed
2025-10-08 14:44:06,370 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:06,387 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4f5bb439-8f4b-4ad6-8921-f9bff2bb1d4e
2025-10-08 14:44:06,401 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4f5bb439-8f4b-4ad6-8921-f9bff2bb1d4e
2025-10-08 14:44:06,413 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/4725fef4-4fa2-4135-9622-13b28b9d3dd1
2025-10-08 14:44:06,431 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6322488969785331111', 'uuid-lslong': '11676116332743025457'}, 'enable': 'true', 'created': '2025-10-08T14:44:05', 'last-modified': '2025-10-08T14:44:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.070407'}
2025-10-08 14:44:06,440 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:06,446 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6322488969785331111', 'uuid-lslong': '11676116332743025457'}, 'enable': 'true', 'created': '2025-10-08T14:44:05', 'last-modified': '2025-10-08T14:44:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.087080'}
2025-10-08 14:44:06,454 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:06,462 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6322488969785331111', 'uuid-lslong': '11676116332743025457'}, 'enable': 'true', 'created': '2025-10-08T14:44:05', 'last-modified': '2025-10-08T14:44:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.101547'}
2025-10-08 14:44:06,468 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:06,468 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-50687886 passed
2025-10-08 14:44:06,469 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-50687886 in API_Server ======
2025-10-08 14:44:06,469 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:06,478 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:06,503 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:06,604 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/57bdfe38-5461-4da7-a209-e5f12148e331
2025-10-08 14:44:06,619 - DEBUG - =>VN ctest-mgmt_vn-50687886 has no policy to be verified
2025-10-08 14:44:06,619 - DEBUG - Verifying the vn in opserver
2025-10-08 14:44:06,619 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886 virtual network link through opserver 10.0.0.133
2025-10-08 14:44:06,619 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:06,944 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:07,030 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886?flat'}
2025-10-08 14:44:07,030 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886 virtual network link through opserver 10.0.0.50
2025-10-08 14:44:07,030 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:07,362 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:07,427 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886?flat'}
2025-10-08 14:44:07,427 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886 virtual network link through opserver 10.0.0.242
2025-10-08 14:44:07,427 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:07,748 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:07,827 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886?flat'}
2025-10-08 14:44:07,827 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-50687886 is found in opserver
2025-10-08 14:44:07,849 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:44:07,861 - DEBUG - VRF ids for VN ctest-mgmt_vn-50687886: {}
2025-10-08 14:44:08,186 - DEBUG - Response for create_network : {'network': {'id': 'fdda381a-b728-4b2f-8b4c-7b69d3b5800a', 'name': 'service-left-vn-60671441', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'project_id': '3765d555234944e0aac5f22861f208a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26991768', 'service-left-vn-60671441'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:44:08,457 - DEBUG - Response for create_subnet : {'subnet': {'id': '44e261ad-f127-488a-98c7-d781866a10bb', 'name': '', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'network_id': 'fdda381a-b728-4b2f-8b4c-7b69d3b5800a', 'ip_version': 4, 'cidr': '15.40.37.128/26', 'allocation_pools': [{'start': '15.40.37.130', 'end': '15.40.37.190'}], 'gateway_ip': '15.40.37.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '15.40.37.130', 'tags': [], 'project_id': '3765d555234944e0aac5f22861f208a8'}}
2025-10-08 14:44:08,489 - INFO - Created VN service-left-vn-60671441
2025-10-08 14:44:08,504 - DEBUG - VN service-left-vn-60671441 UUID is fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:08,612 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:08,620 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:08,636 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:08,733 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:08,755 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:08,766 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ab864d1a-25f6-433b-b69c-9a1728be6086
2025-10-08 14:44:08,780 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ab864d1a-25f6-433b-b69c-9a1728be6086
2025-10-08 14:44:08,790 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/1ae57f89-5cb9-46ba-8755-22943869c22f
2025-10-08 14:44:08,798 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:08,798 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:08,809 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ab864d1a-25f6-433b-b69c-9a1728be6086
2025-10-08 14:44:08,826 - INFO - Verified VN network id 15 for VN fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:08,826 - INFO - Verifications in API Server for VN service-left-vn-60671441 passed
2025-10-08 14:44:08,826 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:08,838 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ab864d1a-25f6-433b-b69c-9a1728be6086
2025-10-08 14:44:08,847 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ab864d1a-25f6-433b-b69c-9a1728be6086
2025-10-08 14:44:08,856 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/1ae57f89-5cb9-46ba-8755-22943869c22f
2025-10-08 14:44:08,876 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18291994523911867183', 'uuid-lslong': '10037533363955662858'}, 'enable': 'true', 'created': '2025-10-08T14:44:08', 'last-modified': '2025-10-08T14:44:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477367'}
2025-10-08 14:44:08,884 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:08,892 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18291994523911867183', 'uuid-lslong': '10037533363955662858'}, 'enable': 'true', 'created': '2025-10-08T14:44:08', 'last-modified': '2025-10-08T14:44:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.494475'}
2025-10-08 14:44:08,899 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:08,907 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18291994523911867183', 'uuid-lslong': '10037533363955662858'}, 'enable': 'true', 'created': '2025-10-08T14:44:08', 'last-modified': '2025-10-08T14:44:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512067'}
2025-10-08 14:44:08,914 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:08,915 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-60671441 passed
2025-10-08 14:44:08,915 - DEBUG - ====Verifying policy data for service-left-vn-60671441 in API_Server ======
2025-10-08 14:44:08,915 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:08,924 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:08,939 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:09,069 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/fdda381a-b728-4b2f-8b4c-7b69d3b5800a
2025-10-08 14:44:09,095 - DEBUG - =>VN service-left-vn-60671441 has no policy to be verified
2025-10-08 14:44:09,095 - DEBUG - Verifying the vn in opserver
2025-10-08 14:44:09,095 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441 virtual network link through opserver 10.0.0.133
2025-10-08 14:44:09,095 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:09,173 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441?flat'}
2025-10-08 14:44:09,173 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441 virtual network link through opserver 10.0.0.50
2025-10-08 14:44:09,173 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:09,262 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441?flat'}
2025-10-08 14:44:09,262 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441 virtual network link through opserver 10.0.0.242
2025-10-08 14:44:09,262 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:09,355 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441?flat'}
2025-10-08 14:44:09,356 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26991768:service-left-vn-60671441 is found in opserver
2025-10-08 14:44:09,379 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:44:09,394 - DEBUG - VRF ids for VN service-left-vn-60671441: {}
2025-10-08 14:44:09,703 - DEBUG - Response for create_network : {'network': {'id': '5948c90c-bb5e-4a93-974d-3a5e8548fc45', 'name': 'service-right-vn-63743527', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'project_id': '3765d555234944e0aac5f22861f208a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26991768', 'service-right-vn-63743527'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:44:09,964 - DEBUG - Response for create_subnet : {'subnet': {'id': '848ce08f-119d-4da0-815b-50c9cc940a37', 'name': '', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'network_id': '5948c90c-bb5e-4a93-974d-3a5e8548fc45', 'ip_version': 4, 'cidr': '20.213.47.192/26', 'allocation_pools': [{'start': '20.213.47.194', 'end': '20.213.47.254'}], 'gateway_ip': '20.213.47.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.213.47.194', 'tags': [], 'project_id': '3765d555234944e0aac5f22861f208a8'}}
2025-10-08 14:44:09,991 - INFO - Created VN service-right-vn-63743527
2025-10-08 14:44:10,005 - DEBUG - VN service-right-vn-63743527 UUID is 5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,111 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:10,119 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:10,133 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:10,256 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,281 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,297 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ee4a305c-8372-446a-a1ee-e69f45023bed
2025-10-08 14:44:10,314 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ee4a305c-8372-446a-a1ee-e69f45023bed
2025-10-08 14:44:10,324 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/1c6e90a8-4e60-48fb-aac9-98858746f9e8
2025-10-08 14:44:10,336 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:10,336 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,351 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ee4a305c-8372-446a-a1ee-e69f45023bed
2025-10-08 14:44:10,376 - INFO - Verified VN network id 16 for VN 5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,376 - INFO - Verifications in API Server for VN service-right-vn-63743527 passed
2025-10-08 14:44:10,376 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,389 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ee4a305c-8372-446a-a1ee-e69f45023bed
2025-10-08 14:44:10,398 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ee4a305c-8372-446a-a1ee-e69f45023bed
2025-10-08 14:44:10,407 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/1c6e90a8-4e60-48fb-aac9-98858746f9e8
2025-10-08 14:44:10,423 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6433613124219062931', 'uuid-lslong': '10902434450571328581'}, 'enable': 'true', 'created': '2025-10-08T14:44:09', 'last-modified': '2025-10-08T14:44:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517867'}
2025-10-08 14:44:10,431 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:10,438 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6433613124219062931', 'uuid-lslong': '10902434450571328581'}, 'enable': 'true', 'created': '2025-10-08T14:44:09', 'last-modified': '2025-10-08T14:44:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.541238'}
2025-10-08 14:44:10,445 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:10,451 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6433613124219062931', 'uuid-lslong': '10902434450571328581'}, 'enable': 'true', 'created': '2025-10-08T14:44:09', 'last-modified': '2025-10-08T14:44:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549609'}
2025-10-08 14:44:10,457 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:10,457 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-63743527 passed
2025-10-08 14:44:10,458 - DEBUG - ====Verifying policy data for service-right-vn-63743527 in API_Server ======
2025-10-08 14:44:10,458 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:10,465 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:10,479 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:10,588 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/5948c90c-bb5e-4a93-974d-3a5e8548fc45
2025-10-08 14:44:10,602 - DEBUG - =>VN service-right-vn-63743527 has no policy to be verified
2025-10-08 14:44:10,602 - DEBUG - Verifying the vn in opserver
2025-10-08 14:44:10,602 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527 virtual network link through opserver 10.0.0.133
2025-10-08 14:44:10,602 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:10,692 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527?flat'}
2025-10-08 14:44:10,692 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527 virtual network link through opserver 10.0.0.50
2025-10-08 14:44:10,692 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:10,783 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527?flat'}
2025-10-08 14:44:10,783 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527 virtual network link through opserver 10.0.0.242
2025-10-08 14:44:10,783 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:10,877 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527?flat'}
2025-10-08 14:44:10,877 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26991768:service-right-vn-63743527 is found in opserver
2025-10-08 14:44:10,902 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:44:10,917 - DEBUG - VRF ids for VN service-right-vn-63743527: {}
2025-10-08 14:44:10,996 - DEBUG - Services list from nova: [, , , ]
2025-10-08 14:44:12,267 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 14:44:13,871 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1)
2025-10-08 14:44:13,872 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-38144586']
2025-10-08 14:44:14,181 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-38144586'] created succesfully
2025-10-08 14:44:14,633 - DEBUG - Image by name tiny_nat_fw not found
2025-10-08 14:44:14,633 - DEBUG - Installing image tiny_nat_fw
2025-10-08 14:44:14,633 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-08 14:44:14,634 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:44:14,634 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:44:14,634 - DEBUG - Unzip image
2025-10-08 14:44:14,634 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:44:14,657 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-08 14:44:14,657 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-38144586']
2025-10-08 14:44:14,760 - INFO - Service template: ['default-domain', 'ctest-service_template_1-38144586'] deleted successfully.
2025-10-08 14:44:14,760 - INFO - Skip interface_detach for VM ctest-right_vm-32231525 in state BUILD
2025-10-08 14:44:14,761 - INFO - Deleting VM ctest-right_vm-32231525
2025-10-08 14:44:14,797 - ERROR - VM ctest-right_vm-32231525 has failed to come up
2025-10-08 14:44:14,797 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:14,797 - ERROR - VM ctest-right_vm-32231525 has failed to come up
2025-10-08 14:44:14,797 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:14,886 - INFO - Skip interface_detach for VM ctest-left_vm-27034755 in state BUILD
2025-10-08 14:44:14,886 - INFO - Deleting VM ctest-left_vm-27034755
2025-10-08 14:44:14,886 - ERROR - VM ctest-left_vm-27034755 has failed to come up
2025-10-08 14:44:14,886 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:14,886 - ERROR - VM ctest-left_vm-27034755 has failed to come up
2025-10-08 14:44:14,887 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:15,030 - INFO - Deleting VN service-right-vn-63743527
2025-10-08 14:44:15,088 - DEBUG - VN 5948c90c-bb5e-4a93-974d-3a5e8548fc45 still in use: Unable to complete operation on network 5948c90c-bb5e-4a93-974d-3a5e8548fc45. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c292b332-eb1f-4ce5-9bbc-b33c265970b5']
2025-10-08 14:44:15,088 - WARNING - Deleting VN service-right-vn-63743527 failed..Will retry
2025-10-08 14:44:17,347 - DEBUG - Response for deleting network ()
2025-10-08 14:44:17,348 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ee4a305c-8372-446a-a1ee-e69f45023bed
2025-10-08 14:44:17,363 - DEBUG - Response Code: 404
2025-10-08 14:44:17,364 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:17,383 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:17,412 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:17,541 - INFO - Validated that VN service-right-vn-63743527 is not found in API Server
2025-10-08 14:44:17,558 - DEBUG - VN service-right-vn-63743527 is not present in Agent 10.0.0.61
2025-10-08 14:44:17,578 - DEBUG - VN service-right-vn-63743527 is not present in Agent 10.0.0.241
2025-10-08 14:44:17,579 - INFO - Validated that VN service-right-vn-63743527 is not in any agent
2025-10-08 14:44:17,618 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-63743527 info
2025-10-08 14:44:17,618 - INFO - Deleting VN service-left-vn-60671441
2025-10-08 14:44:17,846 - DEBUG - Response for deleting network ()
2025-10-08 14:44:17,846 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/ab864d1a-25f6-433b-b69c-9a1728be6086
2025-10-08 14:44:17,853 - DEBUG - Response Code: 404
2025-10-08 14:44:17,853 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:17,864 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:17,924 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:18,075 - INFO - Validated that VN service-left-vn-60671441 is not found in API Server
2025-10-08 14:44:18,088 - DEBUG - VN service-left-vn-60671441 is not present in Agent 10.0.0.61
2025-10-08 14:44:18,100 - DEBUG - VN service-left-vn-60671441 is not present in Agent 10.0.0.241
2025-10-08 14:44:18,100 - INFO - Validated that VN service-left-vn-60671441 is not in any agent
2025-10-08 14:44:18,144 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-60671441 info
2025-10-08 14:44:18,144 - INFO - Deleting VN ctest-mgmt_vn-50687886
2025-10-08 14:44:18,363 - DEBUG - Response for deleting network ()
2025-10-08 14:44:18,363 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/4f5bb439-8f4b-4ad6-8921-f9bff2bb1d4e
2025-10-08 14:44:18,379 - DEBUG - Response Code: 404
2025-10-08 14:44:18,379 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:18,409 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:18,424 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:18,634 - INFO - Validated that VN ctest-mgmt_vn-50687886 is not found in API Server
2025-10-08 14:44:18,648 - DEBUG - VN ctest-mgmt_vn-50687886 is not present in Agent 10.0.0.61
2025-10-08 14:44:18,662 - DEBUG - VN ctest-mgmt_vn-50687886 is not present in Agent 10.0.0.241
2025-10-08 14:44:18,662 - INFO - Validated that VN ctest-mgmt_vn-50687886 is not in any agent
2025-10-08 14:44:18,715 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-50687886 info
2025-10-08 14:44:20,044 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 14:44:20,044 - INFO -
2025-10-08 14:44:20,044 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:17]
2025-10-08 14:44:20,044 - INFO - --------------------------------------------------------------------------------
2025-10-08 14:44:20,048 - INFO - ================================================================================
2025-10-08 14:44:20,048 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-08 14:44:21,366 - DEBUG - Nothing to compare xmpp stats {'10.0.0.61': {'10.20.0.249': '0', '10.20.0.252': '0'}, '10.0.0.241': {'10.20.0.252': '0', '10.20.0.249': '0'}} with
2025-10-08 14:44:21,366 - INFO - Initial checks done. Running the testcase now
2025-10-08 14:44:21,366 - INFO -
2025-10-08 14:44:21,691 - DEBUG - Response for create_network : {'network': {'id': '007282ed-e8dc-4f71-af8e-e1dc352cae11', 'name': 'ctest-mgmt_vn-89919770', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'project_id': '3765d555234944e0aac5f22861f208a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26991768', 'ctest-mgmt_vn-89919770'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:44:22,071 - DEBUG - Response for create_subnet : {'subnet': {'id': '03a636ea-e298-4fc9-9788-872f5bfe426b', 'name': '', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'network_id': '007282ed-e8dc-4f71-af8e-e1dc352cae11', 'ip_version': 4, 'cidr': '14.36.77.0/26', 'allocation_pools': [{'start': '14.36.77.2', 'end': '14.36.77.62'}], 'gateway_ip': '14.36.77.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '14.36.77.2', 'tags': [], 'project_id': '3765d555234944e0aac5f22861f208a8'}}
2025-10-08 14:44:22,105 - INFO - Created VN ctest-mgmt_vn-89919770
2025-10-08 14:44:22,120 - DEBUG - VN ctest-mgmt_vn-89919770 UUID is 007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,222 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:22,229 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:22,246 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:22,364 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,384 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,396 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/912f2746-2a1b-4f4d-9f19-de24b5b56041
2025-10-08 14:44:22,408 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/912f2746-2a1b-4f4d-9f19-de24b5b56041
2025-10-08 14:44:22,419 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/f1ff8129-15e4-4d62-9454-9baa7815428c
2025-10-08 14:44:22,429 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:22,429 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,440 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/912f2746-2a1b-4f4d-9f19-de24b5b56041
2025-10-08 14:44:22,459 - INFO - Verified VN network id 14 for VN 007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,460 - INFO - Verifications in API Server for VN ctest-mgmt_vn-89919770 passed
2025-10-08 14:44:22,460 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,474 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/912f2746-2a1b-4f4d-9f19-de24b5b56041
2025-10-08 14:44:22,484 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/912f2746-2a1b-4f4d-9f19-de24b5b56041
2025-10-08 14:44:22,494 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/f1ff8129-15e4-4d62-9454-9baa7815428c
2025-10-08 14:44:22,510 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '32232105670627185', 'uuid-lslong': '12650296739231477265'}, 'enable': 'true', 'created': '2025-10-08T14:44:21', 'last-modified': '2025-10-08T14:44:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486455'}
2025-10-08 14:44:22,518 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:22,525 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '32232105670627185', 'uuid-lslong': '12650296739231477265'}, 'enable': 'true', 'created': '2025-10-08T14:44:21', 'last-modified': '2025-10-08T14:44:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.523712'}
2025-10-08 14:44:22,532 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:22,540 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '32232105670627185', 'uuid-lslong': '12650296739231477265'}, 'enable': 'true', 'created': '2025-10-08T14:44:21', 'last-modified': '2025-10-08T14:44:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532643'}
2025-10-08 14:44:22,548 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-08 14:44:22,548 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-89919770 passed
2025-10-08 14:44:22,549 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-89919770 in API_Server ======
2025-10-08 14:44:22,549 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:22,562 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:22,581 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:22,698 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/007282ed-e8dc-4f71-af8e-e1dc352cae11
2025-10-08 14:44:22,709 - DEBUG - =>VN ctest-mgmt_vn-89919770 has no policy to be verified
2025-10-08 14:44:22,709 - DEBUG - Verifying the vn in opserver
2025-10-08 14:44:22,709 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770 virtual network link through opserver 10.0.0.133
2025-10-08 14:44:22,709 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:22,722 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770?flat'}
2025-10-08 14:44:22,723 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770 virtual network link through opserver 10.0.0.50
2025-10-08 14:44:22,723 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:22,739 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770?flat'}
2025-10-08 14:44:22,739 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770 virtual network link through opserver 10.0.0.242
2025-10-08 14:44:22,739 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:22,756 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770?flat'}
2025-10-08 14:44:22,756 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26991768:ctest-mgmt_vn-89919770 is found in opserver
2025-10-08 14:44:22,781 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:44:22,795 - DEBUG - VRF ids for VN ctest-mgmt_vn-89919770: {}
2025-10-08 14:44:23,167 - DEBUG - Response for create_network : {'network': {'id': '8d395ca5-69f5-4086-9915-c2e7cb78d3fb', 'name': 'ctest-left_vn-04091567', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'project_id': '3765d555234944e0aac5f22861f208a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26991768', 'ctest-left_vn-04091567'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:44:23,468 - DEBUG - Response for create_subnet : {'subnet': {'id': '6dc083eb-f818-4e1f-819e-7b5266a84ca6', 'name': '', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'network_id': '8d395ca5-69f5-4086-9915-c2e7cb78d3fb', 'ip_version': 4, 'cidr': '9.254.158.192/26', 'allocation_pools': [{'start': '9.254.158.194', 'end': '9.254.158.254'}], 'gateway_ip': '9.254.158.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '9.254.158.194', 'tags': [], 'project_id': '3765d555234944e0aac5f22861f208a8'}}
2025-10-08 14:44:23,496 - INFO - Created VN ctest-left_vn-04091567
2025-10-08 14:44:23,520 - DEBUG - VN ctest-left_vn-04091567 UUID is 8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:23,611 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:23,625 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:23,642 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:23,759 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:23,775 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:23,787 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/754b581d-2fca-4076-8862-f9db04c18b2d
2025-10-08 14:44:23,799 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/754b581d-2fca-4076-8862-f9db04c18b2d
2025-10-08 14:44:23,810 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/ec61144c-c5fe-480a-b60e-f614cfdeff7d
2025-10-08 14:44:23,820 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:23,820 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:23,832 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/754b581d-2fca-4076-8862-f9db04c18b2d
2025-10-08 14:44:23,856 - INFO - Verified VN network id 15 for VN 8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:23,857 - INFO - Verifications in API Server for VN ctest-left_vn-04091567 passed
2025-10-08 14:44:23,857 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:23,872 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/754b581d-2fca-4076-8862-f9db04c18b2d
2025-10-08 14:44:23,882 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/754b581d-2fca-4076-8862-f9db04c18b2d
2025-10-08 14:44:23,893 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/ec61144c-c5fe-480a-b60e-f614cfdeff7d
2025-10-08 14:44:23,910 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10176266698537386118', 'uuid-lslong': '11030937163120825339'}, 'enable': 'true', 'created': '2025-10-08T14:44:23', 'last-modified': '2025-10-08T14:44:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.518476'}
2025-10-08 14:44:23,920 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:23,929 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10176266698537386118', 'uuid-lslong': '11030937163120825339'}, 'enable': 'true', 'created': '2025-10-08T14:44:23', 'last-modified': '2025-10-08T14:44:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.537203'}
2025-10-08 14:44:23,937 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:23,945 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10176266698537386118', 'uuid-lslong': '11030937163120825339'}, 'enable': 'true', 'created': '2025-10-08T14:44:23', 'last-modified': '2025-10-08T14:44:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.544625'}
2025-10-08 14:44:23,953 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-08 14:44:23,953 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-04091567 passed
2025-10-08 14:44:23,954 - DEBUG - ====Verifying policy data for ctest-left_vn-04091567 in API_Server ======
2025-10-08 14:44:23,954 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:23,962 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:23,977 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:24,088 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/8d395ca5-69f5-4086-9915-c2e7cb78d3fb
2025-10-08 14:44:24,105 - DEBUG - =>VN ctest-left_vn-04091567 has no policy to be verified
2025-10-08 14:44:24,106 - DEBUG - Verifying the vn in opserver
2025-10-08 14:44:24,106 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567 virtual network link through opserver 10.0.0.133
2025-10-08 14:44:24,106 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:24,121 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567?flat'}
2025-10-08 14:44:24,121 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567 virtual network link through opserver 10.0.0.50
2025-10-08 14:44:24,121 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:24,137 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567?flat'}
2025-10-08 14:44:24,137 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567 virtual network link through opserver 10.0.0.242
2025-10-08 14:44:24,137 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:24,151 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567?flat'}
2025-10-08 14:44:24,151 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26991768:ctest-left_vn-04091567 is found in opserver
2025-10-08 14:44:24,176 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:44:24,188 - DEBUG - VRF ids for VN ctest-left_vn-04091567: {}
2025-10-08 14:44:24,539 - DEBUG - Response for create_network : {'network': {'id': 'a4f0b22e-2b1b-41f2-be3e-8289b236fd51', 'name': 'ctest-right_vn-04507998', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'project_id': '3765d555234944e0aac5f22861f208a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-26991768', 'ctest-right_vn-04507998'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 14:44:24,800 - DEBUG - Response for create_subnet : {'subnet': {'id': '957d3055-4b8a-4f54-b7e1-1f970eb82d5e', 'name': '', 'tenant_id': '3765d555234944e0aac5f22861f208a8', 'network_id': 'a4f0b22e-2b1b-41f2-be3e-8289b236fd51', 'ip_version': 4, 'cidr': '95.38.77.192/26', 'allocation_pools': [{'start': '95.38.77.194', 'end': '95.38.77.254'}], 'gateway_ip': '95.38.77.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.38.77.194', 'tags': [], 'project_id': '3765d555234944e0aac5f22861f208a8'}}
2025-10-08 14:44:24,832 - INFO - Created VN ctest-right_vn-04507998
2025-10-08 14:44:24,847 - DEBUG - VN ctest-right_vn-04507998 UUID is a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:24,940 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:24,947 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:24,962 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:25,059 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:25,073 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:25,084 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/99e85f6f-0cbe-48e6-8dd9-d79ff92a0c2a
2025-10-08 14:44:25,097 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/99e85f6f-0cbe-48e6-8dd9-d79ff92a0c2a
2025-10-08 14:44:25,109 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/41b2e026-4f0a-49fd-a7c5-5a1438d659b6
2025-10-08 14:44:25,121 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:25,121 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:25,133 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/99e85f6f-0cbe-48e6-8dd9-d79ff92a0c2a
2025-10-08 14:44:25,154 - INFO - Verified VN network id 16 for VN a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:25,155 - INFO - Verifications in API Server for VN ctest-right_vn-04507998 passed
2025-10-08 14:44:25,155 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:25,168 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/99e85f6f-0cbe-48e6-8dd9-d79ff92a0c2a
2025-10-08 14:44:25,178 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/99e85f6f-0cbe-48e6-8dd9-d79ff92a0c2a
2025-10-08 14:44:25,188 - DEBUG - Requesting: http://10.0.0.133:8082/route-target/41b2e026-4f0a-49fd-a7c5-5a1438d659b6
2025-10-08 14:44:25,204 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11885195327992185330', 'uuid-lslong': '13708537843674447185'}, 'enable': 'true', 'created': '2025-10-08T14:44:24', 'last-modified': '2025-10-08T14:44:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.459712'}
2025-10-08 14:44:25,214 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:25,220 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11885195327992185330', 'uuid-lslong': '13708537843674447185'}, 'enable': 'true', 'created': '2025-10-08T14:44:24', 'last-modified': '2025-10-08T14:44:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.468815'}
2025-10-08 14:44:25,227 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:25,233 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-26991768', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11885195327992185330', 'uuid-lslong': '13708537843674447185'}, 'enable': 'true', 'created': '2025-10-08T14:44:24', 'last-modified': '2025-10-08T14:44:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.489992'}
2025-10-08 14:44:25,240 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-08 14:44:25,240 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-04507998 passed
2025-10-08 14:44:25,240 - DEBUG - ====Verifying policy data for ctest-right_vn-04507998 in API_Server ======
2025-10-08 14:44:25,240 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:25,249 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:25,267 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:25,362 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-network/a4f0b22e-2b1b-41f2-be3e-8289b236fd51
2025-10-08 14:44:25,375 - DEBUG - =>VN ctest-right_vn-04507998 has no policy to be verified
2025-10-08 14:44:25,376 - DEBUG - Verifying the vn in opserver
2025-10-08 14:44:25,376 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998 virtual network link through opserver 10.0.0.133
2025-10-08 14:44:25,376 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-08 14:44:25,392 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998?flat'}
2025-10-08 14:44:25,392 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998 virtual network link through opserver 10.0.0.50
2025-10-08 14:44:25,392 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-10-08 14:44:25,407 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998?flat'}
2025-10-08 14:44:25,407 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998 virtual network link through opserver 10.0.0.242
2025-10-08 14:44:25,408 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-08 14:44:25,425 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998?flat'}
2025-10-08 14:44:25,425 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-26991768:ctest-right_vn-04507998 is found in opserver
2025-10-08 14:44:25,453 - DEBUG - Do not have enough data to verify VN in agent
2025-10-08 14:44:25,468 - DEBUG - VRF ids for VN ctest-right_vn-04507998: {}
2025-10-08 14:44:26,648 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1)
2025-10-08 14:44:28,079 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 14:44:28,080 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-06336168']
2025-10-08 14:44:28,421 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-06336168'] created succesfully
2025-10-08 14:44:28,831 - DEBUG - Image by name tiny_nat_fw not found
2025-10-08 14:44:28,831 - DEBUG - Installing image tiny_nat_fw
2025-10-08 14:44:28,831 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-08 14:44:28,831 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:44:28,831 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:44:28,831 - DEBUG - Unzip image
2025-10-08 14:44:28,831 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-08 14:44:28,870 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-08 14:44:28,870 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-06336168']
2025-10-08 14:44:29,020 - INFO - Service template: ['default-domain', 'ctest-service_template_1-06336168'] deleted successfully.
2025-10-08 14:44:29,020 - INFO - Skip interface_detach for VM ctest-right_vm-98011320 in state BUILD
2025-10-08 14:44:29,020 - INFO - Deleting VM ctest-right_vm-98011320
2025-10-08 14:44:29,021 - ERROR - VM ctest-right_vm-98011320 has failed to come up
2025-10-08 14:44:29,021 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:29,021 - ERROR - VM ctest-right_vm-98011320 has failed to come up
2025-10-08 14:44:29,021 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:29,143 - INFO - Skip interface_detach for VM ctest-left_vm-77656799 in state BUILD
2025-10-08 14:44:29,143 - INFO - Deleting VM ctest-left_vm-77656799
2025-10-08 14:44:29,143 - ERROR - VM ctest-left_vm-77656799 has failed to come up
2025-10-08 14:44:29,144 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:29,144 - ERROR - VM ctest-left_vm-77656799 has failed to come up
2025-10-08 14:44:29,144 - ERROR - Nova failed to get host of the VM
2025-10-08 14:44:29,283 - INFO - Deleting VN ctest-right_vn-04507998
2025-10-08 14:44:29,330 - DEBUG - VN a4f0b22e-2b1b-41f2-be3e-8289b236fd51 still in use: Unable to complete operation on network a4f0b22e-2b1b-41f2-be3e-8289b236fd51. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0d02ec84-a6f1-48af-848a-08b28db55d4e']
2025-10-08 14:44:29,330 - WARNING - Deleting VN ctest-right_vn-04507998 failed..Will retry
2025-10-08 14:44:31,491 - DEBUG - Response for deleting network ()
2025-10-08 14:44:31,491 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/99e85f6f-0cbe-48e6-8dd9-d79ff92a0c2a
2025-10-08 14:44:31,498 - DEBUG - Response Code: 404
2025-10-08 14:44:31,498 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:31,514 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:31,534 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:31,627 - INFO - Validated that VN ctest-right_vn-04507998 is not found in API Server
2025-10-08 14:44:31,648 - DEBUG - VN ctest-right_vn-04507998 is not present in Agent 10.0.0.61
2025-10-08 14:44:31,665 - DEBUG - VN ctest-right_vn-04507998 is not present in Agent 10.0.0.241
2025-10-08 14:44:31,665 - INFO - Validated that VN ctest-right_vn-04507998 is not in any agent
2025-10-08 14:44:31,702 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-04507998 info
2025-10-08 14:44:31,702 - INFO - Deleting VN ctest-left_vn-04091567
2025-10-08 14:44:31,880 - DEBUG - Response for deleting network ()
2025-10-08 14:44:31,880 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/754b581d-2fca-4076-8862-f9db04c18b2d
2025-10-08 14:44:31,889 - DEBUG - Response Code: 404
2025-10-08 14:44:31,890 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:31,899 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:31,925 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:32,027 - INFO - Validated that VN ctest-left_vn-04091567 is not found in API Server
2025-10-08 14:44:32,042 - DEBUG - VN ctest-left_vn-04091567 is not present in Agent 10.0.0.61
2025-10-08 14:44:32,056 - DEBUG - VN ctest-left_vn-04091567 is not present in Agent 10.0.0.241
2025-10-08 14:44:32,056 - INFO - Validated that VN ctest-left_vn-04091567 is not in any agent
2025-10-08 14:44:32,092 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-04091567 info
2025-10-08 14:44:32,092 - INFO - Deleting VN ctest-mgmt_vn-89919770
2025-10-08 14:44:32,246 - DEBUG - Response for deleting network ()
2025-10-08 14:44:32,246 - DEBUG - Requesting: http://10.0.0.133:8082/routing-instance/912f2746-2a1b-4f4d-9f19-de24b5b56041
2025-10-08 14:44:32,253 - DEBUG - Response Code: 404
2025-10-08 14:44:32,253 - DEBUG - Requesting: http://10.0.0.133:8082/domains
2025-10-08 14:44:32,265 - DEBUG - Requesting: http://10.0.0.133:8082/domain/adc52b80-2ed1-485c-90d4-83e78c8e1400
2025-10-08 14:44:32,283 - DEBUG - Requesting: http://10.0.0.133:8082/project/3765d555-2349-44e0-aac5-f22861f208a8
2025-10-08 14:44:32,396 - INFO - Validated that VN ctest-mgmt_vn-89919770 is not found in API Server
2025-10-08 14:44:32,408 - DEBUG - VN ctest-mgmt_vn-89919770 is not present in Agent 10.0.0.61
2025-10-08 14:44:32,418 - DEBUG - VN ctest-mgmt_vn-89919770 is not present in Agent 10.0.0.241
2025-10-08 14:44:32,418 - INFO - Validated that VN ctest-mgmt_vn-89919770 is not in any agent
2025-10-08 14:44:32,451 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-89919770 info
2025-10-08 14:44:33,750 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 14:44:33,750 - INFO -
2025-10-08 14:44:33,751 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-10-08 14:44:33,751 - INFO - --------------------------------------------------------------------------------
2025-10-08 14:44:34,497 - INFO - Deleted project: ctest-TestSvcRegr-26991768, ID : 3765d555-2349-44e0-aac5-f22861f208a8