]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-1)
2025-10-13 03:05:47,554 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-79149407']
2025-10-13 03:05:47,861 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-79149407'] created succesfully
2025-10-13 03:05:48,282 - DEBUG - Image by name tiny_nat_fw not found
2025-10-13 03:05:48,282 - DEBUG - Installing image tiny_nat_fw
2025-10-13 03:05:48,282 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-13 03:05:48,283 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-13 03:05:48,283 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-13 03:05:48,305 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:05:48,390 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:05:48,390 - DEBUG - Unzip image
2025-10-13 03:05:48,390 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:05:48,418 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-13 03:05:48,418 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-79149407']
2025-10-13 03:05:48,516 - INFO - Service template: ['default-domain', 'ctest-service_template_1-79149407'] deleted successfully.
2025-10-13 03:05:48,516 - INFO - Skip interface_detach for VM ctest-right_vm-51173402 in state BUILD
2025-10-13 03:05:48,516 - INFO - Deleting VM ctest-right_vm-51173402
2025-10-13 03:05:48,546 - ERROR - VM ctest-right_vm-51173402 has failed to come up
2025-10-13 03:05:48,546 - ERROR - Nova failed to get host of the VM
2025-10-13 03:05:48,546 - ERROR - VM ctest-right_vm-51173402 has failed to come up
2025-10-13 03:05:48,546 - ERROR - Nova failed to get host of the VM
2025-10-13 03:05:48,612 - INFO - Skip interface_detach for VM ctest-left_vm-11268352 in state BUILD
2025-10-13 03:05:48,612 - INFO - Deleting VM ctest-left_vm-11268352
2025-10-13 03:05:48,612 - ERROR - VM ctest-left_vm-11268352 has failed to come up
2025-10-13 03:05:48,612 - ERROR - Nova failed to get host of the VM
2025-10-13 03:05:48,612 - ERROR - VM ctest-left_vm-11268352 has failed to come up
2025-10-13 03:05:48,613 - ERROR - Nova failed to get host of the VM
2025-10-13 03:05:48,688 - INFO - Deleting VN ctest-right_vn-81399234
2025-10-13 03:05:48,730 - DEBUG - VN 78d4208a-030e-46ee-a173-a538ad7f00ff still in use: Unable to complete operation on network 78d4208a-030e-46ee-a173-a538ad7f00ff. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ccc56e96-59e7-4865-9332-9f142eb947e6']
2025-10-13 03:05:48,730 - WARNING - Deleting VN ctest-right_vn-81399234 failed..Will retry
2025-10-13 03:05:50,889 - DEBUG - Response for deleting network ()
2025-10-13 03:05:50,889 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3fa1350e-47e7-416f-b540-8fe3b3b2e56c
2025-10-13 03:05:50,897 - DEBUG - Response Code: 404
2025-10-13 03:05:50,897 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:50,911 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:50,969 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:51,083 - INFO - Validated that VN ctest-right_vn-81399234 is not found in API Server
2025-10-13 03:05:51,100 - DEBUG - VN ctest-right_vn-81399234 is not present in Agent 10.0.0.249
2025-10-13 03:05:51,110 - DEBUG - VN ctest-right_vn-81399234 is not present in Agent 10.0.0.27
2025-10-13 03:05:51,110 - INFO - Validated that VN ctest-right_vn-81399234 is not in any agent
2025-10-13 03:05:51,144 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-81399234 info
2025-10-13 03:05:51,144 - INFO - Deleting VN ctest-left_vn-24057650
2025-10-13 03:05:51,320 - DEBUG - Response for deleting network ()
2025-10-13 03:05:51,320 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7e4e0e83-ae78-4076-9b5f-c0e6857974b1
2025-10-13 03:05:51,325 - DEBUG - Response Code: 404
2025-10-13 03:05:51,325 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:51,339 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:51,390 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:51,481 - INFO - Validated that VN ctest-left_vn-24057650 is not found in API Server
2025-10-13 03:05:51,495 - DEBUG - VN ctest-left_vn-24057650 is not present in Agent 10.0.0.249
2025-10-13 03:05:51,506 - DEBUG - VN ctest-left_vn-24057650 is not present in Agent 10.0.0.27
2025-10-13 03:05:51,506 - INFO - Validated that VN ctest-left_vn-24057650 is not in any agent
2025-10-13 03:05:51,536 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-24057650 info
2025-10-13 03:05:51,536 - INFO - Deleting VN ctest-mgmt_vn-26877646
2025-10-13 03:05:51,669 - DEBUG - Response for deleting network ()
2025-10-13 03:05:51,669 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9bb22f67-b88d-427a-8400-df7fc4509da9
2025-10-13 03:05:51,674 - DEBUG - Response Code: 404
2025-10-13 03:05:51,675 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:51,679 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:51,688 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:51,777 - INFO - Validated that VN ctest-mgmt_vn-26877646 is not found in API Server
2025-10-13 03:05:51,790 - DEBUG - VN ctest-mgmt_vn-26877646 is not present in Agent 10.0.0.249
2025-10-13 03:05:51,802 - DEBUG - VN ctest-mgmt_vn-26877646 is not present in Agent 10.0.0.27
2025-10-13 03:05:51,802 - INFO - Validated that VN ctest-mgmt_vn-26877646 is not in any agent
2025-10-13 03:05:51,832 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-26877646 info
2025-10-13 03:05:51,832 - INFO - Deleting VM ctest-vm-in-third-vn-08730903
2025-10-13 03:05:51,904 - INFO - Deleting VN ctest-third-vn-49262797
2025-10-13 03:05:51,953 - DEBUG - VN 5bf89e9c-4496-4511-aff9-a57865d33ee0 still in use: Unable to complete operation on network 5bf89e9c-4496-4511-aff9-a57865d33ee0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-50261857-0179-43c0-8362-375b322acb7a']
2025-10-13 03:05:51,954 - WARNING - Deleting VN ctest-third-vn-49262797 failed..Will retry
2025-10-13 03:05:54,121 - DEBUG - Response for deleting network ()
2025-10-13 03:05:55,402 - DEBUG - No XMPP flaps were noticed during the test
2025-10-13 03:05:55,402 - INFO -
2025-10-13 03:05:55,402 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-10-13 03:05:55,402 - INFO - --------------------------------------------------------------------------------
2025-10-13 03:05:55,405 - INFO - ================================================================================
2025-10-13 03:05:55,405 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-13 03:05:56,685 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.27': {'10.20.0.18': '0', '10.20.0.129': '0'}} with
2025-10-13 03:05:56,685 - INFO - Initial checks done. Running the testcase now
2025-10-13 03:05:56,685 - INFO -
2025-10-13 03:05:56,983 - DEBUG - Response for create_network : {'network': {'id': '3431b2ee-00da-4679-acae-1019b08f18b9', 'name': 'ctest-mgmt_vn-63110683', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'project_id': '8aff1669a1424f09985f70a6fa046c6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-11126966', 'ctest-mgmt_vn-63110683'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:05:57,211 - DEBUG - Response for create_subnet : {'subnet': {'id': '99083e71-fcb6-49da-bcd5-8eb2375c87b8', 'name': '', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'network_id': '3431b2ee-00da-4679-acae-1019b08f18b9', 'ip_version': 4, 'cidr': '144.128.100.64/26', 'allocation_pools': [{'start': '144.128.100.66', 'end': '144.128.100.126'}], 'gateway_ip': '144.128.100.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '144.128.100.66', 'tags': [], 'project_id': '8aff1669a1424f09985f70a6fa046c6a'}}
2025-10-13 03:05:57,232 - INFO - Created VN ctest-mgmt_vn-63110683
2025-10-13 03:05:57,245 - DEBUG - VN ctest-mgmt_vn-63110683 UUID is 3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,311 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:57,317 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:57,331 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:57,425 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,435 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,444 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ea3b7e34-8d2c-4a5c-934f-d322b0b292da
2025-10-13 03:05:57,450 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ea3b7e34-8d2c-4a5c-934f-d322b0b292da
2025-10-13 03:05:57,457 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/070249d3-879d-43a9-acd9-bd213083db0a
2025-10-13 03:05:57,464 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:05:57,464 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,472 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ea3b7e34-8d2c-4a5c-934f-d322b0b292da
2025-10-13 03:05:57,487 - INFO - Verified VN network id 13 for VN 3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,487 - INFO - Verifications in API Server for VN ctest-mgmt_vn-63110683 passed
2025-10-13 03:05:57,487 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,496 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ea3b7e34-8d2c-4a5c-934f-d322b0b292da
2025-10-13 03:05:57,504 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ea3b7e34-8d2c-4a5c-934f-d322b0b292da
2025-10-13 03:05:57,512 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/070249d3-879d-43a9-acd9-bd213083db0a
2025-10-13 03:05:57,525 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3760983899117340281', 'uuid-lslong': '12442900522993653945'}, 'enable': 'true', 'created': '2025-10-13T03:05:56', 'last-modified': '2025-10-13T03:05:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.363435'}
2025-10-13 03:05:57,531 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:05:57,537 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3760983899117340281', 'uuid-lslong': '12442900522993653945'}, 'enable': 'true', 'created': '2025-10-13T03:05:56', 'last-modified': '2025-10-13T03:05:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369117'}
2025-10-13 03:05:57,544 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:05:57,550 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3760983899117340281', 'uuid-lslong': '12442900522993653945'}, 'enable': 'true', 'created': '2025-10-13T03:05:56', 'last-modified': '2025-10-13T03:05:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.385735'}
2025-10-13 03:05:57,556 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:05:57,556 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-63110683 passed
2025-10-13 03:05:57,556 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-63110683 in API_Server ======
2025-10-13 03:05:57,556 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:57,563 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:57,575 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:57,686 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/3431b2ee-00da-4679-acae-1019b08f18b9
2025-10-13 03:05:57,696 - DEBUG - =>VN ctest-mgmt_vn-63110683 has no policy to be verified
2025-10-13 03:05:57,696 - DEBUG - Verifying the vn in opserver
2025-10-13 03:05:57,696 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683 virtual network link through opserver 10.0.0.22
2025-10-13 03:05:57,696 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:05:57,712 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683?flat'}
2025-10-13 03:05:57,712 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683 virtual network link through opserver 10.0.0.19
2025-10-13 03:05:57,712 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:05:57,724 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683?flat'}
2025-10-13 03:05:57,724 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683 virtual network link through opserver 10.0.0.41
2025-10-13 03:05:57,724 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:05:57,736 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683?flat'}
2025-10-13 03:05:57,736 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-11126966:ctest-mgmt_vn-63110683 is found in opserver
2025-10-13 03:05:57,758 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:05:57,767 - DEBUG - VRF ids for VN ctest-mgmt_vn-63110683: {}
2025-10-13 03:05:58,020 - DEBUG - Response for create_network : {'network': {'id': '39b2a6d5-c1c3-4354-951a-85605f3ef4e8', 'name': 'ctest-left_vn-97579277', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'project_id': '8aff1669a1424f09985f70a6fa046c6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-11126966', 'ctest-left_vn-97579277'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:05:58,238 - DEBUG - Response for create_subnet : {'subnet': {'id': '6d365a47-f0ea-4d4b-ab4d-d07cce9d9370', 'name': '', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'network_id': '39b2a6d5-c1c3-4354-951a-85605f3ef4e8', 'ip_version': 4, 'cidr': '130.23.163.192/26', 'allocation_pools': [{'start': '130.23.163.194', 'end': '130.23.163.254'}], 'gateway_ip': '130.23.163.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.23.163.194', 'tags': [], 'project_id': '8aff1669a1424f09985f70a6fa046c6a'}}
2025-10-13 03:05:58,260 - INFO - Created VN ctest-left_vn-97579277
2025-10-13 03:05:58,272 - DEBUG - VN ctest-left_vn-97579277 UUID is 39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,342 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:58,349 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:58,360 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:58,443 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,455 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,464 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/526108dd-d7bf-426c-81cb-d8559a4f8c53
2025-10-13 03:05:58,470 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/526108dd-d7bf-426c-81cb-d8559a4f8c53
2025-10-13 03:05:58,476 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c59d5e7c-6b6a-490a-a5f7-ab31564ae712
2025-10-13 03:05:58,482 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:05:58,482 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,490 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/526108dd-d7bf-426c-81cb-d8559a4f8c53
2025-10-13 03:05:58,503 - INFO - Verified VN network id 14 for VN 39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,503 - INFO - Verifications in API Server for VN ctest-left_vn-97579277 passed
2025-10-13 03:05:58,503 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,512 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/526108dd-d7bf-426c-81cb-d8559a4f8c53
2025-10-13 03:05:58,518 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/526108dd-d7bf-426c-81cb-d8559a4f8c53
2025-10-13 03:05:58,524 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c59d5e7c-6b6a-490a-a5f7-ab31564ae712
2025-10-13 03:05:58,534 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157568843025433428', 'uuid-lslong': '10744046510007055592'}, 'enable': 'true', 'created': '2025-10-13T03:05:57', 'last-modified': '2025-10-13T03:05:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.350794'}
2025-10-13 03:05:58,538 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:05:58,544 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157568843025433428', 'uuid-lslong': '10744046510007055592'}, 'enable': 'true', 'created': '2025-10-13T03:05:57', 'last-modified': '2025-10-13T03:05:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.358260'}
2025-10-13 03:05:58,551 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:05:58,556 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157568843025433428', 'uuid-lslong': '10744046510007055592'}, 'enable': 'true', 'created': '2025-10-13T03:05:57', 'last-modified': '2025-10-13T03:05:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.374969'}
2025-10-13 03:05:58,561 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:05:58,561 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-97579277 passed
2025-10-13 03:05:58,561 - DEBUG - ====Verifying policy data for ctest-left_vn-97579277 in API_Server ======
2025-10-13 03:05:58,561 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:58,566 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:58,579 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:58,671 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/39b2a6d5-c1c3-4354-951a-85605f3ef4e8
2025-10-13 03:05:58,680 - DEBUG - =>VN ctest-left_vn-97579277 has no policy to be verified
2025-10-13 03:05:58,680 - DEBUG - Verifying the vn in opserver
2025-10-13 03:05:58,680 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277 virtual network link through opserver 10.0.0.22
2025-10-13 03:05:58,680 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:05:58,691 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277?flat'}
2025-10-13 03:05:58,691 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277 virtual network link through opserver 10.0.0.19
2025-10-13 03:05:58,691 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:05:58,702 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277?flat'}
2025-10-13 03:05:58,702 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277 virtual network link through opserver 10.0.0.41
2025-10-13 03:05:58,702 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:05:58,714 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277?flat'}
2025-10-13 03:05:58,714 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-11126966:ctest-left_vn-97579277 is found in opserver
2025-10-13 03:05:58,734 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:05:58,744 - DEBUG - VRF ids for VN ctest-left_vn-97579277: {}
2025-10-13 03:05:59,053 - DEBUG - Response for create_network : {'network': {'id': '4fab1e5e-7706-48de-89eb-c6d356847b8c', 'name': 'ctest-right_vn-16674873', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'project_id': '8aff1669a1424f09985f70a6fa046c6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-11126966', 'ctest-right_vn-16674873'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:05:59,246 - DEBUG - Response for create_subnet : {'subnet': {'id': '6d4dbabf-4d1b-448b-ac1f-d3ddc7a8ba57', 'name': '', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'network_id': '4fab1e5e-7706-48de-89eb-c6d356847b8c', 'ip_version': 4, 'cidr': '141.57.76.0/26', 'allocation_pools': [{'start': '141.57.76.2', 'end': '141.57.76.62'}], 'gateway_ip': '141.57.76.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '141.57.76.2', 'tags': [], 'project_id': '8aff1669a1424f09985f70a6fa046c6a'}}
2025-10-13 03:05:59,270 - INFO - Created VN ctest-right_vn-16674873
2025-10-13 03:05:59,282 - DEBUG - VN ctest-right_vn-16674873 UUID is 4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,353 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:59,360 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:59,371 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:59,455 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,466 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,474 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3a5d04c1-1c19-4e3b-8f5f-dc3bfd482770
2025-10-13 03:05:59,484 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3a5d04c1-1c19-4e3b-8f5f-dc3bfd482770
2025-10-13 03:05:59,490 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/0a81d354-5cc6-4e71-be37-bba4fd2287db
2025-10-13 03:05:59,497 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:05:59,497 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,505 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3a5d04c1-1c19-4e3b-8f5f-dc3bfd482770
2025-10-13 03:05:59,521 - INFO - Verified VN network id 15 for VN 4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,522 - INFO - Verifications in API Server for VN ctest-right_vn-16674873 passed
2025-10-13 03:05:59,522 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,532 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3a5d04c1-1c19-4e3b-8f5f-dc3bfd482770
2025-10-13 03:05:59,539 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3a5d04c1-1c19-4e3b-8f5f-dc3bfd482770
2025-10-13 03:05:59,547 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/0a81d354-5cc6-4e71-be37-bba4fd2287db
2025-10-13 03:05:59,560 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5740715541086488798', 'uuid-lslong': '9938255613715053452'}, 'enable': 'true', 'created': '2025-10-13T03:05:59', 'last-modified': '2025-10-13T03:05:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362922'}
2025-10-13 03:05:59,565 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:05:59,571 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5740715541086488798', 'uuid-lslong': '9938255613715053452'}, 'enable': 'true', 'created': '2025-10-13T03:05:59', 'last-modified': '2025-10-13T03:05:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.376981'}
2025-10-13 03:05:59,577 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:05:59,583 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5740715541086488798', 'uuid-lslong': '9938255613715053452'}, 'enable': 'true', 'created': '2025-10-13T03:05:59', 'last-modified': '2025-10-13T03:05:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.385437'}
2025-10-13 03:05:59,588 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:05:59,588 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-16674873 passed
2025-10-13 03:05:59,589 - DEBUG - ====Verifying policy data for ctest-right_vn-16674873 in API_Server ======
2025-10-13 03:05:59,589 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:05:59,595 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:05:59,608 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:05:59,697 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4fab1e5e-7706-48de-89eb-c6d356847b8c
2025-10-13 03:05:59,705 - DEBUG - =>VN ctest-right_vn-16674873 has no policy to be verified
2025-10-13 03:05:59,705 - DEBUG - Verifying the vn in opserver
2025-10-13 03:05:59,705 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873 virtual network link through opserver 10.0.0.22
2025-10-13 03:05:59,705 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:05:59,717 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873?flat'}
2025-10-13 03:05:59,717 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873 virtual network link through opserver 10.0.0.19
2025-10-13 03:05:59,717 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:05:59,729 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873?flat'}
2025-10-13 03:05:59,730 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873 virtual network link through opserver 10.0.0.41
2025-10-13 03:05:59,730 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:05:59,742 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873?flat'}
2025-10-13 03:05:59,742 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-11126966:ctest-right_vn-16674873 is found in opserver
2025-10-13 03:05:59,762 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:05:59,771 - DEBUG - VRF ids for VN ctest-right_vn-16674873: {}
2025-10-13 03:06:00,032 - DEBUG - Response for create_network : {'network': {'id': '1ca04c16-94b6-4451-bb46-3354d595e2e7', 'name': 'ctest-trans_left_vn-43489479', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'project_id': '8aff1669a1424f09985f70a6fa046c6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-11126966', 'ctest-trans_left_vn-43489479'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:00,276 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eaef5e4a-7879-43d9-9286-b5bb27eeb3b9', 'name': '', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'network_id': '1ca04c16-94b6-4451-bb46-3354d595e2e7', 'ip_version': 4, 'cidr': '121.49.96.192/26', 'allocation_pools': [{'start': '121.49.96.194', 'end': '121.49.96.254'}], 'gateway_ip': '121.49.96.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.49.96.194', 'tags': [], 'project_id': '8aff1669a1424f09985f70a6fa046c6a'}}
2025-10-13 03:06:00,300 - INFO - Created VN ctest-trans_left_vn-43489479
2025-10-13 03:06:00,315 - DEBUG - VN ctest-trans_left_vn-43489479 UUID is 1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,391 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:00,398 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:00,409 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:00,501 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,516 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,524 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/09fb36ef-acbc-45f9-bf5a-9693f8236c27
2025-10-13 03:06:00,531 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/09fb36ef-acbc-45f9-bf5a-9693f8236c27
2025-10-13 03:06:00,537 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/68d8e2b3-05e3-4710-aab3-8e58b730d3a7
2025-10-13 03:06:00,544 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-13 03:06:00,544 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,552 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/09fb36ef-acbc-45f9-bf5a-9693f8236c27
2025-10-13 03:06:00,569 - INFO - Verified VN network id 16 for VN 1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,569 - INFO - Verifications in API Server for VN ctest-trans_left_vn-43489479 passed
2025-10-13 03:06:00,569 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,577 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/09fb36ef-acbc-45f9-bf5a-9693f8236c27
2025-10-13 03:06:00,583 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/09fb36ef-acbc-45f9-bf5a-9693f8236c27
2025-10-13 03:06:00,590 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/68d8e2b3-05e3-4710-aab3-8e58b730d3a7
2025-10-13 03:06:00,601 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2062732289203651665', 'uuid-lslong': '13494529772915909351'}, 'enable': 'true', 'created': '2025-10-13T03:05:59', 'last-modified': '2025-10-13T03:06:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.379307'}
2025-10-13 03:06:00,607 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-13 03:06:00,612 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2062732289203651665', 'uuid-lslong': '13494529772915909351'}, 'enable': 'true', 'created': '2025-10-13T03:05:59', 'last-modified': '2025-10-13T03:06:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388876'}
2025-10-13 03:06:00,619 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-13 03:06:00,624 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2062732289203651665', 'uuid-lslong': '13494529772915909351'}, 'enable': 'true', 'created': '2025-10-13T03:05:59', 'last-modified': '2025-10-13T03:06:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396702'}
2025-10-13 03:06:00,629 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-13 03:06:00,629 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-43489479 passed
2025-10-13 03:06:00,630 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-43489479 in API_Server ======
2025-10-13 03:06:00,630 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:00,635 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:00,646 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:00,732 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1ca04c16-94b6-4451-bb46-3354d595e2e7
2025-10-13 03:06:00,743 - DEBUG - =>VN ctest-trans_left_vn-43489479 has no policy to be verified
2025-10-13 03:06:00,743 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:00,743 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:00,743 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:00,754 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479?flat'}
2025-10-13 03:06:00,754 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:00,754 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:00,765 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479?flat'}
2025-10-13 03:06:00,765 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:00,765 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:00,778 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479?flat'}
2025-10-13 03:06:00,778 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-11126966:ctest-trans_left_vn-43489479 is found in opserver
2025-10-13 03:06:00,800 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:00,810 - DEBUG - VRF ids for VN ctest-trans_left_vn-43489479: {}
2025-10-13 03:06:01,073 - DEBUG - Response for create_network : {'network': {'id': 'dc471bdc-dd15-47d5-a914-19cddfde1a0b', 'name': 'ctest-trans_right_vn-64099892', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'project_id': '8aff1669a1424f09985f70a6fa046c6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-11126966', 'ctest-trans_right_vn-64099892'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:01,360 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b83d8b79-7297-4cc5-8a34-f70f806cd4a8', 'name': '', 'tenant_id': '8aff1669a1424f09985f70a6fa046c6a', 'network_id': 'dc471bdc-dd15-47d5-a914-19cddfde1a0b', 'ip_version': 4, 'cidr': '193.232.77.64/26', 'allocation_pools': [{'start': '193.232.77.66', 'end': '193.232.77.126'}], 'gateway_ip': '193.232.77.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.232.77.66', 'tags': [], 'project_id': '8aff1669a1424f09985f70a6fa046c6a'}}
2025-10-13 03:06:01,388 - INFO - Created VN ctest-trans_right_vn-64099892
2025-10-13 03:06:01,398 - DEBUG - VN ctest-trans_right_vn-64099892 UUID is dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,483 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:01,490 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:01,504 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:01,595 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,609 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,618 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3f815bb-5707-4e4b-8d18-7248b547cae6
2025-10-13 03:06:01,625 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3f815bb-5707-4e4b-8d18-7248b547cae6
2025-10-13 03:06:01,632 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/70dd82b7-589c-4b03-b394-907d93ba1cfc
2025-10-13 03:06:01,640 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-13 03:06:01,640 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,650 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3f815bb-5707-4e4b-8d18-7248b547cae6
2025-10-13 03:06:01,671 - INFO - Verified VN network id 17 for VN dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,671 - INFO - Verifications in API Server for VN ctest-trans_right_vn-64099892 passed
2025-10-13 03:06:01,671 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,680 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3f815bb-5707-4e4b-8d18-7248b547cae6
2025-10-13 03:06:01,689 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3f815bb-5707-4e4b-8d18-7248b547cae6
2025-10-13 03:06:01,696 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/70dd82b7-589c-4b03-b394-907d93ba1cfc
2025-10-13 03:06:01,710 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15872686047106516949', 'uuid-lslong': '12183391263958899211'}, 'enable': 'true', 'created': '2025-10-13T03:06:01', 'last-modified': '2025-10-13T03:06:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427159'}
2025-10-13 03:06:01,715 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-13 03:06:01,719 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15872686047106516949', 'uuid-lslong': '12183391263958899211'}, 'enable': 'true', 'created': '2025-10-13T03:06:01', 'last-modified': '2025-10-13T03:06:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419553'}
2025-10-13 03:06:01,725 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-13 03:06:01,731 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-11126966', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15872686047106516949', 'uuid-lslong': '12183391263958899211'}, 'enable': 'true', 'created': '2025-10-13T03:06:01', 'last-modified': '2025-10-13T03:06:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.444712'}
2025-10-13 03:06:01,737 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-13 03:06:01,737 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-64099892 passed
2025-10-13 03:06:01,738 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-64099892 in API_Server ======
2025-10-13 03:06:01,738 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:01,744 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:01,755 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:01,844 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dc471bdc-dd15-47d5-a914-19cddfde1a0b
2025-10-13 03:06:01,853 - DEBUG - =>VN ctest-trans_right_vn-64099892 has no policy to be verified
2025-10-13 03:06:01,853 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:01,853 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:01,853 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:01,866 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892?flat'}
2025-10-13 03:06:01,866 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:01,866 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:01,877 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892?flat'}
2025-10-13 03:06:01,877 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:01,877 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:01,889 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892?flat'}
2025-10-13 03:06:01,889 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-11126966:ctest-trans_right_vn-64099892 is found in opserver
2025-10-13 03:06:01,909 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:01,918 - DEBUG - VRF ids for VN ctest-trans_right_vn-64099892: {}
2025-10-13 03:06:03,027 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-1)
2025-10-13 03:06:04,410 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-2)
2025-10-13 03:06:04,410 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-21696721']
2025-10-13 03:06:04,702 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-21696721'] created succesfully
2025-10-13 03:06:05,018 - DEBUG - Image by name tiny_trans_fw not found
2025-10-13 03:06:05,018 - DEBUG - Installing image tiny_trans_fw
2025-10-13 03:06:05,018 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-10-13 03:06:05,018 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-13 03:06:05,018 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-13 03:06:05,052 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-13 03:06:05,102 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-13 03:06:05,102 - DEBUG - Unzip image
2025-10-13 03:06:05,102 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-13 03:06:05,131 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-13 03:06:05,132 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-21696721']
2025-10-13 03:06:05,228 - INFO - Service template: ['default-domain', 'ctest-service_template_1-21696721'] deleted successfully.
2025-10-13 03:06:05,228 - INFO - Skip interface_detach for VM ctest-right_vm-93240440 in state BUILD
2025-10-13 03:06:05,228 - INFO - Deleting VM ctest-right_vm-93240440
2025-10-13 03:06:05,228 - ERROR - VM ctest-right_vm-93240440 has failed to come up
2025-10-13 03:06:05,228 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:05,229 - ERROR - VM ctest-right_vm-93240440 has failed to come up
2025-10-13 03:06:05,229 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:05,313 - INFO - Skip interface_detach for VM ctest-left_vm-32544461 in state BUILD
2025-10-13 03:06:05,313 - INFO - Deleting VM ctest-left_vm-32544461
2025-10-13 03:06:05,313 - ERROR - VM ctest-left_vm-32544461 has failed to come up
2025-10-13 03:06:05,313 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:05,313 - ERROR - VM ctest-left_vm-32544461 has failed to come up
2025-10-13 03:06:05,313 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:05,436 - INFO - Deleting VN ctest-trans_right_vn-64099892
2025-10-13 03:06:05,591 - DEBUG - Response for deleting network ()
2025-10-13 03:06:05,591 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f3f815bb-5707-4e4b-8d18-7248b547cae6
2025-10-13 03:06:05,599 - DEBUG - Response Code: 404
2025-10-13 03:06:05,599 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:05,609 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:05,634 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:05,739 - INFO - Validated that VN ctest-trans_right_vn-64099892 is not found in API Server
2025-10-13 03:06:05,749 - DEBUG - VN ctest-trans_right_vn-64099892 is not present in Agent 10.0.0.249
2025-10-13 03:06:05,760 - DEBUG - VN ctest-trans_right_vn-64099892 is not present in Agent 10.0.0.27
2025-10-13 03:06:05,760 - INFO - Validated that VN ctest-trans_right_vn-64099892 is not in any agent
2025-10-13 03:06:05,795 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-64099892 info
2025-10-13 03:06:05,796 - INFO - Deleting VN ctest-trans_left_vn-43489479
2025-10-13 03:06:05,960 - DEBUG - Response for deleting network ()
2025-10-13 03:06:05,960 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/09fb36ef-acbc-45f9-bf5a-9693f8236c27
2025-10-13 03:06:05,966 - DEBUG - Response Code: 404
2025-10-13 03:06:05,966 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:05,972 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:05,983 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:06,080 - INFO - Validated that VN ctest-trans_left_vn-43489479 is not found in API Server
2025-10-13 03:06:06,090 - DEBUG - VN ctest-trans_left_vn-43489479 is not present in Agent 10.0.0.249
2025-10-13 03:06:06,110 - DEBUG - VN ctest-trans_left_vn-43489479 is not present in Agent 10.0.0.27
2025-10-13 03:06:06,110 - INFO - Validated that VN ctest-trans_left_vn-43489479 is not in any agent
2025-10-13 03:06:06,161 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-43489479 info
2025-10-13 03:06:06,161 - INFO - Deleting VN ctest-right_vn-16674873
2025-10-13 03:06:06,226 - DEBUG - VN 4fab1e5e-7706-48de-89eb-c6d356847b8c still in use: Unable to complete operation on network 4fab1e5e-7706-48de-89eb-c6d356847b8c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7e8352af-3c05-4c9a-89ca-07327faba1f2']
2025-10-13 03:06:06,226 - WARNING - Deleting VN ctest-right_vn-16674873 failed..Will retry
2025-10-13 03:06:08,361 - DEBUG - Response for deleting network ()
2025-10-13 03:06:08,361 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3a5d04c1-1c19-4e3b-8f5f-dc3bfd482770
2025-10-13 03:06:08,368 - DEBUG - Response Code: 404
2025-10-13 03:06:08,368 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:08,374 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:08,383 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:08,474 - INFO - Validated that VN ctest-right_vn-16674873 is not found in API Server
2025-10-13 03:06:08,483 - DEBUG - VN ctest-right_vn-16674873 is not present in Agent 10.0.0.249
2025-10-13 03:06:08,491 - DEBUG - VN ctest-right_vn-16674873 is not present in Agent 10.0.0.27
2025-10-13 03:06:08,491 - INFO - Validated that VN ctest-right_vn-16674873 is not in any agent
2025-10-13 03:06:08,516 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-16674873 info
2025-10-13 03:06:08,516 - INFO - Deleting VN ctest-left_vn-97579277
2025-10-13 03:06:08,656 - DEBUG - Response for deleting network ()
2025-10-13 03:06:08,656 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/526108dd-d7bf-426c-81cb-d8559a4f8c53
2025-10-13 03:06:08,662 - DEBUG - Response Code: 404
2025-10-13 03:06:08,662 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:08,667 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:08,725 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:08,810 - INFO - Validated that VN ctest-left_vn-97579277 is not found in API Server
2025-10-13 03:06:08,821 - DEBUG - VN ctest-left_vn-97579277 is not present in Agent 10.0.0.249
2025-10-13 03:06:08,830 - DEBUG - VN ctest-left_vn-97579277 is not present in Agent 10.0.0.27
2025-10-13 03:06:08,830 - INFO - Validated that VN ctest-left_vn-97579277 is not in any agent
2025-10-13 03:06:08,862 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-97579277 info
2025-10-13 03:06:08,862 - INFO - Deleting VN ctest-mgmt_vn-63110683
2025-10-13 03:06:09,010 - DEBUG - Response for deleting network ()
2025-10-13 03:06:09,010 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ea3b7e34-8d2c-4a5c-934f-d322b0b292da
2025-10-13 03:06:09,016 - DEBUG - Response Code: 404
2025-10-13 03:06:09,016 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:09,029 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:09,057 - DEBUG - Requesting: http://10.0.0.22:8082/project/8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:09,145 - INFO - Validated that VN ctest-mgmt_vn-63110683 is not found in API Server
2025-10-13 03:06:09,155 - DEBUG - VN ctest-mgmt_vn-63110683 is not present in Agent 10.0.0.249
2025-10-13 03:06:09,166 - DEBUG - VN ctest-mgmt_vn-63110683 is not present in Agent 10.0.0.27
2025-10-13 03:06:09,166 - INFO - Validated that VN ctest-mgmt_vn-63110683 is not in any agent
2025-10-13 03:06:09,199 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-63110683 info
2025-10-13 03:06:10,462 - DEBUG - No XMPP flaps were noticed during the test
2025-10-13 03:06:10,462 - INFO -
2025-10-13 03:06:10,462 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-10-13 03:06:10,462 - INFO - --------------------------------------------------------------------------------
2025-10-13 03:06:11,179 - INFO - Deleted project: ctest-TestSvcRegr-11126966, ID : 8aff1669-a142-4f09-985f-70a6fa046c6a
2025-10-13 03:06:11,333 - INFO - Domain Default found not creating
2025-10-13 03:06:11,478 - INFO - Project ctest-TestSvcRegr-09761367 not found, creating it
2025-10-13 03:06:11,987 - INFO - Created Project:ctest-TestSvcRegr-09761367, ID : a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:13,434 - INFO - ================================================================================
2025-10-13 03:06:13,434 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-13 03:06:14,696 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.27': {'10.20.0.18': '0', '10.20.0.129': '0'}} with
2025-10-13 03:06:14,696 - INFO - Initial checks done. Running the testcase now
2025-10-13 03:06:14,696 - INFO -
2025-10-13 03:06:15,340 - DEBUG - Response for create_network : {'network': {'id': '0babceed-4321-4bfd-81b8-6cb231a81964', 'name': 'ctest-mgmt_vn-33805057', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'project_id': 'a1decea466df4b1794f2165223103ef5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-09761367', 'ctest-mgmt_vn-33805057'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:15,595 - DEBUG - Response for create_subnet : {'subnet': {'id': '8baae3d5-d4af-4972-904c-c5bafd1f9ec2', 'name': '', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'network_id': '0babceed-4321-4bfd-81b8-6cb231a81964', 'ip_version': 4, 'cidr': '68.182.164.64/26', 'allocation_pools': [{'start': '68.182.164.66', 'end': '68.182.164.126'}], 'gateway_ip': '68.182.164.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '68.182.164.66', 'tags': [], 'project_id': 'a1decea466df4b1794f2165223103ef5'}}
2025-10-13 03:06:15,618 - INFO - Created VN ctest-mgmt_vn-33805057
2025-10-13 03:06:15,629 - DEBUG - VN ctest-mgmt_vn-33805057 UUID is 0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:15,704 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:15,995 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:16,023 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:16,038 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:16,124 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:16,143 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:16,153 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f13f01b9-81f8-4733-9bd2-9668b1158902
2025-10-13 03:06:16,162 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f13f01b9-81f8-4733-9bd2-9668b1158902
2025-10-13 03:06:16,169 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/3858236a-29ae-4cbf-a218-d08682968e63
2025-10-13 03:06:16,175 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:16,175 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:16,183 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f13f01b9-81f8-4733-9bd2-9668b1158902
2025-10-13 03:06:16,198 - INFO - Verified VN network id 13 for VN 0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:16,198 - INFO - Verifications in API Server for VN ctest-mgmt_vn-33805057 passed
2025-10-13 03:06:16,198 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:16,205 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f13f01b9-81f8-4733-9bd2-9668b1158902
2025-10-13 03:06:16,211 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f13f01b9-81f8-4733-9bd2-9668b1158902
2025-10-13 03:06:16,217 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/3858236a-29ae-4cbf-a218-d08682968e63
2025-10-13 03:06:16,230 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '840993273863556093', 'uuid-lslong': '9347340539200543076'}, 'enable': 'true', 'created': '2025-10-13T03:06:15', 'last-modified': '2025-10-13T03:06:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.680435'}
2025-10-13 03:06:16,237 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:16,242 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '840993273863556093', 'uuid-lslong': '9347340539200543076'}, 'enable': 'true', 'created': '2025-10-13T03:06:15', 'last-modified': '2025-10-13T03:06:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.690437'}
2025-10-13 03:06:16,248 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:16,254 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '840993273863556093', 'uuid-lslong': '9347340539200543076'}, 'enable': 'true', 'created': '2025-10-13T03:06:15', 'last-modified': '2025-10-13T03:06:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.707527'}
2025-10-13 03:06:16,259 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:16,259 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-33805057 passed
2025-10-13 03:06:16,259 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-33805057 in API_Server ======
2025-10-13 03:06:16,260 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:16,265 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:16,286 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:16,382 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0babceed-4321-4bfd-81b8-6cb231a81964
2025-10-13 03:06:16,392 - DEBUG - =>VN ctest-mgmt_vn-33805057 has no policy to be verified
2025-10-13 03:06:16,392 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:16,392 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:16,392 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:16,687 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:16,740 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057?flat'}
2025-10-13 03:06:16,740 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:16,740 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:17,040 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:17,097 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057?flat'}
2025-10-13 03:06:17,097 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:17,097 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:17,394 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:17,450 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057?flat'}
2025-10-13 03:06:17,450 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-33805057 is found in opserver
2025-10-13 03:06:17,470 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:17,480 - DEBUG - VRF ids for VN ctest-mgmt_vn-33805057: {}
2025-10-13 03:06:17,753 - DEBUG - Response for create_network : {'network': {'id': 'b1d99384-35d3-45f7-8056-9710328d4f09', 'name': 'service-left-vn-86053852', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'project_id': 'a1decea466df4b1794f2165223103ef5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-09761367', 'service-left-vn-86053852'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:17,999 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd69a0b3e-1ac2-411f-961c-dfe55664aacd', 'name': '', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'network_id': 'b1d99384-35d3-45f7-8056-9710328d4f09', 'ip_version': 4, 'cidr': '80.42.77.192/26', 'allocation_pools': [{'start': '80.42.77.194', 'end': '80.42.77.254'}], 'gateway_ip': '80.42.77.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '80.42.77.194', 'tags': [], 'project_id': 'a1decea466df4b1794f2165223103ef5'}}
2025-10-13 03:06:18,019 - INFO - Created VN service-left-vn-86053852
2025-10-13 03:06:18,033 - DEBUG - VN service-left-vn-86053852 UUID is b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,098 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:18,105 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:18,116 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:18,211 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,221 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,229 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f664ae91-1ae9-422b-be0e-f1ef1275470d
2025-10-13 03:06:18,236 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f664ae91-1ae9-422b-be0e-f1ef1275470d
2025-10-13 03:06:18,242 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/6e4cff45-928c-4194-9010-0e00e99e9af1
2025-10-13 03:06:18,248 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:18,248 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,255 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f664ae91-1ae9-422b-be0e-f1ef1275470d
2025-10-13 03:06:18,270 - INFO - Verified VN network id 14 for VN b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,270 - INFO - Verifications in API Server for VN service-left-vn-86053852 passed
2025-10-13 03:06:18,270 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,278 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f664ae91-1ae9-422b-be0e-f1ef1275470d
2025-10-13 03:06:18,285 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f664ae91-1ae9-422b-be0e-f1ef1275470d
2025-10-13 03:06:18,291 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/6e4cff45-928c-4194-9010-0e00e99e9af1
2025-10-13 03:06:18,302 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12815436410707461623', 'uuid-lslong': '9247744980675284745'}, 'enable': 'true', 'created': '2025-10-13T03:06:17', 'last-modified': '2025-10-13T03:06:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.337927'}
2025-10-13 03:06:18,307 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:18,312 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12815436410707461623', 'uuid-lslong': '9247744980675284745'}, 'enable': 'true', 'created': '2025-10-13T03:06:17', 'last-modified': '2025-10-13T03:06:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.357385'}
2025-10-13 03:06:18,317 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:18,324 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12815436410707461623', 'uuid-lslong': '9247744980675284745'}, 'enable': 'true', 'created': '2025-10-13T03:06:17', 'last-modified': '2025-10-13T03:06:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364194'}
2025-10-13 03:06:18,329 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:18,329 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-86053852 passed
2025-10-13 03:06:18,330 - DEBUG - ====Verifying policy data for service-left-vn-86053852 in API_Server ======
2025-10-13 03:06:18,330 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:18,337 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:18,349 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:18,455 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b1d99384-35d3-45f7-8056-9710328d4f09
2025-10-13 03:06:18,465 - DEBUG - =>VN service-left-vn-86053852 has no policy to be verified
2025-10-13 03:06:18,465 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:18,465 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:18,465 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:18,517 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852?flat'}
2025-10-13 03:06:18,517 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:18,517 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:18,573 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852?flat'}
2025-10-13 03:06:18,573 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:18,573 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:18,625 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852?flat'}
2025-10-13 03:06:18,625 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-09761367:service-left-vn-86053852 is found in opserver
2025-10-13 03:06:18,644 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:18,653 - DEBUG - VRF ids for VN service-left-vn-86053852: {}
2025-10-13 03:06:18,914 - DEBUG - Response for create_network : {'network': {'id': '752cf5ef-29d3-4036-8a47-07718ae1d549', 'name': 'service-right-vn-36427783', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'project_id': 'a1decea466df4b1794f2165223103ef5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-09761367', 'service-right-vn-36427783'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:19,111 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f56de626-ab58-4cd6-8295-84eeccf3ebd8', 'name': '', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'network_id': '752cf5ef-29d3-4036-8a47-07718ae1d549', 'ip_version': 4, 'cidr': '107.12.37.0/26', 'allocation_pools': [{'start': '107.12.37.2', 'end': '107.12.37.62'}], 'gateway_ip': '107.12.37.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '107.12.37.2', 'tags': [], 'project_id': 'a1decea466df4b1794f2165223103ef5'}}
2025-10-13 03:06:19,130 - INFO - Created VN service-right-vn-36427783
2025-10-13 03:06:19,141 - DEBUG - VN service-right-vn-36427783 UUID is 752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,214 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:19,222 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:19,233 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:19,321 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,331 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,339 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f72a9867-d513-4159-a104-1dc545d935e9
2025-10-13 03:06:19,350 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f72a9867-d513-4159-a104-1dc545d935e9
2025-10-13 03:06:19,356 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/82340ee7-208b-4b1a-b692-978cbd70d00b
2025-10-13 03:06:19,363 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:19,363 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,371 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f72a9867-d513-4159-a104-1dc545d935e9
2025-10-13 03:06:19,385 - INFO - Verified VN network id 15 for VN 752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,385 - INFO - Verifications in API Server for VN service-right-vn-36427783 passed
2025-10-13 03:06:19,385 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,396 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f72a9867-d513-4159-a104-1dc545d935e9
2025-10-13 03:06:19,402 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f72a9867-d513-4159-a104-1dc545d935e9
2025-10-13 03:06:19,408 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/82340ee7-208b-4b1a-b692-978cbd70d00b
2025-10-13 03:06:19,419 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8443393808960536630', 'uuid-lslong': '9963940884823266633'}, 'enable': 'true', 'created': '2025-10-13T03:06:18', 'last-modified': '2025-10-13T03:06:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.358290'}
2025-10-13 03:06:19,425 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:19,429 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8443393808960536630', 'uuid-lslong': '9963940884823266633'}, 'enable': 'true', 'created': '2025-10-13T03:06:18', 'last-modified': '2025-10-13T03:06:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370153'}
2025-10-13 03:06:19,435 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:19,440 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8443393808960536630', 'uuid-lslong': '9963940884823266633'}, 'enable': 'true', 'created': '2025-10-13T03:06:18', 'last-modified': '2025-10-13T03:06:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.379985'}
2025-10-13 03:06:19,446 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:19,446 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-36427783 passed
2025-10-13 03:06:19,446 - DEBUG - ====Verifying policy data for service-right-vn-36427783 in API_Server ======
2025-10-13 03:06:19,446 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:19,453 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:19,463 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:19,563 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/752cf5ef-29d3-4036-8a47-07718ae1d549
2025-10-13 03:06:19,575 - DEBUG - =>VN service-right-vn-36427783 has no policy to be verified
2025-10-13 03:06:19,575 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:19,575 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:19,575 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:19,635 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783?flat'}
2025-10-13 03:06:19,635 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:19,635 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:19,685 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783?flat'}
2025-10-13 03:06:19,685 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:19,685 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:19,733 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783?flat'}
2025-10-13 03:06:19,733 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-09761367:service-right-vn-36427783 is found in opserver
2025-10-13 03:06:19,753 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:19,762 - DEBUG - VRF ids for VN service-right-vn-36427783: {}
2025-10-13 03:06:19,812 - DEBUG - Services list from nova: [, , , ]
2025-10-13 03:06:20,799 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-1)
2025-10-13 03:06:22,002 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-2)
2025-10-13 03:06:22,002 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-71675799']
2025-10-13 03:06:22,299 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-71675799'] created succesfully
2025-10-13 03:06:22,721 - DEBUG - Image by name tiny_nat_fw not found
2025-10-13 03:06:22,721 - DEBUG - Installing image tiny_nat_fw
2025-10-13 03:06:22,721 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-13 03:06:22,721 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:06:22,721 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:06:22,721 - DEBUG - Unzip image
2025-10-13 03:06:22,721 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:06:22,742 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-13 03:06:22,742 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-71675799']
2025-10-13 03:06:22,844 - INFO - Service template: ['default-domain', 'ctest-service_template_1-71675799'] deleted successfully.
2025-10-13 03:06:22,845 - INFO - Skip interface_detach for VM ctest-right_vm-28686835 in state BUILD
2025-10-13 03:06:22,845 - INFO - Deleting VM ctest-right_vm-28686835
2025-10-13 03:06:22,899 - ERROR - VM ctest-right_vm-28686835 has failed to come up
2025-10-13 03:06:22,900 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:22,900 - ERROR - VM ctest-right_vm-28686835 has failed to come up
2025-10-13 03:06:22,900 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:22,969 - INFO - Skip interface_detach for VM ctest-left_vm-09901171 in state BUILD
2025-10-13 03:06:22,969 - INFO - Deleting VM ctest-left_vm-09901171
2025-10-13 03:06:22,970 - ERROR - VM ctest-left_vm-09901171 has failed to come up
2025-10-13 03:06:22,970 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:22,970 - ERROR - VM ctest-left_vm-09901171 has failed to come up
2025-10-13 03:06:22,970 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:23,032 - INFO - Deleting VN service-right-vn-36427783
2025-10-13 03:06:23,078 - DEBUG - VN 752cf5ef-29d3-4036-8a47-07718ae1d549 still in use: Unable to complete operation on network 752cf5ef-29d3-4036-8a47-07718ae1d549. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-baf6b641-bd58-47b1-bec6-4cfc4038701f']
2025-10-13 03:06:23,078 - WARNING - Deleting VN service-right-vn-36427783 failed..Will retry
2025-10-13 03:06:25,269 - DEBUG - Response for deleting network ()
2025-10-13 03:06:25,269 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f72a9867-d513-4159-a104-1dc545d935e9
2025-10-13 03:06:25,278 - DEBUG - Response Code: 404
2025-10-13 03:06:25,278 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:25,288 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:25,343 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:25,430 - INFO - Validated that VN service-right-vn-36427783 is not found in API Server
2025-10-13 03:06:25,440 - DEBUG - VN service-right-vn-36427783 is not present in Agent 10.0.0.249
2025-10-13 03:06:25,449 - DEBUG - VN service-right-vn-36427783 is not present in Agent 10.0.0.27
2025-10-13 03:06:25,449 - INFO - Validated that VN service-right-vn-36427783 is not in any agent
2025-10-13 03:06:25,475 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-36427783 info
2025-10-13 03:06:25,475 - INFO - Deleting VN service-left-vn-86053852
2025-10-13 03:06:25,632 - DEBUG - Response for deleting network ()
2025-10-13 03:06:25,632 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f664ae91-1ae9-422b-be0e-f1ef1275470d
2025-10-13 03:06:25,637 - DEBUG - Response Code: 404
2025-10-13 03:06:25,637 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:25,641 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:25,668 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:25,761 - INFO - Validated that VN service-left-vn-86053852 is not found in API Server
2025-10-13 03:06:25,773 - DEBUG - VN service-left-vn-86053852 is not present in Agent 10.0.0.249
2025-10-13 03:06:25,784 - DEBUG - VN service-left-vn-86053852 is not present in Agent 10.0.0.27
2025-10-13 03:06:25,784 - INFO - Validated that VN service-left-vn-86053852 is not in any agent
2025-10-13 03:06:25,817 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-86053852 info
2025-10-13 03:06:25,817 - INFO - Deleting VN ctest-mgmt_vn-33805057
2025-10-13 03:06:25,967 - DEBUG - Response for deleting network ()
2025-10-13 03:06:25,967 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f13f01b9-81f8-4733-9bd2-9668b1158902
2025-10-13 03:06:25,973 - DEBUG - Response Code: 404
2025-10-13 03:06:25,973 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:25,978 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:25,988 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:26,085 - INFO - Validated that VN ctest-mgmt_vn-33805057 is not found in API Server
2025-10-13 03:06:26,097 - DEBUG - VN ctest-mgmt_vn-33805057 is not present in Agent 10.0.0.249
2025-10-13 03:06:26,106 - DEBUG - VN ctest-mgmt_vn-33805057 is not present in Agent 10.0.0.27
2025-10-13 03:06:26,107 - INFO - Validated that VN ctest-mgmt_vn-33805057 is not in any agent
2025-10-13 03:06:26,135 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-33805057 info
2025-10-13 03:06:27,405 - DEBUG - No XMPP flaps were noticed during the test
2025-10-13 03:06:27,405 - INFO -
2025-10-13 03:06:27,405 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2025-10-13 03:06:27,406 - INFO - --------------------------------------------------------------------------------
2025-10-13 03:06:27,409 - INFO - ================================================================================
2025-10-13 03:06:27,409 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-13 03:06:28,672 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.27': {'10.20.0.18': '0', '10.20.0.129': '0'}} with
2025-10-13 03:06:28,672 - INFO - Initial checks done. Running the testcase now
2025-10-13 03:06:28,672 - INFO -
2025-10-13 03:06:28,925 - DEBUG - Response for create_network : {'network': {'id': '6d78bd57-488c-43e1-953c-167f5208d02f', 'name': 'ctest-mgmt_vn-72294151', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'project_id': 'a1decea466df4b1794f2165223103ef5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-09761367', 'ctest-mgmt_vn-72294151'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:29,125 - DEBUG - Response for create_subnet : {'subnet': {'id': '8635c039-4975-42d7-8a5f-07d86bcff193', 'name': '', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'network_id': '6d78bd57-488c-43e1-953c-167f5208d02f', 'ip_version': 4, 'cidr': '146.130.96.64/26', 'allocation_pools': [{'start': '146.130.96.66', 'end': '146.130.96.126'}], 'gateway_ip': '146.130.96.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.130.96.66', 'tags': [], 'project_id': 'a1decea466df4b1794f2165223103ef5'}}
2025-10-13 03:06:29,146 - INFO - Created VN ctest-mgmt_vn-72294151
2025-10-13 03:06:29,156 - DEBUG - VN ctest-mgmt_vn-72294151 UUID is 6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,225 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:29,232 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:29,244 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:29,341 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,354 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,363 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1cd82c68-ecf1-40c2-8c26-249a90f9910d
2025-10-13 03:06:29,372 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1cd82c68-ecf1-40c2-8c26-249a90f9910d
2025-10-13 03:06:29,378 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4546f722-75dc-4658-8187-b95123f9fe98
2025-10-13 03:06:29,384 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:29,384 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,391 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1cd82c68-ecf1-40c2-8c26-249a90f9910d
2025-10-13 03:06:29,407 - INFO - Verified VN network id 13 for VN 6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,407 - INFO - Verifications in API Server for VN ctest-mgmt_vn-72294151 passed
2025-10-13 03:06:29,407 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,417 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1cd82c68-ecf1-40c2-8c26-249a90f9910d
2025-10-13 03:06:29,423 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1cd82c68-ecf1-40c2-8c26-249a90f9910d
2025-10-13 03:06:29,431 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4546f722-75dc-4658-8187-b95123f9fe98
2025-10-13 03:06:29,442 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7888262929916380129', 'uuid-lslong': '10753494746346868783'}, 'enable': 'true', 'created': '2025-10-13T03:06:28', 'last-modified': '2025-10-13T03:06:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.359802'}
2025-10-13 03:06:29,448 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:29,453 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7888262929916380129', 'uuid-lslong': '10753494746346868783'}, 'enable': 'true', 'created': '2025-10-13T03:06:28', 'last-modified': '2025-10-13T03:06:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384336'}
2025-10-13 03:06:29,459 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:29,464 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7888262929916380129', 'uuid-lslong': '10753494746346868783'}, 'enable': 'true', 'created': '2025-10-13T03:06:28', 'last-modified': '2025-10-13T03:06:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393346'}
2025-10-13 03:06:29,474 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-13 03:06:29,474 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-72294151 passed
2025-10-13 03:06:29,474 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-72294151 in API_Server ======
2025-10-13 03:06:29,474 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:29,480 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:29,491 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:29,590 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/6d78bd57-488c-43e1-953c-167f5208d02f
2025-10-13 03:06:29,600 - DEBUG - =>VN ctest-mgmt_vn-72294151 has no policy to be verified
2025-10-13 03:06:29,600 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:29,600 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:29,601 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:29,612 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151?flat'}
2025-10-13 03:06:29,612 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:29,612 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:29,625 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151?flat'}
2025-10-13 03:06:29,625 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:29,625 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:29,636 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151?flat'}
2025-10-13 03:06:29,636 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-09761367:ctest-mgmt_vn-72294151 is found in opserver
2025-10-13 03:06:29,655 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:29,665 - DEBUG - VRF ids for VN ctest-mgmt_vn-72294151: {}
2025-10-13 03:06:29,932 - DEBUG - Response for create_network : {'network': {'id': '1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3', 'name': 'ctest-left_vn-52313585', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'project_id': 'a1decea466df4b1794f2165223103ef5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-09761367', 'ctest-left_vn-52313585'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:30,193 - DEBUG - Response for create_subnet : {'subnet': {'id': '32e462e1-0fb5-46f4-8052-e08f707e16b3', 'name': '', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'network_id': '1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3', 'ip_version': 4, 'cidr': '120.61.189.64/26', 'allocation_pools': [{'start': '120.61.189.66', 'end': '120.61.189.126'}], 'gateway_ip': '120.61.189.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.61.189.66', 'tags': [], 'project_id': 'a1decea466df4b1794f2165223103ef5'}}
2025-10-13 03:06:30,220 - INFO - Created VN ctest-left_vn-52313585
2025-10-13 03:06:30,233 - DEBUG - VN ctest-left_vn-52313585 UUID is 1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,306 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:30,312 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:30,321 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:30,416 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,428 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,439 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7dcae406-11da-44f4-8e5e-c93cdc7d7601
2025-10-13 03:06:30,454 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7dcae406-11da-44f4-8e5e-c93cdc7d7601
2025-10-13 03:06:30,462 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/8d459ea3-2f51-42a5-8eb6-ab0eedf5f0b5
2025-10-13 03:06:30,470 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:30,470 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,480 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7dcae406-11da-44f4-8e5e-c93cdc7d7601
2025-10-13 03:06:30,500 - INFO - Verified VN network id 14 for VN 1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,501 - INFO - Verifications in API Server for VN ctest-left_vn-52313585 passed
2025-10-13 03:06:30,501 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,509 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7dcae406-11da-44f4-8e5e-c93cdc7d7601
2025-10-13 03:06:30,520 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7dcae406-11da-44f4-8e5e-c93cdc7d7601
2025-10-13 03:06:30,526 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/8d459ea3-2f51-42a5-8eb6-ab0eedf5f0b5
2025-10-13 03:06:30,538 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2165805449917451433', 'uuid-lslong': '11600959002058278371'}, 'enable': 'true', 'created': '2025-10-13T03:06:29', 'last-modified': '2025-10-13T03:06:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413547'}
2025-10-13 03:06:30,544 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:30,550 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2165805449917451433', 'uuid-lslong': '11600959002058278371'}, 'enable': 'true', 'created': '2025-10-13T03:06:29', 'last-modified': '2025-10-13T03:06:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428730'}
2025-10-13 03:06:30,556 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:30,560 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2165805449917451433', 'uuid-lslong': '11600959002058278371'}, 'enable': 'true', 'created': '2025-10-13T03:06:29', 'last-modified': '2025-10-13T03:06:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435842'}
2025-10-13 03:06:30,572 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-13 03:06:30,572 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-52313585 passed
2025-10-13 03:06:30,572 - DEBUG - ====Verifying policy data for ctest-left_vn-52313585 in API_Server ======
2025-10-13 03:06:30,573 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:30,578 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:30,588 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:30,684 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1e0e7c94-eeef-4ca9-a0fe-e2bf738dc5e3
2025-10-13 03:06:30,696 - DEBUG - =>VN ctest-left_vn-52313585 has no policy to be verified
2025-10-13 03:06:30,696 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:30,696 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:30,696 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:30,711 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585?flat'}
2025-10-13 03:06:30,711 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:30,712 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:30,722 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585?flat'}
2025-10-13 03:06:30,722 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:30,722 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:30,732 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585?flat'}
2025-10-13 03:06:30,732 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-09761367:ctest-left_vn-52313585 is found in opserver
2025-10-13 03:06:30,752 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:30,760 - DEBUG - VRF ids for VN ctest-left_vn-52313585: {}
2025-10-13 03:06:31,026 - DEBUG - Response for create_network : {'network': {'id': 'e1c8e5bc-6b23-4a14-947b-c51522f6c3d1', 'name': 'ctest-right_vn-88020363', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'project_id': 'a1decea466df4b1794f2165223103ef5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-09761367', 'ctest-right_vn-88020363'], 'port_security_enabled': True, 'description': ''}}
2025-10-13 03:06:31,284 - DEBUG - Response for create_subnet : {'subnet': {'id': '1ebac939-59a1-4516-b9b7-61e705fb6405', 'name': '', 'tenant_id': 'a1decea466df4b1794f2165223103ef5', 'network_id': 'e1c8e5bc-6b23-4a14-947b-c51522f6c3d1', 'ip_version': 4, 'cidr': '84.186.198.128/26', 'allocation_pools': [{'start': '84.186.198.130', 'end': '84.186.198.190'}], 'gateway_ip': '84.186.198.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '84.186.198.130', 'tags': [], 'project_id': 'a1decea466df4b1794f2165223103ef5'}}
2025-10-13 03:06:31,305 - INFO - Created VN ctest-right_vn-88020363
2025-10-13 03:06:31,323 - DEBUG - VN ctest-right_vn-88020363 UUID is e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,406 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:31,413 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:31,422 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:31,516 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,527 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,537 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b68045c8-9bb2-48ce-a8e7-f63ff66234b9
2025-10-13 03:06:31,542 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b68045c8-9bb2-48ce-a8e7-f63ff66234b9
2025-10-13 03:06:31,548 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/404c330a-774e-480c-a3a0-b8610abb7838
2025-10-13 03:06:31,554 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:31,554 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,561 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b68045c8-9bb2-48ce-a8e7-f63ff66234b9
2025-10-13 03:06:31,577 - INFO - Verified VN network id 15 for VN e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,577 - INFO - Verifications in API Server for VN ctest-right_vn-88020363 passed
2025-10-13 03:06:31,578 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,586 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b68045c8-9bb2-48ce-a8e7-f63ff66234b9
2025-10-13 03:06:31,592 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b68045c8-9bb2-48ce-a8e7-f63ff66234b9
2025-10-13 03:06:31,598 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/404c330a-774e-480c-a3a0-b8610abb7838
2025-10-13 03:06:31,615 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16269506251290003988', 'uuid-lslong': '10699362034320327633'}, 'enable': 'true', 'created': '2025-10-13T03:06:30', 'last-modified': '2025-10-13T03:06:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.386288'}
2025-10-13 03:06:31,620 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:31,625 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16269506251290003988', 'uuid-lslong': '10699362034320327633'}, 'enable': 'true', 'created': '2025-10-13T03:06:30', 'last-modified': '2025-10-13T03:06:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419960'}
2025-10-13 03:06:31,632 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:31,638 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-09761367', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16269506251290003988', 'uuid-lslong': '10699362034320327633'}, 'enable': 'true', 'created': '2025-10-13T03:06:30', 'last-modified': '2025-10-13T03:06:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420862'}
2025-10-13 03:06:31,644 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-13 03:06:31,644 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-88020363 passed
2025-10-13 03:06:31,644 - DEBUG - ====Verifying policy data for ctest-right_vn-88020363 in API_Server ======
2025-10-13 03:06:31,644 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:31,650 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:31,663 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:31,760 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e1c8e5bc-6b23-4a14-947b-c51522f6c3d1
2025-10-13 03:06:31,768 - DEBUG - =>VN ctest-right_vn-88020363 has no policy to be verified
2025-10-13 03:06:31,768 - DEBUG - Verifying the vn in opserver
2025-10-13 03:06:31,768 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363 virtual network link through opserver 10.0.0.22
2025-10-13 03:06:31,768 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-13 03:06:31,779 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363?flat'}
2025-10-13 03:06:31,779 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363 virtual network link through opserver 10.0.0.19
2025-10-13 03:06:31,779 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-13 03:06:31,791 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363?flat'}
2025-10-13 03:06:31,791 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363 virtual network link through opserver 10.0.0.41
2025-10-13 03:06:31,791 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-13 03:06:31,802 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363?flat'}
2025-10-13 03:06:31,802 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-09761367:ctest-right_vn-88020363 is found in opserver
2025-10-13 03:06:31,822 - DEBUG - Do not have enough data to verify VN in agent
2025-10-13 03:06:31,831 - DEBUG - VRF ids for VN ctest-right_vn-88020363: {}
2025-10-13 03:06:32,866 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-2)
2025-10-13 03:06:34,367 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-1)
2025-10-13 03:06:34,368 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-57998151']
2025-10-13 03:06:34,663 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-57998151'] created succesfully
2025-10-13 03:06:35,002 - DEBUG - Image by name tiny_nat_fw not found
2025-10-13 03:06:35,002 - DEBUG - Installing image tiny_nat_fw
2025-10-13 03:06:35,003 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-13 03:06:35,003 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:06:35,003 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:06:35,003 - DEBUG - Unzip image
2025-10-13 03:06:35,003 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-13 03:06:35,023 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-13 03:06:35,023 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-57998151']
2025-10-13 03:06:35,124 - INFO - Service template: ['default-domain', 'ctest-service_template_1-57998151'] deleted successfully.
2025-10-13 03:06:35,124 - INFO - Skip interface_detach for VM ctest-right_vm-13396554 in state BUILD
2025-10-13 03:06:35,124 - INFO - Deleting VM ctest-right_vm-13396554
2025-10-13 03:06:35,125 - ERROR - VM ctest-right_vm-13396554 has failed to come up
2025-10-13 03:06:35,125 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:35,125 - ERROR - VM ctest-right_vm-13396554 has failed to come up
2025-10-13 03:06:35,125 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:35,233 - INFO - Skip interface_detach for VM ctest-left_vm-95630877 in state BUILD
2025-10-13 03:06:35,233 - INFO - Deleting VM ctest-left_vm-95630877
2025-10-13 03:06:35,234 - ERROR - VM ctest-left_vm-95630877 has failed to come up
2025-10-13 03:06:35,234 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:35,234 - ERROR - VM ctest-left_vm-95630877 has failed to come up
2025-10-13 03:06:35,234 - ERROR - Nova failed to get host of the VM
2025-10-13 03:06:35,304 - INFO - Deleting VN ctest-right_vn-88020363
2025-10-13 03:06:35,353 - DEBUG - VN e1c8e5bc-6b23-4a14-947b-c51522f6c3d1 still in use: Unable to complete operation on network e1c8e5bc-6b23-4a14-947b-c51522f6c3d1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7b424550-ebae-493a-b3d2-a5d4fba1cc8b']
2025-10-13 03:06:35,353 - WARNING - Deleting VN ctest-right_vn-88020363 failed..Will retry
2025-10-13 03:06:37,504 - DEBUG - Response for deleting network ()
2025-10-13 03:06:37,505 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b68045c8-9bb2-48ce-a8e7-f63ff66234b9
2025-10-13 03:06:37,514 - DEBUG - Response Code: 404
2025-10-13 03:06:37,515 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:37,528 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:37,643 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:37,739 - INFO - Validated that VN ctest-right_vn-88020363 is not found in API Server
2025-10-13 03:06:37,749 - DEBUG - VN ctest-right_vn-88020363 is not present in Agent 10.0.0.249
2025-10-13 03:06:37,758 - DEBUG - VN ctest-right_vn-88020363 is not present in Agent 10.0.0.27
2025-10-13 03:06:37,758 - INFO - Validated that VN ctest-right_vn-88020363 is not in any agent
2025-10-13 03:06:37,783 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-88020363 info
2025-10-13 03:06:37,783 - INFO - Deleting VN ctest-left_vn-52313585
2025-10-13 03:06:37,924 - DEBUG - Response for deleting network ()
2025-10-13 03:06:37,924 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7dcae406-11da-44f4-8e5e-c93cdc7d7601
2025-10-13 03:06:37,930 - DEBUG - Response Code: 404
2025-10-13 03:06:37,930 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:37,937 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:37,952 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:38,047 - INFO - Validated that VN ctest-left_vn-52313585 is not found in API Server
2025-10-13 03:06:38,056 - DEBUG - VN ctest-left_vn-52313585 is not present in Agent 10.0.0.249
2025-10-13 03:06:38,066 - DEBUG - VN ctest-left_vn-52313585 is not present in Agent 10.0.0.27
2025-10-13 03:06:38,066 - INFO - Validated that VN ctest-left_vn-52313585 is not in any agent
2025-10-13 03:06:38,093 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-52313585 info
2025-10-13 03:06:38,093 - INFO - Deleting VN ctest-mgmt_vn-72294151
2025-10-13 03:06:38,257 - DEBUG - Response for deleting network ()
2025-10-13 03:06:38,257 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1cd82c68-ecf1-40c2-8c26-249a90f9910d
2025-10-13 03:06:38,269 - DEBUG - Response Code: 404
2025-10-13 03:06:38,269 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-13 03:06:38,277 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c
2025-10-13 03:06:38,330 - DEBUG - Requesting: http://10.0.0.22:8082/project/a1decea4-66df-4b17-94f2-165223103ef5
2025-10-13 03:06:38,426 - INFO - Validated that VN ctest-mgmt_vn-72294151 is not found in API Server
2025-10-13 03:06:38,437 - DEBUG - VN ctest-mgmt_vn-72294151 is not present in Agent 10.0.0.249
2025-10-13 03:06:38,446 - DEBUG - VN ctest-mgmt_vn-72294151 is not present in Agent 10.0.0.27
2025-10-13 03:06:38,446 - INFO - Validated that VN ctest-mgmt_vn-72294151 is not in any agent
2025-10-13 03:06:38,476 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-72294151 info
2025-10-13 03:06:39,749 - DEBUG - No XMPP flaps were noticed during the test
2025-10-13 03:06:39,749 - INFO -
2025-10-13 03:06:39,750 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-10-13 03:06:39,750 - INFO - --------------------------------------------------------------------------------
2025-10-13 03:06:40,462 - INFO - Deleted project: ctest-TestSvcRegr-09761367, ID : a1decea4-66df-4b17-94f2-165223103ef5