2026-02-03 12:58:42,388 - INFO - Domain Default found not creating 2026-02-03 12:58:42,568 - INFO - Project ctest-TestBasicPolicy-77139567 not found, creating it 2026-02-03 12:58:43,026 - INFO - Created Project:ctest-TestBasicPolicy-77139567, ID : d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:58:44,737 - INFO - ================================================================================ 2026-02-03 12:58:44,737 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-02-03 12:58:44,737 - 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:58:45,003 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.20.0.254': '0'}} with 2026-02-03 12:58:45,003 - INFO - Initial checks done. Running the testcase now 2026-02-03 12:58:45,003 - INFO - 2026-02-03 12:58:45,650 - DEBUG - Response for create_network : {'network': {'id': '4ecb273b-542f-4e62-9da9-86a7c4698f8f', 'name': 'ctest-vn-70513419', 'tenant_id': 'd364e2a08b064e769ffc39c2b565472c', 'project_id': 'd364e2a08b064e769ffc39c2b565472c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-vn-70513419'], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:58:45,889 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c0d53cbd-f95b-4df0-b275-d00500d9c248', 'name': '', 'tenant_id': 'd364e2a08b064e769ffc39c2b565472c', 'network_id': '4ecb273b-542f-4e62-9da9-86a7c4698f8f', 'ip_version': 4, 'cidr': '164.216.144.64/26', 'allocation_pools': [{'start': '164.216.144.66', 'end': '164.216.144.126'}], 'gateway_ip': '164.216.144.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.216.144.66', 'tags': [], 'project_id': 'd364e2a08b064e769ffc39c2b565472c'}} 2026-02-03 12:58:45,911 - INFO - Created VN ctest-vn-70513419 2026-02-03 12:58:45,964 - DEBUG - VN ctest-vn-70513419 UUID is 4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:46,285 - DEBUG - Response for create_network : {'network': {'id': '4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061', 'name': 'ctest-vn-54653923', 'tenant_id': 'd364e2a08b064e769ffc39c2b565472c', 'project_id': 'd364e2a08b064e769ffc39c2b565472c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-vn-54653923'], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:58:46,755 - DEBUG - Response for create_subnet : {'subnet': {'id': '7027f492-8815-4913-9f35-1389b03a8b38', 'name': '', 'tenant_id': 'd364e2a08b064e769ffc39c2b565472c', 'network_id': '4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061', 'ip_version': 4, 'cidr': '155.133.198.192/26', 'allocation_pools': [{'start': '155.133.198.194', 'end': '155.133.198.254'}], 'gateway_ip': '155.133.198.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '155.133.198.194', 'tags': [], 'project_id': 'd364e2a08b064e769ffc39c2b565472c'}} 2026-02-03 12:58:46,793 - INFO - Created VN ctest-vn-54653923 2026-02-03 12:58:46,851 - DEBUG - VN ctest-vn-54653923 UUID is 4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:46,992 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419, 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-77139567:ctest-vn-54653923, 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:58:47,379 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '4ecb273b-542f-4e62-9da9-86a7c4698f8f', 'name': 'ctest-vn-70513419', 'tenant_id': 'd364e2a08b064e769ffc39c2b565472c', 'project_id': 'd364e2a08b064e769ffc39c2b565472c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['c0d53cbd-f95b-4df0-b275-d00500d9c248'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-vn-70513419'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-policy-allow-all-93888878']], 'subnet_ipam': [{'subnet_cidr': '164.216.144.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:58:47,623 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061', 'name': 'ctest-vn-54653923', 'tenant_id': 'd364e2a08b064e769ffc39c2b565472c', 'project_id': 'd364e2a08b064e769ffc39c2b565472c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['7027f492-8815-4913-9f35-1389b03a8b38'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-vn-54653923'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-policy-allow-all-93888878']], 'subnet_ipam': [{'subnet_cidr': '155.133.198.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-02-03 12:58:47,799 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:58:48,108 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:58:48,150 - DEBUG - Requesting: http://10.0.0.129:8082/domain/33182b97-85b8-4cd8-a9b5-fe71359837a2 2026-02-03 12:58:48,163 - DEBUG - Requesting: http://10.0.0.129:8082/project/d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:58:48,234 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:48,245 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:48,255 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/174dc953-56fe-42b6-b846-c1394d216d9f 2026-02-03 12:58:48,265 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/174dc953-56fe-42b6-b846-c1394d216d9f 2026-02-03 12:58:48,272 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/747e6d67-ede7-4297-a906-12a5c1decb38 2026-02-03 12:58:48,279 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-03 12:58:48,279 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:48,289 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/174dc953-56fe-42b6-b846-c1394d216d9f 2026-02-03 12:58:48,303 - INFO - Verified VN network id 11 for VN 4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:48,303 - INFO - Verifications in API Server for VN ctest-vn-70513419 passed 2026-02-03 12:58:48,303 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:48,313 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/174dc953-56fe-42b6-b846-c1394d216d9f 2026-02-03 12:58:48,321 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/174dc953-56fe-42b6-b846-c1394d216d9f 2026-02-03 12:58:48,328 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/747e6d67-ede7-4297-a906-12a5c1decb38 2026-02-03 12:58:48,341 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-77139567', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5677674890999582306', 'uuid-lslong': '11360759590131699599'}, 'enable': 'true', 'created': '2026-02-03T12:58:45', 'last-modified': '2026-02-03T12:58:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.994635'} 2026-02-03 12:58:48,348 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-03 12:58:48,348 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-70513419 passed 2026-02-03 12:58:48,349 - DEBUG - ====Verifying policy data for ctest-vn-70513419 in API_Server ====== 2026-02-03 12:58:48,349 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:58:48,356 - DEBUG - Requesting: http://10.0.0.129:8082/domain/33182b97-85b8-4cd8-a9b5-fe71359837a2 2026-02-03 12:58:48,367 - DEBUG - Requesting: http://10.0.0.129:8082/project/d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:58:48,442 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4ecb273b-542f-4e62-9da9-86a7c4698f8f 2026-02-03 12:58:48,451 - DEBUG - ==>Verifying data for policy with id: f00642a8-b0e3-4eee-a95e-4c7088731c85, fqn: ['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-policy-allow-all-93888878'] 2026-02-03 12:58:48,451 - INFO - VN ctest-vn-70513419 Policy verification: verify_vn_policy_in_api_server, status: True 2026-02-03 12:58:48,451 - DEBUG - Verifying the vn in opserver 2026-02-03 12:58:48,451 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419 virtual network link through opserver 10.0.0.129 2026-02-03 12:58:48,452 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2026-02-03 12:58:48,749 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2026-02-03 12:58:48,836 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419?flat'} 2026-02-03 12:58:48,836 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419 is found in opserver 2026-02-03 12:58:48,847 - DEBUG - Do not have enough data to verify VN in agent 2026-02-03 12:58:48,852 - DEBUG - VRF ids for VN ctest-vn-70513419: {} 2026-02-03 12:58:48,852 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:58:48,857 - DEBUG - Requesting: http://10.0.0.129:8082/domain/33182b97-85b8-4cd8-a9b5-fe71359837a2 2026-02-03 12:58:48,869 - DEBUG - Requesting: http://10.0.0.129:8082/project/d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:58:48,943 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:48,955 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:48,964 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/a5ddfdfb-067c-458f-ac2e-fd957ed0ca2e 2026-02-03 12:58:48,972 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/a5ddfdfb-067c-458f-ac2e-fd957ed0ca2e 2026-02-03 12:58:48,980 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/8230606c-1a9b-4c48-b1be-d8c3ddc90e84 2026-02-03 12:58:48,988 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-02-03 12:58:48,988 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:48,998 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/a5ddfdfb-067c-458f-ac2e-fd957ed0ca2e 2026-02-03 12:58:49,017 - INFO - Verified VN network id 12 for VN 4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:49,018 - INFO - Verifications in API Server for VN ctest-vn-54653923 passed 2026-02-03 12:58:49,018 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:49,029 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/a5ddfdfb-067c-458f-ac2e-fd957ed0ca2e 2026-02-03 12:58:49,039 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/a5ddfdfb-067c-458f-ac2e-fd957ed0ca2e 2026-02-03 12:58:49,047 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/8230606c-1a9b-4c48-b1be-d8c3ddc90e84 2026-02-03 12:58:49,060 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-54653923', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-77139567', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5693046812314914396', 'uuid-lslong': '11325001146926821473'}, 'enable': 'true', 'created': '2026-02-03T12:58:46', 'last-modified': '2026-02-03T12:58:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.456938'} 2026-02-03 12:58:49,066 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-02-03 12:58:49,066 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-54653923 passed 2026-02-03 12:58:49,066 - DEBUG - ====Verifying policy data for ctest-vn-54653923 in API_Server ====== 2026-02-03 12:58:49,066 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:58:49,073 - DEBUG - Requesting: http://10.0.0.129:8082/domain/33182b97-85b8-4cd8-a9b5-fe71359837a2 2026-02-03 12:58:49,085 - DEBUG - Requesting: http://10.0.0.129:8082/project/d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:58:49,160 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 2026-02-03 12:58:49,170 - DEBUG - ==>Verifying data for policy with id: f00642a8-b0e3-4eee-a95e-4c7088731c85, fqn: ['default-domain', 'ctest-TestBasicPolicy-77139567', 'ctest-policy-allow-all-93888878'] 2026-02-03 12:58:49,170 - INFO - VN ctest-vn-54653923 Policy verification: verify_vn_policy_in_api_server, status: True 2026-02-03 12:58:49,170 - DEBUG - Verifying the vn in opserver 2026-02-03 12:58:49,170 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-54653923 virtual network link through opserver 10.0.0.129 2026-02-03 12:58:49,170 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2026-02-03 12:58:49,182 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-54653923', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-54653923?flat'} 2026-02-03 12:58:49,182 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-54653923 is found in opserver 2026-02-03 12:58:49,191 - DEBUG - Do not have enough data to verify VN in agent 2026-02-03 12:58:49,195 - DEBUG - VRF ids for VN ctest-vn-54653923: {} 2026-02-03 12:58:49,269 - DEBUG - Services list from nova: [, , ] 2026-02-03 12:58:50,428 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5188-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5188-1) 2026-02-03 12:58:51,692 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5188-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5188-1) 2026-02-03 12:58:51,692 - INFO - Waiting for VM ctest-TestBasicPolicy-77139567-88299194 to be up.. 2026-02-03 12:58:51,800 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-03 12:58:56,889 - DEBUG - VM is in ACTIVE state now 2026-02-03 12:58:56,890 - INFO - VM name : ctest-TestBasicPolicy-77139567-88299194 2026-02-03 12:58:56,976 - DEBUG - VM ctest-TestBasicPolicy-77139567-88299194 ID is 2a979a5c-2d8a-4ab6-bd18-4dda994bfc63 2026-02-03 12:58:56,999 - DEBUG - VM ctest-TestBasicPolicy-77139567-88299194 launched on Node cn-jenkins-deploy-platform-ansible-os-5188-1 2026-02-03 12:58:57,095 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/2a979a5c-2d8a-4ab6-bd18-4dda994bfc63 2026-02-03 12:58:57,107 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/e2c679ec-8ac3-420c-9368-b04ef15ae3af 2026-02-03 12:59:00,379 - 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 1022ms') 2026-02-03 12:59:00,379 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-77139567-88299194 failed! 2026-02-03 12:59:00,439 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-77139567:ctest-vn-70513419 is 164.216.144.65 and allocation pool is NOT set 2026-02-03 12:59:02,504 - 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.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.70 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 = 2.699/5.600/8.502/2.901 ms') 2026-02-03 12:59:02,504 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-77139567-88299194 passed 2026-02-03 12:59:02,663 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:59:02,663 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-77139567-88299194, IP 164.216.144.67, Port 22 2026-02-03 12:59:02,834 - DEBUG - VM ctest-TestBasicPolicy-77139567-88299194 is ready for SSH connections 2026-02-03 12:59:02,834 - INFO - Waiting for VM ctest-TestBasicPolicy-77139567-98294234 to be up.. 2026-02-03 12:59:02,988 - DEBUG - VM is in ACTIVE state now 2026-02-03 12:59:02,988 - INFO - VM name : ctest-TestBasicPolicy-77139567-98294234 2026-02-03 12:59:03,105 - DEBUG - VM ctest-TestBasicPolicy-77139567-98294234 ID is f63e9154-7ae7-4d38-a3f8-0500c564d716 2026-02-03 12:59:03,105 - DEBUG - VM ctest-TestBasicPolicy-77139567-98294234 launched on Node cn-jenkins-deploy-platform-ansible-os-5188-1 2026-02-03 12:59:03,227 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/f63e9154-7ae7-4d38-a3f8-0500c564d716 2026-02-03 12:59:03,238 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/38ffd22e-b06c-4eba-be43-53bb3638ff9d 2026-02-03 12:59:04,494 - 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.95 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.35 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 = 2.349/3.651/4.954/1.302 ms') 2026-02-03 12:59:04,494 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-77139567-98294234 passed 2026-02-03 12:59:04,651 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:59:04,651 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-77139567-98294234, IP 155.133.198.195, Port 22 2026-02-03 12:59:04,821 - DEBUG - VM ctest-TestBasicPolicy-77139567-98294234 is ready for SSH connections 2026-02-03 12:59:04,822 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:59:04,822 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 155.133.198.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.129, gateway password: c0ntrail123 2026-02-03 12:59:04,822 - DEBUG - ping -s 56 -c 3 -W 1 155.133.198.195 2026-02-03 12:59:09,046 - DEBUG - PING 155.133.198.195 (155.133.198.195): 56 data bytes 64 bytes from 155.133.198.195: seq=0 ttl=63 time=4.200 ms 64 bytes from 155.133.198.195: seq=1 ttl=63 time=0.929 ms 64 bytes from 155.133.198.195: seq=2 ttl=63 time=1.248 ms --- 155.133.198.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.929/2.125/4.200 ms 2026-02-03 12:59:09,046 - INFO - Ping to IP 155.133.198.195 from VM ctest-TestBasicPolicy-77139567-88299194 passed 2026-02-03 12:59:09,242 - DEBUG - Policy Update Response {"network-policy": {"uuid": "f00642a8-b0e3-4eee-a95e-4c7088731c85", "href": "http://10.0.0.129:8082/network-policy/f00642a8-b0e3-4eee-a95e-4c7088731c85"}} 2026-02-03 12:59:09,243 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-03 12:59:09,243 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 155.133.198.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.129, gateway password: c0ntrail123 2026-02-03 12:59:09,243 - DEBUG - ping -s 56 -c 3 -W 1 155.133.198.195 2026-02-03 12:59:12,481 - DEBUG - PING 155.133.198.195 (155.133.198.195): 56 data bytes --- 155.133.198.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-03 12:59:12,481 - WARNING - Ping to IP 155.133.198.195 from VM ctest-TestBasicPolicy-77139567-88299194 failed 2026-02-03 12:59:12,481 - INFO - Deleting VM ctest-TestBasicPolicy-77139567-98294234 2026-02-03 12:59:12,584 - INFO - Deleting VM ctest-TestBasicPolicy-77139567-88299194 2026-02-03 12:59:13,019 - INFO - Deleted policy ctest-policy-allow-all-93888878 2026-02-03 12:59:13,019 - INFO - Deleting VN ctest-vn-54653923 2026-02-03 12:59:13,060 - DEBUG - VN 4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061 still in use: Unable to complete operation on network 4f01c3e9-a9b3-4e5c-9d2a-7c8a6b76f061. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e13f69de-f1f7-4ca3-b707-a2a127b89d28'] 2026-02-03 12:59:13,061 - WARNING - Deleting VN ctest-vn-54653923 failed..Will retry 2026-02-03 12:59:15,180 - DEBUG - Response for deleting network () 2026-02-03 12:59:15,180 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/a5ddfdfb-067c-458f-ac2e-fd957ed0ca2e 2026-02-03 12:59:15,186 - DEBUG - Response Code: 404 2026-02-03 12:59:15,186 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:59:15,197 - DEBUG - Requesting: http://10.0.0.129:8082/domain/33182b97-85b8-4cd8-a9b5-fe71359837a2 2026-02-03 12:59:15,252 - DEBUG - Requesting: http://10.0.0.129:8082/project/d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:59:15,331 - INFO - Validated that VN ctest-vn-54653923 is not found in API Server 2026-02-03 12:59:15,341 - DEBUG - VN ctest-vn-54653923 is not present in Agent 10.0.0.129 2026-02-03 12:59:15,341 - INFO - Validated that VN ctest-vn-54653923 is not in any agent 2026-02-03 12:59:15,354 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-54653923 info 2026-02-03 12:59:15,355 - INFO - Deleting VN ctest-vn-70513419 2026-02-03 12:59:15,474 - DEBUG - Response for deleting network () 2026-02-03 12:59:15,474 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/174dc953-56fe-42b6-b846-c1394d216d9f 2026-02-03 12:59:15,480 - DEBUG - Response Code: 404 2026-02-03 12:59:15,481 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2026-02-03 12:59:15,487 - DEBUG - Requesting: http://10.0.0.129:8082/domain/33182b97-85b8-4cd8-a9b5-fe71359837a2 2026-02-03 12:59:15,545 - DEBUG - Requesting: http://10.0.0.129:8082/project/d364e2a0-8b06-4e76-9ffc-39c2b565472c 2026-02-03 12:59:15,624 - INFO - Validated that VN ctest-vn-70513419 is not found in API Server 2026-02-03 12:59:15,635 - DEBUG - VN ctest-vn-70513419 is not present in Agent 10.0.0.129 2026-02-03 12:59:15,635 - INFO - Validated that VN ctest-vn-70513419 is not in any agent 2026-02-03 12:59:15,651 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-70513419 info 2026-02-03 12:59:15,918 - DEBUG - No XMPP flaps were noticed during the test
2026-02-03 12:59:15,918 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:31]
2026-02-03 12:59:15,918 - INFO - -------------------------------------------------------------------------------- 2026-02-03 12:59:16,708 - INFO - Deleted project: ctest-TestBasicPolicy-77139567, ID : d364e2a0-8b06-4e76-9ffc-39c2b565472c