2026-01-23 13:40:15,444 - INFO - Domain Default found not creating 2026-01-23 13:40:15,624 - INFO - Project ctest-TestBasicPolicy-39580015 not found, creating it 2026-01-23 13:40:16,224 - INFO - Created Project:ctest-TestBasicPolicy-39580015, ID : 29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:17,861 - INFO - ================================================================================ 2026-01-23 13:40:17,861 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-01-23 13:40:17,861 - 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-01-23 13:40:19,162 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.192': '0', '10.20.0.252': '0'}, '10.0.0.65': {'10.20.0.192': '0', '10.20.0.252': '0'}} with 2026-01-23 13:40:19,162 - INFO - Initial checks done. Running the testcase now 2026-01-23 13:40:19,162 - INFO - 2026-01-23 13:40:19,822 - DEBUG - Response for create_network : {'network': {'id': '9453a99b-5e74-4bb0-9837-78067ca2b287', 'name': 'ctest-vn-80470724', 'tenant_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'project_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-vn-80470724'], 'port_security_enabled': True, 'description': ''}} 2026-01-23 13:40:20,149 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd9a0e2db-3213-4262-98db-397fbf9e30de', 'name': '', 'tenant_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'network_id': '9453a99b-5e74-4bb0-9837-78067ca2b287', 'ip_version': 4, 'cidr': '78.252.133.192/26', 'allocation_pools': [{'start': '78.252.133.194', 'end': '78.252.133.254'}], 'gateway_ip': '78.252.133.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.252.133.194', 'tags': [], 'project_id': '29b778ecf52c4110be0597c7e1f8a8ef'}} 2026-01-23 13:40:20,177 - INFO - Created VN ctest-vn-80470724 2026-01-23 13:40:20,191 - DEBUG - VN ctest-vn-80470724 UUID is 9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:20,593 - DEBUG - Response for create_network : {'network': {'id': 'd152d927-d10c-402e-a686-dc64a2fd41cf', 'name': 'ctest-vn-48184348', 'tenant_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'project_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-vn-48184348'], 'port_security_enabled': True, 'description': ''}} 2026-01-23 13:40:20,832 - DEBUG - Response for create_subnet : {'subnet': {'id': '6493204d-bcec-4e07-895f-9a9978b25328', 'name': '', 'tenant_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'network_id': 'd152d927-d10c-402e-a686-dc64a2fd41cf', 'ip_version': 4, 'cidr': '165.169.15.128/26', 'allocation_pools': [{'start': '165.169.15.130', 'end': '165.169.15.190'}], 'gateway_ip': '165.169.15.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '165.169.15.130', 'tags': [], 'project_id': '29b778ecf52c4110be0597c7e1f8a8ef'}} 2026-01-23 13:40:20,855 - INFO - Created VN ctest-vn-48184348 2026-01-23 13:40:20,865 - DEBUG - VN ctest-vn-48184348 UUID is d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:20,983 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724, 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-39580015:ctest-vn-48184348, 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-01-23 13:40:21,317 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '9453a99b-5e74-4bb0-9837-78067ca2b287', 'name': 'ctest-vn-80470724', 'tenant_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'project_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['d9a0e2db-3213-4262-98db-397fbf9e30de'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-vn-80470724'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-policy-allow-all-76105791']], 'subnet_ipam': [{'subnet_cidr': '78.252.133.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-01-23 13:40:21,573 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'd152d927-d10c-402e-a686-dc64a2fd41cf', 'name': 'ctest-vn-48184348', 'tenant_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'project_id': '29b778ecf52c4110be0597c7e1f8a8ef', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['6493204d-bcec-4e07-895f-9a9978b25328'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-vn-48184348'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-policy-allow-all-76105791']], 'subnet_ipam': [{'subnet_cidr': '165.169.15.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-01-23 13:40:21,669 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:22,032 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:22,062 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca 2026-01-23 13:40:22,075 - DEBUG - Requesting: http://10.0.0.241:8082/project/29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:22,178 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:22,194 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:22,212 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/d7889b95-c94a-4314-a484-7069dd742efe 2026-01-23 13:40:22,221 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/d7889b95-c94a-4314-a484-7069dd742efe 2026-01-23 13:40:22,229 - DEBUG - Requesting: http://10.0.0.241:8082/route-target/84905084-f51e-4061-8891-5555a7685a3c 2026-01-23 13:40:22,236 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-23 13:40:22,236 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:22,248 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/d7889b95-c94a-4314-a484-7069dd742efe 2026-01-23 13:40:22,267 - INFO - Verified VN network id 11 for VN 9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:22,268 - INFO - Verifications in API Server for VN ctest-vn-80470724 passed 2026-01-23 13:40:22,268 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:22,279 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/d7889b95-c94a-4314-a484-7069dd742efe 2026-01-23 13:40:22,287 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/d7889b95-c94a-4314-a484-7069dd742efe 2026-01-23 13:40:22,294 - DEBUG - Requesting: http://10.0.0.241:8082/route-target/84905084-f51e-4061-8891-5555a7685a3c 2026-01-23 13:40:22,307 - DEBUG - Control-node 10.0.0.241 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-39580015', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10688072825450023856', 'uuid-lslong': '10968367386740306567'}, 'enable': 'true', 'created': '2026-01-23T13:40:19', 'last-modified': '2026-01-23T13:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.025267'} 2026-01-23 13:40:22,315 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-23 13:40:22,321 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-39580015', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10688072825450023856', 'uuid-lslong': '10968367386740306567'}, 'enable': 'true', 'created': '2026-01-23T13:40:19', 'last-modified': '2026-01-23T13:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.039331'} 2026-01-23 13:40:22,327 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-23 13:40:22,333 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-39580015', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10688072825450023856', 'uuid-lslong': '10968367386740306567'}, 'enable': 'true', 'created': '2026-01-23T13:40:19', 'last-modified': '2026-01-23T13:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.052921'} 2026-01-23 13:40:22,339 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-23 13:40:22,339 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-80470724 passed 2026-01-23 13:40:22,339 - DEBUG - ====Verifying policy data for ctest-vn-80470724 in API_Server ====== 2026-01-23 13:40:22,340 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:22,347 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca 2026-01-23 13:40:22,360 - DEBUG - Requesting: http://10.0.0.241:8082/project/29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:22,456 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/9453a99b-5e74-4bb0-9837-78067ca2b287 2026-01-23 13:40:22,474 - DEBUG - ==>Verifying data for policy with id: a8365542-7fc3-407b-828d-afc094bd7bf7, fqn: ['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-policy-allow-all-76105791'] 2026-01-23 13:40:22,474 - INFO - VN ctest-vn-80470724 Policy verification: verify_vn_policy_in_api_server, status: True 2026-01-23 13:40:22,474 - DEBUG - Verifying the vn in opserver 2026-01-23 13:40:22,474 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724 virtual network link through opserver 10.0.0.241 2026-01-23 13:40:22,474 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-networks 2026-01-23 13:40:22,775 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-networks 2026-01-23 13:40:22,835 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724', 'href': 'http://10.0.0.241:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724?flat'} 2026-01-23 13:40:22,835 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724 virtual network link through opserver 10.0.0.242 2026-01-23 13:40:22,836 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks 2026-01-23 13:40:23,131 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks 2026-01-23 13:40:23,195 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724?flat'} 2026-01-23 13:40:23,195 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724 virtual network link through opserver 10.0.0.240 2026-01-23 13:40:23,195 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-01-23 13:40:23,503 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-01-23 13:40:23,567 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724?flat'} 2026-01-23 13:40:23,567 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724 is found in opserver 2026-01-23 13:40:23,587 - DEBUG - Do not have enough data to verify VN in agent 2026-01-23 13:40:23,595 - DEBUG - VRF ids for VN ctest-vn-80470724: {} 2026-01-23 13:40:23,595 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:23,601 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca 2026-01-23 13:40:23,614 - DEBUG - Requesting: http://10.0.0.241:8082/project/29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:23,774 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:23,789 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:23,800 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/211ac810-a0b6-4314-ab59-5c8ea0a8c4cf 2026-01-23 13:40:23,808 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/211ac810-a0b6-4314-ab59-5c8ea0a8c4cf 2026-01-23 13:40:23,817 - DEBUG - Requesting: http://10.0.0.241:8082/route-target/27726ac4-656a-469c-9c28-28593f79fa9c 2026-01-23 13:40:23,823 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-23 13:40:23,823 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:23,833 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/211ac810-a0b6-4314-ab59-5c8ea0a8c4cf 2026-01-23 13:40:23,850 - INFO - Verified VN network id 12 for VN d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:23,851 - INFO - Verifications in API Server for VN ctest-vn-48184348 passed 2026-01-23 13:40:23,851 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:23,860 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/211ac810-a0b6-4314-ab59-5c8ea0a8c4cf 2026-01-23 13:40:23,868 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/211ac810-a0b6-4314-ab59-5c8ea0a8c4cf 2026-01-23 13:40:23,875 - DEBUG - Requesting: http://10.0.0.241:8082/route-target/27726ac4-656a-469c-9c28-28593f79fa9c 2026-01-23 13:40:23,887 - DEBUG - Control-node 10.0.0.241 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-39580015', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15083356867051405358', 'uuid-lslong': '11999520581964612047'}, 'enable': 'true', 'created': '2026-01-23T13:40:20', 'last-modified': '2026-01-23T13:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.375485'} 2026-01-23 13:40:23,893 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-23 13:40:23,899 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-39580015', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15083356867051405358', 'uuid-lslong': '11999520581964612047'}, 'enable': 'true', 'created': '2026-01-23T13:40:20', 'last-modified': '2026-01-23T13:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.386271'} 2026-01-23 13:40:23,906 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-23 13:40:23,911 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-39580015', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15083356867051405358', 'uuid-lslong': '11999520581964612047'}, 'enable': 'true', 'created': '2026-01-23T13:40:20', 'last-modified': '2026-01-23T13:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.401585'} 2026-01-23 13:40:23,916 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-23 13:40:23,916 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-48184348 passed 2026-01-23 13:40:23,916 - DEBUG - ====Verifying policy data for ctest-vn-48184348 in API_Server ====== 2026-01-23 13:40:23,917 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:23,923 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca 2026-01-23 13:40:23,934 - DEBUG - Requesting: http://10.0.0.241:8082/project/29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:24,029 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/d152d927-d10c-402e-a686-dc64a2fd41cf 2026-01-23 13:40:24,039 - DEBUG - ==>Verifying data for policy with id: a8365542-7fc3-407b-828d-afc094bd7bf7, fqn: ['default-domain', 'ctest-TestBasicPolicy-39580015', 'ctest-policy-allow-all-76105791'] 2026-01-23 13:40:24,039 - INFO - VN ctest-vn-48184348 Policy verification: verify_vn_policy_in_api_server, status: True 2026-01-23 13:40:24,039 - DEBUG - Verifying the vn in opserver 2026-01-23 13:40:24,039 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348 virtual network link through opserver 10.0.0.241 2026-01-23 13:40:24,039 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-networks 2026-01-23 13:40:24,103 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348', 'href': 'http://10.0.0.241:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348?flat'} 2026-01-23 13:40:24,103 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348 virtual network link through opserver 10.0.0.242 2026-01-23 13:40:24,104 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks 2026-01-23 13:40:24,164 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348?flat'} 2026-01-23 13:40:24,164 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348 virtual network link through opserver 10.0.0.240 2026-01-23 13:40:24,164 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-01-23 13:40:24,226 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348?flat'} 2026-01-23 13:40:24,226 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-48184348 is found in opserver 2026-01-23 13:40:24,245 - DEBUG - Do not have enough data to verify VN in agent 2026-01-23 13:40:24,256 - DEBUG - VRF ids for VN ctest-vn-48184348: {} 2026-01-23 13:40:24,312 - DEBUG - Services list from nova: [, , , ] 2026-01-23 13:40:25,335 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5069-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5069-2) 2026-01-23 13:40:26,654 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5069-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5069-1) 2026-01-23 13:40:26,655 - INFO - Waiting for VM ctest-TestBasicPolicy-39580015-83687857 to be up.. 2026-01-23 13:40:26,759 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-23 13:40:31,843 - DEBUG - VM is in ACTIVE state now 2026-01-23 13:40:31,843 - INFO - VM name : ctest-TestBasicPolicy-39580015-83687857 2026-01-23 13:40:31,929 - DEBUG - VM ctest-TestBasicPolicy-39580015-83687857 ID is 36e70418-1bcc-4334-9155-9f0ffcadf23f 2026-01-23 13:40:31,952 - DEBUG - VM ctest-TestBasicPolicy-39580015-83687857 launched on Node an-jenkins-deploy-platform-ansible-os-5069-2 2026-01-23 13:40:32,036 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/36e70418-1bcc-4334-9155-9f0ffcadf23f 2026-01-23 13:40:32,337 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/36e70418-1bcc-4334-9155-9f0ffcadf23f 2026-01-23 13:40:32,381 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/3aa0c47c-370a-4efd-9edb-590d32b65728 2026-01-23 13:40:35,613 - 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-01-23 13:40:35,613 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-39580015-83687857 failed! 2026-01-23 13:40:35,626 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-39580015:ctest-vn-80470724 is 78.252.133.193 and allocation pool is NOT set 2026-01-23 13:40:37,686 - 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=5.32 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.774 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.774/3.046/5.319/2.272 ms') 2026-01-23 13:40:37,686 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-39580015-83687857 passed 2026-01-23 13:40:37,810 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 13:40:37,810 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-39580015-83687857, IP 78.252.133.195, Port 22 2026-01-23 13:40:37,976 - DEBUG - VM ctest-TestBasicPolicy-39580015-83687857 is ready for SSH connections 2026-01-23 13:40:37,976 - INFO - Waiting for VM ctest-TestBasicPolicy-39580015-22804554 to be up.. 2026-01-23 13:40:38,078 - DEBUG - VM is in ACTIVE state now 2026-01-23 13:40:38,078 - INFO - VM name : ctest-TestBasicPolicy-39580015-22804554 2026-01-23 13:40:38,170 - DEBUG - VM ctest-TestBasicPolicy-39580015-22804554 ID is 5d4f9861-4349-4ca3-8226-5a25b32eefa3 2026-01-23 13:40:38,170 - DEBUG - VM ctest-TestBasicPolicy-39580015-22804554 launched on Node an-jenkins-deploy-platform-ansible-os-5069-1 2026-01-23 13:40:38,257 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/5d4f9861-4349-4ca3-8226-5a25b32eefa3 2026-01-23 13:40:38,267 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/a35abdae-ec04-4074-8731-f61042e3a4db 2026-01-23 13:40:39,480 - 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.67 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.550 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.550/2.112/3.674/1.562 ms') 2026-01-23 13:40:39,480 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-39580015-22804554 passed 2026-01-23 13:40:39,591 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 13:40:39,591 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-39580015-22804554, IP 165.169.15.131, Port 22 2026-01-23 13:40:39,763 - DEBUG - VM ctest-TestBasicPolicy-39580015-22804554 is ready for SSH connections 2026-01-23 13:40:39,763 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 13:40:39,763 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 165.169.15.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-23 13:40:39,764 - DEBUG - ping -s 56 -c 3 -W 1 165.169.15.131 2026-01-23 13:40:43,685 - DEBUG - PING 165.169.15.131 (165.169.15.131): 56 data bytes 64 bytes from 165.169.15.131: seq=0 ttl=63 time=6.604 ms 64 bytes from 165.169.15.131: seq=1 ttl=63 time=1.373 ms 64 bytes from 165.169.15.131: seq=2 ttl=63 time=1.397 ms --- 165.169.15.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.373/3.124/6.604 ms 2026-01-23 13:40:43,685 - INFO - Ping to IP 165.169.15.131 from VM ctest-TestBasicPolicy-39580015-83687857 passed 2026-01-23 13:40:43,914 - DEBUG - Policy Update Response {"network-policy": {"uuid": "a8365542-7fc3-407b-828d-afc094bd7bf7", "href": "http://10.0.0.240:8082/network-policy/a8365542-7fc3-407b-828d-afc094bd7bf7"}} 2026-01-23 13:40:43,914 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-23 13:40:43,914 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 165.169.15.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-23 13:40:43,914 - DEBUG - ping -s 56 -c 3 -W 1 165.169.15.131 2026-01-23 13:40:47,120 - DEBUG - PING 165.169.15.131 (165.169.15.131): 56 data bytes --- 165.169.15.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-23 13:40:47,120 - WARNING - Ping to IP 165.169.15.131 from VM ctest-TestBasicPolicy-39580015-83687857 failed 2026-01-23 13:40:47,121 - INFO - Deleting VM ctest-TestBasicPolicy-39580015-22804554 2026-01-23 13:40:47,196 - INFO - Deleting VM ctest-TestBasicPolicy-39580015-83687857 2026-01-23 13:40:47,724 - INFO - Deleted policy ctest-policy-allow-all-76105791 2026-01-23 13:40:47,724 - INFO - Deleting VN ctest-vn-48184348 2026-01-23 13:40:47,830 - DEBUG - VN d152d927-d10c-402e-a686-dc64a2fd41cf still in use: Unable to complete operation on network d152d927-d10c-402e-a686-dc64a2fd41cf. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-972aaa0c-c95e-4ce2-bdcb-7b41091d6b88'] 2026-01-23 13:40:47,830 - WARNING - Deleting VN ctest-vn-48184348 failed..Will retry 2026-01-23 13:40:50,034 - DEBUG - Response for deleting network () 2026-01-23 13:40:50,034 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/211ac810-a0b6-4314-ab59-5c8ea0a8c4cf 2026-01-23 13:40:50,044 - DEBUG - Response Code: 404 2026-01-23 13:40:50,044 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:50,061 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca 2026-01-23 13:40:50,087 - DEBUG - Requesting: http://10.0.0.241:8082/project/29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:50,203 - INFO - Validated that VN ctest-vn-48184348 is not found in API Server 2026-01-23 13:40:50,214 - DEBUG - VN ctest-vn-48184348 is not present in Agent 10.0.0.19 2026-01-23 13:40:50,227 - DEBUG - VN ctest-vn-48184348 is not present in Agent 10.0.0.65 2026-01-23 13:40:50,227 - INFO - Validated that VN ctest-vn-48184348 is not in any agent 2026-01-23 13:40:50,272 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-48184348 info 2026-01-23 13:40:50,272 - INFO - Deleting VN ctest-vn-80470724 2026-01-23 13:40:50,467 - DEBUG - Response for deleting network () 2026-01-23 13:40:50,467 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/d7889b95-c94a-4314-a484-7069dd742efe 2026-01-23 13:40:50,472 - DEBUG - Response Code: 404 2026-01-23 13:40:50,472 - DEBUG - Requesting: http://10.0.0.241:8082/domains 2026-01-23 13:40:50,483 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca 2026-01-23 13:40:50,496 - DEBUG - Requesting: http://10.0.0.241:8082/project/29b778ec-f52c-4110-be05-97c7e1f8a8ef 2026-01-23 13:40:50,584 - INFO - Validated that VN ctest-vn-80470724 is not found in API Server 2026-01-23 13:40:50,595 - DEBUG - VN ctest-vn-80470724 is not present in Agent 10.0.0.19 2026-01-23 13:40:50,604 - DEBUG - VN ctest-vn-80470724 is not present in Agent 10.0.0.65 2026-01-23 13:40:50,604 - INFO - Validated that VN ctest-vn-80470724 is not in any agent 2026-01-23 13:40:50,657 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-80470724 info 2026-01-23 13:40:51,959 - DEBUG - No XMPP flaps were noticed during the test
2026-01-23 13:40:51,959 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:34]
2026-01-23 13:40:51,960 - INFO - -------------------------------------------------------------------------------- 2026-01-23 13:40:52,711 - INFO - Deleted project: ctest-TestBasicPolicy-39580015, ID : 29b778ec-f52c-4110-be05-97c7e1f8a8ef