2025-07-31 03:39:46,681 - INFO - Domain Default found not creating
2025-07-31 03:39:46,934 - INFO - Project ctest-TestBasicPolicyNegativeIpv4v6-08703933 not found, creating it
2025-07-31 03:39:47,604 - INFO - Created Project:ctest-TestBasicPolicyNegativeIpv4v6-08703933, ID : f0a2fd2d-e664-4dbf-8a4a-ead5778db56c
2025-07-31 03:39:49,690 - INFO - ================================================================================
2025-07-31 03:39:49,691 - INFO - STARTING TEST : test_remove_policy_with_ref
2025-07-31 03:39:51,073 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.57': {'10.20.0.18': '0', '10.20.0.193': '0'}} with
2025-07-31 03:39:51,074 - INFO - Initial checks done. Running the testcase now
2025-07-31 03:39:51,074 - INFO -
2025-07-31 03:39:51,074 - INFO - ================================================================================
2025-07-31 03:39:51,074 - INFO - STARTING TEST : test_remove_policy_with_ref
2025-07-31 03:39:51,074 - INFO - TEST DESCRIPTION : This tests the following scenarios.
1. Test to validate that policy removal will fail when it referenced with VN.
2. validate vn_policy data in api-s against quantum-vn data, when created and unbind policy from VN thru quantum APIs.
3. validate policy data in api-s against quantum-policy data, when created and deleted thru quantum APIs.
2025-07-31 03:39:52,419 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.57': {'10.20.0.18': '0', '10.20.0.193': '0'}} with
2025-07-31 03:39:52,419 - INFO - Initial checks done. Running the testcase now
2025-07-31 03:39:52,419 - INFO -
2025-07-31 03:39:52,848 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395, 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-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395, 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-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395, 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-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395, 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-31 03:39:53,502 - DEBUG - Response for create_network : {'network': {'id': 'c62dd5d3-ebe9-464a-877d-440e4d29cbad', 'name': 'ctest-vn4-53343395', 'tenant_id': 'f0a2fd2de6644dbf8a4aead5778db56c', 'project_id': 'f0a2fd2de6644dbf8a4aead5778db56c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'ctest-vn4-53343395'], 'port_security_enabled': True, 'description': ''}}
2025-07-31 03:39:53,905 - DEBUG - Response for create_subnet : {'subnet': {'id': '8fc02fa7-99e6-4abb-8b2d-f8077fec44b6', 'name': '', 'tenant_id': 'f0a2fd2de6644dbf8a4aead5778db56c', 'network_id': 'c62dd5d3-ebe9-464a-877d-440e4d29cbad', 'ip_version': 4, 'cidr': '137.153.90.0/26', 'allocation_pools': [{'start': '137.153.90.2', 'end': '137.153.90.62'}], 'gateway_ip': '137.153.90.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '137.153.90.2', 'tags': [], 'project_id': 'f0a2fd2de6644dbf8a4aead5778db56c'}}
2025-07-31 03:39:54,151 - DEBUG - Response for create_subnet : {'subnet': {'id': '1717fb58-0405-486c-858a-0235219d5523', 'name': '', 'tenant_id': 'f0a2fd2de6644dbf8a4aead5778db56c', 'network_id': 'c62dd5d3-ebe9-464a-877d-440e4d29cbad', 'ip_version': 6, 'cidr': '2bc6:d2dd:2cf0:4be3:976a:c6c8::/96', 'allocation_pools': [{'start': '2bc6:d2dd:2cf0:4be3:976a:c6c8:0:2', 'end': '2bc6:d2dd:2cf0:4be3:976a:c6c8:ffff:fffe'}], 'gateway_ip': '2bc6:d2dd:2cf0:4be3:976a:c6c8:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2bc6:d2dd:2cf0:4be3:976a:c6c8:0:2', 'tags': [], 'project_id': 'f0a2fd2de6644dbf8a4aead5778db56c'}}
2025-07-31 03:39:54,187 - INFO - Created VN ctest-vn4-53343395
2025-07-31 03:39:54,202 - DEBUG - VN ctest-vn4-53343395 UUID is c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:54,467 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'c62dd5d3-ebe9-464a-877d-440e4d29cbad', 'name': 'ctest-vn4-53343395', 'tenant_id': 'f0a2fd2de6644dbf8a4aead5778db56c', 'project_id': 'f0a2fd2de6644dbf8a4aead5778db56c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['8fc02fa7-99e6-4abb-8b2d-f8077fec44b6', '1717fb58-0405-486c-858a-0235219d5523'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'ctest-vn4-53343395'], 'policys': [['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'ctest-policy1-97006124']], 'subnet_ipam': [{'subnet_cidr': '137.153.90.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '2bc6:d2dd:2cf0:4be3:976a:c6c8::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-07-31 03:39:54,593 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 03:39:54,905 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 03:39:54,977 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 03:39:55,006 - DEBUG - Requesting: http://10.0.0.41:8082/project/f0a2fd2d-e664-4dbf-8a4a-ead5778db56c
2025-07-31 03:39:55,180 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:55,202 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:55,215 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/725547a8-a8bd-40e9-ac43-f8c211dd1d71
2025-07-31 03:39:55,224 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/725547a8-a8bd-40e9-ac43-f8c211dd1d71
2025-07-31 03:39:55,232 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/bcae5d55-1d98-4b0d-882e-db62ad7fae84
2025-07-31 03:39:55,242 - DEBUG - Route Targets: ['target:64512:8000005']
2025-07-31 03:39:55,242 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:55,255 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/725547a8-a8bd-40e9-ac43-f8c211dd1d71
2025-07-31 03:39:55,277 - INFO - Verified VN network id 11 for VN c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:55,277 - INFO - Verifications in API Server for VN ctest-vn4-53343395 passed
2025-07-31 03:39:55,277 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:55,304 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/725547a8-a8bd-40e9-ac43-f8c211dd1d71
2025-07-31 03:39:55,316 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/725547a8-a8bd-40e9-ac43-f8c211dd1d71
2025-07-31 03:39:55,329 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/bcae5d55-1d98-4b0d-882e-db62ad7fae84
2025-07-31 03:39:55,347 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14280305099634460234', 'uuid-lslong': '9763034395423919021'}, 'enable': 'true', 'created': '2025-07-31T03:39:53', 'last-modified': '2025-07-31T03:39:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.935919'}
2025-07-31 03:39:55,355 - DEBUG - Route Targets: ['target:64512:8000005']
2025-07-31 03:39:55,361 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14280305099634460234', 'uuid-lslong': '9763034395423919021'}, 'enable': 'true', 'created': '2025-07-31T03:39:53', 'last-modified': '2025-07-31T03:39:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.936822'}
2025-07-31 03:39:55,368 - DEBUG - Route Targets: ['target:64512:8000005']
2025-07-31 03:39:55,375 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14280305099634460234', 'uuid-lslong': '9763034395423919021'}, 'enable': 'true', 'created': '2025-07-31T03:39:53', 'last-modified': '2025-07-31T03:39:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.964166'}
2025-07-31 03:39:55,383 - DEBUG - Route Targets: ['target:64512:8000005']
2025-07-31 03:39:55,383 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn4-53343395 passed
2025-07-31 03:39:55,384 - DEBUG - ====Verifying policy data for ctest-vn4-53343395 in API_Server ======
2025-07-31 03:39:55,384 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 03:39:55,392 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 03:39:55,410 - DEBUG - Requesting: http://10.0.0.41:8082/project/f0a2fd2d-e664-4dbf-8a4a-ead5778db56c
2025-07-31 03:39:55,556 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c62dd5d3-ebe9-464a-877d-440e4d29cbad
2025-07-31 03:39:55,590 - DEBUG - ==>Verifying data for policy with id: 87f199b3-1841-4fac-acdf-aff296156002, fqn: ['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08703933', 'ctest-policy1-97006124']
2025-07-31 03:39:55,590 - INFO - VN ctest-vn4-53343395 Policy verification: verify_vn_policy_in_api_server, status: True
2025-07-31 03:39:55,590 - DEBUG - Verifying the vn in opserver
2025-07-31 03:39:55,590 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395 virtual network link through opserver 10.0.0.41
2025-07-31 03:39:55,590 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-07-31 03:39:55,911 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-07-31 03:39:55,985 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395?flat'}
2025-07-31 03:39:55,985 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395 virtual network link through opserver 10.0.0.38
2025-07-31 03:39:55,985 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-07-31 03:39:56,294 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-07-31 03:39:56,423 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395?flat'}
2025-07-31 03:39:56,423 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395 virtual network link through opserver 10.0.0.61
2025-07-31 03:39:56,424 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-07-31 03:39:56,770 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-07-31 03:39:56,835 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395?flat'}
2025-07-31 03:39:56,835 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08703933:ctest-vn4-53343395 is found in opserver
2025-07-31 03:39:56,874 - DEBUG - Do not have enough data to verify VN in agent
2025-07-31 03:39:56,893 - DEBUG - VRF ids for VN ctest-vn4-53343395: {}
2025-07-31 03:39:56,894 - DEBUG - ====Verifying data for ctest-policy1-97006124 in API_Server ======
2025-07-31 03:39:56,894 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 03:39:56,905 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 03:39:56,937 - DEBUG - Requesting: http://10.0.0.41:8082/project/f0a2fd2d-e664-4dbf-8a4a-ead5778db56c
2025-07-31 03:39:57,058 - DEBUG - Requesting: http://10.0.0.41:8082/network-policy/87f199b3-1841-4fac-acdf-aff296156002
2025-07-31 03:39:57,081 - INFO - Verified policy ctest-policy1-97006124 in API Server, Result: True
2025-07-31 03:39:57,082 - INFO - Done with setup and verification, moving onto test ..
2025-07-31 03:39:57,096 - INFO - Deleting VN ctest-vn4-53343395
2025-07-31 03:39:57,324 - DEBUG - Response for deleting network ()
2025-07-31 03:39:57,324 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/725547a8-a8bd-40e9-ac43-f8c211dd1d71
2025-07-31 03:39:57,333 - DEBUG - Response Code: 404
2025-07-31 03:39:57,333 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 03:39:57,342 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 03:39:57,358 - DEBUG - Requesting: http://10.0.0.41:8082/project/f0a2fd2d-e664-4dbf-8a4a-ead5778db56c
2025-07-31 03:39:57,464 - INFO - Validated that VN ctest-vn4-53343395 is not found in API Server
2025-07-31 03:39:57,477 - DEBUG - VN ctest-vn4-53343395 is not present in Agent 10.0.0.27
2025-07-31 03:39:57,493 - DEBUG - VN ctest-vn4-53343395 is not present in Agent 10.0.0.57
2025-07-31 03:39:57,493 - INFO - Validated that VN ctest-vn4-53343395 is not in any agent
2025-07-31 03:39:57,535 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn4-53343395 info
2025-07-31 03:39:57,583 - INFO - Deleted policy ctest-policy1-97006124
2025-07-31 03:39:57,777 - DEBUG - policy ctest-policy1-97006124 is not found in API Server
2025-07-31 03:39:59,095 - DEBUG - No XMPP flaps were noticed during the test
2025-07-31 03:39:59,095 - INFO - END TEST : test_remove_policy_with_ref : PASSED[0:00:08]
2025-07-31 03:39:59,096 - INFO - --------------------------------------------------------------------------------
2025-07-31 03:40:00,467 - DEBUG - No XMPP flaps were noticed during the test
2025-07-31 03:40:00,467 - INFO - END TEST : test_remove_policy_with_ref : PASSED[0:00:11]
2025-07-31 03:40:00,467 - INFO - --------------------------------------------------------------------------------
2025-07-31 03:40:01,287 - INFO - Deleted project: ctest-TestBasicPolicyNegativeIpv4v6-08703933, ID : f0a2fd2d-e664-4dbf-8a4a-ead5778db56c