]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-1)
2026-03-16 03:05:02,979 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-76600172']
2026-03-16 03:05:03,301 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-76600172'] created succesfully
2026-03-16 03:05:03,799 - DEBUG - Image by name tiny_nat_fw not found
2026-03-16 03:05:03,801 - DEBUG - Installing image tiny_nat_fw
2026-03-16 03:05:03,801 - 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'"}
2026-03-16 03:05:03,802 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:03,802 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:03,821 - 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
2026-03-16 03:05:03,970 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:03,970 - DEBUG - Unzip image
2026-03-16 03:05:03,970 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:03,993 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-03-16 03:05:03,993 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-76600172']
2026-03-16 03:05:04,110 - INFO - Service template: ['default-domain', 'ctest-service_template_1-76600172'] deleted successfully.
2026-03-16 03:05:04,110 - INFO - Skip interface_detach for VM ctest-right_vm-44564557 in state BUILD
2026-03-16 03:05:04,110 - INFO - Deleting VM ctest-right_vm-44564557
2026-03-16 03:05:04,148 - ERROR - VM ctest-right_vm-44564557 has failed to come up
2026-03-16 03:05:04,148 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:04,148 - ERROR - VM ctest-right_vm-44564557 has failed to come up
2026-03-16 03:05:04,148 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:04,229 - INFO - Skip interface_detach for VM ctest-left_vm-01158484 in state BUILD
2026-03-16 03:05:04,229 - INFO - Deleting VM ctest-left_vm-01158484
2026-03-16 03:05:04,229 - ERROR - VM ctest-left_vm-01158484 has failed to come up
2026-03-16 03:05:04,229 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:04,229 - ERROR - VM ctest-left_vm-01158484 has failed to come up
2026-03-16 03:05:04,229 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:04,304 - INFO - Deleting VN ctest-right_vn-59666951
2026-03-16 03:05:04,354 - DEBUG - VN 158ea337-aee4-474d-bfd5-c424025fbe2e still in use: Unable to complete operation on network 158ea337-aee4-474d-bfd5-c424025fbe2e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0efa2dbb-dcaf-4ca2-8be0-5772413e6abc']
2026-03-16 03:05:04,354 - WARNING - Deleting VN ctest-right_vn-59666951 failed..Will retry
2026-03-16 03:05:06,519 - DEBUG - Response for deleting network ()
2026-03-16 03:05:06,519 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4d6d57dd-6ad3-42dd-b34f-62a5fc238a0c
2026-03-16 03:05:06,527 - DEBUG - Response Code: 404
2026-03-16 03:05:06,528 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:06,535 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:06,565 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:06,669 - INFO - Validated that VN ctest-right_vn-59666951 is not found in API Server
2026-03-16 03:05:06,684 - DEBUG - VN ctest-right_vn-59666951 is not present in Agent 10.0.0.41
2026-03-16 03:05:06,696 - DEBUG - VN ctest-right_vn-59666951 is not present in Agent 10.0.0.240
2026-03-16 03:05:06,696 - INFO - Validated that VN ctest-right_vn-59666951 is not in any agent
2026-03-16 03:05:06,740 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-59666951 info
2026-03-16 03:05:06,740 - INFO - Deleting VN ctest-left_vn-03059150
2026-03-16 03:05:06,903 - DEBUG - Response for deleting network ()
2026-03-16 03:05:06,903 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/858ac0d0-59c5-4819-b8f0-4c0c2d301c07
2026-03-16 03:05:06,909 - DEBUG - Response Code: 404
2026-03-16 03:05:06,909 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:06,915 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:06,932 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:07,021 - INFO - Validated that VN ctest-left_vn-03059150 is not found in API Server
2026-03-16 03:05:07,034 - DEBUG - VN ctest-left_vn-03059150 is not present in Agent 10.0.0.41
2026-03-16 03:05:07,045 - DEBUG - VN ctest-left_vn-03059150 is not present in Agent 10.0.0.240
2026-03-16 03:05:07,045 - INFO - Validated that VN ctest-left_vn-03059150 is not in any agent
2026-03-16 03:05:07,096 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-03059150 info
2026-03-16 03:05:07,097 - INFO - Deleting VN ctest-mgmt_vn-41059839
2026-03-16 03:05:07,291 - DEBUG - Response for deleting network ()
2026-03-16 03:05:07,291 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/fc611c23-4589-4d5b-830c-66851eb537ad
2026-03-16 03:05:07,308 - DEBUG - Response Code: 404
2026-03-16 03:05:07,309 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:07,327 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:07,344 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:07,449 - INFO - Validated that VN ctest-mgmt_vn-41059839 is not found in API Server
2026-03-16 03:05:07,464 - DEBUG - VN ctest-mgmt_vn-41059839 is not present in Agent 10.0.0.41
2026-03-16 03:05:07,476 - DEBUG - VN ctest-mgmt_vn-41059839 is not present in Agent 10.0.0.240
2026-03-16 03:05:07,476 - INFO - Validated that VN ctest-mgmt_vn-41059839 is not in any agent
2026-03-16 03:05:07,519 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-41059839 info
2026-03-16 03:05:07,519 - INFO - Deleting VM ctest-vm-in-third-vn-11046624
2026-03-16 03:05:07,610 - INFO - Deleting VN ctest-third-vn-28731150
2026-03-16 03:05:07,655 - DEBUG - VN 76948abf-87e7-4b29-a283-e24d0547d5b5 still in use: Unable to complete operation on network 76948abf-87e7-4b29-a283-e24d0547d5b5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6af69ffd-b1b1-4162-a794-84187645f8d8']
2026-03-16 03:05:07,656 - WARNING - Deleting VN ctest-third-vn-28731150 failed..Will retry
2026-03-16 03:05:09,844 - DEBUG - Response for deleting network ()
2026-03-16 03:05:11,140 - DEBUG - No XMPP flaps were noticed during the test
2026-03-16 03:05:11,140 - INFO -
2026-03-16 03:05:11,140 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2026-03-16 03:05:11,140 - INFO - --------------------------------------------------------------------------------
2026-03-16 03:05:11,146 - INFO - ================================================================================
2026-03-16 03:05:11,146 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-03-16 03:05:12,440 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.240': {'10.20.0.193': '0', '10.20.0.129': '0'}} with
2026-03-16 03:05:12,440 - INFO - Initial checks done. Running the testcase now
2026-03-16 03:05:12,440 - INFO -
2026-03-16 03:05:12,771 - DEBUG - Response for create_network : {'network': {'id': '9a644454-effe-44b8-a8c8-ef12e403e2b0', 'name': 'ctest-mgmt_vn-58419575', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'project_id': '3287debaee9f486380651607428e8eb3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-98149332', 'ctest-mgmt_vn-58419575'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:13,044 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ef012ccc-844a-4239-85f7-b253cdb22e11', 'name': '', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'network_id': '9a644454-effe-44b8-a8c8-ef12e403e2b0', 'ip_version': 4, 'cidr': '52.142.185.64/26', 'allocation_pools': [{'start': '52.142.185.66', 'end': '52.142.185.126'}], 'gateway_ip': '52.142.185.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.142.185.66', 'tags': [], 'project_id': '3287debaee9f486380651607428e8eb3'}}
2026-03-16 03:05:13,074 - INFO - Created VN ctest-mgmt_vn-58419575
2026-03-16 03:05:13,093 - DEBUG - VN ctest-mgmt_vn-58419575 UUID is 9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,191 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:13,199 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:13,213 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:13,337 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,357 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,380 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4e99da2d-d23a-4070-a8a5-71df19193c45
2026-03-16 03:05:13,396 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4e99da2d-d23a-4070-a8a5-71df19193c45
2026-03-16 03:05:13,404 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/fcf4a45b-99b0-47d8-9e4d-bba7e2efaaa0
2026-03-16 03:05:13,413 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:13,414 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,426 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4e99da2d-d23a-4070-a8a5-71df19193c45
2026-03-16 03:05:13,445 - INFO - Verified VN network id 14 for VN 9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,445 - INFO - Verifications in API Server for VN ctest-mgmt_vn-58419575 passed
2026-03-16 03:05:13,445 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,465 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4e99da2d-d23a-4070-a8a5-71df19193c45
2026-03-16 03:05:13,479 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4e99da2d-d23a-4070-a8a5-71df19193c45
2026-03-16 03:05:13,490 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/fcf4a45b-99b0-47d8-9e4d-bba7e2efaaa0
2026-03-16 03:05:13,517 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11125092111106327736', 'uuid-lslong': '12162233658127934128'}, 'enable': 'true', 'created': '2026-03-16T03:05:12', 'last-modified': '2026-03-16T03:05:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.539237'}
2026-03-16 03:05:13,528 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:13,534 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11125092111106327736', 'uuid-lslong': '12162233658127934128'}, 'enable': 'true', 'created': '2026-03-16T03:05:12', 'last-modified': '2026-03-16T03:05:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.562769'}
2026-03-16 03:05:13,543 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:13,554 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11125092111106327736', 'uuid-lslong': '12162233658127934128'}, 'enable': 'true', 'created': '2026-03-16T03:05:12', 'last-modified': '2026-03-16T03:05:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.578589'}
2026-03-16 03:05:13,565 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:13,565 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-58419575 passed
2026-03-16 03:05:13,567 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-58419575 in API_Server ======
2026-03-16 03:05:13,567 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:13,578 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:13,596 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:13,703 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a644454-effe-44b8-a8c8-ef12e403e2b0
2026-03-16 03:05:13,717 - DEBUG - =>VN ctest-mgmt_vn-58419575 has no policy to be verified
2026-03-16 03:05:13,717 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:13,717 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:13,717 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:13,733 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575?flat'}
2026-03-16 03:05:13,733 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:13,733 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:13,751 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575?flat'}
2026-03-16 03:05:13,751 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:13,751 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:13,772 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575?flat'}
2026-03-16 03:05:13,772 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-98149332:ctest-mgmt_vn-58419575 is found in opserver
2026-03-16 03:05:13,799 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:13,812 - DEBUG - VRF ids for VN ctest-mgmt_vn-58419575: {}
2026-03-16 03:05:14,199 - DEBUG - Response for create_network : {'network': {'id': 'd682411b-c209-4f2c-8797-91314d9e1a11', 'name': 'ctest-left_vn-55161539', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'project_id': '3287debaee9f486380651607428e8eb3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-98149332', 'ctest-left_vn-55161539'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:14,525 - DEBUG - Response for create_subnet : {'subnet': {'id': '20b04ab5-8fcd-450d-8752-cc38c10a3591', 'name': '', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'network_id': 'd682411b-c209-4f2c-8797-91314d9e1a11', 'ip_version': 4, 'cidr': '128.185.51.192/26', 'allocation_pools': [{'start': '128.185.51.194', 'end': '128.185.51.254'}], 'gateway_ip': '128.185.51.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.185.51.194', 'tags': [], 'project_id': '3287debaee9f486380651607428e8eb3'}}
2026-03-16 03:05:14,557 - INFO - Created VN ctest-left_vn-55161539
2026-03-16 03:05:14,578 - DEBUG - VN ctest-left_vn-55161539 UUID is d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:14,681 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:14,689 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:14,708 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:14,807 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:14,826 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:14,850 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9cb11608-e6ac-4b5c-8732-0963df745b1f
2026-03-16 03:05:14,865 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9cb11608-e6ac-4b5c-8732-0963df745b1f
2026-03-16 03:05:14,879 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c3d7a39c-43a4-4c15-8bcf-94a85135dc96
2026-03-16 03:05:14,887 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:14,887 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:14,901 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9cb11608-e6ac-4b5c-8732-0963df745b1f
2026-03-16 03:05:14,919 - INFO - Verified VN network id 15 for VN d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:14,919 - INFO - Verifications in API Server for VN ctest-left_vn-55161539 passed
2026-03-16 03:05:14,919 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:14,932 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9cb11608-e6ac-4b5c-8732-0963df745b1f
2026-03-16 03:05:14,941 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9cb11608-e6ac-4b5c-8732-0963df745b1f
2026-03-16 03:05:14,951 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c3d7a39c-43a4-4c15-8bcf-94a85135dc96
2026-03-16 03:05:14,970 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15456988458564276012', 'uuid-lslong': '9770437557545212433'}, 'enable': 'true', 'created': '2026-03-16T03:05:14', 'last-modified': '2026-03-16T03:05:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.529831'}
2026-03-16 03:05:14,977 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:14,985 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15456988458564276012', 'uuid-lslong': '9770437557545212433'}, 'enable': 'true', 'created': '2026-03-16T03:05:14', 'last-modified': '2026-03-16T03:05:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549256'}
2026-03-16 03:05:14,993 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:15,002 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15456988458564276012', 'uuid-lslong': '9770437557545212433'}, 'enable': 'true', 'created': '2026-03-16T03:05:14', 'last-modified': '2026-03-16T03:05:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.565895'}
2026-03-16 03:05:15,010 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:15,010 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-55161539 passed
2026-03-16 03:05:15,010 - DEBUG - ====Verifying policy data for ctest-left_vn-55161539 in API_Server ======
2026-03-16 03:05:15,010 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:15,018 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:15,036 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:15,135 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d682411b-c209-4f2c-8797-91314d9e1a11
2026-03-16 03:05:15,148 - DEBUG - =>VN ctest-left_vn-55161539 has no policy to be verified
2026-03-16 03:05:15,148 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:15,148 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:15,148 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:15,163 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539?flat'}
2026-03-16 03:05:15,163 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:15,163 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:15,181 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539?flat'}
2026-03-16 03:05:15,181 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:15,181 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:15,195 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539?flat'}
2026-03-16 03:05:15,195 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-98149332:ctest-left_vn-55161539 is found in opserver
2026-03-16 03:05:15,222 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:15,235 - DEBUG - VRF ids for VN ctest-left_vn-55161539: {}
2026-03-16 03:05:15,614 - DEBUG - Response for create_network : {'network': {'id': '23b34de6-f64a-4598-8578-f0359b8e4512', 'name': 'ctest-right_vn-37188303', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'project_id': '3287debaee9f486380651607428e8eb3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-98149332', 'ctest-right_vn-37188303'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:15,923 - DEBUG - Response for create_subnet : {'subnet': {'id': '33b1e887-f8c5-48bc-9dce-1539a92bd9f9', 'name': '', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'network_id': '23b34de6-f64a-4598-8578-f0359b8e4512', 'ip_version': 4, 'cidr': '86.42.22.64/26', 'allocation_pools': [{'start': '86.42.22.66', 'end': '86.42.22.126'}], 'gateway_ip': '86.42.22.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '86.42.22.66', 'tags': [], 'project_id': '3287debaee9f486380651607428e8eb3'}}
2026-03-16 03:05:15,950 - INFO - Created VN ctest-right_vn-37188303
2026-03-16 03:05:15,967 - DEBUG - VN ctest-right_vn-37188303 UUID is 23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,056 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:16,062 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:16,073 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:16,188 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,200 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,210 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bca66bf8-cb13-4991-bbd7-30436f5e52d0
2026-03-16 03:05:16,218 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bca66bf8-cb13-4991-bbd7-30436f5e52d0
2026-03-16 03:05:16,227 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/8d0d43b1-0291-4e72-8e75-1f4b1da149b2
2026-03-16 03:05:16,234 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:16,234 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,247 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bca66bf8-cb13-4991-bbd7-30436f5e52d0
2026-03-16 03:05:16,266 - INFO - Verified VN network id 16 for VN 23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,266 - INFO - Verifications in API Server for VN ctest-right_vn-37188303 passed
2026-03-16 03:05:16,266 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,276 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bca66bf8-cb13-4991-bbd7-30436f5e52d0
2026-03-16 03:05:16,284 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bca66bf8-cb13-4991-bbd7-30436f5e52d0
2026-03-16 03:05:16,294 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/8d0d43b1-0291-4e72-8e75-1f4b1da149b2
2026-03-16 03:05:16,308 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2572485466528564632', 'uuid-lslong': '9617701117283419410'}, 'enable': 'true', 'created': '2026-03-16T03:05:15', 'last-modified': '2026-03-16T03:05:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463757'}
2026-03-16 03:05:16,315 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:16,326 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2572485466528564632', 'uuid-lslong': '9617701117283419410'}, 'enable': 'true', 'created': '2026-03-16T03:05:15', 'last-modified': '2026-03-16T03:05:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477268'}
2026-03-16 03:05:16,333 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:16,341 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2572485466528564632', 'uuid-lslong': '9617701117283419410'}, 'enable': 'true', 'created': '2026-03-16T03:05:15', 'last-modified': '2026-03-16T03:05:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.496963'}
2026-03-16 03:05:16,349 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:16,349 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-37188303 passed
2026-03-16 03:05:16,349 - DEBUG - ====Verifying policy data for ctest-right_vn-37188303 in API_Server ======
2026-03-16 03:05:16,349 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:16,357 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:16,369 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:16,467 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/23b34de6-f64a-4598-8578-f0359b8e4512
2026-03-16 03:05:16,477 - DEBUG - =>VN ctest-right_vn-37188303 has no policy to be verified
2026-03-16 03:05:16,477 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:16,478 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:16,478 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:16,491 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303?flat'}
2026-03-16 03:05:16,491 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:16,491 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:16,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303?flat'}
2026-03-16 03:05:16,504 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:16,504 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:16,518 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303?flat'}
2026-03-16 03:05:16,518 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-98149332:ctest-right_vn-37188303 is found in opserver
2026-03-16 03:05:16,539 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:16,549 - DEBUG - VRF ids for VN ctest-right_vn-37188303: {}
2026-03-16 03:05:16,859 - DEBUG - Response for create_network : {'network': {'id': '0cb3022a-d868-4625-a06c-791ff110959c', 'name': 'ctest-trans_left_vn-02263788', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'project_id': '3287debaee9f486380651607428e8eb3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-98149332', 'ctest-trans_left_vn-02263788'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:17,082 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b09136e1-4ac7-4429-a55f-dd12bf547bdc', 'name': '', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'network_id': '0cb3022a-d868-4625-a06c-791ff110959c', 'ip_version': 4, 'cidr': '65.37.28.192/26', 'allocation_pools': [{'start': '65.37.28.194', 'end': '65.37.28.254'}], 'gateway_ip': '65.37.28.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '65.37.28.194', 'tags': [], 'project_id': '3287debaee9f486380651607428e8eb3'}}
2026-03-16 03:05:17,111 - INFO - Created VN ctest-trans_left_vn-02263788
2026-03-16 03:05:17,125 - DEBUG - VN ctest-trans_left_vn-02263788 UUID is 0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,203 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:17,210 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:17,222 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:17,330 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,347 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,360 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bb7f9bde-a0c9-4db7-8e6b-d3ccfcd827bc
2026-03-16 03:05:17,378 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bb7f9bde-a0c9-4db7-8e6b-d3ccfcd827bc
2026-03-16 03:05:17,391 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/a657e159-0443-4196-bb2f-9f37dadc348e
2026-03-16 03:05:17,401 - DEBUG - Route Targets: ['target:64512:8000007']
2026-03-16 03:05:17,401 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,414 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bb7f9bde-a0c9-4db7-8e6b-d3ccfcd827bc
2026-03-16 03:05:17,451 - INFO - Verified VN network id 17 for VN 0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,451 - INFO - Verifications in API Server for VN ctest-trans_left_vn-02263788 passed
2026-03-16 03:05:17,451 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,473 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bb7f9bde-a0c9-4db7-8e6b-d3ccfcd827bc
2026-03-16 03:05:17,485 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bb7f9bde-a0c9-4db7-8e6b-d3ccfcd827bc
2026-03-16 03:05:17,492 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/a657e159-0443-4196-bb2f-9f37dadc348e
2026-03-16 03:05:17,507 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '915077532328936997', 'uuid-lslong': '11559747521648563612'}, 'enable': 'true', 'created': '2026-03-16T03:05:16', 'last-modified': '2026-03-16T03:05:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.482017'}
2026-03-16 03:05:17,517 - DEBUG - Route Targets: ['target:64512:8000007']
2026-03-16 03:05:17,523 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '915077532328936997', 'uuid-lslong': '11559747521648563612'}, 'enable': 'true', 'created': '2026-03-16T03:05:16', 'last-modified': '2026-03-16T03:05:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.501166'}
2026-03-16 03:05:17,538 - DEBUG - Route Targets: ['target:64512:8000007']
2026-03-16 03:05:17,557 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '915077532328936997', 'uuid-lslong': '11559747521648563612'}, 'enable': 'true', 'created': '2026-03-16T03:05:16', 'last-modified': '2026-03-16T03:05:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534190'}
2026-03-16 03:05:17,567 - DEBUG - Route Targets: ['target:64512:8000007']
2026-03-16 03:05:17,567 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-02263788 passed
2026-03-16 03:05:17,568 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-02263788 in API_Server ======
2026-03-16 03:05:17,568 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:17,575 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:17,585 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:17,710 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0cb3022a-d868-4625-a06c-791ff110959c
2026-03-16 03:05:17,722 - DEBUG - =>VN ctest-trans_left_vn-02263788 has no policy to be verified
2026-03-16 03:05:17,722 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:17,722 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:17,722 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:17,735 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788?flat'}
2026-03-16 03:05:17,735 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:17,735 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:17,754 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788?flat'}
2026-03-16 03:05:17,754 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:17,754 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:17,775 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788?flat'}
2026-03-16 03:05:17,775 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-98149332:ctest-trans_left_vn-02263788 is found in opserver
2026-03-16 03:05:17,810 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:17,828 - DEBUG - VRF ids for VN ctest-trans_left_vn-02263788: {}
2026-03-16 03:05:18,170 - DEBUG - Response for create_network : {'network': {'id': '50210bf5-e273-4cac-8ebe-2bdb0d8287b8', 'name': 'ctest-trans_right_vn-17746183', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'project_id': '3287debaee9f486380651607428e8eb3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-98149332', 'ctest-trans_right_vn-17746183'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:18,431 - DEBUG - Response for create_subnet : {'subnet': {'id': '8a7379f8-0dd9-44ce-a0a8-90e76f909dbb', 'name': '', 'tenant_id': '3287debaee9f486380651607428e8eb3', 'network_id': '50210bf5-e273-4cac-8ebe-2bdb0d8287b8', 'ip_version': 4, 'cidr': '130.160.248.128/26', 'allocation_pools': [{'start': '130.160.248.130', 'end': '130.160.248.190'}], 'gateway_ip': '130.160.248.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.160.248.130', 'tags': [], 'project_id': '3287debaee9f486380651607428e8eb3'}}
2026-03-16 03:05:18,473 - INFO - Created VN ctest-trans_right_vn-17746183
2026-03-16 03:05:18,488 - DEBUG - VN ctest-trans_right_vn-17746183 UUID is 50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:18,577 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:18,584 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:18,603 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:18,697 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:18,724 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:18,733 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4122c9af-bd3e-4f8c-b47b-a48a59482a00
2026-03-16 03:05:18,751 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4122c9af-bd3e-4f8c-b47b-a48a59482a00
2026-03-16 03:05:18,758 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e3897006-44e5-4172-8e05-f484edc602f5
2026-03-16 03:05:18,766 - DEBUG - Route Targets: ['target:64512:8000008']
2026-03-16 03:05:18,766 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:18,775 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4122c9af-bd3e-4f8c-b47b-a48a59482a00
2026-03-16 03:05:18,793 - INFO - Verified VN network id 18 for VN 50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:18,795 - INFO - Verifications in API Server for VN ctest-trans_right_vn-17746183 passed
2026-03-16 03:05:18,795 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:18,805 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4122c9af-bd3e-4f8c-b47b-a48a59482a00
2026-03-16 03:05:18,813 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4122c9af-bd3e-4f8c-b47b-a48a59482a00
2026-03-16 03:05:18,821 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e3897006-44e5-4172-8e05-f484edc602f5
2026-03-16 03:05:18,833 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5773909347959786668', 'uuid-lslong': '10285706818785281976'}, 'enable': 'true', 'created': '2026-03-16T03:05:18', 'last-modified': '2026-03-16T03:05:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462933'}
2026-03-16 03:05:18,844 - DEBUG - Route Targets: ['target:64512:8000008']
2026-03-16 03:05:18,849 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5773909347959786668', 'uuid-lslong': '10285706818785281976'}, 'enable': 'true', 'created': '2026-03-16T03:05:18', 'last-modified': '2026-03-16T03:05:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.480078'}
2026-03-16 03:05:18,856 - DEBUG - Route Targets: ['target:64512:8000008']
2026-03-16 03:05:18,863 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-98149332', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5773909347959786668', 'uuid-lslong': '10285706818785281976'}, 'enable': 'true', 'created': '2026-03-16T03:05:18', 'last-modified': '2026-03-16T03:05:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.492451'}
2026-03-16 03:05:18,870 - DEBUG - Route Targets: ['target:64512:8000008']
2026-03-16 03:05:18,870 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-17746183 passed
2026-03-16 03:05:18,870 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-17746183 in API_Server ======
2026-03-16 03:05:18,870 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:18,876 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:18,890 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:18,989 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/50210bf5-e273-4cac-8ebe-2bdb0d8287b8
2026-03-16 03:05:19,002 - DEBUG - =>VN ctest-trans_right_vn-17746183 has no policy to be verified
2026-03-16 03:05:19,002 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:19,002 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:19,002 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:19,022 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183?flat'}
2026-03-16 03:05:19,022 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:19,022 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:19,035 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183?flat'}
2026-03-16 03:05:19,035 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:19,035 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:19,051 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183?flat'}
2026-03-16 03:05:19,051 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-98149332:ctest-trans_right_vn-17746183 is found in opserver
2026-03-16 03:05:19,073 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:19,086 - DEBUG - VRF ids for VN ctest-trans_right_vn-17746183: {}
2026-03-16 03:05:20,230 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-1)
2026-03-16 03:05:21,659 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-2)
2026-03-16 03:05:21,659 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-93038242']
2026-03-16 03:05:21,976 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-93038242'] created succesfully
2026-03-16 03:05:22,404 - DEBUG - Image by name tiny_trans_fw not found
2026-03-16 03:05:22,405 - DEBUG - Installing image tiny_trans_fw
2026-03-16 03:05:22,405 - 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'}
2026-03-16 03:05:22,405 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-03-16 03:05:22,405 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-03-16 03:05:22,427 - 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
2026-03-16 03:05:22,471 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-03-16 03:05:22,471 - DEBUG - Unzip image
2026-03-16 03:05:22,471 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-03-16 03:05:22,489 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-03-16 03:05:22,490 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-93038242']
2026-03-16 03:05:22,621 - INFO - Service template: ['default-domain', 'ctest-service_template_1-93038242'] deleted successfully.
2026-03-16 03:05:22,621 - INFO - Skip interface_detach for VM ctest-right_vm-99552182 in state BUILD
2026-03-16 03:05:22,621 - INFO - Deleting VM ctest-right_vm-99552182
2026-03-16 03:05:22,621 - ERROR - VM ctest-right_vm-99552182 has failed to come up
2026-03-16 03:05:22,621 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:22,621 - ERROR - VM ctest-right_vm-99552182 has failed to come up
2026-03-16 03:05:22,622 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:22,744 - INFO - Skip interface_detach for VM ctest-left_vm-57854442 in state BUILD
2026-03-16 03:05:22,744 - INFO - Deleting VM ctest-left_vm-57854442
2026-03-16 03:05:22,744 - ERROR - VM ctest-left_vm-57854442 has failed to come up
2026-03-16 03:05:22,744 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:22,744 - ERROR - VM ctest-left_vm-57854442 has failed to come up
2026-03-16 03:05:22,744 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:22,809 - INFO - Deleting VN ctest-trans_right_vn-17746183
2026-03-16 03:05:22,978 - DEBUG - Response for deleting network ()
2026-03-16 03:05:22,978 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4122c9af-bd3e-4f8c-b47b-a48a59482a00
2026-03-16 03:05:22,984 - DEBUG - Response Code: 404
2026-03-16 03:05:22,985 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:23,000 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:23,023 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:23,132 - INFO - Validated that VN ctest-trans_right_vn-17746183 is not found in API Server
2026-03-16 03:05:23,145 - DEBUG - VN ctest-trans_right_vn-17746183 is not present in Agent 10.0.0.41
2026-03-16 03:05:23,185 - DEBUG - VN ctest-trans_right_vn-17746183 is not present in Agent 10.0.0.240
2026-03-16 03:05:23,185 - INFO - Validated that VN ctest-trans_right_vn-17746183 is not in any agent
2026-03-16 03:05:23,220 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-17746183 info
2026-03-16 03:05:23,220 - INFO - Deleting VN ctest-trans_left_vn-02263788
2026-03-16 03:05:23,422 - DEBUG - Response for deleting network ()
2026-03-16 03:05:23,422 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bb7f9bde-a0c9-4db7-8e6b-d3ccfcd827bc
2026-03-16 03:05:23,434 - DEBUG - Response Code: 404
2026-03-16 03:05:23,434 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:23,442 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:23,454 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:23,568 - INFO - Validated that VN ctest-trans_left_vn-02263788 is not found in API Server
2026-03-16 03:05:23,578 - DEBUG - VN ctest-trans_left_vn-02263788 is not present in Agent 10.0.0.41
2026-03-16 03:05:23,598 - DEBUG - VN ctest-trans_left_vn-02263788 is not present in Agent 10.0.0.240
2026-03-16 03:05:23,598 - INFO - Validated that VN ctest-trans_left_vn-02263788 is not in any agent
2026-03-16 03:05:23,653 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-02263788 info
2026-03-16 03:05:23,653 - INFO - Deleting VN ctest-right_vn-37188303
2026-03-16 03:05:23,725 - DEBUG - VN 23b34de6-f64a-4598-8578-f0359b8e4512 still in use: Unable to complete operation on network 23b34de6-f64a-4598-8578-f0359b8e4512. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-735505a7-ebb5-4d51-9516-0329b8b73d41']
2026-03-16 03:05:23,725 - WARNING - Deleting VN ctest-right_vn-37188303 failed..Will retry
2026-03-16 03:05:25,914 - DEBUG - Response for deleting network ()
2026-03-16 03:05:25,914 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/bca66bf8-cb13-4991-bbd7-30436f5e52d0
2026-03-16 03:05:25,925 - DEBUG - Response Code: 404
2026-03-16 03:05:25,925 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:25,936 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:25,955 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:26,061 - INFO - Validated that VN ctest-right_vn-37188303 is not found in API Server
2026-03-16 03:05:26,075 - DEBUG - VN ctest-right_vn-37188303 is not present in Agent 10.0.0.41
2026-03-16 03:05:26,086 - DEBUG - VN ctest-right_vn-37188303 is not present in Agent 10.0.0.240
2026-03-16 03:05:26,086 - INFO - Validated that VN ctest-right_vn-37188303 is not in any agent
2026-03-16 03:05:26,122 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-37188303 info
2026-03-16 03:05:26,122 - INFO - Deleting VN ctest-left_vn-55161539
2026-03-16 03:05:26,290 - DEBUG - Response for deleting network ()
2026-03-16 03:05:26,290 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9cb11608-e6ac-4b5c-8732-0963df745b1f
2026-03-16 03:05:26,298 - DEBUG - Response Code: 404
2026-03-16 03:05:26,300 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:26,306 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:26,325 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:26,436 - INFO - Validated that VN ctest-left_vn-55161539 is not found in API Server
2026-03-16 03:05:26,448 - DEBUG - VN ctest-left_vn-55161539 is not present in Agent 10.0.0.41
2026-03-16 03:05:26,460 - DEBUG - VN ctest-left_vn-55161539 is not present in Agent 10.0.0.240
2026-03-16 03:05:26,460 - INFO - Validated that VN ctest-left_vn-55161539 is not in any agent
2026-03-16 03:05:26,500 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-55161539 info
2026-03-16 03:05:26,500 - INFO - Deleting VN ctest-mgmt_vn-58419575
2026-03-16 03:05:26,740 - DEBUG - Response for deleting network ()
2026-03-16 03:05:26,740 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4e99da2d-d23a-4070-a8a5-71df19193c45
2026-03-16 03:05:26,747 - DEBUG - Response Code: 404
2026-03-16 03:05:26,748 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:26,766 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:26,823 - DEBUG - Requesting: http://10.0.0.38:8082/project/3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:26,923 - INFO - Validated that VN ctest-mgmt_vn-58419575 is not found in API Server
2026-03-16 03:05:26,934 - DEBUG - VN ctest-mgmt_vn-58419575 is not present in Agent 10.0.0.41
2026-03-16 03:05:26,943 - DEBUG - VN ctest-mgmt_vn-58419575 is not present in Agent 10.0.0.240
2026-03-16 03:05:26,943 - INFO - Validated that VN ctest-mgmt_vn-58419575 is not in any agent
2026-03-16 03:05:26,976 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-58419575 info
2026-03-16 03:05:28,251 - DEBUG - No XMPP flaps were noticed during the test
2026-03-16 03:05:28,251 - INFO -
2026-03-16 03:05:28,252 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2026-03-16 03:05:28,252 - INFO - --------------------------------------------------------------------------------
2026-03-16 03:05:28,999 - INFO - Deleted project: ctest-TestSvcRegr-98149332, ID : 3287deba-ee9f-4863-8065-1607428e8eb3
2026-03-16 03:05:29,199 - INFO - Domain Default found not creating
2026-03-16 03:05:29,383 - INFO - Project ctest-TestSvcRegr-94855679 not found, creating it
2026-03-16 03:05:29,977 - INFO - Created Project:ctest-TestSvcRegr-94855679, ID : a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:31,574 - INFO - ================================================================================
2026-03-16 03:05:31,574 - INFO - STARTING TEST : test_svc_CEM_22032
2026-03-16 03:05:32,877 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.240': {'10.20.0.193': '0', '10.20.0.129': '0'}} with
2026-03-16 03:05:32,877 - INFO - Initial checks done. Running the testcase now
2026-03-16 03:05:32,877 - INFO -
2026-03-16 03:05:33,673 - DEBUG - Response for create_network : {'network': {'id': 'f55b6498-2f8f-4dc3-82c4-f7b8edf60116', 'name': 'ctest-mgmt_vn-24008654', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'project_id': 'a8653c6c8de0414591ccaa6562594fef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94855679', 'ctest-mgmt_vn-24008654'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:34,045 - DEBUG - Response for create_subnet : {'subnet': {'id': '7b15bddc-a035-4210-b9f3-f156f9d88d26', 'name': '', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'network_id': 'f55b6498-2f8f-4dc3-82c4-f7b8edf60116', 'ip_version': 4, 'cidr': '201.239.189.128/26', 'allocation_pools': [{'start': '201.239.189.130', 'end': '201.239.189.190'}], 'gateway_ip': '201.239.189.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '201.239.189.130', 'tags': [], 'project_id': 'a8653c6c8de0414591ccaa6562594fef'}}
2026-03-16 03:05:34,068 - INFO - Created VN ctest-mgmt_vn-24008654
2026-03-16 03:05:34,085 - DEBUG - VN ctest-mgmt_vn-24008654 UUID is f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,167 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:34,475 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:34,508 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:34,524 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:34,624 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,641 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,651 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/08bf81b5-c734-448f-bfde-57991ffadf0f
2026-03-16 03:05:34,659 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/08bf81b5-c734-448f-bfde-57991ffadf0f
2026-03-16 03:05:34,668 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c5517dd4-daa1-4c52-82dd-d18a24a7a9b7
2026-03-16 03:05:34,677 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:34,677 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,688 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/08bf81b5-c734-448f-bfde-57991ffadf0f
2026-03-16 03:05:34,708 - INFO - Verified VN network id 14 for VN f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,708 - INFO - Verifications in API Server for VN ctest-mgmt_vn-24008654 passed
2026-03-16 03:05:34,708 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,721 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/08bf81b5-c734-448f-bfde-57991ffadf0f
2026-03-16 03:05:34,728 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/08bf81b5-c734-448f-bfde-57991ffadf0f
2026-03-16 03:05:34,737 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c5517dd4-daa1-4c52-82dd-d18a24a7a9b7
2026-03-16 03:05:34,749 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17679835366968741315', 'uuid-lslong': '9422928694004285718'}, 'enable': 'true', 'created': '2026-03-16T03:05:33', 'last-modified': '2026-03-16T03:05:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.771348'}
2026-03-16 03:05:34,755 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:34,762 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17679835366968741315', 'uuid-lslong': '9422928694004285718'}, 'enable': 'true', 'created': '2026-03-16T03:05:33', 'last-modified': '2026-03-16T03:05:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.787021'}
2026-03-16 03:05:34,768 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:34,776 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17679835366968741315', 'uuid-lslong': '9422928694004285718'}, 'enable': 'true', 'created': '2026-03-16T03:05:33', 'last-modified': '2026-03-16T03:05:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.802737'}
2026-03-16 03:05:34,783 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:34,783 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-24008654 passed
2026-03-16 03:05:34,783 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-24008654 in API_Server ======
2026-03-16 03:05:34,783 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:34,789 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:34,802 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:34,897 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f55b6498-2f8f-4dc3-82c4-f7b8edf60116
2026-03-16 03:05:34,911 - DEBUG - =>VN ctest-mgmt_vn-24008654 has no policy to be verified
2026-03-16 03:05:34,911 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:34,911 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:34,911 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:35,210 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:35,282 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654?flat'}
2026-03-16 03:05:35,283 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:35,283 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:35,575 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:35,644 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654?flat'}
2026-03-16 03:05:35,644 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:35,644 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:35,937 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:36,004 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654?flat'}
2026-03-16 03:05:36,004 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-24008654 is found in opserver
2026-03-16 03:05:36,024 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:36,033 - DEBUG - VRF ids for VN ctest-mgmt_vn-24008654: {}
2026-03-16 03:05:36,320 - DEBUG - Response for create_network : {'network': {'id': '7ecd63c0-5660-4d25-93b0-ca72780449cf', 'name': 'service-left-vn-74034428', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'project_id': 'a8653c6c8de0414591ccaa6562594fef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94855679', 'service-left-vn-74034428'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:36,551 - DEBUG - Response for create_subnet : {'subnet': {'id': '1b3907c8-9a54-4f18-b9ce-1c485aae6acd', 'name': '', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'network_id': '7ecd63c0-5660-4d25-93b0-ca72780449cf', 'ip_version': 4, 'cidr': '162.177.131.0/26', 'allocation_pools': [{'start': '162.177.131.2', 'end': '162.177.131.62'}], 'gateway_ip': '162.177.131.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.177.131.2', 'tags': [], 'project_id': 'a8653c6c8de0414591ccaa6562594fef'}}
2026-03-16 03:05:36,582 - INFO - Created VN service-left-vn-74034428
2026-03-16 03:05:36,595 - DEBUG - VN service-left-vn-74034428 UUID is 7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:36,671 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:36,678 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:36,694 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:36,793 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:36,805 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:36,814 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2bc9f807-4300-4171-98d5-a522e7f62390
2026-03-16 03:05:36,823 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2bc9f807-4300-4171-98d5-a522e7f62390
2026-03-16 03:05:36,831 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c1791542-3da4-40f5-a548-63dad5fea94e
2026-03-16 03:05:36,838 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:36,838 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:36,847 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2bc9f807-4300-4171-98d5-a522e7f62390
2026-03-16 03:05:36,863 - INFO - Verified VN network id 15 for VN 7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:36,863 - INFO - Verifications in API Server for VN service-left-vn-74034428 passed
2026-03-16 03:05:36,863 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:36,872 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2bc9f807-4300-4171-98d5-a522e7f62390
2026-03-16 03:05:36,879 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2bc9f807-4300-4171-98d5-a522e7f62390
2026-03-16 03:05:36,886 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/c1791542-3da4-40f5-a548-63dad5fea94e
2026-03-16 03:05:36,898 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9137068896738626853', 'uuid-lslong': '10642228512465111503'}, 'enable': 'true', 'created': '2026-03-16T03:05:36', 'last-modified': '2026-03-16T03:05:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408879'}
2026-03-16 03:05:36,905 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:36,910 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9137068896738626853', 'uuid-lslong': '10642228512465111503'}, 'enable': 'true', 'created': '2026-03-16T03:05:36', 'last-modified': '2026-03-16T03:05:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419904'}
2026-03-16 03:05:36,916 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:36,922 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9137068896738626853', 'uuid-lslong': '10642228512465111503'}, 'enable': 'true', 'created': '2026-03-16T03:05:36', 'last-modified': '2026-03-16T03:05:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433135'}
2026-03-16 03:05:36,928 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:36,928 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-74034428 passed
2026-03-16 03:05:36,929 - DEBUG - ====Verifying policy data for service-left-vn-74034428 in API_Server ======
2026-03-16 03:05:36,929 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:36,936 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:36,947 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:37,041 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7ecd63c0-5660-4d25-93b0-ca72780449cf
2026-03-16 03:05:37,050 - DEBUG - =>VN service-left-vn-74034428 has no policy to be verified
2026-03-16 03:05:37,050 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:37,050 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:37,050 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:37,112 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428?flat'}
2026-03-16 03:05:37,112 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:37,112 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:37,171 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428?flat'}
2026-03-16 03:05:37,171 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:37,171 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:37,240 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428?flat'}
2026-03-16 03:05:37,240 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94855679:service-left-vn-74034428 is found in opserver
2026-03-16 03:05:37,262 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:37,276 - DEBUG - VRF ids for VN service-left-vn-74034428: {}
2026-03-16 03:05:37,571 - DEBUG - Response for create_network : {'network': {'id': '1d7612bc-53bb-488a-aa68-443b6988f225', 'name': 'service-right-vn-61061075', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'project_id': 'a8653c6c8de0414591ccaa6562594fef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94855679', 'service-right-vn-61061075'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:37,856 - DEBUG - Response for create_subnet : {'subnet': {'id': '76687ab8-e9d1-409f-a944-ad62c4afea05', 'name': '', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'network_id': '1d7612bc-53bb-488a-aa68-443b6988f225', 'ip_version': 4, 'cidr': '112.91.81.64/26', 'allocation_pools': [{'start': '112.91.81.66', 'end': '112.91.81.126'}], 'gateway_ip': '112.91.81.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.91.81.66', 'tags': [], 'project_id': 'a8653c6c8de0414591ccaa6562594fef'}}
2026-03-16 03:05:37,881 - INFO - Created VN service-right-vn-61061075
2026-03-16 03:05:37,897 - DEBUG - VN service-right-vn-61061075 UUID is 1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:37,977 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:37,984 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:37,996 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:38,099 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:38,113 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:38,123 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2c749f73-3505-4197-aaaf-90b36913c62b
2026-03-16 03:05:38,131 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2c749f73-3505-4197-aaaf-90b36913c62b
2026-03-16 03:05:38,138 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/398652e6-a3db-4463-b202-eb4601382186
2026-03-16 03:05:38,145 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:38,145 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:38,156 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2c749f73-3505-4197-aaaf-90b36913c62b
2026-03-16 03:05:38,179 - INFO - Verified VN network id 16 for VN 1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:38,179 - INFO - Verifications in API Server for VN service-right-vn-61061075 passed
2026-03-16 03:05:38,179 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:38,191 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2c749f73-3505-4197-aaaf-90b36913c62b
2026-03-16 03:05:38,201 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2c749f73-3505-4197-aaaf-90b36913c62b
2026-03-16 03:05:38,217 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/398652e6-a3db-4463-b202-eb4601382186
2026-03-16 03:05:38,233 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2122904874419701898', 'uuid-lslong': '12279139405989999141'}, 'enable': 'true', 'created': '2026-03-16T03:05:37', 'last-modified': '2026-03-16T03:05:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428961'}
2026-03-16 03:05:38,241 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:38,247 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2122904874419701898', 'uuid-lslong': '12279139405989999141'}, 'enable': 'true', 'created': '2026-03-16T03:05:37', 'last-modified': '2026-03-16T03:05:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446671'}
2026-03-16 03:05:38,254 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:38,261 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2122904874419701898', 'uuid-lslong': '12279139405989999141'}, 'enable': 'true', 'created': '2026-03-16T03:05:37', 'last-modified': '2026-03-16T03:05:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.460771'}
2026-03-16 03:05:38,268 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:38,268 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-61061075 passed
2026-03-16 03:05:38,268 - DEBUG - ====Verifying policy data for service-right-vn-61061075 in API_Server ======
2026-03-16 03:05:38,269 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:38,275 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:38,288 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:38,387 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1d7612bc-53bb-488a-aa68-443b6988f225
2026-03-16 03:05:38,398 - DEBUG - =>VN service-right-vn-61061075 has no policy to be verified
2026-03-16 03:05:38,398 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:38,398 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:38,398 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:38,467 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075?flat'}
2026-03-16 03:05:38,467 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:38,467 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:38,542 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075?flat'}
2026-03-16 03:05:38,542 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:38,542 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:38,607 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075?flat'}
2026-03-16 03:05:38,607 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94855679:service-right-vn-61061075 is found in opserver
2026-03-16 03:05:38,632 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:38,644 - DEBUG - VRF ids for VN service-right-vn-61061075: {}
2026-03-16 03:05:38,722 - DEBUG - Services list from nova: [, , , ]
2026-03-16 03:05:39,866 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-1)
2026-03-16 03:05:41,215 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-2)
2026-03-16 03:05:41,216 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-54740554']
2026-03-16 03:05:41,528 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-54740554'] created succesfully
2026-03-16 03:05:42,039 - DEBUG - Image by name tiny_nat_fw not found
2026-03-16 03:05:42,039 - DEBUG - Installing image tiny_nat_fw
2026-03-16 03:05:42,039 - 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'"}
2026-03-16 03:05:42,039 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:42,039 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:42,039 - DEBUG - Unzip image
2026-03-16 03:05:42,039 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:42,064 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-03-16 03:05:42,065 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-54740554']
2026-03-16 03:05:42,197 - INFO - Service template: ['default-domain', 'ctest-service_template_1-54740554'] deleted successfully.
2026-03-16 03:05:42,198 - INFO - Skip interface_detach for VM ctest-right_vm-81675676 in state BUILD
2026-03-16 03:05:42,198 - INFO - Deleting VM ctest-right_vm-81675676
2026-03-16 03:05:42,310 - ERROR - VM ctest-right_vm-81675676 has failed to come up
2026-03-16 03:05:42,310 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:42,310 - ERROR - VM ctest-right_vm-81675676 has failed to come up
2026-03-16 03:05:42,310 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:42,432 - INFO - Skip interface_detach for VM ctest-left_vm-33029993 in state BUILD
2026-03-16 03:05:42,433 - INFO - Deleting VM ctest-left_vm-33029993
2026-03-16 03:05:42,433 - ERROR - VM ctest-left_vm-33029993 has failed to come up
2026-03-16 03:05:42,433 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:42,433 - ERROR - VM ctest-left_vm-33029993 has failed to come up
2026-03-16 03:05:42,433 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:42,552 - INFO - Deleting VN service-right-vn-61061075
2026-03-16 03:05:42,598 - DEBUG - VN 1d7612bc-53bb-488a-aa68-443b6988f225 still in use: Unable to complete operation on network 1d7612bc-53bb-488a-aa68-443b6988f225. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-68ee2516-dba0-4e0c-831f-affadbec947e']
2026-03-16 03:05:42,598 - WARNING - Deleting VN service-right-vn-61061075 failed..Will retry
2026-03-16 03:05:44,854 - DEBUG - Response for deleting network ()
2026-03-16 03:05:44,854 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2c749f73-3505-4197-aaaf-90b36913c62b
2026-03-16 03:05:44,861 - DEBUG - Response Code: 404
2026-03-16 03:05:44,862 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:44,891 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:44,918 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:45,060 - INFO - Validated that VN service-right-vn-61061075 is not found in API Server
2026-03-16 03:05:45,072 - DEBUG - VN service-right-vn-61061075 is not present in Agent 10.0.0.41
2026-03-16 03:05:45,084 - DEBUG - VN service-right-vn-61061075 is not present in Agent 10.0.0.240
2026-03-16 03:05:45,085 - INFO - Validated that VN service-right-vn-61061075 is not in any agent
2026-03-16 03:05:45,131 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-61061075 info
2026-03-16 03:05:45,131 - INFO - Deleting VN service-left-vn-74034428
2026-03-16 03:05:45,362 - DEBUG - Response for deleting network ()
2026-03-16 03:05:45,362 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2bc9f807-4300-4171-98d5-a522e7f62390
2026-03-16 03:05:45,371 - DEBUG - Response Code: 404
2026-03-16 03:05:45,371 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:45,381 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:45,438 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:45,577 - INFO - Validated that VN service-left-vn-74034428 is not found in API Server
2026-03-16 03:05:45,589 - DEBUG - VN service-left-vn-74034428 is not present in Agent 10.0.0.41
2026-03-16 03:05:45,599 - DEBUG - VN service-left-vn-74034428 is not present in Agent 10.0.0.240
2026-03-16 03:05:45,600 - INFO - Validated that VN service-left-vn-74034428 is not in any agent
2026-03-16 03:05:45,651 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-74034428 info
2026-03-16 03:05:45,651 - INFO - Deleting VN ctest-mgmt_vn-24008654
2026-03-16 03:05:45,892 - DEBUG - Response for deleting network ()
2026-03-16 03:05:45,892 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/08bf81b5-c734-448f-bfde-57991ffadf0f
2026-03-16 03:05:45,899 - DEBUG - Response Code: 404
2026-03-16 03:05:45,899 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:45,907 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:45,943 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:46,012 - INFO - Validated that VN ctest-mgmt_vn-24008654 is not found in API Server
2026-03-16 03:05:46,025 - DEBUG - VN ctest-mgmt_vn-24008654 is not present in Agent 10.0.0.41
2026-03-16 03:05:46,040 - DEBUG - VN ctest-mgmt_vn-24008654 is not present in Agent 10.0.0.240
2026-03-16 03:05:46,040 - INFO - Validated that VN ctest-mgmt_vn-24008654 is not in any agent
2026-03-16 03:05:46,094 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-24008654 info
2026-03-16 03:05:47,391 - DEBUG - No XMPP flaps were noticed during the test
2026-03-16 03:05:47,391 - INFO -
2026-03-16 03:05:47,391 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2026-03-16 03:05:47,391 - INFO - --------------------------------------------------------------------------------
2026-03-16 03:05:47,394 - INFO - ================================================================================
2026-03-16 03:05:47,394 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-03-16 03:05:48,683 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.240': {'10.20.0.193': '0', '10.20.0.129': '0'}} with
2026-03-16 03:05:48,683 - INFO - Initial checks done. Running the testcase now
2026-03-16 03:05:48,683 - INFO -
2026-03-16 03:05:48,965 - DEBUG - Response for create_network : {'network': {'id': '6e4a1f44-40e8-4aa4-84f6-3708bf132879', 'name': 'ctest-mgmt_vn-61924404', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'project_id': 'a8653c6c8de0414591ccaa6562594fef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94855679', 'ctest-mgmt_vn-61924404'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:49,252 - DEBUG - Response for create_subnet : {'subnet': {'id': '0665d82a-5428-4d3a-9218-fdc3de9a9c49', 'name': '', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'network_id': '6e4a1f44-40e8-4aa4-84f6-3708bf132879', 'ip_version': 4, 'cidr': '70.115.122.192/26', 'allocation_pools': [{'start': '70.115.122.194', 'end': '70.115.122.254'}], 'gateway_ip': '70.115.122.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.115.122.194', 'tags': [], 'project_id': 'a8653c6c8de0414591ccaa6562594fef'}}
2026-03-16 03:05:49,296 - INFO - Created VN ctest-mgmt_vn-61924404
2026-03-16 03:05:49,314 - DEBUG - VN ctest-mgmt_vn-61924404 UUID is 6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,421 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:49,428 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:49,450 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:49,585 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,605 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,615 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3011e184-15e3-4c85-9cfb-d07b9fa91f87
2026-03-16 03:05:49,624 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3011e184-15e3-4c85-9cfb-d07b9fa91f87
2026-03-16 03:05:49,632 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5d368cba-294c-4541-b480-142e7046d067
2026-03-16 03:05:49,639 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:49,639 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,651 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3011e184-15e3-4c85-9cfb-d07b9fa91f87
2026-03-16 03:05:49,671 - INFO - Verified VN network id 14 for VN 6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,671 - INFO - Verifications in API Server for VN ctest-mgmt_vn-61924404 passed
2026-03-16 03:05:49,671 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,684 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3011e184-15e3-4c85-9cfb-d07b9fa91f87
2026-03-16 03:05:49,693 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3011e184-15e3-4c85-9cfb-d07b9fa91f87
2026-03-16 03:05:49,706 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5d368cba-294c-4541-b480-142e7046d067
2026-03-16 03:05:49,722 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7947198870455863972', 'uuid-lslong': '9580905767982278777'}, 'enable': 'true', 'created': '2026-03-16T03:05:48', 'last-modified': '2026-03-16T03:05:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.527492'}
2026-03-16 03:05:49,731 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:49,738 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7947198870455863972', 'uuid-lslong': '9580905767982278777'}, 'enable': 'true', 'created': '2026-03-16T03:05:48', 'last-modified': '2026-03-16T03:05:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.547451'}
2026-03-16 03:05:49,745 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:49,751 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7947198870455863972', 'uuid-lslong': '9580905767982278777'}, 'enable': 'true', 'created': '2026-03-16T03:05:48', 'last-modified': '2026-03-16T03:05:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.558952'}
2026-03-16 03:05:49,759 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-16 03:05:49,759 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-61924404 passed
2026-03-16 03:05:49,759 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-61924404 in API_Server ======
2026-03-16 03:05:49,759 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:49,766 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:49,783 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:49,897 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/6e4a1f44-40e8-4aa4-84f6-3708bf132879
2026-03-16 03:05:49,908 - DEBUG - =>VN ctest-mgmt_vn-61924404 has no policy to be verified
2026-03-16 03:05:49,908 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:49,908 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:49,908 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:49,923 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404?flat'}
2026-03-16 03:05:49,923 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:49,923 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:49,939 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404?flat'}
2026-03-16 03:05:49,939 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:49,939 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:49,958 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404?flat'}
2026-03-16 03:05:49,958 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94855679:ctest-mgmt_vn-61924404 is found in opserver
2026-03-16 03:05:49,991 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:50,005 - DEBUG - VRF ids for VN ctest-mgmt_vn-61924404: {}
2026-03-16 03:05:50,359 - DEBUG - Response for create_network : {'network': {'id': '1f70ba43-1188-462f-b3a4-e63dd5499e7d', 'name': 'ctest-left_vn-60725566', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'project_id': 'a8653c6c8de0414591ccaa6562594fef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94855679', 'ctest-left_vn-60725566'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:50,623 - DEBUG - Response for create_subnet : {'subnet': {'id': '2af78c57-b5d2-4631-b638-6357f002fd06', 'name': '', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'network_id': '1f70ba43-1188-462f-b3a4-e63dd5499e7d', 'ip_version': 4, 'cidr': '209.60.89.192/26', 'allocation_pools': [{'start': '209.60.89.194', 'end': '209.60.89.254'}], 'gateway_ip': '209.60.89.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '209.60.89.194', 'tags': [], 'project_id': 'a8653c6c8de0414591ccaa6562594fef'}}
2026-03-16 03:05:50,648 - INFO - Created VN ctest-left_vn-60725566
2026-03-16 03:05:50,668 - DEBUG - VN ctest-left_vn-60725566 UUID is 1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:50,744 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:50,752 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:50,764 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:50,866 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:50,882 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:50,896 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/876af96d-19c6-4914-b5ce-c5b1f8458c2d
2026-03-16 03:05:50,905 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/876af96d-19c6-4914-b5ce-c5b1f8458c2d
2026-03-16 03:05:50,914 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/74e36b95-c9a9-4b59-9a6b-23efd834f9c2
2026-03-16 03:05:50,922 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:50,923 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:50,934 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/876af96d-19c6-4914-b5ce-c5b1f8458c2d
2026-03-16 03:05:50,954 - INFO - Verified VN network id 15 for VN 1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:50,954 - INFO - Verifications in API Server for VN ctest-left_vn-60725566 passed
2026-03-16 03:05:50,954 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:50,966 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/876af96d-19c6-4914-b5ce-c5b1f8458c2d
2026-03-16 03:05:50,974 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/876af96d-19c6-4914-b5ce-c5b1f8458c2d
2026-03-16 03:05:50,982 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/74e36b95-c9a9-4b59-9a6b-23efd834f9c2
2026-03-16 03:05:50,998 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2265515409787078191', 'uuid-lslong': '12944724382215413373'}, 'enable': 'true', 'created': '2026-03-16T03:05:50', 'last-modified': '2026-03-16T03:05:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462225'}
2026-03-16 03:05:51,004 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:51,010 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2265515409787078191', 'uuid-lslong': '12944724382215413373'}, 'enable': 'true', 'created': '2026-03-16T03:05:50', 'last-modified': '2026-03-16T03:05:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.475842'}
2026-03-16 03:05:51,017 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:51,023 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2265515409787078191', 'uuid-lslong': '12944724382215413373'}, 'enable': 'true', 'created': '2026-03-16T03:05:50', 'last-modified': '2026-03-16T03:05:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.487893'}
2026-03-16 03:05:51,030 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-16 03:05:51,030 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-60725566 passed
2026-03-16 03:05:51,030 - DEBUG - ====Verifying policy data for ctest-left_vn-60725566 in API_Server ======
2026-03-16 03:05:51,030 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:51,037 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:51,048 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:51,163 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/1f70ba43-1188-462f-b3a4-e63dd5499e7d
2026-03-16 03:05:51,175 - DEBUG - =>VN ctest-left_vn-60725566 has no policy to be verified
2026-03-16 03:05:51,175 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:51,175 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:51,175 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:51,193 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566?flat'}
2026-03-16 03:05:51,193 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:51,193 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:51,209 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566?flat'}
2026-03-16 03:05:51,209 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:51,209 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:51,224 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566?flat'}
2026-03-16 03:05:51,224 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94855679:ctest-left_vn-60725566 is found in opserver
2026-03-16 03:05:51,249 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:51,262 - DEBUG - VRF ids for VN ctest-left_vn-60725566: {}
2026-03-16 03:05:51,584 - DEBUG - Response for create_network : {'network': {'id': '3292128a-1df9-4d1b-8e29-1dc667775898', 'name': 'ctest-right_vn-74892115', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'project_id': 'a8653c6c8de0414591ccaa6562594fef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94855679', 'ctest-right_vn-74892115'], 'port_security_enabled': True, 'description': ''}}
2026-03-16 03:05:51,824 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ffeec6ce-aa52-4eb6-9d42-baf590c99e64', 'name': '', 'tenant_id': 'a8653c6c8de0414591ccaa6562594fef', 'network_id': '3292128a-1df9-4d1b-8e29-1dc667775898', 'ip_version': 4, 'cidr': '92.126.76.0/26', 'allocation_pools': [{'start': '92.126.76.2', 'end': '92.126.76.62'}], 'gateway_ip': '92.126.76.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '92.126.76.2', 'tags': [], 'project_id': 'a8653c6c8de0414591ccaa6562594fef'}}
2026-03-16 03:05:51,850 - INFO - Created VN ctest-right_vn-74892115
2026-03-16 03:05:51,863 - DEBUG - VN ctest-right_vn-74892115 UUID is 3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:51,947 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:51,955 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:51,968 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:52,062 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:52,078 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:52,088 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6481d969-d7b2-4832-ad07-eea4f476e49f
2026-03-16 03:05:52,099 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6481d969-d7b2-4832-ad07-eea4f476e49f
2026-03-16 03:05:52,107 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/a02bffb8-629e-45af-ac12-5a8d24f9b057
2026-03-16 03:05:52,115 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:52,115 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:52,125 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6481d969-d7b2-4832-ad07-eea4f476e49f
2026-03-16 03:05:52,143 - INFO - Verified VN network id 16 for VN 3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:52,144 - INFO - Verifications in API Server for VN ctest-right_vn-74892115 passed
2026-03-16 03:05:52,144 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:52,155 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6481d969-d7b2-4832-ad07-eea4f476e49f
2026-03-16 03:05:52,165 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6481d969-d7b2-4832-ad07-eea4f476e49f
2026-03-16 03:05:52,174 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/a02bffb8-629e-45af-ac12-5a8d24f9b057
2026-03-16 03:05:52,186 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3643995432913816859', 'uuid-lslong': '10243751565407508632'}, 'enable': 'true', 'created': '2026-03-16T03:05:51', 'last-modified': '2026-03-16T03:05:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419096'}
2026-03-16 03:05:52,192 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:52,198 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3643995432913816859', 'uuid-lslong': '10243751565407508632'}, 'enable': 'true', 'created': '2026-03-16T03:05:51', 'last-modified': '2026-03-16T03:05:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424276'}
2026-03-16 03:05:52,205 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:52,211 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94855679', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3643995432913816859', 'uuid-lslong': '10243751565407508632'}, 'enable': 'true', 'created': '2026-03-16T03:05:51', 'last-modified': '2026-03-16T03:05:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437147'}
2026-03-16 03:05:52,218 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-16 03:05:52,218 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-74892115 passed
2026-03-16 03:05:52,218 - DEBUG - ====Verifying policy data for ctest-right_vn-74892115 in API_Server ======
2026-03-16 03:05:52,219 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:52,226 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:52,238 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:52,332 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3292128a-1df9-4d1b-8e29-1dc667775898
2026-03-16 03:05:52,344 - DEBUG - =>VN ctest-right_vn-74892115 has no policy to be verified
2026-03-16 03:05:52,344 - DEBUG - Verifying the vn in opserver
2026-03-16 03:05:52,344 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115 virtual network link through opserver 10.0.0.38
2026-03-16 03:05:52,344 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2026-03-16 03:05:52,359 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115?flat'}
2026-03-16 03:05:52,359 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115 virtual network link through opserver 10.0.0.22
2026-03-16 03:05:52,359 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-03-16 03:05:52,372 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115?flat'}
2026-03-16 03:05:52,372 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115 virtual network link through opserver 10.0.0.23
2026-03-16 03:05:52,372 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-16 03:05:52,388 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115?flat'}
2026-03-16 03:05:52,388 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94855679:ctest-right_vn-74892115 is found in opserver
2026-03-16 03:05:52,410 - DEBUG - Do not have enough data to verify VN in agent
2026-03-16 03:05:52,420 - DEBUG - VRF ids for VN ctest-right_vn-74892115: {}
2026-03-16 03:05:53,556 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-2)
2026-03-16 03:05:54,915 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5469-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5469-1)
2026-03-16 03:05:54,915 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-69095979']
2026-03-16 03:05:55,229 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-69095979'] created succesfully
2026-03-16 03:05:55,568 - DEBUG - Image by name tiny_nat_fw not found
2026-03-16 03:05:55,568 - DEBUG - Installing image tiny_nat_fw
2026-03-16 03:05:55,568 - 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'"}
2026-03-16 03:05:55,568 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:55,568 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:55,569 - DEBUG - Unzip image
2026-03-16 03:05:55,569 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-03-16 03:05:55,594 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-03-16 03:05:55,595 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-69095979']
2026-03-16 03:05:55,709 - INFO - Service template: ['default-domain', 'ctest-service_template_1-69095979'] deleted successfully.
2026-03-16 03:05:55,709 - INFO - Skip interface_detach for VM ctest-right_vm-63661991 in state BUILD
2026-03-16 03:05:55,709 - INFO - Deleting VM ctest-right_vm-63661991
2026-03-16 03:05:55,709 - ERROR - VM ctest-right_vm-63661991 has failed to come up
2026-03-16 03:05:55,709 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:55,709 - ERROR - VM ctest-right_vm-63661991 has failed to come up
2026-03-16 03:05:55,709 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:55,799 - INFO - Skip interface_detach for VM ctest-left_vm-98050589 in state BUILD
2026-03-16 03:05:55,799 - INFO - Deleting VM ctest-left_vm-98050589
2026-03-16 03:05:55,799 - ERROR - VM ctest-left_vm-98050589 has failed to come up
2026-03-16 03:05:55,799 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:55,799 - ERROR - VM ctest-left_vm-98050589 has failed to come up
2026-03-16 03:05:55,799 - ERROR - Nova failed to get host of the VM
2026-03-16 03:05:55,886 - INFO - Deleting VN ctest-right_vn-74892115
2026-03-16 03:05:55,947 - DEBUG - VN 3292128a-1df9-4d1b-8e29-1dc667775898 still in use: Unable to complete operation on network 3292128a-1df9-4d1b-8e29-1dc667775898. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-99c60c51-6b3e-40ce-8ee3-7449e4596b9b']
2026-03-16 03:05:55,948 - WARNING - Deleting VN ctest-right_vn-74892115 failed..Will retry
2026-03-16 03:05:58,139 - DEBUG - Response for deleting network ()
2026-03-16 03:05:58,139 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6481d969-d7b2-4832-ad07-eea4f476e49f
2026-03-16 03:05:58,147 - DEBUG - Response Code: 404
2026-03-16 03:05:58,147 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:58,172 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:58,193 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:58,298 - INFO - Validated that VN ctest-right_vn-74892115 is not found in API Server
2026-03-16 03:05:58,315 - DEBUG - VN ctest-right_vn-74892115 is not present in Agent 10.0.0.41
2026-03-16 03:05:58,326 - DEBUG - VN ctest-right_vn-74892115 is not present in Agent 10.0.0.240
2026-03-16 03:05:58,326 - INFO - Validated that VN ctest-right_vn-74892115 is not in any agent
2026-03-16 03:05:58,363 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-74892115 info
2026-03-16 03:05:58,363 - INFO - Deleting VN ctest-left_vn-60725566
2026-03-16 03:05:58,567 - DEBUG - Response for deleting network ()
2026-03-16 03:05:58,567 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/876af96d-19c6-4914-b5ce-c5b1f8458c2d
2026-03-16 03:05:58,575 - DEBUG - Response Code: 404
2026-03-16 03:05:58,575 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:58,586 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:58,598 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:58,683 - INFO - Validated that VN ctest-left_vn-60725566 is not found in API Server
2026-03-16 03:05:58,695 - DEBUG - VN ctest-left_vn-60725566 is not present in Agent 10.0.0.41
2026-03-16 03:05:58,706 - DEBUG - VN ctest-left_vn-60725566 is not present in Agent 10.0.0.240
2026-03-16 03:05:58,706 - INFO - Validated that VN ctest-left_vn-60725566 is not in any agent
2026-03-16 03:05:58,742 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-60725566 info
2026-03-16 03:05:58,742 - INFO - Deleting VN ctest-mgmt_vn-61924404
2026-03-16 03:05:58,887 - DEBUG - Response for deleting network ()
2026-03-16 03:05:58,887 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3011e184-15e3-4c85-9cfb-d07b9fa91f87
2026-03-16 03:05:58,899 - DEBUG - Response Code: 404
2026-03-16 03:05:58,899 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2026-03-16 03:05:58,914 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d1af4696-a589-4518-a8ed-3c39c5e9266b
2026-03-16 03:05:58,931 - DEBUG - Requesting: http://10.0.0.38:8082/project/a8653c6c-8de0-4145-91cc-aa6562594fef
2026-03-16 03:05:59,031 - INFO - Validated that VN ctest-mgmt_vn-61924404 is not found in API Server
2026-03-16 03:05:59,051 - DEBUG - VN ctest-mgmt_vn-61924404 is not present in Agent 10.0.0.41
2026-03-16 03:05:59,064 - DEBUG - VN ctest-mgmt_vn-61924404 is not present in Agent 10.0.0.240
2026-03-16 03:05:59,064 - INFO - Validated that VN ctest-mgmt_vn-61924404 is not in any agent
2026-03-16 03:05:59,099 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-61924404 info
2026-03-16 03:06:00,415 - DEBUG - No XMPP flaps were noticed during the test
2026-03-16 03:06:00,415 - INFO -
2026-03-16 03:06:00,415 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2026-03-16 03:06:00,416 - INFO - --------------------------------------------------------------------------------
2026-03-16 03:06:01,227 - INFO - Deleted project: ctest-TestSvcRegr-94855679, ID : a8653c6c-8de0-4145-91cc-aa6562594fef