2026-08-23 17:03:30,241 - INFO - Domain Default found not creating 2026-08-23 17:03:30,412 - INFO - Project ctest-TestBasicPolicy-79226647 not found, creating it 2026-08-23 17:03:30,909 - INFO - Created Project:ctest-TestBasicPolicy-79226647, ID : 98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:03:32,685 - INFO - ================================================================================ 2026-08-23 17:03:32,685 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-08-23 17:03:32,685 - 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-08-23 17:03:32,950 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.17': '0'}} with 2026-08-23 17:03:32,950 - INFO - Initial checks done. Running the testcase now 2026-08-23 17:03:32,950 - INFO - 2026-08-23 17:03:33,667 - DEBUG - Response for create_network : {'network': {'id': '95ec1146-a0bc-473f-8313-23ecd1477b23', 'name': 'ctest-vn-97719094', 'tenant_id': '98fed1614031491b98e8a3c9b344ad86', 'project_id': '98fed1614031491b98e8a3c9b344ad86', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-vn-97719094'], 'port_security_enabled': True, 'description': ''}} 2026-08-23 17:03:33,884 - DEBUG - Response for create_subnet : {'subnet': {'id': '7c1a264a-94d0-4b91-a4a4-405b7702dd67', 'name': '', 'tenant_id': '98fed1614031491b98e8a3c9b344ad86', 'network_id': '95ec1146-a0bc-473f-8313-23ecd1477b23', 'ip_version': 4, 'cidr': '90.85.236.128/26', 'allocation_pools': [{'start': '90.85.236.130', 'end': '90.85.236.190'}], 'gateway_ip': '90.85.236.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.85.236.130', 'tags': [], 'project_id': '98fed1614031491b98e8a3c9b344ad86'}} 2026-08-23 17:03:33,906 - INFO - Created VN ctest-vn-97719094 2026-08-23 17:03:33,961 - DEBUG - VN ctest-vn-97719094 UUID is 95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:34,280 - DEBUG - Response for create_network : {'network': {'id': 'b8cd3e63-ca82-4e30-925b-ad8a132ba7d2', 'name': 'ctest-vn-55037086', 'tenant_id': '98fed1614031491b98e8a3c9b344ad86', 'project_id': '98fed1614031491b98e8a3c9b344ad86', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-vn-55037086'], 'port_security_enabled': True, 'description': ''}} 2026-08-23 17:03:34,473 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd3dc7117-4301-4e9c-87a2-4998085b6475', 'name': '', 'tenant_id': '98fed1614031491b98e8a3c9b344ad86', 'network_id': 'b8cd3e63-ca82-4e30-925b-ad8a132ba7d2', 'ip_version': 4, 'cidr': '62.77.139.0/26', 'allocation_pools': [{'start': '62.77.139.2', 'end': '62.77.139.62'}], 'gateway_ip': '62.77.139.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '62.77.139.2', 'tags': [], 'project_id': '98fed1614031491b98e8a3c9b344ad86'}} 2026-08-23 17:03:34,494 - INFO - Created VN ctest-vn-55037086 2026-08-23 17:03:34,549 - DEBUG - VN ctest-vn-55037086 UUID is b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:34,664 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094, 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-79226647:ctest-vn-55037086, 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-08-23 17:03:34,986 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '95ec1146-a0bc-473f-8313-23ecd1477b23', 'name': 'ctest-vn-97719094', 'tenant_id': '98fed1614031491b98e8a3c9b344ad86', 'project_id': '98fed1614031491b98e8a3c9b344ad86', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['7c1a264a-94d0-4b91-a4a4-405b7702dd67'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-vn-97719094'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-policy-allow-all-40268511']], 'subnet_ipam': [{'subnet_cidr': '90.85.236.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-08-23 17:03:35,230 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b8cd3e63-ca82-4e30-925b-ad8a132ba7d2', 'name': 'ctest-vn-55037086', 'tenant_id': '98fed1614031491b98e8a3c9b344ad86', 'project_id': '98fed1614031491b98e8a3c9b344ad86', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['d3dc7117-4301-4e9c-87a2-4998085b6475'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-vn-55037086'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-policy-allow-all-40268511']], 'subnet_ipam': [{'subnet_cidr': '62.77.139.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-08-23 17:03:35,413 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:03:35,787 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:03:35,817 - DEBUG - Requesting: http://10.0.0.161:8082/domain/26c5f815-39c8-4b64-a28c-1e754a1b87eb 2026-08-23 17:03:35,832 - DEBUG - Requesting: http://10.0.0.161:8082/project/98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:03:35,915 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:35,928 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:35,939 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/7836eff0-ccca-49b0-abfd-1c60537c8dc2 2026-08-23 17:03:35,948 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/7836eff0-ccca-49b0-abfd-1c60537c8dc2 2026-08-23 17:03:35,955 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/79cb1497-6530-47df-9d48-7368501e2236 2026-08-23 17:03:35,962 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-23 17:03:35,962 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:35,971 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/7836eff0-ccca-49b0-abfd-1c60537c8dc2 2026-08-23 17:03:35,987 - INFO - Verified VN network id 11 for VN 95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:35,987 - INFO - Verifications in API Server for VN ctest-vn-97719094 passed 2026-08-23 17:03:35,987 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:35,998 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/7836eff0-ccca-49b0-abfd-1c60537c8dc2 2026-08-23 17:03:36,007 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/7836eff0-ccca-49b0-abfd-1c60537c8dc2 2026-08-23 17:03:36,016 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/79cb1497-6530-47df-9d48-7368501e2236 2026-08-23 17:03:36,035 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-79226647', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10803028601197053759', 'uuid-lslong': '9444932343556438819'}, 'enable': 'true', 'created': '2026-08-23T17:03:33', 'last-modified': '2026-08-23T17:03:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.061718'} 2026-08-23 17:03:36,043 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-23 17:03:36,043 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-97719094 passed 2026-08-23 17:03:36,043 - DEBUG - ====Verifying policy data for ctest-vn-97719094 in API_Server ====== 2026-08-23 17:03:36,043 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:03:36,050 - DEBUG - Requesting: http://10.0.0.161:8082/domain/26c5f815-39c8-4b64-a28c-1e754a1b87eb 2026-08-23 17:03:36,061 - DEBUG - Requesting: http://10.0.0.161:8082/project/98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:03:36,141 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/95ec1146-a0bc-473f-8313-23ecd1477b23 2026-08-23 17:03:36,152 - DEBUG - ==>Verifying data for policy with id: 6216767e-a81d-47f8-bdaf-ae73abf5fccb, fqn: ['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-policy-allow-all-40268511'] 2026-08-23 17:03:36,152 - INFO - VN ctest-vn-97719094 Policy verification: verify_vn_policy_in_api_server, status: True 2026-08-23 17:03:36,152 - DEBUG - Verifying the vn in opserver 2026-08-23 17:03:36,152 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094 virtual network link through opserver 10.0.0.161 2026-08-23 17:03:36,152 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-08-23 17:03:36,532 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-08-23 17:03:36,594 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094?flat'} 2026-08-23 17:03:36,594 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094 is found in opserver 2026-08-23 17:03:36,606 - DEBUG - Do not have enough data to verify VN in agent 2026-08-23 17:03:36,611 - DEBUG - VRF ids for VN ctest-vn-97719094: {} 2026-08-23 17:03:36,611 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:03:36,618 - DEBUG - Requesting: http://10.0.0.161:8082/domain/26c5f815-39c8-4b64-a28c-1e754a1b87eb 2026-08-23 17:03:36,630 - DEBUG - Requesting: http://10.0.0.161:8082/project/98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:03:36,709 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:36,721 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:36,730 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/b42c8ac9-a412-4d2c-9745-7801256a8562 2026-08-23 17:03:36,739 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/b42c8ac9-a412-4d2c-9745-7801256a8562 2026-08-23 17:03:36,748 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/a5095f22-c450-4eec-8c44-61f6dae0a626 2026-08-23 17:03:36,756 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-23 17:03:36,756 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:36,766 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/b42c8ac9-a412-4d2c-9745-7801256a8562 2026-08-23 17:03:36,782 - INFO - Verified VN network id 12 for VN b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:36,782 - INFO - Verifications in API Server for VN ctest-vn-55037086 passed 2026-08-23 17:03:36,782 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:36,792 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/b42c8ac9-a412-4d2c-9745-7801256a8562 2026-08-23 17:03:36,800 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/b42c8ac9-a412-4d2c-9745-7801256a8562 2026-08-23 17:03:36,808 - DEBUG - Requesting: http://10.0.0.161:8082/route-target/a5095f22-c450-4eec-8c44-61f6dae0a626 2026-08-23 17:03:36,823 - DEBUG - Control-node 10.0.0.161 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-55037086', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-79226647', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13316368271524646448', 'uuid-lslong': '10546213760956868562'}, 'enable': 'true', 'created': '2026-08-23T17:03:34', 'last-modified': '2026-08-23T17:03:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.615795'} 2026-08-23 17:03:36,830 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-23 17:03:36,830 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-55037086 passed 2026-08-23 17:03:36,830 - DEBUG - ====Verifying policy data for ctest-vn-55037086 in API_Server ====== 2026-08-23 17:03:36,830 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:03:36,836 - DEBUG - Requesting: http://10.0.0.161:8082/domain/26c5f815-39c8-4b64-a28c-1e754a1b87eb 2026-08-23 17:03:36,847 - DEBUG - Requesting: http://10.0.0.161:8082/project/98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:03:36,932 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-network/b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 2026-08-23 17:03:36,945 - DEBUG - ==>Verifying data for policy with id: 6216767e-a81d-47f8-bdaf-ae73abf5fccb, fqn: ['default-domain', 'ctest-TestBasicPolicy-79226647', 'ctest-policy-allow-all-40268511'] 2026-08-23 17:03:36,945 - INFO - VN ctest-vn-55037086 Policy verification: verify_vn_policy_in_api_server, status: True 2026-08-23 17:03:36,945 - DEBUG - Verifying the vn in opserver 2026-08-23 17:03:36,945 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-55037086 virtual network link through opserver 10.0.0.161 2026-08-23 17:03:36,945 - DEBUG - Requesting: http://10.0.0.161:8081/analytics/uves/virtual-networks 2026-08-23 17:03:36,958 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-55037086', 'href': 'http://10.0.0.161:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-55037086?flat'} 2026-08-23 17:03:36,958 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-55037086 is found in opserver 2026-08-23 17:03:36,973 - DEBUG - Do not have enough data to verify VN in agent 2026-08-23 17:03:36,979 - DEBUG - VRF ids for VN ctest-vn-55037086: {} 2026-08-23 17:03:37,039 - DEBUG - Services list from nova: [, , ] 2026-08-23 17:03:37,501 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6209-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6209-1) 2026-08-23 17:03:37,961 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6209-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6209-1) 2026-08-23 17:03:37,961 - INFO - Waiting for VM ctest-TestBasicPolicy-79226647-83364569 to be up.. 2026-08-23 17:03:38,003 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:03:43,083 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:03:48,201 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:03:53,290 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:03:58,371 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:04:03,457 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:04:08,544 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-23 17:04:13,649 - DEBUG - VM is in ACTIVE state now 2026-08-23 17:04:13,649 - INFO - VM name : ctest-TestBasicPolicy-79226647-83364569 2026-08-23 17:04:13,741 - DEBUG - VM ctest-TestBasicPolicy-79226647-83364569 ID is cc4466a0-46b0-4fbe-84fc-79a9cab23f4c 2026-08-23 17:04:13,762 - DEBUG - VM ctest-TestBasicPolicy-79226647-83364569 launched on Node cn-jenkins-deploy-platform-ansible-os-6209-1 2026-08-23 17:04:13,847 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/cc4466a0-46b0-4fbe-84fc-79a9cab23f4c 2026-08-23 17:04:13,857 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/23e46167-214e-47e3-a95e-5e91ebf037da 2026-08-23 17:04:17,120 - 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 1008ms') 2026-08-23 17:04:17,120 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-79226647-83364569 failed! 2026-08-23 17:04:17,177 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094 is 90.85.236.129 and allocation pool is NOT set 2026-08-23 17:04:21,248 - 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 1005ms') 2026-08-23 17:04:21,249 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-79226647-83364569 failed! 2026-08-23 17:04:21,305 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-97719094 is 90.85.236.129 and allocation pool is NOT set 2026-08-23 17:04:23,370 - 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=4.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.598 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.598/2.376/4.154/1.778 ms') 2026-08-23 17:04:23,370 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-79226647-83364569 passed 2026-08-23 17:04:23,525 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-23 17:04:23,525 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-79226647-83364569, IP 90.85.236.131, Port 22 2026-08-23 17:04:23,695 - DEBUG - VM ctest-TestBasicPolicy-79226647-83364569 is ready for SSH connections 2026-08-23 17:04:23,696 - INFO - Waiting for VM ctest-TestBasicPolicy-79226647-98594552 to be up.. 2026-08-23 17:04:23,785 - DEBUG - VM is in ACTIVE state now 2026-08-23 17:04:23,786 - INFO - VM name : ctest-TestBasicPolicy-79226647-98594552 2026-08-23 17:04:23,882 - DEBUG - VM ctest-TestBasicPolicy-79226647-98594552 ID is e1ab186e-ccab-49f4-9685-1958aeeef963 2026-08-23 17:04:23,882 - DEBUG - VM ctest-TestBasicPolicy-79226647-98594552 launched on Node cn-jenkins-deploy-platform-ansible-os-6209-1 2026-08-23 17:04:23,993 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/e1ab186e-ccab-49f4-9685-1958aeeef963 2026-08-23 17:04:24,006 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/c2211f57-9ed6-4a6b-8ca5-7d0f588eb5bd 2026-08-23 17:04:27,329 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1028ms') 2026-08-23 17:04:27,329 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-79226647-98594552 failed! 2026-08-23 17:04:27,389 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-79226647:ctest-vn-55037086 is 62.77.139.1 and allocation pool is NOT set 2026-08-23 17:04:29,457 - 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.37 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.609 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.609/2.490/4.371/1.881 ms') 2026-08-23 17:04:29,457 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-79226647-98594552 passed 2026-08-23 17:04:29,609 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-23 17:04:29,609 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-79226647-98594552, IP 62.77.139.3, Port 22 2026-08-23 17:04:29,783 - DEBUG - VM ctest-TestBasicPolicy-79226647-98594552 is ready for SSH connections 2026-08-23 17:04:29,783 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-23 17:04:29,783 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 62.77.139.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-23 17:04:29,783 - DEBUG - ping -s 56 -c 3 -W 1 62.77.139.3 2026-08-23 17:04:33,865 - DEBUG - PING 62.77.139.3 (62.77.139.3): 56 data bytes 64 bytes from 62.77.139.3: seq=0 ttl=63 time=4.673 ms 64 bytes from 62.77.139.3: seq=1 ttl=63 time=1.077 ms 64 bytes from 62.77.139.3: seq=2 ttl=63 time=1.051 ms --- 62.77.139.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.051/2.267/4.673 ms 2026-08-23 17:04:33,867 - INFO - Ping to IP 62.77.139.3 from VM ctest-TestBasicPolicy-79226647-83364569 passed 2026-08-23 17:04:34,061 - DEBUG - Policy Update Response {"network-policy": {"uuid": "6216767e-a81d-47f8-bdaf-ae73abf5fccb", "href": "http://10.0.0.161:8082/network-policy/6216767e-a81d-47f8-bdaf-ae73abf5fccb"}} 2026-08-23 17:04:34,061 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-23 17:04:34,061 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 62.77.139.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-23 17:04:34,061 - DEBUG - ping -s 56 -c 3 -W 1 62.77.139.3 2026-08-23 17:04:37,208 - DEBUG - PING 62.77.139.3 (62.77.139.3): 56 data bytes 64 bytes from 62.77.139.3: seq=0 ttl=63 time=1.894 ms --- 62.77.139.3 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.894/1.894/1.894 ms 2026-08-23 17:04:37,208 - WARNING - Ping to IP 62.77.139.3 from VM ctest-TestBasicPolicy-79226647-83364569 failed 2026-08-23 17:04:37,208 - INFO - Deleting VM ctest-TestBasicPolicy-79226647-98594552 2026-08-23 17:04:37,295 - INFO - Deleting VM ctest-TestBasicPolicy-79226647-83364569 2026-08-23 17:04:37,759 - INFO - Deleted policy ctest-policy-allow-all-40268511 2026-08-23 17:04:37,759 - INFO - Deleting VN ctest-vn-55037086 2026-08-23 17:04:37,795 - DEBUG - VN b8cd3e63-ca82-4e30-925b-ad8a132ba7d2 still in use: Unable to complete operation on network b8cd3e63-ca82-4e30-925b-ad8a132ba7d2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bdad6a5c-ab95-4aea-900f-24cb967cec35'] 2026-08-23 17:04:37,795 - WARNING - Deleting VN ctest-vn-55037086 failed..Will retry 2026-08-23 17:04:39,922 - DEBUG - Response for deleting network () 2026-08-23 17:04:39,922 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/b42c8ac9-a412-4d2c-9745-7801256a8562 2026-08-23 17:04:39,932 - DEBUG - Response Code: 404 2026-08-23 17:04:39,933 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:04:39,940 - DEBUG - Requesting: http://10.0.0.161:8082/domain/26c5f815-39c8-4b64-a28c-1e754a1b87eb 2026-08-23 17:04:39,997 - DEBUG - Requesting: http://10.0.0.161:8082/project/98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:04:40,083 - INFO - Validated that VN ctest-vn-55037086 is not found in API Server 2026-08-23 17:04:40,095 - DEBUG - VN ctest-vn-55037086 is not present in Agent 10.0.0.161 2026-08-23 17:04:40,095 - INFO - Validated that VN ctest-vn-55037086 is not in any agent 2026-08-23 17:04:40,112 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-55037086 info 2026-08-23 17:04:40,112 - INFO - Deleting VN ctest-vn-97719094 2026-08-23 17:04:40,233 - DEBUG - Response for deleting network () 2026-08-23 17:04:40,233 - DEBUG - Requesting: http://10.0.0.161:8082/routing-instance/7836eff0-ccca-49b0-abfd-1c60537c8dc2 2026-08-23 17:04:40,239 - DEBUG - Response Code: 404 2026-08-23 17:04:40,239 - DEBUG - Requesting: http://10.0.0.161:8082/domains 2026-08-23 17:04:40,245 - DEBUG - Requesting: http://10.0.0.161:8082/domain/26c5f815-39c8-4b64-a28c-1e754a1b87eb 2026-08-23 17:04:40,299 - DEBUG - Requesting: http://10.0.0.161:8082/project/98fed161-4031-491b-98e8-a3c9b344ad86 2026-08-23 17:04:40,384 - INFO - Validated that VN ctest-vn-97719094 is not found in API Server 2026-08-23 17:04:40,398 - DEBUG - VN ctest-vn-97719094 is not present in Agent 10.0.0.161 2026-08-23 17:04:40,398 - INFO - Validated that VN ctest-vn-97719094 is not in any agent 2026-08-23 17:04:40,419 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-97719094 info 2026-08-23 17:04:40,690 - DEBUG - No XMPP flaps were noticed during the test
2026-08-23 17:04:40,690 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:08]
2026-08-23 17:04:40,690 - INFO - -------------------------------------------------------------------------------- 2026-08-23 17:04:41,485 - INFO - Deleted project: ctest-TestBasicPolicy-79226647, ID : 98fed161-4031-491b-98e8-a3c9b344ad86