2025-09-04 20:03:08,286 - INFO - Domain Default found not creating 2025-09-04 20:03:08,545 - INFO - Project ctest-TestBasicPolicy-50342167 not found, creating it 2025-09-04 20:03:09,183 - INFO - Created Project:ctest-TestBasicPolicy-50342167, ID : 9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:11,973 - INFO - ================================================================================ 2025-09-04 20:03:11,974 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-09-04 20:03:11,974 - 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-09-04 20:03:12,301 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2025-09-04 20:03:12,301 - INFO - Initial checks done. Running the testcase now 2025-09-04 20:03:12,301 - INFO - 2025-09-04 20:03:13,026 - DEBUG - Response for create_network : {'network': {'id': 'd3e7058d-2a8b-42a3-a664-57abb3571f8e', 'name': 'ctest-vn-91350835', 'tenant_id': '9b237de40c3c4b109387f979ceed868a', 'project_id': '9b237de40c3c4b109387f979ceed868a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-vn-91350835'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 20:03:13,180 - DEBUG - Response for create_subnet : {'subnet': {'id': '286c3881-49ee-474b-ac7e-7464c5f2b0f9', 'name': '', 'tenant_id': '9b237de40c3c4b109387f979ceed868a', 'network_id': 'd3e7058d-2a8b-42a3-a664-57abb3571f8e', 'ip_version': 4, 'cidr': '184.250.165.128/26', 'allocation_pools': [{'start': '184.250.165.130', 'end': '184.250.165.190'}], 'gateway_ip': '184.250.165.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '184.250.165.130', 'tags': [], 'project_id': '9b237de40c3c4b109387f979ceed868a'}} 2025-09-04 20:03:13,199 - INFO - Created VN ctest-vn-91350835 2025-09-04 20:03:13,253 - DEBUG - VN ctest-vn-91350835 UUID is d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:13,632 - DEBUG - Response for create_network : {'network': {'id': '4cb26c48-cd06-41e7-8ba6-a494f422c92c', 'name': 'ctest-vn-46328359', 'tenant_id': '9b237de40c3c4b109387f979ceed868a', 'project_id': '9b237de40c3c4b109387f979ceed868a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-vn-46328359'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 20:03:13,872 - DEBUG - Response for create_subnet : {'subnet': {'id': '5a757c7a-a515-4dde-aba9-a76f69b27ba1', 'name': '', 'tenant_id': '9b237de40c3c4b109387f979ceed868a', 'network_id': '4cb26c48-cd06-41e7-8ba6-a494f422c92c', 'ip_version': 4, 'cidr': '146.21.46.0/26', 'allocation_pools': [{'start': '146.21.46.2', 'end': '146.21.46.62'}], 'gateway_ip': '146.21.46.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.21.46.2', 'tags': [], 'project_id': '9b237de40c3c4b109387f979ceed868a'}} 2025-09-04 20:03:13,890 - INFO - Created VN ctest-vn-46328359 2025-09-04 20:03:13,948 - DEBUG - VN ctest-vn-46328359 UUID is 4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:14,118 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835, 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-50342167:ctest-vn-46328359, 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-09-04 20:03:14,523 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'd3e7058d-2a8b-42a3-a664-57abb3571f8e', 'name': 'ctest-vn-91350835', 'tenant_id': '9b237de40c3c4b109387f979ceed868a', 'project_id': '9b237de40c3c4b109387f979ceed868a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['286c3881-49ee-474b-ac7e-7464c5f2b0f9'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-vn-91350835'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-policy-allow-all-16364417']], 'subnet_ipam': [{'subnet_cidr': '184.250.165.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-09-04 20:03:14,829 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '4cb26c48-cd06-41e7-8ba6-a494f422c92c', 'name': 'ctest-vn-46328359', 'tenant_id': '9b237de40c3c4b109387f979ceed868a', 'project_id': '9b237de40c3c4b109387f979ceed868a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['5a757c7a-a515-4dde-aba9-a76f69b27ba1'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-vn-46328359'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-policy-allow-all-16364417']], 'subnet_ipam': [{'subnet_cidr': '146.21.46.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-09-04 20:03:15,001 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:15,348 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:15,382 - DEBUG - Requesting: http://10.0.0.22:8082/domain/165a7e6f-635a-4e7c-a164-69db2e5db9be 2025-09-04 20:03:15,411 - DEBUG - Requesting: http://10.0.0.22:8082/project/9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:15,507 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:15,518 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:15,530 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/cbdc373f-4476-458b-bbbb-cfb306e0f2ac 2025-09-04 20:03:15,538 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/cbdc373f-4476-458b-bbbb-cfb306e0f2ac 2025-09-04 20:03:15,546 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f29de307-9a98-4181-b333-9f408df06279 2025-09-04 20:03:15,553 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 20:03:15,553 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:15,562 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/cbdc373f-4476-458b-bbbb-cfb306e0f2ac 2025-09-04 20:03:15,582 - INFO - Verified VN network id 11 for VN d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:15,582 - INFO - Verifications in API Server for VN ctest-vn-91350835 passed 2025-09-04 20:03:15,582 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:15,592 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/cbdc373f-4476-458b-bbbb-cfb306e0f2ac 2025-09-04 20:03:15,600 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/cbdc373f-4476-458b-bbbb-cfb306e0f2ac 2025-09-04 20:03:15,607 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f29de307-9a98-4181-b333-9f408df06279 2025-09-04 20:03:15,620 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-50342167', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15269179165485253283', 'uuid-lslong': '11989804502926958478'}, 'enable': 'true', 'created': '2025-09-04T20:03:12', 'last-modified': '2025-09-04T20:03:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.133043'} 2025-09-04 20:03:15,627 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 20:03:15,627 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-91350835 passed 2025-09-04 20:03:15,627 - DEBUG - ====Verifying policy data for ctest-vn-91350835 in API_Server ====== 2025-09-04 20:03:15,627 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:15,634 - DEBUG - Requesting: http://10.0.0.22:8082/domain/165a7e6f-635a-4e7c-a164-69db2e5db9be 2025-09-04 20:03:15,644 - DEBUG - Requesting: http://10.0.0.22:8082/project/9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:15,717 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d3e7058d-2a8b-42a3-a664-57abb3571f8e 2025-09-04 20:03:15,729 - DEBUG - ==>Verifying data for policy with id: c30becfc-9ead-45ec-b945-51934d0d0b4e, fqn: ['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-policy-allow-all-16364417'] 2025-09-04 20:03:15,729 - INFO - VN ctest-vn-91350835 Policy verification: verify_vn_policy_in_api_server, status: True 2025-09-04 20:03:15,729 - DEBUG - Verifying the vn in opserver 2025-09-04 20:03:15,729 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835 virtual network link through opserver 10.0.0.22 2025-09-04 20:03:15,729 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-09-04 20:03:16,045 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-09-04 20:03:16,140 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835?flat'} 2025-09-04 20:03:16,140 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835 is found in opserver 2025-09-04 20:03:16,162 - DEBUG - Do not have enough data to verify VN in agent 2025-09-04 20:03:16,172 - DEBUG - VRF ids for VN ctest-vn-91350835: {} 2025-09-04 20:03:16,172 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:16,181 - DEBUG - Requesting: http://10.0.0.22:8082/domain/165a7e6f-635a-4e7c-a164-69db2e5db9be 2025-09-04 20:03:16,195 - DEBUG - Requesting: http://10.0.0.22:8082/project/9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:16,287 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:16,308 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:16,325 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f54a7af-558c-48b2-b4ad-6cd65cc259e6 2025-09-04 20:03:16,339 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f54a7af-558c-48b2-b4ad-6cd65cc259e6 2025-09-04 20:03:16,348 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/dd6d70a8-c0c1-40d9-b35f-66324b9d65e0 2025-09-04 20:03:16,356 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-09-04 20:03:16,356 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:16,368 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f54a7af-558c-48b2-b4ad-6cd65cc259e6 2025-09-04 20:03:16,389 - INFO - Verified VN network id 12 for VN 4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:16,389 - INFO - Verifications in API Server for VN ctest-vn-46328359 passed 2025-09-04 20:03:16,389 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:16,401 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f54a7af-558c-48b2-b4ad-6cd65cc259e6 2025-09-04 20:03:16,411 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f54a7af-558c-48b2-b4ad-6cd65cc259e6 2025-09-04 20:03:16,420 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/dd6d70a8-c0c1-40d9-b35f-66324b9d65e0 2025-09-04 20:03:16,434 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-46328359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-50342167', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5526598752670204391', 'uuid-lslong': '10062911377063987500'}, 'enable': 'true', 'created': '2025-09-04T20:03:13', 'last-modified': '2025-09-04T20:03:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.644414'} 2025-09-04 20:03:16,440 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-09-04 20:03:16,440 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-46328359 passed 2025-09-04 20:03:16,441 - DEBUG - ====Verifying policy data for ctest-vn-46328359 in API_Server ====== 2025-09-04 20:03:16,441 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:16,448 - DEBUG - Requesting: http://10.0.0.22:8082/domain/165a7e6f-635a-4e7c-a164-69db2e5db9be 2025-09-04 20:03:16,460 - DEBUG - Requesting: http://10.0.0.22:8082/project/9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:16,555 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4cb26c48-cd06-41e7-8ba6-a494f422c92c 2025-09-04 20:03:16,572 - DEBUG - ==>Verifying data for policy with id: c30becfc-9ead-45ec-b945-51934d0d0b4e, fqn: ['default-domain', 'ctest-TestBasicPolicy-50342167', 'ctest-policy-allow-all-16364417'] 2025-09-04 20:03:16,572 - INFO - VN ctest-vn-46328359 Policy verification: verify_vn_policy_in_api_server, status: True 2025-09-04 20:03:16,573 - DEBUG - Verifying the vn in opserver 2025-09-04 20:03:16,573 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-46328359 virtual network link through opserver 10.0.0.22 2025-09-04 20:03:16,573 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-09-04 20:03:16,589 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-46328359', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-46328359?flat'} 2025-09-04 20:03:16,589 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-46328359 is found in opserver 2025-09-04 20:03:16,609 - DEBUG - Do not have enough data to verify VN in agent 2025-09-04 20:03:16,616 - DEBUG - VRF ids for VN ctest-vn-46328359: {} 2025-09-04 20:03:16,736 - DEBUG - Services list from nova: [, , ] 2025-09-04 20:03:18,093 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4019-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4019-1) 2025-09-04 20:03:19,386 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4019-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4019-1) 2025-09-04 20:03:19,386 - INFO - Waiting for VM ctest-TestBasicPolicy-50342167-07967081 to be up.. 2025-09-04 20:03:19,465 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-04 20:03:24,607 - DEBUG - VM is in ACTIVE state now 2025-09-04 20:03:24,607 - INFO - VM name : ctest-TestBasicPolicy-50342167-07967081 2025-09-04 20:03:24,723 - DEBUG - VM ctest-TestBasicPolicy-50342167-07967081 ID is 6135487c-c3f6-4f1e-9d55-0e75806b66cf 2025-09-04 20:03:24,747 - DEBUG - VM ctest-TestBasicPolicy-50342167-07967081 launched on Node cn-jenkins-deploy-platform-ansible-os-4019-1 2025-09-04 20:03:24,868 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/6135487c-c3f6-4f1e-9d55-0e75806b66cf 2025-09-04 20:03:24,880 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/d65ea75e-2923-41d7-93f1-8d78f06d3d3b 2025-09-04 20:03:28,174 - 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 1019ms') 2025-09-04 20:03:28,174 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-50342167-07967081 failed! 2025-09-04 20:03:28,250 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-50342167:ctest-vn-91350835 is 184.250.165.129 and allocation pool is NOT set 2025-09-04 20:03:30,337 - 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=8.38 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.491 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.491/4.436/8.381/3.945 ms') 2025-09-04 20:03:30,337 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-50342167-07967081 passed 2025-09-04 20:03:30,492 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 20:03:30,493 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-50342167-07967081, IP 184.250.165.131, Port 22 2025-09-04 20:03:30,658 - DEBUG - VM ctest-TestBasicPolicy-50342167-07967081 is ready for SSH connections 2025-09-04 20:03:30,658 - INFO - Waiting for VM ctest-TestBasicPolicy-50342167-93214292 to be up.. 2025-09-04 20:03:30,809 - DEBUG - VM is in ACTIVE state now 2025-09-04 20:03:30,809 - INFO - VM name : ctest-TestBasicPolicy-50342167-93214292 2025-09-04 20:03:30,921 - DEBUG - VM ctest-TestBasicPolicy-50342167-93214292 ID is 8b25e159-fac2-47cb-998c-29767082f730 2025-09-04 20:03:30,921 - DEBUG - VM ctest-TestBasicPolicy-50342167-93214292 launched on Node cn-jenkins-deploy-platform-ansible-os-4019-1 2025-09-04 20:03:31,051 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/8b25e159-fac2-47cb-998c-29767082f730 2025-09-04 20:03:31,069 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/1a85448f-8f3a-432b-9e6e-68fab3cc7c3b 2025-09-04 20:03:32,384 - 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.07 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.04 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 1.042/2.057/3.073/1.015 ms') 2025-09-04 20:03:32,384 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-50342167-93214292 passed 2025-09-04 20:03:32,553 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 20:03:32,553 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-50342167-93214292, IP 146.21.46.3, Port 22 2025-09-04 20:03:32,735 - DEBUG - VM ctest-TestBasicPolicy-50342167-93214292 is ready for SSH connections 2025-09-04 20:03:32,735 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 20:03:32,735 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.21.46.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-09-04 20:03:32,736 - DEBUG - ping -s 56 -c 3 -W 1 146.21.46.3 2025-09-04 20:03:37,236 - DEBUG - PING 146.21.46.3 (146.21.46.3): 56 data bytes 64 bytes from 146.21.46.3: seq=0 ttl=63 time=4.046 ms 64 bytes from 146.21.46.3: seq=1 ttl=63 time=1.503 ms 64 bytes from 146.21.46.3: seq=2 ttl=63 time=1.763 ms --- 146.21.46.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.503/2.437/4.046 ms 2025-09-04 20:03:37,236 - INFO - Ping to IP 146.21.46.3 from VM ctest-TestBasicPolicy-50342167-07967081 passed 2025-09-04 20:03:37,452 - DEBUG - Policy Update Response {"network-policy": {"uuid": "c30becfc-9ead-45ec-b945-51934d0d0b4e", "href": "http://10.0.0.22:8082/network-policy/c30becfc-9ead-45ec-b945-51934d0d0b4e"}} 2025-09-04 20:03:37,452 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 20:03:37,452 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.21.46.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-09-04 20:03:37,452 - DEBUG - ping -s 56 -c 3 -W 1 146.21.46.3 2025-09-04 20:03:40,691 - DEBUG - PING 146.21.46.3 (146.21.46.3): 56 data bytes --- 146.21.46.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-09-04 20:03:40,691 - WARNING - Ping to IP 146.21.46.3 from VM ctest-TestBasicPolicy-50342167-07967081 failed 2025-09-04 20:03:40,692 - INFO - Deleting VM ctest-TestBasicPolicy-50342167-93214292 2025-09-04 20:03:40,819 - INFO - Deleting VM ctest-TestBasicPolicy-50342167-07967081 2025-09-04 20:03:41,382 - INFO - Deleted policy ctest-policy-allow-all-16364417 2025-09-04 20:03:41,382 - INFO - Deleting VN ctest-vn-46328359 2025-09-04 20:03:41,418 - DEBUG - VN 4cb26c48-cd06-41e7-8ba6-a494f422c92c still in use: Unable to complete operation on network 4cb26c48-cd06-41e7-8ba6-a494f422c92c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a2e2079d-ef77-40ab-b50c-1043b655874b'] 2025-09-04 20:03:41,418 - WARNING - Deleting VN ctest-vn-46328359 failed..Will retry 2025-09-04 20:03:43,601 - DEBUG - Response for deleting network () 2025-09-04 20:03:43,601 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f54a7af-558c-48b2-b4ad-6cd65cc259e6 2025-09-04 20:03:43,608 - DEBUG - Response Code: 404 2025-09-04 20:03:43,608 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:43,622 - DEBUG - Requesting: http://10.0.0.22:8082/domain/165a7e6f-635a-4e7c-a164-69db2e5db9be 2025-09-04 20:03:43,678 - DEBUG - Requesting: http://10.0.0.22:8082/project/9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:43,763 - INFO - Validated that VN ctest-vn-46328359 is not found in API Server 2025-09-04 20:03:43,774 - DEBUG - VN ctest-vn-46328359 is not present in Agent 10.0.0.22 2025-09-04 20:03:43,774 - INFO - Validated that VN ctest-vn-46328359 is not in any agent 2025-09-04 20:03:43,798 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-46328359 info 2025-09-04 20:03:43,799 - INFO - Deleting VN ctest-vn-91350835 2025-09-04 20:03:43,952 - DEBUG - Response for deleting network () 2025-09-04 20:03:43,952 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/cbdc373f-4476-458b-bbbb-cfb306e0f2ac 2025-09-04 20:03:43,959 - DEBUG - Response Code: 404 2025-09-04 20:03:43,960 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-09-04 20:03:43,966 - DEBUG - Requesting: http://10.0.0.22:8082/domain/165a7e6f-635a-4e7c-a164-69db2e5db9be 2025-09-04 20:03:44,023 - DEBUG - Requesting: http://10.0.0.22:8082/project/9b237de4-0c3c-4b10-9387-f979ceed868a 2025-09-04 20:03:44,100 - INFO - Validated that VN ctest-vn-91350835 is not found in API Server 2025-09-04 20:03:44,118 - DEBUG - VN ctest-vn-91350835 is not present in Agent 10.0.0.22 2025-09-04 20:03:44,118 - INFO - Validated that VN ctest-vn-91350835 is not in any agent 2025-09-04 20:03:44,137 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-91350835 info 2025-09-04 20:03:44,435 - DEBUG - No XMPP flaps were noticed during the test
2025-09-04 20:03:44,435 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:33]
2025-09-04 20:03:44,435 - INFO - -------------------------------------------------------------------------------- 2025-09-04 20:03:45,457 - INFO - Deleted project: ctest-TestBasicPolicy-50342167, ID : 9b237de4-0c3c-4b10-9387-f979ceed868a