]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-1)
2025-12-25 03:28:31,052 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-22027847']
2025-12-25 03:28:31,370 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-22027847'] created succesfully
2025-12-25 03:28:31,830 - DEBUG - Image by name tiny_nat_fw not found
2025-12-25 03:28:31,831 - DEBUG - Installing image tiny_nat_fw
2025-12-25 03:28:31,831 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-12-25 03:28:31,831 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-12-25 03:28:31,831 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-12-25 03:28:31,852 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:28:31,925 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:28:31,925 - DEBUG - Unzip image
2025-12-25 03:28:31,925 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:28:31,949 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-25 03:28:31,950 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-22027847']
2025-12-25 03:28:32,044 - INFO - Service template: ['default-domain', 'ctest-service_template_1-22027847'] deleted successfully.
2025-12-25 03:28:32,044 - INFO - Skip interface_detach for VM ctest-right_vm-61028249 in state BUILD
2025-12-25 03:28:32,044 - INFO - Deleting VM ctest-right_vm-61028249
2025-12-25 03:28:32,092 - ERROR - VM ctest-right_vm-61028249 has failed to come up
2025-12-25 03:28:32,093 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:32,093 - ERROR - VM ctest-right_vm-61028249 has failed to come up
2025-12-25 03:28:32,093 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:32,232 - INFO - Skip interface_detach for VM ctest-left_vm-88925303 in state BUILD
2025-12-25 03:28:32,232 - INFO - Deleting VM ctest-left_vm-88925303
2025-12-25 03:28:32,232 - ERROR - VM ctest-left_vm-88925303 has failed to come up
2025-12-25 03:28:32,233 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:32,233 - ERROR - VM ctest-left_vm-88925303 has failed to come up
2025-12-25 03:28:32,233 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:32,334 - INFO - Deleting VN ctest-right_vn-37893662
2025-12-25 03:28:32,390 - DEBUG - VN 17705328-4f6c-47b1-9575-265f07acffe5 still in use: Unable to complete operation on network 17705328-4f6c-47b1-9575-265f07acffe5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0a7f4020-06ad-4132-afc7-bc24bfa2a282']
2025-12-25 03:28:32,390 - WARNING - Deleting VN ctest-right_vn-37893662 failed..Will retry
2025-12-25 03:28:34,589 - DEBUG - Response for deleting network ()
2025-12-25 03:28:34,589 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cf9c592c-9930-49b8-b95f-2fae8c883d86
2025-12-25 03:28:34,597 - DEBUG - Response Code: 404
2025-12-25 03:28:34,597 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:34,615 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:34,643 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:34,743 - INFO - Validated that VN ctest-right_vn-37893662 is not found in API Server
2025-12-25 03:28:34,756 - DEBUG - VN ctest-right_vn-37893662 is not present in Agent 10.0.0.19
2025-12-25 03:28:34,776 - DEBUG - VN ctest-right_vn-37893662 is not present in Agent 10.0.0.130
2025-12-25 03:28:34,776 - INFO - Validated that VN ctest-right_vn-37893662 is not in any agent
2025-12-25 03:28:34,812 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-37893662 info
2025-12-25 03:28:34,813 - INFO - Deleting VN ctest-left_vn-96110404
2025-12-25 03:28:35,008 - DEBUG - Response for deleting network ()
2025-12-25 03:28:35,008 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5d415b87-1c3d-458a-a917-e94ff56a10c6
2025-12-25 03:28:35,020 - DEBUG - Response Code: 404
2025-12-25 03:28:35,020 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:35,030 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:35,088 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:35,198 - INFO - Validated that VN ctest-left_vn-96110404 is not found in API Server
2025-12-25 03:28:35,219 - DEBUG - VN ctest-left_vn-96110404 is not present in Agent 10.0.0.19
2025-12-25 03:28:35,230 - DEBUG - VN ctest-left_vn-96110404 is not present in Agent 10.0.0.130
2025-12-25 03:28:35,230 - INFO - Validated that VN ctest-left_vn-96110404 is not in any agent
2025-12-25 03:28:35,274 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-96110404 info
2025-12-25 03:28:35,274 - INFO - Deleting VN ctest-mgmt_vn-28145452
2025-12-25 03:28:35,504 - DEBUG - Response for deleting network ()
2025-12-25 03:28:35,504 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b2a56192-f2c6-4a22-8aee-6a57e05a5527
2025-12-25 03:28:35,512 - DEBUG - Response Code: 404
2025-12-25 03:28:35,513 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:35,520 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:35,576 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:35,679 - INFO - Validated that VN ctest-mgmt_vn-28145452 is not found in API Server
2025-12-25 03:28:35,699 - DEBUG - VN ctest-mgmt_vn-28145452 is not present in Agent 10.0.0.19
2025-12-25 03:28:35,711 - DEBUG - VN ctest-mgmt_vn-28145452 is not present in Agent 10.0.0.130
2025-12-25 03:28:35,711 - INFO - Validated that VN ctest-mgmt_vn-28145452 is not in any agent
2025-12-25 03:28:35,755 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-28145452 info
2025-12-25 03:28:35,755 - INFO - Deleting VM ctest-vm-in-third-vn-35875496
2025-12-25 03:28:35,842 - INFO - Deleting VN ctest-third-vn-64910001
2025-12-25 03:28:35,887 - DEBUG - VN 97c8ecb9-e33f-4a1c-b2e7-6cfac48fdb5a still in use: Unable to complete operation on network 97c8ecb9-e33f-4a1c-b2e7-6cfac48fdb5a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-518c259b-57d4-42bf-8280-ba18e5ca1c68']
2025-12-25 03:28:35,887 - WARNING - Deleting VN ctest-third-vn-64910001 failed..Will retry
2025-12-25 03:28:38,051 - DEBUG - Response for deleting network ()
2025-12-25 03:28:39,362 - DEBUG - No XMPP flaps were noticed during the test
2025-12-25 03:28:39,363 - INFO -
2025-12-25 03:28:39,363 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:27]
2025-12-25 03:28:39,363 - INFO - --------------------------------------------------------------------------------
2025-12-25 03:28:39,369 - INFO - ================================================================================
2025-12-25 03:28:39,369 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-12-25 03:28:40,667 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.130': {'10.20.0.14': '0', '10.20.0.209': '0'}} with
2025-12-25 03:28:40,667 - INFO - Initial checks done. Running the testcase now
2025-12-25 03:28:40,667 - INFO -
2025-12-25 03:28:41,034 - DEBUG - Response for create_network : {'network': {'id': '029a87e4-13fe-41cf-9795-19eebc2865f4', 'name': 'ctest-mgmt_vn-57347366', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'project_id': '4efb09a02d374f6d9edcc585d57645bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87501716', 'ctest-mgmt_vn-57347366'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:28:41,250 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a9736cd4-2868-4406-9d1a-7781ac9fc550', 'name': '', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'network_id': '029a87e4-13fe-41cf-9795-19eebc2865f4', 'ip_version': 4, 'cidr': '170.130.101.64/26', 'allocation_pools': [{'start': '170.130.101.66', 'end': '170.130.101.126'}], 'gateway_ip': '170.130.101.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '170.130.101.66', 'tags': [], 'project_id': '4efb09a02d374f6d9edcc585d57645bf'}}
2025-12-25 03:28:41,289 - INFO - Created VN ctest-mgmt_vn-57347366
2025-12-25 03:28:41,304 - DEBUG - VN ctest-mgmt_vn-57347366 UUID is 029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,392 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:41,401 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:41,414 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:41,530 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,545 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,558 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6fa67f24-d9d2-4558-9d9a-727d20acef84
2025-12-25 03:28:41,575 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6fa67f24-d9d2-4558-9d9a-727d20acef84
2025-12-25 03:28:41,585 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/2f6b6d0b-92d3-4ead-a76d-04a6e5142964
2025-12-25 03:28:41,597 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:28:41,597 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,609 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6fa67f24-d9d2-4558-9d9a-727d20acef84
2025-12-25 03:28:41,629 - INFO - Verified VN network id 15 for VN 029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,629 - INFO - Verifications in API Server for VN ctest-mgmt_vn-57347366 passed
2025-12-25 03:28:41,629 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,642 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6fa67f24-d9d2-4558-9d9a-727d20acef84
2025-12-25 03:28:41,650 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6fa67f24-d9d2-4558-9d9a-727d20acef84
2025-12-25 03:28:41,659 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/2f6b6d0b-92d3-4ead-a76d-04a6e5142964
2025-12-25 03:28:41,671 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '187611748147020239', 'uuid-lslong': '10922664984406681076'}, 'enable': 'true', 'created': '2025-12-25T03:28:40', 'last-modified': '2025-12-25T03:28:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.465315'}
2025-12-25 03:28:41,678 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:28:41,685 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '187611748147020239', 'uuid-lslong': '10922664984406681076'}, 'enable': 'true', 'created': '2025-12-25T03:28:40', 'last-modified': '2025-12-25T03:28:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481478'}
2025-12-25 03:28:41,691 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:28:41,698 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '187611748147020239', 'uuid-lslong': '10922664984406681076'}, 'enable': 'true', 'created': '2025-12-25T03:28:40', 'last-modified': '2025-12-25T03:28:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497325'}
2025-12-25 03:28:41,705 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:28:41,705 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-57347366 passed
2025-12-25 03:28:41,705 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-57347366 in API_Server ======
2025-12-25 03:28:41,706 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:41,713 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:41,727 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:41,830 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/029a87e4-13fe-41cf-9795-19eebc2865f4
2025-12-25 03:28:41,848 - DEBUG - =>VN ctest-mgmt_vn-57347366 has no policy to be verified
2025-12-25 03:28:41,849 - DEBUG - Verifying the vn in opserver
2025-12-25 03:28:41,849 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366 virtual network link through opserver 10.0.0.27
2025-12-25 03:28:41,849 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:28:41,864 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366?flat'}
2025-12-25 03:28:41,864 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366 virtual network link through opserver 10.0.0.33
2025-12-25 03:28:41,864 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:28:41,877 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366?flat'}
2025-12-25 03:28:41,878 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366 virtual network link through opserver 10.0.0.23
2025-12-25 03:28:41,878 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:28:41,969 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366?flat'}
2025-12-25 03:28:41,969 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87501716:ctest-mgmt_vn-57347366 is found in opserver
2025-12-25 03:28:41,997 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:28:42,008 - DEBUG - VRF ids for VN ctest-mgmt_vn-57347366: {}
2025-12-25 03:28:42,329 - DEBUG - Response for create_network : {'network': {'id': 'f1ac3637-e69f-48e3-9988-2b645eb5c4ba', 'name': 'ctest-left_vn-27457131', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'project_id': '4efb09a02d374f6d9edcc585d57645bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87501716', 'ctest-left_vn-27457131'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:28:42,603 - DEBUG - Response for create_subnet : {'subnet': {'id': 'afc99776-52fa-47f2-8a0f-68ccaaeecf62', 'name': '', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'network_id': 'f1ac3637-e69f-48e3-9988-2b645eb5c4ba', 'ip_version': 4, 'cidr': '91.127.216.192/26', 'allocation_pools': [{'start': '91.127.216.194', 'end': '91.127.216.254'}], 'gateway_ip': '91.127.216.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.127.216.194', 'tags': [], 'project_id': '4efb09a02d374f6d9edcc585d57645bf'}}
2025-12-25 03:28:42,627 - INFO - Created VN ctest-left_vn-27457131
2025-12-25 03:28:42,640 - DEBUG - VN ctest-left_vn-27457131 UUID is f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:42,724 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:42,732 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:42,747 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:42,858 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:42,876 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:42,889 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0942fd6b-3a96-46e3-8f70-7d8a7145f978
2025-12-25 03:28:42,901 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0942fd6b-3a96-46e3-8f70-7d8a7145f978
2025-12-25 03:28:42,910 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/313bd38e-3030-4743-bc37-4d01888ff5cc
2025-12-25 03:28:42,919 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:28:42,919 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:42,930 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0942fd6b-3a96-46e3-8f70-7d8a7145f978
2025-12-25 03:28:42,948 - INFO - Verified VN network id 16 for VN f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:42,948 - INFO - Verifications in API Server for VN ctest-left_vn-27457131 passed
2025-12-25 03:28:42,949 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:42,961 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0942fd6b-3a96-46e3-8f70-7d8a7145f978
2025-12-25 03:28:42,971 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0942fd6b-3a96-46e3-8f70-7d8a7145f978
2025-12-25 03:28:42,980 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/313bd38e-3030-4743-bc37-4d01888ff5cc
2025-12-25 03:28:42,993 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17414353472855165155', 'uuid-lslong': '11063140194721318074'}, 'enable': 'true', 'created': '2025-12-25T03:28:42', 'last-modified': '2025-12-25T03:28:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456040'}
2025-12-25 03:28:43,000 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:28:43,008 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17414353472855165155', 'uuid-lslong': '11063140194721318074'}, 'enable': 'true', 'created': '2025-12-25T03:28:42', 'last-modified': '2025-12-25T03:28:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471059'}
2025-12-25 03:28:43,016 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:28:43,022 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17414353472855165155', 'uuid-lslong': '11063140194721318074'}, 'enable': 'true', 'created': '2025-12-25T03:28:42', 'last-modified': '2025-12-25T03:28:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486326'}
2025-12-25 03:28:43,029 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:28:43,030 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-27457131 passed
2025-12-25 03:28:43,030 - DEBUG - ====Verifying policy data for ctest-left_vn-27457131 in API_Server ======
2025-12-25 03:28:43,030 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:43,036 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:43,051 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:43,149 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f1ac3637-e69f-48e3-9988-2b645eb5c4ba
2025-12-25 03:28:43,161 - DEBUG - =>VN ctest-left_vn-27457131 has no policy to be verified
2025-12-25 03:28:43,161 - DEBUG - Verifying the vn in opserver
2025-12-25 03:28:43,161 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131 virtual network link through opserver 10.0.0.27
2025-12-25 03:28:43,161 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:28:43,174 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131?flat'}
2025-12-25 03:28:43,174 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131 virtual network link through opserver 10.0.0.33
2025-12-25 03:28:43,174 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:28:43,189 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131?flat'}
2025-12-25 03:28:43,189 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131 virtual network link through opserver 10.0.0.23
2025-12-25 03:28:43,189 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:28:43,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131?flat'}
2025-12-25 03:28:43,207 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87501716:ctest-left_vn-27457131 is found in opserver
2025-12-25 03:28:43,232 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:28:43,248 - DEBUG - VRF ids for VN ctest-left_vn-27457131: {}
2025-12-25 03:28:43,548 - DEBUG - Response for create_network : {'network': {'id': 'a2200d28-5bb4-4b13-a768-60aeb23a910a', 'name': 'ctest-right_vn-14330735', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'project_id': '4efb09a02d374f6d9edcc585d57645bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87501716', 'ctest-right_vn-14330735'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:28:43,834 - DEBUG - Response for create_subnet : {'subnet': {'id': '565c131f-fef2-4bbd-b0cd-0232376cd215', 'name': '', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'network_id': 'a2200d28-5bb4-4b13-a768-60aeb23a910a', 'ip_version': 4, 'cidr': '223.154.44.64/26', 'allocation_pools': [{'start': '223.154.44.66', 'end': '223.154.44.126'}], 'gateway_ip': '223.154.44.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '223.154.44.66', 'tags': [], 'project_id': '4efb09a02d374f6d9edcc585d57645bf'}}
2025-12-25 03:28:43,862 - INFO - Created VN ctest-right_vn-14330735
2025-12-25 03:28:43,877 - DEBUG - VN ctest-right_vn-14330735 UUID is a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:43,972 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:43,986 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:44,012 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:44,122 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:44,142 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:44,154 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/88398bfb-91f1-4960-847f-48028515345f
2025-12-25 03:28:44,164 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/88398bfb-91f1-4960-847f-48028515345f
2025-12-25 03:28:44,172 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/8833e56c-5d96-43a3-aebb-88163f3d2df0
2025-12-25 03:28:44,180 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:28:44,180 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:44,194 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/88398bfb-91f1-4960-847f-48028515345f
2025-12-25 03:28:44,215 - INFO - Verified VN network id 17 for VN a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:44,215 - INFO - Verifications in API Server for VN ctest-right_vn-14330735 passed
2025-12-25 03:28:44,215 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:44,227 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/88398bfb-91f1-4960-847f-48028515345f
2025-12-25 03:28:44,237 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/88398bfb-91f1-4960-847f-48028515345f
2025-12-25 03:28:44,246 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/8833e56c-5d96-43a3-aebb-88163f3d2df0
2025-12-25 03:28:44,260 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11682351900387461907', 'uuid-lslong': '12062997905342632202'}, 'enable': 'true', 'created': '2025-12-25T03:28:43', 'last-modified': '2025-12-25T03:28:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.482079'}
2025-12-25 03:28:44,267 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:28:44,273 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11682351900387461907', 'uuid-lslong': '12062997905342632202'}, 'enable': 'true', 'created': '2025-12-25T03:28:43', 'last-modified': '2025-12-25T03:28:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497559'}
2025-12-25 03:28:44,280 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:28:44,286 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11682351900387461907', 'uuid-lslong': '12062997905342632202'}, 'enable': 'true', 'created': '2025-12-25T03:28:43', 'last-modified': '2025-12-25T03:28:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.502094'}
2025-12-25 03:28:44,293 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:28:44,293 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-14330735 passed
2025-12-25 03:28:44,293 - DEBUG - ====Verifying policy data for ctest-right_vn-14330735 in API_Server ======
2025-12-25 03:28:44,293 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:44,301 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:44,315 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:44,419 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a2200d28-5bb4-4b13-a768-60aeb23a910a
2025-12-25 03:28:44,437 - DEBUG - =>VN ctest-right_vn-14330735 has no policy to be verified
2025-12-25 03:28:44,438 - DEBUG - Verifying the vn in opserver
2025-12-25 03:28:44,438 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735 virtual network link through opserver 10.0.0.27
2025-12-25 03:28:44,438 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:28:44,452 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735?flat'}
2025-12-25 03:28:44,452 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735 virtual network link through opserver 10.0.0.33
2025-12-25 03:28:44,452 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:28:44,469 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735?flat'}
2025-12-25 03:28:44,469 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735 virtual network link through opserver 10.0.0.23
2025-12-25 03:28:44,470 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:28:44,482 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735?flat'}
2025-12-25 03:28:44,483 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87501716:ctest-right_vn-14330735 is found in opserver
2025-12-25 03:28:44,506 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:28:44,517 - DEBUG - VRF ids for VN ctest-right_vn-14330735: {}
2025-12-25 03:28:44,836 - DEBUG - Response for create_network : {'network': {'id': '8a10b708-9e43-4be9-9bef-52f92e484d2a', 'name': 'ctest-trans_left_vn-20037259', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'project_id': '4efb09a02d374f6d9edcc585d57645bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87501716', 'ctest-trans_left_vn-20037259'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:28:45,049 - DEBUG - Response for create_subnet : {'subnet': {'id': '218d61a7-c09b-4302-b2ca-9cb5e5ba5c75', 'name': '', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'network_id': '8a10b708-9e43-4be9-9bef-52f92e484d2a', 'ip_version': 4, 'cidr': '120.81.128.0/26', 'allocation_pools': [{'start': '120.81.128.2', 'end': '120.81.128.62'}], 'gateway_ip': '120.81.128.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.81.128.2', 'tags': [], 'project_id': '4efb09a02d374f6d9edcc585d57645bf'}}
2025-12-25 03:28:45,080 - INFO - Created VN ctest-trans_left_vn-20037259
2025-12-25 03:28:45,092 - DEBUG - VN ctest-trans_left_vn-20037259 UUID is 8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,182 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:45,190 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:45,204 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:45,307 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,324 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,340 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63f0bc67-f514-4655-a349-00eef183e53c
2025-12-25 03:28:45,354 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63f0bc67-f514-4655-a349-00eef183e53c
2025-12-25 03:28:45,366 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/457b7f10-2338-4027-bd2f-5a689b260d30
2025-12-25 03:28:45,376 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-25 03:28:45,376 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,388 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63f0bc67-f514-4655-a349-00eef183e53c
2025-12-25 03:28:45,411 - INFO - Verified VN network id 18 for VN 8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,412 - INFO - Verifications in API Server for VN ctest-trans_left_vn-20037259 passed
2025-12-25 03:28:45,412 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,430 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63f0bc67-f514-4655-a349-00eef183e53c
2025-12-25 03:28:45,442 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63f0bc67-f514-4655-a349-00eef183e53c
2025-12-25 03:28:45,453 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/457b7f10-2338-4027-bd2f-5a689b260d30
2025-12-25 03:28:45,467 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9948652824504257513', 'uuid-lslong': '11236290825489501482'}, 'enable': 'true', 'created': '2025-12-25T03:28:44', 'last-modified': '2025-12-25T03:28:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.472620'}
2025-12-25 03:28:45,473 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-25 03:28:45,479 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9948652824504257513', 'uuid-lslong': '11236290825489501482'}, 'enable': 'true', 'created': '2025-12-25T03:28:44', 'last-modified': '2025-12-25T03:28:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484016'}
2025-12-25 03:28:45,486 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-25 03:28:45,492 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9948652824504257513', 'uuid-lslong': '11236290825489501482'}, 'enable': 'true', 'created': '2025-12-25T03:28:44', 'last-modified': '2025-12-25T03:28:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497099'}
2025-12-25 03:28:45,499 - DEBUG - Route Targets: ['target:64512:8000008']
2025-12-25 03:28:45,500 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-20037259 passed
2025-12-25 03:28:45,500 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-20037259 in API_Server ======
2025-12-25 03:28:45,500 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:45,507 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:45,522 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:45,615 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8a10b708-9e43-4be9-9bef-52f92e484d2a
2025-12-25 03:28:45,630 - DEBUG - =>VN ctest-trans_left_vn-20037259 has no policy to be verified
2025-12-25 03:28:45,630 - DEBUG - Verifying the vn in opserver
2025-12-25 03:28:45,630 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259 virtual network link through opserver 10.0.0.27
2025-12-25 03:28:45,630 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:28:45,647 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259?flat'}
2025-12-25 03:28:45,647 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259 virtual network link through opserver 10.0.0.33
2025-12-25 03:28:45,647 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:28:45,661 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259?flat'}
2025-12-25 03:28:45,661 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259 virtual network link through opserver 10.0.0.23
2025-12-25 03:28:45,661 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:28:45,676 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259?flat'}
2025-12-25 03:28:45,676 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87501716:ctest-trans_left_vn-20037259 is found in opserver
2025-12-25 03:28:45,703 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:28:45,715 - DEBUG - VRF ids for VN ctest-trans_left_vn-20037259: {}
2025-12-25 03:28:46,042 - DEBUG - Response for create_network : {'network': {'id': '8dbe4f10-9993-45f9-9235-00c145103737', 'name': 'ctest-trans_right_vn-82431757', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'project_id': '4efb09a02d374f6d9edcc585d57645bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87501716', 'ctest-trans_right_vn-82431757'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:28:46,325 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ce1df68c-5898-41cd-b992-9be2fdaab345', 'name': '', 'tenant_id': '4efb09a02d374f6d9edcc585d57645bf', 'network_id': '8dbe4f10-9993-45f9-9235-00c145103737', 'ip_version': 4, 'cidr': '61.176.23.128/26', 'allocation_pools': [{'start': '61.176.23.130', 'end': '61.176.23.190'}], 'gateway_ip': '61.176.23.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '61.176.23.130', 'tags': [], 'project_id': '4efb09a02d374f6d9edcc585d57645bf'}}
2025-12-25 03:28:46,360 - INFO - Created VN ctest-trans_right_vn-82431757
2025-12-25 03:28:46,375 - DEBUG - VN ctest-trans_right_vn-82431757 UUID is 8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,465 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:46,472 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:46,488 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:46,601 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,614 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,625 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f92e347a-c57b-4360-ac16-95a476daba71
2025-12-25 03:28:46,637 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f92e347a-c57b-4360-ac16-95a476daba71
2025-12-25 03:28:46,646 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/2d636527-15e5-4b3d-a729-1f72ca2a40ed
2025-12-25 03:28:46,656 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-25 03:28:46,656 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,671 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f92e347a-c57b-4360-ac16-95a476daba71
2025-12-25 03:28:46,693 - INFO - Verified VN network id 19 for VN 8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,694 - INFO - Verifications in API Server for VN ctest-trans_right_vn-82431757 passed
2025-12-25 03:28:46,694 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,705 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f92e347a-c57b-4360-ac16-95a476daba71
2025-12-25 03:28:46,714 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f92e347a-c57b-4360-ac16-95a476daba71
2025-12-25 03:28:46,723 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/2d636527-15e5-4b3d-a729-1f72ca2a40ed
2025-12-25 03:28:46,737 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10213687937637500409', 'uuid-lslong': '10535327733390522167'}, 'enable': 'true', 'created': '2025-12-25T03:28:45', 'last-modified': '2025-12-25T03:28:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.457238'}
2025-12-25 03:28:46,744 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-25 03:28:46,750 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10213687937637500409', 'uuid-lslong': '10535327733390522167'}, 'enable': 'true', 'created': '2025-12-25T03:28:45', 'last-modified': '2025-12-25T03:28:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.478150'}
2025-12-25 03:28:46,770 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-25 03:28:46,776 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87501716', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10213687937637500409', 'uuid-lslong': '10535327733390522167'}, 'enable': 'true', 'created': '2025-12-25T03:28:45', 'last-modified': '2025-12-25T03:28:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.504848'}
2025-12-25 03:28:46,783 - DEBUG - Route Targets: ['target:64512:8000009']
2025-12-25 03:28:46,784 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-82431757 passed
2025-12-25 03:28:46,784 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-82431757 in API_Server ======
2025-12-25 03:28:46,784 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:46,793 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:46,807 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:46,922 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8dbe4f10-9993-45f9-9235-00c145103737
2025-12-25 03:28:46,935 - DEBUG - =>VN ctest-trans_right_vn-82431757 has no policy to be verified
2025-12-25 03:28:46,935 - DEBUG - Verifying the vn in opserver
2025-12-25 03:28:46,935 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757 virtual network link through opserver 10.0.0.27
2025-12-25 03:28:46,935 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:28:46,950 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757?flat'}
2025-12-25 03:28:46,950 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757 virtual network link through opserver 10.0.0.33
2025-12-25 03:28:46,950 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:28:46,964 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757?flat'}
2025-12-25 03:28:46,965 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757 virtual network link through opserver 10.0.0.23
2025-12-25 03:28:46,965 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:28:46,982 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757?flat'}
2025-12-25 03:28:46,982 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87501716:ctest-trans_right_vn-82431757 is found in opserver
2025-12-25 03:28:47,006 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:28:47,018 - DEBUG - VRF ids for VN ctest-trans_right_vn-82431757: {}
2025-12-25 03:28:48,323 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-1)
2025-12-25 03:28:49,863 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-2)
2025-12-25 03:28:49,864 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-36944567']
2025-12-25 03:28:50,190 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-36944567'] created succesfully
2025-12-25 03:28:50,636 - DEBUG - Image by name tiny_trans_fw not found
2025-12-25 03:28:50,636 - DEBUG - Installing image tiny_trans_fw
2025-12-25 03:28:50,636 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-12-25 03:28:50,636 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-12-25 03:28:50,637 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-12-25 03:28:50,657 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-25 03:28:50,690 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-25 03:28:50,690 - DEBUG - Unzip image
2025-12-25 03:28:50,690 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-12-25 03:28:50,715 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-12-25 03:28:50,721 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-36944567']
2025-12-25 03:28:50,836 - INFO - Service template: ['default-domain', 'ctest-service_template_1-36944567'] deleted successfully.
2025-12-25 03:28:50,836 - INFO - Skip interface_detach for VM ctest-right_vm-65862482 in state BUILD
2025-12-25 03:28:50,836 - INFO - Deleting VM ctest-right_vm-65862482
2025-12-25 03:28:50,837 - ERROR - VM ctest-right_vm-65862482 has failed to come up
2025-12-25 03:28:50,837 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:50,837 - ERROR - VM ctest-right_vm-65862482 has failed to come up
2025-12-25 03:28:50,837 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:50,927 - INFO - Skip interface_detach for VM ctest-left_vm-75996270 in state BUILD
2025-12-25 03:28:50,927 - INFO - Deleting VM ctest-left_vm-75996270
2025-12-25 03:28:50,927 - ERROR - VM ctest-left_vm-75996270 has failed to come up
2025-12-25 03:28:50,927 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:50,927 - ERROR - VM ctest-left_vm-75996270 has failed to come up
2025-12-25 03:28:50,928 - ERROR - Nova failed to get host of the VM
2025-12-25 03:28:51,012 - INFO - Deleting VN ctest-trans_right_vn-82431757
2025-12-25 03:28:51,163 - DEBUG - Response for deleting network ()
2025-12-25 03:28:51,163 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f92e347a-c57b-4360-ac16-95a476daba71
2025-12-25 03:28:51,170 - DEBUG - Response Code: 404
2025-12-25 03:28:51,170 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:51,179 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:51,209 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:51,321 - INFO - Validated that VN ctest-trans_right_vn-82431757 is not found in API Server
2025-12-25 03:28:51,334 - DEBUG - VN ctest-trans_right_vn-82431757 is not present in Agent 10.0.0.19
2025-12-25 03:28:51,347 - DEBUG - VN ctest-trans_right_vn-82431757 is not present in Agent 10.0.0.130
2025-12-25 03:28:51,347 - INFO - Validated that VN ctest-trans_right_vn-82431757 is not in any agent
2025-12-25 03:28:51,385 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-82431757 info
2025-12-25 03:28:51,385 - INFO - Deleting VN ctest-trans_left_vn-20037259
2025-12-25 03:28:51,554 - DEBUG - Response for deleting network ()
2025-12-25 03:28:51,554 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63f0bc67-f514-4655-a349-00eef183e53c
2025-12-25 03:28:51,562 - DEBUG - Response Code: 404
2025-12-25 03:28:51,562 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:51,578 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:51,592 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:51,723 - INFO - Validated that VN ctest-trans_left_vn-20037259 is not found in API Server
2025-12-25 03:28:51,735 - DEBUG - VN ctest-trans_left_vn-20037259 is not present in Agent 10.0.0.19
2025-12-25 03:28:51,746 - DEBUG - VN ctest-trans_left_vn-20037259 is not present in Agent 10.0.0.130
2025-12-25 03:28:51,746 - INFO - Validated that VN ctest-trans_left_vn-20037259 is not in any agent
2025-12-25 03:28:51,808 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-20037259 info
2025-12-25 03:28:51,808 - INFO - Deleting VN ctest-right_vn-14330735
2025-12-25 03:28:51,865 - DEBUG - VN a2200d28-5bb4-4b13-a768-60aeb23a910a still in use: Unable to complete operation on network a2200d28-5bb4-4b13-a768-60aeb23a910a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-75facce7-0467-4da5-894a-8a9cc6cbf8e4']
2025-12-25 03:28:51,865 - WARNING - Deleting VN ctest-right_vn-14330735 failed..Will retry
2025-12-25 03:28:54,030 - DEBUG - Response for deleting network ()
2025-12-25 03:28:54,030 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/88398bfb-91f1-4960-847f-48028515345f
2025-12-25 03:28:54,038 - DEBUG - Response Code: 404
2025-12-25 03:28:54,038 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:54,045 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:54,067 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:54,153 - INFO - Validated that VN ctest-right_vn-14330735 is not found in API Server
2025-12-25 03:28:54,165 - DEBUG - VN ctest-right_vn-14330735 is not present in Agent 10.0.0.19
2025-12-25 03:28:54,178 - DEBUG - VN ctest-right_vn-14330735 is not present in Agent 10.0.0.130
2025-12-25 03:28:54,178 - INFO - Validated that VN ctest-right_vn-14330735 is not in any agent
2025-12-25 03:28:54,222 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-14330735 info
2025-12-25 03:28:54,222 - INFO - Deleting VN ctest-left_vn-27457131
2025-12-25 03:28:54,409 - DEBUG - Response for deleting network ()
2025-12-25 03:28:54,410 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0942fd6b-3a96-46e3-8f70-7d8a7145f978
2025-12-25 03:28:54,416 - DEBUG - Response Code: 404
2025-12-25 03:28:54,417 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:54,436 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:54,451 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:54,544 - INFO - Validated that VN ctest-left_vn-27457131 is not found in API Server
2025-12-25 03:28:54,560 - DEBUG - VN ctest-left_vn-27457131 is not present in Agent 10.0.0.19
2025-12-25 03:28:54,573 - DEBUG - VN ctest-left_vn-27457131 is not present in Agent 10.0.0.130
2025-12-25 03:28:54,573 - INFO - Validated that VN ctest-left_vn-27457131 is not in any agent
2025-12-25 03:28:54,612 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-27457131 info
2025-12-25 03:28:54,613 - INFO - Deleting VN ctest-mgmt_vn-57347366
2025-12-25 03:28:54,802 - DEBUG - Response for deleting network ()
2025-12-25 03:28:54,802 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6fa67f24-d9d2-4558-9d9a-727d20acef84
2025-12-25 03:28:54,809 - DEBUG - Response Code: 404
2025-12-25 03:28:54,809 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:28:54,826 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:28:54,846 - DEBUG - Requesting: http://10.0.0.27:8082/project/4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:54,944 - INFO - Validated that VN ctest-mgmt_vn-57347366 is not found in API Server
2025-12-25 03:28:54,960 - DEBUG - VN ctest-mgmt_vn-57347366 is not present in Agent 10.0.0.19
2025-12-25 03:28:54,972 - DEBUG - VN ctest-mgmt_vn-57347366 is not present in Agent 10.0.0.130
2025-12-25 03:28:54,973 - INFO - Validated that VN ctest-mgmt_vn-57347366 is not in any agent
2025-12-25 03:28:55,010 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-57347366 info
2025-12-25 03:28:56,288 - DEBUG - No XMPP flaps were noticed during the test
2025-12-25 03:28:56,288 - INFO -
2025-12-25 03:28:56,288 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2025-12-25 03:28:56,288 - INFO - --------------------------------------------------------------------------------
2025-12-25 03:28:57,117 - INFO - Deleted project: ctest-TestSvcRegr-87501716, ID : 4efb09a0-2d37-4f6d-9edc-c585d57645bf
2025-12-25 03:28:57,363 - INFO - Domain Default found not creating
2025-12-25 03:28:57,610 - INFO - Project ctest-TestSvcRegr-34564705 not found, creating it
2025-12-25 03:28:58,223 - INFO - Created Project:ctest-TestSvcRegr-34564705, ID : 3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:00,175 - INFO - ================================================================================
2025-12-25 03:29:00,175 - INFO - STARTING TEST : test_svc_CEM_22032
2025-12-25 03:29:01,498 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.130': {'10.20.0.14': '0', '10.20.0.209': '0'}} with
2025-12-25 03:29:01,498 - INFO - Initial checks done. Running the testcase now
2025-12-25 03:29:01,498 - INFO -
2025-12-25 03:29:02,350 - DEBUG - Response for create_network : {'network': {'id': '2d6e6a91-fe63-424f-918c-1d17ef2fa1bb', 'name': 'ctest-mgmt_vn-09579252', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'project_id': '3181eea2639c4681965654f68b57e4e4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34564705', 'ctest-mgmt_vn-09579252'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:29:02,767 - DEBUG - Response for create_subnet : {'subnet': {'id': '94924d9b-57f8-4c05-8591-751b96bc5c51', 'name': '', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'network_id': '2d6e6a91-fe63-424f-918c-1d17ef2fa1bb', 'ip_version': 4, 'cidr': '126.48.244.128/26', 'allocation_pools': [{'start': '126.48.244.130', 'end': '126.48.244.190'}], 'gateway_ip': '126.48.244.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '126.48.244.130', 'tags': [], 'project_id': '3181eea2639c4681965654f68b57e4e4'}}
2025-12-25 03:29:02,802 - INFO - Created VN ctest-mgmt_vn-09579252
2025-12-25 03:29:02,816 - DEBUG - VN ctest-mgmt_vn-09579252 UUID is 2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:02,908 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:03,276 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:03,332 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:03,355 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:03,472 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:03,504 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:03,518 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d02d0e5e-7e82-4bbc-8201-50ca0559cd68
2025-12-25 03:29:03,529 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d02d0e5e-7e82-4bbc-8201-50ca0559cd68
2025-12-25 03:29:03,539 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/bd63ff4f-28bf-48a6-830a-ad19b88e1cbf
2025-12-25 03:29:03,549 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:03,549 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:03,565 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d02d0e5e-7e82-4bbc-8201-50ca0559cd68
2025-12-25 03:29:03,589 - INFO - Verified VN network id 15 for VN 2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:03,591 - INFO - Verifications in API Server for VN ctest-mgmt_vn-09579252 passed
2025-12-25 03:29:03,592 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:03,606 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d02d0e5e-7e82-4bbc-8201-50ca0559cd68
2025-12-25 03:29:03,619 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d02d0e5e-7e82-4bbc-8201-50ca0559cd68
2025-12-25 03:29:03,627 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/bd63ff4f-28bf-48a6-830a-ad19b88e1cbf
2025-12-25 03:29:03,640 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3273671154415649359', 'uuid-lslong': '10487789620873372091'}, 'enable': 'true', 'created': '2025-12-25T03:29:02', 'last-modified': '2025-12-25T03:29:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.983627'}
2025-12-25 03:29:03,652 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:03,658 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3273671154415649359', 'uuid-lslong': '10487789620873372091'}, 'enable': 'true', 'created': '2025-12-25T03:29:02', 'last-modified': '2025-12-25T03:29:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.010148'}
2025-12-25 03:29:03,665 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:03,673 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3273671154415649359', 'uuid-lslong': '10487789620873372091'}, 'enable': 'true', 'created': '2025-12-25T03:29:02', 'last-modified': '2025-12-25T03:29:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.024786'}
2025-12-25 03:29:03,680 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:03,680 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-09579252 passed
2025-12-25 03:29:03,680 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-09579252 in API_Server ======
2025-12-25 03:29:03,680 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:03,692 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:03,708 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:03,815 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/2d6e6a91-fe63-424f-918c-1d17ef2fa1bb
2025-12-25 03:29:03,834 - DEBUG - =>VN ctest-mgmt_vn-09579252 has no policy to be verified
2025-12-25 03:29:03,834 - DEBUG - Verifying the vn in opserver
2025-12-25 03:29:03,834 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252 virtual network link through opserver 10.0.0.27
2025-12-25 03:29:03,834 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:04,230 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:04,376 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252?flat'}
2025-12-25 03:29:04,377 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252 virtual network link through opserver 10.0.0.33
2025-12-25 03:29:04,377 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:04,837 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:04,923 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252?flat'}
2025-12-25 03:29:04,923 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252 virtual network link through opserver 10.0.0.23
2025-12-25 03:29:04,923 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:05,279 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:05,366 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252?flat'}
2025-12-25 03:29:05,366 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-09579252 is found in opserver
2025-12-25 03:29:05,387 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:29:05,398 - DEBUG - VRF ids for VN ctest-mgmt_vn-09579252: {}
2025-12-25 03:29:05,737 - DEBUG - Response for create_network : {'network': {'id': 'ac2af501-a307-475b-86b3-b8bf03a99211', 'name': 'service-left-vn-98310634', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'project_id': '3181eea2639c4681965654f68b57e4e4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34564705', 'service-left-vn-98310634'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:29:05,993 - DEBUG - Response for create_subnet : {'subnet': {'id': '780f12be-77b0-432c-93cc-a8d6e808a2a6', 'name': '', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'network_id': 'ac2af501-a307-475b-86b3-b8bf03a99211', 'ip_version': 4, 'cidr': '85.185.181.128/26', 'allocation_pools': [{'start': '85.185.181.130', 'end': '85.185.181.190'}], 'gateway_ip': '85.185.181.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '85.185.181.130', 'tags': [], 'project_id': '3181eea2639c4681965654f68b57e4e4'}}
2025-12-25 03:29:06,026 - INFO - Created VN service-left-vn-98310634
2025-12-25 03:29:06,040 - DEBUG - VN service-left-vn-98310634 UUID is ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,126 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:06,133 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:06,147 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:06,265 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,279 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,290 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/87065afc-d424-44a3-afa1-31315ffad7a2
2025-12-25 03:29:06,301 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/87065afc-d424-44a3-afa1-31315ffad7a2
2025-12-25 03:29:06,310 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/ce33481e-ad05-4660-a3e5-f508fd7db885
2025-12-25 03:29:06,320 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:06,320 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,331 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/87065afc-d424-44a3-afa1-31315ffad7a2
2025-12-25 03:29:06,354 - INFO - Verified VN network id 16 for VN ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,354 - INFO - Verifications in API Server for VN service-left-vn-98310634 passed
2025-12-25 03:29:06,354 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,366 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/87065afc-d424-44a3-afa1-31315ffad7a2
2025-12-25 03:29:06,377 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/87065afc-d424-44a3-afa1-31315ffad7a2
2025-12-25 03:29:06,386 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/ce33481e-ad05-4660-a3e5-f508fd7db885
2025-12-25 03:29:06,400 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12405997510924388187', 'uuid-lslong': '9706304752453259793'}, 'enable': 'true', 'created': '2025-12-25T03:29:05', 'last-modified': '2025-12-25T03:29:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.460264'}
2025-12-25 03:29:06,409 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:06,415 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12405997510924388187', 'uuid-lslong': '9706304752453259793'}, 'enable': 'true', 'created': '2025-12-25T03:29:05', 'last-modified': '2025-12-25T03:29:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.479659'}
2025-12-25 03:29:06,422 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:06,429 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12405997510924388187', 'uuid-lslong': '9706304752453259793'}, 'enable': 'true', 'created': '2025-12-25T03:29:05', 'last-modified': '2025-12-25T03:29:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.495524'}
2025-12-25 03:29:06,437 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:06,437 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-98310634 passed
2025-12-25 03:29:06,437 - DEBUG - ====Verifying policy data for service-left-vn-98310634 in API_Server ======
2025-12-25 03:29:06,437 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:06,446 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:06,459 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:06,568 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ac2af501-a307-475b-86b3-b8bf03a99211
2025-12-25 03:29:06,580 - DEBUG - =>VN service-left-vn-98310634 has no policy to be verified
2025-12-25 03:29:06,580 - DEBUG - Verifying the vn in opserver
2025-12-25 03:29:06,580 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634 virtual network link through opserver 10.0.0.27
2025-12-25 03:29:06,580 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:06,683 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634?flat'}
2025-12-25 03:29:06,683 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634 virtual network link through opserver 10.0.0.33
2025-12-25 03:29:06,683 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:06,776 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634?flat'}
2025-12-25 03:29:06,776 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634 virtual network link through opserver 10.0.0.23
2025-12-25 03:29:06,776 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:06,869 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634?flat'}
2025-12-25 03:29:06,869 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34564705:service-left-vn-98310634 is found in opserver
2025-12-25 03:29:06,893 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:29:06,904 - DEBUG - VRF ids for VN service-left-vn-98310634: {}
2025-12-25 03:29:07,213 - DEBUG - Response for create_network : {'network': {'id': 'b542ec83-0089-4a3e-957e-486f764af1cc', 'name': 'service-right-vn-17025809', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'project_id': '3181eea2639c4681965654f68b57e4e4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34564705', 'service-right-vn-17025809'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:29:07,462 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f4b7ce1b-ae74-436c-b7a1-ad73935794e0', 'name': '', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'network_id': 'b542ec83-0089-4a3e-957e-486f764af1cc', 'ip_version': 4, 'cidr': '163.48.227.0/26', 'allocation_pools': [{'start': '163.48.227.2', 'end': '163.48.227.62'}], 'gateway_ip': '163.48.227.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '163.48.227.2', 'tags': [], 'project_id': '3181eea2639c4681965654f68b57e4e4'}}
2025-12-25 03:29:07,488 - INFO - Created VN service-right-vn-17025809
2025-12-25 03:29:07,502 - DEBUG - VN service-right-vn-17025809 UUID is b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:07,589 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:07,596 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:07,610 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:07,713 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:07,732 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:07,744 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c87d4a8d-4663-4893-82ec-91b551868854
2025-12-25 03:29:07,753 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c87d4a8d-4663-4893-82ec-91b551868854
2025-12-25 03:29:07,763 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/1fca6281-ca01-43dd-bcd9-6913c3bce2b8
2025-12-25 03:29:07,773 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:07,773 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:07,785 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c87d4a8d-4663-4893-82ec-91b551868854
2025-12-25 03:29:07,807 - INFO - Verified VN network id 17 for VN b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:07,807 - INFO - Verifications in API Server for VN service-right-vn-17025809 passed
2025-12-25 03:29:07,807 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:07,825 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c87d4a8d-4663-4893-82ec-91b551868854
2025-12-25 03:29:07,834 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c87d4a8d-4663-4893-82ec-91b551868854
2025-12-25 03:29:07,844 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/1fca6281-ca01-43dd-bcd9-6913c3bce2b8
2025-12-25 03:29:07,861 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13061261916721728062', 'uuid-lslong': '10772127002279997900'}, 'enable': 'true', 'created': '2025-12-25T03:29:07', 'last-modified': '2025-12-25T03:29:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449934'}
2025-12-25 03:29:07,874 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:07,880 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13061261916721728062', 'uuid-lslong': '10772127002279997900'}, 'enable': 'true', 'created': '2025-12-25T03:29:07', 'last-modified': '2025-12-25T03:29:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.474876'}
2025-12-25 03:29:07,888 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:07,895 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13061261916721728062', 'uuid-lslong': '10772127002279997900'}, 'enable': 'true', 'created': '2025-12-25T03:29:07', 'last-modified': '2025-12-25T03:29:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486274'}
2025-12-25 03:29:07,902 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:07,902 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-17025809 passed
2025-12-25 03:29:07,902 - DEBUG - ====Verifying policy data for service-right-vn-17025809 in API_Server ======
2025-12-25 03:29:07,902 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:07,910 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:07,928 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:08,041 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b542ec83-0089-4a3e-957e-486f764af1cc
2025-12-25 03:29:08,055 - DEBUG - =>VN service-right-vn-17025809 has no policy to be verified
2025-12-25 03:29:08,055 - DEBUG - Verifying the vn in opserver
2025-12-25 03:29:08,055 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809 virtual network link through opserver 10.0.0.27
2025-12-25 03:29:08,055 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:08,139 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809?flat'}
2025-12-25 03:29:08,139 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809 virtual network link through opserver 10.0.0.33
2025-12-25 03:29:08,139 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:08,226 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809?flat'}
2025-12-25 03:29:08,226 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809 virtual network link through opserver 10.0.0.23
2025-12-25 03:29:08,226 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:08,299 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809?flat'}
2025-12-25 03:29:08,299 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34564705:service-right-vn-17025809 is found in opserver
2025-12-25 03:29:08,333 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:29:08,350 - DEBUG - VRF ids for VN service-right-vn-17025809: {}
2025-12-25 03:29:08,429 - DEBUG - Services list from nova: [, , , ]
2025-12-25 03:29:09,704 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-1)
2025-12-25 03:29:11,365 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-2)
2025-12-25 03:29:11,366 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-50306591']
2025-12-25 03:29:11,672 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-50306591'] created succesfully
2025-12-25 03:29:12,191 - DEBUG - Image by name tiny_nat_fw not found
2025-12-25 03:29:12,191 - DEBUG - Installing image tiny_nat_fw
2025-12-25 03:29:12,192 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-12-25 03:29:12,192 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:29:12,192 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:29:12,192 - DEBUG - Unzip image
2025-12-25 03:29:12,192 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:29:12,213 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-25 03:29:12,214 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-50306591']
2025-12-25 03:29:12,335 - INFO - Service template: ['default-domain', 'ctest-service_template_1-50306591'] deleted successfully.
2025-12-25 03:29:12,335 - INFO - Skip interface_detach for VM ctest-right_vm-18133854 in state BUILD
2025-12-25 03:29:12,335 - INFO - Deleting VM ctest-right_vm-18133854
2025-12-25 03:29:12,452 - ERROR - VM ctest-right_vm-18133854 has failed to come up
2025-12-25 03:29:12,452 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:12,452 - ERROR - VM ctest-right_vm-18133854 has failed to come up
2025-12-25 03:29:12,452 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:12,548 - INFO - Skip interface_detach for VM ctest-left_vm-55096714 in state BUILD
2025-12-25 03:29:12,549 - INFO - Deleting VM ctest-left_vm-55096714
2025-12-25 03:29:12,550 - ERROR - VM ctest-left_vm-55096714 has failed to come up
2025-12-25 03:29:12,550 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:12,550 - ERROR - VM ctest-left_vm-55096714 has failed to come up
2025-12-25 03:29:12,550 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:12,648 - INFO - Deleting VN service-right-vn-17025809
2025-12-25 03:29:12,702 - DEBUG - VN b542ec83-0089-4a3e-957e-486f764af1cc still in use: Unable to complete operation on network b542ec83-0089-4a3e-957e-486f764af1cc. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-23c50bbc-a703-417e-8a78-f80cdc9a64b7']
2025-12-25 03:29:12,702 - WARNING - Deleting VN service-right-vn-17025809 failed..Will retry
2025-12-25 03:29:14,971 - DEBUG - Response for deleting network ()
2025-12-25 03:29:14,971 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/c87d4a8d-4663-4893-82ec-91b551868854
2025-12-25 03:29:14,988 - DEBUG - Response Code: 404
2025-12-25 03:29:14,988 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:14,999 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:15,018 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:15,120 - INFO - Validated that VN service-right-vn-17025809 is not found in API Server
2025-12-25 03:29:15,137 - DEBUG - VN service-right-vn-17025809 is not present in Agent 10.0.0.19
2025-12-25 03:29:15,152 - DEBUG - VN service-right-vn-17025809 is not present in Agent 10.0.0.130
2025-12-25 03:29:15,152 - INFO - Validated that VN service-right-vn-17025809 is not in any agent
2025-12-25 03:29:15,197 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-17025809 info
2025-12-25 03:29:15,198 - INFO - Deleting VN service-left-vn-98310634
2025-12-25 03:29:15,469 - DEBUG - Response for deleting network ()
2025-12-25 03:29:15,469 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/87065afc-d424-44a3-afa1-31315ffad7a2
2025-12-25 03:29:15,477 - DEBUG - Response Code: 404
2025-12-25 03:29:15,477 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:15,484 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:15,540 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:15,670 - INFO - Validated that VN service-left-vn-98310634 is not found in API Server
2025-12-25 03:29:15,681 - DEBUG - VN service-left-vn-98310634 is not present in Agent 10.0.0.19
2025-12-25 03:29:15,691 - DEBUG - VN service-left-vn-98310634 is not present in Agent 10.0.0.130
2025-12-25 03:29:15,691 - INFO - Validated that VN service-left-vn-98310634 is not in any agent
2025-12-25 03:29:15,728 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-98310634 info
2025-12-25 03:29:15,728 - INFO - Deleting VN ctest-mgmt_vn-09579252
2025-12-25 03:29:15,925 - DEBUG - Response for deleting network ()
2025-12-25 03:29:15,925 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d02d0e5e-7e82-4bbc-8201-50ca0559cd68
2025-12-25 03:29:15,933 - DEBUG - Response Code: 404
2025-12-25 03:29:15,934 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:15,961 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:15,992 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:16,089 - INFO - Validated that VN ctest-mgmt_vn-09579252 is not found in API Server
2025-12-25 03:29:16,102 - DEBUG - VN ctest-mgmt_vn-09579252 is not present in Agent 10.0.0.19
2025-12-25 03:29:16,114 - DEBUG - VN ctest-mgmt_vn-09579252 is not present in Agent 10.0.0.130
2025-12-25 03:29:16,114 - INFO - Validated that VN ctest-mgmt_vn-09579252 is not in any agent
2025-12-25 03:29:16,152 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-09579252 info
2025-12-25 03:29:17,440 - DEBUG - No XMPP flaps were noticed during the test
2025-12-25 03:29:17,440 - INFO -
2025-12-25 03:29:17,441 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:17]
2025-12-25 03:29:17,441 - INFO - --------------------------------------------------------------------------------
2025-12-25 03:29:17,448 - INFO - ================================================================================
2025-12-25 03:29:17,449 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-12-25 03:29:18,748 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.130': {'10.20.0.14': '0', '10.20.0.209': '0'}} with
2025-12-25 03:29:18,748 - INFO - Initial checks done. Running the testcase now
2025-12-25 03:29:18,748 - INFO -
2025-12-25 03:29:19,084 - DEBUG - Response for create_network : {'network': {'id': 'c2da2d00-a886-4c81-9c33-44092843f15d', 'name': 'ctest-mgmt_vn-58316969', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'project_id': '3181eea2639c4681965654f68b57e4e4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34564705', 'ctest-mgmt_vn-58316969'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:29:19,306 - DEBUG - Response for create_subnet : {'subnet': {'id': '116b273a-5d14-4766-91eb-ffc15077796c', 'name': '', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'network_id': 'c2da2d00-a886-4c81-9c33-44092843f15d', 'ip_version': 4, 'cidr': '141.64.250.192/26', 'allocation_pools': [{'start': '141.64.250.194', 'end': '141.64.250.254'}], 'gateway_ip': '141.64.250.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '141.64.250.194', 'tags': [], 'project_id': '3181eea2639c4681965654f68b57e4e4'}}
2025-12-25 03:29:19,334 - INFO - Created VN ctest-mgmt_vn-58316969
2025-12-25 03:29:19,349 - DEBUG - VN ctest-mgmt_vn-58316969 UUID is c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,431 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:19,440 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:19,458 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:19,558 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,593 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,610 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/315f2619-d5b4-479f-bc76-7d99d59d784b
2025-12-25 03:29:19,623 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/315f2619-d5b4-479f-bc76-7d99d59d784b
2025-12-25 03:29:19,632 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e3c019a1-a4ce-4c4d-b26e-1fb3882af115
2025-12-25 03:29:19,639 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:19,640 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,651 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/315f2619-d5b4-479f-bc76-7d99d59d784b
2025-12-25 03:29:19,668 - INFO - Verified VN network id 15 for VN c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,668 - INFO - Verifications in API Server for VN ctest-mgmt_vn-58316969 passed
2025-12-25 03:29:19,668 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,683 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/315f2619-d5b4-479f-bc76-7d99d59d784b
2025-12-25 03:29:19,696 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/315f2619-d5b4-479f-bc76-7d99d59d784b
2025-12-25 03:29:19,704 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e3c019a1-a4ce-4c4d-b26e-1fb3882af115
2025-12-25 03:29:19,724 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14040584269131566209', 'uuid-lslong': '11255414699849937245'}, 'enable': 'true', 'created': '2025-12-25T03:29:18', 'last-modified': '2025-12-25T03:29:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464120'}
2025-12-25 03:29:19,737 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:19,743 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14040584269131566209', 'uuid-lslong': '11255414699849937245'}, 'enable': 'true', 'created': '2025-12-25T03:29:18', 'last-modified': '2025-12-25T03:29:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483679'}
2025-12-25 03:29:19,750 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:19,758 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14040584269131566209', 'uuid-lslong': '11255414699849937245'}, 'enable': 'true', 'created': '2025-12-25T03:29:18', 'last-modified': '2025-12-25T03:29:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497959'}
2025-12-25 03:29:19,765 - DEBUG - Route Targets: ['target:64512:8000004']
2025-12-25 03:29:19,765 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-58316969 passed
2025-12-25 03:29:19,765 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-58316969 in API_Server ======
2025-12-25 03:29:19,766 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:19,773 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:19,786 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:19,899 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/c2da2d00-a886-4c81-9c33-44092843f15d
2025-12-25 03:29:19,913 - DEBUG - =>VN ctest-mgmt_vn-58316969 has no policy to be verified
2025-12-25 03:29:19,913 - DEBUG - Verifying the vn in opserver
2025-12-25 03:29:19,913 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969 virtual network link through opserver 10.0.0.27
2025-12-25 03:29:19,913 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:19,927 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969?flat'}
2025-12-25 03:29:19,927 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969 virtual network link through opserver 10.0.0.33
2025-12-25 03:29:19,927 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:19,942 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969?flat'}
2025-12-25 03:29:19,942 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969 virtual network link through opserver 10.0.0.23
2025-12-25 03:29:19,942 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:19,956 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969?flat'}
2025-12-25 03:29:19,956 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34564705:ctest-mgmt_vn-58316969 is found in opserver
2025-12-25 03:29:19,982 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:29:19,997 - DEBUG - VRF ids for VN ctest-mgmt_vn-58316969: {}
2025-12-25 03:29:20,324 - DEBUG - Response for create_network : {'network': {'id': 'ef555ecd-e652-4da0-b0df-ee92f467e297', 'name': 'ctest-left_vn-35292676', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'project_id': '3181eea2639c4681965654f68b57e4e4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34564705', 'ctest-left_vn-35292676'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:29:20,659 - DEBUG - Response for create_subnet : {'subnet': {'id': '760f98fd-5e46-46a0-9962-fc4f5a2a03e1', 'name': '', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'network_id': 'ef555ecd-e652-4da0-b0df-ee92f467e297', 'ip_version': 4, 'cidr': '189.120.49.128/26', 'allocation_pools': [{'start': '189.120.49.130', 'end': '189.120.49.190'}], 'gateway_ip': '189.120.49.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '189.120.49.130', 'tags': [], 'project_id': '3181eea2639c4681965654f68b57e4e4'}}
2025-12-25 03:29:20,684 - INFO - Created VN ctest-left_vn-35292676
2025-12-25 03:29:20,701 - DEBUG - VN ctest-left_vn-35292676 UUID is ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:20,789 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:20,797 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:20,811 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:20,919 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:20,934 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:20,946 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/eae78769-4498-4a36-be6d-cb3af0c70757
2025-12-25 03:29:20,954 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/eae78769-4498-4a36-be6d-cb3af0c70757
2025-12-25 03:29:20,962 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7bd4437a-a10c-4057-81cb-5a6e4193ae06
2025-12-25 03:29:20,971 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:20,971 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:20,982 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/eae78769-4498-4a36-be6d-cb3af0c70757
2025-12-25 03:29:21,002 - INFO - Verified VN network id 16 for VN ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:21,002 - INFO - Verifications in API Server for VN ctest-left_vn-35292676 passed
2025-12-25 03:29:21,002 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:21,014 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/eae78769-4498-4a36-be6d-cb3af0c70757
2025-12-25 03:29:21,023 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/eae78769-4498-4a36-be6d-cb3af0c70757
2025-12-25 03:29:21,033 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7bd4437a-a10c-4057-81cb-5a6e4193ae06
2025-12-25 03:29:21,049 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17245794586510642592', 'uuid-lslong': '12745167785414877847'}, 'enable': 'true', 'created': '2025-12-25T03:29:20', 'last-modified': '2025-12-25T03:29:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466299'}
2025-12-25 03:29:21,058 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:21,064 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17245794586510642592', 'uuid-lslong': '12745167785414877847'}, 'enable': 'true', 'created': '2025-12-25T03:29:20', 'last-modified': '2025-12-25T03:29:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486386'}
2025-12-25 03:29:21,075 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:21,081 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17245794586510642592', 'uuid-lslong': '12745167785414877847'}, 'enable': 'true', 'created': '2025-12-25T03:29:20', 'last-modified': '2025-12-25T03:29:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.504088'}
2025-12-25 03:29:21,087 - DEBUG - Route Targets: ['target:64512:8000006']
2025-12-25 03:29:21,087 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-35292676 passed
2025-12-25 03:29:21,087 - DEBUG - ====Verifying policy data for ctest-left_vn-35292676 in API_Server ======
2025-12-25 03:29:21,087 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:21,094 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:21,107 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:21,214 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/ef555ecd-e652-4da0-b0df-ee92f467e297
2025-12-25 03:29:21,224 - DEBUG - =>VN ctest-left_vn-35292676 has no policy to be verified
2025-12-25 03:29:21,224 - DEBUG - Verifying the vn in opserver
2025-12-25 03:29:21,224 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676 virtual network link through opserver 10.0.0.27
2025-12-25 03:29:21,224 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:21,238 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676?flat'}
2025-12-25 03:29:21,238 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676 virtual network link through opserver 10.0.0.33
2025-12-25 03:29:21,238 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:21,253 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676?flat'}
2025-12-25 03:29:21,253 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676 virtual network link through opserver 10.0.0.23
2025-12-25 03:29:21,253 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:21,266 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676?flat'}
2025-12-25 03:29:21,267 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34564705:ctest-left_vn-35292676 is found in opserver
2025-12-25 03:29:21,291 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:29:21,302 - DEBUG - VRF ids for VN ctest-left_vn-35292676: {}
2025-12-25 03:29:21,602 - DEBUG - Response for create_network : {'network': {'id': '67295543-0827-465a-afd0-46b946853909', 'name': 'ctest-right_vn-22449731', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'project_id': '3181eea2639c4681965654f68b57e4e4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-34564705', 'ctest-right_vn-22449731'], 'port_security_enabled': True, 'description': ''}}
2025-12-25 03:29:21,866 - DEBUG - Response for create_subnet : {'subnet': {'id': '02dba9e6-39e3-46d6-b4e7-ffd348c857ec', 'name': '', 'tenant_id': '3181eea2639c4681965654f68b57e4e4', 'network_id': '67295543-0827-465a-afd0-46b946853909', 'ip_version': 4, 'cidr': '47.42.188.0/26', 'allocation_pools': [{'start': '47.42.188.2', 'end': '47.42.188.62'}], 'gateway_ip': '47.42.188.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.42.188.2', 'tags': [], 'project_id': '3181eea2639c4681965654f68b57e4e4'}}
2025-12-25 03:29:21,895 - INFO - Created VN ctest-right_vn-22449731
2025-12-25 03:29:21,910 - DEBUG - VN ctest-right_vn-22449731 UUID is 67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:21,995 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:22,002 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:22,016 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:22,129 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:22,150 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:22,163 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0b09eb37-28b7-45fc-a74d-ffc25c0ea74e
2025-12-25 03:29:22,173 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0b09eb37-28b7-45fc-a74d-ffc25c0ea74e
2025-12-25 03:29:22,183 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/0913ac4b-f343-45cd-8d3f-9e5422c1a3ca
2025-12-25 03:29:22,191 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:22,191 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:22,203 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0b09eb37-28b7-45fc-a74d-ffc25c0ea74e
2025-12-25 03:29:22,239 - INFO - Verified VN network id 17 for VN 67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:22,239 - INFO - Verifications in API Server for VN ctest-right_vn-22449731 passed
2025-12-25 03:29:22,239 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:22,251 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0b09eb37-28b7-45fc-a74d-ffc25c0ea74e
2025-12-25 03:29:22,278 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0b09eb37-28b7-45fc-a74d-ffc25c0ea74e
2025-12-25 03:29:22,290 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/0913ac4b-f343-45cd-8d3f-9e5422c1a3ca
2025-12-25 03:29:22,307 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7433566406339675738', 'uuid-lslong': '12668703513359235337'}, 'enable': 'true', 'created': '2025-12-25T03:29:21', 'last-modified': '2025-12-25T03:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.490084'}
2025-12-25 03:29:22,319 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:22,330 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7433566406339675738', 'uuid-lslong': '12668703513359235337'}, 'enable': 'true', 'created': '2025-12-25T03:29:21', 'last-modified': '2025-12-25T03:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.518143'}
2025-12-25 03:29:22,338 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:22,346 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-34564705', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7433566406339675738', 'uuid-lslong': '12668703513359235337'}, 'enable': 'true', 'created': '2025-12-25T03:29:21', 'last-modified': '2025-12-25T03:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.537801'}
2025-12-25 03:29:22,353 - DEBUG - Route Targets: ['target:64512:8000007']
2025-12-25 03:29:22,353 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-22449731 passed
2025-12-25 03:29:22,353 - DEBUG - ====Verifying policy data for ctest-right_vn-22449731 in API_Server ======
2025-12-25 03:29:22,353 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:22,361 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:22,378 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:22,502 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/67295543-0827-465a-afd0-46b946853909
2025-12-25 03:29:22,522 - DEBUG - =>VN ctest-right_vn-22449731 has no policy to be verified
2025-12-25 03:29:22,522 - DEBUG - Verifying the vn in opserver
2025-12-25 03:29:22,522 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731 virtual network link through opserver 10.0.0.27
2025-12-25 03:29:22,522 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-12-25 03:29:22,536 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731?flat'}
2025-12-25 03:29:22,536 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731 virtual network link through opserver 10.0.0.33
2025-12-25 03:29:22,536 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-12-25 03:29:22,553 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731?flat'}
2025-12-25 03:29:22,553 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731 virtual network link through opserver 10.0.0.23
2025-12-25 03:29:22,553 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-12-25 03:29:22,570 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731?flat'}
2025-12-25 03:29:22,570 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-34564705:ctest-right_vn-22449731 is found in opserver
2025-12-25 03:29:22,602 - DEBUG - Do not have enough data to verify VN in agent
2025-12-25 03:29:22,616 - DEBUG - VRF ids for VN ctest-right_vn-22449731: {}
2025-12-25 03:29:23,920 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-2)
2025-12-25 03:29:25,528 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4927-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4927-1)
2025-12-25 03:29:25,528 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-32584658']
2025-12-25 03:29:25,828 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-32584658'] created succesfully
2025-12-25 03:29:26,156 - DEBUG - Image by name tiny_nat_fw not found
2025-12-25 03:29:26,156 - DEBUG - Installing image tiny_nat_fw
2025-12-25 03:29:26,157 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-12-25 03:29:26,157 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:29:26,157 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:29:26,157 - DEBUG - Unzip image
2025-12-25 03:29:26,157 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-12-25 03:29:26,180 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-12-25 03:29:26,181 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-32584658']
2025-12-25 03:29:26,308 - INFO - Service template: ['default-domain', 'ctest-service_template_1-32584658'] deleted successfully.
2025-12-25 03:29:26,308 - INFO - Skip interface_detach for VM ctest-right_vm-20954434 in state BUILD
2025-12-25 03:29:26,309 - INFO - Deleting VM ctest-right_vm-20954434
2025-12-25 03:29:26,309 - ERROR - VM ctest-right_vm-20954434 has failed to come up
2025-12-25 03:29:26,309 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:26,309 - ERROR - VM ctest-right_vm-20954434 has failed to come up
2025-12-25 03:29:26,310 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:26,432 - INFO - Skip interface_detach for VM ctest-left_vm-36105693 in state BUILD
2025-12-25 03:29:26,432 - INFO - Deleting VM ctest-left_vm-36105693
2025-12-25 03:29:26,432 - ERROR - VM ctest-left_vm-36105693 has failed to come up
2025-12-25 03:29:26,432 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:26,432 - ERROR - VM ctest-left_vm-36105693 has failed to come up
2025-12-25 03:29:26,432 - ERROR - Nova failed to get host of the VM
2025-12-25 03:29:26,546 - INFO - Deleting VN ctest-right_vn-22449731
2025-12-25 03:29:26,630 - DEBUG - VN 67295543-0827-465a-afd0-46b946853909 still in use: Unable to complete operation on network 67295543-0827-465a-afd0-46b946853909. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-90064b22-2f6b-48cf-98cf-d388f6696553']
2025-12-25 03:29:26,630 - WARNING - Deleting VN ctest-right_vn-22449731 failed..Will retry
2025-12-25 03:29:28,823 - DEBUG - Response for deleting network ()
2025-12-25 03:29:28,824 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0b09eb37-28b7-45fc-a74d-ffc25c0ea74e
2025-12-25 03:29:28,830 - DEBUG - Response Code: 404
2025-12-25 03:29:28,830 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:28,843 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:28,877 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:29,027 - INFO - Validated that VN ctest-right_vn-22449731 is not found in API Server
2025-12-25 03:29:29,052 - DEBUG - VN ctest-right_vn-22449731 is not present in Agent 10.0.0.19
2025-12-25 03:29:29,066 - DEBUG - VN ctest-right_vn-22449731 is not present in Agent 10.0.0.130
2025-12-25 03:29:29,066 - INFO - Validated that VN ctest-right_vn-22449731 is not in any agent
2025-12-25 03:29:29,113 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-22449731 info
2025-12-25 03:29:29,113 - INFO - Deleting VN ctest-left_vn-35292676
2025-12-25 03:29:29,336 - DEBUG - Response for deleting network ()
2025-12-25 03:29:29,336 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/eae78769-4498-4a36-be6d-cb3af0c70757
2025-12-25 03:29:29,346 - DEBUG - Response Code: 404
2025-12-25 03:29:29,346 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:29,357 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:29,372 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:29,481 - INFO - Validated that VN ctest-left_vn-35292676 is not found in API Server
2025-12-25 03:29:29,492 - DEBUG - VN ctest-left_vn-35292676 is not present in Agent 10.0.0.19
2025-12-25 03:29:29,504 - DEBUG - VN ctest-left_vn-35292676 is not present in Agent 10.0.0.130
2025-12-25 03:29:29,504 - INFO - Validated that VN ctest-left_vn-35292676 is not in any agent
2025-12-25 03:29:29,550 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-35292676 info
2025-12-25 03:29:29,550 - INFO - Deleting VN ctest-mgmt_vn-58316969
2025-12-25 03:29:29,721 - DEBUG - Response for deleting network ()
2025-12-25 03:29:29,721 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/315f2619-d5b4-479f-bc76-7d99d59d784b
2025-12-25 03:29:29,728 - DEBUG - Response Code: 404
2025-12-25 03:29:29,728 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-12-25 03:29:29,738 - DEBUG - Requesting: http://10.0.0.27:8082/domain/d4f5d7ea-1779-482f-9e93-03873d431e99
2025-12-25 03:29:29,775 - DEBUG - Requesting: http://10.0.0.27:8082/project/3181eea2-639c-4681-9656-54f68b57e4e4
2025-12-25 03:29:29,901 - INFO - Validated that VN ctest-mgmt_vn-58316969 is not found in API Server
2025-12-25 03:29:29,915 - DEBUG - VN ctest-mgmt_vn-58316969 is not present in Agent 10.0.0.19
2025-12-25 03:29:29,927 - DEBUG - VN ctest-mgmt_vn-58316969 is not present in Agent 10.0.0.130
2025-12-25 03:29:29,927 - INFO - Validated that VN ctest-mgmt_vn-58316969 is not in any agent
2025-12-25 03:29:29,972 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-58316969 info
2025-12-25 03:29:31,290 - DEBUG - No XMPP flaps were noticed during the test
2025-12-25 03:29:31,291 - INFO -
2025-12-25 03:29:31,291 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-12-25 03:29:31,291 - INFO - --------------------------------------------------------------------------------
2025-12-25 03:29:32,287 - INFO - Deleted project: ctest-TestSvcRegr-34564705, ID : 3181eea2-639c-4681-9656-54f68b57e4e4