2026-02-03 12:30:23,401 - INFO - Domain Default found not creating 2026-02-03 12:30:23,561 - INFO - Project ctest-TestBasicPolicy-83264832 not found, creating it 2026-02-03 12:30:24,032 - INFO - Created Project:ctest-TestBasicPolicy-83264832, ID : 08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:25,452 - INFO - ================================================================================ 2026-02-03 12:30:25,452 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-02-03 12:30:25,453 - 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-02-03 12:30:25,705 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2026-02-03 12:30:25,705 - INFO - Initial checks done. Running the testcase now 2026-02-03 12:30:25,705 - INFO - 2026-02-03 12:30:26,238 - DEBUG - Response for create_network : {'network': {'id': 'a6319b2b-5502-456b-a636-2bb1e09185b1', 'name': 'ctest-vn-99432424', 'tenant_id': '08e02ebdd36f4827996b09caf07b016a', 'project_id': '08e02ebdd36f4827996b09caf07b016a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-vn-99432424'], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:30:26,448 - DEBUG - Response for create_subnet : {'subnet': {'id': '44eb101a-07bb-4c29-bd44-8d530df8bfb9', 'name': '', 'tenant_id': '08e02ebdd36f4827996b09caf07b016a', 'network_id': 'a6319b2b-5502-456b-a636-2bb1e09185b1', 'ip_version': 4, 'cidr': '198.146.3.192/26', 'allocation_pools': [{'start': '198.146.3.194', 'end': '198.146.3.254'}], 'gateway_ip': '198.146.3.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.146.3.194', 'tags': [], 'project_id': '08e02ebdd36f4827996b09caf07b016a'}} 2026-02-03 12:30:26,464 - INFO - Created VN ctest-vn-99432424 2026-02-03 12:30:26,516 - DEBUG - VN ctest-vn-99432424 UUID is a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:26,825 - DEBUG - Response for create_network : {'network': {'id': 'c5e42701-0aeb-4c4e-b2ae-8238fb253db1', 'name': 'ctest-vn-71152136', 'tenant_id': '08e02ebdd36f4827996b09caf07b016a', 'project_id': '08e02ebdd36f4827996b09caf07b016a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-vn-71152136'], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:30:26,976 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b29ac3a0-2413-4d3e-8bce-7feb2e7d2258', 'name': '', 'tenant_id': '08e02ebdd36f4827996b09caf07b016a', 'network_id': 'c5e42701-0aeb-4c4e-b2ae-8238fb253db1', 'ip_version': 4, 'cidr': '163.157.158.192/26', 'allocation_pools': [{'start': '163.157.158.194', 'end': '163.157.158.254'}], 'gateway_ip': '163.157.158.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '163.157.158.194', 'tags': [], 'project_id': '08e02ebdd36f4827996b09caf07b016a'}} 2026-02-03 12:30:26,993 - INFO - Created VN ctest-vn-71152136 2026-02-03 12:30:27,044 - DEBUG - VN ctest-vn-71152136 UUID is c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:27,192 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424, 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-83264832:ctest-vn-71152136, 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-02-03 12:30:27,493 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'a6319b2b-5502-456b-a636-2bb1e09185b1', 'name': 'ctest-vn-99432424', 'tenant_id': '08e02ebdd36f4827996b09caf07b016a', 'project_id': '08e02ebdd36f4827996b09caf07b016a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['44eb101a-07bb-4c29-bd44-8d530df8bfb9'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-vn-99432424'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-policy-allow-all-68015055']], 'subnet_ipam': [{'subnet_cidr': '198.146.3.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:30:27,706 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'c5e42701-0aeb-4c4e-b2ae-8238fb253db1', 'name': 'ctest-vn-71152136', 'tenant_id': '08e02ebdd36f4827996b09caf07b016a', 'project_id': '08e02ebdd36f4827996b09caf07b016a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['b29ac3a0-2413-4d3e-8bce-7feb2e7d2258'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-vn-71152136'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-policy-allow-all-68015055']], 'subnet_ipam': [{'subnet_cidr': '163.157.158.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:30:27,876 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:28,173 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:28,212 - DEBUG - Requesting: http://10.0.0.22:8082/domain/ea853b7b-7df4-40ca-9e55-bf597707668d 2026-02-03 12:30:28,226 - DEBUG - Requesting: http://10.0.0.22:8082/project/08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:28,301 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:28,313 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:28,323 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/24f477b8-92b1-4df3-8963-2e3bf4e0ec13 2026-02-03 12:30:28,330 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/24f477b8-92b1-4df3-8963-2e3bf4e0ec13 2026-02-03 12:30:28,336 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/9bb05dbf-708f-4e15-b574-47dfd9d6643e 2026-02-03 12:30:28,342 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-03 12:30:28,342 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:28,350 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/24f477b8-92b1-4df3-8963-2e3bf4e0ec13 2026-02-03 12:30:28,375 - INFO - Verified VN network id 12 for VN a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:28,375 - INFO - Verifications in API Server for VN ctest-vn-99432424 passed 2026-02-03 12:30:28,375 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:28,385 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/24f477b8-92b1-4df3-8963-2e3bf4e0ec13 2026-02-03 12:30:28,392 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/24f477b8-92b1-4df3-8963-2e3bf4e0ec13 2026-02-03 12:30:28,400 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/9bb05dbf-708f-4e15-b574-47dfd9d6643e 2026-02-03 12:30:28,410 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-83264832', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11975523494566970731', 'uuid-lslong': '11976808302015251889'}, 'enable': 'true', 'created': '2026-02-03T12:30:26', 'last-modified': '2026-02-03T12:30:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.938855'} 2026-02-03 12:30:28,416 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-03 12:30:28,416 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-99432424 passed 2026-02-03 12:30:28,416 - DEBUG - ====Verifying policy data for ctest-vn-99432424 in API_Server ====== 2026-02-03 12:30:28,416 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:28,421 - DEBUG - Requesting: http://10.0.0.22:8082/domain/ea853b7b-7df4-40ca-9e55-bf597707668d 2026-02-03 12:30:28,431 - DEBUG - Requesting: http://10.0.0.22:8082/project/08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:28,502 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a6319b2b-5502-456b-a636-2bb1e09185b1 2026-02-03 12:30:28,510 - DEBUG - ==>Verifying data for policy with id: 48ea1e51-6c78-4ade-8fc0-b400f011397a, fqn: ['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-policy-allow-all-68015055'] 2026-02-03 12:30:28,510 - INFO - VN ctest-vn-99432424 Policy verification: verify_vn_policy_in_api_server, status: True 2026-02-03 12:30:28,511 - DEBUG - Verifying the vn in opserver 2026-02-03 12:30:28,511 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424 virtual network link through opserver 10.0.0.22 2026-02-03 12:30:28,511 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-03 12:30:28,804 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-03 12:30:28,869 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424?flat'} 2026-02-03 12:30:28,870 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424 is found in opserver 2026-02-03 12:30:28,879 - DEBUG - Do not have enough data to verify VN in agent 2026-02-03 12:30:28,883 - DEBUG - VRF ids for VN ctest-vn-99432424: {} 2026-02-03 12:30:28,883 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:28,889 - DEBUG - Requesting: http://10.0.0.22:8082/domain/ea853b7b-7df4-40ca-9e55-bf597707668d 2026-02-03 12:30:28,901 - DEBUG - Requesting: http://10.0.0.22:8082/project/08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:28,970 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:28,981 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:28,991 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/02bda9e2-65b2-4069-82c5-c1d2a0945b65 2026-02-03 12:30:28,999 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/02bda9e2-65b2-4069-82c5-c1d2a0945b65 2026-02-03 12:30:29,006 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f6af48e7-1db1-4f3e-9414-e7c296592a76 2026-02-03 12:30:29,013 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-02-03 12:30:29,013 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:29,023 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/02bda9e2-65b2-4069-82c5-c1d2a0945b65 2026-02-03 12:30:29,037 - INFO - Verified VN network id 13 for VN c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:29,037 - INFO - Verifications in API Server for VN ctest-vn-71152136 passed 2026-02-03 12:30:29,037 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:29,045 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/02bda9e2-65b2-4069-82c5-c1d2a0945b65 2026-02-03 12:30:29,053 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/02bda9e2-65b2-4069-82c5-c1d2a0945b65 2026-02-03 12:30:29,059 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f6af48e7-1db1-4f3e-9414-e7c296592a76 2026-02-03 12:30:29,073 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-71152136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-83264832', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14259565205593476174', 'uuid-lslong': '12875371565942128049'}, 'enable': 'true', 'created': '2026-02-03T12:30:26', 'last-modified': '2026-02-03T12:30:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.387705'} 2026-02-03 12:30:29,078 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-02-03 12:30:29,078 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-71152136 passed 2026-02-03 12:30:29,078 - DEBUG - ====Verifying policy data for ctest-vn-71152136 in API_Server ====== 2026-02-03 12:30:29,078 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:29,083 - DEBUG - Requesting: http://10.0.0.22:8082/domain/ea853b7b-7df4-40ca-9e55-bf597707668d 2026-02-03 12:30:29,093 - DEBUG - Requesting: http://10.0.0.22:8082/project/08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:29,164 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5e42701-0aeb-4c4e-b2ae-8238fb253db1 2026-02-03 12:30:29,173 - DEBUG - ==>Verifying data for policy with id: 48ea1e51-6c78-4ade-8fc0-b400f011397a, fqn: ['default-domain', 'ctest-TestBasicPolicy-83264832', 'ctest-policy-allow-all-68015055'] 2026-02-03 12:30:29,173 - INFO - VN ctest-vn-71152136 Policy verification: verify_vn_policy_in_api_server, status: True 2026-02-03 12:30:29,173 - DEBUG - Verifying the vn in opserver 2026-02-03 12:30:29,173 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-71152136 virtual network link through opserver 10.0.0.22 2026-02-03 12:30:29,173 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-03 12:30:29,183 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-71152136', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-71152136?flat'} 2026-02-03 12:30:29,183 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-71152136 is found in opserver 2026-02-03 12:30:29,192 - DEBUG - Do not have enough data to verify VN in agent 2026-02-03 12:30:29,196 - DEBUG - VRF ids for VN ctest-vn-71152136: {} 2026-02-03 12:30:29,253 - DEBUG - Services list from nova: [, , ] 2026-02-03 12:30:30,277 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5186-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5186-1) 2026-02-03 12:30:31,436 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5186-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5186-1) 2026-02-03 12:30:31,436 - INFO - Waiting for VM ctest-TestBasicPolicy-83264832-76119094 to be up.. 2026-02-03 12:30:31,511 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-03 12:30:36,582 - DEBUG - VM is in ACTIVE state now 2026-02-03 12:30:36,582 - INFO - VM name : ctest-TestBasicPolicy-83264832-76119094 2026-02-03 12:30:36,657 - DEBUG - VM ctest-TestBasicPolicy-83264832-76119094 ID is 36189fa6-8fb4-46e0-92e8-202b5b7e348a 2026-02-03 12:30:36,674 - DEBUG - VM ctest-TestBasicPolicy-83264832-76119094 launched on Node cn-jenkins-deploy-platform-ansible-os-5186-1 2026-02-03 12:30:36,742 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/36189fa6-8fb4-46e0-92e8-202b5b7e348a 2026-02-03 12:30:36,751 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/2a09f1d3-4008-4d3c-a1bb-9bec90a151b7 2026-02-03 12:30:40,003 - 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 1023ms') 2026-02-03 12:30:40,003 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-83264832-76119094 failed! 2026-02-03 12:30:40,056 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-83264832:ctest-vn-99432424 is 198.146.3.193 and allocation pool is NOT set 2026-02-03 12:30:42,118 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.98 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.14 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 = 1.139/2.558/3.978/1.419 ms') 2026-02-03 12:30:42,118 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-83264832-76119094 passed 2026-02-03 12:30:42,264 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:30:42,264 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-83264832-76119094, IP 198.146.3.195, Port 22 2026-02-03 12:30:42,415 - DEBUG - VM ctest-TestBasicPolicy-83264832-76119094 is ready for SSH connections 2026-02-03 12:30:42,415 - INFO - Waiting for VM ctest-TestBasicPolicy-83264832-46675515 to be up.. 2026-02-03 12:30:42,492 - DEBUG - VM is in ACTIVE state now 2026-02-03 12:30:42,492 - INFO - VM name : ctest-TestBasicPolicy-83264832-46675515 2026-02-03 12:30:42,561 - DEBUG - VM ctest-TestBasicPolicy-83264832-46675515 ID is 52be0722-d659-4e0c-b1dd-f45d4aff829a 2026-02-03 12:30:42,561 - DEBUG - VM ctest-TestBasicPolicy-83264832-46675515 launched on Node cn-jenkins-deploy-platform-ansible-os-5186-1 2026-02-03 12:30:42,639 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/52be0722-d659-4e0c-b1dd-f45d4aff829a 2026-02-03 12:30:42,648 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/1f5637d1-d407-4488-9514-5e0e6f0d574b 2026-02-03 12:30:43,893 - 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=4.56 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.770 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.770/2.664/4.559/1.894 ms') 2026-02-03 12:30:43,893 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-83264832-46675515 passed 2026-02-03 12:30:44,044 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:30:44,044 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-83264832-46675515, IP 163.157.158.195, Port 22 2026-02-03 12:30:44,195 - DEBUG - VM ctest-TestBasicPolicy-83264832-46675515 is ready for SSH connections 2026-02-03 12:30:44,195 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:30:44,196 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 163.157.158.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2026-02-03 12:30:44,196 - DEBUG - ping -s 56 -c 3 -W 1 163.157.158.195 2026-02-03 12:30:48,197 - DEBUG - PING 163.157.158.195 (163.157.158.195): 56 data bytes 64 bytes from 163.157.158.195: seq=0 ttl=63 time=3.518 ms 64 bytes from 163.157.158.195: seq=1 ttl=63 time=1.473 ms 64 bytes from 163.157.158.195: seq=2 ttl=63 time=1.184 ms --- 163.157.158.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.184/2.058/3.518 ms 2026-02-03 12:30:48,198 - INFO - Ping to IP 163.157.158.195 from VM ctest-TestBasicPolicy-83264832-76119094 passed 2026-02-03 12:30:48,368 - DEBUG - Policy Update Response {"network-policy": {"uuid": "48ea1e51-6c78-4ade-8fc0-b400f011397a", "href": "http://10.0.0.22:8082/network-policy/48ea1e51-6c78-4ade-8fc0-b400f011397a"}} 2026-02-03 12:30:48,368 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:30:48,368 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 163.157.158.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2026-02-03 12:30:48,368 - DEBUG - ping -s 56 -c 3 -W 1 163.157.158.195 2026-02-03 12:30:51,602 - DEBUG - PING 163.157.158.195 (163.157.158.195): 56 data bytes --- 163.157.158.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-03 12:30:51,602 - WARNING - Ping to IP 163.157.158.195 from VM ctest-TestBasicPolicy-83264832-76119094 failed 2026-02-03 12:30:51,602 - INFO - Deleting VM ctest-TestBasicPolicy-83264832-46675515 2026-02-03 12:30:51,669 - INFO - Deleting VM ctest-TestBasicPolicy-83264832-76119094 2026-02-03 12:30:52,023 - INFO - Deleted policy ctest-policy-allow-all-68015055 2026-02-03 12:30:52,023 - INFO - Deleting VN ctest-vn-71152136 2026-02-03 12:30:52,066 - DEBUG - VN c5e42701-0aeb-4c4e-b2ae-8238fb253db1 still in use: Unable to complete operation on network c5e42701-0aeb-4c4e-b2ae-8238fb253db1. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-dbbe7948-c58a-462b-96c4-8467011891c2'] 2026-02-03 12:30:52,066 - WARNING - Deleting VN ctest-vn-71152136 failed..Will retry 2026-02-03 12:30:54,169 - DEBUG - Response for deleting network () 2026-02-03 12:30:54,169 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/02bda9e2-65b2-4069-82c5-c1d2a0945b65 2026-02-03 12:30:54,174 - DEBUG - Response Code: 404 2026-02-03 12:30:54,174 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:54,182 - DEBUG - Requesting: http://10.0.0.22:8082/domain/ea853b7b-7df4-40ca-9e55-bf597707668d 2026-02-03 12:30:54,238 - DEBUG - Requesting: http://10.0.0.22:8082/project/08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:54,306 - INFO - Validated that VN ctest-vn-71152136 is not found in API Server 2026-02-03 12:30:54,315 - DEBUG - VN ctest-vn-71152136 is not present in Agent 10.0.0.22 2026-02-03 12:30:54,315 - INFO - Validated that VN ctest-vn-71152136 is not in any agent 2026-02-03 12:30:54,327 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-71152136 info 2026-02-03 12:30:54,327 - INFO - Deleting VN ctest-vn-99432424 2026-02-03 12:30:54,426 - DEBUG - Response for deleting network () 2026-02-03 12:30:54,426 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/24f477b8-92b1-4df3-8963-2e3bf4e0ec13 2026-02-03 12:30:54,431 - DEBUG - Response Code: 404 2026-02-03 12:30:54,431 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-02-03 12:30:54,436 - DEBUG - Requesting: http://10.0.0.22:8082/domain/ea853b7b-7df4-40ca-9e55-bf597707668d 2026-02-03 12:30:54,489 - DEBUG - Requesting: http://10.0.0.22:8082/project/08e02ebd-d36f-4827-996b-09caf07b016a 2026-02-03 12:30:54,559 - INFO - Validated that VN ctest-vn-99432424 is not found in API Server 2026-02-03 12:30:54,568 - DEBUG - VN ctest-vn-99432424 is not present in Agent 10.0.0.22 2026-02-03 12:30:54,568 - INFO - Validated that VN ctest-vn-99432424 is not in any agent 2026-02-03 12:30:54,580 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-99432424 info 2026-02-03 12:30:54,834 - DEBUG - No XMPP flaps were noticed during the test
2026-02-03 12:30:54,835 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:29]
2026-02-03 12:30:54,835 - INFO - -------------------------------------------------------------------------------- 2026-02-03 12:30:55,525 - INFO - Deleted project: ctest-TestBasicPolicy-83264832, ID : 08e02ebd-d36f-4827-996b-09caf07b016a