2026-08-25 20:58:07,341 - INFO - Domain Default found not creating 2026-08-25 20:58:07,497 - INFO - Project ctest-TestBasicPolicy-87176566 not found, creating it 2026-08-25 20:58:08,010 - INFO - Created Project:ctest-TestBasicPolicy-87176566, ID : 31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:58:09,537 - INFO - ================================================================================ 2026-08-25 20:58:09,537 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-08-25 20:58:09,537 - 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-08-25 20:58:09,793 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.25': '0'}} with 2026-08-25 20:58:09,793 - INFO - Initial checks done. Running the testcase now 2026-08-25 20:58:09,793 - INFO - 2026-08-25 20:58:10,370 - DEBUG - Response for create_network : {'network': {'id': '48bc5580-2329-4720-9052-18078d728c14', 'name': 'ctest-vn-05188621', 'tenant_id': '31c7352593eb4f709d77a01a78d5f2b8', 'project_id': '31c7352593eb4f709d77a01a78d5f2b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-vn-05188621'], 'port_security_enabled': True, 'description': ''}} 2026-08-25 20:58:10,569 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca75324e-c80d-4f60-aec9-1a47b5ced880', 'name': '', 'tenant_id': '31c7352593eb4f709d77a01a78d5f2b8', 'network_id': '48bc5580-2329-4720-9052-18078d728c14', 'ip_version': 4, 'cidr': '183.217.247.128/26', 'allocation_pools': [{'start': '183.217.247.130', 'end': '183.217.247.190'}], 'gateway_ip': '183.217.247.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '183.217.247.130', 'tags': [], 'project_id': '31c7352593eb4f709d77a01a78d5f2b8'}} 2026-08-25 20:58:10,587 - INFO - Created VN ctest-vn-05188621 2026-08-25 20:58:10,642 - DEBUG - VN ctest-vn-05188621 UUID is 48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:10,940 - DEBUG - Response for create_network : {'network': {'id': '64d729a0-91e1-4a05-9a20-04a1fe482004', 'name': 'ctest-vn-33266649', 'tenant_id': '31c7352593eb4f709d77a01a78d5f2b8', 'project_id': '31c7352593eb4f709d77a01a78d5f2b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-vn-33266649'], 'port_security_enabled': True, 'description': ''}} 2026-08-25 20:58:11,111 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f434f9e1-2b89-4024-96bb-debe562ea383', 'name': '', 'tenant_id': '31c7352593eb4f709d77a01a78d5f2b8', 'network_id': '64d729a0-91e1-4a05-9a20-04a1fe482004', 'ip_version': 4, 'cidr': '56.211.188.0/26', 'allocation_pools': [{'start': '56.211.188.2', 'end': '56.211.188.62'}], 'gateway_ip': '56.211.188.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.211.188.2', 'tags': [], 'project_id': '31c7352593eb4f709d77a01a78d5f2b8'}} 2026-08-25 20:58:11,127 - INFO - Created VN ctest-vn-33266649 2026-08-25 20:58:11,178 - DEBUG - VN ctest-vn-33266649 UUID is 64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:11,330 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621, 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-87176566:ctest-vn-33266649, 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-08-25 20:58:11,748 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '48bc5580-2329-4720-9052-18078d728c14', 'name': 'ctest-vn-05188621', 'tenant_id': '31c7352593eb4f709d77a01a78d5f2b8', 'project_id': '31c7352593eb4f709d77a01a78d5f2b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['ca75324e-c80d-4f60-aec9-1a47b5ced880'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-vn-05188621'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-policy-allow-all-35085203']], 'subnet_ipam': [{'subnet_cidr': '183.217.247.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-08-25 20:58:11,995 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '64d729a0-91e1-4a05-9a20-04a1fe482004', 'name': 'ctest-vn-33266649', 'tenant_id': '31c7352593eb4f709d77a01a78d5f2b8', 'project_id': '31c7352593eb4f709d77a01a78d5f2b8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['f434f9e1-2b89-4024-96bb-debe562ea383'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-vn-33266649'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-policy-allow-all-35085203']], 'subnet_ipam': [{'subnet_cidr': '56.211.188.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-08-25 20:58:12,166 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:58:12,499 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:58:12,528 - DEBUG - Requesting: http://10.0.0.161:8082/domain/cd32befe-7dff-4772-ad77-7c5510de2390 2026-08-25 20:58:12,560 - DEBUG - Requesting: http://10.0.0.161:8082/project/31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:58:12,646 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:12,657 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:12,666 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/624540b9-bdf9-4a91-a12a-d2674383f621 2026-08-25 20:58:12,673 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/624540b9-bdf9-4a91-a12a-d2674383f621 2026-08-25 20:58:12,679 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/23cf64c1-751e-4e94-8ec4-316d9463fc5f 2026-08-25 20:58:12,685 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-25 20:58:12,685 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:12,694 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/624540b9-bdf9-4a91-a12a-d2674383f621 2026-08-25 20:58:12,707 - INFO - Verified VN network id 11 for VN 48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:12,707 - INFO - Verifications in API Server for VN ctest-vn-05188621 passed 2026-08-25 20:58:12,707 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:12,715 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/624540b9-bdf9-4a91-a12a-d2674383f621 2026-08-25 20:58:12,722 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/624540b9-bdf9-4a91-a12a-d2674383f621 2026-08-25 20:58:12,727 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/23cf64c1-751e-4e94-8ec4-316d9463fc5f 2026-08-25 20:58:12,739 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-87176566', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5241158075186497312', 'uuid-lslong': '10399400910268828692'}, 'enable': 'true', 'created': '2026-08-25T20:58:10', 'last-modified': '2026-08-25T20:58:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.125720'} 2026-08-25 20:58:12,746 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-25 20:58:12,746 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-05188621 passed 2026-08-25 20:58:12,746 - DEBUG - ====Verifying policy data for ctest-vn-05188621 in API_Server ====== 2026-08-25 20:58:12,746 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:58:12,753 - DEBUG - Requesting: http://10.0.0.161:8082/domain/cd32befe-7dff-4772-ad77-7c5510de2390 2026-08-25 20:58:12,764 - DEBUG - Requesting: http://10.0.0.161:8082/project/31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:58:12,849 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/48bc5580-2329-4720-9052-18078d728c14 2026-08-25 20:58:12,862 - DEBUG - ==>Verifying data for policy with id: 406ca7c5-42a4-4d5c-bec0-e4a0a3171615, fqn: ['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-policy-allow-all-35085203'] 2026-08-25 20:58:12,862 - INFO - VN ctest-vn-05188621 Policy verification: verify_vn_policy_in_api_server, status: True 2026-08-25 20:58:12,862 - DEBUG - Verifying the vn in opserver 2026-08-25 20:58:12,862 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621 virtual network link through opserver 10.0.0.161 2026-08-25 20:58:12,863 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-08-25 20:58:13,182 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-08-25 20:58:13,234 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621?flat'} 2026-08-25 20:58:13,234 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621 is found in opserver 2026-08-25 20:58:13,244 - DEBUG - Do not have enough data to verify VN in agent 2026-08-25 20:58:13,249 - DEBUG - VRF ids for VN ctest-vn-05188621: {} 2026-08-25 20:58:13,249 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:58:13,255 - DEBUG - Requesting: http://10.0.0.161:8082/domain/cd32befe-7dff-4772-ad77-7c5510de2390 2026-08-25 20:58:13,267 - DEBUG - Requesting: http://10.0.0.161:8082/project/31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:58:13,346 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:13,359 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:13,369 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/d65a3812-2d16-425a-94b8-02a927b66181 2026-08-25 20:58:13,378 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/d65a3812-2d16-425a-94b8-02a927b66181 2026-08-25 20:58:13,385 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/e22b8fce-f5be-4382-8aa5-ef47611583cd 2026-08-25 20:58:13,392 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-25 20:58:13,392 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:13,401 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/d65a3812-2d16-425a-94b8-02a927b66181 2026-08-25 20:58:13,416 - INFO - Verified VN network id 12 for VN 64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:13,416 - INFO - Verifications in API Server for VN ctest-vn-33266649 passed 2026-08-25 20:58:13,416 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:13,425 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/d65a3812-2d16-425a-94b8-02a927b66181 2026-08-25 20:58:13,431 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/d65a3812-2d16-425a-94b8-02a927b66181 2026-08-25 20:58:13,437 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/e22b8fce-f5be-4382-8aa5-ef47611583cd 2026-08-25 20:58:13,450 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-33266649', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-87176566', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7266322293404551685', 'uuid-lslong': '11105881774898028548'}, 'enable': 'true', 'created': '2026-08-25T20:58:10', 'last-modified': '2026-08-25T20:58:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.489259'} 2026-08-25 20:58:13,457 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-25 20:58:13,457 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-33266649 passed 2026-08-25 20:58:13,458 - DEBUG - ====Verifying policy data for ctest-vn-33266649 in API_Server ====== 2026-08-25 20:58:13,458 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:58:13,464 - DEBUG - Requesting: http://10.0.0.161:8082/domain/cd32befe-7dff-4772-ad77-7c5510de2390 2026-08-25 20:58:13,476 - DEBUG - Requesting: http://10.0.0.161:8082/project/31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:58:13,554 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/64d729a0-91e1-4a05-9a20-04a1fe482004 2026-08-25 20:58:13,565 - DEBUG - ==>Verifying data for policy with id: 406ca7c5-42a4-4d5c-bec0-e4a0a3171615, fqn: ['default-domain', 'ctest-TestBasicPolicy-87176566', 'ctest-policy-allow-all-35085203'] 2026-08-25 20:58:13,565 - INFO - VN ctest-vn-33266649 Policy verification: verify_vn_policy_in_api_server, status: True 2026-08-25 20:58:13,565 - DEBUG - Verifying the vn in opserver 2026-08-25 20:58:13,565 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-33266649 virtual network link through opserver 10.0.0.161 2026-08-25 20:58:13,565 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-08-25 20:58:13,576 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-33266649', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-33266649?flat'} 2026-08-25 20:58:13,576 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-33266649 is found in opserver 2026-08-25 20:58:13,590 - DEBUG - Do not have enough data to verify VN in agent 2026-08-25 20:58:13,595 - DEBUG - VRF ids for VN ctest-vn-33266649: {} 2026-08-25 20:58:13,657 - DEBUG - Services list from nova: [, , ] 2026-08-25 20:58:14,115 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6228-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6228-1) 2026-08-25 20:58:14,510 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6228-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6228-1) 2026-08-25 20:58:14,510 - INFO - Waiting for VM ctest-TestBasicPolicy-87176566-85834073 to be up.. 2026-08-25 20:58:14,552 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:19,623 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:24,691 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:29,772 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:34,846 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:39,923 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:45,008 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 20:58:50,085 - DEBUG - VM is in ACTIVE state now 2026-08-25 20:58:50,086 - INFO - VM name : ctest-TestBasicPolicy-87176566-85834073 2026-08-25 20:58:50,164 - DEBUG - VM ctest-TestBasicPolicy-87176566-85834073 ID is eb642f99-28c2-430e-bfb8-8b584bc5912c 2026-08-25 20:58:50,184 - DEBUG - VM ctest-TestBasicPolicy-87176566-85834073 launched on Node cn-jenkins-deploy-platform-ansible-os-6228-1 2026-08-25 20:58:50,274 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/eb642f99-28c2-430e-bfb8-8b584bc5912c 2026-08-25 20:58:50,284 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/48cf9eab-4f45-4708-b1b0-8e40768a5cba 2026-08-25 20:58:53,533 - 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 1002ms') 2026-08-25 20:58:53,534 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-87176566-85834073 failed! 2026-08-25 20:58:53,586 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-05188621 is 183.217.247.129 and allocation pool is NOT set 2026-08-25 20:58:55,652 - 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=1 ttl=63 time=3.23 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.643 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.643/1.935/3.227/1.292 ms') 2026-08-25 20:58:55,652 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-87176566-85834073 passed 2026-08-25 20:58:55,806 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 20:58:55,806 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-87176566-85834073, IP 183.217.247.131, Port 22 2026-08-25 20:58:55,962 - DEBUG - VM ctest-TestBasicPolicy-87176566-85834073 is ready for SSH connections 2026-08-25 20:58:55,962 - INFO - Waiting for VM ctest-TestBasicPolicy-87176566-81758810 to be up.. 2026-08-25 20:58:56,040 - DEBUG - VM is in ACTIVE state now 2026-08-25 20:58:56,041 - INFO - VM name : ctest-TestBasicPolicy-87176566-81758810 2026-08-25 20:58:56,119 - DEBUG - VM ctest-TestBasicPolicy-87176566-81758810 ID is 543a1b23-b0b3-4d7e-aa57-e1f827e681a5 2026-08-25 20:58:56,119 - DEBUG - VM ctest-TestBasicPolicy-87176566-81758810 launched on Node cn-jenkins-deploy-platform-ansible-os-6228-1 2026-08-25 20:58:56,191 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/543a1b23-b0b3-4d7e-aa57-e1f827e681a5 2026-08-25 20:58:56,200 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/613a9016-a7ea-48ed-b0eb-859193fd2136 2026-08-25 20:58:59,456 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-08-25 20:58:59,456 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-87176566-81758810 failed! 2026-08-25 20:58:59,514 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-87176566:ctest-vn-33266649 is 56.211.188.1 and allocation pool is NOT set 2026-08-25 20:59:01,571 - 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=3.89 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.600 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.600/2.247/3.894/1.647 ms') 2026-08-25 20:59:01,571 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-87176566-81758810 passed 2026-08-25 20:59:01,722 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 20:59:01,723 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-87176566-81758810, IP 56.211.188.3, Port 22 2026-08-25 20:59:01,889 - DEBUG - VM ctest-TestBasicPolicy-87176566-81758810 is ready for SSH connections 2026-08-25 20:59:01,889 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 20:59:01,889 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 56.211.188.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-25 20:59:01,890 - DEBUG - ping -s 56 -c 3 -W 1 56.211.188.3 2026-08-25 20:59:05,801 - DEBUG - PING 56.211.188.3 (56.211.188.3): 56 data bytes 64 bytes from 56.211.188.3: seq=0 ttl=63 time=3.767 ms 64 bytes from 56.211.188.3: seq=1 ttl=63 time=1.157 ms 64 bytes from 56.211.188.3: seq=2 ttl=63 time=1.097 ms --- 56.211.188.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.097/2.007/3.767 ms 2026-08-25 20:59:05,801 - INFO - Ping to IP 56.211.188.3 from VM ctest-TestBasicPolicy-87176566-85834073 passed 2026-08-25 20:59:05,994 - DEBUG - Policy Update Response {"network-policy": {"uuid": "406ca7c5-42a4-4d5c-bec0-e4a0a3171615", "href": "http://10.0.0.161:8082/network-policy/406ca7c5-42a4-4d5c-bec0-e4a0a3171615"}} 2026-08-25 20:59:05,994 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 20:59:05,994 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 56.211.188.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-25 20:59:05,995 - DEBUG - ping -s 56 -c 3 -W 1 56.211.188.3 2026-08-25 20:59:09,122 - DEBUG - PING 56.211.188.3 (56.211.188.3): 56 data bytes 64 bytes from 56.211.188.3: seq=0 ttl=63 time=1.357 ms --- 56.211.188.3 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.357/1.357/1.357 ms 2026-08-25 20:59:09,122 - WARNING - Ping to IP 56.211.188.3 from VM ctest-TestBasicPolicy-87176566-85834073 failed 2026-08-25 20:59:09,122 - INFO - Deleting VM ctest-TestBasicPolicy-87176566-81758810 2026-08-25 20:59:09,199 - INFO - Deleting VM ctest-TestBasicPolicy-87176566-85834073 2026-08-25 20:59:09,586 - INFO - Deleted policy ctest-policy-allow-all-35085203 2026-08-25 20:59:09,587 - INFO - Deleting VN ctest-vn-33266649 2026-08-25 20:59:09,626 - DEBUG - VN 64d729a0-91e1-4a05-9a20-04a1fe482004 still in use: Unable to complete operation on network 64d729a0-91e1-4a05-9a20-04a1fe482004. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5b28bfce-6da7-451b-b3c7-fa9e3e15a853'] 2026-08-25 20:59:09,626 - WARNING - Deleting VN ctest-vn-33266649 failed..Will retry 2026-08-25 20:59:11,749 - DEBUG - Response for deleting network () 2026-08-25 20:59:11,749 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/d65a3812-2d16-425a-94b8-02a927b66181 2026-08-25 20:59:11,756 - DEBUG - Response Code: 404 2026-08-25 20:59:11,756 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:59:11,762 - DEBUG - Requesting: http://10.0.0.161:8082/domain/cd32befe-7dff-4772-ad77-7c5510de2390 2026-08-25 20:59:11,817 - DEBUG - Requesting: http://10.0.0.161:8082/project/31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:59:11,910 - INFO - Validated that VN ctest-vn-33266649 is not found in API Server 2026-08-25 20:59:11,920 - DEBUG - VN ctest-vn-33266649 is not present in Agent 10.0.0.161 2026-08-25 20:59:11,920 - INFO - Validated that VN ctest-vn-33266649 is not in any agent 2026-08-25 20:59:11,939 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-33266649 info 2026-08-25 20:59:11,939 - INFO - Deleting VN ctest-vn-05188621 2026-08-25 20:59:12,080 - DEBUG - Response for deleting network () 2026-08-25 20:59:12,080 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/624540b9-bdf9-4a91-a12a-d2674383f621 2026-08-25 20:59:12,085 - DEBUG - Response Code: 404 2026-08-25 20:59:12,086 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-25 20:59:12,090 - DEBUG - Requesting: http://10.0.0.161:8082/domain/cd32befe-7dff-4772-ad77-7c5510de2390 2026-08-25 20:59:12,143 - DEBUG - Requesting: http://10.0.0.161:8082/project/31c73525-93eb-4f70-9d77-a01a78d5f2b8 2026-08-25 20:59:12,227 - INFO - Validated that VN ctest-vn-05188621 is not found in API Server 2026-08-25 20:59:12,240 - DEBUG - VN ctest-vn-05188621 is not present in Agent 10.0.0.161 2026-08-25 20:59:12,240 - INFO - Validated that VN ctest-vn-05188621 is not in any agent 2026-08-25 20:59:12,257 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-05188621 info 2026-08-25 20:59:12,521 - DEBUG - No XMPP flaps were noticed during the test
2026-08-25 20:59:12,521 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:03]
2026-08-25 20:59:12,521 - INFO - -------------------------------------------------------------------------------- 2026-08-25 20:59:13,255 - INFO - Deleted project: ctest-TestBasicPolicy-87176566, ID : 31c73525-93eb-4f70-9d77-a01a78d5f2b8