2025-08-11 02:32:50,042 - INFO - Domain Default found not creating
2025-08-11 02:32:50,186 - INFO - Project ctest-TestBasicPolicyIpv4v6-95272051 not found, creating it
2025-08-11 02:32:50,756 - INFO - Created Project:ctest-TestBasicPolicyIpv4v6-95272051, ID : 1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:32:52,229 - INFO - ================================================================================
2025-08-11 02:32:52,230 - INFO - STARTING TEST : test_basic_policy_allow_deny
2025-08-11 02:32:52,230 - 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
2025-08-11 02:32:53,468 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-11 02:32:53,468 - INFO - Initial checks done. Running the testcase now
2025-08-11 02:32:53,468 - INFO -
2025-08-11 02:32:54,082 - DEBUG - Response for create_network : {'network': {'id': '9738f9c2-8282-4623-bceb-90ccb366e603', 'name': 'ctest-vn-16976946', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vn-16976946'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:32:54,292 - DEBUG - Response for create_subnet : {'subnet': {'id': '5fb2abd0-520d-4a39-a658-4e20d59927c2', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '9738f9c2-8282-4623-bceb-90ccb366e603', 'ip_version': 4, 'cidr': '73.51.175.0/26', 'allocation_pools': [{'start': '73.51.175.2', 'end': '73.51.175.62'}], 'gateway_ip': '73.51.175.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '73.51.175.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:32:54,504 - DEBUG - Response for create_subnet : {'subnet': {'id': 'af8f9ffb-0122-4254-b8c6-a59f5f2599b0', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '9738f9c2-8282-4623-bceb-90ccb366e603', 'ip_version': 6, 'cidr': '20d6:7b92:4e79:f93d:8886:adf7::/96', 'allocation_pools': [{'start': '20d6:7b92:4e79:f93d:8886:adf7:0:2', 'end': '20d6:7b92:4e79:f93d:8886:adf7:ffff:fffe'}], 'gateway_ip': '20d6:7b92:4e79:f93d:8886:adf7:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20d6:7b92:4e79:f93d:8886:adf7:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:32:54,528 - INFO - Created VN ctest-vn-16976946
2025-08-11 02:32:54,543 - DEBUG - VN ctest-vn-16976946 UUID is 9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:54,914 - DEBUG - Response for create_network : {'network': {'id': '6dcd8820-07c2-4454-864c-663ca4257b0e', 'name': 'ctest-vn-68851002', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vn-68851002'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:32:55,194 - DEBUG - Response for create_subnet : {'subnet': {'id': '50ae935d-ffad-4e4b-b580-935582ead85a', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '6dcd8820-07c2-4454-864c-663ca4257b0e', 'ip_version': 4, 'cidr': '169.23.179.64/26', 'allocation_pools': [{'start': '169.23.179.66', 'end': '169.23.179.126'}], 'gateway_ip': '169.23.179.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '169.23.179.66', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:32:55,386 - DEBUG - Response for create_subnet : {'subnet': {'id': '2c2ad66d-edb3-44da-a621-e62fb05d748f', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '6dcd8820-07c2-4454-864c-663ca4257b0e', 'ip_version': 6, 'cidr': '2262:565a:2a2b:598a:c902:fd67::/96', 'allocation_pools': [{'start': '2262:565a:2a2b:598a:c902:fd67:0:2', 'end': '2262:565a:2a2b:598a:c902:fd67:ffff:fffe'}], 'gateway_ip': '2262:565a:2a2b:598a:c902:fd67:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2262:565a:2a2b:598a:c902:fd67:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:32:55,414 - INFO - Created VN ctest-vn-68851002
2025-08-11 02:32:55,431 - DEBUG - VN ctest-vn-68851002 UUID is 6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:55,536 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946, 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-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002, 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946, 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-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002, 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]
2025-08-11 02:32:55,861 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '9738f9c2-8282-4623-bceb-90ccb366e603', 'name': 'ctest-vn-16976946', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['5fb2abd0-520d-4a39-a658-4e20d59927c2', 'af8f9ffb-0122-4254-b8c6-a59f5f2599b0'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vn-16976946'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy-allow-all-57767657']], 'subnet_ipam': [{'subnet_cidr': '73.51.175.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '20d6:7b92:4e79:f93d:8886:adf7::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:32:56,093 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '6dcd8820-07c2-4454-864c-663ca4257b0e', 'name': 'ctest-vn-68851002', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['50ae935d-ffad-4e4b-b580-935582ead85a', '2c2ad66d-edb3-44da-a621-e62fb05d748f'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vn-68851002'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy-allow-all-57767657']], 'subnet_ipam': [{'subnet_cidr': '169.23.179.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '2262:565a:2a2b:598a:c902:fd67::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:32:56,228 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:32:56,549 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:32:56,574 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:32:56,589 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:32:56,685 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:56,699 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:56,708 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/650cf714-3a66-4350-ac09-7cbc61b05474
2025-08-11 02:32:56,715 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/650cf714-3a66-4350-ac09-7cbc61b05474
2025-08-11 02:32:56,722 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/9f637c66-9c48-4a15-9a48-fa46d704ea22
2025-08-11 02:32:56,733 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:32:56,733 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:56,744 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/650cf714-3a66-4350-ac09-7cbc61b05474
2025-08-11 02:32:56,759 - INFO - Verified VN network id 12 for VN 9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:56,759 - INFO - Verifications in API Server for VN ctest-vn-16976946 passed
2025-08-11 02:32:56,759 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:56,769 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/650cf714-3a66-4350-ac09-7cbc61b05474
2025-08-11 02:32:56,782 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/650cf714-3a66-4350-ac09-7cbc61b05474
2025-08-11 02:32:56,791 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/9f637c66-9c48-4a15-9a48-fa46d704ea22
2025-08-11 02:32:56,804 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10896733912231462435', 'uuid-lslong': '13613133507515049475'}, 'enable': 'true', 'created': '2025-08-11T02:32:54', 'last-modified': '2025-08-11T02:32:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.981284'}
2025-08-11 02:32:56,811 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:32:56,817 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10896733912231462435', 'uuid-lslong': '13613133507515049475'}, 'enable': 'true', 'created': '2025-08-11T02:32:54', 'last-modified': '2025-08-11T02:32:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.995445'}
2025-08-11 02:32:56,824 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:32:56,831 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10896733912231462435', 'uuid-lslong': '13613133507515049475'}, 'enable': 'true', 'created': '2025-08-11T02:32:54', 'last-modified': '2025-08-11T02:32:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.010267'}
2025-08-11 02:32:56,840 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:32:56,840 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-16976946 passed
2025-08-11 02:32:56,841 - DEBUG - ====Verifying policy data for ctest-vn-16976946 in API_Server ======
2025-08-11 02:32:56,841 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:32:56,848 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:32:56,864 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:32:56,971 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9738f9c2-8282-4623-bceb-90ccb366e603
2025-08-11 02:32:56,981 - DEBUG - ==>Verifying data for policy with id: 330dda8f-e4f2-4493-b0f9-569c25a2557a, fqn: ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy-allow-all-57767657']
2025-08-11 02:32:56,981 - INFO - VN ctest-vn-16976946 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 02:32:56,981 - DEBUG - Verifying the vn in opserver
2025-08-11 02:32:56,981 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 virtual network link through opserver 10.0.0.23
2025-08-11 02:32:56,981 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:32:57,276 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:32:57,324 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946?flat'}
2025-08-11 02:32:57,325 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 virtual network link through opserver 10.0.0.38
2025-08-11 02:32:57,325 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:32:57,623 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:32:57,672 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946?flat'}
2025-08-11 02:32:57,672 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 virtual network link through opserver 10.0.0.141
2025-08-11 02:32:57,673 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:32:57,960 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:32:58,012 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946?flat'}
2025-08-11 02:32:58,012 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is found in opserver
2025-08-11 02:32:58,033 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:32:58,042 - DEBUG - VRF ids for VN ctest-vn-16976946: {}
2025-08-11 02:32:58,043 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:32:58,048 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:32:58,059 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:32:58,151 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:58,166 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:58,176 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d2ed1ab5-d46c-45ac-a0d4-8e8849aa0c5a
2025-08-11 02:32:58,183 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d2ed1ab5-d46c-45ac-a0d4-8e8849aa0c5a
2025-08-11 02:32:58,189 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5bbc4653-4e92-4e3f-8756-fa59e4771498
2025-08-11 02:32:58,195 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:32:58,195 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:58,204 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d2ed1ab5-d46c-45ac-a0d4-8e8849aa0c5a
2025-08-11 02:32:58,219 - INFO - Verified VN network id 13 for VN 6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:58,219 - INFO - Verifications in API Server for VN ctest-vn-68851002 passed
2025-08-11 02:32:58,219 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:58,229 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d2ed1ab5-d46c-45ac-a0d4-8e8849aa0c5a
2025-08-11 02:32:58,236 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d2ed1ab5-d46c-45ac-a0d4-8e8849aa0c5a
2025-08-11 02:32:58,243 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5bbc4653-4e92-4e3f-8756-fa59e4771498
2025-08-11 02:32:58,254 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7912129791510332500', 'uuid-lslong': '9677222109950343950'}, 'enable': 'true', 'created': '2025-08-11T02:32:54', 'last-modified': '2025-08-11T02:32:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.217430'}
2025-08-11 02:32:58,260 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:32:58,265 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7912129791510332500', 'uuid-lslong': '9677222109950343950'}, 'enable': 'true', 'created': '2025-08-11T02:32:54', 'last-modified': '2025-08-11T02:32:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.228371'}
2025-08-11 02:32:58,272 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:32:58,277 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7912129791510332500', 'uuid-lslong': '9677222109950343950'}, 'enable': 'true', 'created': '2025-08-11T02:32:54', 'last-modified': '2025-08-11T02:32:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.236901'}
2025-08-11 02:32:58,284 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:32:58,284 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-68851002 passed
2025-08-11 02:32:58,284 - DEBUG - ====Verifying policy data for ctest-vn-68851002 in API_Server ======
2025-08-11 02:32:58,284 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:32:58,289 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:32:58,299 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:32:58,385 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6dcd8820-07c2-4454-864c-663ca4257b0e
2025-08-11 02:32:58,395 - DEBUG - ==>Verifying data for policy with id: 330dda8f-e4f2-4493-b0f9-569c25a2557a, fqn: ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy-allow-all-57767657']
2025-08-11 02:32:58,395 - INFO - VN ctest-vn-68851002 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 02:32:58,396 - DEBUG - Verifying the vn in opserver
2025-08-11 02:32:58,396 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002 virtual network link through opserver 10.0.0.23
2025-08-11 02:32:58,396 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:32:58,444 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002?flat'}
2025-08-11 02:32:58,444 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002 virtual network link through opserver 10.0.0.38
2025-08-11 02:32:58,444 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:32:58,490 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002?flat'}
2025-08-11 02:32:58,490 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002 virtual network link through opserver 10.0.0.141
2025-08-11 02:32:58,490 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:32:58,539 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002?flat'}
2025-08-11 02:32:58,539 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-68851002 is found in opserver
2025-08-11 02:32:58,560 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:32:58,570 - DEBUG - VRF ids for VN ctest-vn-68851002: {}
2025-08-11 02:32:58,618 - DEBUG - Services list from nova: [, , , ]
2025-08-11 02:32:59,567 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3794-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3794-2)
2025-08-11 02:33:00,767 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3794-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3794-1)
2025-08-11 02:33:00,767 - INFO - Waiting for VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 to be up..
2025-08-11 02:33:00,857 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-11 02:33:05,930 - DEBUG - VM is in ACTIVE state now
2025-08-11 02:33:05,930 - INFO - VM name : ctest-TestBasicPolicyIpv4v6-95272051-23673438
2025-08-11 02:33:06,001 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 ID is cafed177-30ae-450c-9e83-993ef5cb75fa
2025-08-11 02:33:06,022 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 launched on Node an-jenkins-deploy-platform-ansible-os-3794-2
2025-08-11 02:33:06,098 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/cafed177-30ae-450c-9e83-993ef5cb75fa
2025-08-11 02:33:06,386 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/cafed177-30ae-450c-9e83-993ef5cb75fa
2025-08-11 02:33:06,416 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/f691dc46-551a-4fb8-a010-49b10387484d
2025-08-11 02:33:09,636 - 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 1026ms')
2025-08-11 02:33:09,637 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:09,650 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:09,651 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:13,737 - 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 1020ms')
2025-08-11 02:33:13,737 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:13,756 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:13,756 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:17,839 - 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 1021ms')
2025-08-11 02:33:17,840 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:17,854 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:17,855 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:21,933 - 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 1010ms')
2025-08-11 02:33:21,933 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:21,953 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:21,953 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:26,019 - 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 1012ms')
2025-08-11 02:33:26,019 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:26,033 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:26,033 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:30,123 - 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 1027ms')
2025-08-11 02:33:30,123 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:30,138 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:30,138 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:34,216 - 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 1020ms')
2025-08-11 02:33:34,217 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed!
2025-08-11 02:33:34,232 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 73.51.175.1 and allocation pool is NOT set
2025-08-11 02:33:34,232 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn-16976946 is 20d6:7b92:4e79:f93d:8886:adf7:0:1 and allocation pool is NOT set
2025-08-11 02:33:36,299 - 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.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.600 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.600/2.727/4.854/2.127 ms')
2025-08-11 02:33:36,299 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 passed
2025-08-11 02:33:36,413 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:33:36,413 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-95272051-23673438, IP 73.51.175.3, Port 22
2025-08-11 02:33:36,479 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:33:36,550 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:33:41,551 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:33:41,551 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-95272051-23673438, IP 73.51.175.3, Port 22
2025-08-11 02:33:41,608 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:33:41,695 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:33:46,696 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:33:46,696 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-95272051-23673438, IP 73.51.175.3, Port 22
2025-08-11 02:33:46,763 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:33:46,843 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:33:51,844 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:33:51,844 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-95272051-23673438, IP 73.51.175.3, Port 22
2025-08-11 02:33:52,000 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 is ready for SSH connections
2025-08-11 02:33:52,001 - INFO - Waiting for VM ctest-TestBasicPolicyIpv4v6-95272051-26888800 to be up..
2025-08-11 02:33:52,075 - DEBUG - VM is in ACTIVE state now
2025-08-11 02:33:52,075 - INFO - VM name : ctest-TestBasicPolicyIpv4v6-95272051-26888800
2025-08-11 02:33:52,148 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-26888800 ID is 2b2664cf-17d3-4de5-b4c6-920ddb17506b
2025-08-11 02:33:52,148 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-26888800 launched on Node an-jenkins-deploy-platform-ansible-os-3794-1
2025-08-11 02:33:52,213 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/2b2664cf-17d3-4de5-b4c6-920ddb17506b
2025-08-11 02:33:52,223 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/d11818e6-3216-461d-9db9-8e320956891c
2025-08-11 02:33:53,421 - 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=1.58 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.687 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.687/1.133/1.580/0.446 ms')
2025-08-11 02:33:53,421 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-95272051-26888800 passed
2025-08-11 02:33:53,531 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:33:53,531 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-95272051-26888800, IP 169.23.179.67, Port 22
2025-08-11 02:33:53,687 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-95272051-26888800 is ready for SSH connections
2025-08-11 02:33:53,687 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:33:53,687 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 169.23.179.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-11 02:33:53,687 - DEBUG - ping -s 56 -c 3 -W 1 169.23.179.67
2025-08-11 02:34:13,595 - DEBUG - PING 169.23.179.67 (169.23.179.67) 56(84) bytes of data.
64 bytes from 169.23.179.67: icmp_req=1 ttl=63 time=9.26 ms
64 bytes from 169.23.179.67: icmp_req=2 ttl=63 time=1.06 ms
64 bytes from 169.23.179.67: icmp_req=3 ttl=63 time=1.66 ms
--- 169.23.179.67 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.069/3.999/9.260/3.728 ms
2025-08-11 02:34:13,595 - INFO - Ping to IP 169.23.179.67 from VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 passed
2025-08-11 02:34:13,787 - DEBUG - Policy Update Response {"network-policy": {"uuid": "330dda8f-e4f2-4493-b0f9-569c25a2557a", "href": "http://10.0.0.141:8082/network-policy/330dda8f-e4f2-4493-b0f9-569c25a2557a"}}
2025-08-11 02:34:13,787 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:34:13,787 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 169.23.179.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-11 02:34:13,787 - DEBUG - ping -s 56 -c 3 -W 1 169.23.179.67
2025-08-11 02:34:17,220 - DEBUG - PING 169.23.179.67 (169.23.179.67) 56(84) bytes of data.
64 bytes from 169.23.179.67: icmp_req=1 ttl=63 time=4.18 ms
--- 169.23.179.67 ping statistics ---
3 packets transmitted, 1 received, 66% packet loss, time 2010ms
rtt min/avg/max/mdev = 4.189/4.189/4.189/0.000 ms
2025-08-11 02:34:17,220 - WARNING - Ping to IP 169.23.179.67 from VM ctest-TestBasicPolicyIpv4v6-95272051-23673438 failed
2025-08-11 02:34:17,221 - INFO - Deleting VM ctest-TestBasicPolicyIpv4v6-95272051-26888800
2025-08-11 02:34:17,287 - INFO - Deleting VM ctest-TestBasicPolicyIpv4v6-95272051-23673438
2025-08-11 02:34:17,835 - INFO - Deleted policy ctest-policy-allow-all-57767657
2025-08-11 02:34:17,835 - INFO - Deleting VN ctest-vn-68851002
2025-08-11 02:34:17,895 - DEBUG - VN 6dcd8820-07c2-4454-864c-663ca4257b0e still in use: Unable to complete operation on network 6dcd8820-07c2-4454-864c-663ca4257b0e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-819858ea-bc55-464d-b6e4-222df643465d']
2025-08-11 02:34:17,895 - WARNING - Deleting VN ctest-vn-68851002 failed..Will retry
2025-08-11 02:34:20,052 - DEBUG - Response for deleting network ()
2025-08-11 02:34:20,052 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d2ed1ab5-d46c-45ac-a0d4-8e8849aa0c5a
2025-08-11 02:34:20,063 - DEBUG - Response Code: 404
2025-08-11 02:34:20,063 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:20,083 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:20,096 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:20,191 - INFO - Validated that VN ctest-vn-68851002 is not found in API Server
2025-08-11 02:34:20,202 - DEBUG - VN ctest-vn-68851002 is not present in Agent 10.0.0.57
2025-08-11 02:34:20,212 - DEBUG - VN ctest-vn-68851002 is not present in Agent 10.0.0.65
2025-08-11 02:34:20,212 - INFO - Validated that VN ctest-vn-68851002 is not in any agent
2025-08-11 02:34:20,244 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-68851002 info
2025-08-11 02:34:20,244 - INFO - Deleting VN ctest-vn-16976946
2025-08-11 02:34:20,427 - DEBUG - Response for deleting network ()
2025-08-11 02:34:20,427 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/650cf714-3a66-4350-ac09-7cbc61b05474
2025-08-11 02:34:20,433 - DEBUG - Response Code: 404
2025-08-11 02:34:20,434 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:20,440 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:20,451 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:20,551 - INFO - Validated that VN ctest-vn-16976946 is not found in API Server
2025-08-11 02:34:20,570 - DEBUG - VN ctest-vn-16976946 is not present in Agent 10.0.0.57
2025-08-11 02:34:20,586 - DEBUG - VN ctest-vn-16976946 is not present in Agent 10.0.0.65
2025-08-11 02:34:20,586 - INFO - Validated that VN ctest-vn-16976946 is not in any agent
2025-08-11 02:34:20,619 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-16976946 info
2025-08-11 02:34:21,878 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 02:34:21,878 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:29]
2025-08-11 02:34:21,878 - INFO - --------------------------------------------------------------------------------
2025-08-11 02:34:21,881 - INFO - ================================================================================
2025-08-11 02:34:21,881 - INFO - STARTING TEST : test_policy
2025-08-11 02:34:23,146 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-11 02:34:23,146 - INFO - Initial checks done. Running the testcase now
2025-08-11 02:34:23,146 - INFO -
2025-08-11 02:34:23,146 - INFO - ================================================================================
2025-08-11 02:34:23,146 - INFO - STARTING TEST : test_policy
2025-08-11 02:34:23,146 - INFO - TEST DESCRIPTION : Configure policies based on topology and run policy related verifications.
2025-08-11 02:34:24,418 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-11 02:34:24,418 - INFO - Initial checks done. Running the testcase now
2025-08-11 02:34:24,418 - INFO -
2025-08-11 02:34:24,418 - INFO - Scenario for the test used is:
2025-08-11 02:34:24,420 - DEBUG - Starting setup
2025-08-11 02:34:24,842 - INFO - Using existing project ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051'](1bbb82c3-e73f-4655-a3a3-6fbfd8854d56)
2025-08-11 02:34:24,842 - INFO - provision user ctest-TestBasicPolicyIpv4v6-95272051 with role as admin in tenant ctest-TestBasicPolicyIpv4v6-95272051
2025-08-11 02:34:25,123 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:25,131 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:25,144 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:25,256 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-11 02:34:25,576 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-11 02:34:25,629 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:25,653 - DEBUG - Requesting: http://10.0.0.38:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:25,745 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2025-08-11 02:34:25,754 - DEBUG - Requesting: http://10.0.0.141:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:25,776 - DEBUG - Requesting: http://10.0.0.141:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:25,872 - INFO - Verification of project ctest-TestBasicPolicyIpv4v6-95272051 in API Server 10.0.0.141 passed
2025-08-11 02:34:26,151 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:26,159 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:26,174 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:26,285 - DEBUG - Requesting: http://10.0.0.23:8082/network-ipam/a8a3a393-9794-4c4c-828f-014a0ec0ec2b
2025-08-11 02:34:26,300 - INFO - Verifications in API Server for IPAM: ctest-TestBasicPolicyIpv4v6-95272051-default-ipam passed
2025-08-11 02:34:26,308 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-TestBasicPolicyIpv4v6-95272051-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12151736073874328652', 'uuid-lslong': '9407739564186987563'}, 'enable': 'true', 'created': '2025-08-11T02:34:25', 'last-modified': '2025-08-11T02:34:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.183997'}
2025-08-11 02:34:26,315 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-TestBasicPolicyIpv4v6-95272051-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12151736073874328652', 'uuid-lslong': '9407739564186987563'}, 'enable': 'true', 'created': '2025-08-11T02:34:25', 'last-modified': '2025-08-11T02:34:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.187422'}
2025-08-11 02:34:26,322 - DEBUG - Control-node 10.0.0.141 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-TestBasicPolicyIpv4v6-95272051-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12151736073874328652', 'uuid-lslong': '9407739564186987563'}, 'enable': 'true', 'created': '2025-08-11T02:34:25', 'last-modified': '2025-08-11T02:34:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.196848'}
2025-08-11 02:34:26,322 - INFO - Verifications in Control node for IPAM: ctest-TestBasicPolicyIpv4v6-95272051-default-ipam passed
2025-08-11 02:34:26,611 - DEBUG - Response for create_network : {'network': {'id': 'b52f64db-78ea-49eb-b3af-b5972de3ec0f', 'name': 'ctest-vnet0-41636008', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet0-41636008'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:26,889 - DEBUG - Response for create_subnet : {'subnet': {'id': '86838ff7-75da-4211-9064-da9f9f27b350', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': 'b52f64db-78ea-49eb-b3af-b5972de3ec0f', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:27,118 - DEBUG - Response for create_subnet : {'subnet': {'id': '29ff0697-85c2-4d71-a930-799159db02b7', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': 'b52f64db-78ea-49eb-b3af-b5972de3ec0f', 'ip_version': 4, 'cidr': '11.1.1.0/24', 'allocation_pools': [{'start': '11.1.1.2', 'end': '11.1.1.254'}], 'gateway_ip': '11.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:27,343 - DEBUG - Response for create_subnet : {'subnet': {'id': 'db3b7287-d1b6-4aaa-b13c-39cde2330136', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': 'b52f64db-78ea-49eb-b3af-b5972de3ec0f', 'ip_version': 6, 'cidr': '3db0:f474:3cf2:1655:5af7:4fa8::/96', 'allocation_pools': [{'start': '3db0:f474:3cf2:1655:5af7:4fa8:0:2', 'end': '3db0:f474:3cf2:1655:5af7:4fa8:ffff:fffe'}], 'gateway_ip': '3db0:f474:3cf2:1655:5af7:4fa8:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3db0:f474:3cf2:1655:5af7:4fa8:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:27,372 - INFO - Created VN ctest-vnet0-41636008
2025-08-11 02:34:27,391 - DEBUG - VN ctest-vnet0-41636008 UUID is b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,547 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:27,556 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:27,581 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:27,688 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,709 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,722 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/3db30999-2f2e-4eaa-8d6c-ea4d96749461
2025-08-11 02:34:27,730 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/3db30999-2f2e-4eaa-8d6c-ea4d96749461
2025-08-11 02:34:27,738 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/463e1970-2eb4-40cb-90b8-a148da539601
2025-08-11 02:34:27,744 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:27,745 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,755 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/3db30999-2f2e-4eaa-8d6c-ea4d96749461
2025-08-11 02:34:27,770 - INFO - Verified VN network id 12 for VN b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,771 - INFO - Verifications in API Server for VN ctest-vnet0-41636008 passed
2025-08-11 02:34:27,771 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,782 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/3db30999-2f2e-4eaa-8d6c-ea4d96749461
2025-08-11 02:34:27,791 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/3db30999-2f2e-4eaa-8d6c-ea4d96749461
2025-08-11 02:34:27,798 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/463e1970-2eb4-40cb-90b8-a148da539601
2025-08-11 02:34:27,812 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13055764738559592939', 'uuid-lslong': '12947767114628066319'}, 'enable': 'true', 'created': '2025-08-11T02:34:26', 'last-modified': '2025-08-11T02:34:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.551887'}
2025-08-11 02:34:27,821 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:27,828 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13055764738559592939', 'uuid-lslong': '12947767114628066319'}, 'enable': 'true', 'created': '2025-08-11T02:34:26', 'last-modified': '2025-08-11T02:34:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.573159'}
2025-08-11 02:34:27,834 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:27,842 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13055764738559592939', 'uuid-lslong': '12947767114628066319'}, 'enable': 'true', 'created': '2025-08-11T02:34:26', 'last-modified': '2025-08-11T02:34:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.587822'}
2025-08-11 02:34:27,849 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:27,849 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet0-41636008 passed
2025-08-11 02:34:27,849 - DEBUG - ====Verifying policy data for ctest-vnet0-41636008 in API_Server ======
2025-08-11 02:34:27,849 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:27,857 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:27,871 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:27,971 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:27,995 - DEBUG - =>VN ctest-vnet0-41636008 has no policy to be verified
2025-08-11 02:34:27,995 - DEBUG - Verifying the vn in opserver
2025-08-11 02:34:27,995 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008 virtual network link through opserver 10.0.0.23
2025-08-11 02:34:27,995 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:34:28,008 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008?flat'}
2025-08-11 02:34:28,008 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008 virtual network link through opserver 10.0.0.38
2025-08-11 02:34:28,008 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:34:28,021 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008?flat'}
2025-08-11 02:34:28,021 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008 virtual network link through opserver 10.0.0.141
2025-08-11 02:34:28,021 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:34:28,036 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008?flat'}
2025-08-11 02:34:28,036 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008 is found in opserver
2025-08-11 02:34:28,074 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:34:28,088 - DEBUG - VRF ids for VN ctest-vnet0-41636008: {}
2025-08-11 02:34:28,362 - DEBUG - Response for create_network : {'network': {'id': '3fe2d3bf-d9c1-421e-ac52-32dff15e1d96', 'name': 'ctest-vnet1-65998996', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet1-65998996'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:28,654 - DEBUG - Response for create_subnet : {'subnet': {'id': '9598c899-a291-4d5c-82a3-d3f395c0ac3c', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '3fe2d3bf-d9c1-421e-ac52-32dff15e1d96', 'ip_version': 4, 'cidr': '12.1.1.0/24', 'allocation_pools': [{'start': '12.1.1.2', 'end': '12.1.1.254'}], 'gateway_ip': '12.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '12.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:28,864 - DEBUG - Response for create_subnet : {'subnet': {'id': '81ac3638-855c-45e8-848c-91bbc754009b', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '3fe2d3bf-d9c1-421e-ac52-32dff15e1d96', 'ip_version': 4, 'cidr': '13.1.1.0/24', 'allocation_pools': [{'start': '13.1.1.2', 'end': '13.1.1.254'}], 'gateway_ip': '13.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:29,050 - DEBUG - Response for create_subnet : {'subnet': {'id': '1280ce91-3605-4eaa-88f2-3d612c51bdb3', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '3fe2d3bf-d9c1-421e-ac52-32dff15e1d96', 'ip_version': 6, 'cidr': '2bcb:1e3a:612d:b4db:7616:25fb::/96', 'allocation_pools': [{'start': '2bcb:1e3a:612d:b4db:7616:25fb:0:2', 'end': '2bcb:1e3a:612d:b4db:7616:25fb:ffff:fffe'}], 'gateway_ip': '2bcb:1e3a:612d:b4db:7616:25fb:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2bcb:1e3a:612d:b4db:7616:25fb:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:29,074 - INFO - Created VN ctest-vnet1-65998996
2025-08-11 02:34:29,086 - DEBUG - VN ctest-vnet1-65998996 UUID is 3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,212 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:29,219 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:29,237 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:29,328 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,348 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,360 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/11c547de-69fe-4bcc-9a85-04677c62bc05
2025-08-11 02:34:29,367 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/11c547de-69fe-4bcc-9a85-04677c62bc05
2025-08-11 02:34:29,374 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5df5fe63-a511-4d96-8c35-725cb8be61a3
2025-08-11 02:34:29,381 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:34:29,381 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,391 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/11c547de-69fe-4bcc-9a85-04677c62bc05
2025-08-11 02:34:29,407 - INFO - Verified VN network id 13 for VN 3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,407 - INFO - Verifications in API Server for VN ctest-vnet1-65998996 passed
2025-08-11 02:34:29,408 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,423 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/11c547de-69fe-4bcc-9a85-04677c62bc05
2025-08-11 02:34:29,431 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/11c547de-69fe-4bcc-9a85-04677c62bc05
2025-08-11 02:34:29,438 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5df5fe63-a511-4d96-8c35-725cb8be61a3
2025-08-11 02:34:29,451 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4603474590071603742', 'uuid-lslong': '12417043060022451606'}, 'enable': 'true', 'created': '2025-08-11T02:34:28', 'last-modified': '2025-08-11T02:34:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463534'}
2025-08-11 02:34:29,459 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:34:29,464 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4603474590071603742', 'uuid-lslong': '12417043060022451606'}, 'enable': 'true', 'created': '2025-08-11T02:34:28', 'last-modified': '2025-08-11T02:34:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483038'}
2025-08-11 02:34:29,470 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:34:29,477 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4603474590071603742', 'uuid-lslong': '12417043060022451606'}, 'enable': 'true', 'created': '2025-08-11T02:34:28', 'last-modified': '2025-08-11T02:34:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484742'}
2025-08-11 02:34:29,483 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 02:34:29,483 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet1-65998996 passed
2025-08-11 02:34:29,483 - DEBUG - ====Verifying policy data for ctest-vnet1-65998996 in API_Server ======
2025-08-11 02:34:29,483 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:29,489 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:29,500 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:29,585 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:29,599 - DEBUG - =>VN ctest-vnet1-65998996 has no policy to be verified
2025-08-11 02:34:29,599 - DEBUG - Verifying the vn in opserver
2025-08-11 02:34:29,599 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996 virtual network link through opserver 10.0.0.23
2025-08-11 02:34:29,599 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:34:29,655 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996?flat'}
2025-08-11 02:34:29,655 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996 virtual network link through opserver 10.0.0.38
2025-08-11 02:34:29,655 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:34:29,667 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996?flat'}
2025-08-11 02:34:29,667 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996 virtual network link through opserver 10.0.0.141
2025-08-11 02:34:29,668 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:34:29,681 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996?flat'}
2025-08-11 02:34:29,681 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996 is found in opserver
2025-08-11 02:34:29,705 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:34:29,717 - DEBUG - VRF ids for VN ctest-vnet1-65998996: {}
2025-08-11 02:34:29,974 - DEBUG - Response for create_network : {'network': {'id': '598cb1a0-6877-477f-8495-5fe47645103a', 'name': 'ctest-vnet2-94993794', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet2-94993794'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:30,190 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd5333951-d0c7-4e09-9e55-042246c8072a', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '598cb1a0-6877-477f-8495-5fe47645103a', 'ip_version': 4, 'cidr': '14.1.1.0/24', 'allocation_pools': [{'start': '14.1.1.2', 'end': '14.1.1.254'}], 'gateway_ip': '14.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '14.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:30,381 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cff435b9-c8cd-4659-9652-892ba0c4ecef', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '598cb1a0-6877-477f-8495-5fe47645103a', 'ip_version': 4, 'cidr': '15.1.1.0/24', 'allocation_pools': [{'start': '15.1.1.2', 'end': '15.1.1.254'}], 'gateway_ip': '15.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '15.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:30,628 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd932a4f1-19f7-455f-9547-343640d6320b', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '598cb1a0-6877-477f-8495-5fe47645103a', 'ip_version': 6, 'cidr': '2e47:cf80:ecde:b5fa:c59d:4ab7::/96', 'allocation_pools': [{'start': '2e47:cf80:ecde:b5fa:c59d:4ab7:0:2', 'end': '2e47:cf80:ecde:b5fa:c59d:4ab7:ffff:fffe'}], 'gateway_ip': '2e47:cf80:ecde:b5fa:c59d:4ab7:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2e47:cf80:ecde:b5fa:c59d:4ab7:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:30,662 - INFO - Created VN ctest-vnet2-94993794
2025-08-11 02:34:30,674 - DEBUG - VN ctest-vnet2-94993794 UUID is 598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:30,790 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:30,796 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:30,808 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:30,899 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:30,913 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:30,925 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/89e4d141-04e9-4774-84f2-043568c1f127
2025-08-11 02:34:30,934 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/89e4d141-04e9-4774-84f2-043568c1f127
2025-08-11 02:34:30,941 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/fb781fee-ed9e-4db2-871f-e0c993578d70
2025-08-11 02:34:30,948 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 02:34:30,949 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:30,959 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/89e4d141-04e9-4774-84f2-043568c1f127
2025-08-11 02:34:30,973 - INFO - Verified VN network id 14 for VN 598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:30,973 - INFO - Verifications in API Server for VN ctest-vnet2-94993794 passed
2025-08-11 02:34:30,973 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:30,983 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/89e4d141-04e9-4774-84f2-043568c1f127
2025-08-11 02:34:30,991 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/89e4d141-04e9-4774-84f2-043568c1f127
2025-08-11 02:34:30,998 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/fb781fee-ed9e-4db2-871f-e0c993578d70
2025-08-11 02:34:31,010 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6452727668620609407', 'uuid-lslong': '9553647619377795130'}, 'enable': 'true', 'created': '2025-08-11T02:34:29', 'last-modified': '2025-08-11T02:34:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436070'}
2025-08-11 02:34:31,016 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 02:34:31,022 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6452727668620609407', 'uuid-lslong': '9553647619377795130'}, 'enable': 'true', 'created': '2025-08-11T02:34:29', 'last-modified': '2025-08-11T02:34:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446582'}
2025-08-11 02:34:31,028 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 02:34:31,035 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6452727668620609407', 'uuid-lslong': '9553647619377795130'}, 'enable': 'true', 'created': '2025-08-11T02:34:29', 'last-modified': '2025-08-11T02:34:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456230'}
2025-08-11 02:34:31,041 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 02:34:31,041 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet2-94993794 passed
2025-08-11 02:34:31,041 - DEBUG - ====Verifying policy data for ctest-vnet2-94993794 in API_Server ======
2025-08-11 02:34:31,041 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:31,047 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:31,059 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:31,153 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:31,166 - DEBUG - =>VN ctest-vnet2-94993794 has no policy to be verified
2025-08-11 02:34:31,166 - DEBUG - Verifying the vn in opserver
2025-08-11 02:34:31,166 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794 virtual network link through opserver 10.0.0.23
2025-08-11 02:34:31,166 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:34:31,179 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794?flat'}
2025-08-11 02:34:31,179 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794 virtual network link through opserver 10.0.0.38
2025-08-11 02:34:31,179 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:34:31,232 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794?flat'}
2025-08-11 02:34:31,232 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794 virtual network link through opserver 10.0.0.141
2025-08-11 02:34:31,232 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:34:31,282 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794?flat'}
2025-08-11 02:34:31,282 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794 is found in opserver
2025-08-11 02:34:31,306 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:34:31,317 - DEBUG - VRF ids for VN ctest-vnet2-94993794: {}
2025-08-11 02:34:31,594 - DEBUG - Response for create_network : {'network': {'id': 'a02bc3e5-01c7-42c8-a9a7-82460b90772d', 'name': 'ctest-vnet3-00329869', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet3-00329869'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:31,828 - DEBUG - Response for create_subnet : {'subnet': {'id': '08d16474-a8d1-4dc9-ac52-ec81142e16cd', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': 'a02bc3e5-01c7-42c8-a9a7-82460b90772d', 'ip_version': 4, 'cidr': '16.1.1.0/24', 'allocation_pools': [{'start': '16.1.1.2', 'end': '16.1.1.254'}], 'gateway_ip': '16.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '16.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:32,018 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fbf985ae-bdd4-4273-b2ba-aa33dedec4fc', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': 'a02bc3e5-01c7-42c8-a9a7-82460b90772d', 'ip_version': 4, 'cidr': '17.1.1.0/24', 'allocation_pools': [{'start': '17.1.1.2', 'end': '17.1.1.254'}], 'gateway_ip': '17.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.1.1.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:32,283 - DEBUG - Response for create_subnet : {'subnet': {'id': '184ffbb7-5e5a-4209-b9de-9295fd5444d0', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': 'a02bc3e5-01c7-42c8-a9a7-82460b90772d', 'ip_version': 6, 'cidr': '22b6:2911:cfe9:c928:84ed:7e2f::/96', 'allocation_pools': [{'start': '22b6:2911:cfe9:c928:84ed:7e2f:0:2', 'end': '22b6:2911:cfe9:c928:84ed:7e2f:ffff:fffe'}], 'gateway_ip': '22b6:2911:cfe9:c928:84ed:7e2f:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22b6:2911:cfe9:c928:84ed:7e2f:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:32,307 - INFO - Created VN ctest-vnet3-00329869
2025-08-11 02:34:32,320 - DEBUG - VN ctest-vnet3-00329869 UUID is a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,435 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:32,442 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:32,454 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:32,545 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,567 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,582 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/657bf67b-792f-44ee-8a8d-16b3b873e5d5
2025-08-11 02:34:32,592 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/657bf67b-792f-44ee-8a8d-16b3b873e5d5
2025-08-11 02:34:32,601 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/a0c99e66-3a3e-4901-bf52-c72b0776fd10
2025-08-11 02:34:32,609 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 02:34:32,609 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,625 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/657bf67b-792f-44ee-8a8d-16b3b873e5d5
2025-08-11 02:34:32,644 - INFO - Verified VN network id 15 for VN a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,644 - INFO - Verifications in API Server for VN ctest-vnet3-00329869 passed
2025-08-11 02:34:32,644 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,658 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/657bf67b-792f-44ee-8a8d-16b3b873e5d5
2025-08-11 02:34:32,669 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/657bf67b-792f-44ee-8a8d-16b3b873e5d5
2025-08-11 02:34:32,677 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/a0c99e66-3a3e-4901-bf52-c72b0776fd10
2025-08-11 02:34:32,691 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11541533858411791048', 'uuid-lslong': '12224882950873839405'}, 'enable': 'true', 'created': '2025-08-11T02:34:31', 'last-modified': '2025-08-11T02:34:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.488413'}
2025-08-11 02:34:32,699 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 02:34:32,705 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11541533858411791048', 'uuid-lslong': '12224882950873839405'}, 'enable': 'true', 'created': '2025-08-11T02:34:31', 'last-modified': '2025-08-11T02:34:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.503089'}
2025-08-11 02:34:32,711 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 02:34:32,717 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11541533858411791048', 'uuid-lslong': '12224882950873839405'}, 'enable': 'true', 'created': '2025-08-11T02:34:31', 'last-modified': '2025-08-11T02:34:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.510477'}
2025-08-11 02:34:32,723 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 02:34:32,723 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet3-00329869 passed
2025-08-11 02:34:32,724 - DEBUG - ====Verifying policy data for ctest-vnet3-00329869 in API_Server ======
2025-08-11 02:34:32,724 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:32,730 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:32,742 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:32,835 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:32,846 - DEBUG - =>VN ctest-vnet3-00329869 has no policy to be verified
2025-08-11 02:34:32,846 - DEBUG - Verifying the vn in opserver
2025-08-11 02:34:32,846 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869 virtual network link through opserver 10.0.0.23
2025-08-11 02:34:32,846 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:34:32,864 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869?flat'}
2025-08-11 02:34:32,864 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869 virtual network link through opserver 10.0.0.38
2025-08-11 02:34:32,864 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:34:32,877 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869?flat'}
2025-08-11 02:34:32,877 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869 virtual network link through opserver 10.0.0.141
2025-08-11 02:34:32,877 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:34:32,890 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869?flat'}
2025-08-11 02:34:32,890 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869 is found in opserver
2025-08-11 02:34:32,914 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:34:32,927 - DEBUG - VRF ids for VN ctest-vnet3-00329869: {}
2025-08-11 02:34:32,933 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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]
2025-08-11 02:34:33,199 - DEBUG - ====Verifying data for ctest-policy0-19786107 in API_Server ======
2025-08-11 02:34:33,199 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:33,206 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:33,218 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:33,314 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/39ec857f-2f96-4c49-b846-e030414de93b
2025-08-11 02:34:33,329 - INFO - Verified policy ctest-policy0-19786107 in API Server, Result: True
2025-08-11 02:34:33,336 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:33,608 - DEBUG - ====Verifying data for ctest-policy1-86075905 in API_Server ======
2025-08-11 02:34:33,608 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:33,613 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:33,624 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:33,729 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/c9d480ac-c87f-4b01-b57b-2e7f929dd8da
2025-08-11 02:34:33,741 - INFO - Verified policy ctest-policy1-86075905 in API Server, Result: True
2025-08-11 02:34:33,748 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet0-41636008, 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]
2025-08-11 02:34:34,009 - DEBUG - ====Verifying data for ctest-policy2-32225947 in API_Server ======
2025-08-11 02:34:34,009 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:34,019 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:34,031 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:34,122 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/6b56c5f0-4e7b-44ab-875b-1edc86dae461
2025-08-11 02:34:34,139 - INFO - Verified policy ctest-policy2-32225947 in API Server, Result: True
2025-08-11 02:34:34,146 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet1-65998996, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:34,410 - DEBUG - ====Verifying data for ctest-policy3-53272584 in API_Server ======
2025-08-11 02:34:34,410 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:34,417 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:34,428 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:34,520 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/608599c2-52eb-451c-ab1b-9f80696a6be1
2025-08-11 02:34:34,536 - INFO - Verified policy ctest-policy3-53272584 in API Server, Result: True
2025-08-11 02:34:34,583 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:34,844 - DEBUG - ====Verifying data for ctest-policy4-81003355 in API_Server ======
2025-08-11 02:34:34,844 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:34,850 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:34,860 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:34,949 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/c9c92c38-51de-4e59-8ae1-6e13798e2217
2025-08-11 02:34:34,961 - INFO - Verified policy ctest-policy4-81003355 in API Server, Result: True
2025-08-11 02:34:35,011 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet2-94993794, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:35,272 - DEBUG - ====Verifying data for ctest-policy5-41295126 in API_Server ======
2025-08-11 02:34:35,272 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:35,278 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:35,288 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:35,375 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/e3cbb3be-4759-4b9d-a6bc-a09039327b34
2025-08-11 02:34:35,388 - INFO - Verified policy ctest-policy5-41295126 in API Server, Result: True
2025-08-11 02:34:35,435 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:35,708 - DEBUG - ====Verifying data for ctest-policy6-56206306 in API_Server ======
2025-08-11 02:34:35,709 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:35,715 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:35,726 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:35,817 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/f8ef3a97-1bbd-480d-9dde-5736b00c75a9
2025-08-11 02:34:35,833 - INFO - Verified policy ctest-policy6-56206306 in API Server, Result: True
2025-08-11 02:34:35,883 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 0], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, 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-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 2, end_port = 2], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 3, end_port = 3], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vnet3-00329869, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:36,152 - DEBUG - ====Verifying data for ctest-policy7-90806821 in API_Server ======
2025-08-11 02:34:36,152 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:36,158 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:36,169 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:36,255 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/46b71e8d-0457-47f6-a8fe-5915fc46d632
2025-08-11 02:34:36,266 - INFO - Verified policy ctest-policy7-90806821 in API Server, Result: True
2025-08-11 02:34:36,267 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:36,272 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:36,284 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:36,394 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:36,409 - DEBUG - Setup step: Associating the policy to VN'
2025-08-11 02:34:36,543 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b52f64db-78ea-49eb-b3af-b5972de3ec0f', 'name': 'ctest-vnet0-41636008', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['86838ff7-75da-4211-9064-da9f9f27b350', '29ff0697-85c2-4d71-a930-799159db02b7', 'db3b7287-d1b6-4aaa-b13c-39cde2330136'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet0-41636008'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy0-19786107'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy1-86075905']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '3db0:f474:3cf2:1655:5af7:4fa8::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:36,734 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy0-19786107'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy1-86075905']] to ctest-vnet0-41636008
2025-08-11 02:34:36,734 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:36,739 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:36,751 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:36,853 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:36,865 - DEBUG - Setup step: Associating the policy to VN'
2025-08-11 02:34:37,000 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '3fe2d3bf-d9c1-421e-ac52-32dff15e1d96', 'name': 'ctest-vnet1-65998996', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['9598c899-a291-4d5c-82a3-d3f395c0ac3c', '81ac3638-855c-45e8-848c-91bbc754009b', '1280ce91-3605-4eaa-88f2-3d612c51bdb3'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet1-65998996'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy2-32225947'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy3-53272584']], 'subnet_ipam': [{'subnet_cidr': '12.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '13.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '2bcb:1e3a:612d:b4db:7616:25fb::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:37,214 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy2-32225947'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy3-53272584']] to ctest-vnet1-65998996
2025-08-11 02:34:37,214 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:37,221 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:37,231 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:37,322 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:37,333 - DEBUG - Setup step: Associating the policy to VN'
2025-08-11 02:34:37,465 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '598cb1a0-6877-477f-8495-5fe47645103a', 'name': 'ctest-vnet2-94993794', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['d5333951-d0c7-4e09-9e55-042246c8072a', 'cff435b9-c8cd-4659-9652-892ba0c4ecef', 'd932a4f1-19f7-455f-9547-343640d6320b'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet2-94993794'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy4-81003355'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy5-41295126']], 'subnet_ipam': [{'subnet_cidr': '14.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '15.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '2e47:cf80:ecde:b5fa:c59d:4ab7::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:37,654 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy4-81003355'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy5-41295126']] to ctest-vnet2-94993794
2025-08-11 02:34:37,654 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:37,660 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:37,671 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:37,756 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:37,768 - DEBUG - Setup step: Associating the policy to VN'
2025-08-11 02:34:37,883 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'a02bc3e5-01c7-42c8-a9a7-82460b90772d', 'name': 'ctest-vnet3-00329869', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['08d16474-a8d1-4dc9-ac52-ec81142e16cd', 'fbf985ae-bdd4-4273-b2ba-aa33dedec4fc', '184ffbb7-5e5a-4209-b9de-9295fd5444d0'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vnet3-00329869'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy6-56206306'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy7-90806821']], 'subnet_ipam': [{'subnet_cidr': '16.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '17.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}, {'subnet_cidr': '22b6:2911:cfe9:c928:84ed:7e2f::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-TestBasicPolicyIpv4v6-95272051-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:38,054 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy6-56206306'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy7-90806821']] to ctest-vnet3-00329869
2025-08-11 02:34:38,054 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3794-2', 'an-jenkins-deploy-platform-ansible-os-3794-1']}
2025-08-11 02:34:38,143 - DEBUG - Services list from nova: [, , , ]
2025-08-11 02:34:38,164 - DEBUG - Image by name ubuntu-traffic-py3 not found
2025-08-11 02:34:38,164 - DEBUG - Installing image ubuntu-traffic-py3
2025-08-11 02:34:38,165 - DEBUG - Image info {'name': 'ubuntu-traffic-py3.qcow2.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'ubuntu', 'password': 'ubuntu', 'type': 'os', 'flavor': 'contrail_flavor_small', 'params': '--container-format ovf --disk-format qcow2 --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-11 02:34:38,165 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic-py3.qcow2.gz
2025-08-11 02:34:38,165 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic-py3.qcow2.gz
2025-08-11 02:34:38,178 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic-py3.qcow2.gz -O /contrail-test/images/ubuntu-traffic-py3.qcow2.gz
2025-08-11 02:34:38,215 - DEBUG - Image has been downloaded to /contrail-test/images/ubuntu-traffic-py3.qcow2.gz
2025-08-11 02:34:38,215 - DEBUG - Unzip image
2025-08-11 02:34:38,215 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-traffic-py3.qcow2.gz
2025-08-11 02:34:38,226 - INFO - image /contrail-test/images/ubuntu-traffic-py3.qcow2 is not found
2025-08-11 02:34:38,226 - DEBUG - Detaching the Policy for VN :ctest-vnet3-00329869
2025-08-11 02:34:38,227 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:38,234 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:38,246 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:38,336 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/a02bc3e5-01c7-42c8-a9a7-82460b90772d
2025-08-11 02:34:38,543 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy6-56206306'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy7-90806821']] from ctest-vnet3-00329869
2025-08-11 02:34:38,543 - DEBUG - Detaching the Policy for VN :ctest-vnet2-94993794
2025-08-11 02:34:38,543 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:38,548 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:38,559 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:38,638 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/598cb1a0-6877-477f-8495-5fe47645103a
2025-08-11 02:34:38,845 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy4-81003355'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy5-41295126']] from ctest-vnet2-94993794
2025-08-11 02:34:38,845 - DEBUG - Detaching the Policy for VN :ctest-vnet1-65998996
2025-08-11 02:34:38,845 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:38,853 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:38,869 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:38,958 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/3fe2d3bf-d9c1-421e-ac52-32dff15e1d96
2025-08-11 02:34:39,135 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy2-32225947'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy3-53272584']] from ctest-vnet1-65998996
2025-08-11 02:34:39,135 - DEBUG - Detaching the Policy for VN :ctest-vnet0-41636008
2025-08-11 02:34:39,135 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:39,140 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:39,151 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:39,245 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b52f64db-78ea-49eb-b3af-b5972de3ec0f
2025-08-11 02:34:39,518 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy0-19786107'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy1-86075905']] from ctest-vnet0-41636008
2025-08-11 02:34:39,555 - INFO - Deleted policy ctest-policy7-90806821
2025-08-11 02:34:39,717 - DEBUG - policy ctest-policy7-90806821 is not found in API Server
2025-08-11 02:34:39,788 - INFO - Deleted policy ctest-policy6-56206306
2025-08-11 02:34:39,983 - DEBUG - policy ctest-policy6-56206306 is not found in API Server
2025-08-11 02:34:40,025 - INFO - Deleted policy ctest-policy5-41295126
2025-08-11 02:34:40,239 - DEBUG - policy ctest-policy5-41295126 is not found in API Server
2025-08-11 02:34:40,290 - INFO - Deleted policy ctest-policy4-81003355
2025-08-11 02:34:40,487 - DEBUG - policy ctest-policy4-81003355 is not found in API Server
2025-08-11 02:34:40,555 - INFO - Deleted policy ctest-policy3-53272584
2025-08-11 02:34:40,771 - DEBUG - policy ctest-policy3-53272584 is not found in API Server
2025-08-11 02:34:40,827 - INFO - Deleted policy ctest-policy2-32225947
2025-08-11 02:34:41,015 - DEBUG - policy ctest-policy2-32225947 is not found in API Server
2025-08-11 02:34:41,074 - INFO - Deleted policy ctest-policy1-86075905
2025-08-11 02:34:41,279 - DEBUG - policy ctest-policy1-86075905 is not found in API Server
2025-08-11 02:34:41,341 - INFO - Deleted policy ctest-policy0-19786107
2025-08-11 02:34:41,547 - DEBUG - policy ctest-policy0-19786107 is not found in API Server
2025-08-11 02:34:41,547 - INFO - Deleting VN ctest-vnet3-00329869
2025-08-11 02:34:41,843 - DEBUG - Response for deleting network ()
2025-08-11 02:34:41,843 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/657bf67b-792f-44ee-8a8d-16b3b873e5d5
2025-08-11 02:34:41,849 - DEBUG - Response Code: 404
2025-08-11 02:34:41,849 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:41,854 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:41,866 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:41,972 - INFO - Validated that VN ctest-vnet3-00329869 is not found in API Server
2025-08-11 02:34:41,992 - DEBUG - VN ctest-vnet3-00329869 is not present in Agent 10.0.0.57
2025-08-11 02:34:42,009 - DEBUG - VN ctest-vnet3-00329869 is not present in Agent 10.0.0.65
2025-08-11 02:34:42,009 - INFO - Validated that VN ctest-vnet3-00329869 is not in any agent
2025-08-11 02:34:42,050 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet3-00329869 info
2025-08-11 02:34:42,050 - INFO - Deleting VN ctest-vnet2-94993794
2025-08-11 02:34:42,350 - DEBUG - Response for deleting network ()
2025-08-11 02:34:42,350 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/89e4d141-04e9-4774-84f2-043568c1f127
2025-08-11 02:34:42,362 - DEBUG - Response Code: 404
2025-08-11 02:34:42,362 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:42,376 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:42,387 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:42,492 - INFO - Validated that VN ctest-vnet2-94993794 is not found in API Server
2025-08-11 02:34:42,507 - DEBUG - VN ctest-vnet2-94993794 is not present in Agent 10.0.0.57
2025-08-11 02:34:42,518 - DEBUG - VN ctest-vnet2-94993794 is not present in Agent 10.0.0.65
2025-08-11 02:34:42,518 - INFO - Validated that VN ctest-vnet2-94993794 is not in any agent
2025-08-11 02:34:42,551 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet2-94993794 info
2025-08-11 02:34:42,551 - INFO - Deleting VN ctest-vnet1-65998996
2025-08-11 02:34:42,794 - DEBUG - Response for deleting network ()
2025-08-11 02:34:42,795 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/11c547de-69fe-4bcc-9a85-04677c62bc05
2025-08-11 02:34:42,809 - DEBUG - Response Code: 404
2025-08-11 02:34:42,809 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:42,816 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:42,872 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:42,978 - INFO - Validated that VN ctest-vnet1-65998996 is not found in API Server
2025-08-11 02:34:42,990 - DEBUG - VN ctest-vnet1-65998996 is not present in Agent 10.0.0.57
2025-08-11 02:34:43,002 - DEBUG - VN ctest-vnet1-65998996 is not present in Agent 10.0.0.65
2025-08-11 02:34:43,002 - INFO - Validated that VN ctest-vnet1-65998996 is not in any agent
2025-08-11 02:34:43,037 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet1-65998996 info
2025-08-11 02:34:43,037 - INFO - Deleting VN ctest-vnet0-41636008
2025-08-11 02:34:43,274 - DEBUG - Response for deleting network ()
2025-08-11 02:34:43,274 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/3db30999-2f2e-4eaa-8d6c-ea4d96749461
2025-08-11 02:34:43,281 - DEBUG - Response Code: 404
2025-08-11 02:34:43,281 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:43,287 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:43,298 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:43,404 - INFO - Validated that VN ctest-vnet0-41636008 is not found in API Server
2025-08-11 02:34:43,416 - DEBUG - VN ctest-vnet0-41636008 is not present in Agent 10.0.0.57
2025-08-11 02:34:43,431 - DEBUG - VN ctest-vnet0-41636008 is not present in Agent 10.0.0.65
2025-08-11 02:34:43,431 - INFO - Validated that VN ctest-vnet0-41636008 is not in any agent
2025-08-11 02:34:43,469 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet0-41636008 info
2025-08-11 02:34:43,571 - INFO - IPAM: ctest-TestBasicPolicyIpv4v6-95272051-default-ipam is not found in API Server
2025-08-11 02:34:43,592 - INFO - IPAM:ctest-TestBasicPolicyIpv4v6-95272051-default-ipam is not found in control node
2025-08-11 02:34:44,051 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051']
2025-08-11 02:34:44,051 - DEBUG - Skipping the deletion of User ctest-TestBasicPolicyIpv4v6-95272051
2025-08-11 02:34:45,305 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 02:34:45,305 - INFO -
2025-08-11 02:34:45,305 - INFO - END TEST : test_policy : SKIPPED[0:00:22]
2025-08-11 02:34:45,305 - INFO - --------------------------------------------------------------------------------
2025-08-11 02:34:45,305 - INFO - image /contrail-test/images/ubuntu-traffic-py3.qcow2 is not found
2025-08-11 02:34:46,561 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 02:34:46,561 - INFO -
2025-08-11 02:34:46,561 - INFO - END TEST : test_policy : SKIPPED[0:00:25]
2025-08-11 02:34:46,562 - INFO - --------------------------------------------------------------------------------
2025-08-11 02:34:46,565 - INFO - ================================================================================
2025-08-11 02:34:46,565 - INFO - STARTING TEST : test_policy_to_deny
2025-08-11 02:34:47,822 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-11 02:34:47,822 - INFO - Initial checks done. Running the testcase now
2025-08-11 02:34:47,822 - INFO -
2025-08-11 02:34:47,822 - INFO - ================================================================================
2025-08-11 02:34:47,822 - INFO - STARTING TEST : test_policy_to_deny
2025-08-11 02:34:47,822 - INFO - TEST DESCRIPTION : Test to validate that with policy having rule to disable icmp within the VN, ping between VMs should fail
1. Pick 2 VN from resource pool which have one VM in each
2. Create policy with icmp deny rule
3. Associate policy to both VN
4. Ping from one VM to another. Ping should fail
Pass criteria: Step 2,3 and 4 should pass
2025-08-11 02:34:49,081 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-11 02:34:49,081 - INFO - Initial checks done. Running the testcase now
2025-08-11 02:34:49,081 - INFO -
2025-08-11 02:34:49,088 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622, 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-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622, 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-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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]
2025-08-11 02:34:49,940 - DEBUG - Response for create_network : {'network': {'id': '2f9bb14c-67bf-485a-ad90-4a119b4aca41', 'name': 'ctest-vn1-31469622', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vn1-31469622'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:50,149 - DEBUG - Response for create_subnet : {'subnet': {'id': '8f2c323f-c476-45ae-9b2f-49c8fb7702c6', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '2f9bb14c-67bf-485a-ad90-4a119b4aca41', 'ip_version': 4, 'cidr': '192.168.10.0/24', 'allocation_pools': [{'start': '192.168.10.2', 'end': '192.168.10.254'}], 'gateway_ip': '192.168.10.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '192.168.10.2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:50,330 - DEBUG - Response for create_subnet : {'subnet': {'id': '24d6e30b-ba89-4743-99b7-965560177f97', 'name': '', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'network_id': '2f9bb14c-67bf-485a-ad90-4a119b4aca41', 'ip_version': 6, 'cidr': '3257:643c:6244:d6d5:3485:9e85::/96', 'allocation_pools': [{'start': '3257:643c:6244:d6d5:3485:9e85:0:2', 'end': '3257:643c:6244:d6d5:3485:9e85:ffff:fffe'}], 'gateway_ip': '3257:643c:6244:d6d5:3485:9e85:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3257:643c:6244:d6d5:3485:9e85:0:2', 'tags': [], 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56'}}
2025-08-11 02:34:50,374 - INFO - Created VN ctest-vn1-31469622
2025-08-11 02:34:50,399 - DEBUG - VN ctest-vn1-31469622 UUID is 2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:50,603 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '2f9bb14c-67bf-485a-ad90-4a119b4aca41', 'name': 'ctest-vn1-31469622', 'tenant_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'project_id': '1bbb82c3e73f4655a3a36fbfd8854d56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['8f2c323f-c476-45ae-9b2f-49c8fb7702c6', '24d6e30b-ba89-4743-99b7-965560177f97'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-vn1-31469622'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy1-13181751']], 'subnet_ipam': [{'subnet_cidr': '192.168.10.0/24', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '3257:643c:6244:d6d5:3485:9e85::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 02:34:50,697 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:50,703 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:50,719 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:50,814 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:50,833 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:50,846 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/33516db0-0ebd-41b8-b3ac-67d94ccf8ed7
2025-08-11 02:34:50,857 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/33516db0-0ebd-41b8-b3ac-67d94ccf8ed7
2025-08-11 02:34:50,866 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/0368e961-06f4-4eac-9b36-253d041b85cd
2025-08-11 02:34:50,874 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:50,875 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:50,887 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/33516db0-0ebd-41b8-b3ac-67d94ccf8ed7
2025-08-11 02:34:50,902 - INFO - Verified VN network id 12 for VN 2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:50,902 - INFO - Verifications in API Server for VN ctest-vn1-31469622 passed
2025-08-11 02:34:50,902 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:50,912 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/33516db0-0ebd-41b8-b3ac-67d94ccf8ed7
2025-08-11 02:34:50,919 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/33516db0-0ebd-41b8-b3ac-67d94ccf8ed7
2025-08-11 02:34:50,925 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/0368e961-06f4-4eac-9b36-253d041b85cd
2025-08-11 02:34:50,948 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3430530482888984666', 'uuid-lslong': '12506577604688136769'}, 'enable': 'true', 'created': '2025-08-11T02:34:49', 'last-modified': '2025-08-11T02:34:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389478'}
2025-08-11 02:34:50,955 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:50,961 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3430530482888984666', 'uuid-lslong': '12506577604688136769'}, 'enable': 'true', 'created': '2025-08-11T02:34:49', 'last-modified': '2025-08-11T02:34:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397982'}
2025-08-11 02:34:50,967 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:50,972 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-95272051', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3430530482888984666', 'uuid-lslong': '12506577604688136769'}, 'enable': 'true', 'created': '2025-08-11T02:34:49', 'last-modified': '2025-08-11T02:34:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414154'}
2025-08-11 02:34:50,978 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 02:34:50,978 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-31469622 passed
2025-08-11 02:34:50,978 - DEBUG - ====Verifying policy data for ctest-vn1-31469622 in API_Server ======
2025-08-11 02:34:50,978 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:34:50,984 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:34:50,996 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:34:51,081 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/2f9bb14c-67bf-485a-ad90-4a119b4aca41
2025-08-11 02:34:51,092 - DEBUG - ==>Verifying data for policy with id: f8112b84-3b45-4a97-bb4c-04caa050623e, fqn: ['default-domain', 'ctest-TestBasicPolicyIpv4v6-95272051', 'ctest-policy1-13181751']
2025-08-11 02:34:51,092 - INFO - VN ctest-vn1-31469622 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 02:34:51,092 - DEBUG - Verifying the vn in opserver
2025-08-11 02:34:51,092 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 virtual network link through opserver 10.0.0.23
2025-08-11 02:34:51,092 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-08-11 02:34:51,104 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622?flat'}
2025-08-11 02:34:51,104 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 virtual network link through opserver 10.0.0.38
2025-08-11 02:34:51,104 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 02:34:51,116 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622?flat'}
2025-08-11 02:34:51,116 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 virtual network link through opserver 10.0.0.141
2025-08-11 02:34:51,116 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2025-08-11 02:34:51,131 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622?flat'}
2025-08-11 02:34:51,131 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is found in opserver
2025-08-11 02:34:51,152 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 02:34:51,162 - DEBUG - VRF ids for VN ctest-vn1-31469622: {}
2025-08-11 02:34:52,343 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3794-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3794-2)
2025-08-11 02:34:53,676 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3794-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3794-1)
2025-08-11 02:34:53,676 - INFO - Waiting for VM ctest-vn1_vm1-42267160 to be up..
2025-08-11 02:34:53,756 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-11 02:34:58,834 - DEBUG - VM is in ACTIVE state now
2025-08-11 02:34:58,834 - INFO - VM name : ctest-vn1_vm1-42267160
2025-08-11 02:34:58,909 - DEBUG - VM ctest-vn1_vm1-42267160 ID is c70d5f74-4e41-411b-9b07-fa9794abe006
2025-08-11 02:34:58,909 - DEBUG - VM ctest-vn1_vm1-42267160 launched on Node an-jenkins-deploy-platform-ansible-os-3794-2
2025-08-11 02:34:58,981 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/c70d5f74-4e41-411b-9b07-fa9794abe006
2025-08-11 02:34:58,990 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/300aadfd-045f-4711-bd9e-3f8c1aaec597
2025-08-11 02:35:02,215 - 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 1032ms')
2025-08-11 02:35:02,215 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:02,232 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:02,232 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:06,313 - 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 1016ms')
2025-08-11 02:35:06,313 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:06,327 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:06,327 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:10,416 - 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 1024ms')
2025-08-11 02:35:10,416 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:10,432 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:10,432 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:14,502 - 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 1016ms')
2025-08-11 02:35:14,502 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:14,521 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:14,521 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:18,601 - 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 1020ms')
2025-08-11 02:35:18,601 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:18,617 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:18,617 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:22,698 - 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 1020ms')
2025-08-11 02:35:22,698 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:22,711 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:22,711 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:26,788 - 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 1019ms')
2025-08-11 02:35:26,788 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 failed!
2025-08-11 02:35:26,802 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 192.168.10.1 and allocation pool is NOT set
2025-08-11 02:35:26,802 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-95272051:ctest-vn1-31469622 is 3257:643c:6244:d6d5:3485:9e85:0:1 and allocation pool is NOT set
2025-08-11 02:35:28,866 - 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=6.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.558 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.558/3.351/6.145/2.793 ms')
2025-08-11 02:35:28,866 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-42267160 passed
2025-08-11 02:35:28,979 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:35:28,979 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-42267160, IP 192.168.10.3, Port 22
2025-08-11 02:35:29,047 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:35:29,129 - DEBUG - VM ctest-vn1_vm1-42267160 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:35:34,129 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:35:34,129 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-42267160, IP 192.168.10.3, Port 22
2025-08-11 02:35:34,185 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:35:34,258 - DEBUG - VM ctest-vn1_vm1-42267160 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:35:39,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:35:39,260 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-42267160, IP 192.168.10.3, Port 22
2025-08-11 02:35:39,316 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:35:39,397 - DEBUG - VM ctest-vn1_vm1-42267160 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:35:44,398 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:35:44,398 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-42267160, IP 192.168.10.3, Port 22
2025-08-11 02:35:44,453 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 02:35:44,521 - DEBUG - VM ctest-vn1_vm1-42267160 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 02:35:49,522 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:35:49,522 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-42267160, IP 192.168.10.3, Port 22
2025-08-11 02:35:49,683 - DEBUG - VM ctest-vn1_vm1-42267160 is ready for SSH connections
2025-08-11 02:35:49,683 - INFO - Waiting for VM ctest-vn1_vm2-91486120 to be up..
2025-08-11 02:35:49,753 - DEBUG - VM is in ACTIVE state now
2025-08-11 02:35:49,753 - INFO - VM name : ctest-vn1_vm2-91486120
2025-08-11 02:35:49,816 - DEBUG - VM ctest-vn1_vm2-91486120 ID is 39d51bbf-d35f-4798-b9d3-38a50b2c73f2
2025-08-11 02:35:49,817 - DEBUG - VM ctest-vn1_vm2-91486120 launched on Node an-jenkins-deploy-platform-ansible-os-3794-1
2025-08-11 02:35:49,884 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/39d51bbf-d35f-4798-b9d3-38a50b2c73f2
2025-08-11 02:35:49,893 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/7e563938-e119-4857-a71d-04d9dd2064a8
2025-08-11 02:35:51,094 - 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=2.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.695 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.695/1.490/2.285/0.795 ms')
2025-08-11 02:35:51,094 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm2-91486120 passed
2025-08-11 02:35:51,209 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 02:35:51,209 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-91486120, IP 192.168.10.4, Port 22
2025-08-11 02:35:51,370 - DEBUG - VM ctest-vn1_vm2-91486120 is ready for SSH connections
2025-08-11 02:35:51,370 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 192.168.10.4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-11 02:35:51,370 - DEBUG - ping -s 56 -c 3 -W 1 192.168.10.4
2025-08-11 02:36:08,283 - DEBUG - PING 192.168.10.4 (192.168.10.4) 56(84) bytes of data.
--- 192.168.10.4 ping statistics ---
3 packets transmitted, 0 received, 100% packet loss, time 2002ms
2025-08-11 02:36:08,283 - WARNING - Ping to IP 192.168.10.4 from VM ctest-vn1_vm1-42267160 failed
2025-08-11 02:36:08,284 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3257:643c:6244:d6d5:3485:9e85:0:4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-11 02:36:08,284 - DEBUG - ping6 -s 56 -c 3 -W 1 3257:643c:6244:d6d5:3485:9e85:0:4
2025-08-11 02:36:11,757 - DEBUG - PING 3257:643c:6244:d6d5:3485:9e85:0:4(3257:643c:6244:d6d5:3485:9e85:0:4) 56 data bytes
--- 3257:643c:6244:d6d5:3485:9e85:0:4 ping statistics ---
3 packets transmitted, 0 received, 100% packet loss, time 2003ms
2025-08-11 02:36:11,757 - WARNING - Ping to IP 3257:643c:6244:d6d5:3485:9e85:0:4 from VM ctest-vn1_vm1-42267160 failed
2025-08-11 02:36:11,757 - INFO - Deleting VM ctest-vn1_vm2-91486120
2025-08-11 02:36:11,819 - INFO - Deleting VM ctest-vn1_vm1-42267160
2025-08-11 02:36:12,049 - INFO - Deleting VN ctest-vn1-31469622
2025-08-11 02:36:12,092 - DEBUG - VN 2f9bb14c-67bf-485a-ad90-4a119b4aca41 still in use: Unable to complete operation on network 2f9bb14c-67bf-485a-ad90-4a119b4aca41. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-335bb041-8c06-4750-8373-9db4e40cf2f5']
2025-08-11 02:36:12,092 - WARNING - Deleting VN ctest-vn1-31469622 failed..Will retry
2025-08-11 02:36:14,363 - DEBUG - Response for deleting network ()
2025-08-11 02:36:14,363 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/33516db0-0ebd-41b8-b3ac-67d94ccf8ed7
2025-08-11 02:36:14,368 - DEBUG - Response Code: 404
2025-08-11 02:36:14,369 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2025-08-11 02:36:14,373 - DEBUG - Requesting: http://10.0.0.23:8082/domain/e652ea40-e608-445e-8759-8973ddbd3f22
2025-08-11 02:36:14,429 - DEBUG - Requesting: http://10.0.0.23:8082/project/1bbb82c3-e73f-4655-a3a3-6fbfd8854d56
2025-08-11 02:36:14,547 - INFO - Validated that VN ctest-vn1-31469622 is not found in API Server
2025-08-11 02:36:14,558 - DEBUG - VN ctest-vn1-31469622 is not present in Agent 10.0.0.57
2025-08-11 02:36:14,568 - DEBUG - VN ctest-vn1-31469622 is not present in Agent 10.0.0.65
2025-08-11 02:36:14,568 - INFO - Validated that VN ctest-vn1-31469622 is not in any agent
2025-08-11 02:36:14,604 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-31469622 info
2025-08-11 02:36:14,642 - INFO - Deleted policy ctest-policy1-13181751
2025-08-11 02:36:15,903 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 02:36:15,904 - INFO - END TEST : test_policy_to_deny : PASSED[0:01:28]
2025-08-11 02:36:15,904 - INFO - --------------------------------------------------------------------------------
2025-08-11 02:36:17,160 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 02:36:17,160 - INFO - END TEST : test_policy_to_deny : PASSED[0:01:31]
2025-08-11 02:36:17,160 - INFO - --------------------------------------------------------------------------------
2025-08-11 02:36:17,830 - INFO - Deleted project: ctest-TestBasicPolicyIpv4v6-95272051, ID : 1bbb82c3-e73f-4655-a3a3-6fbfd8854d56