2026-06-01 13:20:07,749 - INFO - Domain Default found not creating 2026-06-01 13:20:07,927 - INFO - Project ctest-TestBasicPolicy-41394054 not found, creating it 2026-06-01 13:20:08,402 - INFO - Created Project:ctest-TestBasicPolicy-41394054, ID : d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:09,931 - INFO - ================================================================================ 2026-06-01 13:20:09,931 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-06-01 13:20:09,931 - 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-06-01 13:20:10,184 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.17': '0'}} with 2026-06-01 13:20:10,184 - INFO - Initial checks done. Running the testcase now 2026-06-01 13:20:10,185 - INFO - 2026-06-01 13:20:10,743 - DEBUG - Response for create_network : {'network': {'id': 'c231b6e8-d816-4d78-a27e-223a18f72199', 'name': 'ctest-vn-73367124', 'tenant_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'project_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-vn-73367124'], 'port_security_enabled': True, 'description': ''}} 2026-06-01 13:20:10,978 - DEBUG - Response for create_subnet : {'subnet': {'id': '0f20a0aa-1c2a-4ff9-9577-e1090a788d07', 'name': '', 'tenant_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'network_id': 'c231b6e8-d816-4d78-a27e-223a18f72199', 'ip_version': 4, 'cidr': '179.252.246.0/26', 'allocation_pools': [{'start': '179.252.246.2', 'end': '179.252.246.62'}], 'gateway_ip': '179.252.246.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '179.252.246.2', 'tags': [], 'project_id': 'd0f8099df23643c3bb8b1f0ebaad3b56'}} 2026-06-01 13:20:10,996 - INFO - Created VN ctest-vn-73367124 2026-06-01 13:20:11,052 - DEBUG - VN ctest-vn-73367124 UUID is c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:11,389 - DEBUG - Response for create_network : {'network': {'id': '1e33a646-eea2-4d5c-a973-6261ebec918b', 'name': 'ctest-vn-09715976', 'tenant_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'project_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-vn-09715976'], 'port_security_enabled': True, 'description': ''}} 2026-06-01 13:20:11,598 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e65980d1-9466-4e18-a2dd-acfc450d09b7', 'name': '', 'tenant_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'network_id': '1e33a646-eea2-4d5c-a973-6261ebec918b', 'ip_version': 4, 'cidr': '80.215.14.64/26', 'allocation_pools': [{'start': '80.215.14.66', 'end': '80.215.14.126'}], 'gateway_ip': '80.215.14.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '80.215.14.66', 'tags': [], 'project_id': 'd0f8099df23643c3bb8b1f0ebaad3b56'}} 2026-06-01 13:20:11,617 - INFO - Created VN ctest-vn-09715976 2026-06-01 13:20:11,671 - DEBUG - VN ctest-vn-09715976 UUID is 1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:11,792 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124, 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-41394054:ctest-vn-09715976, 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-06-01 13:20:12,078 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'c231b6e8-d816-4d78-a27e-223a18f72199', 'name': 'ctest-vn-73367124', 'tenant_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'project_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['0f20a0aa-1c2a-4ff9-9577-e1090a788d07'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-vn-73367124'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-policy-allow-all-22899232']], 'subnet_ipam': [{'subnet_cidr': '179.252.246.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-06-01 13:20:12,300 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '1e33a646-eea2-4d5c-a973-6261ebec918b', 'name': 'ctest-vn-09715976', 'tenant_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'project_id': 'd0f8099df23643c3bb8b1f0ebaad3b56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['e65980d1-9466-4e18-a2dd-acfc450d09b7'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-vn-09715976'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-policy-allow-all-22899232']], 'subnet_ipam': [{'subnet_cidr': '80.215.14.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-06-01 13:20:12,467 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:12,767 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:12,801 - DEBUG - Requesting: http://10.0.0.19:8082/domain/116c3e4f-3d94-428a-8a5f-a369dfbc7bf7 2026-06-01 13:20:12,812 - DEBUG - Requesting: http://10.0.0.19:8082/project/d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:12,885 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:12,898 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:12,907 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a8bf377-038f-4c6a-836f-fe0a78a2e504 2026-06-01 13:20:12,914 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a8bf377-038f-4c6a-836f-fe0a78a2e504 2026-06-01 13:20:12,922 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/7e5569d6-adf4-4192-8e01-c872af52418b 2026-06-01 13:20:12,929 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-01 13:20:12,929 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:12,939 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a8bf377-038f-4c6a-836f-fe0a78a2e504 2026-06-01 13:20:12,955 - INFO - Verified VN network id 11 for VN c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:12,955 - INFO - Verifications in API Server for VN ctest-vn-73367124 passed 2026-06-01 13:20:12,955 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:12,964 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a8bf377-038f-4c6a-836f-fe0a78a2e504 2026-06-01 13:20:12,972 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a8bf377-038f-4c6a-836f-fe0a78a2e504 2026-06-01 13:20:12,980 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/7e5569d6-adf4-4192-8e01-c872af52418b 2026-06-01 13:20:12,994 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-41394054', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13993166628390849912', 'uuid-lslong': '11708833714132165017'}, 'enable': 'true', 'created': '2026-06-01T13:20:10', 'last-modified': '2026-06-01T13:20:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.938506'} 2026-06-01 13:20:13,001 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-01 13:20:13,001 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-73367124 passed 2026-06-01 13:20:13,001 - DEBUG - ====Verifying policy data for ctest-vn-73367124 in API_Server ====== 2026-06-01 13:20:13,001 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:13,007 - DEBUG - Requesting: http://10.0.0.19:8082/domain/116c3e4f-3d94-428a-8a5f-a369dfbc7bf7 2026-06-01 13:20:13,017 - DEBUG - Requesting: http://10.0.0.19:8082/project/d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:13,085 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/c231b6e8-d816-4d78-a27e-223a18f72199 2026-06-01 13:20:13,103 - DEBUG - ==>Verifying data for policy with id: 4ba89c94-c457-4437-8afb-5dae6ff2fba6, fqn: ['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-policy-allow-all-22899232'] 2026-06-01 13:20:13,103 - INFO - VN ctest-vn-73367124 Policy verification: verify_vn_policy_in_api_server, status: True 2026-06-01 13:20:13,103 - DEBUG - Verifying the vn in opserver 2026-06-01 13:20:13,103 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124 virtual network link through opserver 10.0.0.19 2026-06-01 13:20:13,104 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2026-06-01 13:20:13,400 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2026-06-01 13:20:13,473 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124?flat'} 2026-06-01 13:20:13,473 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124 is found in opserver 2026-06-01 13:20:13,482 - DEBUG - Do not have enough data to verify VN in agent 2026-06-01 13:20:13,487 - DEBUG - VRF ids for VN ctest-vn-73367124: {} 2026-06-01 13:20:13,487 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:13,492 - DEBUG - Requesting: http://10.0.0.19:8082/domain/116c3e4f-3d94-428a-8a5f-a369dfbc7bf7 2026-06-01 13:20:13,502 - DEBUG - Requesting: http://10.0.0.19:8082/project/d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:13,571 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:13,584 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:13,593 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3176a62d-5ed9-4c80-8f96-fe721f8ec8dd 2026-06-01 13:20:13,600 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3176a62d-5ed9-4c80-8f96-fe721f8ec8dd 2026-06-01 13:20:13,606 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/8dee0b9e-8980-4b0c-bbe9-a851419d6edc 2026-06-01 13:20:13,611 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-06-01 13:20:13,611 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:13,620 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3176a62d-5ed9-4c80-8f96-fe721f8ec8dd 2026-06-01 13:20:13,637 - INFO - Verified VN network id 12 for VN 1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:13,637 - INFO - Verifications in API Server for VN ctest-vn-09715976 passed 2026-06-01 13:20:13,637 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:13,646 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3176a62d-5ed9-4c80-8f96-fe721f8ec8dd 2026-06-01 13:20:13,653 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3176a62d-5ed9-4c80-8f96-fe721f8ec8dd 2026-06-01 13:20:13,661 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/8dee0b9e-8980-4b0c-bbe9-a851419d6edc 2026-06-01 13:20:13,675 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-09715976', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-41394054', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2176265868531617116', 'uuid-lslong': '12210211187441045899'}, 'enable': 'true', 'created': '2026-06-01T13:20:11', 'last-modified': '2026-06-01T13:20:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.396515'} 2026-06-01 13:20:13,681 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-06-01 13:20:13,681 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-09715976 passed 2026-06-01 13:20:13,681 - DEBUG - ====Verifying policy data for ctest-vn-09715976 in API_Server ====== 2026-06-01 13:20:13,681 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:13,687 - DEBUG - Requesting: http://10.0.0.19:8082/domain/116c3e4f-3d94-428a-8a5f-a369dfbc7bf7 2026-06-01 13:20:13,698 - DEBUG - Requesting: http://10.0.0.19:8082/project/d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:13,771 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/1e33a646-eea2-4d5c-a973-6261ebec918b 2026-06-01 13:20:13,780 - DEBUG - ==>Verifying data for policy with id: 4ba89c94-c457-4437-8afb-5dae6ff2fba6, fqn: ['default-domain', 'ctest-TestBasicPolicy-41394054', 'ctest-policy-allow-all-22899232'] 2026-06-01 13:20:13,780 - INFO - VN ctest-vn-09715976 Policy verification: verify_vn_policy_in_api_server, status: True 2026-06-01 13:20:13,781 - DEBUG - Verifying the vn in opserver 2026-06-01 13:20:13,781 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-09715976 virtual network link through opserver 10.0.0.19 2026-06-01 13:20:13,781 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2026-06-01 13:20:13,790 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-09715976', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-09715976?flat'} 2026-06-01 13:20:13,790 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-09715976 is found in opserver 2026-06-01 13:20:13,802 - DEBUG - Do not have enough data to verify VN in agent 2026-06-01 13:20:13,807 - DEBUG - VRF ids for VN ctest-vn-09715976: {} 2026-06-01 13:20:13,894 - DEBUG - Services list from nova: [, , ] 2026-06-01 13:20:14,936 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5867-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5867-1) 2026-06-01 13:20:16,080 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5867-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5867-1) 2026-06-01 13:20:16,081 - INFO - Waiting for VM ctest-TestBasicPolicy-41394054-88109001 to be up.. 2026-06-01 13:20:16,170 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-01 13:20:21,245 - DEBUG - VM is in ACTIVE state now 2026-06-01 13:20:21,245 - INFO - VM name : ctest-TestBasicPolicy-41394054-88109001 2026-06-01 13:20:21,320 - DEBUG - VM ctest-TestBasicPolicy-41394054-88109001 ID is b64d70b4-2ad7-41a4-84b5-bd236bb62133 2026-06-01 13:20:21,341 - DEBUG - VM ctest-TestBasicPolicy-41394054-88109001 launched on Node cn-jenkins-deploy-platform-ansible-os-5867-1 2026-06-01 13:20:21,418 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/b64d70b4-2ad7-41a4-84b5-bd236bb62133 2026-06-01 13:20:21,426 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/786b14bd-9868-474b-94fd-9c2de891eb30 2026-06-01 13:20:24,678 - 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 1014ms') 2026-06-01 13:20:24,678 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-41394054-88109001 failed! 2026-06-01 13:20:24,735 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-41394054:ctest-vn-73367124 is 179.252.246.1 and allocation pool is NOT set 2026-06-01 13:20:26,799 - 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=9.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.881 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.881/4.999/9.117/4.118 ms') 2026-06-01 13:20:26,799 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-41394054-88109001 passed 2026-06-01 13:20:26,947 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-01 13:20:26,947 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-41394054-88109001, IP 179.252.246.3, Port 22 2026-06-01 13:20:27,111 - DEBUG - VM ctest-TestBasicPolicy-41394054-88109001 is ready for SSH connections 2026-06-01 13:20:27,111 - INFO - Waiting for VM ctest-TestBasicPolicy-41394054-05085217 to be up.. 2026-06-01 13:20:27,184 - DEBUG - VM is in ACTIVE state now 2026-06-01 13:20:27,184 - INFO - VM name : ctest-TestBasicPolicy-41394054-05085217 2026-06-01 13:20:27,256 - DEBUG - VM ctest-TestBasicPolicy-41394054-05085217 ID is 22ee1969-3930-40c0-b7bd-540cfcd086b0 2026-06-01 13:20:27,256 - DEBUG - VM ctest-TestBasicPolicy-41394054-05085217 launched on Node cn-jenkins-deploy-platform-ansible-os-5867-1 2026-06-01 13:20:27,327 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/22ee1969-3930-40c0-b7bd-540cfcd086b0 2026-06-01 13:20:27,335 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/d567eab1-486f-44f2-b908-d2d1eb804a2e 2026-06-01 13:20:28,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.97 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.862 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.862/2.417/3.972/1.555 ms') 2026-06-01 13:20:28,571 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-41394054-05085217 passed 2026-06-01 13:20:28,727 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-01 13:20:28,727 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-41394054-05085217, IP 80.215.14.67, Port 22 2026-06-01 13:20:28,887 - DEBUG - VM ctest-TestBasicPolicy-41394054-05085217 is ready for SSH connections 2026-06-01 13:20:28,887 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-01 13:20:28,887 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 80.215.14.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-06-01 13:20:28,887 - DEBUG - ping -s 56 -c 3 -W 1 80.215.14.67 2026-06-01 13:20:32,916 - DEBUG - PING 80.215.14.67 (80.215.14.67): 56 data bytes 64 bytes from 80.215.14.67: seq=0 ttl=63 time=4.046 ms 64 bytes from 80.215.14.67: seq=1 ttl=63 time=1.109 ms 64 bytes from 80.215.14.67: seq=2 ttl=63 time=1.353 ms --- 80.215.14.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.109/2.169/4.046 ms 2026-06-01 13:20:32,917 - INFO - Ping to IP 80.215.14.67 from VM ctest-TestBasicPolicy-41394054-88109001 passed 2026-06-01 13:20:33,095 - DEBUG - Policy Update Response {"network-policy": {"uuid": "4ba89c94-c457-4437-8afb-5dae6ff2fba6", "href": "http://10.0.0.19:8082/network-policy/4ba89c94-c457-4437-8afb-5dae6ff2fba6"}} 2026-06-01 13:20:33,095 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-01 13:20:33,095 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 80.215.14.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-06-01 13:20:33,095 - DEBUG - ping -s 56 -c 3 -W 1 80.215.14.67 2026-06-01 13:20:36,302 - DEBUG - PING 80.215.14.67 (80.215.14.67): 56 data bytes --- 80.215.14.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-06-01 13:20:36,302 - WARNING - Ping to IP 80.215.14.67 from VM ctest-TestBasicPolicy-41394054-88109001 failed 2026-06-01 13:20:36,302 - INFO - Deleting VM ctest-TestBasicPolicy-41394054-05085217 2026-06-01 13:20:36,370 - INFO - Deleting VM ctest-TestBasicPolicy-41394054-88109001 2026-06-01 13:20:36,744 - INFO - Deleted policy ctest-policy-allow-all-22899232 2026-06-01 13:20:36,744 - INFO - Deleting VN ctest-vn-09715976 2026-06-01 13:20:36,781 - DEBUG - VN 1e33a646-eea2-4d5c-a973-6261ebec918b still in use: Unable to complete operation on network 1e33a646-eea2-4d5c-a973-6261ebec918b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2ec2ab2f-6830-48dd-96af-83c975247e9f'] 2026-06-01 13:20:36,782 - WARNING - Deleting VN ctest-vn-09715976 failed..Will retry 2026-06-01 13:20:38,872 - DEBUG - Response for deleting network () 2026-06-01 13:20:38,873 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3176a62d-5ed9-4c80-8f96-fe721f8ec8dd 2026-06-01 13:20:38,879 - DEBUG - Response Code: 404 2026-06-01 13:20:38,879 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:38,887 - DEBUG - Requesting: http://10.0.0.19:8082/domain/116c3e4f-3d94-428a-8a5f-a369dfbc7bf7 2026-06-01 13:20:38,941 - DEBUG - Requesting: http://10.0.0.19:8082/project/d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:39,010 - INFO - Validated that VN ctest-vn-09715976 is not found in API Server 2026-06-01 13:20:39,019 - DEBUG - VN ctest-vn-09715976 is not present in Agent 10.0.0.19 2026-06-01 13:20:39,019 - INFO - Validated that VN ctest-vn-09715976 is not in any agent 2026-06-01 13:20:39,035 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-09715976 info 2026-06-01 13:20:39,035 - INFO - Deleting VN ctest-vn-73367124 2026-06-01 13:20:39,132 - DEBUG - Response for deleting network () 2026-06-01 13:20:39,133 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a8bf377-038f-4c6a-836f-fe0a78a2e504 2026-06-01 13:20:39,139 - DEBUG - Response Code: 404 2026-06-01 13:20:39,139 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2026-06-01 13:20:39,144 - DEBUG - Requesting: http://10.0.0.19:8082/domain/116c3e4f-3d94-428a-8a5f-a369dfbc7bf7 2026-06-01 13:20:39,196 - DEBUG - Requesting: http://10.0.0.19:8082/project/d0f8099d-f236-43c3-bb8b-1f0ebaad3b56 2026-06-01 13:20:39,264 - INFO - Validated that VN ctest-vn-73367124 is not found in API Server 2026-06-01 13:20:39,273 - DEBUG - VN ctest-vn-73367124 is not present in Agent 10.0.0.19 2026-06-01 13:20:39,273 - INFO - Validated that VN ctest-vn-73367124 is not in any agent 2026-06-01 13:20:39,286 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-73367124 info 2026-06-01 13:20:39,560 - DEBUG - No XMPP flaps were noticed during the test
2026-06-01 13:20:39,560 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:30]
2026-06-01 13:20:39,560 - INFO - -------------------------------------------------------------------------------- 2026-06-01 13:20:40,301 - INFO - Deleted project: ctest-TestBasicPolicy-41394054, ID : d0f8099d-f236-43c3-bb8b-1f0ebaad3b56