2024-10-28 02:18:49,971 - INFO - Domain Default found not creating
2024-10-28 02:18:50,137 - INFO - Project ctest-TestBasicPolicy-55133740 not found, creating it
2024-10-28 02:18:50,598 - INFO - Created Project:ctest-TestBasicPolicy-55133740, ID : 67e142c3-f3de-472d-8aae-b86049a466f2
2024-10-28 02:18:52,147 - INFO - ================================================================================
2024-10-28 02:18:52,147 - INFO - STARTING TEST : test_basic_policy_allow_deny
2024-10-28 02:18:52,147 - 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-10-28 02:18:52,406 - DEBUG - Nothing to compare xmpp stats {'10.0.0.17': {'10.20.0.23': '0'}} with
2024-10-28 02:18:52,407 - INFO - Initial checks done. Running the testcase now
2024-10-28 02:18:52,407 - INFO -
2024-10-28 02:18:52,978 - DEBUG - Response for create_network : {'network': {'id': '1144c42a-62ca-4338-be52-891c514809eb', 'name': 'ctest-vn-11563777', 'tenant_id': '67e142c3f3de472d8aaeb86049a466f2', 'project_id': '67e142c3f3de472d8aaeb86049a466f2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-vn-11563777'], 'port_security_enabled': True, 'description': ''}}
2024-10-28 02:18:53,191 - DEBUG - Response for create_subnet : {'subnet': {'id': '8852b2d9-4e02-4d63-922e-00cc94d16c0b', 'name': '', 'tenant_id': '67e142c3f3de472d8aaeb86049a466f2', 'network_id': '1144c42a-62ca-4338-be52-891c514809eb', 'ip_version': 4, 'cidr': '144.214.194.192/26', 'allocation_pools': [{'start': '144.214.194.194', 'end': '144.214.194.254'}], 'gateway_ip': '144.214.194.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '144.214.194.194', 'tags': [], 'project_id': '67e142c3f3de472d8aaeb86049a466f2'}}
2024-10-28 02:18:53,213 - INFO - Created VN ctest-vn-11563777
2024-10-28 02:18:53,272 - DEBUG - VN ctest-vn-11563777 UUID is 1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:53,592 - DEBUG - Response for create_network : {'network': {'id': '160ccb58-7d51-4ef3-94da-a5bab0b395bc', 'name': 'ctest-vn-76446477', 'tenant_id': '67e142c3f3de472d8aaeb86049a466f2', 'project_id': '67e142c3f3de472d8aaeb86049a466f2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-vn-76446477'], 'port_security_enabled': True, 'description': ''}}
2024-10-28 02:18:53,774 - DEBUG - Response for create_subnet : {'subnet': {'id': '8281c37f-b306-4d08-b684-ce1997a7c919', 'name': '', 'tenant_id': '67e142c3f3de472d8aaeb86049a466f2', 'network_id': '160ccb58-7d51-4ef3-94da-a5bab0b395bc', 'ip_version': 4, 'cidr': '89.91.109.64/26', 'allocation_pools': [{'start': '89.91.109.66', 'end': '89.91.109.126'}], 'gateway_ip': '89.91.109.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '89.91.109.66', 'tags': [], 'project_id': '67e142c3f3de472d8aaeb86049a466f2'}}
2024-10-28 02:18:53,796 - INFO - Created VN ctest-vn-76446477
2024-10-28 02:18:53,856 - DEBUG - VN ctest-vn-76446477 UUID is 160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:53,975 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-11563777, 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-55133740:ctest-vn-76446477, 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-10-28 02:18:54,294 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '1144c42a-62ca-4338-be52-891c514809eb', 'name': 'ctest-vn-11563777', 'tenant_id': '67e142c3f3de472d8aaeb86049a466f2', 'project_id': '67e142c3f3de472d8aaeb86049a466f2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['8852b2d9-4e02-4d63-922e-00cc94d16c0b'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-vn-11563777'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-policy-allow-all-22981615']], 'subnet_ipam': [{'subnet_cidr': '144.214.194.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2024-10-28 02:18:54,551 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '160ccb58-7d51-4ef3-94da-a5bab0b395bc', 'name': 'ctest-vn-76446477', 'tenant_id': '67e142c3f3de472d8aaeb86049a466f2', 'project_id': '67e142c3f3de472d8aaeb86049a466f2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['8281c37f-b306-4d08-b684-ce1997a7c919'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-vn-76446477'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-policy-allow-all-22981615']], 'subnet_ipam': [{'subnet_cidr': '89.91.109.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2024-10-28 02:18:54,728 - DEBUG - Requesting: http://10.0.0.17:8082/domains
2024-10-28 02:18:55,035 - DEBUG - Requesting: http://10.0.0.17:8082/domains
2024-10-28 02:18:55,067 - DEBUG - Requesting: http://10.0.0.17:8082/domain/e92f8937-f54d-44e7-9020-3c14a03f7680
2024-10-28 02:18:55,081 - DEBUG - Requesting: http://10.0.0.17:8082/project/67e142c3-f3de-472d-8aae-b86049a466f2
2024-10-28 02:18:55,170 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:55,185 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:55,200 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/afdc625d-4d6c-4748-b4d8-d89bdd361973
2024-10-28 02:18:55,210 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/afdc625d-4d6c-4748-b4d8-d89bdd361973
2024-10-28 02:18:55,219 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/19c9cb67-a47d-47c8-b113-8a3b5b6ea3d8
2024-10-28 02:18:55,229 - DEBUG - Route Targets: ['target:64512:8000005']
2024-10-28 02:18:55,229 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:55,249 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/afdc625d-4d6c-4748-b4d8-d89bdd361973
2024-10-28 02:18:55,270 - INFO - Verified VN network id 7 for VN 1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:55,270 - INFO - Verifications in API Server for VN ctest-vn-11563777 passed
2024-10-28 02:18:55,270 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:55,284 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/afdc625d-4d6c-4748-b4d8-d89bdd361973
2024-10-28 02:18:55,293 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/afdc625d-4d6c-4748-b4d8-d89bdd361973
2024-10-28 02:18:55,305 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/19c9cb67-a47d-47c8-b113-8a3b5b6ea3d8
2024-10-28 02:18:55,322 - DEBUG - Control-node 10.0.0.17 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-11563777', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-55133740', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1244335083386192696', 'uuid-lslong': '13714174570012346859'}, 'enable': 'true', 'created': '2024-10-28T02:18:52', 'last-modified': '2024-10-28T02:18:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.047499'}
2024-10-28 02:18:55,332 - DEBUG - Route Targets: ['target:64512:8000005']
2024-10-28 02:18:55,332 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-11563777 passed
2024-10-28 02:18:55,332 - DEBUG - ====Verifying policy data for ctest-vn-11563777 in API_Server ======
2024-10-28 02:18:55,332 - DEBUG - Requesting: http://10.0.0.17:8082/domains
2024-10-28 02:18:55,341 - DEBUG - Requesting: http://10.0.0.17:8082/domain/e92f8937-f54d-44e7-9020-3c14a03f7680
2024-10-28 02:18:55,364 - DEBUG - Requesting: http://10.0.0.17:8082/project/67e142c3-f3de-472d-8aae-b86049a466f2
2024-10-28 02:18:55,452 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1144c42a-62ca-4338-be52-891c514809eb
2024-10-28 02:18:55,468 - DEBUG - ==>Verifying data for policy with id: a5d22536-90c8-4f1e-9c45-7b123c82537a, fqn: ['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-policy-allow-all-22981615']
2024-10-28 02:18:55,468 - INFO - VN ctest-vn-11563777 Policy verification: verify_vn_policy_in_api_server, status: True
2024-10-28 02:18:55,468 - DEBUG - Verifying the vn in opserver
2024-10-28 02:18:55,469 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-11563777 virtual network link through opserver 10.0.0.17
2024-10-28 02:18:55,469 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks
2024-10-28 02:18:55,775 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks
2024-10-28 02:18:55,842 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-11563777', 'href': 'http://10.0.0.17:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-11563777?flat'}
2024-10-28 02:18:55,842 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-11563777 is found in opserver
2024-10-28 02:18:55,855 - DEBUG - Do not have enough data to verify VN in agent
2024-10-28 02:18:55,861 - DEBUG - VRF ids for VN ctest-vn-11563777: {}
2024-10-28 02:18:55,861 - DEBUG - Requesting: http://10.0.0.17:8082/domains
2024-10-28 02:18:55,868 - DEBUG - Requesting: http://10.0.0.17:8082/domain/e92f8937-f54d-44e7-9020-3c14a03f7680
2024-10-28 02:18:55,881 - DEBUG - Requesting: http://10.0.0.17:8082/project/67e142c3-f3de-472d-8aae-b86049a466f2
2024-10-28 02:18:55,963 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:55,979 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:55,993 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/abdbb27c-913d-472d-98c3-d71086b899e9
2024-10-28 02:18:56,003 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/abdbb27c-913d-472d-98c3-d71086b899e9
2024-10-28 02:18:56,012 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/e667860c-726f-4968-a493-7476a1120f14
2024-10-28 02:18:56,020 - DEBUG - Route Targets: ['target:64512:8000006']
2024-10-28 02:18:56,020 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:56,037 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/abdbb27c-913d-472d-98c3-d71086b899e9
2024-10-28 02:18:56,061 - INFO - Verified VN network id 8 for VN 160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:56,061 - INFO - Verifications in API Server for VN ctest-vn-76446477 passed
2024-10-28 02:18:56,061 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:56,077 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/abdbb27c-913d-472d-98c3-d71086b899e9
2024-10-28 02:18:56,085 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/abdbb27c-913d-472d-98c3-d71086b899e9
2024-10-28 02:18:56,095 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/e667860c-726f-4968-a493-7476a1120f14
2024-10-28 02:18:56,109 - DEBUG - Control-node 10.0.0.17 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-76446477', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-55133740', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1588868349474983667', 'uuid-lslong': '10726067683783316924'}, 'enable': 'true', 'created': '2024-10-28T02:18:53', 'last-modified': '2024-10-28T02:18:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.584899'}
2024-10-28 02:18:56,115 - DEBUG - Route Targets: ['target:64512:8000006']
2024-10-28 02:18:56,115 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-76446477 passed
2024-10-28 02:18:56,115 - DEBUG - ====Verifying policy data for ctest-vn-76446477 in API_Server ======
2024-10-28 02:18:56,116 - DEBUG - Requesting: http://10.0.0.17:8082/domains
2024-10-28 02:18:56,122 - DEBUG - Requesting: http://10.0.0.17:8082/domain/e92f8937-f54d-44e7-9020-3c14a03f7680
2024-10-28 02:18:56,136 - DEBUG - Requesting: http://10.0.0.17:8082/project/67e142c3-f3de-472d-8aae-b86049a466f2
2024-10-28 02:18:56,227 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/160ccb58-7d51-4ef3-94da-a5bab0b395bc
2024-10-28 02:18:56,242 - DEBUG - ==>Verifying data for policy with id: a5d22536-90c8-4f1e-9c45-7b123c82537a, fqn: ['default-domain', 'ctest-TestBasicPolicy-55133740', 'ctest-policy-allow-all-22981615']
2024-10-28 02:18:56,242 - INFO - VN ctest-vn-76446477 Policy verification: verify_vn_policy_in_api_server, status: True
2024-10-28 02:18:56,242 - DEBUG - Verifying the vn in opserver
2024-10-28 02:18:56,243 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-76446477 virtual network link through opserver 10.0.0.17
2024-10-28 02:18:56,243 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks
2024-10-28 02:18:56,255 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-76446477', 'href': 'http://10.0.0.17:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-76446477?flat'}
2024-10-28 02:18:56,255 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-55133740:ctest-vn-76446477 is found in opserver
2024-10-28 02:18:56,267 - DEBUG - Do not have enough data to verify VN in agent
2024-10-28 02:18:56,273 - DEBUG - VRF ids for VN ctest-vn-76446477: {}
2024-10-28 02:18:56,332 - DEBUG - Services list from nova: [