2025-04-29 09:58:05,769 - INFO - Domain Default found not creating 2025-04-29 09:58:05,882 - INFO - Project ctest-TestBasicPolicy-66215172 not found, creating it 2025-04-29 09:58:06,353 - INFO - Created Project:ctest-TestBasicPolicy-66215172, ID : 3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:07,638 - INFO - ================================================================================ 2025-04-29 09:58:07,639 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-04-29 09:58:07,639 - 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 2025-04-29 09:58:07,892 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2025-04-29 09:58:07,892 - INFO - Initial checks done. Running the testcase now 2025-04-29 09:58:07,892 - INFO - 2025-04-29 09:58:08,366 - DEBUG - Response for create_network : {'network': {'id': '13ecab36-3829-4f1e-b119-4e0c1b20fda1', 'name': 'ctest-vn-68052629', 'tenant_id': '3c0d4debae9e4f2e8558c11a69b79313', 'project_id': '3c0d4debae9e4f2e8558c11a69b79313', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-vn-68052629'], 'port_security_enabled': True, 'description': ''}} 2025-04-29 09:58:08,542 - DEBUG - Response for create_subnet : {'subnet': {'id': '4abc8dca-13f7-4e37-ae41-31b56236fae3', 'name': '', 'tenant_id': '3c0d4debae9e4f2e8558c11a69b79313', 'network_id': '13ecab36-3829-4f1e-b119-4e0c1b20fda1', 'ip_version': 4, 'cidr': '67.141.179.64/26', 'allocation_pools': [{'start': '67.141.179.66', 'end': '67.141.179.126'}], 'gateway_ip': '67.141.179.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '67.141.179.66', 'tags': [], 'project_id': '3c0d4debae9e4f2e8558c11a69b79313'}} 2025-04-29 09:58:08,558 - INFO - Created VN ctest-vn-68052629 2025-04-29 09:58:08,609 - DEBUG - VN ctest-vn-68052629 UUID is 13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:09,047 - DEBUG - Response for create_network : {'network': {'id': '23b88280-af64-4558-969e-950c38d83050', 'name': 'ctest-vn-50903447', 'tenant_id': '3c0d4debae9e4f2e8558c11a69b79313', 'project_id': '3c0d4debae9e4f2e8558c11a69b79313', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-vn-50903447'], 'port_security_enabled': True, 'description': ''}} 2025-04-29 09:58:09,221 - DEBUG - Response for create_subnet : {'subnet': {'id': '15249aa3-9b6d-4138-b83a-8b551963b3f1', 'name': '', 'tenant_id': '3c0d4debae9e4f2e8558c11a69b79313', 'network_id': '23b88280-af64-4558-969e-950c38d83050', 'ip_version': 4, 'cidr': '170.53.179.192/26', 'allocation_pools': [{'start': '170.53.179.194', 'end': '170.53.179.254'}], 'gateway_ip': '170.53.179.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '170.53.179.194', 'tags': [], 'project_id': '3c0d4debae9e4f2e8558c11a69b79313'}} 2025-04-29 09:58:09,239 - INFO - Created VN ctest-vn-50903447 2025-04-29 09:58:09,293 - DEBUG - VN ctest-vn-50903447 UUID is 23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:09,433 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629, 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-66215172:ctest-vn-50903447, 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] 2025-04-29 09:58:09,716 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '13ecab36-3829-4f1e-b119-4e0c1b20fda1', 'name': 'ctest-vn-68052629', 'tenant_id': '3c0d4debae9e4f2e8558c11a69b79313', 'project_id': '3c0d4debae9e4f2e8558c11a69b79313', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['4abc8dca-13f7-4e37-ae41-31b56236fae3'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-vn-68052629'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-policy-allow-all-11840966']], 'subnet_ipam': [{'subnet_cidr': '67.141.179.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-04-29 09:58:09,986 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '23b88280-af64-4558-969e-950c38d83050', 'name': 'ctest-vn-50903447', 'tenant_id': '3c0d4debae9e4f2e8558c11a69b79313', 'project_id': '3c0d4debae9e4f2e8558c11a69b79313', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['15249aa3-9b6d-4138-b83a-8b551963b3f1'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-vn-50903447'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-policy-allow-all-11840966']], 'subnet_ipam': [{'subnet_cidr': '170.53.179.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-04-29 09:58:10,133 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:10,401 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:10,426 - DEBUG - Requesting: http://10.0.0.22:8082/domain/e5a67299-2367-48ba-930b-25db39b38f17 2025-04-29 09:58:10,443 - DEBUG - Requesting: http://10.0.0.22:8082/project/3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:10,549 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:10,589 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:10,603 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/727c1d22-529b-4087-a4b2-e1b47eafc4aa 2025-04-29 09:58:10,613 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/727c1d22-529b-4087-a4b2-e1b47eafc4aa 2025-04-29 09:58:10,636 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/514c2b29-09ba-4a8e-96ea-119959c05f98 2025-04-29 09:58:10,644 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-29 09:58:10,644 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:10,664 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/727c1d22-529b-4087-a4b2-e1b47eafc4aa 2025-04-29 09:58:10,693 - INFO - Verified VN network id 9 for VN 13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:10,693 - INFO - Verifications in API Server for VN ctest-vn-68052629 passed 2025-04-29 09:58:10,693 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:10,704 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/727c1d22-529b-4087-a4b2-e1b47eafc4aa 2025-04-29 09:58:10,717 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/727c1d22-529b-4087-a4b2-e1b47eafc4aa 2025-04-29 09:58:10,729 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/514c2b29-09ba-4a8e-96ea-119959c05f98 2025-04-29 09:58:10,741 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-66215172', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1435710630583160606', 'uuid-lslong': '12761316833032732065'}, 'enable': 'true', 'created': '2025-04-29T09:58:08', 'last-modified': '2025-04-29T09:58:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.043472'} 2025-04-29 09:58:10,747 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-29 09:58:10,747 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-68052629 passed 2025-04-29 09:58:10,748 - DEBUG - ====Verifying policy data for ctest-vn-68052629 in API_Server ====== 2025-04-29 09:58:10,748 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:10,766 - DEBUG - Requesting: http://10.0.0.22:8082/domain/e5a67299-2367-48ba-930b-25db39b38f17 2025-04-29 09:58:10,788 - DEBUG - Requesting: http://10.0.0.22:8082/project/3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:10,887 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/13ecab36-3829-4f1e-b119-4e0c1b20fda1 2025-04-29 09:58:10,902 - DEBUG - ==>Verifying data for policy with id: 0cb7fc7c-11f3-43b1-8c38-9e8d78e1fada, fqn: ['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-policy-allow-all-11840966'] 2025-04-29 09:58:10,902 - INFO - VN ctest-vn-68052629 Policy verification: verify_vn_policy_in_api_server, status: True 2025-04-29 09:58:10,902 - DEBUG - Verifying the vn in opserver 2025-04-29 09:58:10,902 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629 virtual network link through opserver 10.0.0.22 2025-04-29 09:58:10,902 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-04-29 09:58:11,208 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-04-29 09:58:11,260 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629?flat'} 2025-04-29 09:58:11,260 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629 is found in opserver 2025-04-29 09:58:11,270 - DEBUG - Do not have enough data to verify VN in agent 2025-04-29 09:58:11,278 - DEBUG - VRF ids for VN ctest-vn-68052629: {} 2025-04-29 09:58:11,278 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:11,284 - DEBUG - Requesting: http://10.0.0.22:8082/domain/e5a67299-2367-48ba-930b-25db39b38f17 2025-04-29 09:58:11,303 - DEBUG - Requesting: http://10.0.0.22:8082/project/3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:11,376 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:11,392 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:11,403 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/68c0f250-3dbf-43cf-9584-5a8cfbf12d1c 2025-04-29 09:58:11,411 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/68c0f250-3dbf-43cf-9584-5a8cfbf12d1c 2025-04-29 09:58:11,419 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/641cbe63-2ea8-49b9-990e-c7e4f1e8ec0c 2025-04-29 09:58:11,426 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-04-29 09:58:11,426 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:11,435 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/68c0f250-3dbf-43cf-9584-5a8cfbf12d1c 2025-04-29 09:58:11,448 - INFO - Verified VN network id 10 for VN 23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:11,448 - INFO - Verifications in API Server for VN ctest-vn-50903447 passed 2025-04-29 09:58:11,448 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:11,456 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/68c0f250-3dbf-43cf-9584-5a8cfbf12d1c 2025-04-29 09:58:11,462 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/68c0f250-3dbf-43cf-9584-5a8cfbf12d1c 2025-04-29 09:58:11,468 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/641cbe63-2ea8-49b9-990e-c7e4f1e8ec0c 2025-04-29 09:58:11,479 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-50903447', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-66215172', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2573950676252247384', 'uuid-lslong': '10853276031735312464'}, 'enable': 'true', 'created': '2025-04-29T09:58:09', 'last-modified': '2025-04-29T09:58:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.555585'} 2025-04-29 09:58:11,484 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-04-29 09:58:11,484 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-50903447 passed 2025-04-29 09:58:11,485 - DEBUG - ====Verifying policy data for ctest-vn-50903447 in API_Server ====== 2025-04-29 09:58:11,485 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:11,490 - DEBUG - Requesting: http://10.0.0.22:8082/domain/e5a67299-2367-48ba-930b-25db39b38f17 2025-04-29 09:58:11,500 - DEBUG - Requesting: http://10.0.0.22:8082/project/3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:11,569 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/23b88280-af64-4558-969e-950c38d83050 2025-04-29 09:58:11,578 - DEBUG - ==>Verifying data for policy with id: 0cb7fc7c-11f3-43b1-8c38-9e8d78e1fada, fqn: ['default-domain', 'ctest-TestBasicPolicy-66215172', 'ctest-policy-allow-all-11840966'] 2025-04-29 09:58:11,578 - INFO - VN ctest-vn-50903447 Policy verification: verify_vn_policy_in_api_server, status: True 2025-04-29 09:58:11,578 - DEBUG - Verifying the vn in opserver 2025-04-29 09:58:11,578 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-50903447 virtual network link through opserver 10.0.0.22 2025-04-29 09:58:11,578 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-04-29 09:58:11,587 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-50903447', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-50903447?flat'} 2025-04-29 09:58:11,587 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-50903447 is found in opserver 2025-04-29 09:58:11,597 - DEBUG - Do not have enough data to verify VN in agent 2025-04-29 09:58:11,601 - DEBUG - VRF ids for VN ctest-vn-50903447: {} 2025-04-29 09:58:11,648 - DEBUG - Services list from nova: [, , ] 2025-04-29 09:58:12,529 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3246-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3246-1) 2025-04-29 09:58:13,623 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3246-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3246-1) 2025-04-29 09:58:13,623 - INFO - Waiting for VM ctest-TestBasicPolicy-66215172-72898950 to be up.. 2025-04-29 09:58:13,698 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-04-29 09:58:18,767 - DEBUG - VM is in ACTIVE state now 2025-04-29 09:58:18,767 - INFO - VM name : ctest-TestBasicPolicy-66215172-72898950 2025-04-29 09:58:18,847 - DEBUG - VM ctest-TestBasicPolicy-66215172-72898950 ID is a8882c90-a127-4d04-a1d4-243bd6ec5e20 2025-04-29 09:58:18,864 - DEBUG - VM ctest-TestBasicPolicy-66215172-72898950 launched on Node cn-jenkins-deploy-platform-ansible-os-3246-1 2025-04-29 09:58:18,961 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/a8882c90-a127-4d04-a1d4-243bd6ec5e20 2025-04-29 09:58:18,971 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/5ab4ca1a-f935-4283-a202-79b68dcd16c5 2025-04-29 09:58:22,232 - 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 1009ms') 2025-04-29 09:58:22,232 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-66215172-72898950 failed! 2025-04-29 09:58:22,289 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-66215172:ctest-vn-68052629 is 67.141.179.65 and allocation pool is NOT set 2025-04-29 09:58:24,349 - 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=2.57 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.407 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.407/1.490/2.573/1.083 ms') 2025-04-29 09:58:24,349 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-66215172-72898950 passed 2025-04-29 09:58:24,501 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-29 09:58:24,501 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-66215172-72898950, IP 67.141.179.67, Port 22 2025-04-29 09:58:24,660 - DEBUG - VM ctest-TestBasicPolicy-66215172-72898950 is ready for SSH connections 2025-04-29 09:58:24,661 - INFO - Waiting for VM ctest-TestBasicPolicy-66215172-82232201 to be up.. 2025-04-29 09:58:24,741 - DEBUG - VM is in ACTIVE state now 2025-04-29 09:58:24,742 - INFO - VM name : ctest-TestBasicPolicy-66215172-82232201 2025-04-29 09:58:24,812 - DEBUG - VM ctest-TestBasicPolicy-66215172-82232201 ID is b3e9c2c5-c1f6-4f51-afe9-03c24c3e0564 2025-04-29 09:58:24,813 - DEBUG - VM ctest-TestBasicPolicy-66215172-82232201 launched on Node cn-jenkins-deploy-platform-ansible-os-3246-1 2025-04-29 09:58:24,875 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/b3e9c2c5-c1f6-4f51-afe9-03c24c3e0564 2025-04-29 09:58:24,882 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/943570db-e778-45a7-92d2-0722979764a9 2025-04-29 09:58:26,120 - 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=2.62 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.375 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.375/1.496/2.617/1.121 ms') 2025-04-29 09:58:26,120 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-66215172-82232201 passed 2025-04-29 09:58:26,269 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-29 09:58:26,269 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-66215172-82232201, IP 170.53.179.195, Port 22 2025-04-29 09:58:26,421 - DEBUG - VM ctest-TestBasicPolicy-66215172-82232201 is ready for SSH connections 2025-04-29 09:58:26,421 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-29 09:58:26,421 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 170.53.179.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-04-29 09:58:26,421 - DEBUG - ping -s 56 -c 3 -W 1 170.53.179.195 2025-04-29 09:58:30,278 - DEBUG - PING 170.53.179.195 (170.53.179.195): 56 data bytes 64 bytes from 170.53.179.195: seq=0 ttl=63 time=2.953 ms 64 bytes from 170.53.179.195: seq=1 ttl=63 time=0.861 ms 64 bytes from 170.53.179.195: seq=2 ttl=63 time=0.658 ms --- 170.53.179.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.658/1.490/2.953 ms 2025-04-29 09:58:30,278 - INFO - Ping to IP 170.53.179.195 from VM ctest-TestBasicPolicy-66215172-72898950 passed 2025-04-29 09:58:30,452 - DEBUG - Policy Update Response {"network-policy": {"uuid": "0cb7fc7c-11f3-43b1-8c38-9e8d78e1fada", "href": "http://10.0.0.22:8082/network-policy/0cb7fc7c-11f3-43b1-8c38-9e8d78e1fada"}} 2025-04-29 09:58:30,453 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-29 09:58:30,453 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 170.53.179.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-04-29 09:58:30,453 - DEBUG - ping -s 56 -c 3 -W 1 170.53.179.195 2025-04-29 09:58:33,644 - DEBUG - PING 170.53.179.195 (170.53.179.195): 56 data bytes --- 170.53.179.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-04-29 09:58:33,644 - WARNING - Ping to IP 170.53.179.195 from VM ctest-TestBasicPolicy-66215172-72898950 failed 2025-04-29 09:58:33,645 - INFO - Deleting VM ctest-TestBasicPolicy-66215172-82232201 2025-04-29 09:58:33,699 - INFO - Deleting VM ctest-TestBasicPolicy-66215172-72898950 2025-04-29 09:58:34,062 - INFO - Deleted policy ctest-policy-allow-all-11840966 2025-04-29 09:58:34,062 - INFO - Deleting VN ctest-vn-50903447 2025-04-29 09:58:34,091 - DEBUG - VN 23b88280-af64-4558-969e-950c38d83050 still in use: Unable to complete operation on network 23b88280-af64-4558-969e-950c38d83050. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9b60f37f-7f3f-4679-8470-539dc7834d61'] 2025-04-29 09:58:34,091 - WARNING - Deleting VN ctest-vn-50903447 failed..Will retry 2025-04-29 09:58:36,185 - DEBUG - Response for deleting network () 2025-04-29 09:58:36,185 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/68c0f250-3dbf-43cf-9584-5a8cfbf12d1c 2025-04-29 09:58:36,192 - DEBUG - Response Code: 404 2025-04-29 09:58:36,193 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:36,198 - DEBUG - Requesting: http://10.0.0.22:8082/domain/e5a67299-2367-48ba-930b-25db39b38f17 2025-04-29 09:58:36,250 - DEBUG - Requesting: http://10.0.0.22:8082/project/3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:36,316 - INFO - Validated that VN ctest-vn-50903447 is not found in API Server 2025-04-29 09:58:36,326 - DEBUG - VN ctest-vn-50903447 is not present in Agent 10.0.0.22 2025-04-29 09:58:36,326 - INFO - Validated that VN ctest-vn-50903447 is not in any agent 2025-04-29 09:58:36,340 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-50903447 info 2025-04-29 09:58:36,340 - INFO - Deleting VN ctest-vn-68052629 2025-04-29 09:58:36,422 - DEBUG - Response for deleting network () 2025-04-29 09:58:36,422 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/727c1d22-529b-4087-a4b2-e1b47eafc4aa 2025-04-29 09:58:36,429 - DEBUG - Response Code: 404 2025-04-29 09:58:36,429 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-29 09:58:36,434 - DEBUG - Requesting: http://10.0.0.22:8082/domain/e5a67299-2367-48ba-930b-25db39b38f17 2025-04-29 09:58:36,485 - DEBUG - Requesting: http://10.0.0.22:8082/project/3c0d4deb-ae9e-4f2e-8558-c11a69b79313 2025-04-29 09:58:36,555 - INFO - Validated that VN ctest-vn-68052629 is not found in API Server 2025-04-29 09:58:36,564 - DEBUG - VN ctest-vn-68052629 is not present in Agent 10.0.0.22 2025-04-29 09:58:36,564 - INFO - Validated that VN ctest-vn-68052629 is not in any agent 2025-04-29 09:58:36,577 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-68052629 info 2025-04-29 09:58:36,826 - DEBUG - No XMPP flaps were noticed during the test
2025-04-29 09:58:36,826 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:29]
2025-04-29 09:58:36,826 - INFO - -------------------------------------------------------------------------------- 2025-04-29 09:58:37,431 - INFO - Deleted project: ctest-TestBasicPolicy-66215172, ID : 3c0d4deb-ae9e-4f2e-8558-c11a69b79313