2024-08-23 08:38:19,210 - INFO - Domain Default found not creating
2024-08-23 08:38:19,387 - INFO - Project ctest-TestBasicPolicy-63442621 not found, creating it
2024-08-23 08:38:20,240 - INFO - Created Project:ctest-TestBasicPolicy-63442621, ID : 0976e2b9-bbd4-4413-9866-97d547230a2e
2024-08-23 08:38:21,823 - INFO - ================================================================================
2024-08-23 08:38:21,823 - INFO - STARTING TEST : test_basic_policy_allow_deny
2024-08-23 08:38:21,823 - 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
2024-08-23 08:38:22,086 - DEBUG - Nothing to compare xmpp stats {'10.0.0.12': {'10.20.0.25': '0'}} with
2024-08-23 08:38:22,086 - INFO - Initial checks done. Running the testcase now
2024-08-23 08:38:22,086 - INFO -
2024-08-23 08:38:22,679 - DEBUG - Response for create_network : {'network': {'id': '39b23fbc-48c5-42a0-813e-eed8eb53dcf7', 'name': 'ctest-vn-43795198', 'tenant_id': '0976e2b9bbd44413986697d547230a2e', 'project_id': '0976e2b9bbd44413986697d547230a2e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-vn-43795198'], 'port_security_enabled': True, 'description': ''}}
2024-08-23 08:38:22,837 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dc06c918-be5e-44b3-88f9-a57f7547fd65', 'name': '', 'tenant_id': '0976e2b9bbd44413986697d547230a2e', 'network_id': '39b23fbc-48c5-42a0-813e-eed8eb53dcf7', 'ip_version': 4, 'cidr': '110.161.178.128/26', 'allocation_pools': [{'start': '110.161.178.130', 'end': '110.161.178.190'}], 'gateway_ip': '110.161.178.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '110.161.178.130', 'tags': [], 'project_id': '0976e2b9bbd44413986697d547230a2e'}}
2024-08-23 08:38:22,857 - INFO - Created VN ctest-vn-43795198
2024-08-23 08:38:22,912 - DEBUG - VN ctest-vn-43795198 UUID is 39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:23,218 - DEBUG - Response for create_network : {'network': {'id': '14713e8a-bc74-4c32-a2dd-a6834c39cc09', 'name': 'ctest-vn-79909305', 'tenant_id': '0976e2b9bbd44413986697d547230a2e', 'project_id': '0976e2b9bbd44413986697d547230a2e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-vn-79909305'], 'port_security_enabled': True, 'description': ''}}
2024-08-23 08:38:23,416 - DEBUG - Response for create_subnet : {'subnet': {'id': '983a5fb1-d077-479b-b6b6-997552b90281', 'name': '', 'tenant_id': '0976e2b9bbd44413986697d547230a2e', 'network_id': '14713e8a-bc74-4c32-a2dd-a6834c39cc09', 'ip_version': 4, 'cidr': '98.248.30.192/26', 'allocation_pools': [{'start': '98.248.30.194', 'end': '98.248.30.254'}], 'gateway_ip': '98.248.30.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '98.248.30.194', 'tags': [], 'project_id': '0976e2b9bbd44413986697d547230a2e'}}
2024-08-23 08:38:23,437 - INFO - Created VN ctest-vn-79909305
2024-08-23 08:38:23,492 - DEBUG - VN ctest-vn-79909305 UUID is 14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:23,609 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-43795198, 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-63442621:ctest-vn-79909305, 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]
2024-08-23 08:38:23,908 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '39b23fbc-48c5-42a0-813e-eed8eb53dcf7', 'name': 'ctest-vn-43795198', 'tenant_id': '0976e2b9bbd44413986697d547230a2e', 'project_id': '0976e2b9bbd44413986697d547230a2e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['dc06c918-be5e-44b3-88f9-a57f7547fd65'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-vn-43795198'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-policy-allow-all-26018350']], 'subnet_ipam': [{'subnet_cidr': '110.161.178.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2024-08-23 08:38:24,200 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '14713e8a-bc74-4c32-a2dd-a6834c39cc09', 'name': 'ctest-vn-79909305', 'tenant_id': '0976e2b9bbd44413986697d547230a2e', 'project_id': '0976e2b9bbd44413986697d547230a2e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['983a5fb1-d077-479b-b6b6-997552b90281'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-vn-79909305'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-policy-allow-all-26018350']], 'subnet_ipam': [{'subnet_cidr': '98.248.30.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2024-08-23 08:38:24,356 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2024-08-23 08:38:24,659 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2024-08-23 08:38:24,696 - DEBUG - Requesting: http://10.0.0.12:8082/domain/4f715ed0-879c-4c7f-8f7a-b55391705480
2024-08-23 08:38:24,714 - DEBUG - Requesting: http://10.0.0.12:8082/project/0976e2b9-bbd4-4413-9866-97d547230a2e
2024-08-23 08:38:24,786 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:24,797 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:24,806 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/6f2c9065-052c-4ed9-8a8e-872af5f5540c
2024-08-23 08:38:24,814 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/6f2c9065-052c-4ed9-8a8e-872af5f5540c
2024-08-23 08:38:24,822 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/08b9bafc-83f1-438b-813d-c5349a4208cd
2024-08-23 08:38:24,830 - DEBUG - Route Targets: ['target:64512:8000005']
2024-08-23 08:38:24,830 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:24,839 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/6f2c9065-052c-4ed9-8a8e-872af5f5540c
2024-08-23 08:38:24,853 - INFO - Verified VN network id 8 for VN 39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:24,853 - INFO - Verifications in API Server for VN ctest-vn-43795198 passed
2024-08-23 08:38:24,853 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:24,862 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/6f2c9065-052c-4ed9-8a8e-872af5f5540c
2024-08-23 08:38:24,872 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/6f2c9065-052c-4ed9-8a8e-872af5f5540c
2024-08-23 08:38:24,879 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/08b9bafc-83f1-438b-813d-c5349a4208cd
2024-08-23 08:38:24,892 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-43795198', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-63442621', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4157455483923677856', 'uuid-lslong': '9313143694877252855'}, 'enable': 'true', 'created': '2024-08-23T08:38:22', 'last-modified': '2024-08-23T08:38:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.006693'}
2024-08-23 08:38:24,899 - DEBUG - Route Targets: ['target:64512:8000005']
2024-08-23 08:38:24,900 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-43795198 passed
2024-08-23 08:38:24,900 - DEBUG - ====Verifying policy data for ctest-vn-43795198 in API_Server ======
2024-08-23 08:38:24,900 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2024-08-23 08:38:24,906 - DEBUG - Requesting: http://10.0.0.12:8082/domain/4f715ed0-879c-4c7f-8f7a-b55391705480
2024-08-23 08:38:24,919 - DEBUG - Requesting: http://10.0.0.12:8082/project/0976e2b9-bbd4-4413-9866-97d547230a2e
2024-08-23 08:38:24,991 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/39b23fbc-48c5-42a0-813e-eed8eb53dcf7
2024-08-23 08:38:25,000 - DEBUG - ==>Verifying data for policy with id: 0102194f-7d8f-4014-bcd2-852e83f26f82, fqn: ['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-policy-allow-all-26018350']
2024-08-23 08:38:25,000 - INFO - VN ctest-vn-43795198 Policy verification: verify_vn_policy_in_api_server, status: True
2024-08-23 08:38:25,000 - DEBUG - Verifying the vn in opserver
2024-08-23 08:38:25,001 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-43795198 virtual network link through opserver 10.0.0.12
2024-08-23 08:38:25,001 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2024-08-23 08:38:25,314 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2024-08-23 08:38:25,382 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-43795198', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-43795198?flat'}
2024-08-23 08:38:25,382 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-43795198 is found in opserver
2024-08-23 08:38:25,393 - DEBUG - Do not have enough data to verify VN in agent
2024-08-23 08:38:25,399 - DEBUG - VRF ids for VN ctest-vn-43795198: {}
2024-08-23 08:38:25,399 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2024-08-23 08:38:25,405 - DEBUG - Requesting: http://10.0.0.12:8082/domain/4f715ed0-879c-4c7f-8f7a-b55391705480
2024-08-23 08:38:25,418 - DEBUG - Requesting: http://10.0.0.12:8082/project/0976e2b9-bbd4-4413-9866-97d547230a2e
2024-08-23 08:38:25,489 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:25,500 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:25,508 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/b6e3642d-0bad-44bc-9fbd-5d8a8fae5628
2024-08-23 08:38:25,516 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/b6e3642d-0bad-44bc-9fbd-5d8a8fae5628
2024-08-23 08:38:25,523 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/5a62ebb8-119b-4bd6-acae-7655efce39aa
2024-08-23 08:38:25,530 - DEBUG - Route Targets: ['target:64512:8000006']
2024-08-23 08:38:25,530 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:25,538 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/b6e3642d-0bad-44bc-9fbd-5d8a8fae5628
2024-08-23 08:38:25,554 - INFO - Verified VN network id 9 for VN 14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:25,554 - INFO - Verifications in API Server for VN ctest-vn-79909305 passed
2024-08-23 08:38:25,555 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:25,566 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/b6e3642d-0bad-44bc-9fbd-5d8a8fae5628
2024-08-23 08:38:25,574 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/b6e3642d-0bad-44bc-9fbd-5d8a8fae5628
2024-08-23 08:38:25,582 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/5a62ebb8-119b-4bd6-acae-7655efce39aa
2024-08-23 08:38:25,597 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-79909305', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-63442621', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1473027318715010098', 'uuid-lslong': '11735719286847163401'}, 'enable': 'true', 'created': '2024-08-23T08:38:23', 'last-modified': '2024-08-23T08:38:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.469431'}
2024-08-23 08:38:25,603 - DEBUG - Route Targets: ['target:64512:8000006']
2024-08-23 08:38:25,604 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-79909305 passed
2024-08-23 08:38:25,604 - DEBUG - ====Verifying policy data for ctest-vn-79909305 in API_Server ======
2024-08-23 08:38:25,604 - DEBUG - Requesting: http://10.0.0.12:8082/domains
2024-08-23 08:38:25,611 - DEBUG - Requesting: http://10.0.0.12:8082/domain/4f715ed0-879c-4c7f-8f7a-b55391705480
2024-08-23 08:38:25,625 - DEBUG - Requesting: http://10.0.0.12:8082/project/0976e2b9-bbd4-4413-9866-97d547230a2e
2024-08-23 08:38:25,698 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/14713e8a-bc74-4c32-a2dd-a6834c39cc09
2024-08-23 08:38:25,707 - DEBUG - ==>Verifying data for policy with id: 0102194f-7d8f-4014-bcd2-852e83f26f82, fqn: ['default-domain', 'ctest-TestBasicPolicy-63442621', 'ctest-policy-allow-all-26018350']
2024-08-23 08:38:25,707 - INFO - VN ctest-vn-79909305 Policy verification: verify_vn_policy_in_api_server, status: True
2024-08-23 08:38:25,707 - DEBUG - Verifying the vn in opserver
2024-08-23 08:38:25,707 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-79909305 virtual network link through opserver 10.0.0.12
2024-08-23 08:38:25,707 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks
2024-08-23 08:38:25,720 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-79909305', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-79909305?flat'}
2024-08-23 08:38:25,720 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-63442621:ctest-vn-79909305 is found in opserver
2024-08-23 08:38:25,732 - DEBUG - Do not have enough data to verify VN in agent
2024-08-23 08:38:25,737 - DEBUG - VRF ids for VN ctest-vn-79909305: {}
2024-08-23 08:38:25,796 - DEBUG - Services list from nova: [