2025-07-24 03:28:05,053 - INFO - Domain Default found not creating 2025-07-24 03:28:05,231 - INFO - Project ctest-TestBasicPolicyIpv4v6-46126473 not found, creating it 2025-07-24 03:28:05,813 - INFO - Created Project:ctest-TestBasicPolicyIpv4v6-46126473, ID : fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:28:07,417 - INFO - ================================================================================ 2025-07-24 03:28:07,417 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-07-24 03:28:07,417 - 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-07-24 03:28:08,734 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with 2025-07-24 03:28:08,734 - INFO - Initial checks done. Running the testcase now 2025-07-24 03:28:08,734 - INFO - 2025-07-24 03:28:09,472 - DEBUG - Response for create_network : {'network': {'id': '038ecba5-ee6f-4b43-8116-1cd70342168e', 'name': 'ctest-vn-69084441', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vn-69084441'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:28:09,769 - DEBUG - Response for create_subnet : {'subnet': {'id': '3e084bce-5bb8-4dca-9e9b-70b81794674a', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '038ecba5-ee6f-4b43-8116-1cd70342168e', 'ip_version': 4, 'cidr': '215.184.137.64/26', 'allocation_pools': [{'start': '215.184.137.66', 'end': '215.184.137.126'}], 'gateway_ip': '215.184.137.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '215.184.137.66', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:28:10,067 - DEBUG - Response for create_subnet : {'subnet': {'id': '498ec3eb-a286-4d71-b1d8-d9caddfd833a', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '038ecba5-ee6f-4b43-8116-1cd70342168e', 'ip_version': 6, 'cidr': '3b1b:78ea:4a23:1da0:b6fe:5a50::/96', 'allocation_pools': [{'start': '3b1b:78ea:4a23:1da0:b6fe:5a50:0:2', 'end': '3b1b:78ea:4a23:1da0:b6fe:5a50:ffff:fffe'}], 'gateway_ip': '3b1b:78ea:4a23:1da0:b6fe:5a50:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3b1b:78ea:4a23:1da0:b6fe:5a50:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:28:10,109 - INFO - Created VN ctest-vn-69084441 2025-07-24 03:28:10,124 - DEBUG - VN ctest-vn-69084441 UUID is 038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:10,576 - DEBUG - Response for create_network : {'network': {'id': '67616fb0-ab2b-42a3-b85b-64fbb9bd957e', 'name': 'ctest-vn-95080860', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vn-95080860'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:28:10,779 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f328eff1-8038-4c50-9b74-841228d4dc47', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '67616fb0-ab2b-42a3-b85b-64fbb9bd957e', 'ip_version': 4, 'cidr': '152.69.246.128/26', 'allocation_pools': [{'start': '152.69.246.130', 'end': '152.69.246.190'}], 'gateway_ip': '152.69.246.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '152.69.246.130', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:28:11,016 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e326dd1a-cbae-4e66-9886-3b674bdee6d6', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '67616fb0-ab2b-42a3-b85b-64fbb9bd957e', 'ip_version': 6, 'cidr': '2020:1cb9:16b0:c8b:ab7a:7874::/96', 'allocation_pools': [{'start': '2020:1cb9:16b0:c8b:ab7a:7874:0:2', 'end': '2020:1cb9:16b0:c8b:ab7a:7874:ffff:fffe'}], 'gateway_ip': '2020:1cb9:16b0:c8b:ab7a:7874:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2020:1cb9:16b0:c8b:ab7a:7874:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:28:11,047 - INFO - Created VN ctest-vn-95080860 2025-07-24 03:28:11,060 - DEBUG - VN ctest-vn-95080860 UUID is 67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:11,210 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441, 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-46126473:ctest-vn-95080860, 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-46126473:ctest-vn-69084441, 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-46126473:ctest-vn-95080860, 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-07-24 03:28:11,636 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '038ecba5-ee6f-4b43-8116-1cd70342168e', 'name': 'ctest-vn-69084441', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3e084bce-5bb8-4dca-9e9b-70b81794674a', '498ec3eb-a286-4d71-b1d8-d9caddfd833a'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vn-69084441'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy-allow-all-38201509']], 'subnet_ipam': [{'subnet_cidr': '215.184.137.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '3b1b:78ea:4a23:1da0:b6fe:5a50::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:28:11,895 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '67616fb0-ab2b-42a3-b85b-64fbb9bd957e', 'name': 'ctest-vn-95080860', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['f328eff1-8038-4c50-9b74-841228d4dc47', 'e326dd1a-cbae-4e66-9886-3b674bdee6d6'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vn-95080860'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy-allow-all-38201509']], 'subnet_ipam': [{'subnet_cidr': '152.69.246.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '2020:1cb9:16b0:c8b:ab7a:7874::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:28:12,033 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:28:12,382 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:28:12,420 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:28:12,439 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:28:12,545 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:12,563 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:12,576 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/643a125f-81bb-4fbe-9524-9a381189f819 2025-07-24 03:28:12,589 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/643a125f-81bb-4fbe-9524-9a381189f819 2025-07-24 03:28:12,599 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/c6d39bf9-784b-4a82-9afa-e107b5186921 2025-07-24 03:28:12,607 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:28:12,608 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:12,621 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/643a125f-81bb-4fbe-9524-9a381189f819 2025-07-24 03:28:12,640 - INFO - Verified VN network id 11 for VN 038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:12,640 - INFO - Verifications in API Server for VN ctest-vn-69084441 passed 2025-07-24 03:28:12,640 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:12,652 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/643a125f-81bb-4fbe-9524-9a381189f819 2025-07-24 03:28:12,661 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/643a125f-81bb-4fbe-9524-9a381189f819 2025-07-24 03:28:12,670 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/c6d39bf9-784b-4a82-9afa-e107b5186921 2025-07-24 03:28:12,689 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '256366142337010499', 'uuid-lslong': '9301653790178547342'}, 'enable': 'true', 'created': '2025-07-24T03:28:09', 'last-modified': '2025-07-24T03:28:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.102284'} 2025-07-24 03:28:12,696 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:28:12,704 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '256366142337010499', 'uuid-lslong': '9301653790178547342'}, 'enable': 'true', 'created': '2025-07-24T03:28:09', 'last-modified': '2025-07-24T03:28:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.131075'} 2025-07-24 03:28:12,711 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:28:12,720 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '256366142337010499', 'uuid-lslong': '9301653790178547342'}, 'enable': 'true', 'created': '2025-07-24T03:28:09', 'last-modified': '2025-07-24T03:28:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.145196'} 2025-07-24 03:28:12,728 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:28:12,728 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-69084441 passed 2025-07-24 03:28:12,729 - DEBUG - ====Verifying policy data for ctest-vn-69084441 in API_Server ====== 2025-07-24 03:28:12,729 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:28:12,738 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:28:12,752 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:28:12,876 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/038ecba5-ee6f-4b43-8116-1cd70342168e 2025-07-24 03:28:12,892 - DEBUG - ==>Verifying data for policy with id: 138fa8f8-323e-4467-9d7c-5fd31ce645d8, fqn: ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy-allow-all-38201509'] 2025-07-24 03:28:12,892 - INFO - VN ctest-vn-69084441 Policy verification: verify_vn_policy_in_api_server, status: True 2025-07-24 03:28:12,892 - DEBUG - Verifying the vn in opserver 2025-07-24 03:28:12,892 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 virtual network link through opserver 10.0.0.19 2025-07-24 03:28:12,892 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:28:13,234 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:28:13,303 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441?flat'} 2025-07-24 03:28:13,303 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 virtual network link through opserver 10.0.0.38 2025-07-24 03:28:13,303 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:28:13,602 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:28:13,665 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441?flat'} 2025-07-24 03:28:13,665 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 virtual network link through opserver 10.0.0.41 2025-07-24 03:28:13,666 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:28:13,977 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:28:14,048 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441?flat'} 2025-07-24 03:28:14,048 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is found in opserver 2025-07-24 03:28:14,075 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:28:14,089 - DEBUG - VRF ids for VN ctest-vn-69084441: {} 2025-07-24 03:28:14,089 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:28:14,096 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:28:14,109 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:28:14,209 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:14,231 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:14,243 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/72e8dcbe-1a5d-4fc5-b01e-4614e96234a2 2025-07-24 03:28:14,251 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/72e8dcbe-1a5d-4fc5-b01e-4614e96234a2 2025-07-24 03:28:14,261 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/ea6591a5-b696-48eb-aeeb-2dd320cf5e96 2025-07-24 03:28:14,272 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:28:14,272 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:14,283 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/72e8dcbe-1a5d-4fc5-b01e-4614e96234a2 2025-07-24 03:28:14,308 - INFO - Verified VN network id 12 for VN 67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:14,309 - INFO - Verifications in API Server for VN ctest-vn-95080860 passed 2025-07-24 03:28:14,309 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:14,322 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/72e8dcbe-1a5d-4fc5-b01e-4614e96234a2 2025-07-24 03:28:14,334 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/72e8dcbe-1a5d-4fc5-b01e-4614e96234a2 2025-07-24 03:28:14,343 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/ea6591a5-b696-48eb-aeeb-2dd320cf5e96 2025-07-24 03:28:14,359 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7449358063224177315', 'uuid-lslong': '13284322558175188350'}, 'enable': 'true', 'created': '2025-07-24T03:28:10', 'last-modified': '2025-07-24T03:28:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.516728'} 2025-07-24 03:28:14,366 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:28:14,372 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7449358063224177315', 'uuid-lslong': '13284322558175188350'}, 'enable': 'true', 'created': '2025-07-24T03:28:10', 'last-modified': '2025-07-24T03:28:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.540995'} 2025-07-24 03:28:14,381 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:28:14,390 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7449358063224177315', 'uuid-lslong': '13284322558175188350'}, 'enable': 'true', 'created': '2025-07-24T03:28:10', 'last-modified': '2025-07-24T03:28:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.556534'} 2025-07-24 03:28:14,408 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:28:14,408 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-95080860 passed 2025-07-24 03:28:14,408 - DEBUG - ====Verifying policy data for ctest-vn-95080860 in API_Server ====== 2025-07-24 03:28:14,409 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:28:14,420 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:28:14,435 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:28:14,542 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/67616fb0-ab2b-42a3-b85b-64fbb9bd957e 2025-07-24 03:28:14,554 - DEBUG - ==>Verifying data for policy with id: 138fa8f8-323e-4467-9d7c-5fd31ce645d8, fqn: ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy-allow-all-38201509'] 2025-07-24 03:28:14,554 - INFO - VN ctest-vn-95080860 Policy verification: verify_vn_policy_in_api_server, status: True 2025-07-24 03:28:14,554 - DEBUG - Verifying the vn in opserver 2025-07-24 03:28:14,554 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860 virtual network link through opserver 10.0.0.19 2025-07-24 03:28:14,554 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:28:14,618 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860?flat'} 2025-07-24 03:28:14,618 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860 virtual network link through opserver 10.0.0.38 2025-07-24 03:28:14,618 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:28:14,677 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860?flat'} 2025-07-24 03:28:14,677 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860 virtual network link through opserver 10.0.0.41 2025-07-24 03:28:14,677 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:28:14,747 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860?flat'} 2025-07-24 03:28:14,747 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-95080860 is found in opserver 2025-07-24 03:28:14,792 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:28:14,807 - DEBUG - VRF ids for VN ctest-vn-95080860: {} 2025-07-24 03:28:14,873 - DEBUG - Services list from nova: [, , , ] 2025-07-24 03:28:16,047 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1) 2025-07-24 03:28:17,518 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2) 2025-07-24 03:28:17,518 - INFO - Waiting for VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 to be up.. 2025-07-24 03:28:17,637 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-24 03:28:22,729 - DEBUG - VM is in ACTIVE state now 2025-07-24 03:28:22,729 - INFO - VM name : ctest-TestBasicPolicyIpv4v6-46126473-08673847 2025-07-24 03:28:22,828 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 ID is 3ed3575f-9178-4ddd-9505-6b8eefb5424c 2025-07-24 03:28:22,855 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 launched on Node an-jenkins-deploy-platform-ansible-os-3712-1 2025-07-24 03:28:22,948 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/3ed3575f-9178-4ddd-9505-6b8eefb5424c 2025-07-24 03:28:23,250 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/3ed3575f-9178-4ddd-9505-6b8eefb5424c 2025-07-24 03:28:23,288 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/2ce0351d-7135-4cc9-af94-1dfce04851bf 2025-07-24 03:28:26,561 - 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 1034ms') 2025-07-24 03:28:26,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:26,580 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:26,581 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:30,664 - 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 1017ms') 2025-07-24 03:28:30,665 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:30,684 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:30,684 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:34,760 - 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 1013ms') 2025-07-24 03:28:34,760 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:34,778 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:34,778 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:38,851 - 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 1007ms') 2025-07-24 03:28:38,852 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:38,870 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:38,870 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:42,949 - 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-07-24 03:28:42,949 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:42,968 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:42,970 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:47,045 - 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 1013ms') 2025-07-24 03:28:47,046 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:47,062 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:47,063 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:51,142 - 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 1011ms') 2025-07-24 03:28:51,142 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:51,160 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:51,160 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:55,237 - 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 1013ms') 2025-07-24 03:28:55,237 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:55,254 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:55,254 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:28:59,331 - 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-07-24 03:28:59,331 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:28:59,351 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:28:59,351 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:29:03,430 - 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 1014ms') 2025-07-24 03:29:03,430 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed! 2025-07-24 03:29:03,454 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 215.184.137.65 and allocation pool is NOT set 2025-07-24 03:29:03,454 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn-69084441 is 3b1b:78ea:4a23:1da0:b6fe:5a50:0:1 and allocation pool is NOT set 2025-07-24 03:29:05,522 - 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.34 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.24 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 = 1.243/3.790/6.338/2.547 ms') 2025-07-24 03:29:05,522 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 passed 2025-07-24 03:29:05,646 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:05,647 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-08673847, IP 215.184.137.67, Port 22 2025-07-24 03:29:05,723 - 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-07-24 03:29:05,818 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:29:10,819 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:10,819 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-08673847, IP 215.184.137.67, Port 22 2025-07-24 03:29:10,886 - 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-07-24 03:29:10,989 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:29:15,990 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:15,991 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-08673847, IP 215.184.137.67, Port 22 2025-07-24 03:29:16,057 - 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-07-24 03:29:16,149 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:29:21,150 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:21,150 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-08673847, IP 215.184.137.67, Port 22 2025-07-24 03:29:21,217 - 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-07-24 03:29:21,309 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:29:26,310 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:26,310 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-08673847, IP 215.184.137.67, Port 22 2025-07-24 03:29:26,378 - 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-07-24 03:29:26,465 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:29:31,466 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:31,466 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-08673847, IP 215.184.137.67, Port 22 2025-07-24 03:29:31,638 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 is ready for SSH connections 2025-07-24 03:29:31,638 - INFO - Waiting for VM ctest-TestBasicPolicyIpv4v6-46126473-90623690 to be up.. 2025-07-24 03:29:31,747 - DEBUG - VM is in ACTIVE state now 2025-07-24 03:29:31,747 - INFO - VM name : ctest-TestBasicPolicyIpv4v6-46126473-90623690 2025-07-24 03:29:31,834 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-90623690 ID is 57a48055-19ac-45c9-a4cf-25a267b92e6e 2025-07-24 03:29:31,834 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-90623690 launched on Node an-jenkins-deploy-platform-ansible-os-3712-2 2025-07-24 03:29:31,928 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/57a48055-19ac-45c9-a4cf-25a267b92e6e 2025-07-24 03:29:31,943 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/838329e1-cef2-4dff-8c90-259d9371910d 2025-07-24 03:29:33,171 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.25 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.76 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 = 2.762/3.003/3.245/0.241 ms') 2025-07-24 03:29:33,171 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicyIpv4v6-46126473-90623690 passed 2025-07-24 03:29:33,295 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:33,295 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicyIpv4v6-46126473-90623690, IP 152.69.246.131, Port 22 2025-07-24 03:29:33,468 - DEBUG - VM ctest-TestBasicPolicyIpv4v6-46126473-90623690 is ready for SSH connections 2025-07-24 03:29:33,468 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:33,469 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 152.69.246.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123 2025-07-24 03:29:33,469 - DEBUG - ping -s 56 -c 3 -W 1 152.69.246.131 2025-07-24 03:29:56,778 - DEBUG - PING 152.69.246.131 (152.69.246.131) 56(84) bytes of data. 64 bytes from 152.69.246.131: icmp_req=1 ttl=63 time=10.3 ms 64 bytes from 152.69.246.131: icmp_req=2 ttl=63 time=1.59 ms 64 bytes from 152.69.246.131: icmp_req=3 ttl=63 time=1.35 ms --- 152.69.246.131 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.358/4.418/10.302/4.161 ms 2025-07-24 03:29:56,778 - INFO - Ping to IP 152.69.246.131 from VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 passed 2025-07-24 03:29:57,012 - DEBUG - Policy Update Response {"network-policy": {"uuid": "138fa8f8-323e-4467-9d7c-5fd31ce645d8", "href": "http://10.0.0.41:8082/network-policy/138fa8f8-323e-4467-9d7c-5fd31ce645d8"}} 2025-07-24 03:29:57,012 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:29:57,012 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 152.69.246.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123 2025-07-24 03:29:57,012 - DEBUG - ping -s 56 -c 3 -W 1 152.69.246.131 2025-07-24 03:30:00,575 - DEBUG - PING 152.69.246.131 (152.69.246.131) 56(84) bytes of data. 64 bytes from 152.69.246.131: icmp_req=1 ttl=63 time=5.47 ms --- 152.69.246.131 ping statistics --- 3 packets transmitted, 1 received, 66% packet loss, time 2001ms rtt min/avg/max/mdev = 5.476/5.476/5.476/0.000 ms 2025-07-24 03:30:00,575 - WARNING - Ping to IP 152.69.246.131 from VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 failed 2025-07-24 03:30:00,575 - INFO - Deleting VM ctest-TestBasicPolicyIpv4v6-46126473-90623690 2025-07-24 03:30:00,691 - INFO - Deleting VM ctest-TestBasicPolicyIpv4v6-46126473-08673847 2025-07-24 03:30:01,654 - INFO - Deleted policy ctest-policy-allow-all-38201509 2025-07-24 03:30:01,654 - INFO - Deleting VN ctest-vn-95080860 2025-07-24 03:30:01,727 - DEBUG - VN 67616fb0-ab2b-42a3-b85b-64fbb9bd957e still in use: Unable to complete operation on network 67616fb0-ab2b-42a3-b85b-64fbb9bd957e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-60c6a54e-f1e7-4469-8789-0ceb8022f846'] 2025-07-24 03:30:01,727 - WARNING - Deleting VN ctest-vn-95080860 failed..Will retry 2025-07-24 03:30:04,003 - DEBUG - Response for deleting network () 2025-07-24 03:30:04,003 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/72e8dcbe-1a5d-4fc5-b01e-4614e96234a2 2025-07-24 03:30:04,013 - DEBUG - Response Code: 404 2025-07-24 03:30:04,013 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:04,037 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:04,060 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:04,168 - INFO - Validated that VN ctest-vn-95080860 is not found in API Server 2025-07-24 03:30:04,191 - DEBUG - VN ctest-vn-95080860 is not present in Agent 10.0.0.57 2025-07-24 03:30:04,208 - DEBUG - VN ctest-vn-95080860 is not present in Agent 10.0.0.23 2025-07-24 03:30:04,208 - INFO - Validated that VN ctest-vn-95080860 is not in any agent 2025-07-24 03:30:04,255 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-95080860 info 2025-07-24 03:30:04,255 - INFO - Deleting VN ctest-vn-69084441 2025-07-24 03:30:04,465 - DEBUG - Response for deleting network () 2025-07-24 03:30:04,465 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/643a125f-81bb-4fbe-9524-9a381189f819 2025-07-24 03:30:04,478 - DEBUG - Response Code: 404 2025-07-24 03:30:04,478 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:04,487 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:04,507 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:04,614 - INFO - Validated that VN ctest-vn-69084441 is not found in API Server 2025-07-24 03:30:04,633 - DEBUG - VN ctest-vn-69084441 is not present in Agent 10.0.0.57 2025-07-24 03:30:04,648 - DEBUG - VN ctest-vn-69084441 is not present in Agent 10.0.0.23 2025-07-24 03:30:04,649 - INFO - Validated that VN ctest-vn-69084441 is not in any agent 2025-07-24 03:30:04,693 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-69084441 info 2025-07-24 03:30:05,992 - DEBUG - No XMPP flaps were noticed during the test

2025-07-24 03:30:05,993 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:58]

2025-07-24 03:30:05,993 - INFO - -------------------------------------------------------------------------------- 2025-07-24 03:30:05,996 - INFO - ================================================================================ 2025-07-24 03:30:05,996 - INFO - STARTING TEST : test_policy 2025-07-24 03:30:07,310 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with 2025-07-24 03:30:07,311 - INFO - Initial checks done. Running the testcase now 2025-07-24 03:30:07,311 - INFO - 2025-07-24 03:30:07,311 - INFO - ================================================================================ 2025-07-24 03:30:07,311 - INFO - STARTING TEST : test_policy 2025-07-24 03:30:07,311 - INFO - TEST DESCRIPTION : Configure policies based on topology and run policy related verifications. 2025-07-24 03:30:08,625 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with 2025-07-24 03:30:08,625 - INFO - Initial checks done. Running the testcase now 2025-07-24 03:30:08,626 - INFO - 2025-07-24 03:30:08,626 - INFO - Scenario for the test used is: 2025-07-24 03:30:08,626 - DEBUG - Starting setup 2025-07-24 03:30:09,071 - INFO - Using existing project ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473'](fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa) 2025-07-24 03:30:09,071 - INFO - provision user ctest-TestBasicPolicyIpv4v6-46126473 with role as admin in tenant ctest-TestBasicPolicyIpv4v6-46126473 2025-07-24 03:30:09,372 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:09,379 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:09,393 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:09,494 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-07-24 03:30:09,828 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-07-24 03:30:09,892 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:09,907 - DEBUG - Requesting: http://10.0.0.38:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:10,007 - DEBUG - Requesting: http://10.0.0.41:8082/domains 2025-07-24 03:30:10,020 - DEBUG - Requesting: http://10.0.0.41:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:10,040 - DEBUG - Requesting: http://10.0.0.41:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:10,138 - INFO - Verification of project ctest-TestBasicPolicyIpv4v6-46126473 in API Server 10.0.0.41 passed 2025-07-24 03:30:10,472 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:10,479 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:10,495 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:10,612 - DEBUG - Requesting: http://10.0.0.19:8082/network-ipam/fa2a5c89-b1f4-41a5-bb37-ed6f405c1151 2025-07-24 03:30:10,623 - INFO - Verifications in API Server for IPAM: ctest-TestBasicPolicyIpv4v6-46126473-default-ipam passed 2025-07-24 03:30:10,630 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-TestBasicPolicyIpv4v6-46126473-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18026322204969681317', 'uuid-lslong': '13490512270888538449'}, 'enable': 'true', 'created': '2025-07-24T03:30:10', 'last-modified': '2025-07-24T03:30:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.192038'} 2025-07-24 03:30:10,637 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-TestBasicPolicyIpv4v6-46126473-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18026322204969681317', 'uuid-lslong': '13490512270888538449'}, 'enable': 'true', 'created': '2025-07-24T03:30:10', 'last-modified': '2025-07-24T03:30:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.202577'} 2025-07-24 03:30:10,645 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-TestBasicPolicyIpv4v6-46126473-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18026322204969681317', 'uuid-lslong': '13490512270888538449'}, 'enable': 'true', 'created': '2025-07-24T03:30:10', 'last-modified': '2025-07-24T03:30:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.202585'} 2025-07-24 03:30:10,645 - INFO - Verifications in Control node for IPAM: ctest-TestBasicPolicyIpv4v6-46126473-default-ipam passed 2025-07-24 03:30:10,943 - DEBUG - Response for create_network : {'network': {'id': '3be02cad-654f-418e-9b65-19a5bb393e0c', 'name': 'ctest-vnet0-58456050', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet0-58456050'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:11,221 - DEBUG - Response for create_subnet : {'subnet': {'id': '533fae16-f5c6-43d0-a5ed-a4a866b7824c', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '3be02cad-654f-418e-9b65-19a5bb393e0c', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:11,447 - DEBUG - Response for create_subnet : {'subnet': {'id': '29c02a7d-d219-44dd-8bac-0f10586cbcf4', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '3be02cad-654f-418e-9b65-19a5bb393e0c', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:11,696 - DEBUG - Response for create_subnet : {'subnet': {'id': '4c24f124-fee0-4fe4-9b3d-dfdcc6723302', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '3be02cad-654f-418e-9b65-19a5bb393e0c', 'ip_version': 6, 'cidr': '379a:8410:a167:d470:9bc8:ffeb::/96', 'allocation_pools': [{'start': '379a:8410:a167:d470:9bc8:ffeb:0:2', 'end': '379a:8410:a167:d470:9bc8:ffeb:ffff:fffe'}], 'gateway_ip': '379a:8410:a167:d470:9bc8:ffeb:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '379a:8410:a167:d470:9bc8:ffeb:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:11,728 - INFO - Created VN ctest-vnet0-58456050 2025-07-24 03:30:11,743 - DEBUG - VN ctest-vnet0-58456050 UUID is 3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:11,893 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:11,901 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:11,920 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:12,024 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:12,050 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:12,066 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/055b363d-b78b-4ebd-a31a-f25d43f05955 2025-07-24 03:30:12,080 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/055b363d-b78b-4ebd-a31a-f25d43f05955 2025-07-24 03:30:12,092 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/4df65df3-07cf-4084-861a-753ef185cd82 2025-07-24 03:30:12,104 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:12,104 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:12,117 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/055b363d-b78b-4ebd-a31a-f25d43f05955 2025-07-24 03:30:12,139 - INFO - Verified VN network id 11 for VN 3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:12,139 - INFO - Verifications in API Server for VN ctest-vnet0-58456050 passed 2025-07-24 03:30:12,139 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:12,153 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/055b363d-b78b-4ebd-a31a-f25d43f05955 2025-07-24 03:30:12,164 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/055b363d-b78b-4ebd-a31a-f25d43f05955 2025-07-24 03:30:12,175 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/4df65df3-07cf-4084-861a-753ef185cd82 2025-07-24 03:30:12,192 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4314497566261592462', 'uuid-lslong': '11197384248127995404'}, 'enable': 'true', 'created': '2025-07-24T03:30:10', 'last-modified': '2025-07-24T03:30:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.555185'} 2025-07-24 03:30:12,198 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:12,208 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4314497566261592462', 'uuid-lslong': '11197384248127995404'}, 'enable': 'true', 'created': '2025-07-24T03:30:10', 'last-modified': '2025-07-24T03:30:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.572991'} 2025-07-24 03:30:12,216 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:12,224 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4314497566261592462', 'uuid-lslong': '11197384248127995404'}, 'enable': 'true', 'created': '2025-07-24T03:30:10', 'last-modified': '2025-07-24T03:30:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.583204'} 2025-07-24 03:30:12,232 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:12,232 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet0-58456050 passed 2025-07-24 03:30:12,233 - DEBUG - ====Verifying policy data for ctest-vnet0-58456050 in API_Server ====== 2025-07-24 03:30:12,233 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:12,242 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:12,257 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:12,366 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:12,384 - DEBUG - =>VN ctest-vnet0-58456050 has no policy to be verified 2025-07-24 03:30:12,384 - DEBUG - Verifying the vn in opserver 2025-07-24 03:30:12,384 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050 virtual network link through opserver 10.0.0.19 2025-07-24 03:30:12,384 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:30:12,402 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050?flat'} 2025-07-24 03:30:12,402 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050 virtual network link through opserver 10.0.0.38 2025-07-24 03:30:12,402 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:30:12,416 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050?flat'} 2025-07-24 03:30:12,416 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050 virtual network link through opserver 10.0.0.41 2025-07-24 03:30:12,416 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:30:12,433 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050?flat'} 2025-07-24 03:30:12,433 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050 is found in opserver 2025-07-24 03:30:12,463 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:30:12,477 - DEBUG - VRF ids for VN ctest-vnet0-58456050: {} 2025-07-24 03:30:12,783 - DEBUG - Response for create_network : {'network': {'id': 'dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f', 'name': 'ctest-vnet1-45484017', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet1-45484017'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:12,993 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bf487ed6-811e-49fd-b639-3e29a1f3d61f', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': 'dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:13,232 - DEBUG - Response for create_subnet : {'subnet': {'id': '61c5fb14-6a44-4cde-af27-5b4704501fca', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': 'dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:13,480 - DEBUG - Response for create_subnet : {'subnet': {'id': '251e718f-786d-4910-b720-b0636e0f831e', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': 'dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f', 'ip_version': 6, 'cidr': '3702:1013:cfa7:3a56:4700:5dbc::/96', 'allocation_pools': [{'start': '3702:1013:cfa7:3a56:4700:5dbc:0:2', 'end': '3702:1013:cfa7:3a56:4700:5dbc:ffff:fffe'}], 'gateway_ip': '3702:1013:cfa7:3a56:4700:5dbc:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3702:1013:cfa7:3a56:4700:5dbc:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:13,512 - INFO - Created VN ctest-vnet1-45484017 2025-07-24 03:30:13,531 - DEBUG - VN ctest-vnet1-45484017 UUID is dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:13,686 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:13,693 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:13,705 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:13,809 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:13,827 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:13,841 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/d47f9d05-86be-417c-a8af-c16b0b3f283c 2025-07-24 03:30:13,850 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/d47f9d05-86be-417c-a8af-c16b0b3f283c 2025-07-24 03:30:13,859 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/23b907b4-3c77-423b-8ce1-e38086b2ca8e 2025-07-24 03:30:13,867 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:30:13,868 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:13,881 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/d47f9d05-86be-417c-a8af-c16b0b3f283c 2025-07-24 03:30:13,900 - INFO - Verified VN network id 12 for VN dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:13,900 - INFO - Verifications in API Server for VN ctest-vnet1-45484017 passed 2025-07-24 03:30:13,900 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:13,914 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/d47f9d05-86be-417c-a8af-c16b0b3f283c 2025-07-24 03:30:13,931 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/d47f9d05-86be-417c-a8af-c16b0b3f283c 2025-07-24 03:30:13,951 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/23b907b4-3c77-423b-8ce1-e38086b2ca8e 2025-07-24 03:30:13,969 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15896568584546829422', 'uuid-lslong': '10119318774008519967'}, 'enable': 'true', 'created': '2025-07-24T03:30:12', 'last-modified': '2025-07-24T03:30:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.569157'} 2025-07-24 03:30:13,976 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:30:13,983 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15896568584546829422', 'uuid-lslong': '10119318774008519967'}, 'enable': 'true', 'created': '2025-07-24T03:30:12', 'last-modified': '2025-07-24T03:30:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.575604'} 2025-07-24 03:30:13,991 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:30:14,001 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15896568584546829422', 'uuid-lslong': '10119318774008519967'}, 'enable': 'true', 'created': '2025-07-24T03:30:12', 'last-modified': '2025-07-24T03:30:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.601158'} 2025-07-24 03:30:14,010 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-24 03:30:14,011 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet1-45484017 passed 2025-07-24 03:30:14,011 - DEBUG - ====Verifying policy data for ctest-vnet1-45484017 in API_Server ====== 2025-07-24 03:30:14,011 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:14,020 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:14,036 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:14,140 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:14,155 - DEBUG - =>VN ctest-vnet1-45484017 has no policy to be verified 2025-07-24 03:30:14,155 - DEBUG - Verifying the vn in opserver 2025-07-24 03:30:14,155 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017 virtual network link through opserver 10.0.0.19 2025-07-24 03:30:14,155 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:30:14,215 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017?flat'} 2025-07-24 03:30:14,215 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017 virtual network link through opserver 10.0.0.38 2025-07-24 03:30:14,215 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:30:14,229 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017?flat'} 2025-07-24 03:30:14,229 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017 virtual network link through opserver 10.0.0.41 2025-07-24 03:30:14,229 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:30:14,246 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017?flat'} 2025-07-24 03:30:14,246 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017 is found in opserver 2025-07-24 03:30:14,272 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:30:14,284 - DEBUG - VRF ids for VN ctest-vnet1-45484017: {} 2025-07-24 03:30:14,592 - DEBUG - Response for create_network : {'network': {'id': '5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2', 'name': 'ctest-vnet2-67889923', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet2-67889923'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:14,812 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd8db705c-e26d-4f51-ab06-8e443ea236cb', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:15,043 - DEBUG - Response for create_subnet : {'subnet': {'id': '9538994c-6ea2-49cc-9a29-e9be222b1c56', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:15,318 - DEBUG - Response for create_subnet : {'subnet': {'id': '69384b84-b816-4281-9c55-f591dcd47af9', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2', 'ip_version': 6, 'cidr': '3a56:60da:81a2:5d71:f9ac:eb9e::/96', 'allocation_pools': [{'start': '3a56:60da:81a2:5d71:f9ac:eb9e:0:2', 'end': '3a56:60da:81a2:5d71:f9ac:eb9e:ffff:fffe'}], 'gateway_ip': '3a56:60da:81a2:5d71:f9ac:eb9e:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3a56:60da:81a2:5d71:f9ac:eb9e:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:15,356 - INFO - Created VN ctest-vnet2-67889923 2025-07-24 03:30:15,377 - DEBUG - VN ctest-vnet2-67889923 UUID is 5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:15,537 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:15,545 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:15,559 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:15,666 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:15,685 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:15,699 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/499bb522-ce06-4e91-b670-bfb784612d7e 2025-07-24 03:30:15,708 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/499bb522-ce06-4e91-b670-bfb784612d7e 2025-07-24 03:30:15,717 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/12944bc8-35c4-4e74-895f-21234a6193ab 2025-07-24 03:30:15,728 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-07-24 03:30:15,728 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:15,742 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/499bb522-ce06-4e91-b670-bfb784612d7e 2025-07-24 03:30:15,763 - INFO - Verified VN network id 13 for VN 5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:15,763 - INFO - Verifications in API Server for VN ctest-vnet2-67889923 passed 2025-07-24 03:30:15,763 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:15,778 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/499bb522-ce06-4e91-b670-bfb784612d7e 2025-07-24 03:30:15,787 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/499bb522-ce06-4e91-b670-bfb784612d7e 2025-07-24 03:30:15,796 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/12944bc8-35c4-4e74-895f-21234a6193ab 2025-07-24 03:30:15,811 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6838417639742914606', 'uuid-lslong': '9468462193099663538'}, 'enable': 'true', 'created': '2025-07-24T03:30:14', 'last-modified': '2025-07-24T03:30:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.596106'} 2025-07-24 03:30:15,819 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-07-24 03:30:15,826 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6838417639742914606', 'uuid-lslong': '9468462193099663538'}, 'enable': 'true', 'created': '2025-07-24T03:30:14', 'last-modified': '2025-07-24T03:30:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.611879'} 2025-07-24 03:30:15,834 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-07-24 03:30:15,843 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6838417639742914606', 'uuid-lslong': '9468462193099663538'}, 'enable': 'true', 'created': '2025-07-24T03:30:14', 'last-modified': '2025-07-24T03:30:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.634715'} 2025-07-24 03:30:15,851 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-07-24 03:30:15,852 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet2-67889923 passed 2025-07-24 03:30:15,852 - DEBUG - ====Verifying policy data for ctest-vnet2-67889923 in API_Server ====== 2025-07-24 03:30:15,852 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:15,860 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:15,876 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:15,994 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:16,009 - DEBUG - =>VN ctest-vnet2-67889923 has no policy to be verified 2025-07-24 03:30:16,009 - DEBUG - Verifying the vn in opserver 2025-07-24 03:30:16,009 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923 virtual network link through opserver 10.0.0.19 2025-07-24 03:30:16,009 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:30:16,026 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923?flat'} 2025-07-24 03:30:16,026 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923 virtual network link through opserver 10.0.0.38 2025-07-24 03:30:16,026 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:30:16,095 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923?flat'} 2025-07-24 03:30:16,095 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923 virtual network link through opserver 10.0.0.41 2025-07-24 03:30:16,095 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:30:16,166 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923?flat'} 2025-07-24 03:30:16,166 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923 is found in opserver 2025-07-24 03:30:16,194 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:30:16,207 - DEBUG - VRF ids for VN ctest-vnet2-67889923: {} 2025-07-24 03:30:16,551 - DEBUG - Response for create_network : {'network': {'id': '3792f51c-0b3a-4432-b764-90011ef82a84', 'name': 'ctest-vnet3-24318904', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet3-24318904'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:16,822 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c2a8b846-4d5b-41aa-a21d-d624d2f43ae5', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '3792f51c-0b3a-4432-b764-90011ef82a84', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:17,066 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f521cc8d-51cf-4f4d-aa5f-617e9f506aa7', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '3792f51c-0b3a-4432-b764-90011ef82a84', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:17,322 - DEBUG - Response for create_subnet : {'subnet': {'id': '90d62f78-0771-4dc1-878a-93f2570dc24d', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '3792f51c-0b3a-4432-b764-90011ef82a84', 'ip_version': 6, 'cidr': '3561:e885:a1ba:7f44:deaf:fc3::/96', 'allocation_pools': [{'start': '3561:e885:a1ba:7f44:deaf:fc3:0:2', 'end': '3561:e885:a1ba:7f44:deaf:fc3:ffff:fffe'}], 'gateway_ip': '3561:e885:a1ba:7f44:deaf:fc3:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3561:e885:a1ba:7f44:deaf:fc3:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:17,360 - INFO - Created VN ctest-vnet3-24318904 2025-07-24 03:30:17,378 - DEBUG - VN ctest-vnet3-24318904 UUID is 3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:17,539 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:17,547 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:17,565 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:17,681 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:17,699 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:17,716 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/bdbb41c9-eeb2-4ef4-85dc-d23c9c54bc58 2025-07-24 03:30:17,731 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/bdbb41c9-eeb2-4ef4-85dc-d23c9c54bc58 2025-07-24 03:30:17,744 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/7eecae72-c21b-461f-b2bc-4b4a3471b16f 2025-07-24 03:30:17,754 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-07-24 03:30:17,754 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:17,774 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/bdbb41c9-eeb2-4ef4-85dc-d23c9c54bc58 2025-07-24 03:30:17,796 - INFO - Verified VN network id 14 for VN 3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:17,796 - INFO - Verifications in API Server for VN ctest-vnet3-24318904 passed 2025-07-24 03:30:17,796 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:17,811 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/bdbb41c9-eeb2-4ef4-85dc-d23c9c54bc58 2025-07-24 03:30:17,824 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/bdbb41c9-eeb2-4ef4-85dc-d23c9c54bc58 2025-07-24 03:30:17,835 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/7eecae72-c21b-461f-b2bc-4b4a3471b16f 2025-07-24 03:30:17,854 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4004532519482049586', 'uuid-lslong': '13214845541100825220'}, 'enable': 'true', 'created': '2025-07-24T03:30:16', 'last-modified': '2025-07-24T03:30:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.597993'} 2025-07-24 03:30:17,865 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-07-24 03:30:17,873 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4004532519482049586', 'uuid-lslong': '13214845541100825220'}, 'enable': 'true', 'created': '2025-07-24T03:30:16', 'last-modified': '2025-07-24T03:30:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.616106'} 2025-07-24 03:30:17,880 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-07-24 03:30:17,888 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4004532519482049586', 'uuid-lslong': '13214845541100825220'}, 'enable': 'true', 'created': '2025-07-24T03:30:16', 'last-modified': '2025-07-24T03:30:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.626920'} 2025-07-24 03:30:17,895 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-07-24 03:30:17,895 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet3-24318904 passed 2025-07-24 03:30:17,895 - DEBUG - ====Verifying policy data for ctest-vnet3-24318904 in API_Server ====== 2025-07-24 03:30:17,896 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:17,903 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:17,918 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:18,020 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:18,038 - DEBUG - =>VN ctest-vnet3-24318904 has no policy to be verified 2025-07-24 03:30:18,038 - DEBUG - Verifying the vn in opserver 2025-07-24 03:30:18,038 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904 virtual network link through opserver 10.0.0.19 2025-07-24 03:30:18,038 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:30:18,055 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904?flat'} 2025-07-24 03:30:18,055 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904 virtual network link through opserver 10.0.0.38 2025-07-24 03:30:18,055 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:30:18,071 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904?flat'} 2025-07-24 03:30:18,071 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904 virtual network link through opserver 10.0.0.41 2025-07-24 03:30:18,071 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:30:18,090 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904?flat'} 2025-07-24 03:30:18,090 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904 is found in opserver 2025-07-24 03:30:18,123 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:30:18,139 - DEBUG - VRF ids for VN ctest-vnet3-24318904: {} 2025-07-24 03:30:18,148 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet1-45484017, 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-07-24 03:30:18,394 - DEBUG - ====Verifying data for ctest-policy0-18666909 in API_Server ====== 2025-07-24 03:30:18,394 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:18,402 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:18,417 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:18,531 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/7a62dd6e-9a9f-4e1a-acd7-776e2193fbbc 2025-07-24 03:30:18,550 - INFO - Verified policy ctest-policy0-18666909 in API Server, Result: True 2025-07-24 03:30:18,559 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet0-58456050, 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-07-24 03:30:18,809 - DEBUG - ====Verifying data for ctest-policy1-08332341 in API_Server ====== 2025-07-24 03:30:18,809 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:18,817 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:18,834 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:18,962 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/0cfc8139-6cff-4d57-bada-79d6fd100248 2025-07-24 03:30:18,987 - INFO - Verified policy ctest-policy1-08332341 in API Server, Result: True 2025-07-24 03:30:18,995 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet0-58456050, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet0-58456050, 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-07-24 03:30:19,239 - DEBUG - ====Verifying data for ctest-policy2-02808871 in API_Server ====== 2025-07-24 03:30:19,239 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:19,248 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:19,267 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:19,385 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/d79edbed-7585-42f4-8062-c4638eb9bb0a 2025-07-24 03:30:19,411 - INFO - Verified policy ctest-policy2-02808871 in API Server, Result: True 2025-07-24 03:30:19,422 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-46126473:ctest-vnet1-45484017, 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-07-24 03:30:19,667 - DEBUG - ====Verifying data for ctest-policy3-04780176 in API_Server ====== 2025-07-24 03:30:19,667 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:19,683 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:19,705 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:19,822 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/370a4b1e-f7fe-4948-be55-44e0292506ef 2025-07-24 03:30:19,849 - INFO - Verified policy ctest-policy3-04780176 in API Server, Result: True 2025-07-24 03:30:19,858 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-07-24 03:30:20,108 - DEBUG - ====Verifying data for ctest-policy4-37973206 in API_Server ====== 2025-07-24 03:30:20,108 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:20,121 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:20,146 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:20,260 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/899ce986-7e74-4dea-9483-a6af32dc9c1a 2025-07-24 03:30:20,275 - INFO - Verified policy ctest-policy4-37973206 in API Server, Result: True 2025-07-24 03:30:20,283 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-46126473:ctest-vnet2-67889923, 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-07-24 03:30:20,516 - DEBUG - ====Verifying data for ctest-policy5-73320668 in API_Server ====== 2025-07-24 03:30:20,516 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:20,526 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:20,544 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:20,676 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/c28ed102-9ff9-432a-9b6d-876ba5a65afd 2025-07-24 03:30:20,699 - INFO - Verified policy ctest-policy5-73320668 in API Server, Result: True 2025-07-24 03:30:20,708 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-07-24 03:30:20,978 - DEBUG - ====Verifying data for ctest-policy6-46980593 in API_Server ====== 2025-07-24 03:30:20,978 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:20,987 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:21,004 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:21,122 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/00a8ad6a-bb02-4fe7-a2e4-047e0b151b59 2025-07-24 03:30:21,143 - INFO - Verified policy ctest-policy6-46980593 in API Server, Result: True 2025-07-24 03:30:21,192 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = >, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-46126473:ctest-vnet3-24318904, 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-07-24 03:30:21,435 - DEBUG - ====Verifying data for ctest-policy7-26761790 in API_Server ====== 2025-07-24 03:30:21,435 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:21,442 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:21,457 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:21,597 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/af54b8f5-fb42-4584-8e82-9b8ffa4bd53f 2025-07-24 03:30:21,611 - INFO - Verified policy ctest-policy7-26761790 in API Server, Result: True 2025-07-24 03:30:21,612 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:21,620 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:21,633 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:21,747 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:21,761 - DEBUG - Setup step: Associating the policy to VN' 2025-07-24 03:30:21,933 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '3be02cad-654f-418e-9b65-19a5bb393e0c', 'name': 'ctest-vnet0-58456050', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['533fae16-f5c6-43d0-a5ed-a4a866b7824c', '29c02a7d-d219-44dd-8bac-0f10586cbcf4', '4c24f124-fee0-4fe4-9b3d-dfdcc6723302'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet0-58456050'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy0-18666909'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy1-08332341']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '379a:8410:a167:d470:9bc8:ffeb::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:22,138 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy0-18666909'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy1-08332341']] to ctest-vnet0-58456050 2025-07-24 03:30:22,138 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:22,145 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:22,159 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:22,262 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:22,282 - DEBUG - Setup step: Associating the policy to VN' 2025-07-24 03:30:22,515 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f', 'name': 'ctest-vnet1-45484017', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['bf487ed6-811e-49fd-b639-3e29a1f3d61f', '61c5fb14-6a44-4cde-af27-5b4704501fca', '251e718f-786d-4910-b720-b0636e0f831e'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet1-45484017'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy2-02808871'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy3-04780176']], 'subnet_ipam': [{'subnet_cidr': '12.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '13.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '3702:1013:cfa7:3a56:4700:5dbc::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:22,717 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy2-02808871'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy3-04780176']] to ctest-vnet1-45484017 2025-07-24 03:30:22,718 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:22,728 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:22,752 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:22,863 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:22,879 - DEBUG - Setup step: Associating the policy to VN' 2025-07-24 03:30:23,099 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2', 'name': 'ctest-vnet2-67889923', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['d8db705c-e26d-4f51-ab06-8e443ea236cb', '9538994c-6ea2-49cc-9a29-e9be222b1c56', '69384b84-b816-4281-9c55-f591dcd47af9'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet2-67889923'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy4-37973206'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy5-73320668']], 'subnet_ipam': [{'subnet_cidr': '14.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '15.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '3a56:60da:81a2:5d71:f9ac:eb9e::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:23,288 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy4-37973206'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy5-73320668']] to ctest-vnet2-67889923 2025-07-24 03:30:23,288 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:23,297 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:23,310 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:23,415 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:23,434 - DEBUG - Setup step: Associating the policy to VN' 2025-07-24 03:30:23,596 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '3792f51c-0b3a-4432-b764-90011ef82a84', 'name': 'ctest-vnet3-24318904', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['c2a8b846-4d5b-41aa-a21d-d624d2f43ae5', 'f521cc8d-51cf-4f4d-aa5f-617e9f506aa7', '90d62f78-0771-4dc1-878a-93f2570dc24d'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vnet3-24318904'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy6-46980593'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy7-26761790']], 'subnet_ipam': [{'subnet_cidr': '16.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '17.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}, {'subnet_cidr': '3561:e885:a1ba:7f44:deaf:fc3::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-TestBasicPolicyIpv4v6-46126473-default-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:23,806 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy6-46980593'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy7-26761790']] to ctest-vnet3-24318904 2025-07-24 03:30:23,806 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3712-1', 'an-jenkins-deploy-platform-ansible-os-3712-2']} 2025-07-24 03:30:23,936 - DEBUG - Services list from nova: [, , , ] 2025-07-24 03:30:24,008 - DEBUG - Image by name ubuntu-traffic-py3 not found 2025-07-24 03:30:24,008 - DEBUG - Installing image ubuntu-traffic-py3 2025-07-24 03:30:24,008 - 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-07-24 03:30:24,009 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic-py3.qcow2.gz 2025-07-24 03:30:24,009 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//ubuntu-traffic-py3.qcow2.gz 2025-07-24 03:30:24,027 - 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-07-24 03:30:24,067 - DEBUG - Image has been downloaded to /contrail-test/images/ubuntu-traffic-py3.qcow2.gz 2025-07-24 03:30:24,068 - DEBUG - Unzip image 2025-07-24 03:30:24,068 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-traffic-py3.qcow2.gz 2025-07-24 03:30:24,085 - DEBUG - Try to create image in user context 2025-07-24 03:30:24,145 - DEBUG - Detaching the Policy for VN :ctest-vnet3-24318904 2025-07-24 03:30:24,145 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:24,153 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:24,170 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:24,282 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3792f51c-0b3a-4432-b764-90011ef82a84 2025-07-24 03:30:24,552 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy6-46980593'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy7-26761790']] from ctest-vnet3-24318904 2025-07-24 03:30:24,552 - DEBUG - Detaching the Policy for VN :ctest-vnet2-67889923 2025-07-24 03:30:24,552 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:24,560 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:24,579 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:24,692 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/5ee6f09c-a2a4-402e-8366-bc32d4ebe8b2 2025-07-24 03:30:24,945 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy4-37973206'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy5-73320668']] from ctest-vnet2-67889923 2025-07-24 03:30:24,945 - DEBUG - Detaching the Policy for VN :ctest-vnet1-45484017 2025-07-24 03:30:24,946 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:24,955 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:24,970 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:25,083 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/dc9bf4e7-6d8c-446e-8c6f-0acf7db81d1f 2025-07-24 03:30:25,371 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy2-02808871'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy3-04780176']] from ctest-vnet1-45484017 2025-07-24 03:30:25,371 - DEBUG - Detaching the Policy for VN :ctest-vnet0-58456050 2025-07-24 03:30:25,372 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:25,387 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:25,402 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:25,493 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/3be02cad-654f-418e-9b65-19a5bb393e0c 2025-07-24 03:30:25,748 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy0-18666909'], ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy1-08332341']] from ctest-vnet0-58456050 2025-07-24 03:30:25,839 - INFO - Deleted policy ctest-policy7-26761790 2025-07-24 03:30:25,998 - DEBUG - policy ctest-policy7-26761790 is not found in API Server 2025-07-24 03:30:26,067 - INFO - Deleted policy ctest-policy6-46980593 2025-07-24 03:30:26,276 - DEBUG - policy ctest-policy6-46980593 is not found in API Server 2025-07-24 03:30:26,331 - INFO - Deleted policy ctest-policy5-73320668 2025-07-24 03:30:26,532 - DEBUG - policy ctest-policy5-73320668 is not found in API Server 2025-07-24 03:30:26,589 - INFO - Deleted policy ctest-policy4-37973206 2025-07-24 03:30:26,784 - DEBUG - policy ctest-policy4-37973206 is not found in API Server 2025-07-24 03:30:26,847 - INFO - Deleted policy ctest-policy3-04780176 2025-07-24 03:30:27,048 - DEBUG - policy ctest-policy3-04780176 is not found in API Server 2025-07-24 03:30:27,100 - INFO - Deleted policy ctest-policy2-02808871 2025-07-24 03:30:27,312 - DEBUG - policy ctest-policy2-02808871 is not found in API Server 2025-07-24 03:30:27,388 - INFO - Deleted policy ctest-policy1-08332341 2025-07-24 03:30:27,588 - DEBUG - policy ctest-policy1-08332341 is not found in API Server 2025-07-24 03:30:27,640 - INFO - Deleted policy ctest-policy0-18666909 2025-07-24 03:30:27,844 - DEBUG - policy ctest-policy0-18666909 is not found in API Server 2025-07-24 03:30:27,844 - INFO - Deleting VN ctest-vnet3-24318904 2025-07-24 03:30:28,067 - DEBUG - Response for deleting network () 2025-07-24 03:30:28,068 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/bdbb41c9-eeb2-4ef4-85dc-d23c9c54bc58 2025-07-24 03:30:28,075 - DEBUG - Response Code: 404 2025-07-24 03:30:28,076 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:28,089 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:28,146 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:28,264 - INFO - Validated that VN ctest-vnet3-24318904 is not found in API Server 2025-07-24 03:30:28,280 - DEBUG - VN ctest-vnet3-24318904 is not present in Agent 10.0.0.57 2025-07-24 03:30:28,293 - DEBUG - VN ctest-vnet3-24318904 is not present in Agent 10.0.0.23 2025-07-24 03:30:28,293 - INFO - Validated that VN ctest-vnet3-24318904 is not in any agent 2025-07-24 03:30:28,335 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet3-24318904 info 2025-07-24 03:30:28,335 - INFO - Deleting VN ctest-vnet2-67889923 2025-07-24 03:30:28,579 - DEBUG - Response for deleting network () 2025-07-24 03:30:28,579 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/499bb522-ce06-4e91-b670-bfb784612d7e 2025-07-24 03:30:28,586 - DEBUG - Response Code: 404 2025-07-24 03:30:28,586 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:28,600 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:28,613 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:28,721 - INFO - Validated that VN ctest-vnet2-67889923 is not found in API Server 2025-07-24 03:30:28,736 - DEBUG - VN ctest-vnet2-67889923 is not present in Agent 10.0.0.57 2025-07-24 03:30:28,756 - DEBUG - VN ctest-vnet2-67889923 is not present in Agent 10.0.0.23 2025-07-24 03:30:28,756 - INFO - Validated that VN ctest-vnet2-67889923 is not in any agent 2025-07-24 03:30:28,803 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet2-67889923 info 2025-07-24 03:30:28,803 - INFO - Deleting VN ctest-vnet1-45484017 2025-07-24 03:30:29,003 - DEBUG - Response for deleting network () 2025-07-24 03:30:29,003 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/d47f9d05-86be-417c-a8af-c16b0b3f283c 2025-07-24 03:30:29,012 - DEBUG - Response Code: 404 2025-07-24 03:30:29,012 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:29,023 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:29,041 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:29,146 - INFO - Validated that VN ctest-vnet1-45484017 is not found in API Server 2025-07-24 03:30:29,161 - DEBUG - VN ctest-vnet1-45484017 is not present in Agent 10.0.0.57 2025-07-24 03:30:29,184 - DEBUG - VN ctest-vnet1-45484017 is not present in Agent 10.0.0.23 2025-07-24 03:30:29,184 - INFO - Validated that VN ctest-vnet1-45484017 is not in any agent 2025-07-24 03:30:29,225 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet1-45484017 info 2025-07-24 03:30:29,225 - INFO - Deleting VN ctest-vnet0-58456050 2025-07-24 03:30:29,457 - DEBUG - Response for deleting network () 2025-07-24 03:30:29,457 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/055b363d-b78b-4ebd-a31a-f25d43f05955 2025-07-24 03:30:29,466 - DEBUG - Response Code: 404 2025-07-24 03:30:29,466 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:29,475 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:29,539 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:29,658 - INFO - Validated that VN ctest-vnet0-58456050 is not found in API Server 2025-07-24 03:30:29,672 - DEBUG - VN ctest-vnet0-58456050 is not present in Agent 10.0.0.57 2025-07-24 03:30:29,688 - DEBUG - VN ctest-vnet0-58456050 is not present in Agent 10.0.0.23 2025-07-24 03:30:29,688 - INFO - Validated that VN ctest-vnet0-58456050 is not in any agent 2025-07-24 03:30:29,735 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet0-58456050 info 2025-07-24 03:30:29,840 - INFO - IPAM: ctest-TestBasicPolicyIpv4v6-46126473-default-ipam is not found in API Server 2025-07-24 03:30:29,871 - INFO - IPAM:ctest-TestBasicPolicyIpv4v6-46126473-default-ipam is not found in control node 2025-07-24 03:30:30,424 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473'] 2025-07-24 03:30:30,424 - DEBUG - Skipping the deletion of User ctest-TestBasicPolicyIpv4v6-46126473 2025-07-24 03:30:31,698 - ERROR -

Traceback (most recent call last):
  File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
    result = function(self, *args, **kwargs)
  File "/contrail-test/scripts/policy/test_policy_basic.py", line 59, in test_policy
    out = setup_obj.topo_setup()
  File "/contrail-test/tcutils/topo/sdn_topo_setup.py", line 94, in topo_setup
    topo_steps.createVMNova(self, config_option, vms_on_single_compute)
  File "/contrail-test/tcutils/topo/topo_steps.py", line 516, in createVMNova
    vm_name=vm))
  File "/usr/local/lib/python3.6/site-packages/fixtures/fixture.py", line 257, in useFixture
    fixture.setUp()
  File "/contrail-test/fixtures/vm_test.py", line 188, in setUp
    self.create()
  File "/contrail-test/fixtures/vm_test.py", line 240, in create
    fixed_ips=self.fixed_ips)
  File "/contrail-test/fixtures/openstack.py", line 118, in create_vm
    return self.nova_h.create_vm(vm_name=vm_name, image_name=image_name, vn_ids=vn_ids, **kwargs)
  File "/contrail-test/fixtures/nova_test.py", line 577, in create_vm
    image = self.get_image(image_name=image_name)
  File "/contrail-test/fixtures/nova_test.py", line 198, in get_image
    self._install_image(image_name=image_name)
  File "/contrail-test/fixtures/nova_test.py", line 338, in _install_image
    return self.copy_and_glance(build_path, image_name, params)
  File "/contrail-test/fixtures/nova_test.py", line 407, in copy_and_glance
    image_id = self.glance_h.create_image(generic_image_name, image_path_real, **params)
  File "/contrail-test/fixtures/glance_test.py", line 50, in create_image
    self.upload_image(obj['id'], filename)
  File "/contrail-test/fixtures/glance_test.py", line 54, in upload_image
    self.obj.images.upload(uuid, open(filename, 'rb'))
FileNotFoundError: [Errno 2] No such file or directory: '/contrail-test/images/ubuntu-traffic-py3.qcow2'

2025-07-24 03:30:31,717 - DEBUG - No XMPP flaps were noticed during the test 2025-07-24 03:30:31,718 - INFO -

2025-07-24 03:30:31,718 - INFO - END TEST : test_policy : FAILED[0:00:24]

2025-07-24 03:30:31,718 - INFO - -------------------------------------------------------------------------------- 2025-07-24 03:30:32,995 - ERROR - TestFailed Python 3.6.8: /usr/bin/python3 Thu Jul 24 03:30:31 2025 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/scripts/policy/test_policy.py in test_policy(self=) 602 @preposttest_wrapper 603 def test_policy(self): 604 super(TestBasicPolicyIpv4v6, self).test_policy() 605 606 @test.attr(type=['sanity', 'quick_sanity']) builtinsuper = global TestBasicPolicyIpv4v6 = self = ).test_policy = > /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 174 if 'ci_image' in list(os.environ.keys()): 175 os.environ['stop_execution_flag'] = 'set' 176 raise TestFailed("\n ".join(errmsg)) 177 elif testskip: 178 log.info('') global TestFailed = "\n ".join undefined errmsg = ["Test failed:

Traceback (most recent call la...ail-test/images/ubuntu-traffic-py3.qcow2'\n

\n"] TestFailed: Test failed:

Traceback (most recent call last):
  File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
    result = function(self, *args, **kwargs)
  File "/contrail-test/scripts/policy/test_policy_basic.py", line 59, in test_policy
    out = setup_obj.topo_setup()
  File "/contrail-test/tcutils/topo/sdn_topo_setup.py", line 94, in topo_setup
    topo_steps.createVMNova(self, config_option, vms_on_single_compute)
  File "/contrail-test/tcutils/topo/topo_steps.py", line 516, in createVMNova
    vm_name=vm))
  File "/usr/local/lib/python3.6/site-packages/fixtures/fixture.py", line 257, in useFixture
    fixture.setUp()
  File "/contrail-test/fixtures/vm_test.py", line 188, in setUp
    self.create()
  File "/contrail-test/fixtures/vm_test.py", line 240, in create
    fixed_ips=self.fixed_ips)
  File "/contrail-test/fixtures/openstack.py", line 118, in create_vm
    return self.nova_h.create_vm(vm_name=vm_name, image_name=image_name, vn_ids=vn_ids, **kwargs)
  File "/contrail-test/fixtures/nova_test.py", line 577, in create_vm
    image = self.get_image(image_name=image_name)
  File "/contrail-test/fixtures/nova_test.py", line 198, in get_image
    self._install_image(image_name=image_name)
  File "/contrail-test/fixtures/nova_test.py", line 338, in _install_image
    return self.copy_and_glance(build_path, image_name, params)
  File "/contrail-test/fixtures/nova_test.py", line 407, in copy_and_glance
    image_id = self.glance_h.create_image(generic_image_name, image_path_real, **params)
  File "/contrail-test/fixtures/glance_test.py", line 50, in create_image
    self.upload_image(obj['id'], filename)
  File "/contrail-test/fixtures/glance_test.py", line 54, in upload_image
    self.obj.images.upload(uuid, open(filename, 'rb'))
FileNotFoundError: [Errno 2] No such file or directory: '/contrail-test/images/ubuntu-traffic-py3.qcow2'

__cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = None __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __module__ = 'tcutils.cores' __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = __weakref__ = None args = ("Test failed:

Traceback (most recent call la...ail-test/images/ubuntu-traffic-py3.qcow2'\n

\n",) with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/policy/test_policy.py", line 604, in test_policy super(TestBasicPolicyIpv4v6, self).test_policy() File "/contrail-test/tcutils/wrappers.py", line 176, in wrapper raise TestFailed("\n ".join(errmsg)) tcutils.cores.TestFailed: Test failed:

Traceback (most recent call last):
  File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
    result = function(self, *args, **kwargs)
  File "/contrail-test/scripts/policy/test_policy_basic.py", line 59, in test_policy
    out = setup_obj.topo_setup()
  File "/contrail-test/tcutils/topo/sdn_topo_setup.py", line 94, in topo_setup
    topo_steps.createVMNova(self, config_option, vms_on_single_compute)
  File "/contrail-test/tcutils/topo/topo_steps.py", line 516, in createVMNova
    vm_name=vm))
  File "/usr/local/lib/python3.6/site-packages/fixtures/fixture.py", line 257, in useFixture
    fixture.setUp()
  File "/contrail-test/fixtures/vm_test.py", line 188, in setUp
    self.create()
  File "/contrail-test/fixtures/vm_test.py", line 240, in create
    fixed_ips=self.fixed_ips)
  File "/contrail-test/fixtures/openstack.py", line 118, in create_vm
    return self.nova_h.create_vm(vm_name=vm_name, image_name=image_name, vn_ids=vn_ids, **kwargs)
  File "/contrail-test/fixtures/nova_test.py", line 577, in create_vm
    image = self.get_image(image_name=image_name)
  File "/contrail-test/fixtures/nova_test.py", line 198, in get_image
    self._install_image(image_name=image_name)
  File "/contrail-test/fixtures/nova_test.py", line 338, in _install_image
    return self.copy_and_glance(build_path, image_name, params)
  File "/contrail-test/fixtures/nova_test.py", line 407, in copy_and_glance
    image_id = self.glance_h.create_image(generic_image_name, image_path_real, **params)
  File "/contrail-test/fixtures/glance_test.py", line 50, in create_image
    self.upload_image(obj['id'], filename)
  File "/contrail-test/fixtures/glance_test.py", line 54, in upload_image
    self.obj.images.upload(uuid, open(filename, 'rb'))
FileNotFoundError: [Errno 2] No such file or directory: '/contrail-test/images/ubuntu-traffic-py3.qcow2'

2025-07-24 03:30:33,013 - DEBUG - No XMPP flaps were noticed during the test 2025-07-24 03:30:33,013 - INFO -

2025-07-24 03:30:33,013 - INFO - END TEST : test_policy : FAILED[0:00:28]

2025-07-24 03:30:33,014 - INFO - -------------------------------------------------------------------------------- 2025-07-24 03:30:33,019 - INFO - ================================================================================ 2025-07-24 03:30:33,019 - INFO - STARTING TEST : test_policy_to_deny 2025-07-24 03:30:34,326 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with 2025-07-24 03:30:34,327 - INFO - Initial checks done. Running the testcase now 2025-07-24 03:30:34,327 - INFO - 2025-07-24 03:30:34,327 - INFO - ================================================================================ 2025-07-24 03:30:34,327 - INFO - STARTING TEST : test_policy_to_deny 2025-07-24 03:30:34,327 - 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-07-24 03:30:35,641 - DEBUG - Nothing to compare xmpp stats {'10.0.0.57': {'10.20.0.17': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.129': '0', '10.20.0.17': '0'}} with 2025-07-24 03:30:35,641 - INFO - Initial checks done. Running the testcase now 2025-07-24 03:30:35,641 - INFO - 2025-07-24 03:30:35,649 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787, 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-46126473:ctest-vn1-00601787, 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-46126473:ctest-vn1-00601787, 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-46126473:ctest-vn1-00601787, 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-07-24 03:30:36,581 - DEBUG - Response for create_network : {'network': {'id': '056210d1-429d-49bb-84fd-ba1f7b778fd7', 'name': 'ctest-vn1-00601787', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vn1-00601787'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:36,863 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c76ad428-e307-4c74-b66f-19bbcd65dc9a', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '056210d1-429d-49bb-84fd-ba1f7b778fd7', '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': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:37,133 - DEBUG - Response for create_subnet : {'subnet': {'id': 'baafe2d2-4971-4c13-97a1-e293b6dfb97a', 'name': '', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'network_id': '056210d1-429d-49bb-84fd-ba1f7b778fd7', 'ip_version': 6, 'cidr': '3262:fc13:1801:31bb:59a1:f4b::/96', 'allocation_pools': [{'start': '3262:fc13:1801:31bb:59a1:f4b:0:2', 'end': '3262:fc13:1801:31bb:59a1:f4b:ffff:fffe'}], 'gateway_ip': '3262:fc13:1801:31bb:59a1:f4b:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3262:fc13:1801:31bb:59a1:f4b:0:2', 'tags': [], 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa'}} 2025-07-24 03:30:37,170 - INFO - Created VN ctest-vn1-00601787 2025-07-24 03:30:37,184 - DEBUG - VN ctest-vn1-00601787 UUID is 056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:37,450 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '056210d1-429d-49bb-84fd-ba1f7b778fd7', 'name': 'ctest-vn1-00601787', 'tenant_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'project_id': 'fdc9d2ced26a422286438d91b8ee2ffa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['c76ad428-e307-4c74-b66f-19bbcd65dc9a', 'baafe2d2-4971-4c13-97a1-e293b6dfb97a'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-vn1-00601787'], 'policys': [['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy1-08529935']], 'subnet_ipam': [{'subnet_cidr': '192.168.10.0/24', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '3262:fc13:1801:31bb:59a1:f4b::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-07-24 03:30:37,584 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:37,593 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:37,613 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:37,723 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:37,740 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:37,754 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a47ddd9-1b47-4341-955e-47ec9b9e137a 2025-07-24 03:30:37,769 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a47ddd9-1b47-4341-955e-47ec9b9e137a 2025-07-24 03:30:37,779 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/cba33504-40b6-4ae8-8eb1-847c266e53d9 2025-07-24 03:30:37,790 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:37,790 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:37,805 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a47ddd9-1b47-4341-955e-47ec9b9e137a 2025-07-24 03:30:37,827 - INFO - Verified VN network id 11 for VN 056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:37,827 - INFO - Verifications in API Server for VN ctest-vn1-00601787 passed 2025-07-24 03:30:37,827 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:37,840 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a47ddd9-1b47-4341-955e-47ec9b9e137a 2025-07-24 03:30:37,850 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a47ddd9-1b47-4341-955e-47ec9b9e137a 2025-07-24 03:30:37,860 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/cba33504-40b6-4ae8-8eb1-847c266e53d9 2025-07-24 03:30:37,876 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '387891008859097531', 'uuid-lslong': '9583020226492469207'}, 'enable': 'true', 'created': '2025-07-24T03:30:36', 'last-modified': '2025-07-24T03:30:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.489314'} 2025-07-24 03:30:37,883 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:37,890 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '387891008859097531', 'uuid-lslong': '9583020226492469207'}, 'enable': 'true', 'created': '2025-07-24T03:30:36', 'last-modified': '2025-07-24T03:30:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.502833'} 2025-07-24 03:30:37,898 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:37,905 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyIpv4v6-46126473', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '387891008859097531', 'uuid-lslong': '9583020226492469207'}, 'enable': 'true', 'created': '2025-07-24T03:30:36', 'last-modified': '2025-07-24T03:30:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.519297'} 2025-07-24 03:30:37,913 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-24 03:30:37,913 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-00601787 passed 2025-07-24 03:30:37,914 - DEBUG - ====Verifying policy data for ctest-vn1-00601787 in API_Server ====== 2025-07-24 03:30:37,914 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:30:37,922 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:30:37,939 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:30:38,055 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/056210d1-429d-49bb-84fd-ba1f7b778fd7 2025-07-24 03:30:38,075 - DEBUG - ==>Verifying data for policy with id: e09f5c3b-842d-4fce-b26a-43790e99ccd4, fqn: ['default-domain', 'ctest-TestBasicPolicyIpv4v6-46126473', 'ctest-policy1-08529935'] 2025-07-24 03:30:38,075 - INFO - VN ctest-vn1-00601787 Policy verification: verify_vn_policy_in_api_server, status: True 2025-07-24 03:30:38,075 - DEBUG - Verifying the vn in opserver 2025-07-24 03:30:38,075 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 virtual network link through opserver 10.0.0.19 2025-07-24 03:30:38,075 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-07-24 03:30:38,094 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787?flat'} 2025-07-24 03:30:38,094 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 virtual network link through opserver 10.0.0.38 2025-07-24 03:30:38,094 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-07-24 03:30:38,110 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787?flat'} 2025-07-24 03:30:38,110 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 virtual network link through opserver 10.0.0.41 2025-07-24 03:30:38,110 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-07-24 03:30:38,129 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787?flat'} 2025-07-24 03:30:38,129 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is found in opserver 2025-07-24 03:30:38,170 - DEBUG - Do not have enough data to verify VN in agent 2025-07-24 03:30:38,189 - DEBUG - VRF ids for VN ctest-vn1-00601787: {} 2025-07-24 03:30:39,561 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1) 2025-07-24 03:30:41,002 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2) 2025-07-24 03:30:41,002 - INFO - Waiting for VM ctest-vn1_vm1-49370988 to be up.. 2025-07-24 03:30:41,101 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-24 03:30:46,194 - DEBUG - VM is in ACTIVE state now 2025-07-24 03:30:46,194 - INFO - VM name : ctest-vn1_vm1-49370988 2025-07-24 03:30:46,283 - DEBUG - VM ctest-vn1_vm1-49370988 ID is 9ed091f9-6328-4bc0-8889-3e6651adcc2e 2025-07-24 03:30:46,283 - DEBUG - VM ctest-vn1_vm1-49370988 launched on Node an-jenkins-deploy-platform-ansible-os-3712-1 2025-07-24 03:30:46,370 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/9ed091f9-6328-4bc0-8889-3e6651adcc2e 2025-07-24 03:30:46,383 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/c5d0d54b-df30-43d7-998b-4ef71af41179 2025-07-24 03:30:49,634 - 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 1028ms') 2025-07-24 03:30:49,634 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:30:49,653 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:30:49,654 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:30:53,738 - 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 1017ms') 2025-07-24 03:30:53,738 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:30:53,754 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:30:53,754 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:30:57,831 - 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 1007ms') 2025-07-24 03:30:57,832 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:30:57,853 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:30:57,853 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:01,927 - 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-07-24 03:31:01,927 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:01,951 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:01,951 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:06,024 - 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 1004ms') 2025-07-24 03:31:06,024 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:06,044 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:06,044 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:10,118 - 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 1002ms') 2025-07-24 03:31:10,119 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:10,139 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:10,139 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:14,214 - 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 1011ms') 2025-07-24 03:31:14,215 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:14,231 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:14,231 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:18,318 - 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 1009ms') 2025-07-24 03:31:18,318 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:18,336 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:18,336 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:22,402 - 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 1003ms') 2025-07-24 03:31:22,403 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:22,422 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:22,422 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:26,499 - 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-07-24 03:31:26,499 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 failed! 2025-07-24 03:31:26,528 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 192.168.10.1 and allocation pool is NOT set 2025-07-24 03:31:26,528 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicyIpv4v6-46126473:ctest-vn1-00601787 is 3262:fc13:1801:31bb:59a1:f4b:0:1 and allocation pool is NOT set 2025-07-24 03:31:28,596 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.56 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 = 1.557/3.421/5.286/1.864 ms') 2025-07-24 03:31:28,597 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-49370988 passed 2025-07-24 03:31:28,723 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:28,723 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:28,789 - 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-07-24 03:31:28,887 - DEBUG - VM ctest-vn1_vm1-49370988 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:31:33,888 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:33,888 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:33,958 - 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-07-24 03:31:34,051 - DEBUG - VM ctest-vn1_vm1-49370988 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:31:39,052 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:39,053 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:39,121 - 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-07-24 03:31:39,220 - DEBUG - VM ctest-vn1_vm1-49370988 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:31:44,221 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:44,221 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:44,289 - 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-07-24 03:31:44,400 - DEBUG - VM ctest-vn1_vm1-49370988 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:31:49,400 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:49,401 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:49,469 - 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-07-24 03:31:49,571 - DEBUG - VM ctest-vn1_vm1-49370988 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:31:54,572 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:54,572 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:54,652 - 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-07-24 03:31:54,782 - DEBUG - VM ctest-vn1_vm1-49370988 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-24 03:31:59,783 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:31:59,783 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-49370988, IP 192.168.10.3, Port 22 2025-07-24 03:31:59,958 - DEBUG - VM ctest-vn1_vm1-49370988 is ready for SSH connections 2025-07-24 03:31:59,958 - INFO - Waiting for VM ctest-vn1_vm2-21951987 to be up.. 2025-07-24 03:32:00,249 - DEBUG - VM is in ACTIVE state now 2025-07-24 03:32:00,249 - INFO - VM name : ctest-vn1_vm2-21951987 2025-07-24 03:32:00,355 - DEBUG - VM ctest-vn1_vm2-21951987 ID is dd3b582b-4679-4fd0-ba90-a76fd61b88d1 2025-07-24 03:32:00,355 - DEBUG - VM ctest-vn1_vm2-21951987 launched on Node an-jenkins-deploy-platform-ansible-os-3712-2 2025-07-24 03:32:00,476 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/dd3b582b-4679-4fd0-ba90-a76fd61b88d1 2025-07-24 03:32:00,492 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/c13b31f2-db97-4bcc-87f8-62b0e643e66e 2025-07-24 03:32:01,726 - 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.02 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.08 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 = 1.077/2.546/4.015/1.469 ms') 2025-07-24 03:32:01,727 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm2-21951987 passed 2025-07-24 03:32:01,868 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 03:32:01,868 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-21951987, IP 192.168.10.4, Port 22 2025-07-24 03:32:02,036 - DEBUG - VM ctest-vn1_vm2-21951987 is ready for SSH connections 2025-07-24 03:32:02,037 - 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.57, gateway password: c0ntrail123 2025-07-24 03:32:02,037 - DEBUG - ping -s 56 -c 3 -W 1 192.168.10.4 2025-07-24 03:32:27,968 - 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 2003ms 2025-07-24 03:32:27,969 - WARNING - Ping to IP 192.168.10.4 from VM ctest-vn1_vm1-49370988 failed 2025-07-24 03:32:27,969 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3262:fc13:1801:31bb:59a1:f4b:0:4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123 2025-07-24 03:32:27,969 - DEBUG - ping6 -s 56 -c 3 -W 1 3262:fc13:1801:31bb:59a1:f4b:0:4 2025-07-24 03:32:31,517 - DEBUG - PING 3262:fc13:1801:31bb:59a1:f4b:0:4(3262:fc13:1801:31bb:59a1:f4b:0:4) 56 data bytes --- 3262:fc13:1801:31bb:59a1:f4b:0:4 ping statistics --- 3 packets transmitted, 0 received, 100% packet loss, time 2001ms 2025-07-24 03:32:31,517 - WARNING - Ping to IP 3262:fc13:1801:31bb:59a1:f4b:0:4 from VM ctest-vn1_vm1-49370988 failed 2025-07-24 03:32:31,518 - INFO - Deleting VM ctest-vn1_vm2-21951987 2025-07-24 03:32:31,600 - INFO - Deleting VM ctest-vn1_vm1-49370988 2025-07-24 03:32:31,880 - INFO - Deleting VN ctest-vn1-00601787 2025-07-24 03:32:31,929 - DEBUG - VN 056210d1-429d-49bb-84fd-ba1f7b778fd7 still in use: Unable to complete operation on network 056210d1-429d-49bb-84fd-ba1f7b778fd7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-42f4f237-1cf0-4a5c-8773-bf4d2f6d50ea'] 2025-07-24 03:32:31,929 - WARNING - Deleting VN ctest-vn1-00601787 failed..Will retry 2025-07-24 03:32:34,180 - DEBUG - Response for deleting network () 2025-07-24 03:32:34,180 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/0a47ddd9-1b47-4341-955e-47ec9b9e137a 2025-07-24 03:32:34,189 - DEBUG - Response Code: 404 2025-07-24 03:32:34,189 - DEBUG - Requesting: http://10.0.0.19:8082/domains 2025-07-24 03:32:34,197 - DEBUG - Requesting: http://10.0.0.19:8082/domain/ef05d1d9-2fbc-48d4-8924-0cbd930e68f2 2025-07-24 03:32:34,280 - DEBUG - Requesting: http://10.0.0.19:8082/project/fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa 2025-07-24 03:32:34,400 - INFO - Validated that VN ctest-vn1-00601787 is not found in API Server 2025-07-24 03:32:34,415 - DEBUG - VN ctest-vn1-00601787 is not present in Agent 10.0.0.57 2025-07-24 03:32:34,432 - DEBUG - VN ctest-vn1-00601787 is not present in Agent 10.0.0.23 2025-07-24 03:32:34,432 - INFO - Validated that VN ctest-vn1-00601787 is not in any agent 2025-07-24 03:32:34,480 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-00601787 info 2025-07-24 03:32:34,533 - INFO - Deleted policy ctest-policy1-08529935 2025-07-24 03:32:35,836 - DEBUG - No XMPP flaps were noticed during the test

2025-07-24 03:32:35,836 - INFO - END TEST : test_policy_to_deny : PASSED[0:02:01]

2025-07-24 03:32:35,836 - INFO - -------------------------------------------------------------------------------- 2025-07-24 03:32:37,119 - DEBUG - No XMPP flaps were noticed during the test

2025-07-24 03:32:37,119 - INFO - END TEST : test_policy_to_deny : PASSED[0:02:04]

2025-07-24 03:32:37,120 - INFO - -------------------------------------------------------------------------------- 2025-07-24 03:32:37,928 - INFO - Deleted project: ctest-TestBasicPolicyIpv4v6-46126473, ID : fdc9d2ce-d26a-4222-8643-8d91b8ee2ffa