]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2)
2025-10-23 03:09:16,682 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-47141838']
2025-10-23 03:09:16,975 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-47141838'] created succesfully
2025-10-23 03:09:17,384 - DEBUG - Image by name tiny_nat_fw not found
2025-10-23 03:09:17,384 - DEBUG - Installing image tiny_nat_fw
2025-10-23 03:09:17,384 - 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-23 03:09:17,385 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:17,385 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:17,419 - 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-23 03:09:17,529 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:17,529 - DEBUG - Unzip image
2025-10-23 03:09:17,529 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:17,561 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-23 03:09:17,562 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-47141838']
2025-10-23 03:09:17,664 - INFO - Service template: ['default-domain', 'ctest-service_template_1-47141838'] deleted successfully.
2025-10-23 03:09:17,664 - INFO - Skip interface_detach for VM ctest-right_vm-59557304 in state BUILD
2025-10-23 03:09:17,664 - INFO - Deleting VM ctest-right_vm-59557304
2025-10-23 03:09:17,760 - ERROR - VM ctest-right_vm-59557304 has failed to come up
2025-10-23 03:09:17,760 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:17,760 - ERROR - VM ctest-right_vm-59557304 has failed to come up
2025-10-23 03:09:17,760 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:17,862 - INFO - Skip interface_detach for VM ctest-left_vm-31945929 in state BUILD
2025-10-23 03:09:17,862 - INFO - Deleting VM ctest-left_vm-31945929
2025-10-23 03:09:17,862 - ERROR - VM ctest-left_vm-31945929 has failed to come up
2025-10-23 03:09:17,862 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:17,862 - ERROR - VM ctest-left_vm-31945929 has failed to come up
2025-10-23 03:09:17,862 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:17,977 - INFO - Deleting VN ctest-right_vn-46742162
2025-10-23 03:09:18,026 - DEBUG - VN 9cd3ef3e-ea59-4c26-a831-72fb370e59a0 still in use: Unable to complete operation on network 9cd3ef3e-ea59-4c26-a831-72fb370e59a0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-fe12a17e-487e-4aa2-8665-7d4dc66e4ae0']
2025-10-23 03:09:18,026 - WARNING - Deleting VN ctest-right_vn-46742162 failed..Will retry
2025-10-23 03:09:20,261 - DEBUG - Response for deleting network ()
2025-10-23 03:09:20,262 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/ee9289c1-79cc-4b60-8e23-01c11a5af5b6
2025-10-23 03:09:20,272 - DEBUG - Response Code: 404
2025-10-23 03:09:20,273 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:20,286 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:20,316 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:20,423 - INFO - Validated that VN ctest-right_vn-46742162 is not found in API Server
2025-10-23 03:09:20,435 - DEBUG - VN ctest-right_vn-46742162 is not present in Agent 10.0.0.48
2025-10-23 03:09:20,446 - DEBUG - VN ctest-right_vn-46742162 is not present in Agent 10.0.0.49
2025-10-23 03:09:20,446 - INFO - Validated that VN ctest-right_vn-46742162 is not in any agent
2025-10-23 03:09:20,480 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-46742162 info
2025-10-23 03:09:20,480 - INFO - Deleting VN ctest-left_vn-56604699
2025-10-23 03:09:20,661 - DEBUG - Response for deleting network ()
2025-10-23 03:09:20,661 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/dee0cc11-1a51-4602-a700-d6b7fa634f1a
2025-10-23 03:09:20,675 - DEBUG - Response Code: 404
2025-10-23 03:09:20,675 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:20,683 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:20,717 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:20,816 - INFO - Validated that VN ctest-left_vn-56604699 is not found in API Server
2025-10-23 03:09:20,829 - DEBUG - VN ctest-left_vn-56604699 is not present in Agent 10.0.0.48
2025-10-23 03:09:20,846 - DEBUG - VN ctest-left_vn-56604699 is not present in Agent 10.0.0.49
2025-10-23 03:09:20,847 - INFO - Validated that VN ctest-left_vn-56604699 is not in any agent
2025-10-23 03:09:20,884 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-56604699 info
2025-10-23 03:09:20,885 - INFO - Deleting VN ctest-mgmt_vn-21067034
2025-10-23 03:09:21,041 - DEBUG - Response for deleting network ()
2025-10-23 03:09:21,041 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f13787af-0033-43f0-a812-938a4e5ddead
2025-10-23 03:09:21,048 - DEBUG - Response Code: 404
2025-10-23 03:09:21,048 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:21,056 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:21,089 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:21,173 - INFO - Validated that VN ctest-mgmt_vn-21067034 is not found in API Server
2025-10-23 03:09:21,187 - DEBUG - VN ctest-mgmt_vn-21067034 is not present in Agent 10.0.0.48
2025-10-23 03:09:21,207 - DEBUG - VN ctest-mgmt_vn-21067034 is not present in Agent 10.0.0.49
2025-10-23 03:09:21,207 - INFO - Validated that VN ctest-mgmt_vn-21067034 is not in any agent
2025-10-23 03:09:21,240 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-21067034 info
2025-10-23 03:09:21,240 - INFO - Deleting VM ctest-vm-in-third-vn-70540281
2025-10-23 03:09:21,327 - INFO - Deleting VN ctest-third-vn-35859810
2025-10-23 03:09:21,374 - DEBUG - VN be8097b9-8bdb-4af3-93e7-72629a15c2e0 still in use: Unable to complete operation on network be8097b9-8bdb-4af3-93e7-72629a15c2e0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e3b16117-c61e-411f-9623-9dc5bd9eccba']
2025-10-23 03:09:21,374 - WARNING - Deleting VN ctest-third-vn-35859810 failed..Will retry
2025-10-23 03:09:23,543 - DEBUG - Response for deleting network ()
2025-10-23 03:09:24,847 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 03:09:24,847 - INFO -
2025-10-23 03:09:24,847 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-10-23 03:09:24,847 - INFO - --------------------------------------------------------------------------------
2025-10-23 03:09:24,850 - INFO - ================================================================================
2025-10-23 03:09:24,850 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-23 03:09:26,135 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.49': {'10.20.0.14': '0', '10.20.0.129': '0'}} with
2025-10-23 03:09:26,135 - INFO - Initial checks done. Running the testcase now
2025-10-23 03:09:26,135 - INFO -
2025-10-23 03:09:26,395 - DEBUG - Response for create_network : {'network': {'id': '8e52a232-8392-4d1a-b8ad-29021ee390a3', 'name': 'ctest-mgmt_vn-10853920', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'project_id': '2f203067a2f0452f97c476076398d539', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43935969', 'ctest-mgmt_vn-10853920'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:26,695 - DEBUG - Response for create_subnet : {'subnet': {'id': '561dac8a-d3b3-4ca9-b0ae-565549f77e70', 'name': '', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'network_id': '8e52a232-8392-4d1a-b8ad-29021ee390a3', 'ip_version': 4, 'cidr': '39.73.94.192/26', 'allocation_pools': [{'start': '39.73.94.194', 'end': '39.73.94.254'}], 'gateway_ip': '39.73.94.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '39.73.94.194', 'tags': [], 'project_id': '2f203067a2f0452f97c476076398d539'}}
2025-10-23 03:09:26,722 - INFO - Created VN ctest-mgmt_vn-10853920
2025-10-23 03:09:26,736 - DEBUG - VN ctest-mgmt_vn-10853920 UUID is 8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:26,815 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:26,822 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:26,835 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:26,933 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:26,945 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:26,955 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/4c7840a8-aecb-4693-a1a7-664077509409
2025-10-23 03:09:26,962 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/4c7840a8-aecb-4693-a1a7-664077509409
2025-10-23 03:09:26,969 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/08cad4df-811f-4eeb-8106-cdfff8c0a5df
2025-10-23 03:09:26,976 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:26,976 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:26,985 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/4c7840a8-aecb-4693-a1a7-664077509409
2025-10-23 03:09:26,998 - INFO - Verified VN network id 15 for VN 8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:26,998 - INFO - Verifications in API Server for VN ctest-mgmt_vn-10853920 passed
2025-10-23 03:09:26,999 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:27,009 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/4c7840a8-aecb-4693-a1a7-664077509409
2025-10-23 03:09:27,016 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/4c7840a8-aecb-4693-a1a7-664077509409
2025-10-23 03:09:27,028 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/08cad4df-811f-4eeb-8106-cdfff8c0a5df
2025-10-23 03:09:27,041 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10255437639315508506', 'uuid-lslong': '13307337563034587299'}, 'enable': 'true', 'created': '2025-10-23T03:09:26', 'last-modified': '2025-10-23T03:09:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.423198'}
2025-10-23 03:09:27,048 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:27,055 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10255437639315508506', 'uuid-lslong': '13307337563034587299'}, 'enable': 'true', 'created': '2025-10-23T03:09:26', 'last-modified': '2025-10-23T03:09:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.434780'}
2025-10-23 03:09:27,062 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:27,067 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10255437639315508506', 'uuid-lslong': '13307337563034587299'}, 'enable': 'true', 'created': '2025-10-23T03:09:26', 'last-modified': '2025-10-23T03:09:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453193'}
2025-10-23 03:09:27,074 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:27,074 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-10853920 passed
2025-10-23 03:09:27,075 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-10853920 in API_Server ======
2025-10-23 03:09:27,075 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:27,081 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:27,093 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:27,191 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8e52a232-8392-4d1a-b8ad-29021ee390a3
2025-10-23 03:09:27,207 - DEBUG - =>VN ctest-mgmt_vn-10853920 has no policy to be verified
2025-10-23 03:09:27,207 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:27,207 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:27,207 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:27,222 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920?flat'}
2025-10-23 03:09:27,222 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:27,222 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:27,236 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920?flat'}
2025-10-23 03:09:27,236 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:27,236 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:27,249 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920?flat'}
2025-10-23 03:09:27,249 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43935969:ctest-mgmt_vn-10853920 is found in opserver
2025-10-23 03:09:27,272 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:27,283 - DEBUG - VRF ids for VN ctest-mgmt_vn-10853920: {}
2025-10-23 03:09:27,571 - DEBUG - Response for create_network : {'network': {'id': 'ecb463d9-b461-402d-8926-353a75ef5b88', 'name': 'ctest-left_vn-42629207', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'project_id': '2f203067a2f0452f97c476076398d539', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43935969', 'ctest-left_vn-42629207'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:27,838 - DEBUG - Response for create_subnet : {'subnet': {'id': '00a4433b-1f9c-4d7d-911f-5e0de60512b0', 'name': '', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'network_id': 'ecb463d9-b461-402d-8926-353a75ef5b88', 'ip_version': 4, 'cidr': '219.186.75.128/26', 'allocation_pools': [{'start': '219.186.75.130', 'end': '219.186.75.190'}], 'gateway_ip': '219.186.75.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '219.186.75.130', 'tags': [], 'project_id': '2f203067a2f0452f97c476076398d539'}}
2025-10-23 03:09:27,863 - INFO - Created VN ctest-left_vn-42629207
2025-10-23 03:09:27,874 - DEBUG - VN ctest-left_vn-42629207 UUID is ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:27,955 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:27,963 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:27,974 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:28,082 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:28,099 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:28,113 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c4512e5d-f26c-4d02-a1f2-2af04c010735
2025-10-23 03:09:28,124 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c4512e5d-f26c-4d02-a1f2-2af04c010735
2025-10-23 03:09:28,136 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/21ce4851-48d3-4915-a7fc-5e6627f2fdd2
2025-10-23 03:09:28,144 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:28,144 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:28,155 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c4512e5d-f26c-4d02-a1f2-2af04c010735
2025-10-23 03:09:28,171 - INFO - Verified VN network id 16 for VN ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:28,171 - INFO - Verifications in API Server for VN ctest-left_vn-42629207 passed
2025-10-23 03:09:28,171 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:28,180 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c4512e5d-f26c-4d02-a1f2-2af04c010735
2025-10-23 03:09:28,187 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c4512e5d-f26c-4d02-a1f2-2af04c010735
2025-10-23 03:09:28,194 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/21ce4851-48d3-4915-a7fc-5e6627f2fdd2
2025-10-23 03:09:28,206 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17056367475444236333', 'uuid-lslong': '9882644957514128264'}, 'enable': 'true', 'created': '2025-10-23T03:09:27', 'last-modified': '2025-10-23T03:09:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.412679'}
2025-10-23 03:09:28,212 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:28,218 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17056367475444236333', 'uuid-lslong': '9882644957514128264'}, 'enable': 'true', 'created': '2025-10-23T03:09:27', 'last-modified': '2025-10-23T03:09:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428145'}
2025-10-23 03:09:28,224 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:28,229 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17056367475444236333', 'uuid-lslong': '9882644957514128264'}, 'enable': 'true', 'created': '2025-10-23T03:09:27', 'last-modified': '2025-10-23T03:09:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443514'}
2025-10-23 03:09:28,235 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:28,235 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-42629207 passed
2025-10-23 03:09:28,235 - DEBUG - ====Verifying policy data for ctest-left_vn-42629207 in API_Server ======
2025-10-23 03:09:28,235 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:28,242 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:28,253 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:28,344 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/ecb463d9-b461-402d-8926-353a75ef5b88
2025-10-23 03:09:28,353 - DEBUG - =>VN ctest-left_vn-42629207 has no policy to be verified
2025-10-23 03:09:28,354 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:28,354 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:28,354 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:28,366 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207?flat'}
2025-10-23 03:09:28,366 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:28,366 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:28,378 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207?flat'}
2025-10-23 03:09:28,378 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:28,378 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:28,390 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207?flat'}
2025-10-23 03:09:28,390 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43935969:ctest-left_vn-42629207 is found in opserver
2025-10-23 03:09:28,411 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:28,421 - DEBUG - VRF ids for VN ctest-left_vn-42629207: {}
2025-10-23 03:09:28,683 - DEBUG - Response for create_network : {'network': {'id': '53756257-8b63-48d0-8e49-f44b7e42884e', 'name': 'ctest-right_vn-07319282', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'project_id': '2f203067a2f0452f97c476076398d539', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43935969', 'ctest-right_vn-07319282'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:28,951 - DEBUG - Response for create_subnet : {'subnet': {'id': '2fad785b-908e-4e02-98d3-64b84bd64449', 'name': '', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'network_id': '53756257-8b63-48d0-8e49-f44b7e42884e', 'ip_version': 4, 'cidr': '143.44.13.0/26', 'allocation_pools': [{'start': '143.44.13.2', 'end': '143.44.13.62'}], 'gateway_ip': '143.44.13.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '143.44.13.2', 'tags': [], 'project_id': '2f203067a2f0452f97c476076398d539'}}
2025-10-23 03:09:28,975 - INFO - Created VN ctest-right_vn-07319282
2025-10-23 03:09:28,986 - DEBUG - VN ctest-right_vn-07319282 UUID is 53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,060 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:29,066 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:29,077 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:29,166 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,188 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,200 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/86f92cf9-d639-4bed-a9f5-cde26475e61f
2025-10-23 03:09:29,210 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/86f92cf9-d639-4bed-a9f5-cde26475e61f
2025-10-23 03:09:29,218 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f03e3f45-58df-4e27-8391-188354e8db33
2025-10-23 03:09:29,226 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:29,226 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,236 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/86f92cf9-d639-4bed-a9f5-cde26475e61f
2025-10-23 03:09:29,251 - INFO - Verified VN network id 17 for VN 53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,252 - INFO - Verifications in API Server for VN ctest-right_vn-07319282 passed
2025-10-23 03:09:29,252 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,262 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/86f92cf9-d639-4bed-a9f5-cde26475e61f
2025-10-23 03:09:29,269 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/86f92cf9-d639-4bed-a9f5-cde26475e61f
2025-10-23 03:09:29,278 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f03e3f45-58df-4e27-8391-188354e8db33
2025-10-23 03:09:29,290 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6013821005563381968', 'uuid-lslong': '10252994631763658830'}, 'enable': 'true', 'created': '2025-10-23T03:09:28', 'last-modified': '2025-10-23T03:09:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384586'}
2025-10-23 03:09:29,295 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:29,303 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6013821005563381968', 'uuid-lslong': '10252994631763658830'}, 'enable': 'true', 'created': '2025-10-23T03:09:28', 'last-modified': '2025-10-23T03:09:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397630'}
2025-10-23 03:09:29,313 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:29,318 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6013821005563381968', 'uuid-lslong': '10252994631763658830'}, 'enable': 'true', 'created': '2025-10-23T03:09:28', 'last-modified': '2025-10-23T03:09:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413124'}
2025-10-23 03:09:29,323 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:29,324 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-07319282 passed
2025-10-23 03:09:29,324 - DEBUG - ====Verifying policy data for ctest-right_vn-07319282 in API_Server ======
2025-10-23 03:09:29,324 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:29,330 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:29,344 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:29,453 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/53756257-8b63-48d0-8e49-f44b7e42884e
2025-10-23 03:09:29,468 - DEBUG - =>VN ctest-right_vn-07319282 has no policy to be verified
2025-10-23 03:09:29,468 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:29,468 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:29,468 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:29,483 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282?flat'}
2025-10-23 03:09:29,484 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:29,484 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:29,495 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282?flat'}
2025-10-23 03:09:29,496 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:29,496 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:29,508 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282?flat'}
2025-10-23 03:09:29,508 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43935969:ctest-right_vn-07319282 is found in opserver
2025-10-23 03:09:29,531 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:29,544 - DEBUG - VRF ids for VN ctest-right_vn-07319282: {}
2025-10-23 03:09:29,801 - DEBUG - Response for create_network : {'network': {'id': '571d2429-8664-4a2d-909e-f7b6e6a1da2c', 'name': 'ctest-trans_left_vn-57341349', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'project_id': '2f203067a2f0452f97c476076398d539', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43935969', 'ctest-trans_left_vn-57341349'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:30,007 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e38a508c-4561-4e0c-821b-830fb7db1930', 'name': '', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'network_id': '571d2429-8664-4a2d-909e-f7b6e6a1da2c', 'ip_version': 4, 'cidr': '194.76.72.0/26', 'allocation_pools': [{'start': '194.76.72.2', 'end': '194.76.72.62'}], 'gateway_ip': '194.76.72.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '194.76.72.2', 'tags': [], 'project_id': '2f203067a2f0452f97c476076398d539'}}
2025-10-23 03:09:30,032 - INFO - Created VN ctest-trans_left_vn-57341349
2025-10-23 03:09:30,045 - DEBUG - VN ctest-trans_left_vn-57341349 UUID is 571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,113 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:30,120 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:30,131 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:30,222 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,265 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,276 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95638127-91a0-410f-b499-6b05a7819da2
2025-10-23 03:09:30,284 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95638127-91a0-410f-b499-6b05a7819da2
2025-10-23 03:09:30,291 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/213e344d-002f-4d02-bac1-20a8ea8c4047
2025-10-23 03:09:30,297 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-23 03:09:30,297 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,306 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95638127-91a0-410f-b499-6b05a7819da2
2025-10-23 03:09:30,322 - INFO - Verified VN network id 18 for VN 571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,322 - INFO - Verifications in API Server for VN ctest-trans_left_vn-57341349 passed
2025-10-23 03:09:30,323 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,333 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95638127-91a0-410f-b499-6b05a7819da2
2025-10-23 03:09:30,341 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95638127-91a0-410f-b499-6b05a7819da2
2025-10-23 03:09:30,348 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/213e344d-002f-4d02-bac1-20a8ea8c4047
2025-10-23 03:09:30,360 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6277213216391318061', 'uuid-lslong': '10421038952707381804'}, 'enable': 'true', 'created': '2025-10-23T03:09:29', 'last-modified': '2025-10-23T03:09:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398651'}
2025-10-23 03:09:30,366 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-23 03:09:30,372 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6277213216391318061', 'uuid-lslong': '10421038952707381804'}, 'enable': 'true', 'created': '2025-10-23T03:09:29', 'last-modified': '2025-10-23T03:09:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411888'}
2025-10-23 03:09:30,377 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-23 03:09:30,383 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6277213216391318061', 'uuid-lslong': '10421038952707381804'}, 'enable': 'true', 'created': '2025-10-23T03:09:29', 'last-modified': '2025-10-23T03:09:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427866'}
2025-10-23 03:09:30,389 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-23 03:09:30,389 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-57341349 passed
2025-10-23 03:09:30,389 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-57341349 in API_Server ======
2025-10-23 03:09:30,389 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:30,395 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:30,405 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:30,501 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/571d2429-8664-4a2d-909e-f7b6e6a1da2c
2025-10-23 03:09:30,513 - DEBUG - =>VN ctest-trans_left_vn-57341349 has no policy to be verified
2025-10-23 03:09:30,513 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:30,513 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:30,513 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:30,527 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349?flat'}
2025-10-23 03:09:30,527 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:30,527 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:30,542 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349?flat'}
2025-10-23 03:09:30,542 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:30,542 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:30,555 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349?flat'}
2025-10-23 03:09:30,555 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43935969:ctest-trans_left_vn-57341349 is found in opserver
2025-10-23 03:09:30,580 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:30,592 - DEBUG - VRF ids for VN ctest-trans_left_vn-57341349: {}
2025-10-23 03:09:30,872 - DEBUG - Response for create_network : {'network': {'id': 'c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529', 'name': 'ctest-trans_right_vn-29669598', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'project_id': '2f203067a2f0452f97c476076398d539', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-43935969', 'ctest-trans_right_vn-29669598'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:31,111 - DEBUG - Response for create_subnet : {'subnet': {'id': '78403b0d-8bfc-4b66-a8d7-43d726a7dc2d', 'name': '', 'tenant_id': '2f203067a2f0452f97c476076398d539', 'network_id': 'c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529', 'ip_version': 4, 'cidr': '79.34.255.0/26', 'allocation_pools': [{'start': '79.34.255.2', 'end': '79.34.255.62'}], 'gateway_ip': '79.34.255.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '79.34.255.2', 'tags': [], 'project_id': '2f203067a2f0452f97c476076398d539'}}
2025-10-23 03:09:31,135 - INFO - Created VN ctest-trans_right_vn-29669598
2025-10-23 03:09:31,147 - DEBUG - VN ctest-trans_right_vn-29669598 UUID is c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,236 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:31,244 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:31,259 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:31,356 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,374 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,384 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a1996d06-40af-4a4f-94f7-f5afc4f4bd68
2025-10-23 03:09:31,393 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a1996d06-40af-4a4f-94f7-f5afc4f4bd68
2025-10-23 03:09:31,401 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/d0c08605-70bf-49aa-976b-2fa7a5b502a4
2025-10-23 03:09:31,408 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-23 03:09:31,408 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,418 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a1996d06-40af-4a4f-94f7-f5afc4f4bd68
2025-10-23 03:09:31,434 - INFO - Verified VN network id 19 for VN c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,434 - INFO - Verifications in API Server for VN ctest-trans_right_vn-29669598 passed
2025-10-23 03:09:31,434 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,444 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a1996d06-40af-4a4f-94f7-f5afc4f4bd68
2025-10-23 03:09:31,451 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a1996d06-40af-4a4f-94f7-f5afc4f4bd68
2025-10-23 03:09:31,458 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/d0c08605-70bf-49aa-976b-2fa7a5b502a4
2025-10-23 03:09:31,471 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14457111601869374423', 'uuid-lslong': '11275451854347810089'}, 'enable': 'true', 'created': '2025-10-23T03:09:30', 'last-modified': '2025-10-23T03:09:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416645'}
2025-10-23 03:09:31,478 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-23 03:09:31,484 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14457111601869374423', 'uuid-lslong': '11275451854347810089'}, 'enable': 'true', 'created': '2025-10-23T03:09:30', 'last-modified': '2025-10-23T03:09:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424646'}
2025-10-23 03:09:31,494 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-23 03:09:31,500 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-43935969', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14457111601869374423', 'uuid-lslong': '11275451854347810089'}, 'enable': 'true', 'created': '2025-10-23T03:09:30', 'last-modified': '2025-10-23T03:09:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447473'}
2025-10-23 03:09:31,507 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-23 03:09:31,507 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-29669598 passed
2025-10-23 03:09:31,508 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-29669598 in API_Server ======
2025-10-23 03:09:31,508 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:31,514 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:31,527 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:31,629 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c8a1fa67-a39a-43d7-9c7a-73b8bc6bb529
2025-10-23 03:09:31,644 - DEBUG - =>VN ctest-trans_right_vn-29669598 has no policy to be verified
2025-10-23 03:09:31,644 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:31,644 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:31,645 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:31,660 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598?flat'}
2025-10-23 03:09:31,660 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:31,660 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:31,673 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598?flat'}
2025-10-23 03:09:31,674 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:31,674 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:31,688 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598?flat'}
2025-10-23 03:09:31,688 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-43935969:ctest-trans_right_vn-29669598 is found in opserver
2025-10-23 03:09:31,717 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:31,732 - DEBUG - VRF ids for VN ctest-trans_right_vn-29669598: {}
2025-10-23 03:09:33,043 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2)
2025-10-23 03:09:34,633 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1)
2025-10-23 03:09:34,634 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-34415324']
2025-10-23 03:09:34,924 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-34415324'] created succesfully
2025-10-23 03:09:35,287 - DEBUG - Image by name tiny_trans_fw not found
2025-10-23 03:09:35,287 - DEBUG - Installing image tiny_trans_fw
2025-10-23 03:09:35,287 - 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-23 03:09:35,288 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-23 03:09:35,288 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-23 03:09:35,310 - 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-23 03:09:35,368 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-23 03:09:35,369 - DEBUG - Unzip image
2025-10-23 03:09:35,369 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-23 03:09:35,397 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-23 03:09:35,398 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-34415324']
2025-10-23 03:09:35,505 - INFO - Service template: ['default-domain', 'ctest-service_template_1-34415324'] deleted successfully.
2025-10-23 03:09:35,507 - INFO - Skip interface_detach for VM ctest-right_vm-08674990 in state BUILD
2025-10-23 03:09:35,507 - INFO - Deleting VM ctest-right_vm-08674990
2025-10-23 03:09:35,508 - ERROR - VM ctest-right_vm-08674990 has failed to come up
2025-10-23 03:09:35,508 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:35,508 - ERROR - VM ctest-right_vm-08674990 has failed to come up
2025-10-23 03:09:35,508 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:35,648 - INFO - Skip interface_detach for VM ctest-left_vm-40321227 in state BUILD
2025-10-23 03:09:35,648 - INFO - Deleting VM ctest-left_vm-40321227
2025-10-23 03:09:35,648 - ERROR - VM ctest-left_vm-40321227 has failed to come up
2025-10-23 03:09:35,648 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:35,648 - ERROR - VM ctest-left_vm-40321227 has failed to come up
2025-10-23 03:09:35,648 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:35,778 - INFO - Deleting VN ctest-trans_right_vn-29669598
2025-10-23 03:09:35,990 - DEBUG - Response for deleting network ()
2025-10-23 03:09:35,990 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/a1996d06-40af-4a4f-94f7-f5afc4f4bd68
2025-10-23 03:09:36,000 - DEBUG - Response Code: 404
2025-10-23 03:09:36,000 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:36,008 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:36,067 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:36,173 - INFO - Validated that VN ctest-trans_right_vn-29669598 is not found in API Server
2025-10-23 03:09:36,198 - DEBUG - VN ctest-trans_right_vn-29669598 is not present in Agent 10.0.0.48
2025-10-23 03:09:36,210 - DEBUG - VN ctest-trans_right_vn-29669598 is not present in Agent 10.0.0.49
2025-10-23 03:09:36,210 - INFO - Validated that VN ctest-trans_right_vn-29669598 is not in any agent
2025-10-23 03:09:36,262 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-29669598 info
2025-10-23 03:09:36,262 - INFO - Deleting VN ctest-trans_left_vn-57341349
2025-10-23 03:09:36,409 - DEBUG - Response for deleting network ()
2025-10-23 03:09:36,409 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95638127-91a0-410f-b499-6b05a7819da2
2025-10-23 03:09:36,416 - DEBUG - Response Code: 404
2025-10-23 03:09:36,416 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:36,430 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:36,450 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:36,553 - INFO - Validated that VN ctest-trans_left_vn-57341349 is not found in API Server
2025-10-23 03:09:36,572 - DEBUG - VN ctest-trans_left_vn-57341349 is not present in Agent 10.0.0.48
2025-10-23 03:09:36,597 - DEBUG - VN ctest-trans_left_vn-57341349 is not present in Agent 10.0.0.49
2025-10-23 03:09:36,597 - INFO - Validated that VN ctest-trans_left_vn-57341349 is not in any agent
2025-10-23 03:09:36,656 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-57341349 info
2025-10-23 03:09:36,657 - INFO - Deleting VN ctest-right_vn-07319282
2025-10-23 03:09:36,711 - DEBUG - VN 53756257-8b63-48d0-8e49-f44b7e42884e still in use: Unable to complete operation on network 53756257-8b63-48d0-8e49-f44b7e42884e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e4958a9c-8ef2-4da5-ad55-b3fa65fdeec4']
2025-10-23 03:09:36,711 - WARNING - Deleting VN ctest-right_vn-07319282 failed..Will retry
2025-10-23 03:09:38,874 - DEBUG - Response for deleting network ()
2025-10-23 03:09:38,874 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/86f92cf9-d639-4bed-a9f5-cde26475e61f
2025-10-23 03:09:38,880 - DEBUG - Response Code: 404
2025-10-23 03:09:38,880 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:38,887 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:38,928 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:39,035 - INFO - Validated that VN ctest-right_vn-07319282 is not found in API Server
2025-10-23 03:09:39,046 - DEBUG - VN ctest-right_vn-07319282 is not present in Agent 10.0.0.48
2025-10-23 03:09:39,060 - DEBUG - VN ctest-right_vn-07319282 is not present in Agent 10.0.0.49
2025-10-23 03:09:39,060 - INFO - Validated that VN ctest-right_vn-07319282 is not in any agent
2025-10-23 03:09:39,095 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-07319282 info
2025-10-23 03:09:39,096 - INFO - Deleting VN ctest-left_vn-42629207
2025-10-23 03:09:39,261 - DEBUG - Response for deleting network ()
2025-10-23 03:09:39,261 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c4512e5d-f26c-4d02-a1f2-2af04c010735
2025-10-23 03:09:39,269 - DEBUG - Response Code: 404
2025-10-23 03:09:39,269 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:39,281 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:39,337 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:39,436 - INFO - Validated that VN ctest-left_vn-42629207 is not found in API Server
2025-10-23 03:09:39,447 - DEBUG - VN ctest-left_vn-42629207 is not present in Agent 10.0.0.48
2025-10-23 03:09:39,464 - DEBUG - VN ctest-left_vn-42629207 is not present in Agent 10.0.0.49
2025-10-23 03:09:39,464 - INFO - Validated that VN ctest-left_vn-42629207 is not in any agent
2025-10-23 03:09:39,500 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-42629207 info
2025-10-23 03:09:39,500 - INFO - Deleting VN ctest-mgmt_vn-10853920
2025-10-23 03:09:39,662 - DEBUG - Response for deleting network ()
2025-10-23 03:09:39,662 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/4c7840a8-aecb-4693-a1a7-664077509409
2025-10-23 03:09:39,670 - DEBUG - Response Code: 404
2025-10-23 03:09:39,670 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:39,677 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:39,709 - DEBUG - Requesting: http://10.0.0.41:8082/project/2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:39,799 - INFO - Validated that VN ctest-mgmt_vn-10853920 is not found in API Server
2025-10-23 03:09:39,810 - DEBUG - VN ctest-mgmt_vn-10853920 is not present in Agent 10.0.0.48
2025-10-23 03:09:39,822 - DEBUG - VN ctest-mgmt_vn-10853920 is not present in Agent 10.0.0.49
2025-10-23 03:09:39,822 - INFO - Validated that VN ctest-mgmt_vn-10853920 is not in any agent
2025-10-23 03:09:39,852 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-10853920 info
2025-10-23 03:09:41,120 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 03:09:41,120 - INFO -
2025-10-23 03:09:41,120 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2025-10-23 03:09:41,120 - INFO - --------------------------------------------------------------------------------
2025-10-23 03:09:41,965 - INFO - Deleted project: ctest-TestSvcRegr-43935969, ID : 2f203067-a2f0-452f-97c4-76076398d539
2025-10-23 03:09:42,169 - INFO - Domain Default found not creating
2025-10-23 03:09:42,332 - INFO - Project ctest-TestSvcRegr-70049075 not found, creating it
2025-10-23 03:09:42,879 - INFO - Created Project:ctest-TestSvcRegr-70049075, ID : b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:44,465 - INFO - ================================================================================
2025-10-23 03:09:44,465 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-23 03:09:45,747 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.49': {'10.20.0.14': '0', '10.20.0.129': '0'}} with
2025-10-23 03:09:45,747 - INFO - Initial checks done. Running the testcase now
2025-10-23 03:09:45,747 - INFO -
2025-10-23 03:09:46,652 - DEBUG - Response for create_network : {'network': {'id': 'f20cb768-25be-4276-9784-238a927bc692', 'name': 'ctest-mgmt_vn-33690819', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049075', 'ctest-mgmt_vn-33690819'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:46,898 - DEBUG - Response for create_subnet : {'subnet': {'id': '855d513f-60b0-4307-8196-bf44f29ce97f', 'name': '', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'network_id': 'f20cb768-25be-4276-9784-238a927bc692', 'ip_version': 4, 'cidr': '111.52.109.0/26', 'allocation_pools': [{'start': '111.52.109.2', 'end': '111.52.109.62'}], 'gateway_ip': '111.52.109.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '111.52.109.2', 'tags': [], 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520'}}
2025-10-23 03:09:46,933 - INFO - Created VN ctest-mgmt_vn-33690819
2025-10-23 03:09:46,944 - DEBUG - VN ctest-mgmt_vn-33690819 UUID is f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,017 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:47,349 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:47,377 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:47,392 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:47,496 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,513 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,528 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/970f2ff9-de98-4844-a999-9c7b1e93c3c2
2025-10-23 03:09:47,540 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/970f2ff9-de98-4844-a999-9c7b1e93c3c2
2025-10-23 03:09:47,550 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/72b3ac1e-5007-4aa7-a4d3-21b75e7e7912
2025-10-23 03:09:47,558 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:47,558 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,567 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/970f2ff9-de98-4844-a999-9c7b1e93c3c2
2025-10-23 03:09:47,583 - INFO - Verified VN network id 15 for VN f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,583 - INFO - Verifications in API Server for VN ctest-mgmt_vn-33690819 passed
2025-10-23 03:09:47,583 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,594 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/970f2ff9-de98-4844-a999-9c7b1e93c3c2
2025-10-23 03:09:47,601 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/970f2ff9-de98-4844-a999-9c7b1e93c3c2
2025-10-23 03:09:47,609 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/72b3ac1e-5007-4aa7-a4d3-21b75e7e7912
2025-10-23 03:09:47,622 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17441517114836796022', 'uuid-lslong': '10917890474722969234'}, 'enable': 'true', 'created': '2025-10-23T03:09:46', 'last-modified': '2025-10-23T03:09:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.770040'}
2025-10-23 03:09:47,628 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:47,633 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17441517114836796022', 'uuid-lslong': '10917890474722969234'}, 'enable': 'true', 'created': '2025-10-23T03:09:46', 'last-modified': '2025-10-23T03:09:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.781455'}
2025-10-23 03:09:47,639 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:47,645 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17441517114836796022', 'uuid-lslong': '10917890474722969234'}, 'enable': 'true', 'created': '2025-10-23T03:09:46', 'last-modified': '2025-10-23T03:09:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.793448'}
2025-10-23 03:09:47,651 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:09:47,651 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-33690819 passed
2025-10-23 03:09:47,651 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-33690819 in API_Server ======
2025-10-23 03:09:47,651 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:47,657 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:47,670 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:47,763 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f20cb768-25be-4276-9784-238a927bc692
2025-10-23 03:09:47,776 - DEBUG - =>VN ctest-mgmt_vn-33690819 has no policy to be verified
2025-10-23 03:09:47,776 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:47,776 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:47,776 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:48,108 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:48,166 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819?flat'}
2025-10-23 03:09:48,166 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:48,167 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:48,501 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:48,560 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819?flat'}
2025-10-23 03:09:48,560 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:48,560 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:48,897 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:48,953 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819?flat'}
2025-10-23 03:09:48,953 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-33690819 is found in opserver
2025-10-23 03:09:48,976 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:48,987 - DEBUG - VRF ids for VN ctest-mgmt_vn-33690819: {}
2025-10-23 03:09:49,272 - DEBUG - Response for create_network : {'network': {'id': 'e3d73f07-7e74-4c8a-84f0-be7e5f279721', 'name': 'service-left-vn-15747148', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049075', 'service-left-vn-15747148'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:49,502 - DEBUG - Response for create_subnet : {'subnet': {'id': '5ba04425-cabe-4432-9363-a29decd6e9b6', 'name': '', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'network_id': 'e3d73f07-7e74-4c8a-84f0-be7e5f279721', 'ip_version': 4, 'cidr': '143.73.94.0/26', 'allocation_pools': [{'start': '143.73.94.2', 'end': '143.73.94.62'}], 'gateway_ip': '143.73.94.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '143.73.94.2', 'tags': [], 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520'}}
2025-10-23 03:09:49,526 - INFO - Created VN service-left-vn-15747148
2025-10-23 03:09:49,539 - DEBUG - VN service-left-vn-15747148 UUID is e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:49,610 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:49,617 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:49,628 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:49,727 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:49,750 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:49,761 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/11448d4c-daee-4fbc-961a-b737cd2be934
2025-10-23 03:09:49,770 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/11448d4c-daee-4fbc-961a-b737cd2be934
2025-10-23 03:09:49,777 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/6bcdc633-7284-4090-904d-b0fe65df5dbf
2025-10-23 03:09:49,784 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:49,784 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:49,794 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/11448d4c-daee-4fbc-961a-b737cd2be934
2025-10-23 03:09:49,810 - INFO - Verified VN network id 16 for VN e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:49,810 - INFO - Verifications in API Server for VN service-left-vn-15747148 passed
2025-10-23 03:09:49,810 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:49,821 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/11448d4c-daee-4fbc-961a-b737cd2be934
2025-10-23 03:09:49,829 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/11448d4c-daee-4fbc-961a-b737cd2be934
2025-10-23 03:09:49,837 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/6bcdc633-7284-4090-904d-b0fe65df5dbf
2025-10-23 03:09:49,853 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16417660268021304458', 'uuid-lslong': '9579365857388631841'}, 'enable': 'true', 'created': '2025-10-23T03:09:49', 'last-modified': '2025-10-23T03:09:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420951'}
2025-10-23 03:09:49,860 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:49,866 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16417660268021304458', 'uuid-lslong': '9579365857388631841'}, 'enable': 'true', 'created': '2025-10-23T03:09:49', 'last-modified': '2025-10-23T03:09:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432568'}
2025-10-23 03:09:49,872 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:49,878 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16417660268021304458', 'uuid-lslong': '9579365857388631841'}, 'enable': 'true', 'created': '2025-10-23T03:09:49', 'last-modified': '2025-10-23T03:09:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.445535'}
2025-10-23 03:09:49,884 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:09:49,884 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-15747148 passed
2025-10-23 03:09:49,884 - DEBUG - ====Verifying policy data for service-left-vn-15747148 in API_Server ======
2025-10-23 03:09:49,884 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:49,891 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:49,906 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:50,004 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/e3d73f07-7e74-4c8a-84f0-be7e5f279721
2025-10-23 03:09:50,018 - DEBUG - =>VN service-left-vn-15747148 has no policy to be verified
2025-10-23 03:09:50,018 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:50,018 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:50,018 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:50,076 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148?flat'}
2025-10-23 03:09:50,076 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:50,076 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:50,135 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148?flat'}
2025-10-23 03:09:50,135 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:50,135 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:50,204 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148?flat'}
2025-10-23 03:09:50,204 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049075:service-left-vn-15747148 is found in opserver
2025-10-23 03:09:50,227 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:50,238 - DEBUG - VRF ids for VN service-left-vn-15747148: {}
2025-10-23 03:09:50,512 - DEBUG - Response for create_network : {'network': {'id': '17d54cb7-f3dd-435f-979d-ab8310cc12c9', 'name': 'service-right-vn-58091002', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049075', 'service-right-vn-58091002'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:09:50,804 - DEBUG - Response for create_subnet : {'subnet': {'id': '050aff53-e198-4c1f-9019-8af4038e5a14', 'name': '', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'network_id': '17d54cb7-f3dd-435f-979d-ab8310cc12c9', 'ip_version': 4, 'cidr': '147.46.27.128/26', 'allocation_pools': [{'start': '147.46.27.130', 'end': '147.46.27.190'}], 'gateway_ip': '147.46.27.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '147.46.27.130', 'tags': [], 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520'}}
2025-10-23 03:09:50,828 - INFO - Created VN service-right-vn-58091002
2025-10-23 03:09:50,839 - DEBUG - VN service-right-vn-58091002 UUID is 17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:50,926 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:50,934 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:50,948 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:51,042 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:51,067 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:51,078 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95958902-24ac-4dc7-82b8-14e107266302
2025-10-23 03:09:51,089 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95958902-24ac-4dc7-82b8-14e107266302
2025-10-23 03:09:51,097 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/3a8f3402-f297-4264-ae9e-8115b1aecde6
2025-10-23 03:09:51,108 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:51,109 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:51,122 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95958902-24ac-4dc7-82b8-14e107266302
2025-10-23 03:09:51,142 - INFO - Verified VN network id 17 for VN 17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:51,142 - INFO - Verifications in API Server for VN service-right-vn-58091002 passed
2025-10-23 03:09:51,142 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:51,156 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95958902-24ac-4dc7-82b8-14e107266302
2025-10-23 03:09:51,165 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95958902-24ac-4dc7-82b8-14e107266302
2025-10-23 03:09:51,174 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/3a8f3402-f297-4264-ae9e-8115b1aecde6
2025-10-23 03:09:51,189 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1717363185865802591', 'uuid-lslong': '10925076850481566409'}, 'enable': 'true', 'created': '2025-10-23T03:09:50', 'last-modified': '2025-10-23T03:09:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.448458'}
2025-10-23 03:09:51,197 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:51,204 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1717363185865802591', 'uuid-lslong': '10925076850481566409'}, 'enable': 'true', 'created': '2025-10-23T03:09:50', 'last-modified': '2025-10-23T03:09:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.467826'}
2025-10-23 03:09:51,210 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:51,216 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1717363185865802591', 'uuid-lslong': '10925076850481566409'}, 'enable': 'true', 'created': '2025-10-23T03:09:50', 'last-modified': '2025-10-23T03:09:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.490296'}
2025-10-23 03:09:51,223 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:09:51,223 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-58091002 passed
2025-10-23 03:09:51,224 - DEBUG - ====Verifying policy data for service-right-vn-58091002 in API_Server ======
2025-10-23 03:09:51,224 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:51,230 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:51,242 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:51,337 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/17d54cb7-f3dd-435f-979d-ab8310cc12c9
2025-10-23 03:09:51,350 - DEBUG - =>VN service-right-vn-58091002 has no policy to be verified
2025-10-23 03:09:51,350 - DEBUG - Verifying the vn in opserver
2025-10-23 03:09:51,350 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002 virtual network link through opserver 10.0.0.41
2025-10-23 03:09:51,350 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:09:51,406 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002?flat'}
2025-10-23 03:09:51,406 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002 virtual network link through opserver 10.0.0.73
2025-10-23 03:09:51,406 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:09:51,463 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002?flat'}
2025-10-23 03:09:51,463 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002 virtual network link through opserver 10.0.0.133
2025-10-23 03:09:51,463 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:09:51,519 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002?flat'}
2025-10-23 03:09:51,519 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049075:service-right-vn-58091002 is found in opserver
2025-10-23 03:09:51,543 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:09:51,555 - DEBUG - VRF ids for VN service-right-vn-58091002: {}
2025-10-23 03:09:51,614 - DEBUG - Services list from nova: [, , , ]
2025-10-23 03:09:52,789 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2)
2025-10-23 03:09:54,206 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1)
2025-10-23 03:09:54,206 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-77386665']
2025-10-23 03:09:54,515 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-77386665'] created succesfully
2025-10-23 03:09:54,942 - DEBUG - Image by name tiny_nat_fw not found
2025-10-23 03:09:54,942 - DEBUG - Installing image tiny_nat_fw
2025-10-23 03:09:54,942 - 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-23 03:09:54,943 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:54,943 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:54,943 - DEBUG - Unzip image
2025-10-23 03:09:54,943 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:09:54,982 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-23 03:09:54,982 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-77386665']
2025-10-23 03:09:55,080 - INFO - Service template: ['default-domain', 'ctest-service_template_1-77386665'] deleted successfully.
2025-10-23 03:09:55,080 - INFO - Skip interface_detach for VM ctest-right_vm-16521848 in state BUILD
2025-10-23 03:09:55,080 - INFO - Deleting VM ctest-right_vm-16521848
2025-10-23 03:09:55,151 - ERROR - VM ctest-right_vm-16521848 has failed to come up
2025-10-23 03:09:55,151 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:55,151 - ERROR - VM ctest-right_vm-16521848 has failed to come up
2025-10-23 03:09:55,151 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:55,339 - INFO - Skip interface_detach for VM ctest-left_vm-84246109 in state BUILD
2025-10-23 03:09:55,339 - INFO - Deleting VM ctest-left_vm-84246109
2025-10-23 03:09:55,339 - ERROR - VM ctest-left_vm-84246109 has failed to come up
2025-10-23 03:09:55,339 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:55,339 - ERROR - VM ctest-left_vm-84246109 has failed to come up
2025-10-23 03:09:55,339 - ERROR - Nova failed to get host of the VM
2025-10-23 03:09:55,431 - INFO - Deleting VN service-right-vn-58091002
2025-10-23 03:09:55,476 - DEBUG - VN 17d54cb7-f3dd-435f-979d-ab8310cc12c9 still in use: Unable to complete operation on network 17d54cb7-f3dd-435f-979d-ab8310cc12c9. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2fda415f-d7f6-457b-97c5-147f19b53739']
2025-10-23 03:09:55,476 - WARNING - Deleting VN service-right-vn-58091002 failed..Will retry
2025-10-23 03:09:57,623 - DEBUG - Response for deleting network ()
2025-10-23 03:09:57,624 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/95958902-24ac-4dc7-82b8-14e107266302
2025-10-23 03:09:57,630 - DEBUG - Response Code: 404
2025-10-23 03:09:57,630 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:57,637 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:57,660 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:57,769 - INFO - Validated that VN service-right-vn-58091002 is not found in API Server
2025-10-23 03:09:57,782 - DEBUG - VN service-right-vn-58091002 is not present in Agent 10.0.0.48
2025-10-23 03:09:57,795 - DEBUG - VN service-right-vn-58091002 is not present in Agent 10.0.0.49
2025-10-23 03:09:57,795 - INFO - Validated that VN service-right-vn-58091002 is not in any agent
2025-10-23 03:09:57,826 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-58091002 info
2025-10-23 03:09:57,826 - INFO - Deleting VN service-left-vn-15747148
2025-10-23 03:09:57,990 - DEBUG - Response for deleting network ()
2025-10-23 03:09:57,991 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/11448d4c-daee-4fbc-961a-b737cd2be934
2025-10-23 03:09:57,997 - DEBUG - Response Code: 404
2025-10-23 03:09:57,997 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:58,003 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:58,034 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:58,127 - INFO - Validated that VN service-left-vn-15747148 is not found in API Server
2025-10-23 03:09:58,139 - DEBUG - VN service-left-vn-15747148 is not present in Agent 10.0.0.48
2025-10-23 03:09:58,152 - DEBUG - VN service-left-vn-15747148 is not present in Agent 10.0.0.49
2025-10-23 03:09:58,152 - INFO - Validated that VN service-left-vn-15747148 is not in any agent
2025-10-23 03:09:58,190 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-15747148 info
2025-10-23 03:09:58,190 - INFO - Deleting VN ctest-mgmt_vn-33690819
2025-10-23 03:09:58,367 - DEBUG - Response for deleting network ()
2025-10-23 03:09:58,367 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/970f2ff9-de98-4844-a999-9c7b1e93c3c2
2025-10-23 03:09:58,373 - DEBUG - Response Code: 404
2025-10-23 03:09:58,373 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:09:58,383 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:09:58,402 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:09:58,491 - INFO - Validated that VN ctest-mgmt_vn-33690819 is not found in API Server
2025-10-23 03:09:58,502 - DEBUG - VN ctest-mgmt_vn-33690819 is not present in Agent 10.0.0.48
2025-10-23 03:09:58,520 - DEBUG - VN ctest-mgmt_vn-33690819 is not present in Agent 10.0.0.49
2025-10-23 03:09:58,520 - INFO - Validated that VN ctest-mgmt_vn-33690819 is not in any agent
2025-10-23 03:09:58,554 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-33690819 info
2025-10-23 03:09:59,831 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 03:09:59,831 - INFO -
2025-10-23 03:09:59,831 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-10-23 03:09:59,831 - INFO - --------------------------------------------------------------------------------
2025-10-23 03:09:59,834 - INFO - ================================================================================
2025-10-23 03:09:59,834 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-23 03:10:01,147 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.49': {'10.20.0.14': '0', '10.20.0.129': '0'}} with
2025-10-23 03:10:01,148 - INFO - Initial checks done. Running the testcase now
2025-10-23 03:10:01,148 - INFO -
2025-10-23 03:10:01,459 - DEBUG - Response for create_network : {'network': {'id': '16e6ccbe-658c-447b-ba63-e7ccc75785f7', 'name': 'ctest-mgmt_vn-52798167', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049075', 'ctest-mgmt_vn-52798167'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:10:01,725 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c0a677b2-8a84-4bb1-9ef7-aa383b0dc1db', 'name': '', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'network_id': '16e6ccbe-658c-447b-ba63-e7ccc75785f7', 'ip_version': 4, 'cidr': '38.221.50.64/26', 'allocation_pools': [{'start': '38.221.50.66', 'end': '38.221.50.126'}], 'gateway_ip': '38.221.50.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '38.221.50.66', 'tags': [], 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520'}}
2025-10-23 03:10:01,754 - INFO - Created VN ctest-mgmt_vn-52798167
2025-10-23 03:10:01,765 - DEBUG - VN ctest-mgmt_vn-52798167 UUID is 16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:01,854 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:01,862 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:01,879 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:01,986 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:02,002 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:02,019 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/390bddeb-132c-4ded-918d-9e6a2cdcfd4c
2025-10-23 03:10:02,030 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/390bddeb-132c-4ded-918d-9e6a2cdcfd4c
2025-10-23 03:10:02,038 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b32d7449-0a6a-4c98-917b-cce8576d91d6
2025-10-23 03:10:02,048 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:10:02,048 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:02,060 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/390bddeb-132c-4ded-918d-9e6a2cdcfd4c
2025-10-23 03:10:02,080 - INFO - Verified VN network id 15 for VN 16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:02,080 - INFO - Verifications in API Server for VN ctest-mgmt_vn-52798167 passed
2025-10-23 03:10:02,080 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:02,092 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/390bddeb-132c-4ded-918d-9e6a2cdcfd4c
2025-10-23 03:10:02,100 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/390bddeb-132c-4ded-918d-9e6a2cdcfd4c
2025-10-23 03:10:02,108 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b32d7449-0a6a-4c98-917b-cce8576d91d6
2025-10-23 03:10:02,121 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1650231431597409403', 'uuid-lslong': '13430833380452697591'}, 'enable': 'true', 'created': '2025-10-23T03:10:01', 'last-modified': '2025-10-23T03:10:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463757'}
2025-10-23 03:10:02,127 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:10:02,133 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1650231431597409403', 'uuid-lslong': '13430833380452697591'}, 'enable': 'true', 'created': '2025-10-23T03:10:01', 'last-modified': '2025-10-23T03:10:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.489034'}
2025-10-23 03:10:02,139 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:10:02,145 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1650231431597409403', 'uuid-lslong': '13430833380452697591'}, 'enable': 'true', 'created': '2025-10-23T03:10:01', 'last-modified': '2025-10-23T03:10:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.499270'}
2025-10-23 03:10:02,151 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 03:10:02,151 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-52798167 passed
2025-10-23 03:10:02,152 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-52798167 in API_Server ======
2025-10-23 03:10:02,152 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:02,158 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:02,170 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:02,272 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/16e6ccbe-658c-447b-ba63-e7ccc75785f7
2025-10-23 03:10:02,284 - DEBUG - =>VN ctest-mgmt_vn-52798167 has no policy to be verified
2025-10-23 03:10:02,284 - DEBUG - Verifying the vn in opserver
2025-10-23 03:10:02,284 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167 virtual network link through opserver 10.0.0.41
2025-10-23 03:10:02,284 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:10:02,298 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167?flat'}
2025-10-23 03:10:02,298 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167 virtual network link through opserver 10.0.0.73
2025-10-23 03:10:02,298 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:10:02,312 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167?flat'}
2025-10-23 03:10:02,312 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167 virtual network link through opserver 10.0.0.133
2025-10-23 03:10:02,312 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:10:02,326 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167?flat'}
2025-10-23 03:10:02,326 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049075:ctest-mgmt_vn-52798167 is found in opserver
2025-10-23 03:10:02,351 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:10:02,363 - DEBUG - VRF ids for VN ctest-mgmt_vn-52798167: {}
2025-10-23 03:10:02,706 - DEBUG - Response for create_network : {'network': {'id': '7dc0b387-e517-4cfe-aa06-b68a1f6e9472', 'name': 'ctest-left_vn-63527356', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049075', 'ctest-left_vn-63527356'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:10:02,991 - DEBUG - Response for create_subnet : {'subnet': {'id': 'da9b3b21-1d6e-4c14-9e1c-f884eadd9f33', 'name': '', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'network_id': '7dc0b387-e517-4cfe-aa06-b68a1f6e9472', 'ip_version': 4, 'cidr': '170.109.52.64/26', 'allocation_pools': [{'start': '170.109.52.66', 'end': '170.109.52.126'}], 'gateway_ip': '170.109.52.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '170.109.52.66', 'tags': [], 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520'}}
2025-10-23 03:10:03,029 - INFO - Created VN ctest-left_vn-63527356
2025-10-23 03:10:03,045 - DEBUG - VN ctest-left_vn-63527356 UUID is 7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,159 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:03,167 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:03,181 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:03,267 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,282 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,292 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0fc1350b-1f99-435f-9c8d-7200759be49b
2025-10-23 03:10:03,300 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0fc1350b-1f99-435f-9c8d-7200759be49b
2025-10-23 03:10:03,307 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/661acdcf-a3a2-4541-a309-7dbcfbe7373d
2025-10-23 03:10:03,314 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:10:03,314 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,323 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0fc1350b-1f99-435f-9c8d-7200759be49b
2025-10-23 03:10:03,339 - INFO - Verified VN network id 16 for VN 7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,339 - INFO - Verifications in API Server for VN ctest-left_vn-63527356 passed
2025-10-23 03:10:03,339 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,350 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0fc1350b-1f99-435f-9c8d-7200759be49b
2025-10-23 03:10:03,357 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0fc1350b-1f99-435f-9c8d-7200759be49b
2025-10-23 03:10:03,366 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/661acdcf-a3a2-4541-a309-7dbcfbe7373d
2025-10-23 03:10:03,389 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9061439846514904318', 'uuid-lslong': '12251680540657095794'}, 'enable': 'true', 'created': '2025-10-23T03:10:02', 'last-modified': '2025-10-23T03:10:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.469918'}
2025-10-23 03:10:03,400 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:10:03,406 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9061439846514904318', 'uuid-lslong': '12251680540657095794'}, 'enable': 'true', 'created': '2025-10-23T03:10:02', 'last-modified': '2025-10-23T03:10:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.492551'}
2025-10-23 03:10:03,412 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:10:03,419 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9061439846514904318', 'uuid-lslong': '12251680540657095794'}, 'enable': 'true', 'created': '2025-10-23T03:10:02', 'last-modified': '2025-10-23T03:10:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.499535'}
2025-10-23 03:10:03,426 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-23 03:10:03,426 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-63527356 passed
2025-10-23 03:10:03,427 - DEBUG - ====Verifying policy data for ctest-left_vn-63527356 in API_Server ======
2025-10-23 03:10:03,427 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:03,435 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:03,448 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:03,539 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/7dc0b387-e517-4cfe-aa06-b68a1f6e9472
2025-10-23 03:10:03,550 - DEBUG - =>VN ctest-left_vn-63527356 has no policy to be verified
2025-10-23 03:10:03,550 - DEBUG - Verifying the vn in opserver
2025-10-23 03:10:03,550 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356 virtual network link through opserver 10.0.0.41
2025-10-23 03:10:03,550 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:10:03,562 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356?flat'}
2025-10-23 03:10:03,562 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356 virtual network link through opserver 10.0.0.73
2025-10-23 03:10:03,562 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:10:03,574 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356?flat'}
2025-10-23 03:10:03,574 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356 virtual network link through opserver 10.0.0.133
2025-10-23 03:10:03,574 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:10:03,586 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356?flat'}
2025-10-23 03:10:03,586 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049075:ctest-left_vn-63527356 is found in opserver
2025-10-23 03:10:03,609 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:10:03,622 - DEBUG - VRF ids for VN ctest-left_vn-63527356: {}
2025-10-23 03:10:03,912 - DEBUG - Response for create_network : {'network': {'id': 'f75983ff-13ee-4e58-9b24-9cc9006aaad9', 'name': 'ctest-right_vn-20948423', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049075', 'ctest-right_vn-20948423'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 03:10:04,250 - DEBUG - Response for create_subnet : {'subnet': {'id': '9100e93b-a834-4666-8aa9-3d0e812747dd', 'name': '', 'tenant_id': 'b9153e12ee8c45acbdb9d34d9f069520', 'network_id': 'f75983ff-13ee-4e58-9b24-9cc9006aaad9', 'ip_version': 4, 'cidr': '51.30.84.64/26', 'allocation_pools': [{'start': '51.30.84.66', 'end': '51.30.84.126'}], 'gateway_ip': '51.30.84.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '51.30.84.66', 'tags': [], 'project_id': 'b9153e12ee8c45acbdb9d34d9f069520'}}
2025-10-23 03:10:04,273 - INFO - Created VN ctest-right_vn-20948423
2025-10-23 03:10:04,288 - DEBUG - VN ctest-right_vn-20948423 UUID is f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,374 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:04,382 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:04,398 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:04,518 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,534 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,550 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/84f5eedc-0dc7-4033-94e1-006f279c9bd5
2025-10-23 03:10:04,560 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/84f5eedc-0dc7-4033-94e1-006f279c9bd5
2025-10-23 03:10:04,570 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/474b0a71-92db-4f0c-977b-9249313a97c0
2025-10-23 03:10:04,583 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:10:04,583 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,593 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/84f5eedc-0dc7-4033-94e1-006f279c9bd5
2025-10-23 03:10:04,613 - INFO - Verified VN network id 17 for VN f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,613 - INFO - Verifications in API Server for VN ctest-right_vn-20948423 passed
2025-10-23 03:10:04,613 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,627 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/84f5eedc-0dc7-4033-94e1-006f279c9bd5
2025-10-23 03:10:04,637 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/84f5eedc-0dc7-4033-94e1-006f279c9bd5
2025-10-23 03:10:04,645 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/474b0a71-92db-4f0c-977b-9249313a97c0
2025-10-23 03:10:04,657 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17823422131869732440', 'uuid-lslong': '11179232562149763801'}, 'enable': 'true', 'created': '2025-10-23T03:10:03', 'last-modified': '2025-10-23T03:10:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464871'}
2025-10-23 03:10:04,664 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:10:04,670 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17823422131869732440', 'uuid-lslong': '11179232562149763801'}, 'enable': 'true', 'created': '2025-10-23T03:10:03', 'last-modified': '2025-10-23T03:10:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484514'}
2025-10-23 03:10:04,677 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:10:04,683 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049075', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17823422131869732440', 'uuid-lslong': '11179232562149763801'}, 'enable': 'true', 'created': '2025-10-23T03:10:03', 'last-modified': '2025-10-23T03:10:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.495307'}
2025-10-23 03:10:04,689 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-23 03:10:04,689 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-20948423 passed
2025-10-23 03:10:04,690 - DEBUG - ====Verifying policy data for ctest-right_vn-20948423 in API_Server ======
2025-10-23 03:10:04,690 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:04,697 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:04,712 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:04,810 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f75983ff-13ee-4e58-9b24-9cc9006aaad9
2025-10-23 03:10:04,821 - DEBUG - =>VN ctest-right_vn-20948423 has no policy to be verified
2025-10-23 03:10:04,821 - DEBUG - Verifying the vn in opserver
2025-10-23 03:10:04,821 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423 virtual network link through opserver 10.0.0.41
2025-10-23 03:10:04,821 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-23 03:10:04,834 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423?flat'}
2025-10-23 03:10:04,834 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423 virtual network link through opserver 10.0.0.73
2025-10-23 03:10:04,834 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2025-10-23 03:10:04,848 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423?flat'}
2025-10-23 03:10:04,848 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423 virtual network link through opserver 10.0.0.133
2025-10-23 03:10:04,848 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2025-10-23 03:10:04,862 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423?flat'}
2025-10-23 03:10:04,862 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049075:ctest-right_vn-20948423 is found in opserver
2025-10-23 03:10:04,890 - DEBUG - Do not have enough data to verify VN in agent
2025-10-23 03:10:04,903 - DEBUG - VRF ids for VN ctest-right_vn-20948423: {}
2025-10-23 03:10:06,004 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1)
2025-10-23 03:10:07,495 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2)
2025-10-23 03:10:07,495 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-96889838']
2025-10-23 03:10:07,791 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-96889838'] created succesfully
2025-10-23 03:10:08,235 - DEBUG - Image by name tiny_nat_fw not found
2025-10-23 03:10:08,235 - DEBUG - Installing image tiny_nat_fw
2025-10-23 03:10:08,235 - 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-23 03:10:08,235 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:10:08,235 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:10:08,235 - DEBUG - Unzip image
2025-10-23 03:10:08,235 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-23 03:10:08,261 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-23 03:10:08,262 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-96889838']
2025-10-23 03:10:08,360 - INFO - Service template: ['default-domain', 'ctest-service_template_1-96889838'] deleted successfully.
2025-10-23 03:10:08,360 - INFO - Skip interface_detach for VM ctest-right_vm-16010466 in state BUILD
2025-10-23 03:10:08,360 - INFO - Deleting VM ctest-right_vm-16010466
2025-10-23 03:10:08,360 - ERROR - VM ctest-right_vm-16010466 has failed to come up
2025-10-23 03:10:08,360 - ERROR - Nova failed to get host of the VM
2025-10-23 03:10:08,360 - ERROR - VM ctest-right_vm-16010466 has failed to come up
2025-10-23 03:10:08,360 - ERROR - Nova failed to get host of the VM
2025-10-23 03:10:08,474 - INFO - Skip interface_detach for VM ctest-left_vm-05837659 in state BUILD
2025-10-23 03:10:08,474 - INFO - Deleting VM ctest-left_vm-05837659
2025-10-23 03:10:08,475 - ERROR - VM ctest-left_vm-05837659 has failed to come up
2025-10-23 03:10:08,475 - ERROR - Nova failed to get host of the VM
2025-10-23 03:10:08,475 - ERROR - VM ctest-left_vm-05837659 has failed to come up
2025-10-23 03:10:08,476 - ERROR - Nova failed to get host of the VM
2025-10-23 03:10:08,566 - INFO - Deleting VN ctest-right_vn-20948423
2025-10-23 03:10:08,612 - DEBUG - VN f75983ff-13ee-4e58-9b24-9cc9006aaad9 still in use: Unable to complete operation on network f75983ff-13ee-4e58-9b24-9cc9006aaad9. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7b60a24c-02f2-46d3-b8f1-dad2b40b1c81']
2025-10-23 03:10:08,612 - WARNING - Deleting VN ctest-right_vn-20948423 failed..Will retry
2025-10-23 03:10:10,804 - DEBUG - Response for deleting network ()
2025-10-23 03:10:10,804 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/84f5eedc-0dc7-4033-94e1-006f279c9bd5
2025-10-23 03:10:10,814 - DEBUG - Response Code: 404
2025-10-23 03:10:10,814 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:10,825 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:10,858 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:10,955 - INFO - Validated that VN ctest-right_vn-20948423 is not found in API Server
2025-10-23 03:10:10,975 - DEBUG - VN ctest-right_vn-20948423 is not present in Agent 10.0.0.48
2025-10-23 03:10:10,988 - DEBUG - VN ctest-right_vn-20948423 is not present in Agent 10.0.0.49
2025-10-23 03:10:10,988 - INFO - Validated that VN ctest-right_vn-20948423 is not in any agent
2025-10-23 03:10:11,026 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-20948423 info
2025-10-23 03:10:11,027 - INFO - Deleting VN ctest-left_vn-63527356
2025-10-23 03:10:11,219 - DEBUG - Response for deleting network ()
2025-10-23 03:10:11,220 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0fc1350b-1f99-435f-9c8d-7200759be49b
2025-10-23 03:10:11,232 - DEBUG - Response Code: 404
2025-10-23 03:10:11,232 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:11,249 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:11,265 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:11,374 - INFO - Validated that VN ctest-left_vn-63527356 is not found in API Server
2025-10-23 03:10:11,388 - DEBUG - VN ctest-left_vn-63527356 is not present in Agent 10.0.0.48
2025-10-23 03:10:11,404 - DEBUG - VN ctest-left_vn-63527356 is not present in Agent 10.0.0.49
2025-10-23 03:10:11,404 - INFO - Validated that VN ctest-left_vn-63527356 is not in any agent
2025-10-23 03:10:11,437 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-63527356 info
2025-10-23 03:10:11,437 - INFO - Deleting VN ctest-mgmt_vn-52798167
2025-10-23 03:10:11,669 - DEBUG - Response for deleting network ()
2025-10-23 03:10:11,669 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/390bddeb-132c-4ded-918d-9e6a2cdcfd4c
2025-10-23 03:10:11,676 - DEBUG - Response Code: 404
2025-10-23 03:10:11,676 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-10-23 03:10:11,684 - DEBUG - Requesting: http://10.0.0.41:8082/domain/4ac2eafd-52e8-4f65-a1ce-6478676e3af6
2025-10-23 03:10:11,746 - DEBUG - Requesting: http://10.0.0.41:8082/project/b9153e12-ee8c-45ac-bdb9-d34d9f069520
2025-10-23 03:10:11,849 - INFO - Validated that VN ctest-mgmt_vn-52798167 is not found in API Server
2025-10-23 03:10:11,862 - DEBUG - VN ctest-mgmt_vn-52798167 is not present in Agent 10.0.0.48
2025-10-23 03:10:11,874 - DEBUG - VN ctest-mgmt_vn-52798167 is not present in Agent 10.0.0.49
2025-10-23 03:10:11,874 - INFO - Validated that VN ctest-mgmt_vn-52798167 is not in any agent
2025-10-23 03:10:11,912 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-52798167 info
2025-10-23 03:10:13,216 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 03:10:13,216 - INFO -
2025-10-23 03:10:13,216 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-10-23 03:10:13,216 - INFO - --------------------------------------------------------------------------------
2025-10-23 03:10:14,006 - INFO - Deleted project: ctest-TestSvcRegr-70049075, ID : b9153e12-ee8c-45ac-bdb9-d34d9f069520