2026-09-09 11:05:03,870 - INFO - Domain Default found not creating 2026-09-09 11:05:04,037 - INFO - Project ctest-TestBasicPolicy-45315593 not found, creating it 2026-09-09 11:05:04,560 - INFO - Created Project:ctest-TestBasicPolicy-45315593, ID : d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:05:06,380 - INFO - ================================================================================ 2026-09-09 11:05:06,381 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-09-09 11:05:06,381 - INFO - TEST DESCRIPTION : Create 2 Vns and allow icmp traffic between them and validate with pings Update the policy to deny the same traffic Check that pings fail 2026-09-09 11:05:06,659 - DEBUG - Nothing to compare xmpp stats {'10.0.0.250': {'10.20.0.254': '0'}} with 2026-09-09 11:05:06,659 - INFO - Initial checks done. Running the testcase now 2026-09-09 11:05:06,659 - INFO - 2026-09-09 11:05:07,396 - DEBUG - Response for create_network : {'network': {'id': '738e2298-ec70-451e-8d23-fcab4ca0f496', 'name': 'ctest-vn-63064665', 'tenant_id': 'd05e61e8df1c475c8b3e599c0784d070', 'project_id': 'd05e61e8df1c475c8b3e599c0784d070', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-vn-63064665'], 'port_security_enabled': True, 'description': ''}} 2026-09-09 11:05:07,646 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c57cab99-30fd-40cf-9780-2017f0bb5d66', 'name': '', 'tenant_id': 'd05e61e8df1c475c8b3e599c0784d070', 'network_id': '738e2298-ec70-451e-8d23-fcab4ca0f496', 'ip_version': 4, 'cidr': '129.251.36.128/26', 'allocation_pools': [{'start': '129.251.36.130', 'end': '129.251.36.190'}], 'gateway_ip': '129.251.36.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '129.251.36.130', 'tags': [], 'project_id': 'd05e61e8df1c475c8b3e599c0784d070'}} 2026-09-09 11:05:07,666 - INFO - Created VN ctest-vn-63064665 2026-09-09 11:05:07,718 - DEBUG - VN ctest-vn-63064665 UUID is 738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:08,112 - DEBUG - Response for create_network : {'network': {'id': '4f8eb5e0-9f24-48b9-a455-46eea46d3606', 'name': 'ctest-vn-06720451', 'tenant_id': 'd05e61e8df1c475c8b3e599c0784d070', 'project_id': 'd05e61e8df1c475c8b3e599c0784d070', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-vn-06720451'], 'port_security_enabled': True, 'description': ''}} 2026-09-09 11:05:08,340 - DEBUG - Response for create_subnet : {'subnet': {'id': '48281c5b-531a-4a42-9f78-fea5870db12a', 'name': '', 'tenant_id': 'd05e61e8df1c475c8b3e599c0784d070', 'network_id': '4f8eb5e0-9f24-48b9-a455-46eea46d3606', 'ip_version': 4, 'cidr': '32.247.36.64/26', 'allocation_pools': [{'start': '32.247.36.66', 'end': '32.247.36.126'}], 'gateway_ip': '32.247.36.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '32.247.36.66', 'tags': [], 'project_id': 'd05e61e8df1c475c8b3e599c0784d070'}} 2026-09-09 11:05:08,361 - INFO - Created VN ctest-vn-06720451 2026-09-09 11:05:08,414 - DEBUG - VN ctest-vn-06720451 UUID is 4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:08,570 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-06720451, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-09-09 11:05:08,943 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '738e2298-ec70-451e-8d23-fcab4ca0f496', 'name': 'ctest-vn-63064665', 'tenant_id': 'd05e61e8df1c475c8b3e599c0784d070', 'project_id': 'd05e61e8df1c475c8b3e599c0784d070', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['c57cab99-30fd-40cf-9780-2017f0bb5d66'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-vn-63064665'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-policy-allow-all-67145701']], 'subnet_ipam': [{'subnet_cidr': '129.251.36.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-09-09 11:05:09,209 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '4f8eb5e0-9f24-48b9-a455-46eea46d3606', 'name': 'ctest-vn-06720451', 'tenant_id': 'd05e61e8df1c475c8b3e599c0784d070', 'project_id': 'd05e61e8df1c475c8b3e599c0784d070', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['48281c5b-531a-4a42-9f78-fea5870db12a'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-vn-06720451'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-policy-allow-all-67145701']], 'subnet_ipam': [{'subnet_cidr': '32.247.36.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-09-09 11:05:09,390 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:05:10,060 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:05:10,109 - DEBUG - Requesting: http://10.0.0.250:8082/domain/8bbb7c25-989c-4100-9771-0b0c233d2e27 2026-09-09 11:05:10,125 - DEBUG - Requesting: http://10.0.0.250:8082/project/d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:05:10,206 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:10,219 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:10,229 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/d5a26ed1-b013-436a-b74b-1628132b9a7a 2026-09-09 11:05:10,237 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/d5a26ed1-b013-436a-b74b-1628132b9a7a 2026-09-09 11:05:10,246 - DEBUG - Requesting: http://10.0.0.250:8082/route-target/f313721e-a5d5-4741-a75a-0ee991cddc27 2026-09-09 11:05:10,254 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-09 11:05:10,254 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:10,265 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/d5a26ed1-b013-436a-b74b-1628132b9a7a 2026-09-09 11:05:10,281 - INFO - Verified VN network id 12 for VN 738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:10,281 - INFO - Verifications in API Server for VN ctest-vn-63064665 passed 2026-09-09 11:05:10,281 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:10,292 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/d5a26ed1-b013-436a-b74b-1628132b9a7a 2026-09-09 11:05:10,302 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/d5a26ed1-b013-436a-b74b-1628132b9a7a 2026-09-09 11:05:10,308 - DEBUG - Requesting: http://10.0.0.250:8082/route-target/f313721e-a5d5-4741-a75a-0ee991cddc27 2026-09-09 11:05:10,320 - DEBUG - Control-node 10.0.0.250 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-45315593', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8326630801251779870', 'uuid-lslong': '10170250196187935894'}, 'enable': 'true', 'created': '2026-09-09T11:05:07', 'last-modified': '2026-09-09T11:05:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.440704'} 2026-09-09 11:05:10,325 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-09 11:05:10,325 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-63064665 passed 2026-09-09 11:05:10,326 - DEBUG - ====Verifying policy data for ctest-vn-63064665 in API_Server ====== 2026-09-09 11:05:10,326 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:05:10,332 - DEBUG - Requesting: http://10.0.0.250:8082/domain/8bbb7c25-989c-4100-9771-0b0c233d2e27 2026-09-09 11:05:10,347 - DEBUG - Requesting: http://10.0.0.250:8082/project/d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:05:10,427 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/738e2298-ec70-451e-8d23-fcab4ca0f496 2026-09-09 11:05:10,439 - DEBUG - ==>Verifying data for policy with id: 29f0f9ef-33d3-4d80-8464-07b9119cdf4a, fqn: ['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-policy-allow-all-67145701'] 2026-09-09 11:05:10,439 - INFO - VN ctest-vn-63064665 Policy verification: verify_vn_policy_in_api_server, status: True 2026-09-09 11:05:10,439 - DEBUG - Verifying the vn in opserver 2026-09-09 11:05:10,439 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665 virtual network link through opserver 10.0.0.250 2026-09-09 11:05:10,440 - DEBUG - Requesting: http://10.0.0.250:8081/analytics/uves/virtual-networks 2026-09-09 11:05:10,806 - DEBUG - Requesting: http://10.0.0.250:8081/analytics/uves/virtual-networks 2026-09-09 11:05:10,857 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665', 'href': 'http://10.0.0.250:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665?flat'} 2026-09-09 11:05:10,857 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665 is found in opserver 2026-09-09 11:05:10,872 - DEBUG - Do not have enough data to verify VN in agent 2026-09-09 11:05:10,878 - DEBUG - VRF ids for VN ctest-vn-63064665: {} 2026-09-09 11:05:10,878 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:05:10,885 - DEBUG - Requesting: http://10.0.0.250:8082/domain/8bbb7c25-989c-4100-9771-0b0c233d2e27 2026-09-09 11:05:10,899 - DEBUG - Requesting: http://10.0.0.250:8082/project/d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:05:10,982 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:10,996 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:11,006 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/ab2c6d7c-660e-420d-ad19-957ee12b85d2 2026-09-09 11:05:11,017 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/ab2c6d7c-660e-420d-ad19-957ee12b85d2 2026-09-09 11:05:11,024 - DEBUG - Requesting: http://10.0.0.250:8082/route-target/f0029a1a-34da-4404-8a11-b9a1460e5082 2026-09-09 11:05:11,030 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-09-09 11:05:11,030 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:11,039 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/ab2c6d7c-660e-420d-ad19-957ee12b85d2 2026-09-09 11:05:11,057 - INFO - Verified VN network id 13 for VN 4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:11,058 - INFO - Verifications in API Server for VN ctest-vn-06720451 passed 2026-09-09 11:05:11,058 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:11,070 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/ab2c6d7c-660e-420d-ad19-957ee12b85d2 2026-09-09 11:05:11,080 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/ab2c6d7c-660e-420d-ad19-957ee12b85d2 2026-09-09 11:05:11,089 - DEBUG - Requesting: http://10.0.0.250:8082/route-target/f0029a1a-34da-4404-8a11-b9a1460e5082 2026-09-09 11:05:11,107 - DEBUG - Control-node 10.0.0.250 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-06720451', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-45315593', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5732719352036477113', 'uuid-lslong': '11841448786015368710'}, 'enable': 'true', 'created': '2026-09-09T11:05:08', 'last-modified': '2026-09-09T11:05:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.918071'} 2026-09-09 11:05:11,114 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-09-09 11:05:11,114 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-06720451 passed 2026-09-09 11:05:11,114 - DEBUG - ====Verifying policy data for ctest-vn-06720451 in API_Server ====== 2026-09-09 11:05:11,114 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:05:11,124 - DEBUG - Requesting: http://10.0.0.250:8082/domain/8bbb7c25-989c-4100-9771-0b0c233d2e27 2026-09-09 11:05:11,140 - DEBUG - Requesting: http://10.0.0.250:8082/project/d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:05:11,235 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-network/4f8eb5e0-9f24-48b9-a455-46eea46d3606 2026-09-09 11:05:11,248 - DEBUG - ==>Verifying data for policy with id: 29f0f9ef-33d3-4d80-8464-07b9119cdf4a, fqn: ['default-domain', 'ctest-TestBasicPolicy-45315593', 'ctest-policy-allow-all-67145701'] 2026-09-09 11:05:11,248 - INFO - VN ctest-vn-06720451 Policy verification: verify_vn_policy_in_api_server, status: True 2026-09-09 11:05:11,248 - DEBUG - Verifying the vn in opserver 2026-09-09 11:05:11,248 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-06720451 virtual network link through opserver 10.0.0.250 2026-09-09 11:05:11,248 - DEBUG - Requesting: http://10.0.0.250:8081/analytics/uves/virtual-networks 2026-09-09 11:05:11,262 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-06720451', 'href': 'http://10.0.0.250:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-06720451?flat'} 2026-09-09 11:05:11,262 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-06720451 is found in opserver 2026-09-09 11:05:11,274 - DEBUG - Do not have enough data to verify VN in agent 2026-09-09 11:05:11,281 - DEBUG - VRF ids for VN ctest-vn-06720451: {} 2026-09-09 11:05:11,356 - DEBUG - Services list from nova: [, , ] 2026-09-09 11:05:11,725 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6267-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6267-1) 2026-09-09 11:05:12,200 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6267-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6267-1) 2026-09-09 11:05:12,200 - INFO - Waiting for VM ctest-TestBasicPolicy-45315593-59600172 to be up.. 2026-09-09 11:05:12,259 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:17,324 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:22,421 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:27,514 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:32,598 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:37,700 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:42,791 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:47,870 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 11:05:52,962 - DEBUG - VM is in ACTIVE state now 2026-09-09 11:05:52,962 - INFO - VM name : ctest-TestBasicPolicy-45315593-59600172 2026-09-09 11:05:53,056 - DEBUG - VM ctest-TestBasicPolicy-45315593-59600172 ID is ec40892e-4084-40a7-be93-78708131976f 2026-09-09 11:05:53,087 - DEBUG - VM ctest-TestBasicPolicy-45315593-59600172 launched on Node cn-jenkins-deploy-platform-ansible-os-6267-1 2026-09-09 11:05:53,200 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-machine/ec40892e-4084-40a7-be93-78708131976f 2026-09-09 11:05:53,210 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-machine-interface/4b62ca30-8e28-41b4-b45f-be41ddc5ebda 2026-09-09 11:05:56,478 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-09-09 11:05:56,478 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-45315593-59600172 failed! 2026-09-09 11:05:56,534 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-45315593:ctest-vn-63064665 is 129.251.36.129 and allocation pool is NOT set 2026-09-09 11:06:00,606 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.27 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1003ms\r\nrtt min/avg/max/mdev = 5.266/5.266/5.266/0.000 ms') 2026-09-09 11:06:00,606 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-45315593-59600172 passed 2026-09-09 11:06:00,778 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 11:06:00,779 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-45315593-59600172, IP 129.251.36.131, Port 22 2026-09-09 11:06:00,953 - DEBUG - VM ctest-TestBasicPolicy-45315593-59600172 is ready for SSH connections 2026-09-09 11:06:00,953 - INFO - Waiting for VM ctest-TestBasicPolicy-45315593-54805360 to be up.. 2026-09-09 11:06:01,059 - DEBUG - VM is in ACTIVE state now 2026-09-09 11:06:01,060 - INFO - VM name : ctest-TestBasicPolicy-45315593-54805360 2026-09-09 11:06:01,165 - DEBUG - VM ctest-TestBasicPolicy-45315593-54805360 ID is 442e612b-052b-424e-ace9-d9f1a3bd4714 2026-09-09 11:06:01,165 - DEBUG - VM ctest-TestBasicPolicy-45315593-54805360 launched on Node cn-jenkins-deploy-platform-ansible-os-6267-1 2026-09-09 11:06:01,267 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-machine/442e612b-052b-424e-ace9-d9f1a3bd4714 2026-09-09 11:06:01,280 - DEBUG - Requesting: http://10.0.0.250:8082/virtual-machine-interface/a441dec6-71b5-4493-9904-5afbc7b319b2 2026-09-09 11:06:02,527 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=4.63 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.668 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.668/2.650/4.632/1.982 ms') 2026-09-09 11:06:02,528 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-45315593-54805360 passed 2026-09-09 11:06:02,682 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 11:06:02,682 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-45315593-54805360, IP 32.247.36.67, Port 22 2026-09-09 11:06:02,862 - DEBUG - VM ctest-TestBasicPolicy-45315593-54805360 is ready for SSH connections 2026-09-09 11:06:02,863 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 11:06:02,863 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 32.247.36.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.250, gateway password: c0ntrail123 2026-09-09 11:06:02,863 - DEBUG - ping -s 56 -c 3 -W 1 32.247.36.67 2026-09-09 11:06:06,950 - DEBUG - PING 32.247.36.67 (32.247.36.67): 56 data bytes 64 bytes from 32.247.36.67: seq=0 ttl=63 time=5.894 ms 64 bytes from 32.247.36.67: seq=1 ttl=63 time=1.367 ms 64 bytes from 32.247.36.67: seq=2 ttl=63 time=1.070 ms --- 32.247.36.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.070/2.777/5.894 ms 2026-09-09 11:06:06,950 - INFO - Ping to IP 32.247.36.67 from VM ctest-TestBasicPolicy-45315593-59600172 passed 2026-09-09 11:06:07,134 - DEBUG - Policy Update Response {"network-policy": {"uuid": "29f0f9ef-33d3-4d80-8464-07b9119cdf4a", "href": "http://10.0.0.250:8082/network-policy/29f0f9ef-33d3-4d80-8464-07b9119cdf4a"}} 2026-09-09 11:06:07,134 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 11:06:07,134 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 32.247.36.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.250, gateway password: c0ntrail123 2026-09-09 11:06:07,134 - DEBUG - ping -s 56 -c 3 -W 1 32.247.36.67 2026-09-09 11:06:10,286 - DEBUG - PING 32.247.36.67 (32.247.36.67): 56 data bytes 64 bytes from 32.247.36.67: seq=0 ttl=63 time=2.473 ms --- 32.247.36.67 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 2.473/2.473/2.473 ms 2026-09-09 11:06:10,286 - WARNING - Ping to IP 32.247.36.67 from VM ctest-TestBasicPolicy-45315593-59600172 failed 2026-09-09 11:06:10,286 - INFO - Deleting VM ctest-TestBasicPolicy-45315593-54805360 2026-09-09 11:06:10,389 - INFO - Deleting VM ctest-TestBasicPolicy-45315593-59600172 2026-09-09 11:06:10,846 - INFO - Deleted policy ctest-policy-allow-all-67145701 2026-09-09 11:06:10,847 - INFO - Deleting VN ctest-vn-06720451 2026-09-09 11:06:10,885 - DEBUG - VN 4f8eb5e0-9f24-48b9-a455-46eea46d3606 still in use: Unable to complete operation on network 4f8eb5e0-9f24-48b9-a455-46eea46d3606. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-99cca246-2ef2-48f0-a430-4aca73c1c5d1'] 2026-09-09 11:06:10,885 - WARNING - Deleting VN ctest-vn-06720451 failed..Will retry 2026-09-09 11:06:13,019 - DEBUG - Response for deleting network () 2026-09-09 11:06:13,019 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/ab2c6d7c-660e-420d-ad19-957ee12b85d2 2026-09-09 11:06:13,027 - DEBUG - Response Code: 404 2026-09-09 11:06:13,027 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:06:13,037 - DEBUG - Requesting: http://10.0.0.250:8082/domain/8bbb7c25-989c-4100-9771-0b0c233d2e27 2026-09-09 11:06:13,097 - DEBUG - Requesting: http://10.0.0.250:8082/project/d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:06:13,187 - INFO - Validated that VN ctest-vn-06720451 is not found in API Server 2026-09-09 11:06:13,203 - DEBUG - VN ctest-vn-06720451 is not present in Agent 10.0.0.250 2026-09-09 11:06:13,203 - INFO - Validated that VN ctest-vn-06720451 is not in any agent 2026-09-09 11:06:13,225 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-06720451 info 2026-09-09 11:06:13,225 - INFO - Deleting VN ctest-vn-63064665 2026-09-09 11:06:13,376 - DEBUG - Response for deleting network () 2026-09-09 11:06:13,376 - DEBUG - Requesting: http://10.0.0.250:8082/routing-instance/d5a26ed1-b013-436a-b74b-1628132b9a7a 2026-09-09 11:06:13,386 - DEBUG - Response Code: 404 2026-09-09 11:06:13,386 - DEBUG - Requesting: http://10.0.0.250:8082/domains 2026-09-09 11:06:13,394 - DEBUG - Requesting: http://10.0.0.250:8082/domain/8bbb7c25-989c-4100-9771-0b0c233d2e27 2026-09-09 11:06:13,448 - DEBUG - Requesting: http://10.0.0.250:8082/project/d05e61e8-df1c-475c-8b3e-599c0784d070 2026-09-09 11:06:13,535 - INFO - Validated that VN ctest-vn-63064665 is not found in API Server 2026-09-09 11:06:13,547 - DEBUG - VN ctest-vn-63064665 is not present in Agent 10.0.0.250 2026-09-09 11:06:13,547 - INFO - Validated that VN ctest-vn-63064665 is not in any agent 2026-09-09 11:06:13,563 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-63064665 info 2026-09-09 11:06:13,835 - DEBUG - No XMPP flaps were noticed during the test
2026-09-09 11:06:13,835 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:07]
2026-09-09 11:06:13,835 - INFO - -------------------------------------------------------------------------------- 2026-09-09 11:06:14,617 - INFO - Deleted project: ctest-TestBasicPolicy-45315593, ID : d05e61e8-df1c-475c-8b3e-599c0784d070