2026-01-23 13:40:02,266 - INFO - Domain Default found not creating
2026-01-23 13:40:02,445 - INFO - Project ctest-TestBasicPolicyNegativeIpv4v6-08384867 not found, creating it
2026-01-23 13:40:03,055 - INFO - Created Project:ctest-TestBasicPolicyNegativeIpv4v6-08384867, ID : 340c7464-394b-466a-952a-0b79acee42f3
2026-01-23 13:40:04,520 - INFO - ================================================================================
2026-01-23 13:40:04,520 - INFO - STARTING TEST : test_remove_policy_with_ref
2026-01-23 13:40:05,825 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.192': '0', '10.20.0.252': '0'}, '10.0.0.65': {'10.20.0.192': '0', '10.20.0.252': '0'}} with
2026-01-23 13:40:05,825 - INFO - Initial checks done. Running the testcase now
2026-01-23 13:40:05,825 - INFO -
2026-01-23 13:40:05,825 - INFO - ================================================================================
2026-01-23 13:40:05,825 - INFO - STARTING TEST : test_remove_policy_with_ref
2026-01-23 13:40:05,825 - 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.
2026-01-23 13:40:07,138 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.192': '0', '10.20.0.252': '0'}, '10.0.0.65': {'10.20.0.192': '0', '10.20.0.252': '0'}} with
2026-01-23 13:40:07,138 - INFO - Initial checks done. Running the testcase now
2026-01-23 13:40:07,138 - INFO -
2026-01-23 13:40:07,492 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004, 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-08384867:ctest-vn4-72454004, 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-08384867:ctest-vn4-72454004, 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-08384867:ctest-vn4-72454004, 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]
2026-01-23 13:40:08,123 - DEBUG - Response for create_network : {'network': {'id': '610a06b4-3579-4a01-aadf-08ce184d4b1b', 'name': 'ctest-vn4-72454004', 'tenant_id': '340c7464394b466a952a0b79acee42f3', 'project_id': '340c7464394b466a952a0b79acee42f3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'ctest-vn4-72454004'], 'port_security_enabled': True, 'description': ''}}
2026-01-23 13:40:08,462 - DEBUG - Response for create_subnet : {'subnet': {'id': '7a10950a-682b-46e0-84d6-07d2f190fe7b', 'name': '', 'tenant_id': '340c7464394b466a952a0b79acee42f3', 'network_id': '610a06b4-3579-4a01-aadf-08ce184d4b1b', 'ip_version': 4, 'cidr': '44.245.94.64/26', 'allocation_pools': [{'start': '44.245.94.66', 'end': '44.245.94.126'}], 'gateway_ip': '44.245.94.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '44.245.94.66', 'tags': [], 'project_id': '340c7464394b466a952a0b79acee42f3'}}
2026-01-23 13:40:08,688 - DEBUG - Response for create_subnet : {'subnet': {'id': 'af880ab0-9625-4326-84e1-08ddffe843c8', 'name': '', 'tenant_id': '340c7464394b466a952a0b79acee42f3', 'network_id': '610a06b4-3579-4a01-aadf-08ce184d4b1b', 'ip_version': 6, 'cidr': '39a1:4002:eb47:ce3b:689a:334a::/96', 'allocation_pools': [{'start': '39a1:4002:eb47:ce3b:689a:334a:0:2', 'end': '39a1:4002:eb47:ce3b:689a:334a:ffff:fffe'}], 'gateway_ip': '39a1:4002:eb47:ce3b:689a:334a:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '39a1:4002:eb47:ce3b:689a:334a:0:2', 'tags': [], 'project_id': '340c7464394b466a952a0b79acee42f3'}}
2026-01-23 13:40:08,712 - INFO - Created VN ctest-vn4-72454004
2026-01-23 13:40:08,725 - DEBUG - VN ctest-vn4-72454004 UUID is 610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:08,966 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '610a06b4-3579-4a01-aadf-08ce184d4b1b', 'name': 'ctest-vn4-72454004', 'tenant_id': '340c7464394b466a952a0b79acee42f3', 'project_id': '340c7464394b466a952a0b79acee42f3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['7a10950a-682b-46e0-84d6-07d2f190fe7b', 'af880ab0-9625-4326-84e1-08ddffe843c8'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'ctest-vn4-72454004'], 'policys': [['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'ctest-policy1-46847889']], 'subnet_ipam': [{'subnet_cidr': '44.245.94.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}, {'subnet_cidr': '39a1:4002:eb47:ce3b:689a:334a::/96', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-01-23 13:40:09,069 - DEBUG - Requesting: http://10.0.0.241:8082/domains
2026-01-23 13:40:09,379 - DEBUG - Requesting: http://10.0.0.241:8082/domains
2026-01-23 13:40:09,413 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca
2026-01-23 13:40:09,428 - DEBUG - Requesting: http://10.0.0.241:8082/project/340c7464-394b-466a-952a-0b79acee42f3
2026-01-23 13:40:09,528 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:09,541 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:09,551 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/a72f9c86-94c6-4ac7-b7af-55623d40711e
2026-01-23 13:40:09,559 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/a72f9c86-94c6-4ac7-b7af-55623d40711e
2026-01-23 13:40:09,568 - DEBUG - Requesting: http://10.0.0.241:8082/route-target/2c5ba588-2b35-4c73-a9b9-67c0d9a19008
2026-01-23 13:40:09,582 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-23 13:40:09,582 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:09,595 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/a72f9c86-94c6-4ac7-b7af-55623d40711e
2026-01-23 13:40:09,614 - INFO - Verified VN network id 11 for VN 610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:09,614 - INFO - Verifications in API Server for VN ctest-vn4-72454004 passed
2026-01-23 13:40:09,614 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:09,625 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/a72f9c86-94c6-4ac7-b7af-55623d40711e
2026-01-23 13:40:09,632 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/a72f9c86-94c6-4ac7-b7af-55623d40711e
2026-01-23 13:40:09,640 - DEBUG - Requesting: http://10.0.0.241:8082/route-target/2c5ba588-2b35-4c73-a9b9-67c0d9a19008
2026-01-23 13:40:09,653 - DEBUG - Control-node 10.0.0.241 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6992408742507137537', 'uuid-lslong': '12312569587518229275'}, 'enable': 'true', 'created': '2026-01-23T13:40:08', 'last-modified': '2026-01-23T13:40:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.743683'}
2026-01-23 13:40:09,661 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-23 13:40:09,668 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6992408742507137537', 'uuid-lslong': '12312569587518229275'}, 'enable': 'true', 'created': '2026-01-23T13:40:08', 'last-modified': '2026-01-23T13:40:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.753578'}
2026-01-23 13:40:09,675 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-23 13:40:09,681 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6992408742507137537', 'uuid-lslong': '12312569587518229275'}, 'enable': 'true', 'created': '2026-01-23T13:40:08', 'last-modified': '2026-01-23T13:40:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.770542'}
2026-01-23 13:40:09,687 - DEBUG - Route Targets: ['target:64512:8000005']
2026-01-23 13:40:09,687 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn4-72454004 passed
2026-01-23 13:40:09,687 - DEBUG - ====Verifying policy data for ctest-vn4-72454004 in API_Server ======
2026-01-23 13:40:09,687 - DEBUG - Requesting: http://10.0.0.241:8082/domains
2026-01-23 13:40:09,693 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca
2026-01-23 13:40:09,707 - DEBUG - Requesting: http://10.0.0.241:8082/project/340c7464-394b-466a-952a-0b79acee42f3
2026-01-23 13:40:09,817 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-network/610a06b4-3579-4a01-aadf-08ce184d4b1b
2026-01-23 13:40:09,835 - DEBUG - ==>Verifying data for policy with id: 6307bde5-bb37-4f85-b9c6-7a4c82517add, fqn: ['default-domain', 'ctest-TestBasicPolicyNegativeIpv4v6-08384867', 'ctest-policy1-46847889']
2026-01-23 13:40:09,835 - INFO - VN ctest-vn4-72454004 Policy verification: verify_vn_policy_in_api_server, status: True
2026-01-23 13:40:09,835 - DEBUG - Verifying the vn in opserver
2026-01-23 13:40:09,835 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004 virtual network link through opserver 10.0.0.241
2026-01-23 13:40:09,836 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-networks
2026-01-23 13:40:10,143 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-networks
2026-01-23 13:40:10,222 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004', 'href': 'http://10.0.0.241:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004?flat'}
2026-01-23 13:40:10,222 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004 virtual network link through opserver 10.0.0.242
2026-01-23 13:40:10,222 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-23 13:40:10,531 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-23 13:40:10,605 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004?flat'}
2026-01-23 13:40:10,605 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004 virtual network link through opserver 10.0.0.240
2026-01-23 13:40:10,605 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2026-01-23 13:40:10,903 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2026-01-23 13:40:10,969 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004?flat'}
2026-01-23 13:40:10,969 - INFO - Validated that VN default-domain:ctest-TestBasicPolicyNegativeIpv4v6-08384867:ctest-vn4-72454004 is found in opserver
2026-01-23 13:40:11,002 - DEBUG - Do not have enough data to verify VN in agent
2026-01-23 13:40:11,014 - DEBUG - VRF ids for VN ctest-vn4-72454004: {}
2026-01-23 13:40:11,014 - DEBUG - ====Verifying data for ctest-policy1-46847889 in API_Server ======
2026-01-23 13:40:11,014 - DEBUG - Requesting: http://10.0.0.241:8082/domains
2026-01-23 13:40:11,023 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca
2026-01-23 13:40:11,037 - DEBUG - Requesting: http://10.0.0.241:8082/project/340c7464-394b-466a-952a-0b79acee42f3
2026-01-23 13:40:11,143 - DEBUG - Requesting: http://10.0.0.241:8082/network-policy/6307bde5-bb37-4f85-b9c6-7a4c82517add
2026-01-23 13:40:11,156 - INFO - Verified policy ctest-policy1-46847889 in API Server, Result: True
2026-01-23 13:40:11,158 - INFO - Done with setup and verification, moving onto test ..
2026-01-23 13:40:11,172 - INFO - Deleting VN ctest-vn4-72454004
2026-01-23 13:40:11,399 - DEBUG - Response for deleting network ()
2026-01-23 13:40:11,399 - DEBUG - Requesting: http://10.0.0.241:8082/routing-instance/a72f9c86-94c6-4ac7-b7af-55623d40711e
2026-01-23 13:40:11,409 - DEBUG - Response Code: 404
2026-01-23 13:40:11,410 - DEBUG - Requesting: http://10.0.0.241:8082/domains
2026-01-23 13:40:11,417 - DEBUG - Requesting: http://10.0.0.241:8082/domain/f15a1bbf-817f-4eb9-b7ab-0dfe5138c0ca
2026-01-23 13:40:11,473 - DEBUG - Requesting: http://10.0.0.241:8082/project/340c7464-394b-466a-952a-0b79acee42f3
2026-01-23 13:40:11,577 - INFO - Validated that VN ctest-vn4-72454004 is not found in API Server
2026-01-23 13:40:11,588 - DEBUG - VN ctest-vn4-72454004 is not present in Agent 10.0.0.19
2026-01-23 13:40:11,599 - DEBUG - VN ctest-vn4-72454004 is not present in Agent 10.0.0.65
2026-01-23 13:40:11,599 - INFO - Validated that VN ctest-vn4-72454004 is not in any agent
2026-01-23 13:40:11,631 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn4-72454004 info
2026-01-23 13:40:11,672 - INFO - Deleted policy ctest-policy1-46847889
2026-01-23 13:40:11,879 - DEBUG - policy ctest-policy1-46847889 is not found in API Server
2026-01-23 13:40:13,148 - DEBUG - No XMPP flaps were noticed during the test
2026-01-23 13:40:13,148 - INFO - END TEST : test_remove_policy_with_ref : PASSED[0:00:08]
2026-01-23 13:40:13,148 - INFO - --------------------------------------------------------------------------------
2026-01-23 13:40:14,447 - DEBUG - No XMPP flaps were noticed during the test
2026-01-23 13:40:14,447 - INFO - END TEST : test_remove_policy_with_ref : PASSED[0:00:10]
2026-01-23 13:40:14,447 - INFO - --------------------------------------------------------------------------------
2026-01-23 13:40:15,227 - INFO - Deleted project: ctest-TestBasicPolicyNegativeIpv4v6-08384867, ID : 340c7464-394b-466a-952a-0b79acee42f3