]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-2)
2026-01-05 03:02:19,296 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-32369391']
2026-01-05 03:02:19,607 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-32369391'] created succesfully
2026-01-05 03:02:20,031 - DEBUG - Image by name tiny_nat_fw not found
2026-01-05 03:02:20,031 - DEBUG - Installing image tiny_nat_fw
2026-01-05 03:02:20,031 - 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-01-05 03:02:20,032 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:20,032 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:20,087 - 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-01-05 03:02:20,141 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:20,141 - DEBUG - Unzip image
2026-01-05 03:02:20,142 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:20,176 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-05 03:02:20,178 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-32369391']
2026-01-05 03:02:20,290 - INFO - Service template: ['default-domain', 'ctest-service_template_1-32369391'] deleted successfully.
2026-01-05 03:02:20,290 - INFO - Skip interface_detach for VM ctest-right_vm-25332638 in state BUILD
2026-01-05 03:02:20,290 - INFO - Deleting VM ctest-right_vm-25332638
2026-01-05 03:02:20,388 - ERROR - VM ctest-right_vm-25332638 has failed to come up
2026-01-05 03:02:20,388 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:20,388 - ERROR - VM ctest-right_vm-25332638 has failed to come up
2026-01-05 03:02:20,388 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:20,574 - INFO - Skip interface_detach for VM ctest-left_vm-24030448 in state BUILD
2026-01-05 03:02:20,575 - INFO - Deleting VM ctest-left_vm-24030448
2026-01-05 03:02:20,575 - ERROR - VM ctest-left_vm-24030448 has failed to come up
2026-01-05 03:02:20,575 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:20,575 - ERROR - VM ctest-left_vm-24030448 has failed to come up
2026-01-05 03:02:20,576 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:20,668 - INFO - Deleting VN ctest-right_vn-87476810
2026-01-05 03:02:20,718 - DEBUG - VN 6fc1eb07-2888-4028-87cd-d1fd8115ffcc still in use: Unable to complete operation on network 6fc1eb07-2888-4028-87cd-d1fd8115ffcc. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-61eaa80e-a6ad-45e8-9a4c-939d95138661']
2026-01-05 03:02:20,719 - WARNING - Deleting VN ctest-right_vn-87476810 failed..Will retry
2026-01-05 03:02:23,010 - DEBUG - Response for deleting network ()
2026-01-05 03:02:23,010 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7409f8cd-7e37-456b-a778-027192af150a
2026-01-05 03:02:23,016 - DEBUG - Response Code: 404
2026-01-05 03:02:23,016 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:23,039 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:23,055 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:23,173 - INFO - Validated that VN ctest-right_vn-87476810 is not found in API Server
2026-01-05 03:02:23,184 - DEBUG - VN ctest-right_vn-87476810 is not present in Agent 10.0.0.57
2026-01-05 03:02:23,194 - DEBUG - VN ctest-right_vn-87476810 is not present in Agent 10.0.0.56
2026-01-05 03:02:23,194 - INFO - Validated that VN ctest-right_vn-87476810 is not in any agent
2026-01-05 03:02:23,227 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-87476810 info
2026-01-05 03:02:23,227 - INFO - Deleting VN ctest-left_vn-93243725
2026-01-05 03:02:23,382 - DEBUG - Response for deleting network ()
2026-01-05 03:02:23,382 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/72f612e0-a811-482e-ab65-86b247152a7a
2026-01-05 03:02:23,390 - DEBUG - Response Code: 404
2026-01-05 03:02:23,390 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:23,397 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:23,452 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:23,556 - INFO - Validated that VN ctest-left_vn-93243725 is not found in API Server
2026-01-05 03:02:23,568 - DEBUG - VN ctest-left_vn-93243725 is not present in Agent 10.0.0.57
2026-01-05 03:02:23,579 - DEBUG - VN ctest-left_vn-93243725 is not present in Agent 10.0.0.56
2026-01-05 03:02:23,579 - INFO - Validated that VN ctest-left_vn-93243725 is not in any agent
2026-01-05 03:02:23,619 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-93243725 info
2026-01-05 03:02:23,619 - INFO - Deleting VN ctest-mgmt_vn-06617513
2026-01-05 03:02:23,774 - DEBUG - Response for deleting network ()
2026-01-05 03:02:23,774 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6e063a5f-7de3-40ad-9770-44a41f0e2d5c
2026-01-05 03:02:23,780 - DEBUG - Response Code: 404
2026-01-05 03:02:23,780 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:23,790 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:23,802 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:23,908 - INFO - Validated that VN ctest-mgmt_vn-06617513 is not found in API Server
2026-01-05 03:02:23,923 - DEBUG - VN ctest-mgmt_vn-06617513 is not present in Agent 10.0.0.57
2026-01-05 03:02:23,936 - DEBUG - VN ctest-mgmt_vn-06617513 is not present in Agent 10.0.0.56
2026-01-05 03:02:23,936 - INFO - Validated that VN ctest-mgmt_vn-06617513 is not in any agent
2026-01-05 03:02:23,974 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-06617513 info
2026-01-05 03:02:23,974 - INFO - Deleting VM ctest-vm-in-third-vn-28498372
2026-01-05 03:02:24,072 - INFO - Deleting VN ctest-third-vn-96084879
2026-01-05 03:02:24,120 - DEBUG - VN ef56d573-a428-4e2b-ab71-f49999e44be3 still in use: Unable to complete operation on network ef56d573-a428-4e2b-ab71-f49999e44be3. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e8e953e3-93e9-48cb-8d48-b1429a85ad7a']
2026-01-05 03:02:24,120 - WARNING - Deleting VN ctest-third-vn-96084879 failed..Will retry
2026-01-05 03:02:26,289 - DEBUG - Response for deleting network ()
2026-01-05 03:02:27,562 - DEBUG - No XMPP flaps were noticed during the test
2026-01-05 03:02:27,562 - INFO -
2026-01-05 03:02:27,563 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2026-01-05 03:02:27,563 - INFO - --------------------------------------------------------------------------------
2026-01-05 03:02:27,566 - INFO - ================================================================================
2026-01-05 03:02:27,566 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-01-05 03:02:28,848 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.129': '0'}, '10.0.0.56': {'10.20.0.254': '0', '10.20.0.129': '0'}} with
2026-01-05 03:02:28,848 - INFO - Initial checks done. Running the testcase now
2026-01-05 03:02:28,848 - INFO -
2026-01-05 03:02:29,151 - DEBUG - Response for create_network : {'network': {'id': '3ce64151-13aa-4943-9cce-f97495d849cd', 'name': 'ctest-mgmt_vn-41199307', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-48895986', 'ctest-mgmt_vn-41199307'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:29,371 - DEBUG - Response for create_subnet : {'subnet': {'id': '3f749376-8194-45e9-8fc6-3bf2f8302652', 'name': '', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'network_id': '3ce64151-13aa-4943-9cce-f97495d849cd', 'ip_version': 4, 'cidr': '168.0.247.128/26', 'allocation_pools': [{'start': '168.0.247.130', 'end': '168.0.247.190'}], 'gateway_ip': '168.0.247.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.0.247.130', 'tags': [], 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f'}}
2026-01-05 03:02:29,397 - INFO - Created VN ctest-mgmt_vn-41199307
2026-01-05 03:02:29,414 - DEBUG - VN ctest-mgmt_vn-41199307 UUID is 3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,486 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:29,492 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:29,503 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:29,599 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,612 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,622 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f9d43d01-e8b0-4424-a697-acdd86b67dbc
2026-01-05 03:02:29,630 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f9d43d01-e8b0-4424-a697-acdd86b67dbc
2026-01-05 03:02:29,637 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/322c875b-a960-4cfe-895b-f1ffc13cd34d
2026-01-05 03:02:29,643 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:29,643 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,652 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f9d43d01-e8b0-4424-a697-acdd86b67dbc
2026-01-05 03:02:29,669 - INFO - Verified VN network id 14 for VN 3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,670 - INFO - Verifications in API Server for VN ctest-mgmt_vn-41199307 passed
2026-01-05 03:02:29,670 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,681 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f9d43d01-e8b0-4424-a697-acdd86b67dbc
2026-01-05 03:02:29,688 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f9d43d01-e8b0-4424-a697-acdd86b67dbc
2026-01-05 03:02:29,697 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/322c875b-a960-4cfe-895b-f1ffc13cd34d
2026-01-05 03:02:29,709 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4388266703397210435', 'uuid-lslong': '11299242794244655565'}, 'enable': 'true', 'created': '2026-01-05T03:02:29', 'last-modified': '2026-01-05T03:02:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399912'}
2026-01-05 03:02:29,715 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:29,720 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4388266703397210435', 'uuid-lslong': '11299242794244655565'}, 'enable': 'true', 'created': '2026-01-05T03:02:29', 'last-modified': '2026-01-05T03:02:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410043'}
2026-01-05 03:02:29,727 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:29,736 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4388266703397210435', 'uuid-lslong': '11299242794244655565'}, 'enable': 'true', 'created': '2026-01-05T03:02:29', 'last-modified': '2026-01-05T03:02:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437116'}
2026-01-05 03:02:29,744 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:29,744 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-41199307 passed
2026-01-05 03:02:29,744 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-41199307 in API_Server ======
2026-01-05 03:02:29,744 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:29,756 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:29,768 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:29,864 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3ce64151-13aa-4943-9cce-f97495d849cd
2026-01-05 03:02:29,885 - DEBUG - =>VN ctest-mgmt_vn-41199307 has no policy to be verified
2026-01-05 03:02:29,886 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:29,886 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:29,886 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:29,906 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307?flat'}
2026-01-05 03:02:29,906 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:29,906 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:29,918 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307?flat'}
2026-01-05 03:02:29,918 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:29,918 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:29,935 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307?flat'}
2026-01-05 03:02:29,935 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-48895986:ctest-mgmt_vn-41199307 is found in opserver
2026-01-05 03:02:29,956 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:29,969 - DEBUG - VRF ids for VN ctest-mgmt_vn-41199307: {}
2026-01-05 03:02:30,250 - DEBUG - Response for create_network : {'network': {'id': '59a786bf-2388-4649-b10e-2a6dab5af6bd', 'name': 'ctest-left_vn-28279402', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-48895986', 'ctest-left_vn-28279402'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:30,492 - DEBUG - Response for create_subnet : {'subnet': {'id': '358925b1-e994-4b93-ae79-b59b949eefcc', 'name': '', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'network_id': '59a786bf-2388-4649-b10e-2a6dab5af6bd', 'ip_version': 4, 'cidr': '29.1.136.128/26', 'allocation_pools': [{'start': '29.1.136.130', 'end': '29.1.136.190'}], 'gateway_ip': '29.1.136.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.1.136.130', 'tags': [], 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f'}}
2026-01-05 03:02:30,514 - INFO - Created VN ctest-left_vn-28279402
2026-01-05 03:02:30,526 - DEBUG - VN ctest-left_vn-28279402 UUID is 59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,610 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:30,617 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:30,629 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:30,726 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,743 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,753 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0f305c2-f399-4649-8e59-7ccd44b17cb0
2026-01-05 03:02:30,760 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0f305c2-f399-4649-8e59-7ccd44b17cb0
2026-01-05 03:02:30,768 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/9c11cc95-f225-46e4-8912-1ee0d03947c7
2026-01-05 03:02:30,776 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:30,776 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,785 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0f305c2-f399-4649-8e59-7ccd44b17cb0
2026-01-05 03:02:30,801 - INFO - Verified VN network id 15 for VN 59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,801 - INFO - Verifications in API Server for VN ctest-left_vn-28279402 passed
2026-01-05 03:02:30,801 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,810 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0f305c2-f399-4649-8e59-7ccd44b17cb0
2026-01-05 03:02:30,819 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0f305c2-f399-4649-8e59-7ccd44b17cb0
2026-01-05 03:02:30,827 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/9c11cc95-f225-46e4-8912-1ee0d03947c7
2026-01-05 03:02:30,839 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6460280345979274825', 'uuid-lslong': '12758181444901861053'}, 'enable': 'true', 'created': '2026-01-05T03:02:30', 'last-modified': '2026-01-05T03:02:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402509'}
2026-01-05 03:02:30,844 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:30,849 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6460280345979274825', 'uuid-lslong': '12758181444901861053'}, 'enable': 'true', 'created': '2026-01-05T03:02:30', 'last-modified': '2026-01-05T03:02:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415648'}
2026-01-05 03:02:30,856 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:30,862 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6460280345979274825', 'uuid-lslong': '12758181444901861053'}, 'enable': 'true', 'created': '2026-01-05T03:02:30', 'last-modified': '2026-01-05T03:02:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427226'}
2026-01-05 03:02:30,868 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:30,868 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-28279402 passed
2026-01-05 03:02:30,868 - DEBUG - ====Verifying policy data for ctest-left_vn-28279402 in API_Server ======
2026-01-05 03:02:30,868 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:30,875 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:30,888 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:30,987 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/59a786bf-2388-4649-b10e-2a6dab5af6bd
2026-01-05 03:02:30,997 - DEBUG - =>VN ctest-left_vn-28279402 has no policy to be verified
2026-01-05 03:02:30,997 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:30,997 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:30,997 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:31,065 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402?flat'}
2026-01-05 03:02:31,065 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:31,065 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:31,128 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402?flat'}
2026-01-05 03:02:31,128 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:31,128 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:31,142 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402?flat'}
2026-01-05 03:02:31,142 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-48895986:ctest-left_vn-28279402 is found in opserver
2026-01-05 03:02:31,161 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:31,171 - DEBUG - VRF ids for VN ctest-left_vn-28279402: {}
2026-01-05 03:02:31,451 - DEBUG - Response for create_network : {'network': {'id': '63bee25a-651d-4484-9c3d-e54dc3d25eb0', 'name': 'ctest-right_vn-98685620', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-48895986', 'ctest-right_vn-98685620'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:31,671 - DEBUG - Response for create_subnet : {'subnet': {'id': '02568474-8475-4ca8-ad1e-8134eb6d2d42', 'name': '', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'network_id': '63bee25a-651d-4484-9c3d-e54dc3d25eb0', 'ip_version': 4, 'cidr': '136.253.21.128/26', 'allocation_pools': [{'start': '136.253.21.130', 'end': '136.253.21.190'}], 'gateway_ip': '136.253.21.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '136.253.21.130', 'tags': [], 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f'}}
2026-01-05 03:02:31,692 - INFO - Created VN ctest-right_vn-98685620
2026-01-05 03:02:31,704 - DEBUG - VN ctest-right_vn-98685620 UUID is 63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:31,779 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:31,784 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:31,796 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:31,889 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:31,904 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:31,913 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5c0b4e51-cfbe-4f46-b7ed-e133ff6109fc
2026-01-05 03:02:31,921 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5c0b4e51-cfbe-4f46-b7ed-e133ff6109fc
2026-01-05 03:02:31,927 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7609ca9f-8a8f-4731-b3b2-634f7cf8334f
2026-01-05 03:02:31,933 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:31,933 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:31,942 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5c0b4e51-cfbe-4f46-b7ed-e133ff6109fc
2026-01-05 03:02:31,956 - INFO - Verified VN network id 16 for VN 63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:31,956 - INFO - Verifications in API Server for VN ctest-right_vn-98685620 passed
2026-01-05 03:02:31,956 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:31,965 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5c0b4e51-cfbe-4f46-b7ed-e133ff6109fc
2026-01-05 03:02:31,976 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5c0b4e51-cfbe-4f46-b7ed-e133ff6109fc
2026-01-05 03:02:31,983 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7609ca9f-8a8f-4731-b3b2-634f7cf8334f
2026-01-05 03:02:31,993 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7187430933201241220', 'uuid-lslong': '11258406765656694448'}, 'enable': 'true', 'created': '2026-01-05T03:02:31', 'last-modified': '2026-01-05T03:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.375719'}
2026-01-05 03:02:31,998 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:32,003 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7187430933201241220', 'uuid-lslong': '11258406765656694448'}, 'enable': 'true', 'created': '2026-01-05T03:02:31', 'last-modified': '2026-01-05T03:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381064'}
2026-01-05 03:02:32,009 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:32,014 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7187430933201241220', 'uuid-lslong': '11258406765656694448'}, 'enable': 'true', 'created': '2026-01-05T03:02:31', 'last-modified': '2026-01-05T03:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393391'}
2026-01-05 03:02:32,019 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:32,019 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-98685620 passed
2026-01-05 03:02:32,020 - DEBUG - ====Verifying policy data for ctest-right_vn-98685620 in API_Server ======
2026-01-05 03:02:32,020 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:32,025 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:32,034 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:32,124 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/63bee25a-651d-4484-9c3d-e54dc3d25eb0
2026-01-05 03:02:32,135 - DEBUG - =>VN ctest-right_vn-98685620 has no policy to be verified
2026-01-05 03:02:32,135 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:32,135 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:32,135 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:32,148 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620?flat'}
2026-01-05 03:02:32,148 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:32,148 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:32,161 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620?flat'}
2026-01-05 03:02:32,161 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:32,161 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:32,173 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620?flat'}
2026-01-05 03:02:32,174 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-48895986:ctest-right_vn-98685620 is found in opserver
2026-01-05 03:02:32,194 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:32,203 - DEBUG - VRF ids for VN ctest-right_vn-98685620: {}
2026-01-05 03:02:32,526 - DEBUG - Response for create_network : {'network': {'id': '8f4e3cfe-587c-44fa-a159-fed65cc7dbe8', 'name': 'ctest-trans_left_vn-80817476', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-48895986', 'ctest-trans_left_vn-80817476'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:32,786 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c9e59ab4-c7d2-41dc-b377-e7483f59f37d', 'name': '', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'network_id': '8f4e3cfe-587c-44fa-a159-fed65cc7dbe8', 'ip_version': 4, 'cidr': '168.71.46.128/26', 'allocation_pools': [{'start': '168.71.46.130', 'end': '168.71.46.190'}], 'gateway_ip': '168.71.46.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.71.46.130', 'tags': [], 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f'}}
2026-01-05 03:02:32,807 - INFO - Created VN ctest-trans_left_vn-80817476
2026-01-05 03:02:32,821 - DEBUG - VN ctest-trans_left_vn-80817476 UUID is 8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:32,899 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:32,907 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:32,920 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:33,019 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:33,030 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:33,040 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/1fc6db4a-43ac-4ce1-8004-4b8df2515bc6
2026-01-05 03:02:33,049 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/1fc6db4a-43ac-4ce1-8004-4b8df2515bc6
2026-01-05 03:02:33,058 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/aadae988-eb5a-4204-837c-c485b4b5ac7c
2026-01-05 03:02:33,065 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-05 03:02:33,065 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:33,075 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/1fc6db4a-43ac-4ce1-8004-4b8df2515bc6
2026-01-05 03:02:33,094 - INFO - Verified VN network id 17 for VN 8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:33,094 - INFO - Verifications in API Server for VN ctest-trans_left_vn-80817476 passed
2026-01-05 03:02:33,094 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:33,104 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/1fc6db4a-43ac-4ce1-8004-4b8df2515bc6
2026-01-05 03:02:33,111 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/1fc6db4a-43ac-4ce1-8004-4b8df2515bc6
2026-01-05 03:02:33,119 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/aadae988-eb5a-4204-837c-c485b4b5ac7c
2026-01-05 03:02:33,132 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10326258058711024890', 'uuid-lslong': '11626604109666704360'}, 'enable': 'true', 'created': '2026-01-05T03:02:32', 'last-modified': '2026-01-05T03:02:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402116'}
2026-01-05 03:02:33,138 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-05 03:02:33,145 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10326258058711024890', 'uuid-lslong': '11626604109666704360'}, 'enable': 'true', 'created': '2026-01-05T03:02:32', 'last-modified': '2026-01-05T03:02:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414759'}
2026-01-05 03:02:33,150 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-05 03:02:33,156 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10326258058711024890', 'uuid-lslong': '11626604109666704360'}, 'enable': 'true', 'created': '2026-01-05T03:02:32', 'last-modified': '2026-01-05T03:02:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426097'}
2026-01-05 03:02:33,161 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-05 03:02:33,161 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-80817476 passed
2026-01-05 03:02:33,161 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-80817476 in API_Server ======
2026-01-05 03:02:33,161 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:33,168 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:33,181 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:33,272 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8f4e3cfe-587c-44fa-a159-fed65cc7dbe8
2026-01-05 03:02:33,294 - DEBUG - =>VN ctest-trans_left_vn-80817476 has no policy to be verified
2026-01-05 03:02:33,294 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:33,294 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:33,294 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:33,307 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476?flat'}
2026-01-05 03:02:33,307 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:33,307 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:33,322 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476?flat'}
2026-01-05 03:02:33,323 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:33,323 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:33,336 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476?flat'}
2026-01-05 03:02:33,336 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-48895986:ctest-trans_left_vn-80817476 is found in opserver
2026-01-05 03:02:33,359 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:33,369 - DEBUG - VRF ids for VN ctest-trans_left_vn-80817476: {}
2026-01-05 03:02:33,650 - DEBUG - Response for create_network : {'network': {'id': '096f6d69-775c-410c-bf70-92231df49a2e', 'name': 'ctest-trans_right_vn-61891114', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-48895986', 'ctest-trans_right_vn-61891114'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:33,919 - DEBUG - Response for create_subnet : {'subnet': {'id': '99433027-01d2-40f5-bb04-13bc1571680a', 'name': '', 'tenant_id': '74dc9028862d4c89b3a76cc86ed69d1f', 'network_id': '096f6d69-775c-410c-bf70-92231df49a2e', 'ip_version': 4, 'cidr': '78.254.74.128/26', 'allocation_pools': [{'start': '78.254.74.130', 'end': '78.254.74.190'}], 'gateway_ip': '78.254.74.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.254.74.130', 'tags': [], 'project_id': '74dc9028862d4c89b3a76cc86ed69d1f'}}
2026-01-05 03:02:33,943 - INFO - Created VN ctest-trans_right_vn-61891114
2026-01-05 03:02:33,955 - DEBUG - VN ctest-trans_right_vn-61891114 UUID is 096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,041 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:34,047 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:34,059 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:34,149 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,162 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,175 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/477cf4c2-fba2-4ccb-8956-9fcb943f97dc
2026-01-05 03:02:34,183 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/477cf4c2-fba2-4ccb-8956-9fcb943f97dc
2026-01-05 03:02:34,190 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/6e0f7d2d-aa3d-49cc-bd03-c43cce8dca53
2026-01-05 03:02:34,196 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-05 03:02:34,196 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,207 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/477cf4c2-fba2-4ccb-8956-9fcb943f97dc
2026-01-05 03:02:34,226 - INFO - Verified VN network id 18 for VN 096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,226 - INFO - Verifications in API Server for VN ctest-trans_right_vn-61891114 passed
2026-01-05 03:02:34,226 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,236 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/477cf4c2-fba2-4ccb-8956-9fcb943f97dc
2026-01-05 03:02:34,243 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/477cf4c2-fba2-4ccb-8956-9fcb943f97dc
2026-01-05 03:02:34,251 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/6e0f7d2d-aa3d-49cc-bd03-c43cce8dca53
2026-01-05 03:02:34,263 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '679882368497762572', 'uuid-lslong': '13794686338159909422'}, 'enable': 'true', 'created': '2026-01-05T03:02:33', 'last-modified': '2026-01-05T03:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397459'}
2026-01-05 03:02:34,271 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-05 03:02:34,280 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '679882368497762572', 'uuid-lslong': '13794686338159909422'}, 'enable': 'true', 'created': '2026-01-05T03:02:33', 'last-modified': '2026-01-05T03:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411456'}
2026-01-05 03:02:34,285 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-05 03:02:34,291 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-48895986', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '679882368497762572', 'uuid-lslong': '13794686338159909422'}, 'enable': 'true', 'created': '2026-01-05T03:02:33', 'last-modified': '2026-01-05T03:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425692'}
2026-01-05 03:02:34,298 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-05 03:02:34,298 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-61891114 passed
2026-01-05 03:02:34,298 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-61891114 in API_Server ======
2026-01-05 03:02:34,298 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:34,304 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:34,315 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:34,409 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/096f6d69-775c-410c-bf70-92231df49a2e
2026-01-05 03:02:34,424 - DEBUG - =>VN ctest-trans_right_vn-61891114 has no policy to be verified
2026-01-05 03:02:34,424 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:34,424 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:34,424 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:34,439 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114?flat'}
2026-01-05 03:02:34,439 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:34,439 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:34,456 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114?flat'}
2026-01-05 03:02:34,456 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:34,456 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:34,480 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114?flat'}
2026-01-05 03:02:34,481 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-48895986:ctest-trans_right_vn-61891114 is found in opserver
2026-01-05 03:02:34,507 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:34,518 - DEBUG - VRF ids for VN ctest-trans_right_vn-61891114: {}
2026-01-05 03:02:35,533 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-2)
2026-01-05 03:02:36,967 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-1)
2026-01-05 03:02:36,967 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-96821790']
2026-01-05 03:02:37,266 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-96821790'] created succesfully
2026-01-05 03:02:37,575 - DEBUG - Image by name tiny_trans_fw not found
2026-01-05 03:02:37,576 - DEBUG - Installing image tiny_trans_fw
2026-01-05 03:02:37,576 - 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-01-05 03:02:37,576 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-05 03:02:37,576 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-05 03:02:37,602 - 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-01-05 03:02:37,639 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-05 03:02:37,639 - DEBUG - Unzip image
2026-01-05 03:02:37,639 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-05 03:02:37,663 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-01-05 03:02:37,664 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-96821790']
2026-01-05 03:02:37,767 - INFO - Service template: ['default-domain', 'ctest-service_template_1-96821790'] deleted successfully.
2026-01-05 03:02:37,768 - INFO - Skip interface_detach for VM ctest-right_vm-44057740 in state BUILD
2026-01-05 03:02:37,768 - INFO - Deleting VM ctest-right_vm-44057740
2026-01-05 03:02:37,768 - ERROR - VM ctest-right_vm-44057740 has failed to come up
2026-01-05 03:02:37,768 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:37,768 - ERROR - VM ctest-right_vm-44057740 has failed to come up
2026-01-05 03:02:37,768 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:37,885 - INFO - Skip interface_detach for VM ctest-left_vm-80010601 in state BUILD
2026-01-05 03:02:37,885 - INFO - Deleting VM ctest-left_vm-80010601
2026-01-05 03:02:37,886 - ERROR - VM ctest-left_vm-80010601 has failed to come up
2026-01-05 03:02:37,886 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:37,886 - ERROR - VM ctest-left_vm-80010601 has failed to come up
2026-01-05 03:02:37,886 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:37,962 - INFO - Deleting VN ctest-trans_right_vn-61891114
2026-01-05 03:02:38,125 - DEBUG - Response for deleting network ()
2026-01-05 03:02:38,126 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/477cf4c2-fba2-4ccb-8956-9fcb943f97dc
2026-01-05 03:02:38,132 - DEBUG - Response Code: 404
2026-01-05 03:02:38,132 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:38,146 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:38,204 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:38,310 - INFO - Validated that VN ctest-trans_right_vn-61891114 is not found in API Server
2026-01-05 03:02:38,359 - DEBUG - VN ctest-trans_right_vn-61891114 is not present in Agent 10.0.0.57
2026-01-05 03:02:38,369 - DEBUG - VN ctest-trans_right_vn-61891114 is not present in Agent 10.0.0.56
2026-01-05 03:02:38,369 - INFO - Validated that VN ctest-trans_right_vn-61891114 is not in any agent
2026-01-05 03:02:38,400 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-61891114 info
2026-01-05 03:02:38,401 - INFO - Deleting VN ctest-trans_left_vn-80817476
2026-01-05 03:02:38,557 - DEBUG - Response for deleting network ()
2026-01-05 03:02:38,557 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/1fc6db4a-43ac-4ce1-8004-4b8df2515bc6
2026-01-05 03:02:38,568 - DEBUG - Response Code: 404
2026-01-05 03:02:38,569 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:38,577 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:38,596 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:38,701 - INFO - Validated that VN ctest-trans_left_vn-80817476 is not found in API Server
2026-01-05 03:02:38,723 - DEBUG - VN ctest-trans_left_vn-80817476 is not present in Agent 10.0.0.57
2026-01-05 03:02:38,749 - DEBUG - VN ctest-trans_left_vn-80817476 is not present in Agent 10.0.0.56
2026-01-05 03:02:38,749 - INFO - Validated that VN ctest-trans_left_vn-80817476 is not in any agent
2026-01-05 03:02:38,792 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-80817476 info
2026-01-05 03:02:38,792 - INFO - Deleting VN ctest-right_vn-98685620
2026-01-05 03:02:38,847 - DEBUG - VN 63bee25a-651d-4484-9c3d-e54dc3d25eb0 still in use: Unable to complete operation on network 63bee25a-651d-4484-9c3d-e54dc3d25eb0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f694cee8-10de-4b82-861f-91d3c705f1d3']
2026-01-05 03:02:38,847 - WARNING - Deleting VN ctest-right_vn-98685620 failed..Will retry
2026-01-05 03:02:40,994 - DEBUG - Response for deleting network ()
2026-01-05 03:02:40,994 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5c0b4e51-cfbe-4f46-b7ed-e133ff6109fc
2026-01-05 03:02:41,000 - DEBUG - Response Code: 404
2026-01-05 03:02:41,001 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:41,007 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:41,019 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:41,123 - INFO - Validated that VN ctest-right_vn-98685620 is not found in API Server
2026-01-05 03:02:41,135 - DEBUG - VN ctest-right_vn-98685620 is not present in Agent 10.0.0.57
2026-01-05 03:02:41,145 - DEBUG - VN ctest-right_vn-98685620 is not present in Agent 10.0.0.56
2026-01-05 03:02:41,145 - INFO - Validated that VN ctest-right_vn-98685620 is not in any agent
2026-01-05 03:02:41,177 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-98685620 info
2026-01-05 03:02:41,177 - INFO - Deleting VN ctest-left_vn-28279402
2026-01-05 03:02:41,329 - DEBUG - Response for deleting network ()
2026-01-05 03:02:41,330 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0f305c2-f399-4649-8e59-7ccd44b17cb0
2026-01-05 03:02:41,336 - DEBUG - Response Code: 404
2026-01-05 03:02:41,336 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:41,341 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:41,408 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:41,523 - INFO - Validated that VN ctest-left_vn-28279402 is not found in API Server
2026-01-05 03:02:41,539 - DEBUG - VN ctest-left_vn-28279402 is not present in Agent 10.0.0.57
2026-01-05 03:02:41,553 - DEBUG - VN ctest-left_vn-28279402 is not present in Agent 10.0.0.56
2026-01-05 03:02:41,553 - INFO - Validated that VN ctest-left_vn-28279402 is not in any agent
2026-01-05 03:02:41,589 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-28279402 info
2026-01-05 03:02:41,589 - INFO - Deleting VN ctest-mgmt_vn-41199307
2026-01-05 03:02:41,743 - DEBUG - Response for deleting network ()
2026-01-05 03:02:41,743 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f9d43d01-e8b0-4424-a697-acdd86b67dbc
2026-01-05 03:02:41,749 - DEBUG - Response Code: 404
2026-01-05 03:02:41,749 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:41,756 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:41,767 - DEBUG - Requesting: http://10.0.0.27:8082/project/74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:41,851 - INFO - Validated that VN ctest-mgmt_vn-41199307 is not found in API Server
2026-01-05 03:02:41,868 - DEBUG - VN ctest-mgmt_vn-41199307 is not present in Agent 10.0.0.57
2026-01-05 03:02:41,878 - DEBUG - VN ctest-mgmt_vn-41199307 is not present in Agent 10.0.0.56
2026-01-05 03:02:41,878 - INFO - Validated that VN ctest-mgmt_vn-41199307 is not in any agent
2026-01-05 03:02:41,909 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-41199307 info
2026-01-05 03:02:43,182 - DEBUG - No XMPP flaps were noticed during the test
2026-01-05 03:02:43,182 - INFO -
2026-01-05 03:02:43,183 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2026-01-05 03:02:43,183 - INFO - --------------------------------------------------------------------------------
2026-01-05 03:02:43,898 - INFO - Deleted project: ctest-TestSvcRegr-48895986, ID : 74dc9028-862d-4c89-b3a7-6cc86ed69d1f
2026-01-05 03:02:44,085 - INFO - Domain Default found not creating
2026-01-05 03:02:44,254 - INFO - Project ctest-TestSvcRegr-78203014 not found, creating it
2026-01-05 03:02:44,807 - INFO - Created Project:ctest-TestSvcRegr-78203014, ID : d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:46,344 - INFO - ================================================================================
2026-01-05 03:02:46,344 - INFO - STARTING TEST : test_svc_CEM_22032
2026-01-05 03:02:47,625 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.129': '0'}, '10.0.0.56': {'10.20.0.254': '0', '10.20.0.129': '0'}} with
2026-01-05 03:02:47,625 - INFO - Initial checks done. Running the testcase now
2026-01-05 03:02:47,626 - INFO -
2026-01-05 03:02:48,297 - DEBUG - Response for create_network : {'network': {'id': 'b3471116-1b2a-4908-b496-db2cb8f92fb7', 'name': 'ctest-mgmt_vn-36475164', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'project_id': 'd7ee2745edf04722ae02f47d269c5429', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78203014', 'ctest-mgmt_vn-36475164'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:48,560 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a2b7e3ea-c860-43ad-86b8-251f186683e6', 'name': '', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'network_id': 'b3471116-1b2a-4908-b496-db2cb8f92fb7', 'ip_version': 4, 'cidr': '6.254.251.0/26', 'allocation_pools': [{'start': '6.254.251.2', 'end': '6.254.251.62'}], 'gateway_ip': '6.254.251.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.254.251.2', 'tags': [], 'project_id': 'd7ee2745edf04722ae02f47d269c5429'}}
2026-01-05 03:02:48,586 - INFO - Created VN ctest-mgmt_vn-36475164
2026-01-05 03:02:48,602 - DEBUG - VN ctest-mgmt_vn-36475164 UUID is b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:48,715 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:49,169 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:49,296 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:49,319 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:49,416 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:49,429 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:49,439 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/3ac476df-adc4-4097-97af-9267fc3cbf10
2026-01-05 03:02:49,449 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/3ac476df-adc4-4097-97af-9267fc3cbf10
2026-01-05 03:02:49,460 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/88253ad6-3de9-40fb-b7aa-41e436d431a4
2026-01-05 03:02:49,466 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:49,466 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:49,476 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/3ac476df-adc4-4097-97af-9267fc3cbf10
2026-01-05 03:02:49,493 - INFO - Verified VN network id 14 for VN b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:49,493 - INFO - Verifications in API Server for VN ctest-mgmt_vn-36475164 passed
2026-01-05 03:02:49,493 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:49,503 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/3ac476df-adc4-4097-97af-9267fc3cbf10
2026-01-05 03:02:49,510 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/3ac476df-adc4-4097-97af-9267fc3cbf10
2026-01-05 03:02:49,516 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/88253ad6-3de9-40fb-b7aa-41e436d431a4
2026-01-05 03:02:49,527 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12918312842778265864', 'uuid-lslong': '13012829158462009271'}, 'enable': 'true', 'created': '2026-01-05T03:02:48', 'last-modified': '2026-01-05T03:02:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.035887'}
2026-01-05 03:02:49,534 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:49,541 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12918312842778265864', 'uuid-lslong': '13012829158462009271'}, 'enable': 'true', 'created': '2026-01-05T03:02:48', 'last-modified': '2026-01-05T03:02:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.053291'}
2026-01-05 03:02:49,547 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:49,553 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12918312842778265864', 'uuid-lslong': '13012829158462009271'}, 'enable': 'true', 'created': '2026-01-05T03:02:48', 'last-modified': '2026-01-05T03:02:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.064217'}
2026-01-05 03:02:49,560 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:02:49,560 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-36475164 passed
2026-01-05 03:02:49,560 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-36475164 in API_Server ======
2026-01-05 03:02:49,560 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:49,567 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:49,583 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:49,683 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b3471116-1b2a-4908-b496-db2cb8f92fb7
2026-01-05 03:02:49,694 - DEBUG - =>VN ctest-mgmt_vn-36475164 has no policy to be verified
2026-01-05 03:02:49,694 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:49,694 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:49,694 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:49,962 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:50,028 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164?flat'}
2026-01-05 03:02:50,028 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:50,029 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:50,295 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:50,362 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164?flat'}
2026-01-05 03:02:50,362 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:50,362 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:50,627 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:50,700 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164?flat'}
2026-01-05 03:02:50,700 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-36475164 is found in opserver
2026-01-05 03:02:50,725 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:50,738 - DEBUG - VRF ids for VN ctest-mgmt_vn-36475164: {}
2026-01-05 03:02:51,019 - DEBUG - Response for create_network : {'network': {'id': '605cd976-c0a8-4f8f-be74-6a521fad2dd9', 'name': 'service-left-vn-43423288', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'project_id': 'd7ee2745edf04722ae02f47d269c5429', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78203014', 'service-left-vn-43423288'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:51,319 - DEBUG - Response for create_subnet : {'subnet': {'id': '6db7473f-fbe5-447f-8585-7f93fa91a257', 'name': '', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'network_id': '605cd976-c0a8-4f8f-be74-6a521fad2dd9', 'ip_version': 4, 'cidr': '173.96.147.0/26', 'allocation_pools': [{'start': '173.96.147.2', 'end': '173.96.147.62'}], 'gateway_ip': '173.96.147.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.96.147.2', 'tags': [], 'project_id': 'd7ee2745edf04722ae02f47d269c5429'}}
2026-01-05 03:02:51,366 - INFO - Created VN service-left-vn-43423288
2026-01-05 03:02:51,386 - DEBUG - VN service-left-vn-43423288 UUID is 605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,473 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:51,481 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:51,492 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:51,587 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,603 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,615 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/006ccb38-f960-4de0-8bcb-11a3917bc6b1
2026-01-05 03:02:51,625 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/006ccb38-f960-4de0-8bcb-11a3917bc6b1
2026-01-05 03:02:51,645 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/89a8be80-aa16-4d9a-a3d2-c0819807fcea
2026-01-05 03:02:51,656 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:51,656 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,666 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/006ccb38-f960-4de0-8bcb-11a3917bc6b1
2026-01-05 03:02:51,686 - INFO - Verified VN network id 15 for VN 605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,686 - INFO - Verifications in API Server for VN service-left-vn-43423288 passed
2026-01-05 03:02:51,686 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,698 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/006ccb38-f960-4de0-8bcb-11a3917bc6b1
2026-01-05 03:02:51,705 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/006ccb38-f960-4de0-8bcb-11a3917bc6b1
2026-01-05 03:02:51,713 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/89a8be80-aa16-4d9a-a3d2-c0819807fcea
2026-01-05 03:02:51,724 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6943663829560086415', 'uuid-lslong': '13723710865456049625'}, 'enable': 'true', 'created': '2026-01-05T03:02:50', 'last-modified': '2026-01-05T03:02:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477846'}
2026-01-05 03:02:51,730 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:51,736 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6943663829560086415', 'uuid-lslong': '13723710865456049625'}, 'enable': 'true', 'created': '2026-01-05T03:02:50', 'last-modified': '2026-01-05T03:02:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.489685'}
2026-01-05 03:02:51,743 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:51,750 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6943663829560086415', 'uuid-lslong': '13723710865456049625'}, 'enable': 'true', 'created': '2026-01-05T03:02:50', 'last-modified': '2026-01-05T03:02:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.502726'}
2026-01-05 03:02:51,756 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:02:51,756 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-43423288 passed
2026-01-05 03:02:51,756 - DEBUG - ====Verifying policy data for service-left-vn-43423288 in API_Server ======
2026-01-05 03:02:51,756 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:51,762 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:51,776 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:51,872 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/605cd976-c0a8-4f8f-be74-6a521fad2dd9
2026-01-05 03:02:51,884 - DEBUG - =>VN service-left-vn-43423288 has no policy to be verified
2026-01-05 03:02:51,885 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:51,885 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:51,885 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:51,951 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288?flat'}
2026-01-05 03:02:51,951 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:51,952 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:52,024 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288?flat'}
2026-01-05 03:02:52,024 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:52,024 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:52,091 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288?flat'}
2026-01-05 03:02:52,091 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78203014:service-left-vn-43423288 is found in opserver
2026-01-05 03:02:52,112 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:52,122 - DEBUG - VRF ids for VN service-left-vn-43423288: {}
2026-01-05 03:02:52,418 - DEBUG - Response for create_network : {'network': {'id': '48e7d7dc-e60a-4495-b144-50cc7784f78b', 'name': 'service-right-vn-46403964', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'project_id': 'd7ee2745edf04722ae02f47d269c5429', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78203014', 'service-right-vn-46403964'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:02:52,651 - DEBUG - Response for create_subnet : {'subnet': {'id': '616ca167-02ff-401a-9b30-d9af743b61cb', 'name': '', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'network_id': '48e7d7dc-e60a-4495-b144-50cc7784f78b', 'ip_version': 4, 'cidr': '3.160.19.192/26', 'allocation_pools': [{'start': '3.160.19.194', 'end': '3.160.19.254'}], 'gateway_ip': '3.160.19.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3.160.19.194', 'tags': [], 'project_id': 'd7ee2745edf04722ae02f47d269c5429'}}
2026-01-05 03:02:52,675 - INFO - Created VN service-right-vn-46403964
2026-01-05 03:02:52,685 - DEBUG - VN service-right-vn-46403964 UUID is 48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:52,759 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:52,765 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:52,774 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:52,866 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:52,879 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:52,887 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/aaf3f949-a7e9-40d4-ad61-efedce86b6d7
2026-01-05 03:02:52,894 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/aaf3f949-a7e9-40d4-ad61-efedce86b6d7
2026-01-05 03:02:52,901 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/1e731e42-915d-40ab-a559-f8416014032c
2026-01-05 03:02:52,908 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:52,908 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:52,917 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/aaf3f949-a7e9-40d4-ad61-efedce86b6d7
2026-01-05 03:02:52,932 - INFO - Verified VN network id 16 for VN 48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:52,932 - INFO - Verifications in API Server for VN service-right-vn-46403964 passed
2026-01-05 03:02:52,932 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:52,941 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/aaf3f949-a7e9-40d4-ad61-efedce86b6d7
2026-01-05 03:02:52,950 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/aaf3f949-a7e9-40d4-ad61-efedce86b6d7
2026-01-05 03:02:52,958 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/1e731e42-915d-40ab-a559-f8416014032c
2026-01-05 03:02:52,980 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5253404834103182485', 'uuid-lslong': '12773423282238322571'}, 'enable': 'true', 'created': '2026-01-05T03:02:52', 'last-modified': '2026-01-05T03:02:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378657'}
2026-01-05 03:02:52,987 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:52,993 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5253404834103182485', 'uuid-lslong': '12773423282238322571'}, 'enable': 'true', 'created': '2026-01-05T03:02:52', 'last-modified': '2026-01-05T03:02:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.400247'}
2026-01-05 03:02:52,998 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:53,003 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5253404834103182485', 'uuid-lslong': '12773423282238322571'}, 'enable': 'true', 'created': '2026-01-05T03:02:52', 'last-modified': '2026-01-05T03:02:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408994'}
2026-01-05 03:02:53,009 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:02:53,009 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-46403964 passed
2026-01-05 03:02:53,009 - DEBUG - ====Verifying policy data for service-right-vn-46403964 in API_Server ======
2026-01-05 03:02:53,009 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:53,016 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:53,027 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:53,116 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/48e7d7dc-e60a-4495-b144-50cc7784f78b
2026-01-05 03:02:53,129 - DEBUG - =>VN service-right-vn-46403964 has no policy to be verified
2026-01-05 03:02:53,129 - DEBUG - Verifying the vn in opserver
2026-01-05 03:02:53,129 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964 virtual network link through opserver 10.0.0.27
2026-01-05 03:02:53,129 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:02:53,191 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964?flat'}
2026-01-05 03:02:53,191 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964 virtual network link through opserver 10.0.0.33
2026-01-05 03:02:53,191 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:02:53,264 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964?flat'}
2026-01-05 03:02:53,264 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964 virtual network link through opserver 10.0.0.23
2026-01-05 03:02:53,264 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:02:53,327 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964?flat'}
2026-01-05 03:02:53,327 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78203014:service-right-vn-46403964 is found in opserver
2026-01-05 03:02:53,347 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:02:53,356 - DEBUG - VRF ids for VN service-right-vn-46403964: {}
2026-01-05 03:02:53,412 - DEBUG - Services list from nova: [, , , ]
2026-01-05 03:02:54,401 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-2)
2026-01-05 03:02:55,759 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-1)
2026-01-05 03:02:55,759 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-24443692']
2026-01-05 03:02:56,055 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-24443692'] created succesfully
2026-01-05 03:02:56,440 - DEBUG - Image by name tiny_nat_fw not found
2026-01-05 03:02:56,440 - DEBUG - Installing image tiny_nat_fw
2026-01-05 03:02:56,440 - 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-01-05 03:02:56,440 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:56,440 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:56,440 - DEBUG - Unzip image
2026-01-05 03:02:56,440 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:02:56,466 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-05 03:02:56,466 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-24443692']
2026-01-05 03:02:56,570 - INFO - Service template: ['default-domain', 'ctest-service_template_1-24443692'] deleted successfully.
2026-01-05 03:02:56,570 - INFO - Skip interface_detach for VM ctest-right_vm-54410958 in state BUILD
2026-01-05 03:02:56,570 - INFO - Deleting VM ctest-right_vm-54410958
2026-01-05 03:02:56,611 - ERROR - VM ctest-right_vm-54410958 has failed to come up
2026-01-05 03:02:56,611 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:56,611 - ERROR - VM ctest-right_vm-54410958 has failed to come up
2026-01-05 03:02:56,611 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:56,715 - INFO - Skip interface_detach for VM ctest-left_vm-30784849 in state BUILD
2026-01-05 03:02:56,715 - INFO - Deleting VM ctest-left_vm-30784849
2026-01-05 03:02:56,715 - ERROR - VM ctest-left_vm-30784849 has failed to come up
2026-01-05 03:02:56,715 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:56,715 - ERROR - VM ctest-left_vm-30784849 has failed to come up
2026-01-05 03:02:56,715 - ERROR - Nova failed to get host of the VM
2026-01-05 03:02:56,793 - INFO - Deleting VN service-right-vn-46403964
2026-01-05 03:02:56,869 - DEBUG - VN 48e7d7dc-e60a-4495-b144-50cc7784f78b still in use: Unable to complete operation on network 48e7d7dc-e60a-4495-b144-50cc7784f78b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dae537d3-052e-4577-bbde-e388bd665c7a']
2026-01-05 03:02:56,869 - WARNING - Deleting VN service-right-vn-46403964 failed..Will retry
2026-01-05 03:02:59,088 - DEBUG - Response for deleting network ()
2026-01-05 03:02:59,088 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/aaf3f949-a7e9-40d4-ad61-efedce86b6d7
2026-01-05 03:02:59,094 - DEBUG - Response Code: 404
2026-01-05 03:02:59,094 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:59,104 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:59,166 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:59,271 - INFO - Validated that VN service-right-vn-46403964 is not found in API Server
2026-01-05 03:02:59,284 - DEBUG - VN service-right-vn-46403964 is not present in Agent 10.0.0.57
2026-01-05 03:02:59,295 - DEBUG - VN service-right-vn-46403964 is not present in Agent 10.0.0.56
2026-01-05 03:02:59,295 - INFO - Validated that VN service-right-vn-46403964 is not in any agent
2026-01-05 03:02:59,328 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-46403964 info
2026-01-05 03:02:59,328 - INFO - Deleting VN service-left-vn-43423288
2026-01-05 03:02:59,502 - DEBUG - Response for deleting network ()
2026-01-05 03:02:59,502 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/006ccb38-f960-4de0-8bcb-11a3917bc6b1
2026-01-05 03:02:59,509 - DEBUG - Response Code: 404
2026-01-05 03:02:59,509 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:59,520 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:59,542 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:02:59,657 - INFO - Validated that VN service-left-vn-43423288 is not found in API Server
2026-01-05 03:02:59,669 - DEBUG - VN service-left-vn-43423288 is not present in Agent 10.0.0.57
2026-01-05 03:02:59,682 - DEBUG - VN service-left-vn-43423288 is not present in Agent 10.0.0.56
2026-01-05 03:02:59,683 - INFO - Validated that VN service-left-vn-43423288 is not in any agent
2026-01-05 03:02:59,717 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-43423288 info
2026-01-05 03:02:59,717 - INFO - Deleting VN ctest-mgmt_vn-36475164
2026-01-05 03:02:59,849 - DEBUG - Response for deleting network ()
2026-01-05 03:02:59,849 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/3ac476df-adc4-4097-97af-9267fc3cbf10
2026-01-05 03:02:59,859 - DEBUG - Response Code: 404
2026-01-05 03:02:59,860 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:02:59,876 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:02:59,897 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:00,011 - INFO - Validated that VN ctest-mgmt_vn-36475164 is not found in API Server
2026-01-05 03:03:00,039 - DEBUG - VN ctest-mgmt_vn-36475164 is not present in Agent 10.0.0.57
2026-01-05 03:03:00,056 - DEBUG - VN ctest-mgmt_vn-36475164 is not present in Agent 10.0.0.56
2026-01-05 03:03:00,056 - INFO - Validated that VN ctest-mgmt_vn-36475164 is not in any agent
2026-01-05 03:03:00,103 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-36475164 info
2026-01-05 03:03:01,394 - DEBUG - No XMPP flaps were noticed during the test
2026-01-05 03:03:01,394 - INFO -
2026-01-05 03:03:01,394 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2026-01-05 03:03:01,394 - INFO - --------------------------------------------------------------------------------
2026-01-05 03:03:01,397 - INFO - ================================================================================
2026-01-05 03:03:01,397 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-01-05 03:03:02,673 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.25': '0', '10.20.0.129': '0'}, '10.0.0.56': {'10.20.0.254': '0', '10.20.0.129': '0'}} with
2026-01-05 03:03:02,673 - INFO - Initial checks done. Running the testcase now
2026-01-05 03:03:02,673 - INFO -
2026-01-05 03:03:02,946 - DEBUG - Response for create_network : {'network': {'id': '9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065', 'name': 'ctest-mgmt_vn-68982947', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'project_id': 'd7ee2745edf04722ae02f47d269c5429', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78203014', 'ctest-mgmt_vn-68982947'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:03:03,168 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f4ce9cb6-3185-4b60-b946-49067b9dba70', 'name': '', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'network_id': '9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065', 'ip_version': 4, 'cidr': '165.119.216.64/26', 'allocation_pools': [{'start': '165.119.216.66', 'end': '165.119.216.126'}], 'gateway_ip': '165.119.216.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '165.119.216.66', 'tags': [], 'project_id': 'd7ee2745edf04722ae02f47d269c5429'}}
2026-01-05 03:03:03,189 - INFO - Created VN ctest-mgmt_vn-68982947
2026-01-05 03:03:03,200 - DEBUG - VN ctest-mgmt_vn-68982947 UUID is 9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,279 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:03,285 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:03,296 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:03,380 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,394 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,402 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/39a92abe-719f-435e-8b0a-f86a01b84bf0
2026-01-05 03:03:03,409 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/39a92abe-719f-435e-8b0a-f86a01b84bf0
2026-01-05 03:03:03,416 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/335925a6-e6c8-4eee-a802-0bf216ff2c9b
2026-01-05 03:03:03,422 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:03:03,422 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,430 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/39a92abe-719f-435e-8b0a-f86a01b84bf0
2026-01-05 03:03:03,445 - INFO - Verified VN network id 14 for VN 9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,445 - INFO - Verifications in API Server for VN ctest-mgmt_vn-68982947 passed
2026-01-05 03:03:03,445 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,455 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/39a92abe-719f-435e-8b0a-f86a01b84bf0
2026-01-05 03:03:03,461 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/39a92abe-719f-435e-8b0a-f86a01b84bf0
2026-01-05 03:03:03,468 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/335925a6-e6c8-4eee-a802-0bf216ff2c9b
2026-01-05 03:03:03,481 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11317188849722215338', 'uuid-lslong': '13167383593415041125'}, 'enable': 'true', 'created': '2026-01-05T03:03:02', 'last-modified': '2026-01-05T03:03:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362196'}
2026-01-05 03:03:03,487 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:03:03,492 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11317188849722215338', 'uuid-lslong': '13167383593415041125'}, 'enable': 'true', 'created': '2026-01-05T03:03:02', 'last-modified': '2026-01-05T03:03:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372782'}
2026-01-05 03:03:03,498 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:03:03,504 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11317188849722215338', 'uuid-lslong': '13167383593415041125'}, 'enable': 'true', 'created': '2026-01-05T03:03:02', 'last-modified': '2026-01-05T03:03:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384722'}
2026-01-05 03:03:03,511 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-05 03:03:03,511 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-68982947 passed
2026-01-05 03:03:03,511 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-68982947 in API_Server ======
2026-01-05 03:03:03,511 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:03,518 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:03,528 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:03,626 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9d0ebb4c-3bb4-4baa-b6bb-f19d4acf6065
2026-01-05 03:03:03,636 - DEBUG - =>VN ctest-mgmt_vn-68982947 has no policy to be verified
2026-01-05 03:03:03,636 - DEBUG - Verifying the vn in opserver
2026-01-05 03:03:03,636 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947 virtual network link through opserver 10.0.0.27
2026-01-05 03:03:03,636 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:03:03,647 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947?flat'}
2026-01-05 03:03:03,647 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947 virtual network link through opserver 10.0.0.33
2026-01-05 03:03:03,648 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:03:03,660 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947?flat'}
2026-01-05 03:03:03,660 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947 virtual network link through opserver 10.0.0.23
2026-01-05 03:03:03,660 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:03:03,674 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947?flat'}
2026-01-05 03:03:03,674 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78203014:ctest-mgmt_vn-68982947 is found in opserver
2026-01-05 03:03:03,700 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:03:03,713 - DEBUG - VRF ids for VN ctest-mgmt_vn-68982947: {}
2026-01-05 03:03:04,014 - DEBUG - Response for create_network : {'network': {'id': 'd1ab1630-21a1-46e9-879f-269853cc773a', 'name': 'ctest-left_vn-70253151', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'project_id': 'd7ee2745edf04722ae02f47d269c5429', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78203014', 'ctest-left_vn-70253151'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:03:04,254 - DEBUG - Response for create_subnet : {'subnet': {'id': '3d1e82b2-ef5d-44ff-a17e-07946c5d140c', 'name': '', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'network_id': 'd1ab1630-21a1-46e9-879f-269853cc773a', 'ip_version': 4, 'cidr': '202.167.130.192/26', 'allocation_pools': [{'start': '202.167.130.194', 'end': '202.167.130.254'}], 'gateway_ip': '202.167.130.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '202.167.130.194', 'tags': [], 'project_id': 'd7ee2745edf04722ae02f47d269c5429'}}
2026-01-05 03:03:04,277 - INFO - Created VN ctest-left_vn-70253151
2026-01-05 03:03:04,290 - DEBUG - VN ctest-left_vn-70253151 UUID is d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,365 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:04,371 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:04,381 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:04,489 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,506 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,518 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/a6aeea30-c6dc-4b5b-908f-62eff33f6878
2026-01-05 03:03:04,531 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/a6aeea30-c6dc-4b5b-908f-62eff33f6878
2026-01-05 03:03:04,542 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/b2b49b8f-1dbb-430e-a063-3972df75d123
2026-01-05 03:03:04,552 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:03:04,552 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,562 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/a6aeea30-c6dc-4b5b-908f-62eff33f6878
2026-01-05 03:03:04,585 - INFO - Verified VN network id 15 for VN d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,586 - INFO - Verifications in API Server for VN ctest-left_vn-70253151 passed
2026-01-05 03:03:04,586 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,595 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/a6aeea30-c6dc-4b5b-908f-62eff33f6878
2026-01-05 03:03:04,604 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/a6aeea30-c6dc-4b5b-908f-62eff33f6878
2026-01-05 03:03:04,612 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/b2b49b8f-1dbb-430e-a063-3972df75d123
2026-01-05 03:03:04,629 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15108193770922919657', 'uuid-lslong': '9772572152100058938'}, 'enable': 'true', 'created': '2026-01-05T03:03:03', 'last-modified': '2026-01-05T03:03:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422711'}
2026-01-05 03:03:04,635 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:03:04,641 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15108193770922919657', 'uuid-lslong': '9772572152100058938'}, 'enable': 'true', 'created': '2026-01-05T03:03:03', 'last-modified': '2026-01-05T03:03:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439722'}
2026-01-05 03:03:04,647 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:03:04,652 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15108193770922919657', 'uuid-lslong': '9772572152100058938'}, 'enable': 'true', 'created': '2026-01-05T03:03:03', 'last-modified': '2026-01-05T03:03:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453457'}
2026-01-05 03:03:04,658 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-05 03:03:04,659 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-70253151 passed
2026-01-05 03:03:04,659 - DEBUG - ====Verifying policy data for ctest-left_vn-70253151 in API_Server ======
2026-01-05 03:03:04,659 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:04,665 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:04,676 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:04,766 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d1ab1630-21a1-46e9-879f-269853cc773a
2026-01-05 03:03:04,782 - DEBUG - =>VN ctest-left_vn-70253151 has no policy to be verified
2026-01-05 03:03:04,782 - DEBUG - Verifying the vn in opserver
2026-01-05 03:03:04,782 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151 virtual network link through opserver 10.0.0.27
2026-01-05 03:03:04,782 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:03:04,796 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151?flat'}
2026-01-05 03:03:04,796 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151 virtual network link through opserver 10.0.0.33
2026-01-05 03:03:04,796 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:03:04,810 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151?flat'}
2026-01-05 03:03:04,810 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151 virtual network link through opserver 10.0.0.23
2026-01-05 03:03:04,811 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:03:04,823 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151?flat'}
2026-01-05 03:03:04,823 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78203014:ctest-left_vn-70253151 is found in opserver
2026-01-05 03:03:04,847 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:03:04,858 - DEBUG - VRF ids for VN ctest-left_vn-70253151: {}
2026-01-05 03:03:05,111 - DEBUG - Response for create_network : {'network': {'id': '732efc09-42c3-4909-a074-25f25ecdd8a9', 'name': 'ctest-right_vn-08044561', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'project_id': 'd7ee2745edf04722ae02f47d269c5429', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78203014', 'ctest-right_vn-08044561'], 'port_security_enabled': True, 'description': ''}}
2026-01-05 03:03:05,382 - DEBUG - Response for create_subnet : {'subnet': {'id': '484aed0d-9b4f-481a-af5e-e8cc85fd3e48', 'name': '', 'tenant_id': 'd7ee2745edf04722ae02f47d269c5429', 'network_id': '732efc09-42c3-4909-a074-25f25ecdd8a9', 'ip_version': 4, 'cidr': '160.49.167.192/26', 'allocation_pools': [{'start': '160.49.167.194', 'end': '160.49.167.254'}], 'gateway_ip': '160.49.167.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.49.167.194', 'tags': [], 'project_id': 'd7ee2745edf04722ae02f47d269c5429'}}
2026-01-05 03:03:05,408 - INFO - Created VN ctest-right_vn-08044561
2026-01-05 03:03:05,423 - DEBUG - VN ctest-right_vn-08044561 UUID is 732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,490 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:05,497 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:05,507 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:05,603 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,614 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,622 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ab562d97-3055-4952-bdf6-4109cfb860ec
2026-01-05 03:03:05,630 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ab562d97-3055-4952-bdf6-4109cfb860ec
2026-01-05 03:03:05,638 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/03cb9171-c9e2-4d34-baed-c078330d3cbc
2026-01-05 03:03:05,647 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:03:05,647 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,656 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ab562d97-3055-4952-bdf6-4109cfb860ec
2026-01-05 03:03:05,671 - INFO - Verified VN network id 16 for VN 732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,671 - INFO - Verifications in API Server for VN ctest-right_vn-08044561 passed
2026-01-05 03:03:05,672 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,682 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ab562d97-3055-4952-bdf6-4109cfb860ec
2026-01-05 03:03:05,690 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ab562d97-3055-4952-bdf6-4109cfb860ec
2026-01-05 03:03:05,696 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/03cb9171-c9e2-4d34-baed-c078330d3cbc
2026-01-05 03:03:05,708 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8299848279995402505', 'uuid-lslong': '11561907866269767849'}, 'enable': 'true', 'created': '2026-01-05T03:03:05', 'last-modified': '2026-01-05T03:03:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372989'}
2026-01-05 03:03:05,715 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:03:05,722 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8299848279995402505', 'uuid-lslong': '11561907866269767849'}, 'enable': 'true', 'created': '2026-01-05T03:03:05', 'last-modified': '2026-01-05T03:03:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389119'}
2026-01-05 03:03:05,727 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:03:05,733 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78203014', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8299848279995402505', 'uuid-lslong': '11561907866269767849'}, 'enable': 'true', 'created': '2026-01-05T03:03:05', 'last-modified': '2026-01-05T03:03:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403645'}
2026-01-05 03:03:05,739 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-05 03:03:05,739 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-08044561 passed
2026-01-05 03:03:05,740 - DEBUG - ====Verifying policy data for ctest-right_vn-08044561 in API_Server ======
2026-01-05 03:03:05,740 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:05,746 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:05,756 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:05,851 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/732efc09-42c3-4909-a074-25f25ecdd8a9
2026-01-05 03:03:05,861 - DEBUG - =>VN ctest-right_vn-08044561 has no policy to be verified
2026-01-05 03:03:05,861 - DEBUG - Verifying the vn in opserver
2026-01-05 03:03:05,861 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561 virtual network link through opserver 10.0.0.27
2026-01-05 03:03:05,861 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-05 03:03:05,874 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561?flat'}
2026-01-05 03:03:05,874 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561 virtual network link through opserver 10.0.0.33
2026-01-05 03:03:05,874 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-05 03:03:05,888 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561?flat'}
2026-01-05 03:03:05,888 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561 virtual network link through opserver 10.0.0.23
2026-01-05 03:03:05,889 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-05 03:03:05,903 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561?flat'}
2026-01-05 03:03:05,903 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78203014:ctest-right_vn-08044561 is found in opserver
2026-01-05 03:03:05,924 - DEBUG - Do not have enough data to verify VN in agent
2026-01-05 03:03:05,933 - DEBUG - VRF ids for VN ctest-right_vn-08044561: {}
2026-01-05 03:03:06,958 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-1)
2026-01-05 03:03:08,183 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4961-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4961-2)
2026-01-05 03:03:08,183 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-71622890']
2026-01-05 03:03:08,519 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-71622890'] created succesfully
2026-01-05 03:03:08,875 - DEBUG - Image by name tiny_nat_fw not found
2026-01-05 03:03:08,875 - DEBUG - Installing image tiny_nat_fw
2026-01-05 03:03:08,875 - 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-01-05 03:03:08,875 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:03:08,875 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:03:08,875 - DEBUG - Unzip image
2026-01-05 03:03:08,875 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-05 03:03:08,896 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-05 03:03:08,896 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-71622890']
2026-01-05 03:03:09,014 - INFO - Service template: ['default-domain', 'ctest-service_template_1-71622890'] deleted successfully.
2026-01-05 03:03:09,014 - INFO - Skip interface_detach for VM ctest-right_vm-39698939 in state BUILD
2026-01-05 03:03:09,014 - INFO - Deleting VM ctest-right_vm-39698939
2026-01-05 03:03:09,014 - ERROR - VM ctest-right_vm-39698939 has failed to come up
2026-01-05 03:03:09,014 - ERROR - Nova failed to get host of the VM
2026-01-05 03:03:09,014 - ERROR - VM ctest-right_vm-39698939 has failed to come up
2026-01-05 03:03:09,015 - ERROR - Nova failed to get host of the VM
2026-01-05 03:03:09,142 - INFO - Skip interface_detach for VM ctest-left_vm-59601371 in state BUILD
2026-01-05 03:03:09,142 - INFO - Deleting VM ctest-left_vm-59601371
2026-01-05 03:03:09,142 - ERROR - VM ctest-left_vm-59601371 has failed to come up
2026-01-05 03:03:09,142 - ERROR - Nova failed to get host of the VM
2026-01-05 03:03:09,142 - ERROR - VM ctest-left_vm-59601371 has failed to come up
2026-01-05 03:03:09,142 - ERROR - Nova failed to get host of the VM
2026-01-05 03:03:09,276 - INFO - Deleting VN ctest-right_vn-08044561
2026-01-05 03:03:09,318 - DEBUG - VN 732efc09-42c3-4909-a074-25f25ecdd8a9 still in use: Unable to complete operation on network 732efc09-42c3-4909-a074-25f25ecdd8a9. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b1588389-9d4d-4658-ad13-8c581872b113']
2026-01-05 03:03:09,318 - WARNING - Deleting VN ctest-right_vn-08044561 failed..Will retry
2026-01-05 03:03:11,472 - DEBUG - Response for deleting network ()
2026-01-05 03:03:11,473 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ab562d97-3055-4952-bdf6-4109cfb860ec
2026-01-05 03:03:11,481 - DEBUG - Response Code: 404
2026-01-05 03:03:11,481 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:11,490 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:11,520 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:11,627 - INFO - Validated that VN ctest-right_vn-08044561 is not found in API Server
2026-01-05 03:03:11,640 - DEBUG - VN ctest-right_vn-08044561 is not present in Agent 10.0.0.57
2026-01-05 03:03:11,651 - DEBUG - VN ctest-right_vn-08044561 is not present in Agent 10.0.0.56
2026-01-05 03:03:11,651 - INFO - Validated that VN ctest-right_vn-08044561 is not in any agent
2026-01-05 03:03:11,684 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-08044561 info
2026-01-05 03:03:11,684 - INFO - Deleting VN ctest-left_vn-70253151
2026-01-05 03:03:11,851 - DEBUG - Response for deleting network ()
2026-01-05 03:03:11,851 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/a6aeea30-c6dc-4b5b-908f-62eff33f6878
2026-01-05 03:03:11,858 - DEBUG - Response Code: 404
2026-01-05 03:03:11,858 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:11,869 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:11,880 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:11,989 - INFO - Validated that VN ctest-left_vn-70253151 is not found in API Server
2026-01-05 03:03:11,999 - DEBUG - VN ctest-left_vn-70253151 is not present in Agent 10.0.0.57
2026-01-05 03:03:12,010 - DEBUG - VN ctest-left_vn-70253151 is not present in Agent 10.0.0.56
2026-01-05 03:03:12,010 - INFO - Validated that VN ctest-left_vn-70253151 is not in any agent
2026-01-05 03:03:12,041 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-70253151 info
2026-01-05 03:03:12,041 - INFO - Deleting VN ctest-mgmt_vn-68982947
2026-01-05 03:03:12,212 - DEBUG - Response for deleting network ()
2026-01-05 03:03:12,212 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/39a92abe-719f-435e-8b0a-f86a01b84bf0
2026-01-05 03:03:12,220 - DEBUG - Response Code: 404
2026-01-05 03:03:12,220 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-05 03:03:12,226 - DEBUG - Requesting: http://10.0.0.27:8082/domain/182056ec-3382-44e1-b0fd-9a9d794f24a6
2026-01-05 03:03:12,284 - DEBUG - Requesting: http://10.0.0.27:8082/project/d7ee2745-edf0-4722-ae02-f47d269c5429
2026-01-05 03:03:12,370 - INFO - Validated that VN ctest-mgmt_vn-68982947 is not found in API Server
2026-01-05 03:03:12,381 - DEBUG - VN ctest-mgmt_vn-68982947 is not present in Agent 10.0.0.57
2026-01-05 03:03:12,393 - DEBUG - VN ctest-mgmt_vn-68982947 is not present in Agent 10.0.0.56
2026-01-05 03:03:12,393 - INFO - Validated that VN ctest-mgmt_vn-68982947 is not in any agent
2026-01-05 03:03:12,423 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-68982947 info
2026-01-05 03:03:13,706 - DEBUG - No XMPP flaps were noticed during the test
2026-01-05 03:03:13,707 - INFO -
2026-01-05 03:03:13,707 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2026-01-05 03:03:13,707 - INFO - --------------------------------------------------------------------------------
2026-01-05 03:03:14,424 - INFO - Deleted project: ctest-TestSvcRegr-78203014, ID : d7ee2745-edf0-4722-ae02-f47d269c5429