2025-10-22 20:26:12,269 - INFO - Domain Default found not creating 2025-10-22 20:26:12,401 - INFO - Project ctest-TestBasicPolicy-87620059 not found, creating it 2025-10-22 20:26:12,835 - INFO - Created Project:ctest-TestBasicPolicy-87620059, ID : cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:14,200 - INFO - ================================================================================ 2025-10-22 20:26:14,200 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-10-22 20:26:14,200 - 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-10-22 20:26:14,454 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with 2025-10-22 20:26:14,454 - INFO - Initial checks done. Running the testcase now 2025-10-22 20:26:14,454 - INFO - 2025-10-22 20:26:14,995 - DEBUG - Response for create_network : {'network': {'id': '809f2ec4-b55c-4916-87d6-bec0847dd4b6', 'name': 'ctest-vn-04156465', 'tenant_id': 'cabff358012742d3a5eaefbf0012902f', 'project_id': 'cabff358012742d3a5eaefbf0012902f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-vn-04156465'], 'port_security_enabled': True, 'description': ''}} 2025-10-22 20:26:15,176 - DEBUG - Response for create_subnet : {'subnet': {'id': 'efd3ab32-b580-4fdf-8710-4a78b882763c', 'name': '', 'tenant_id': 'cabff358012742d3a5eaefbf0012902f', 'network_id': '809f2ec4-b55c-4916-87d6-bec0847dd4b6', 'ip_version': 4, 'cidr': '70.133.74.192/26', 'allocation_pools': [{'start': '70.133.74.194', 'end': '70.133.74.254'}], 'gateway_ip': '70.133.74.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.133.74.194', 'tags': [], 'project_id': 'cabff358012742d3a5eaefbf0012902f'}} 2025-10-22 20:26:15,195 - INFO - Created VN ctest-vn-04156465 2025-10-22 20:26:15,244 - DEBUG - VN ctest-vn-04156465 UUID is 809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:15,551 - DEBUG - Response for create_network : {'network': {'id': 'fb2b6c83-31e5-4c8d-b204-856d4e7099a8', 'name': 'ctest-vn-87370389', 'tenant_id': 'cabff358012742d3a5eaefbf0012902f', 'project_id': 'cabff358012742d3a5eaefbf0012902f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-vn-87370389'], 'port_security_enabled': True, 'description': ''}} 2025-10-22 20:26:15,742 - DEBUG - Response for create_subnet : {'subnet': {'id': '0a13d14f-cd44-47ab-a6dd-0b1dc6af7872', 'name': '', 'tenant_id': 'cabff358012742d3a5eaefbf0012902f', 'network_id': 'fb2b6c83-31e5-4c8d-b204-856d4e7099a8', 'ip_version': 4, 'cidr': '161.165.94.64/26', 'allocation_pools': [{'start': '161.165.94.66', 'end': '161.165.94.126'}], 'gateway_ip': '161.165.94.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '161.165.94.66', 'tags': [], 'project_id': 'cabff358012742d3a5eaefbf0012902f'}} 2025-10-22 20:26:15,769 - INFO - Created VN ctest-vn-87370389 2025-10-22 20:26:15,820 - DEBUG - VN ctest-vn-87370389 UUID is fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:15,972 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465, 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-87620059:ctest-vn-87370389, 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-10-22 20:26:16,255 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '809f2ec4-b55c-4916-87d6-bec0847dd4b6', 'name': 'ctest-vn-04156465', 'tenant_id': 'cabff358012742d3a5eaefbf0012902f', 'project_id': 'cabff358012742d3a5eaefbf0012902f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['efd3ab32-b580-4fdf-8710-4a78b882763c'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-vn-04156465'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-policy-allow-all-30122533']], 'subnet_ipam': [{'subnet_cidr': '70.133.74.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-10-22 20:26:16,517 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'fb2b6c83-31e5-4c8d-b204-856d4e7099a8', 'name': 'ctest-vn-87370389', 'tenant_id': 'cabff358012742d3a5eaefbf0012902f', 'project_id': 'cabff358012742d3a5eaefbf0012902f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['0a13d14f-cd44-47ab-a6dd-0b1dc6af7872'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-vn-87370389'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-policy-allow-all-30122533']], 'subnet_ipam': [{'subnet_cidr': '161.165.94.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-10-22 20:26:16,660 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:16,953 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:16,977 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d5657770-164f-4b23-b68a-38e287d8fbfe 2025-10-22 20:26:16,988 - DEBUG - Requesting: http://10.0.0.38:8082/project/cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:17,063 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:17,072 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:17,080 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2332a732-3a70-4763-8439-9b32c122d69a 2025-10-22 20:26:17,086 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2332a732-3a70-4763-8439-9b32c122d69a 2025-10-22 20:26:17,092 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/a92b43c1-7136-4fe1-97f3-80b7a03f8d2d 2025-10-22 20:26:17,098 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-22 20:26:17,098 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:17,105 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2332a732-3a70-4763-8439-9b32c122d69a 2025-10-22 20:26:17,118 - INFO - Verified VN network id 12 for VN 809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:17,119 - INFO - Verifications in API Server for VN ctest-vn-04156465 passed 2025-10-22 20:26:17,119 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:17,127 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2332a732-3a70-4763-8439-9b32c122d69a 2025-10-22 20:26:17,133 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2332a732-3a70-4763-8439-9b32c122d69a 2025-10-22 20:26:17,139 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/a92b43c1-7136-4fe1-97f3-80b7a03f8d2d 2025-10-22 20:26:17,149 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-87620059', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9268177980542961942', 'uuid-lslong': '9788220574202188982'}, 'enable': 'true', 'created': '2025-10-22T20:26:14', 'last-modified': '2025-10-22T20:26:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.913655'} 2025-10-22 20:26:17,154 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-22 20:26:17,154 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-04156465 passed 2025-10-22 20:26:17,154 - DEBUG - ====Verifying policy data for ctest-vn-04156465 in API_Server ====== 2025-10-22 20:26:17,154 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:17,162 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d5657770-164f-4b23-b68a-38e287d8fbfe 2025-10-22 20:26:17,172 - DEBUG - Requesting: http://10.0.0.38:8082/project/cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:17,240 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/809f2ec4-b55c-4916-87d6-bec0847dd4b6 2025-10-22 20:26:17,250 - DEBUG - ==>Verifying data for policy with id: b2d5982d-fc5b-4e2e-bfda-7ecfa3b31b70, fqn: ['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-policy-allow-all-30122533'] 2025-10-22 20:26:17,250 - INFO - VN ctest-vn-04156465 Policy verification: verify_vn_policy_in_api_server, status: True 2025-10-22 20:26:17,250 - DEBUG - Verifying the vn in opserver 2025-10-22 20:26:17,250 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465 virtual network link through opserver 10.0.0.38 2025-10-22 20:26:17,250 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-22 20:26:17,551 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-22 20:26:17,598 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465?flat'} 2025-10-22 20:26:17,598 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465 is found in opserver 2025-10-22 20:26:17,607 - DEBUG - Do not have enough data to verify VN in agent 2025-10-22 20:26:17,611 - DEBUG - VRF ids for VN ctest-vn-04156465: {} 2025-10-22 20:26:17,612 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:17,617 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d5657770-164f-4b23-b68a-38e287d8fbfe 2025-10-22 20:26:17,627 - DEBUG - Requesting: http://10.0.0.38:8082/project/cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:17,696 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:17,706 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:17,714 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d0057274-e521-49b0-bebe-9bfc06fb4c5d 2025-10-22 20:26:17,720 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d0057274-e521-49b0-bebe-9bfc06fb4c5d 2025-10-22 20:26:17,725 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/0e183afd-84d3-4d9e-850d-f1a83ef06759 2025-10-22 20:26:17,731 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-22 20:26:17,731 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:17,738 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d0057274-e521-49b0-bebe-9bfc06fb4c5d 2025-10-22 20:26:17,750 - INFO - Verified VN network id 13 for VN fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:17,750 - INFO - Verifications in API Server for VN ctest-vn-87370389 passed 2025-10-22 20:26:17,750 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:17,757 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d0057274-e521-49b0-bebe-9bfc06fb4c5d 2025-10-22 20:26:17,762 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d0057274-e521-49b0-bebe-9bfc06fb4c5d 2025-10-22 20:26:17,767 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/0e183afd-84d3-4d9e-850d-f1a83ef06759 2025-10-22 20:26:17,777 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-87370389', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-87620059', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18098678838252096653', 'uuid-lslong': '12827524343171946920'}, 'enable': 'true', 'created': '2025-10-22T20:26:15', 'last-modified': '2025-10-22T20:26:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.322855'} 2025-10-22 20:26:17,782 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-22 20:26:17,782 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-87370389 passed 2025-10-22 20:26:17,782 - DEBUG - ====Verifying policy data for ctest-vn-87370389 in API_Server ====== 2025-10-22 20:26:17,782 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:17,787 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d5657770-164f-4b23-b68a-38e287d8fbfe 2025-10-22 20:26:17,797 - DEBUG - Requesting: http://10.0.0.38:8082/project/cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:17,867 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/fb2b6c83-31e5-4c8d-b204-856d4e7099a8 2025-10-22 20:26:17,876 - DEBUG - ==>Verifying data for policy with id: b2d5982d-fc5b-4e2e-bfda-7ecfa3b31b70, fqn: ['default-domain', 'ctest-TestBasicPolicy-87620059', 'ctest-policy-allow-all-30122533'] 2025-10-22 20:26:17,877 - INFO - VN ctest-vn-87370389 Policy verification: verify_vn_policy_in_api_server, status: True 2025-10-22 20:26:17,877 - DEBUG - Verifying the vn in opserver 2025-10-22 20:26:17,877 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-87370389 virtual network link through opserver 10.0.0.38 2025-10-22 20:26:17,877 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-22 20:26:17,885 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-87370389', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-87370389?flat'} 2025-10-22 20:26:17,885 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-87370389 is found in opserver 2025-10-22 20:26:17,894 - DEBUG - Do not have enough data to verify VN in agent 2025-10-22 20:26:17,899 - DEBUG - VRF ids for VN ctest-vn-87370389: {} 2025-10-22 20:26:17,946 - DEBUG - Services list from nova: [, , ] 2025-10-22 20:26:18,940 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4440-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4440-1) 2025-10-22 20:26:20,038 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4440-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4440-1) 2025-10-22 20:26:20,038 - INFO - Waiting for VM ctest-TestBasicPolicy-87620059-65856015 to be up.. 2025-10-22 20:26:20,109 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-22 20:26:25,196 - DEBUG - VM is in ACTIVE state now 2025-10-22 20:26:25,196 - INFO - VM name : ctest-TestBasicPolicy-87620059-65856015 2025-10-22 20:26:25,267 - DEBUG - VM ctest-TestBasicPolicy-87620059-65856015 ID is 1eaba93b-8548-4958-8bff-44ec636dba67 2025-10-22 20:26:25,287 - DEBUG - VM ctest-TestBasicPolicy-87620059-65856015 launched on Node cn-jenkins-deploy-platform-ansible-os-4440-1 2025-10-22 20:26:25,360 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/1eaba93b-8548-4958-8bff-44ec636dba67 2025-10-22 20:26:25,370 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/ebec6ec6-7470-4597-ba52-3f10b0c4393c 2025-10-22 20:26:28,606 - 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 1003ms') 2025-10-22 20:26:28,606 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-87620059-65856015 failed! 2025-10-22 20:26:28,660 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-87620059:ctest-vn-04156465 is 70.133.74.193 and allocation pool is NOT set 2025-10-22 20:26:30,726 - 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.80 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.454 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.454/1.629/2.804/1.175 ms') 2025-10-22 20:26:30,726 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-87620059-65856015 passed 2025-10-22 20:26:30,876 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-22 20:26:30,876 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-87620059-65856015, IP 70.133.74.195, Port 22 2025-10-22 20:26:31,032 - DEBUG - VM ctest-TestBasicPolicy-87620059-65856015 is ready for SSH connections 2025-10-22 20:26:31,032 - INFO - Waiting for VM ctest-TestBasicPolicy-87620059-69833503 to be up.. 2025-10-22 20:26:31,101 - DEBUG - VM is in ACTIVE state now 2025-10-22 20:26:31,102 - INFO - VM name : ctest-TestBasicPolicy-87620059-69833503 2025-10-22 20:26:31,167 - DEBUG - VM ctest-TestBasicPolicy-87620059-69833503 ID is 1d749372-9858-4c3f-a589-255d9117844a 2025-10-22 20:26:31,168 - DEBUG - VM ctest-TestBasicPolicy-87620059-69833503 launched on Node cn-jenkins-deploy-platform-ansible-os-4440-1 2025-10-22 20:26:31,242 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/1d749372-9858-4c3f-a589-255d9117844a 2025-10-22 20:26:31,250 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/3164fce8-ef1d-4f12-9643-983ad56a3cbc 2025-10-22 20:26:32,490 - 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.83 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.407 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.407/1.620/2.834/1.213 ms') 2025-10-22 20:26:32,490 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-87620059-69833503 passed 2025-10-22 20:26:32,640 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-22 20:26:32,640 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-87620059-69833503, IP 161.165.94.67, Port 22 2025-10-22 20:26:32,810 - DEBUG - VM ctest-TestBasicPolicy-87620059-69833503 is ready for SSH connections 2025-10-22 20:26:32,810 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-22 20:26:32,810 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 161.165.94.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-22 20:26:32,810 - DEBUG - ping -s 56 -c 3 -W 1 161.165.94.67 2025-10-22 20:26:36,828 - DEBUG - PING 161.165.94.67 (161.165.94.67): 56 data bytes 64 bytes from 161.165.94.67: seq=0 ttl=63 time=3.211 ms 64 bytes from 161.165.94.67: seq=1 ttl=63 time=0.787 ms 64 bytes from 161.165.94.67: seq=2 ttl=63 time=0.754 ms --- 161.165.94.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.754/1.584/3.211 ms 2025-10-22 20:26:36,828 - INFO - Ping to IP 161.165.94.67 from VM ctest-TestBasicPolicy-87620059-65856015 passed 2025-10-22 20:26:37,004 - DEBUG - Policy Update Response {"network-policy": {"uuid": "b2d5982d-fc5b-4e2e-bfda-7ecfa3b31b70", "href": "http://10.0.0.38:8082/network-policy/b2d5982d-fc5b-4e2e-bfda-7ecfa3b31b70"}} 2025-10-22 20:26:37,004 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-22 20:26:37,004 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 161.165.94.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-22 20:26:37,004 - DEBUG - ping -s 56 -c 3 -W 1 161.165.94.67 2025-10-22 20:26:40,206 - DEBUG - PING 161.165.94.67 (161.165.94.67): 56 data bytes 64 bytes from 161.165.94.67: seq=0 ttl=63 time=1.323 ms --- 161.165.94.67 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.323/1.323/1.323 ms 2025-10-22 20:26:40,207 - WARNING - Ping to IP 161.165.94.67 from VM ctest-TestBasicPolicy-87620059-65856015 failed 2025-10-22 20:26:40,207 - INFO - Deleting VM ctest-TestBasicPolicy-87620059-69833503 2025-10-22 20:26:40,270 - INFO - Deleting VM ctest-TestBasicPolicy-87620059-65856015 2025-10-22 20:26:40,622 - INFO - Deleted policy ctest-policy-allow-all-30122533 2025-10-22 20:26:40,622 - INFO - Deleting VN ctest-vn-87370389 2025-10-22 20:26:40,652 - DEBUG - VN fb2b6c83-31e5-4c8d-b204-856d4e7099a8 still in use: Unable to complete operation on network fb2b6c83-31e5-4c8d-b204-856d4e7099a8. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f72c55ab-a04b-4f3b-89c5-abfd157bd5a3'] 2025-10-22 20:26:40,653 - WARNING - Deleting VN ctest-vn-87370389 failed..Will retry 2025-10-22 20:26:42,755 - DEBUG - Response for deleting network () 2025-10-22 20:26:42,755 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d0057274-e521-49b0-bebe-9bfc06fb4c5d 2025-10-22 20:26:42,761 - DEBUG - Response Code: 404 2025-10-22 20:26:42,761 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:42,768 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d5657770-164f-4b23-b68a-38e287d8fbfe 2025-10-22 20:26:42,822 - DEBUG - Requesting: http://10.0.0.38:8082/project/cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:42,889 - INFO - Validated that VN ctest-vn-87370389 is not found in API Server 2025-10-22 20:26:42,898 - DEBUG - VN ctest-vn-87370389 is not present in Agent 10.0.0.38 2025-10-22 20:26:42,898 - INFO - Validated that VN ctest-vn-87370389 is not in any agent 2025-10-22 20:26:42,910 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-87370389 info 2025-10-22 20:26:42,910 - INFO - Deleting VN ctest-vn-04156465 2025-10-22 20:26:43,009 - DEBUG - Response for deleting network () 2025-10-22 20:26:43,009 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2332a732-3a70-4763-8439-9b32c122d69a 2025-10-22 20:26:43,013 - DEBUG - Response Code: 404 2025-10-22 20:26:43,013 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-22 20:26:43,019 - DEBUG - Requesting: http://10.0.0.38:8082/domain/d5657770-164f-4b23-b68a-38e287d8fbfe 2025-10-22 20:26:43,072 - DEBUG - Requesting: http://10.0.0.38:8082/project/cabff358-0127-42d3-a5ea-efbf0012902f 2025-10-22 20:26:43,142 - INFO - Validated that VN ctest-vn-04156465 is not found in API Server 2025-10-22 20:26:43,151 - DEBUG - VN ctest-vn-04156465 is not present in Agent 10.0.0.38 2025-10-22 20:26:43,151 - INFO - Validated that VN ctest-vn-04156465 is not in any agent 2025-10-22 20:26:43,162 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-04156465 info 2025-10-22 20:26:43,413 - DEBUG - No XMPP flaps were noticed during the test
2025-10-22 20:26:43,413 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:29]
2025-10-22 20:26:43,413 - INFO - -------------------------------------------------------------------------------- 2025-10-22 20:26:44,056 - INFO - Deleted project: ctest-TestBasicPolicy-87620059, ID : cabff358-0127-42d3-a5ea-efbf0012902f