]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-2)
2026-05-20 15:47:24,005 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-99060691']
2026-05-20 15:47:24,347 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-99060691'] created succesfully
2026-05-20 15:47:24,956 - DEBUG - Image by name tiny_nat_fw not found
2026-05-20 15:47:24,956 - DEBUG - Installing image tiny_nat_fw
2026-05-20 15:47:24,957 - 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-05-20 15:47:24,957 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-05-20 15:47:24,957 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-05-20 15:47:24,984 - 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-05-20 15:47:25,040 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:47:25,040 - DEBUG - Unzip image
2026-05-20 15:47:25,040 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:47:25,069 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-05-20 15:47:25,069 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-99060691']
2026-05-20 15:47:25,186 - INFO - Service template: ['default-domain', 'ctest-service_template_1-99060691'] deleted successfully.
2026-05-20 15:47:25,186 - INFO - Skip interface_detach for VM ctest-right_vm-91693135 in state BUILD
2026-05-20 15:47:25,186 - INFO - Deleting VM ctest-right_vm-91693135
2026-05-20 15:47:25,257 - ERROR - VM ctest-right_vm-91693135 has failed to come up
2026-05-20 15:47:25,257 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:25,257 - ERROR - VM ctest-right_vm-91693135 has failed to come up
2026-05-20 15:47:25,257 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:25,363 - INFO - Skip interface_detach for VM ctest-left_vm-89773846 in state BUILD
2026-05-20 15:47:25,363 - INFO - Deleting VM ctest-left_vm-89773846
2026-05-20 15:47:25,363 - ERROR - VM ctest-left_vm-89773846 has failed to come up
2026-05-20 15:47:25,363 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:25,363 - ERROR - VM ctest-left_vm-89773846 has failed to come up
2026-05-20 15:47:25,363 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:25,457 - INFO - Deleting VN ctest-right_vn-37040715
2026-05-20 15:47:25,520 - DEBUG - VN 7cfde247-67d0-4e09-8ff0-e9bc3180dd97 still in use: Unable to complete operation on network 7cfde247-67d0-4e09-8ff0-e9bc3180dd97. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-85bf00c0-c323-4027-a13d-a3f1d3029d1e']
2026-05-20 15:47:25,521 - WARNING - Deleting VN ctest-right_vn-37040715 failed..Will retry
2026-05-20 15:47:27,730 - DEBUG - Response for deleting network ()
2026-05-20 15:47:27,730 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8901abd2-b5dc-408a-b2d2-a7b6adcee557
2026-05-20 15:47:27,738 - DEBUG - Response Code: 404
2026-05-20 15:47:27,739 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:27,750 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:27,795 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:27,921 - INFO - Validated that VN ctest-right_vn-37040715 is not found in API Server
2026-05-20 15:47:27,935 - DEBUG - VN ctest-right_vn-37040715 is not present in Agent 10.0.0.242
2026-05-20 15:47:27,953 - DEBUG - VN ctest-right_vn-37040715 is not present in Agent 10.0.0.249
2026-05-20 15:47:27,954 - INFO - Validated that VN ctest-right_vn-37040715 is not in any agent
2026-05-20 15:47:27,994 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-37040715 info
2026-05-20 15:47:27,994 - INFO - Deleting VN ctest-left_vn-71325487
2026-05-20 15:47:28,231 - DEBUG - Response for deleting network ()
2026-05-20 15:47:28,231 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8eeca0fe-8373-40d0-8987-c3377382e63c
2026-05-20 15:47:28,239 - DEBUG - Response Code: 404
2026-05-20 15:47:28,239 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:28,261 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:28,289 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:28,412 - INFO - Validated that VN ctest-left_vn-71325487 is not found in API Server
2026-05-20 15:47:28,426 - DEBUG - VN ctest-left_vn-71325487 is not present in Agent 10.0.0.242
2026-05-20 15:47:28,440 - DEBUG - VN ctest-left_vn-71325487 is not present in Agent 10.0.0.249
2026-05-20 15:47:28,440 - INFO - Validated that VN ctest-left_vn-71325487 is not in any agent
2026-05-20 15:47:28,481 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-71325487 info
2026-05-20 15:47:28,481 - INFO - Deleting VN ctest-mgmt_vn-83970109
2026-05-20 15:47:28,692 - DEBUG - Response for deleting network ()
2026-05-20 15:47:28,692 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/e5434a12-c048-433e-89a1-8465decd915e
2026-05-20 15:47:28,700 - DEBUG - Response Code: 404
2026-05-20 15:47:28,700 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:28,712 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:28,743 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:28,868 - INFO - Validated that VN ctest-mgmt_vn-83970109 is not found in API Server
2026-05-20 15:47:28,885 - DEBUG - VN ctest-mgmt_vn-83970109 is not present in Agent 10.0.0.242
2026-05-20 15:47:28,902 - DEBUG - VN ctest-mgmt_vn-83970109 is not present in Agent 10.0.0.249
2026-05-20 15:47:28,902 - INFO - Validated that VN ctest-mgmt_vn-83970109 is not in any agent
2026-05-20 15:47:28,947 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-83970109 info
2026-05-20 15:47:28,948 - INFO - Deleting VM ctest-vm-in-third-vn-48493903
2026-05-20 15:47:29,052 - INFO - Deleting VN ctest-third-vn-74511755
2026-05-20 15:47:29,125 - DEBUG - VN 832fb171-1eb0-40d6-83f5-da9480038e87 still in use: Unable to complete operation on network 832fb171-1eb0-40d6-83f5-da9480038e87. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f5dc260c-0df6-4cf4-9f9e-dc9130ce2f53']
2026-05-20 15:47:29,125 - WARNING - Deleting VN ctest-third-vn-74511755 failed..Will retry
2026-05-20 15:47:31,316 - DEBUG - Response for deleting network ()
2026-05-20 15:47:32,675 - DEBUG - No XMPP flaps were noticed during the test
2026-05-20 15:47:32,675 - INFO -
2026-05-20 15:47:32,675 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:29]
2026-05-20 15:47:32,675 - INFO - --------------------------------------------------------------------------------
2026-05-20 15:47:32,682 - INFO - ================================================================================
2026-05-20 15:47:32,682 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-05-20 15:47:34,010 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.249': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2026-05-20 15:47:34,010 - INFO - Initial checks done. Running the testcase now
2026-05-20 15:47:34,010 - INFO -
2026-05-20 15:47:34,342 - DEBUG - Response for create_network : {'network': {'id': '2097f78b-bc99-4fae-aff8-e76bde0a5ff6', 'name': 'ctest-mgmt_vn-04445087', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'project_id': '54a2dca2af9d44d6add4643c928fa214', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55473575', 'ctest-mgmt_vn-04445087'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:47:34,592 - DEBUG - Response for create_subnet : {'subnet': {'id': '49c9d04b-8f73-426f-994b-0c9933f0c67e', 'name': '', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'network_id': '2097f78b-bc99-4fae-aff8-e76bde0a5ff6', 'ip_version': 4, 'cidr': '211.34.18.128/26', 'allocation_pools': [{'start': '211.34.18.130', 'end': '211.34.18.190'}], 'gateway_ip': '211.34.18.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '211.34.18.130', 'tags': [], 'project_id': '54a2dca2af9d44d6add4643c928fa214'}}
2026-05-20 15:47:34,621 - INFO - Created VN ctest-mgmt_vn-04445087
2026-05-20 15:47:34,634 - DEBUG - VN ctest-mgmt_vn-04445087 UUID is 2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:34,738 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:34,747 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:34,765 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:34,873 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:34,891 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:34,910 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7efca438-8f2b-4e16-bb84-43639623339a
2026-05-20 15:47:34,927 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7efca438-8f2b-4e16-bb84-43639623339a
2026-05-20 15:47:34,942 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/5bf5787c-5229-4abc-8016-7d45ed120edc
2026-05-20 15:47:34,956 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:47:34,956 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:34,973 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7efca438-8f2b-4e16-bb84-43639623339a
2026-05-20 15:47:34,999 - INFO - Verified VN network id 14 for VN 2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:34,999 - INFO - Verifications in API Server for VN ctest-mgmt_vn-04445087 passed
2026-05-20 15:47:34,999 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:35,017 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7efca438-8f2b-4e16-bb84-43639623339a
2026-05-20 15:47:35,031 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7efca438-8f2b-4e16-bb84-43639623339a
2026-05-20 15:47:35,044 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/5bf5787c-5229-4abc-8016-7d45ed120edc
2026-05-20 15:47:35,066 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2348617910233681838', 'uuid-lslong': '12680139201334370294'}, 'enable': 'true', 'created': '2026-05-20T15:47:34', 'last-modified': '2026-05-20T15:47:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.535806'}
2026-05-20 15:47:35,075 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:47:35,084 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2348617910233681838', 'uuid-lslong': '12680139201334370294'}, 'enable': 'true', 'created': '2026-05-20T15:47:34', 'last-modified': '2026-05-20T15:47:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.548471'}
2026-05-20 15:47:35,093 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:47:35,102 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2348617910233681838', 'uuid-lslong': '12680139201334370294'}, 'enable': 'true', 'created': '2026-05-20T15:47:34', 'last-modified': '2026-05-20T15:47:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.572247'}
2026-05-20 15:47:35,115 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:47:35,115 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-04445087 passed
2026-05-20 15:47:35,115 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-04445087 in API_Server ======
2026-05-20 15:47:35,115 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:35,127 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:35,147 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:35,265 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/2097f78b-bc99-4fae-aff8-e76bde0a5ff6
2026-05-20 15:47:35,280 - DEBUG - =>VN ctest-mgmt_vn-04445087 has no policy to be verified
2026-05-20 15:47:35,280 - DEBUG - Verifying the vn in opserver
2026-05-20 15:47:35,281 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087 virtual network link through opserver 10.0.0.57
2026-05-20 15:47:35,281 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:47:35,297 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087?flat'}
2026-05-20 15:47:35,297 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087 virtual network link through opserver 10.0.0.141
2026-05-20 15:47:35,297 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:47:35,314 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087?flat'}
2026-05-20 15:47:35,314 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087 virtual network link through opserver 10.0.0.56
2026-05-20 15:47:35,314 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:47:35,335 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087?flat'}
2026-05-20 15:47:35,335 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55473575:ctest-mgmt_vn-04445087 is found in opserver
2026-05-20 15:47:35,361 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:47:35,373 - DEBUG - VRF ids for VN ctest-mgmt_vn-04445087: {}
2026-05-20 15:47:35,718 - DEBUG - Response for create_network : {'network': {'id': 'ccda2354-141c-4036-a386-0e90a897d93c', 'name': 'ctest-left_vn-83599870', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'project_id': '54a2dca2af9d44d6add4643c928fa214', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55473575', 'ctest-left_vn-83599870'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:47:35,999 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e91836a4-f228-485e-9514-073043795e58', 'name': '', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'network_id': 'ccda2354-141c-4036-a386-0e90a897d93c', 'ip_version': 4, 'cidr': '44.167.150.64/26', 'allocation_pools': [{'start': '44.167.150.66', 'end': '44.167.150.126'}], 'gateway_ip': '44.167.150.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '44.167.150.66', 'tags': [], 'project_id': '54a2dca2af9d44d6add4643c928fa214'}}
2026-05-20 15:47:36,030 - INFO - Created VN ctest-left_vn-83599870
2026-05-20 15:47:36,046 - DEBUG - VN ctest-left_vn-83599870 UUID is ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,131 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:36,140 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:36,155 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:36,284 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,305 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,326 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/b7f011dc-063c-48c7-88bc-3c664e38819b
2026-05-20 15:47:36,341 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/b7f011dc-063c-48c7-88bc-3c664e38819b
2026-05-20 15:47:36,352 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/93402a44-d7a3-4171-b6cd-e9daaea582f6
2026-05-20 15:47:36,362 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:47:36,362 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,376 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/b7f011dc-063c-48c7-88bc-3c664e38819b
2026-05-20 15:47:36,402 - INFO - Verified VN network id 15 for VN ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,402 - INFO - Verifications in API Server for VN ctest-left_vn-83599870 passed
2026-05-20 15:47:36,402 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,418 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/b7f011dc-063c-48c7-88bc-3c664e38819b
2026-05-20 15:47:36,428 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/b7f011dc-063c-48c7-88bc-3c664e38819b
2026-05-20 15:47:36,444 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/93402a44-d7a3-4171-b6cd-e9daaea582f6
2026-05-20 15:47:36,465 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14761149572681842742', 'uuid-lslong': '11783121489528084796'}, 'enable': 'true', 'created': '2026-05-20T15:47:35', 'last-modified': '2026-05-20T15:47:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517066'}
2026-05-20 15:47:36,475 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:47:36,484 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14761149572681842742', 'uuid-lslong': '11783121489528084796'}, 'enable': 'true', 'created': '2026-05-20T15:47:35', 'last-modified': '2026-05-20T15:47:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.544042'}
2026-05-20 15:47:36,494 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:47:36,501 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14761149572681842742', 'uuid-lslong': '11783121489528084796'}, 'enable': 'true', 'created': '2026-05-20T15:47:35', 'last-modified': '2026-05-20T15:47:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.558376'}
2026-05-20 15:47:36,508 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:47:36,509 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-83599870 passed
2026-05-20 15:47:36,509 - DEBUG - ====Verifying policy data for ctest-left_vn-83599870 in API_Server ======
2026-05-20 15:47:36,509 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:36,518 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:36,539 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:36,660 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ccda2354-141c-4036-a386-0e90a897d93c
2026-05-20 15:47:36,674 - DEBUG - =>VN ctest-left_vn-83599870 has no policy to be verified
2026-05-20 15:47:36,674 - DEBUG - Verifying the vn in opserver
2026-05-20 15:47:36,674 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870 virtual network link through opserver 10.0.0.57
2026-05-20 15:47:36,674 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:47:36,689 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870?flat'}
2026-05-20 15:47:36,689 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870 virtual network link through opserver 10.0.0.141
2026-05-20 15:47:36,689 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:47:36,706 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870?flat'}
2026-05-20 15:47:36,706 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870 virtual network link through opserver 10.0.0.56
2026-05-20 15:47:36,706 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:47:36,723 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870?flat'}
2026-05-20 15:47:36,723 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55473575:ctest-left_vn-83599870 is found in opserver
2026-05-20 15:47:36,748 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:47:36,765 - DEBUG - VRF ids for VN ctest-left_vn-83599870: {}
2026-05-20 15:47:37,088 - DEBUG - Response for create_network : {'network': {'id': 'f1bd88e2-9b42-446c-b297-912d841b60ae', 'name': 'ctest-right_vn-34380355', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'project_id': '54a2dca2af9d44d6add4643c928fa214', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55473575', 'ctest-right_vn-34380355'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:47:37,410 - DEBUG - Response for create_subnet : {'subnet': {'id': '1d754f1c-af8e-4fae-92c6-ab6cb5fd47a0', 'name': '', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'network_id': 'f1bd88e2-9b42-446c-b297-912d841b60ae', 'ip_version': 4, 'cidr': '205.230.75.0/26', 'allocation_pools': [{'start': '205.230.75.2', 'end': '205.230.75.62'}], 'gateway_ip': '205.230.75.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '205.230.75.2', 'tags': [], 'project_id': '54a2dca2af9d44d6add4643c928fa214'}}
2026-05-20 15:47:37,446 - INFO - Created VN ctest-right_vn-34380355
2026-05-20 15:47:37,462 - DEBUG - VN ctest-right_vn-34380355 UUID is f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:37,588 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:37,596 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:37,610 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:37,721 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:37,738 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:37,753 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/03875996-74d8-41d1-84ca-82fe057c4bd9
2026-05-20 15:47:37,762 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/03875996-74d8-41d1-84ca-82fe057c4bd9
2026-05-20 15:47:37,771 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/13419057-578b-42a2-8ab3-778124874665
2026-05-20 15:47:37,781 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:47:37,781 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:37,794 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/03875996-74d8-41d1-84ca-82fe057c4bd9
2026-05-20 15:47:37,816 - INFO - Verified VN network id 16 for VN f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:37,818 - INFO - Verifications in API Server for VN ctest-right_vn-34380355 passed
2026-05-20 15:47:37,818 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:37,831 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/03875996-74d8-41d1-84ca-82fe057c4bd9
2026-05-20 15:47:37,839 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/03875996-74d8-41d1-84ca-82fe057c4bd9
2026-05-20 15:47:37,853 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/13419057-578b-42a2-8ab3-778124874665
2026-05-20 15:47:37,872 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17419229440587744364', 'uuid-lslong': '12868914084910424238'}, 'enable': 'true', 'created': '2026-05-20T15:47:37', 'last-modified': '2026-05-20T15:47:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549547'}
2026-05-20 15:47:37,881 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:47:37,887 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17419229440587744364', 'uuid-lslong': '12868914084910424238'}, 'enable': 'true', 'created': '2026-05-20T15:47:37', 'last-modified': '2026-05-20T15:47:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.554488'}
2026-05-20 15:47:37,895 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:47:37,902 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17419229440587744364', 'uuid-lslong': '12868914084910424238'}, 'enable': 'true', 'created': '2026-05-20T15:47:37', 'last-modified': '2026-05-20T15:47:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.577227'}
2026-05-20 15:47:37,911 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:47:37,911 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-34380355 passed
2026-05-20 15:47:37,911 - DEBUG - ====Verifying policy data for ctest-right_vn-34380355 in API_Server ======
2026-05-20 15:47:37,911 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:37,919 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:37,933 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:38,036 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/f1bd88e2-9b42-446c-b297-912d841b60ae
2026-05-20 15:47:38,052 - DEBUG - =>VN ctest-right_vn-34380355 has no policy to be verified
2026-05-20 15:47:38,052 - DEBUG - Verifying the vn in opserver
2026-05-20 15:47:38,052 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355 virtual network link through opserver 10.0.0.57
2026-05-20 15:47:38,052 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:47:38,068 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355?flat'}
2026-05-20 15:47:38,068 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355 virtual network link through opserver 10.0.0.141
2026-05-20 15:47:38,068 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:47:38,084 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355?flat'}
2026-05-20 15:47:38,084 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355 virtual network link through opserver 10.0.0.56
2026-05-20 15:47:38,084 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:47:38,101 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355?flat'}
2026-05-20 15:47:38,101 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55473575:ctest-right_vn-34380355 is found in opserver
2026-05-20 15:47:38,126 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:47:38,141 - DEBUG - VRF ids for VN ctest-right_vn-34380355: {}
2026-05-20 15:47:38,468 - DEBUG - Response for create_network : {'network': {'id': '11d1039d-ba93-4b60-a17f-57ceb9e22b86', 'name': 'ctest-trans_left_vn-53656007', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'project_id': '54a2dca2af9d44d6add4643c928fa214', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55473575', 'ctest-trans_left_vn-53656007'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:47:38,710 - DEBUG - Response for create_subnet : {'subnet': {'id': '96c6e7df-128d-420c-88f1-c2f35d3c285e', 'name': '', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'network_id': '11d1039d-ba93-4b60-a17f-57ceb9e22b86', 'ip_version': 4, 'cidr': '3.60.213.64/26', 'allocation_pools': [{'start': '3.60.213.66', 'end': '3.60.213.126'}], 'gateway_ip': '3.60.213.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3.60.213.66', 'tags': [], 'project_id': '54a2dca2af9d44d6add4643c928fa214'}}
2026-05-20 15:47:38,736 - INFO - Created VN ctest-trans_left_vn-53656007
2026-05-20 15:47:38,751 - DEBUG - VN ctest-trans_left_vn-53656007 UUID is 11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:38,844 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:38,853 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:38,867 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:38,976 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:39,005 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:39,021 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/1bed2c1b-24e6-42e9-8457-cb5586882532
2026-05-20 15:47:39,038 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/1bed2c1b-24e6-42e9-8457-cb5586882532
2026-05-20 15:47:39,052 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/8f780bb2-efd5-4d6b-b52b-f7ece6134f3e
2026-05-20 15:47:39,070 - DEBUG - Route Targets: ['target:64512:8000010']
2026-05-20 15:47:39,070 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:39,084 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/1bed2c1b-24e6-42e9-8457-cb5586882532
2026-05-20 15:47:39,105 - INFO - Verified VN network id 17 for VN 11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:39,106 - INFO - Verifications in API Server for VN ctest-trans_left_vn-53656007 passed
2026-05-20 15:47:39,106 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:39,121 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/1bed2c1b-24e6-42e9-8457-cb5586882532
2026-05-20 15:47:39,132 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/1bed2c1b-24e6-42e9-8457-cb5586882532
2026-05-20 15:47:39,147 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/8f780bb2-efd5-4d6b-b52b-f7ece6134f3e
2026-05-20 15:47:39,168 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1283811344752266080', 'uuid-lslong': '11637116507542137734'}, 'enable': 'true', 'created': '2026-05-20T15:47:38', 'last-modified': '2026-05-20T15:47:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.527192'}
2026-05-20 15:47:39,179 - DEBUG - Route Targets: ['target:64512:8000010']
2026-05-20 15:47:39,187 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1283811344752266080', 'uuid-lslong': '11637116507542137734'}, 'enable': 'true', 'created': '2026-05-20T15:47:38', 'last-modified': '2026-05-20T15:47:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534670'}
2026-05-20 15:47:39,196 - DEBUG - Route Targets: ['target:64512:8000010']
2026-05-20 15:47:39,204 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1283811344752266080', 'uuid-lslong': '11637116507542137734'}, 'enable': 'true', 'created': '2026-05-20T15:47:38', 'last-modified': '2026-05-20T15:47:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563776'}
2026-05-20 15:47:39,213 - DEBUG - Route Targets: ['target:64512:8000010']
2026-05-20 15:47:39,213 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-53656007 passed
2026-05-20 15:47:39,213 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-53656007 in API_Server ======
2026-05-20 15:47:39,213 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:39,222 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:39,242 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:39,368 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/11d1039d-ba93-4b60-a17f-57ceb9e22b86
2026-05-20 15:47:39,384 - DEBUG - =>VN ctest-trans_left_vn-53656007 has no policy to be verified
2026-05-20 15:47:39,384 - DEBUG - Verifying the vn in opserver
2026-05-20 15:47:39,384 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007 virtual network link through opserver 10.0.0.57
2026-05-20 15:47:39,385 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:47:39,401 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007?flat'}
2026-05-20 15:47:39,401 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007 virtual network link through opserver 10.0.0.141
2026-05-20 15:47:39,401 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:47:39,418 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007?flat'}
2026-05-20 15:47:39,418 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007 virtual network link through opserver 10.0.0.56
2026-05-20 15:47:39,418 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:47:39,436 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007?flat'}
2026-05-20 15:47:39,436 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55473575:ctest-trans_left_vn-53656007 is found in opserver
2026-05-20 15:47:39,466 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:47:39,479 - DEBUG - VRF ids for VN ctest-trans_left_vn-53656007: {}
2026-05-20 15:47:39,815 - DEBUG - Response for create_network : {'network': {'id': 'ad7dd30c-bcff-4498-bef5-7535e33ee3c4', 'name': 'ctest-trans_right_vn-29326515', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'project_id': '54a2dca2af9d44d6add4643c928fa214', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55473575', 'ctest-trans_right_vn-29326515'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:47:40,122 - DEBUG - Response for create_subnet : {'subnet': {'id': 'da168745-c6a5-4861-89a7-f5f513cf5d55', 'name': '', 'tenant_id': '54a2dca2af9d44d6add4643c928fa214', 'network_id': 'ad7dd30c-bcff-4498-bef5-7535e33ee3c4', 'ip_version': 4, 'cidr': '142.240.167.128/26', 'allocation_pools': [{'start': '142.240.167.130', 'end': '142.240.167.190'}], 'gateway_ip': '142.240.167.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.240.167.130', 'tags': [], 'project_id': '54a2dca2af9d44d6add4643c928fa214'}}
2026-05-20 15:47:40,152 - INFO - Created VN ctest-trans_right_vn-29326515
2026-05-20 15:47:40,171 - DEBUG - VN ctest-trans_right_vn-29326515 UUID is ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,269 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:40,277 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:40,293 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:40,399 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,416 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,428 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/f4b8670b-fe7c-406b-9872-e4eab5950593
2026-05-20 15:47:40,438 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/f4b8670b-fe7c-406b-9872-e4eab5950593
2026-05-20 15:47:40,448 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/da6d1235-9b4d-47f3-bdc9-7b2e7694ef18
2026-05-20 15:47:40,458 - DEBUG - Route Targets: ['target:64512:8000011']
2026-05-20 15:47:40,458 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,469 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/f4b8670b-fe7c-406b-9872-e4eab5950593
2026-05-20 15:47:40,489 - INFO - Verified VN network id 18 for VN ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,489 - INFO - Verifications in API Server for VN ctest-trans_right_vn-29326515 passed
2026-05-20 15:47:40,490 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,504 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/f4b8670b-fe7c-406b-9872-e4eab5950593
2026-05-20 15:47:40,518 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/f4b8670b-fe7c-406b-9872-e4eab5950593
2026-05-20 15:47:40,530 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/da6d1235-9b4d-47f3-bdc9-7b2e7694ef18
2026-05-20 15:47:40,550 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12501380192314279064', 'uuid-lslong': '13760033110806684612'}, 'enable': 'true', 'created': '2026-05-20T15:47:39', 'last-modified': '2026-05-20T15:47:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.518663'}
2026-05-20 15:47:40,560 - DEBUG - Route Targets: ['target:64512:8000011']
2026-05-20 15:47:40,567 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12501380192314279064', 'uuid-lslong': '13760033110806684612'}, 'enable': 'true', 'created': '2026-05-20T15:47:39', 'last-modified': '2026-05-20T15:47:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.540160'}
2026-05-20 15:47:40,576 - DEBUG - Route Targets: ['target:64512:8000011']
2026-05-20 15:47:40,585 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55473575', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12501380192314279064', 'uuid-lslong': '13760033110806684612'}, 'enable': 'true', 'created': '2026-05-20T15:47:39', 'last-modified': '2026-05-20T15:47:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.551639'}
2026-05-20 15:47:40,594 - DEBUG - Route Targets: ['target:64512:8000011']
2026-05-20 15:47:40,594 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-29326515 passed
2026-05-20 15:47:40,594 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-29326515 in API_Server ======
2026-05-20 15:47:40,594 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:40,602 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:40,620 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:40,736 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/ad7dd30c-bcff-4498-bef5-7535e33ee3c4
2026-05-20 15:47:40,751 - DEBUG - =>VN ctest-trans_right_vn-29326515 has no policy to be verified
2026-05-20 15:47:40,751 - DEBUG - Verifying the vn in opserver
2026-05-20 15:47:40,751 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515 virtual network link through opserver 10.0.0.57
2026-05-20 15:47:40,751 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:47:40,769 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515?flat'}
2026-05-20 15:47:40,769 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515 virtual network link through opserver 10.0.0.141
2026-05-20 15:47:40,769 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:47:40,786 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515?flat'}
2026-05-20 15:47:40,786 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515 virtual network link through opserver 10.0.0.56
2026-05-20 15:47:40,786 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:47:40,804 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515?flat'}
2026-05-20 15:47:40,804 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55473575:ctest-trans_right_vn-29326515 is found in opserver
2026-05-20 15:47:40,831 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:47:40,845 - DEBUG - VRF ids for VN ctest-trans_right_vn-29326515: {}
2026-05-20 15:47:42,391 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-2)
2026-05-20 15:47:44,164 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-1)
2026-05-20 15:47:44,165 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-15472624']
2026-05-20 15:47:44,502 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-15472624'] created succesfully
2026-05-20 15:47:44,996 - DEBUG - Image by name tiny_trans_fw not found
2026-05-20 15:47:44,996 - DEBUG - Installing image tiny_trans_fw
2026-05-20 15:47:44,996 - 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-05-20 15:47:44,996 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-05-20 15:47:44,996 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-05-20 15:47:45,030 - 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-05-20 15:47:45,076 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-05-20 15:47:45,076 - DEBUG - Unzip image
2026-05-20 15:47:45,076 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-05-20 15:47:45,131 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-05-20 15:47:45,134 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-15472624']
2026-05-20 15:47:45,234 - INFO - Service template: ['default-domain', 'ctest-service_template_1-15472624'] deleted successfully.
2026-05-20 15:47:45,234 - INFO - Skip interface_detach for VM ctest-right_vm-24012631 in state BUILD
2026-05-20 15:47:45,234 - INFO - Deleting VM ctest-right_vm-24012631
2026-05-20 15:47:45,234 - ERROR - VM ctest-right_vm-24012631 has failed to come up
2026-05-20 15:47:45,234 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:45,234 - ERROR - VM ctest-right_vm-24012631 has failed to come up
2026-05-20 15:47:45,234 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:45,334 - INFO - Skip interface_detach for VM ctest-left_vm-65428296 in state BUILD
2026-05-20 15:47:45,334 - INFO - Deleting VM ctest-left_vm-65428296
2026-05-20 15:47:45,334 - ERROR - VM ctest-left_vm-65428296 has failed to come up
2026-05-20 15:47:45,334 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:45,335 - ERROR - VM ctest-left_vm-65428296 has failed to come up
2026-05-20 15:47:45,335 - ERROR - Nova failed to get host of the VM
2026-05-20 15:47:45,469 - INFO - Deleting VN ctest-trans_right_vn-29326515
2026-05-20 15:47:45,708 - DEBUG - Response for deleting network ()
2026-05-20 15:47:45,708 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/f4b8670b-fe7c-406b-9872-e4eab5950593
2026-05-20 15:47:45,717 - DEBUG - Response Code: 404
2026-05-20 15:47:45,717 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:45,728 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:45,754 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:45,869 - INFO - Validated that VN ctest-trans_right_vn-29326515 is not found in API Server
2026-05-20 15:47:45,884 - DEBUG - VN ctest-trans_right_vn-29326515 is not present in Agent 10.0.0.242
2026-05-20 15:47:45,906 - DEBUG - VN ctest-trans_right_vn-29326515 is not present in Agent 10.0.0.249
2026-05-20 15:47:45,906 - INFO - Validated that VN ctest-trans_right_vn-29326515 is not in any agent
2026-05-20 15:47:45,955 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-29326515 info
2026-05-20 15:47:45,956 - INFO - Deleting VN ctest-trans_left_vn-53656007
2026-05-20 15:47:46,195 - DEBUG - Response for deleting network ()
2026-05-20 15:47:46,196 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/1bed2c1b-24e6-42e9-8457-cb5586882532
2026-05-20 15:47:46,207 - DEBUG - Response Code: 404
2026-05-20 15:47:46,207 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:46,217 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:46,274 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:46,404 - INFO - Validated that VN ctest-trans_left_vn-53656007 is not found in API Server
2026-05-20 15:47:46,420 - DEBUG - VN ctest-trans_left_vn-53656007 is not present in Agent 10.0.0.242
2026-05-20 15:47:46,441 - DEBUG - VN ctest-trans_left_vn-53656007 is not present in Agent 10.0.0.249
2026-05-20 15:47:46,441 - INFO - Validated that VN ctest-trans_left_vn-53656007 is not in any agent
2026-05-20 15:47:46,499 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-53656007 info
2026-05-20 15:47:46,500 - INFO - Deleting VN ctest-right_vn-34380355
2026-05-20 15:47:46,566 - DEBUG - VN f1bd88e2-9b42-446c-b297-912d841b60ae still in use: Unable to complete operation on network f1bd88e2-9b42-446c-b297-912d841b60ae. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-63a5e326-88ce-4c49-9885-bdb93c118f09']
2026-05-20 15:47:46,566 - WARNING - Deleting VN ctest-right_vn-34380355 failed..Will retry
2026-05-20 15:47:48,753 - DEBUG - Response for deleting network ()
2026-05-20 15:47:48,753 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/03875996-74d8-41d1-84ca-82fe057c4bd9
2026-05-20 15:47:48,763 - DEBUG - Response Code: 404
2026-05-20 15:47:48,763 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:48,780 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:48,800 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:48,915 - INFO - Validated that VN ctest-right_vn-34380355 is not found in API Server
2026-05-20 15:47:48,928 - DEBUG - VN ctest-right_vn-34380355 is not present in Agent 10.0.0.242
2026-05-20 15:47:48,944 - DEBUG - VN ctest-right_vn-34380355 is not present in Agent 10.0.0.249
2026-05-20 15:47:48,944 - INFO - Validated that VN ctest-right_vn-34380355 is not in any agent
2026-05-20 15:47:48,986 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-34380355 info
2026-05-20 15:47:48,986 - INFO - Deleting VN ctest-left_vn-83599870
2026-05-20 15:47:49,213 - DEBUG - Response for deleting network ()
2026-05-20 15:47:49,214 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/b7f011dc-063c-48c7-88bc-3c664e38819b
2026-05-20 15:47:49,222 - DEBUG - Response Code: 404
2026-05-20 15:47:49,222 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:49,245 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:49,266 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:49,385 - INFO - Validated that VN ctest-left_vn-83599870 is not found in API Server
2026-05-20 15:47:49,404 - DEBUG - VN ctest-left_vn-83599870 is not present in Agent 10.0.0.242
2026-05-20 15:47:49,416 - DEBUG - VN ctest-left_vn-83599870 is not present in Agent 10.0.0.249
2026-05-20 15:47:49,416 - INFO - Validated that VN ctest-left_vn-83599870 is not in any agent
2026-05-20 15:47:49,458 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-83599870 info
2026-05-20 15:47:49,459 - INFO - Deleting VN ctest-mgmt_vn-04445087
2026-05-20 15:47:49,650 - DEBUG - Response for deleting network ()
2026-05-20 15:47:49,650 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/7efca438-8f2b-4e16-bb84-43639623339a
2026-05-20 15:47:49,657 - DEBUG - Response Code: 404
2026-05-20 15:47:49,657 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:47:49,665 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:47:49,691 - DEBUG - Requesting: http://10.0.0.57:8082/project/54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:49,792 - INFO - Validated that VN ctest-mgmt_vn-04445087 is not found in API Server
2026-05-20 15:47:49,807 - DEBUG - VN ctest-mgmt_vn-04445087 is not present in Agent 10.0.0.242
2026-05-20 15:47:49,821 - DEBUG - VN ctest-mgmt_vn-04445087 is not present in Agent 10.0.0.249
2026-05-20 15:47:49,821 - INFO - Validated that VN ctest-mgmt_vn-04445087 is not in any agent
2026-05-20 15:47:49,858 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-04445087 info
2026-05-20 15:47:51,171 - DEBUG - No XMPP flaps were noticed during the test
2026-05-20 15:47:51,172 - INFO -
2026-05-20 15:47:51,172 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:19]
2026-05-20 15:47:51,172 - INFO - --------------------------------------------------------------------------------
2026-05-20 15:47:53,045 - INFO - Deleted project: ctest-TestSvcRegr-55473575, ID : 54a2dca2-af9d-44d6-add4-643c928fa214
2026-05-20 15:47:53,686 - INFO - Domain Default found not creating
2026-05-20 15:47:54,120 - INFO - Project ctest-TestSvcRegr-29538373 not found, creating it
2026-05-20 15:47:54,815 - INFO - Created Project:ctest-TestSvcRegr-29538373, ID : 10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:47:56,777 - INFO - ================================================================================
2026-05-20 15:47:56,777 - INFO - STARTING TEST : test_svc_CEM_22032
2026-05-20 15:47:58,107 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.249': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2026-05-20 15:47:58,107 - INFO - Initial checks done. Running the testcase now
2026-05-20 15:47:58,107 - INFO -
2026-05-20 15:47:59,063 - DEBUG - Response for create_network : {'network': {'id': '59012efb-832f-4756-a6a4-e04f3c8d45b3', 'name': 'ctest-mgmt_vn-59600092', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'project_id': '10e05e1b89674c2281f54c895882d17e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29538373', 'ctest-mgmt_vn-59600092'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:47:59,478 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bf635b67-bf5b-4bb8-b9dd-5a1fd66030ab', 'name': '', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'network_id': '59012efb-832f-4756-a6a4-e04f3c8d45b3', 'ip_version': 4, 'cidr': '111.70.224.128/26', 'allocation_pools': [{'start': '111.70.224.130', 'end': '111.70.224.190'}], 'gateway_ip': '111.70.224.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '111.70.224.130', 'tags': [], 'project_id': '10e05e1b89674c2281f54c895882d17e'}}
2026-05-20 15:47:59,514 - INFO - Created VN ctest-mgmt_vn-59600092
2026-05-20 15:47:59,530 - DEBUG - VN ctest-mgmt_vn-59600092 UUID is 59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:47:59,641 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:00,024 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:00,076 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:00,119 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:00,213 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:48:00,235 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:48:00,249 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/12748cc0-0c17-4493-9155-100d9cfba65a
2026-05-20 15:48:00,264 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/12748cc0-0c17-4493-9155-100d9cfba65a
2026-05-20 15:48:00,275 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/a290595c-49fa-4732-a527-9282debd759e
2026-05-20 15:48:00,285 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:00,285 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:48:00,301 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/12748cc0-0c17-4493-9155-100d9cfba65a
2026-05-20 15:48:00,327 - INFO - Verified VN network id 14 for VN 59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:48:00,327 - INFO - Verifications in API Server for VN ctest-mgmt_vn-59600092 passed
2026-05-20 15:48:00,327 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:48:00,341 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/12748cc0-0c17-4493-9155-100d9cfba65a
2026-05-20 15:48:00,353 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/12748cc0-0c17-4493-9155-100d9cfba65a
2026-05-20 15:48:00,367 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/a290595c-49fa-4732-a527-9282debd759e
2026-05-20 15:48:00,387 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6413459002124879702', 'uuid-lslong': '12007969137399514547'}, 'enable': 'true', 'created': '2026-05-20T15:47:58', 'last-modified': '2026-05-20T15:47:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.994573'}
2026-05-20 15:48:00,398 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:00,406 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6413459002124879702', 'uuid-lslong': '12007969137399514547'}, 'enable': 'true', 'created': '2026-05-20T15:47:58', 'last-modified': '2026-05-20T15:47:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.006273'}
2026-05-20 15:48:00,414 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:00,421 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6413459002124879702', 'uuid-lslong': '12007969137399514547'}, 'enable': 'true', 'created': '2026-05-20T15:47:58', 'last-modified': '2026-05-20T15:47:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.031196'}
2026-05-20 15:48:00,431 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:00,431 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-59600092 passed
2026-05-20 15:48:00,431 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-59600092 in API_Server ======
2026-05-20 15:48:00,431 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:00,441 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:00,458 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:00,579 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/59012efb-832f-4756-a6a4-e04f3c8d45b3
2026-05-20 15:48:00,595 - DEBUG - =>VN ctest-mgmt_vn-59600092 has no policy to be verified
2026-05-20 15:48:00,595 - DEBUG - Verifying the vn in opserver
2026-05-20 15:48:00,595 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092 virtual network link through opserver 10.0.0.57
2026-05-20 15:48:00,595 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:00,983 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:01,086 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092?flat'}
2026-05-20 15:48:01,086 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092 virtual network link through opserver 10.0.0.141
2026-05-20 15:48:01,086 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:01,448 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:01,550 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092?flat'}
2026-05-20 15:48:01,550 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092 virtual network link through opserver 10.0.0.56
2026-05-20 15:48:01,551 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:01,917 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:02,014 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092?flat'}
2026-05-20 15:48:02,014 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-59600092 is found in opserver
2026-05-20 15:48:02,044 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:48:02,057 - DEBUG - VRF ids for VN ctest-mgmt_vn-59600092: {}
2026-05-20 15:48:02,503 - DEBUG - Response for create_network : {'network': {'id': '522ccb9d-7c9e-4be2-9184-48be5cc64cc4', 'name': 'service-left-vn-33130861', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'project_id': '10e05e1b89674c2281f54c895882d17e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29538373', 'service-left-vn-33130861'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:48:02,869 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c9c7ae3-1cf4-419c-ab59-eab9fcf38349', 'name': '', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'network_id': '522ccb9d-7c9e-4be2-9184-48be5cc64cc4', 'ip_version': 4, 'cidr': '36.165.106.192/26', 'allocation_pools': [{'start': '36.165.106.194', 'end': '36.165.106.254'}], 'gateway_ip': '36.165.106.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '36.165.106.194', 'tags': [], 'project_id': '10e05e1b89674c2281f54c895882d17e'}}
2026-05-20 15:48:02,901 - INFO - Created VN service-left-vn-33130861
2026-05-20 15:48:02,922 - DEBUG - VN service-left-vn-33130861 UUID is 522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,020 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:03,029 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:03,045 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:03,171 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,195 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,210 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2896144a-50b2-4b49-a239-3722e0709270
2026-05-20 15:48:03,221 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2896144a-50b2-4b49-a239-3722e0709270
2026-05-20 15:48:03,233 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/e5d95e96-4e2b-45eb-b743-734b75bdc500
2026-05-20 15:48:03,243 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:03,243 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,256 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2896144a-50b2-4b49-a239-3722e0709270
2026-05-20 15:48:03,290 - INFO - Verified VN network id 15 for VN 522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,290 - INFO - Verifications in API Server for VN service-left-vn-33130861 passed
2026-05-20 15:48:03,290 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,310 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2896144a-50b2-4b49-a239-3722e0709270
2026-05-20 15:48:03,321 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2896144a-50b2-4b49-a239-3722e0709270
2026-05-20 15:48:03,333 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/e5d95e96-4e2b-45eb-b743-734b75bdc500
2026-05-20 15:48:03,354 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5921331487346412514', 'uuid-lslong': '10485585814862843076'}, 'enable': 'true', 'created': '2026-05-20T15:48:02', 'last-modified': '2026-05-20T15:48:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.593687'}
2026-05-20 15:48:03,364 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:03,371 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5921331487346412514', 'uuid-lslong': '10485585814862843076'}, 'enable': 'true', 'created': '2026-05-20T15:48:02', 'last-modified': '2026-05-20T15:48:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.605509'}
2026-05-20 15:48:03,379 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:03,390 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5921331487346412514', 'uuid-lslong': '10485585814862843076'}, 'enable': 'true', 'created': '2026-05-20T15:48:02', 'last-modified': '2026-05-20T15:48:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.630456'}
2026-05-20 15:48:03,398 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:03,398 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-33130861 passed
2026-05-20 15:48:03,398 - DEBUG - ====Verifying policy data for service-left-vn-33130861 in API_Server ======
2026-05-20 15:48:03,398 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:03,407 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:03,431 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:03,558 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/522ccb9d-7c9e-4be2-9184-48be5cc64cc4
2026-05-20 15:48:03,573 - DEBUG - =>VN service-left-vn-33130861 has no policy to be verified
2026-05-20 15:48:03,573 - DEBUG - Verifying the vn in opserver
2026-05-20 15:48:03,573 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861 virtual network link through opserver 10.0.0.57
2026-05-20 15:48:03,574 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:03,658 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861?flat'}
2026-05-20 15:48:03,659 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861 virtual network link through opserver 10.0.0.141
2026-05-20 15:48:03,659 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:03,753 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861?flat'}
2026-05-20 15:48:03,753 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861 virtual network link through opserver 10.0.0.56
2026-05-20 15:48:03,753 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:03,853 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861?flat'}
2026-05-20 15:48:03,853 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29538373:service-left-vn-33130861 is found in opserver
2026-05-20 15:48:03,881 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:48:03,895 - DEBUG - VRF ids for VN service-left-vn-33130861: {}
2026-05-20 15:48:04,338 - DEBUG - Response for create_network : {'network': {'id': '4608506b-6b08-4740-b1b1-59ecb711dbf7', 'name': 'service-right-vn-65991439', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'project_id': '10e05e1b89674c2281f54c895882d17e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29538373', 'service-right-vn-65991439'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:48:04,677 - DEBUG - Response for create_subnet : {'subnet': {'id': '7391d15b-ee46-4c4a-b5b1-f5ea3f793388', 'name': '', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'network_id': '4608506b-6b08-4740-b1b1-59ecb711dbf7', 'ip_version': 4, 'cidr': '114.188.72.192/26', 'allocation_pools': [{'start': '114.188.72.194', 'end': '114.188.72.254'}], 'gateway_ip': '114.188.72.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.188.72.194', 'tags': [], 'project_id': '10e05e1b89674c2281f54c895882d17e'}}
2026-05-20 15:48:04,716 - INFO - Created VN service-right-vn-65991439
2026-05-20 15:48:04,745 - DEBUG - VN service-right-vn-65991439 UUID is 4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:04,846 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:04,855 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:04,869 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:04,976 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:05,001 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:05,020 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8b23c665-fbb9-4bae-a044-fb5b2af0aacf
2026-05-20 15:48:05,039 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8b23c665-fbb9-4bae-a044-fb5b2af0aacf
2026-05-20 15:48:05,049 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/32588a2c-662f-4471-b1ff-8902197cba41
2026-05-20 15:48:05,072 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:05,072 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:05,090 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8b23c665-fbb9-4bae-a044-fb5b2af0aacf
2026-05-20 15:48:05,114 - INFO - Verified VN network id 16 for VN 4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:05,114 - INFO - Verifications in API Server for VN service-right-vn-65991439 passed
2026-05-20 15:48:05,114 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:05,132 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8b23c665-fbb9-4bae-a044-fb5b2af0aacf
2026-05-20 15:48:05,146 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8b23c665-fbb9-4bae-a044-fb5b2af0aacf
2026-05-20 15:48:05,160 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/32588a2c-662f-4471-b1ff-8902197cba41
2026-05-20 15:48:05,181 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5046371804756068160', 'uuid-lslong': '12804114088809585655'}, 'enable': 'true', 'created': '2026-05-20T15:48:04', 'last-modified': '2026-05-20T15:48:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.582458'}
2026-05-20 15:48:05,196 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:05,203 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5046371804756068160', 'uuid-lslong': '12804114088809585655'}, 'enable': 'true', 'created': '2026-05-20T15:48:04', 'last-modified': '2026-05-20T15:48:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.599821'}
2026-05-20 15:48:05,218 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:05,226 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5046371804756068160', 'uuid-lslong': '12804114088809585655'}, 'enable': 'true', 'created': '2026-05-20T15:48:04', 'last-modified': '2026-05-20T15:48:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.629579'}
2026-05-20 15:48:05,239 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:05,239 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-65991439 passed
2026-05-20 15:48:05,239 - DEBUG - ====Verifying policy data for service-right-vn-65991439 in API_Server ======
2026-05-20 15:48:05,239 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:05,264 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:05,284 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:05,418 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/4608506b-6b08-4740-b1b1-59ecb711dbf7
2026-05-20 15:48:05,434 - DEBUG - =>VN service-right-vn-65991439 has no policy to be verified
2026-05-20 15:48:05,434 - DEBUG - Verifying the vn in opserver
2026-05-20 15:48:05,434 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439 virtual network link through opserver 10.0.0.57
2026-05-20 15:48:05,434 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:05,530 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439?flat'}
2026-05-20 15:48:05,530 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439 virtual network link through opserver 10.0.0.141
2026-05-20 15:48:05,530 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:05,628 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439?flat'}
2026-05-20 15:48:05,628 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439 virtual network link through opserver 10.0.0.56
2026-05-20 15:48:05,628 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:05,711 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439?flat'}
2026-05-20 15:48:05,711 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29538373:service-right-vn-65991439 is found in opserver
2026-05-20 15:48:05,738 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:48:05,751 - DEBUG - VRF ids for VN service-right-vn-65991439: {}
2026-05-20 15:48:05,986 - DEBUG - Services list from nova: [, , , ]
2026-05-20 15:48:07,534 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-2)
2026-05-20 15:48:09,433 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-1)
2026-05-20 15:48:09,434 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-24195504']
2026-05-20 15:48:09,814 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-24195504'] created succesfully
2026-05-20 15:48:10,398 - DEBUG - Image by name tiny_nat_fw not found
2026-05-20 15:48:10,398 - DEBUG - Installing image tiny_nat_fw
2026-05-20 15:48:10,398 - 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-05-20 15:48:10,398 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:48:10,399 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:48:10,399 - DEBUG - Unzip image
2026-05-20 15:48:10,399 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:48:10,421 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-05-20 15:48:10,422 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-24195504']
2026-05-20 15:48:10,546 - INFO - Service template: ['default-domain', 'ctest-service_template_1-24195504'] deleted successfully.
2026-05-20 15:48:10,546 - INFO - Skip interface_detach for VM ctest-right_vm-31346826 in state BUILD
2026-05-20 15:48:10,546 - INFO - Deleting VM ctest-right_vm-31346826
2026-05-20 15:48:10,666 - ERROR - VM ctest-right_vm-31346826 has failed to come up
2026-05-20 15:48:10,666 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:10,666 - ERROR - VM ctest-right_vm-31346826 has failed to come up
2026-05-20 15:48:10,667 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:10,768 - INFO - Skip interface_detach for VM ctest-left_vm-37801815 in state BUILD
2026-05-20 15:48:10,768 - INFO - Deleting VM ctest-left_vm-37801815
2026-05-20 15:48:10,769 - ERROR - VM ctest-left_vm-37801815 has failed to come up
2026-05-20 15:48:10,769 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:10,769 - ERROR - VM ctest-left_vm-37801815 has failed to come up
2026-05-20 15:48:10,769 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:10,872 - INFO - Deleting VN service-right-vn-65991439
2026-05-20 15:48:10,932 - DEBUG - VN 4608506b-6b08-4740-b1b1-59ecb711dbf7 still in use: Unable to complete operation on network 4608506b-6b08-4740-b1b1-59ecb711dbf7. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0e4d63be-0fcd-4b67-bf77-8731254eaa41']
2026-05-20 15:48:10,932 - WARNING - Deleting VN service-right-vn-65991439 failed..Will retry
2026-05-20 15:48:13,214 - DEBUG - Response for deleting network ()
2026-05-20 15:48:13,214 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/8b23c665-fbb9-4bae-a044-fb5b2af0aacf
2026-05-20 15:48:13,222 - DEBUG - Response Code: 404
2026-05-20 15:48:13,222 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:13,242 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:13,268 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:13,383 - INFO - Validated that VN service-right-vn-65991439 is not found in API Server
2026-05-20 15:48:13,396 - DEBUG - VN service-right-vn-65991439 is not present in Agent 10.0.0.242
2026-05-20 15:48:13,411 - DEBUG - VN service-right-vn-65991439 is not present in Agent 10.0.0.249
2026-05-20 15:48:13,411 - INFO - Validated that VN service-right-vn-65991439 is not in any agent
2026-05-20 15:48:13,451 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-65991439 info
2026-05-20 15:48:13,451 - INFO - Deleting VN service-left-vn-33130861
2026-05-20 15:48:13,685 - DEBUG - Response for deleting network ()
2026-05-20 15:48:13,686 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/2896144a-50b2-4b49-a239-3722e0709270
2026-05-20 15:48:13,695 - DEBUG - Response Code: 404
2026-05-20 15:48:13,696 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:13,710 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:13,750 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:13,865 - INFO - Validated that VN service-left-vn-33130861 is not found in API Server
2026-05-20 15:48:13,883 - DEBUG - VN service-left-vn-33130861 is not present in Agent 10.0.0.242
2026-05-20 15:48:13,902 - DEBUG - VN service-left-vn-33130861 is not present in Agent 10.0.0.249
2026-05-20 15:48:13,902 - INFO - Validated that VN service-left-vn-33130861 is not in any agent
2026-05-20 15:48:13,947 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-33130861 info
2026-05-20 15:48:13,947 - INFO - Deleting VN ctest-mgmt_vn-59600092
2026-05-20 15:48:14,171 - DEBUG - Response for deleting network ()
2026-05-20 15:48:14,171 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/12748cc0-0c17-4493-9155-100d9cfba65a
2026-05-20 15:48:14,178 - DEBUG - Response Code: 404
2026-05-20 15:48:14,179 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:14,200 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:14,218 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:14,339 - INFO - Validated that VN ctest-mgmt_vn-59600092 is not found in API Server
2026-05-20 15:48:14,354 - DEBUG - VN ctest-mgmt_vn-59600092 is not present in Agent 10.0.0.242
2026-05-20 15:48:14,367 - DEBUG - VN ctest-mgmt_vn-59600092 is not present in Agent 10.0.0.249
2026-05-20 15:48:14,368 - INFO - Validated that VN ctest-mgmt_vn-59600092 is not in any agent
2026-05-20 15:48:14,429 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-59600092 info
2026-05-20 15:48:15,767 - DEBUG - No XMPP flaps were noticed during the test
2026-05-20 15:48:15,767 - INFO -
2026-05-20 15:48:15,768 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:19]
2026-05-20 15:48:15,768 - INFO - --------------------------------------------------------------------------------
2026-05-20 15:48:15,775 - INFO - ================================================================================
2026-05-20 15:48:15,775 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-05-20 15:48:17,106 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.249': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2026-05-20 15:48:17,106 - INFO - Initial checks done. Running the testcase now
2026-05-20 15:48:17,106 - INFO -
2026-05-20 15:48:17,420 - DEBUG - Response for create_network : {'network': {'id': '5d331e40-4b4c-4e3b-a0da-624aa5164cc5', 'name': 'ctest-mgmt_vn-70297324', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'project_id': '10e05e1b89674c2281f54c895882d17e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29538373', 'ctest-mgmt_vn-70297324'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:48:17,728 - DEBUG - Response for create_subnet : {'subnet': {'id': '17fb0cba-3753-453a-97b9-2a8a3abbf78c', 'name': '', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'network_id': '5d331e40-4b4c-4e3b-a0da-624aa5164cc5', 'ip_version': 4, 'cidr': '16.128.214.0/26', 'allocation_pools': [{'start': '16.128.214.2', 'end': '16.128.214.62'}], 'gateway_ip': '16.128.214.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '16.128.214.2', 'tags': [], 'project_id': '10e05e1b89674c2281f54c895882d17e'}}
2026-05-20 15:48:17,764 - INFO - Created VN ctest-mgmt_vn-70297324
2026-05-20 15:48:17,777 - DEBUG - VN ctest-mgmt_vn-70297324 UUID is 5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:17,888 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:17,900 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:17,918 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:18,036 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:18,060 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:18,076 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a80baa6c-eb2f-444c-bbaf-cd733c0ef2e9
2026-05-20 15:48:18,087 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a80baa6c-eb2f-444c-bbaf-cd733c0ef2e9
2026-05-20 15:48:18,098 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/90c096bc-4d9f-4fab-a5ec-7a951e969aa5
2026-05-20 15:48:18,108 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:18,108 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:18,121 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a80baa6c-eb2f-444c-bbaf-cd733c0ef2e9
2026-05-20 15:48:18,149 - INFO - Verified VN network id 14 for VN 5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:18,149 - INFO - Verifications in API Server for VN ctest-mgmt_vn-70297324 passed
2026-05-20 15:48:18,150 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:18,163 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a80baa6c-eb2f-444c-bbaf-cd733c0ef2e9
2026-05-20 15:48:18,174 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a80baa6c-eb2f-444c-bbaf-cd733c0ef2e9
2026-05-20 15:48:18,184 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/90c096bc-4d9f-4fab-a5ec-7a951e969aa5
2026-05-20 15:48:18,201 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6715744730829573691', 'uuid-lslong': '11590684663728196805'}, 'enable': 'true', 'created': '2026-05-20T15:48:17', 'last-modified': '2026-05-20T15:48:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.540123'}
2026-05-20 15:48:18,210 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:18,216 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6715744730829573691', 'uuid-lslong': '11590684663728196805'}, 'enable': 'true', 'created': '2026-05-20T15:48:17', 'last-modified': '2026-05-20T15:48:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.568623'}
2026-05-20 15:48:18,224 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:18,232 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6715744730829573691', 'uuid-lslong': '11590684663728196805'}, 'enable': 'true', 'created': '2026-05-20T15:48:17', 'last-modified': '2026-05-20T15:48:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.570624'}
2026-05-20 15:48:18,240 - DEBUG - Route Targets: ['target:64512:8000007']
2026-05-20 15:48:18,241 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-70297324 passed
2026-05-20 15:48:18,241 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-70297324 in API_Server ======
2026-05-20 15:48:18,241 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:18,249 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:18,264 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:18,376 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/5d331e40-4b4c-4e3b-a0da-624aa5164cc5
2026-05-20 15:48:18,394 - DEBUG - =>VN ctest-mgmt_vn-70297324 has no policy to be verified
2026-05-20 15:48:18,395 - DEBUG - Verifying the vn in opserver
2026-05-20 15:48:18,395 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324 virtual network link through opserver 10.0.0.57
2026-05-20 15:48:18,395 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:18,411 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324?flat'}
2026-05-20 15:48:18,411 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324 virtual network link through opserver 10.0.0.141
2026-05-20 15:48:18,411 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:18,426 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324?flat'}
2026-05-20 15:48:18,426 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324 virtual network link through opserver 10.0.0.56
2026-05-20 15:48:18,427 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:18,445 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324?flat'}
2026-05-20 15:48:18,445 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29538373:ctest-mgmt_vn-70297324 is found in opserver
2026-05-20 15:48:18,471 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:48:18,484 - DEBUG - VRF ids for VN ctest-mgmt_vn-70297324: {}
2026-05-20 15:48:18,803 - DEBUG - Response for create_network : {'network': {'id': '70096cb0-0524-4ac0-af97-66699704ad80', 'name': 'ctest-left_vn-28649045', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'project_id': '10e05e1b89674c2281f54c895882d17e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29538373', 'ctest-left_vn-28649045'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:48:19,120 - DEBUG - Response for create_subnet : {'subnet': {'id': '08f149b1-b3b8-4108-91d1-d185be3508c4', 'name': '', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'network_id': '70096cb0-0524-4ac0-af97-66699704ad80', 'ip_version': 4, 'cidr': '130.105.160.0/26', 'allocation_pools': [{'start': '130.105.160.2', 'end': '130.105.160.62'}], 'gateway_ip': '130.105.160.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.105.160.2', 'tags': [], 'project_id': '10e05e1b89674c2281f54c895882d17e'}}
2026-05-20 15:48:19,151 - INFO - Created VN ctest-left_vn-28649045
2026-05-20 15:48:19,170 - DEBUG - VN ctest-left_vn-28649045 UUID is 70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,264 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:19,273 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:19,291 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:19,408 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,423 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,439 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6130e8f1-4f87-4c54-a60f-f73d30dc642f
2026-05-20 15:48:19,453 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6130e8f1-4f87-4c54-a60f-f73d30dc642f
2026-05-20 15:48:19,467 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/13ec4f85-7dfa-4e0b-a1e4-228bd8a96207
2026-05-20 15:48:19,477 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:19,477 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,490 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6130e8f1-4f87-4c54-a60f-f73d30dc642f
2026-05-20 15:48:19,515 - INFO - Verified VN network id 15 for VN 70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,516 - INFO - Verifications in API Server for VN ctest-left_vn-28649045 passed
2026-05-20 15:48:19,516 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,529 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6130e8f1-4f87-4c54-a60f-f73d30dc642f
2026-05-20 15:48:19,539 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6130e8f1-4f87-4c54-a60f-f73d30dc642f
2026-05-20 15:48:19,550 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/13ec4f85-7dfa-4e0b-a1e4-228bd8a96207
2026-05-20 15:48:19,567 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8073103310294633152', 'uuid-lslong': '12652694281811963264'}, 'enable': 'true', 'created': '2026-05-20T15:48:18', 'last-modified': '2026-05-20T15:48:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512452'}
2026-05-20 15:48:19,578 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:19,585 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8073103310294633152', 'uuid-lslong': '12652694281811963264'}, 'enable': 'true', 'created': '2026-05-20T15:48:18', 'last-modified': '2026-05-20T15:48:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.535949'}
2026-05-20 15:48:19,593 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:19,601 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8073103310294633152', 'uuid-lslong': '12652694281811963264'}, 'enable': 'true', 'created': '2026-05-20T15:48:18', 'last-modified': '2026-05-20T15:48:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.543581'}
2026-05-20 15:48:19,611 - DEBUG - Route Targets: ['target:64512:8000008']
2026-05-20 15:48:19,611 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-28649045 passed
2026-05-20 15:48:19,611 - DEBUG - ====Verifying policy data for ctest-left_vn-28649045 in API_Server ======
2026-05-20 15:48:19,611 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:19,620 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:19,636 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:19,752 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/70096cb0-0524-4ac0-af97-66699704ad80
2026-05-20 15:48:19,764 - DEBUG - =>VN ctest-left_vn-28649045 has no policy to be verified
2026-05-20 15:48:19,764 - DEBUG - Verifying the vn in opserver
2026-05-20 15:48:19,764 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045 virtual network link through opserver 10.0.0.57
2026-05-20 15:48:19,764 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:19,779 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045?flat'}
2026-05-20 15:48:19,780 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045 virtual network link through opserver 10.0.0.141
2026-05-20 15:48:19,780 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:19,796 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045?flat'}
2026-05-20 15:48:19,796 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045 virtual network link through opserver 10.0.0.56
2026-05-20 15:48:19,796 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:19,813 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045?flat'}
2026-05-20 15:48:19,813 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29538373:ctest-left_vn-28649045 is found in opserver
2026-05-20 15:48:19,841 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:48:19,855 - DEBUG - VRF ids for VN ctest-left_vn-28649045: {}
2026-05-20 15:48:20,240 - DEBUG - Response for create_network : {'network': {'id': 'aa4fbf40-5393-4e96-bd67-852681fa3312', 'name': 'ctest-right_vn-06116528', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'project_id': '10e05e1b89674c2281f54c895882d17e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29538373', 'ctest-right_vn-06116528'], 'port_security_enabled': True, 'description': ''}}
2026-05-20 15:48:20,538 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bfb74f82-6715-4f63-9b76-54c3690beb02', 'name': '', 'tenant_id': '10e05e1b89674c2281f54c895882d17e', 'network_id': 'aa4fbf40-5393-4e96-bd67-852681fa3312', 'ip_version': 4, 'cidr': '8.81.35.0/26', 'allocation_pools': [{'start': '8.81.35.2', 'end': '8.81.35.62'}], 'gateway_ip': '8.81.35.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '8.81.35.2', 'tags': [], 'project_id': '10e05e1b89674c2281f54c895882d17e'}}
2026-05-20 15:48:20,567 - INFO - Created VN ctest-right_vn-06116528
2026-05-20 15:48:20,580 - DEBUG - VN ctest-right_vn-06116528 UUID is aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:20,697 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:20,706 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:20,721 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:20,833 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:20,851 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:20,864 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/c29666ab-772c-4484-b4c3-e8cb6fc6e009
2026-05-20 15:48:20,879 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/c29666ab-772c-4484-b4c3-e8cb6fc6e009
2026-05-20 15:48:20,891 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/9b14314e-4676-4c5c-9c7c-7423568bd684
2026-05-20 15:48:20,901 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:20,902 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:20,913 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/c29666ab-772c-4484-b4c3-e8cb6fc6e009
2026-05-20 15:48:20,932 - INFO - Verified VN network id 16 for VN aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:20,933 - INFO - Verifications in API Server for VN ctest-right_vn-06116528 passed
2026-05-20 15:48:20,933 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:20,945 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/c29666ab-772c-4484-b4c3-e8cb6fc6e009
2026-05-20 15:48:20,956 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/c29666ab-772c-4484-b4c3-e8cb6fc6e009
2026-05-20 15:48:20,966 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/9b14314e-4676-4c5c-9c7c-7423568bd684
2026-05-20 15:48:20,983 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12272237792608865942', 'uuid-lslong': '13648023596205486866'}, 'enable': 'true', 'created': '2026-05-20T15:48:20', 'last-modified': '2026-05-20T15:48:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.531916'}
2026-05-20 15:48:20,990 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:20,997 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12272237792608865942', 'uuid-lslong': '13648023596205486866'}, 'enable': 'true', 'created': '2026-05-20T15:48:20', 'last-modified': '2026-05-20T15:48:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549093'}
2026-05-20 15:48:21,005 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:21,012 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29538373', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12272237792608865942', 'uuid-lslong': '13648023596205486866'}, 'enable': 'true', 'created': '2026-05-20T15:48:20', 'last-modified': '2026-05-20T15:48:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563615'}
2026-05-20 15:48:21,019 - DEBUG - Route Targets: ['target:64512:8000009']
2026-05-20 15:48:21,020 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-06116528 passed
2026-05-20 15:48:21,020 - DEBUG - ====Verifying policy data for ctest-right_vn-06116528 in API_Server ======
2026-05-20 15:48:21,020 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:21,028 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:21,046 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:21,153 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/aa4fbf40-5393-4e96-bd67-852681fa3312
2026-05-20 15:48:21,166 - DEBUG - =>VN ctest-right_vn-06116528 has no policy to be verified
2026-05-20 15:48:21,166 - DEBUG - Verifying the vn in opserver
2026-05-20 15:48:21,166 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528 virtual network link through opserver 10.0.0.57
2026-05-20 15:48:21,166 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks
2026-05-20 15:48:21,182 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528?flat'}
2026-05-20 15:48:21,182 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528 virtual network link through opserver 10.0.0.141
2026-05-20 15:48:21,182 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-20 15:48:21,199 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528?flat'}
2026-05-20 15:48:21,199 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528 virtual network link through opserver 10.0.0.56
2026-05-20 15:48:21,199 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2026-05-20 15:48:21,216 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528?flat'}
2026-05-20 15:48:21,217 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29538373:ctest-right_vn-06116528 is found in opserver
2026-05-20 15:48:21,249 - DEBUG - Do not have enough data to verify VN in agent
2026-05-20 15:48:21,262 - DEBUG - VRF ids for VN ctest-right_vn-06116528: {}
2026-05-20 15:48:22,873 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-1)
2026-05-20 15:48:24,652 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5803-2)
2026-05-20 15:48:24,652 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-33784705']
2026-05-20 15:48:24,991 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-33784705'] created succesfully
2026-05-20 15:48:25,476 - DEBUG - Image by name tiny_nat_fw not found
2026-05-20 15:48:25,477 - DEBUG - Installing image tiny_nat_fw
2026-05-20 15:48:25,477 - 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-05-20 15:48:25,477 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:48:25,477 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:48:25,477 - DEBUG - Unzip image
2026-05-20 15:48:25,477 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-05-20 15:48:25,505 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-05-20 15:48:25,505 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-33784705']
2026-05-20 15:48:25,638 - INFO - Service template: ['default-domain', 'ctest-service_template_1-33784705'] deleted successfully.
2026-05-20 15:48:25,638 - INFO - Skip interface_detach for VM ctest-right_vm-05834496 in state BUILD
2026-05-20 15:48:25,638 - INFO - Deleting VM ctest-right_vm-05834496
2026-05-20 15:48:25,638 - ERROR - VM ctest-right_vm-05834496 has failed to come up
2026-05-20 15:48:25,638 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:25,638 - ERROR - VM ctest-right_vm-05834496 has failed to come up
2026-05-20 15:48:25,638 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:25,824 - INFO - Skip interface_detach for VM ctest-left_vm-46343084 in state BUILD
2026-05-20 15:48:25,824 - INFO - Deleting VM ctest-left_vm-46343084
2026-05-20 15:48:25,824 - ERROR - VM ctest-left_vm-46343084 has failed to come up
2026-05-20 15:48:25,824 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:25,824 - ERROR - VM ctest-left_vm-46343084 has failed to come up
2026-05-20 15:48:25,825 - ERROR - Nova failed to get host of the VM
2026-05-20 15:48:25,933 - INFO - Deleting VN ctest-right_vn-06116528
2026-05-20 15:48:25,998 - DEBUG - VN aa4fbf40-5393-4e96-bd67-852681fa3312 still in use: Unable to complete operation on network aa4fbf40-5393-4e96-bd67-852681fa3312. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-03dec66c-964d-4fe7-b717-04286188f802']
2026-05-20 15:48:25,998 - WARNING - Deleting VN ctest-right_vn-06116528 failed..Will retry
2026-05-20 15:48:28,222 - DEBUG - Response for deleting network ()
2026-05-20 15:48:28,222 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/c29666ab-772c-4484-b4c3-e8cb6fc6e009
2026-05-20 15:48:28,235 - DEBUG - Response Code: 404
2026-05-20 15:48:28,235 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:28,248 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:28,278 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:28,398 - INFO - Validated that VN ctest-right_vn-06116528 is not found in API Server
2026-05-20 15:48:28,412 - DEBUG - VN ctest-right_vn-06116528 is not present in Agent 10.0.0.242
2026-05-20 15:48:28,422 - DEBUG - VN ctest-right_vn-06116528 is not present in Agent 10.0.0.249
2026-05-20 15:48:28,423 - INFO - Validated that VN ctest-right_vn-06116528 is not in any agent
2026-05-20 15:48:28,460 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-06116528 info
2026-05-20 15:48:28,460 - INFO - Deleting VN ctest-left_vn-28649045
2026-05-20 15:48:28,686 - DEBUG - Response for deleting network ()
2026-05-20 15:48:28,686 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6130e8f1-4f87-4c54-a60f-f73d30dc642f
2026-05-20 15:48:28,702 - DEBUG - Response Code: 404
2026-05-20 15:48:28,702 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:28,710 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:28,734 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:28,855 - INFO - Validated that VN ctest-left_vn-28649045 is not found in API Server
2026-05-20 15:48:28,871 - DEBUG - VN ctest-left_vn-28649045 is not present in Agent 10.0.0.242
2026-05-20 15:48:28,885 - DEBUG - VN ctest-left_vn-28649045 is not present in Agent 10.0.0.249
2026-05-20 15:48:28,885 - INFO - Validated that VN ctest-left_vn-28649045 is not in any agent
2026-05-20 15:48:28,926 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-28649045 info
2026-05-20 15:48:28,926 - INFO - Deleting VN ctest-mgmt_vn-70297324
2026-05-20 15:48:29,142 - DEBUG - Response for deleting network ()
2026-05-20 15:48:29,142 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a80baa6c-eb2f-444c-bbaf-cd733c0ef2e9
2026-05-20 15:48:29,152 - DEBUG - Response Code: 404
2026-05-20 15:48:29,152 - DEBUG - Requesting: http://10.0.0.57:8082/domains
2026-05-20 15:48:29,176 - DEBUG - Requesting: http://10.0.0.57:8082/domain/ffb590f3-7b6e-40e6-89b8-bd634f94ea0b
2026-05-20 15:48:29,196 - DEBUG - Requesting: http://10.0.0.57:8082/project/10e05e1b-8967-4c22-81f5-4c895882d17e
2026-05-20 15:48:29,323 - INFO - Validated that VN ctest-mgmt_vn-70297324 is not found in API Server
2026-05-20 15:48:29,337 - DEBUG - VN ctest-mgmt_vn-70297324 is not present in Agent 10.0.0.242
2026-05-20 15:48:29,351 - DEBUG - VN ctest-mgmt_vn-70297324 is not present in Agent 10.0.0.249
2026-05-20 15:48:29,351 - INFO - Validated that VN ctest-mgmt_vn-70297324 is not in any agent
2026-05-20 15:48:29,395 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-70297324 info
2026-05-20 15:48:30,748 - DEBUG - No XMPP flaps were noticed during the test
2026-05-20 15:48:30,749 - INFO -
2026-05-20 15:48:30,749 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:15]
2026-05-20 15:48:30,749 - INFO - --------------------------------------------------------------------------------
2026-05-20 15:48:31,678 - INFO - Deleted project: ctest-TestSvcRegr-29538373, ID : 10e05e1b-8967-4c22-81f5-4c895882d17e