2025-08-04 02:59:08,267 - INFO - Domain Default found not creating
2025-08-04 02:59:08,439 - INFO - Project ctest-TestPolicyAcl-76815164 not found, creating it
2025-08-04 02:59:09,000 - INFO - Created Project:ctest-TestPolicyAcl-76815164, ID : 22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:11,203 - INFO - ================================================================================
2025-08-04 02:59:11,203 - INFO - STARTING TEST : test_policy_cidr_src_cidr_dst_any
2025-08-04 02:59:11,203 - INFO - TEST DESCRIPTION : Test cases to test policy CIDR
2025-08-04 02:59:12,484 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.57': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-08-04 02:59:12,484 - INFO - Initial checks done. Running the testcase now
2025-08-04 02:59:12,484 - INFO -
2025-08-04 02:59:13,800 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:14,101 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:14,138 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:14,159 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:14,278 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/56847d6b-c04d-4916-8c91-b94e50b575cc
2025-08-04 02:59:14,290 - INFO - Verifications in API Server for IPAM: ipam1 passed
2025-08-04 02:59:14,298 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6234245685928872214', 'uuid-lslong': '10129080782945613260'}, 'enable': 'true', 'created': '2025-08-04T02:59:12', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.203262'}
2025-08-04 02:59:14,306 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6234245685928872214', 'uuid-lslong': '10129080782945613260'}, 'enable': 'true', 'created': '2025-08-04T02:59:12', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.209622'}
2025-08-04 02:59:14,315 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6234245685928872214', 'uuid-lslong': '10129080782945613260'}, 'enable': 'true', 'created': '2025-08-04T02:59:12', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.221944'}
2025-08-04 02:59:14,315 - INFO - Verifications in Control node for IPAM: ipam1 passed
2025-08-04 02:59:14,315 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:14,325 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:14,345 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:14,457 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/699f7244-437f-4e78-8209-0d119f43cbab
2025-08-04 02:59:14,466 - INFO - Verifications in API Server for IPAM: ipam2 passed
2025-08-04 02:59:14,476 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7610927532795186808', 'uuid-lslong': '9370034869058653099'}, 'enable': 'true', 'created': '2025-08-04T02:59:13', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.052902'}
2025-08-04 02:59:14,483 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7610927532795186808', 'uuid-lslong': '9370034869058653099'}, 'enable': 'true', 'created': '2025-08-04T02:59:13', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.059625'}
2025-08-04 02:59:14,489 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7610927532795186808', 'uuid-lslong': '9370034869058653099'}, 'enable': 'true', 'created': '2025-08-04T02:59:13', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.063483'}
2025-08-04 02:59:14,490 - INFO - Verifications in Control node for IPAM: ipam2 passed
2025-08-04 02:59:14,490 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:14,497 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:14,511 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:14,637 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/b1f98f9e-a37c-48d0-86c2-7ac8e2e8c41b
2025-08-04 02:59:14,647 - INFO - Verifications in API Server for IPAM: ipam3 passed
2025-08-04 02:59:14,658 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12824439325424634064', 'uuid-lslong': '9710458749783163931'}, 'enable': 'true', 'created': '2025-08-04T02:59:13', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.883236'}
2025-08-04 02:59:14,665 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12824439325424634064', 'uuid-lslong': '9710458749783163931'}, 'enable': 'true', 'created': '2025-08-04T02:59:13', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.896973'}
2025-08-04 02:59:14,672 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12824439325424634064', 'uuid-lslong': '9710458749783163931'}, 'enable': 'true', 'created': '2025-08-04T02:59:13', 'last-modified': '2025-08-04T02:59:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.904318'}
2025-08-04 02:59:14,673 - INFO - Verifications in Control node for IPAM: ipam3 passed
2025-08-04 02:59:15,021 - DEBUG - Response for create_network : {'network': {'id': '9a47a414-2ef6-496a-b3cd-d3dd56b3567f', 'name': 'ctest-VN1-68866152', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN1-68866152'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:15,302 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cca7f95b-1477-4646-bc41-e25ebc42c552', 'name': '', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'network_id': '9a47a414-2ef6-496a-b3cd-d3dd56b3567f', 'ip_version': 4, 'cidr': '13.96.135.128/26', 'allocation_pools': [{'start': '13.96.135.130', 'end': '13.96.135.190'}], 'gateway_ip': '13.96.135.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.96.135.130', 'tags': [], 'project_id': '22641a4155bc4646ad675a86da311710'}}
2025-08-04 02:59:15,325 - INFO - Created VN ctest-VN1-68866152
2025-08-04 02:59:15,338 - DEBUG - VN ctest-VN1-68866152 UUID is 9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:15,524 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152(9a47a414-2ef6-496a-b3cd-d3dd56b3567f) with subnets ['13.96.135.128/26']
2025-08-04 02:59:15,919 - DEBUG - Response for create_network : {'network': {'id': '8950c509-0a92-4dd4-bf33-3597d88ccdf4', 'name': 'ctest-VN2-97022920', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN2-97022920'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:16,159 - DEBUG - Response for create_subnet : {'subnet': {'id': '28a46e9e-1d24-4777-874d-ca95e770746e', 'name': '', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'network_id': '8950c509-0a92-4dd4-bf33-3597d88ccdf4', 'ip_version': 4, 'cidr': '221.172.191.64/26', 'allocation_pools': [{'start': '221.172.191.66', 'end': '221.172.191.126'}], 'gateway_ip': '221.172.191.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '221.172.191.66', 'tags': [], 'project_id': '22641a4155bc4646ad675a86da311710'}}
2025-08-04 02:59:16,184 - INFO - Created VN ctest-VN2-97022920
2025-08-04 02:59:16,199 - DEBUG - VN ctest-VN2-97022920 UUID is 8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:16,566 - DEBUG - Response for create_network : {'network': {'id': '974b2e90-160e-4d62-8282-d0f0ee496640', 'name': 'ctest-VN3-72778652', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN3-72778652'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:16,820 - DEBUG - Response for create_subnet : {'subnet': {'id': '5642b69d-12ac-4a19-a2f9-8bcd95e34a86', 'name': '', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'network_id': '974b2e90-160e-4d62-8282-d0f0ee496640', 'ip_version': 4, 'cidr': '115.235.91.64/26', 'allocation_pools': [{'start': '115.235.91.66', 'end': '115.235.91.126'}], 'gateway_ip': '115.235.91.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '115.235.91.66', 'tags': [], 'project_id': '22641a4155bc4646ad675a86da311710'}}
2025-08-04 02:59:16,843 - INFO - Created VN ctest-VN3-72778652
2025-08-04 02:59:16,861 - DEBUG - VN ctest-VN3-72778652 UUID is 974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:16,943 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:16,950 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:16,991 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:17,107 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:17,122 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:17,137 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/662677c7-b2b1-4b0d-9e66-d977c8d1e527
2025-08-04 02:59:17,149 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/662677c7-b2b1-4b0d-9e66-d977c8d1e527
2025-08-04 02:59:17,159 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e82866ce-efda-4c7c-8d37-f06ae8d07c7c
2025-08-04 02:59:17,169 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:17,169 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:17,182 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/662677c7-b2b1-4b0d-9e66-d977c8d1e527
2025-08-04 02:59:17,206 - INFO - Verified VN network id 13 for VN 9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:17,206 - INFO - Verifications in API Server for VN ctest-VN1-68866152 passed
2025-08-04 02:59:17,206 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:17,217 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/662677c7-b2b1-4b0d-9e66-d977c8d1e527
2025-08-04 02:59:17,226 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/662677c7-b2b1-4b0d-9e66-d977c8d1e527
2025-08-04 02:59:17,235 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e82866ce-efda-4c7c-8d37-f06ae8d07c7c
2025-08-04 02:59:17,252 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11117034611781552490', 'uuid-lslong': '12956244650610611839'}, 'enable': 'true', 'created': '2025-08-04T02:59:14', 'last-modified': '2025-08-04T02:59:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.004275'}
2025-08-04 02:59:17,260 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:17,267 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11117034611781552490', 'uuid-lslong': '12956244650610611839'}, 'enable': 'true', 'created': '2025-08-04T02:59:14', 'last-modified': '2025-08-04T02:59:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.024966'}
2025-08-04 02:59:17,275 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:17,282 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11117034611781552490', 'uuid-lslong': '12956244650610611839'}, 'enable': 'true', 'created': '2025-08-04T02:59:14', 'last-modified': '2025-08-04T02:59:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.039667'}
2025-08-04 02:59:17,290 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:17,290 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-68866152 passed
2025-08-04 02:59:17,290 - DEBUG - ====Verifying policy data for ctest-VN1-68866152 in API_Server ======
2025-08-04 02:59:17,290 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:17,300 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:17,317 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:17,423 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:17,434 - DEBUG - =>VN ctest-VN1-68866152 has no policy to be verified
2025-08-04 02:59:17,434 - DEBUG - Verifying the vn in opserver
2025-08-04 02:59:17,434 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152 virtual network link through opserver 10.0.0.27
2025-08-04 02:59:17,435 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:17,729 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:17,790 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152?flat'}
2025-08-04 02:59:17,790 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152 virtual network link through opserver 10.0.0.38
2025-08-04 02:59:17,790 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:18,104 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:18,164 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152?flat'}
2025-08-04 02:59:18,164 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152 virtual network link through opserver 10.0.0.61
2025-08-04 02:59:18,165 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:18,460 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:18,524 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152?flat'}
2025-08-04 02:59:18,524 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-68866152 is found in opserver
2025-08-04 02:59:18,551 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 02:59:18,568 - DEBUG - VRF ids for VN ctest-VN1-68866152: {}
2025-08-04 02:59:18,568 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:18,578 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:18,597 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:18,700 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:18,717 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:18,730 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2c8ea387-bf54-48f1-8dcf-ba4a616073ef
2025-08-04 02:59:18,739 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2c8ea387-bf54-48f1-8dcf-ba4a616073ef
2025-08-04 02:59:18,748 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/4c3d41b5-a266-4403-a770-2e856a1e80c1
2025-08-04 02:59:18,757 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:18,757 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:18,767 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2c8ea387-bf54-48f1-8dcf-ba4a616073ef
2025-08-04 02:59:18,787 - INFO - Verified VN network id 14 for VN 8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:18,787 - INFO - Verifications in API Server for VN ctest-VN2-97022920 passed
2025-08-04 02:59:18,787 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:18,800 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2c8ea387-bf54-48f1-8dcf-ba4a616073ef
2025-08-04 02:59:18,810 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2c8ea387-bf54-48f1-8dcf-ba4a616073ef
2025-08-04 02:59:18,819 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/4c3d41b5-a266-4403-a770-2e856a1e80c1
2025-08-04 02:59:18,835 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9894625023955717588', 'uuid-lslong': '13777414611345919476'}, 'enable': 'true', 'created': '2025-08-04T02:59:15', 'last-modified': '2025-08-04T02:59:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.723661'}
2025-08-04 02:59:18,844 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:18,850 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9894625023955717588', 'uuid-lslong': '13777414611345919476'}, 'enable': 'true', 'created': '2025-08-04T02:59:15', 'last-modified': '2025-08-04T02:59:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.738832'}
2025-08-04 02:59:18,860 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:18,868 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9894625023955717588', 'uuid-lslong': '13777414611345919476'}, 'enable': 'true', 'created': '2025-08-04T02:59:15', 'last-modified': '2025-08-04T02:59:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.756441'}
2025-08-04 02:59:18,876 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:18,876 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-97022920 passed
2025-08-04 02:59:18,876 - DEBUG - ====Verifying policy data for ctest-VN2-97022920 in API_Server ======
2025-08-04 02:59:18,876 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:18,884 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:18,897 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:18,993 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:19,004 - DEBUG - =>VN ctest-VN2-97022920 has no policy to be verified
2025-08-04 02:59:19,004 - DEBUG - Verifying the vn in opserver
2025-08-04 02:59:19,004 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920 virtual network link through opserver 10.0.0.27
2025-08-04 02:59:19,004 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:19,067 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920?flat'}
2025-08-04 02:59:19,067 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920 virtual network link through opserver 10.0.0.38
2025-08-04 02:59:19,067 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:19,129 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920?flat'}
2025-08-04 02:59:19,129 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920 virtual network link through opserver 10.0.0.61
2025-08-04 02:59:19,129 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:19,190 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920?flat'}
2025-08-04 02:59:19,190 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-97022920 is found in opserver
2025-08-04 02:59:19,217 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 02:59:19,230 - DEBUG - VRF ids for VN ctest-VN2-97022920: {}
2025-08-04 02:59:19,230 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:19,238 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:19,253 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:19,358 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:19,375 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:19,388 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fb56666b-76f3-469d-948a-d0aa10298f95
2025-08-04 02:59:19,400 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fb56666b-76f3-469d-948a-d0aa10298f95
2025-08-04 02:59:19,411 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/26dfa981-ec87-462c-aa21-94f62e4cc859
2025-08-04 02:59:19,421 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:19,421 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:19,433 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fb56666b-76f3-469d-948a-d0aa10298f95
2025-08-04 02:59:19,452 - INFO - Verified VN network id 15 for VN 974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:19,452 - INFO - Verifications in API Server for VN ctest-VN3-72778652 passed
2025-08-04 02:59:19,453 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:19,471 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fb56666b-76f3-469d-948a-d0aa10298f95
2025-08-04 02:59:19,481 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fb56666b-76f3-469d-948a-d0aa10298f95
2025-08-04 02:59:19,490 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/26dfa981-ec87-462c-aa21-94f62e4cc859
2025-08-04 02:59:19,513 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10901858519360621922', 'uuid-lslong': '9404308705111533120'}, 'enable': 'true', 'created': '2025-08-04T02:59:16', 'last-modified': '2025-08-04T02:59:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.756657'}
2025-08-04 02:59:19,525 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:19,534 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10901858519360621922', 'uuid-lslong': '9404308705111533120'}, 'enable': 'true', 'created': '2025-08-04T02:59:16', 'last-modified': '2025-08-04T02:59:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.785357'}
2025-08-04 02:59:19,541 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:19,548 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10901858519360621922', 'uuid-lslong': '9404308705111533120'}, 'enable': 'true', 'created': '2025-08-04T02:59:16', 'last-modified': '2025-08-04T02:59:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.796422'}
2025-08-04 02:59:19,556 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:19,556 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-72778652 passed
2025-08-04 02:59:19,556 - DEBUG - ====Verifying policy data for ctest-VN3-72778652 in API_Server ======
2025-08-04 02:59:19,556 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:19,564 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:19,582 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:19,691 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/974b2e90-160e-4d62-8282-d0f0ee496640
2025-08-04 02:59:19,706 - DEBUG - =>VN ctest-VN3-72778652 has no policy to be verified
2025-08-04 02:59:19,706 - DEBUG - Verifying the vn in opserver
2025-08-04 02:59:19,706 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652 virtual network link through opserver 10.0.0.27
2025-08-04 02:59:19,706 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:19,767 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652?flat'}
2025-08-04 02:59:19,767 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652 virtual network link through opserver 10.0.0.38
2025-08-04 02:59:19,767 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:19,829 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652?flat'}
2025-08-04 02:59:19,829 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652 virtual network link through opserver 10.0.0.61
2025-08-04 02:59:19,829 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:19,897 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652?flat'}
2025-08-04 02:59:19,897 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-72778652 is found in opserver
2025-08-04 02:59:19,927 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 02:59:19,940 - DEBUG - VRF ids for VN ctest-VN3-72778652: {}
2025-08-04 02:59:19,948 - DEBUG - No source network defined
2025-08-04 02:59:19,948 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = ip_prefix = 13.96.135.128, ip_prefix_len = 26, virtual_network = None, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, 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-TestPolicyAcl-76815164:ctest-VN1-68866152, 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-TestPolicyAcl-76815164:ctest-VN2-97022920, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-04 02:59:20,256 - DEBUG - No destination network defined
2025-08-04 02:59:20,256 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = ip_prefix = 13.96.135.128, ip_prefix_len = 26, virtual_network = None, 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-TestPolicyAcl-76815164:ctest-VN2-97022920, 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-TestPolicyAcl-76815164:ctest-VN1-68866152, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-04 02:59:20,495 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:20,504 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:20,518 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:20,626 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:20,637 - DEBUG - Setup step: Associating the policy to VN'
2025-08-04 02:59:20,747 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '9a47a414-2ef6-496a-b3cd-d3dd56b3567f', 'name': 'ctest-VN1-68866152', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['cca7f95b-1477-4646-bc41-e25ebc42c552'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN1-68866152'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy12-79046771']], 'subnet_ipam': [{'subnet_cidr': '13.96.135.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ipam1']}], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:20,860 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy12-79046771']] to ctest-VN1-68866152
2025-08-04 02:59:20,860 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:20,867 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:20,881 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:20,972 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:20,982 - DEBUG - Setup step: Associating the policy to VN'
2025-08-04 02:59:21,103 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '8950c509-0a92-4dd4-bf33-3597d88ccdf4', 'name': 'ctest-VN2-97022920', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['28a46e9e-1d24-4777-874d-ca95e770746e'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN2-97022920'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy21-02627602']], 'subnet_ipam': [{'subnet_cidr': '221.172.191.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:21,252 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy21-02627602']] to ctest-VN2-97022920
2025-08-04 02:59:21,354 - DEBUG - Services list from nova: [, , , ]
2025-08-04 02:59:21,383 - DEBUG - Image by name ubuntu not found
2025-08-04 02:59:21,383 - DEBUG - Installing image ubuntu
2025-08-04 02:59:21,383 - DEBUG - Image info {'name': 'ubuntu.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'ubuntu', 'password': 'ubuntu', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-04 02:59:21,383 - DEBUG - Image build path file:///contrail-test/images/ubuntu.vmdk.gz
2025-08-04 02:59:21,384 - DEBUG - Download image from local file file:///contrail-test/images/ubuntu.vmdk.gz
2025-08-04 02:59:21,384 - DEBUG - Unzip image
2025-08-04 02:59:21,384 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu.vmdk.gz
2025-08-04 02:59:21,401 - INFO - image /contrail-test/images/ubuntu.vmdk is not found
2025-08-04 02:59:21,402 - DEBUG - Detaching the Policy for VN :ctest-VN2-97022920
2025-08-04 02:59:21,402 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:21,412 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:21,426 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:21,531 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8950c509-0a92-4dd4-bf33-3597d88ccdf4
2025-08-04 02:59:21,546 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/542ea78e-9565-4384-a365-a697b2b57fd8
2025-08-04 02:59:21,717 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy21-02627602']] from ctest-VN2-97022920
2025-08-04 02:59:21,718 - DEBUG - Detaching the Policy for VN :ctest-VN1-68866152
2025-08-04 02:59:21,718 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:21,726 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:21,744 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:21,848 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a47a414-2ef6-496a-b3cd-d3dd56b3567f
2025-08-04 02:59:21,866 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/22ece254-9d2d-4417-ae57-7466a9a8f128
2025-08-04 02:59:22,076 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy12-79046771']] from ctest-VN1-68866152
2025-08-04 02:59:22,137 - INFO - Deleted policy ctest-policy21-02627602
2025-08-04 02:59:22,198 - INFO - Deleted policy ctest-policy12-79046771
2025-08-04 02:59:22,198 - INFO - Deleting VN ctest-VN3-72778652
2025-08-04 02:59:22,356 - DEBUG - Response for deleting network ()
2025-08-04 02:59:22,356 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fb56666b-76f3-469d-948a-d0aa10298f95
2025-08-04 02:59:22,363 - DEBUG - Response Code: 404
2025-08-04 02:59:22,364 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:22,370 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:22,394 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:22,504 - INFO - Validated that VN ctest-VN3-72778652 is not found in API Server
2025-08-04 02:59:22,517 - DEBUG - VN ctest-VN3-72778652 is not present in Agent 10.0.0.41
2025-08-04 02:59:22,531 - DEBUG - VN ctest-VN3-72778652 is not present in Agent 10.0.0.57
2025-08-04 02:59:22,531 - INFO - Validated that VN ctest-VN3-72778652 is not in any agent
2025-08-04 02:59:22,568 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-72778652 info
2025-08-04 02:59:22,569 - INFO - Deleting VN ctest-VN2-97022920
2025-08-04 02:59:22,785 - DEBUG - Response for deleting network ()
2025-08-04 02:59:22,786 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2c8ea387-bf54-48f1-8dcf-ba4a616073ef
2025-08-04 02:59:22,795 - DEBUG - Response Code: 404
2025-08-04 02:59:22,796 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:22,815 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:22,833 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:22,932 - INFO - Validated that VN ctest-VN2-97022920 is not found in API Server
2025-08-04 02:59:22,945 - DEBUG - VN ctest-VN2-97022920 is not present in Agent 10.0.0.41
2025-08-04 02:59:22,957 - DEBUG - VN ctest-VN2-97022920 is not present in Agent 10.0.0.57
2025-08-04 02:59:22,957 - INFO - Validated that VN ctest-VN2-97022920 is not in any agent
2025-08-04 02:59:23,001 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-97022920 info
2025-08-04 02:59:23,001 - INFO - Deleting VN ctest-VN1-68866152
2025-08-04 02:59:23,166 - DEBUG - Response for deleting network ()
2025-08-04 02:59:23,166 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/662677c7-b2b1-4b0d-9e66-d977c8d1e527
2025-08-04 02:59:23,173 - DEBUG - Response Code: 404
2025-08-04 02:59:23,173 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:23,191 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:23,217 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:23,316 - INFO - Validated that VN ctest-VN1-68866152 is not found in API Server
2025-08-04 02:59:23,334 - DEBUG - VN ctest-VN1-68866152 is not present in Agent 10.0.0.41
2025-08-04 02:59:23,348 - DEBUG - VN ctest-VN1-68866152 is not present in Agent 10.0.0.57
2025-08-04 02:59:23,348 - INFO - Validated that VN ctest-VN1-68866152 is not in any agent
2025-08-04 02:59:23,389 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-68866152 info
2025-08-04 02:59:23,492 - INFO - IPAM: ipam3 is not found in API Server
2025-08-04 02:59:23,520 - INFO - IPAM:ipam3 is not found in control node
2025-08-04 02:59:23,659 - INFO - IPAM: ipam2 is not found in API Server
2025-08-04 02:59:23,686 - INFO - IPAM:ipam2 is not found in control node
2025-08-04 02:59:23,832 - INFO - IPAM: ipam1 is not found in API Server
2025-08-04 02:59:23,865 - INFO - IPAM:ipam1 is not found in control node
2025-08-04 02:59:25,155 - DEBUG - No XMPP flaps were noticed during the test
2025-08-04 02:59:25,155 - INFO -
2025-08-04 02:59:25,155 - INFO - END TEST : test_policy_cidr_src_cidr_dst_any : SKIPPED[0:00:14]
2025-08-04 02:59:25,155 - INFO - --------------------------------------------------------------------------------
2025-08-04 02:59:25,159 - INFO - ================================================================================
2025-08-04 02:59:25,159 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol
2025-08-04 02:59:25,159 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2025-08-04 02:59:26,443 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.57': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-08-04 02:59:26,443 - INFO - Initial checks done. Running the testcase now
2025-08-04 02:59:26,444 - INFO -
2025-08-04 02:59:27,408 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:27,416 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:27,429 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:27,537 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/6032d727-4aea-44af-9613-d9a372560bb5
2025-08-04 02:59:27,545 - INFO - Verifications in API Server for IPAM: ipam1 passed
2025-08-04 02:59:27,554 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6931839340237178031', 'uuid-lslong': '10814226426267831221'}, 'enable': 'true', 'created': '2025-08-04T02:59:26', 'last-modified': '2025-08-04T02:59:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.845436'}
2025-08-04 02:59:27,560 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6931839340237178031', 'uuid-lslong': '10814226426267831221'}, 'enable': 'true', 'created': '2025-08-04T02:59:26', 'last-modified': '2025-08-04T02:59:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.853504'}
2025-08-04 02:59:27,567 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6931839340237178031', 'uuid-lslong': '10814226426267831221'}, 'enable': 'true', 'created': '2025-08-04T02:59:26', 'last-modified': '2025-08-04T02:59:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.857447'}
2025-08-04 02:59:27,567 - INFO - Verifications in Control node for IPAM: ipam1 passed
2025-08-04 02:59:27,567 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:27,574 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:27,588 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:27,686 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/224ac665-6b32-466b-82d4-5c3b1eb0a588
2025-08-04 02:59:27,699 - INFO - Verifications in API Server for IPAM: ipam2 passed
2025-08-04 02:59:27,706 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2471005484458591851', 'uuid-lslong': '9427261328981009800'}, 'enable': 'true', 'created': '2025-08-04T02:59:26', 'last-modified': '2025-08-04T02:59:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.668151'}
2025-08-04 02:59:27,714 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2471005484458591851', 'uuid-lslong': '9427261328981009800'}, 'enable': 'true', 'created': '2025-08-04T02:59:26', 'last-modified': '2025-08-04T02:59:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.670529'}
2025-08-04 02:59:27,722 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2471005484458591851', 'uuid-lslong': '9427261328981009800'}, 'enable': 'true', 'created': '2025-08-04T02:59:26', 'last-modified': '2025-08-04T02:59:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.685350'}
2025-08-04 02:59:27,722 - INFO - Verifications in Control node for IPAM: ipam2 passed
2025-08-04 02:59:27,722 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:27,740 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:27,758 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:27,873 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/b5aa2983-3269-4231-a73e-bc6fde680963
2025-08-04 02:59:27,883 - INFO - Verifications in API Server for IPAM: ipam3 passed
2025-08-04 02:59:27,892 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13090320910368981553', 'uuid-lslong': '12051276841548777827'}, 'enable': 'true', 'created': '2025-08-04T02:59:27', 'last-modified': '2025-08-04T02:59:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.510980'}
2025-08-04 02:59:27,901 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13090320910368981553', 'uuid-lslong': '12051276841548777827'}, 'enable': 'true', 'created': '2025-08-04T02:59:27', 'last-modified': '2025-08-04T02:59:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520504'}
2025-08-04 02:59:27,912 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-76815164:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13090320910368981553', 'uuid-lslong': '12051276841548777827'}, 'enable': 'true', 'created': '2025-08-04T02:59:27', 'last-modified': '2025-08-04T02:59:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.528125'}
2025-08-04 02:59:27,912 - INFO - Verifications in Control node for IPAM: ipam3 passed
2025-08-04 02:59:28,255 - DEBUG - Response for create_network : {'network': {'id': '9024c057-16c7-4e98-ab8a-fe91a847baea', 'name': 'ctest-VN1-80683907', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN1-80683907'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:28,547 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e224030c-9dd2-4e00-ba1a-895f214aaa3e', 'name': '', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'network_id': '9024c057-16c7-4e98-ab8a-fe91a847baea', 'ip_version': 4, 'cidr': '8.209.219.0/26', 'allocation_pools': [{'start': '8.209.219.2', 'end': '8.209.219.62'}], 'gateway_ip': '8.209.219.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '8.209.219.2', 'tags': [], 'project_id': '22641a4155bc4646ad675a86da311710'}}
2025-08-04 02:59:28,573 - INFO - Created VN ctest-VN1-80683907
2025-08-04 02:59:28,591 - DEBUG - VN ctest-VN1-80683907 UUID is 9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:28,744 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907(9024c057-16c7-4e98-ab8a-fe91a847baea) with subnets ['8.209.219.0/26']
2025-08-04 02:59:29,159 - DEBUG - Response for create_network : {'network': {'id': 'f6799754-f6d4-4e7a-a14b-7324df800957', 'name': 'ctest-VN2-68017943', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN2-68017943'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:29,429 - DEBUG - Response for create_subnet : {'subnet': {'id': '3ff3f8c5-d02b-4959-8e44-eed313b1856d', 'name': '', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'network_id': 'f6799754-f6d4-4e7a-a14b-7324df800957', 'ip_version': 4, 'cidr': '95.100.234.0/26', 'allocation_pools': [{'start': '95.100.234.2', 'end': '95.100.234.62'}], 'gateway_ip': '95.100.234.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.100.234.2', 'tags': [], 'project_id': '22641a4155bc4646ad675a86da311710'}}
2025-08-04 02:59:29,457 - INFO - Created VN ctest-VN2-68017943
2025-08-04 02:59:29,470 - DEBUG - VN ctest-VN2-68017943 UUID is f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:29,918 - DEBUG - Response for create_network : {'network': {'id': '6cbbe34a-e5f0-4d51-abad-7546bae7712f', 'name': 'ctest-VN3-47154532', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN3-47154532'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:30,261 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd1c70e67-2e0d-4a90-9901-4e0d4d7adeb8', 'name': '', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'network_id': '6cbbe34a-e5f0-4d51-abad-7546bae7712f', 'ip_version': 4, 'cidr': '63.23.89.0/26', 'allocation_pools': [{'start': '63.23.89.2', 'end': '63.23.89.62'}], 'gateway_ip': '63.23.89.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.23.89.2', 'tags': [], 'project_id': '22641a4155bc4646ad675a86da311710'}}
2025-08-04 02:59:30,291 - INFO - Created VN ctest-VN3-47154532
2025-08-04 02:59:30,311 - DEBUG - VN ctest-VN3-47154532 UUID is 6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:30,392 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:30,402 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:30,417 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:30,548 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:30,568 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:30,580 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b7696193-c619-4d82-b5a9-6fc7b235d5bf
2025-08-04 02:59:30,589 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b7696193-c619-4d82-b5a9-6fc7b235d5bf
2025-08-04 02:59:30,597 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/82e1e24a-a058-466f-a22f-402cb7c261ed
2025-08-04 02:59:30,607 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:30,607 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:30,624 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b7696193-c619-4d82-b5a9-6fc7b235d5bf
2025-08-04 02:59:30,649 - INFO - Verified VN network id 13 for VN 9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:30,650 - INFO - Verifications in API Server for VN ctest-VN1-80683907 passed
2025-08-04 02:59:30,650 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:30,664 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b7696193-c619-4d82-b5a9-6fc7b235d5bf
2025-08-04 02:59:30,673 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b7696193-c619-4d82-b5a9-6fc7b235d5bf
2025-08-04 02:59:30,685 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/82e1e24a-a058-466f-a22f-402cb7c261ed
2025-08-04 02:59:30,712 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10386638120900054680', 'uuid-lslong': '12360972028818733802'}, 'enable': 'true', 'created': '2025-08-04T02:59:28', 'last-modified': '2025-08-04T02:59:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.238635'}
2025-08-04 02:59:30,724 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:30,737 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10386638120900054680', 'uuid-lslong': '12360972028818733802'}, 'enable': 'true', 'created': '2025-08-04T02:59:28', 'last-modified': '2025-08-04T02:59:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.266198'}
2025-08-04 02:59:30,756 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:30,768 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10386638120900054680', 'uuid-lslong': '12360972028818733802'}, 'enable': 'true', 'created': '2025-08-04T02:59:28', 'last-modified': '2025-08-04T02:59:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.292859'}
2025-08-04 02:59:30,781 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 02:59:30,781 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-80683907 passed
2025-08-04 02:59:30,781 - DEBUG - ====Verifying policy data for ctest-VN1-80683907 in API_Server ======
2025-08-04 02:59:30,781 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:30,788 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:30,802 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:30,900 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:30,921 - DEBUG - =>VN ctest-VN1-80683907 has no policy to be verified
2025-08-04 02:59:30,921 - DEBUG - Verifying the vn in opserver
2025-08-04 02:59:30,922 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907 virtual network link through opserver 10.0.0.27
2025-08-04 02:59:30,922 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:30,940 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907?flat'}
2025-08-04 02:59:30,940 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907 virtual network link through opserver 10.0.0.38
2025-08-04 02:59:30,940 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:30,956 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907?flat'}
2025-08-04 02:59:30,956 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907 virtual network link through opserver 10.0.0.61
2025-08-04 02:59:30,956 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:30,977 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907?flat'}
2025-08-04 02:59:30,977 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907 is found in opserver
2025-08-04 02:59:31,003 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 02:59:31,016 - DEBUG - VRF ids for VN ctest-VN1-80683907: {}
2025-08-04 02:59:31,016 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:31,024 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:31,046 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:31,150 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:31,171 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:31,185 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bca78626-d9f9-4241-bd06-d659c916e2fa
2025-08-04 02:59:31,196 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bca78626-d9f9-4241-bd06-d659c916e2fa
2025-08-04 02:59:31,206 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/ebb0fdbc-0781-47e1-b8cc-5f3f61d73a10
2025-08-04 02:59:31,214 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:31,214 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:31,225 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bca78626-d9f9-4241-bd06-d659c916e2fa
2025-08-04 02:59:31,244 - INFO - Verified VN network id 14 for VN f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:31,244 - INFO - Verifications in API Server for VN ctest-VN2-68017943 passed
2025-08-04 02:59:31,244 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:31,258 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bca78626-d9f9-4241-bd06-d659c916e2fa
2025-08-04 02:59:31,267 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bca78626-d9f9-4241-bd06-d659c916e2fa
2025-08-04 02:59:31,278 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/ebb0fdbc-0781-47e1-b8cc-5f3f61d73a10
2025-08-04 02:59:31,295 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17760392996686417530', 'uuid-lslong': '11622509865565423959'}, 'enable': 'true', 'created': '2025-08-04T02:59:29', 'last-modified': '2025-08-04T02:59:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.954837'}
2025-08-04 02:59:31,304 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:31,310 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17760392996686417530', 'uuid-lslong': '11622509865565423959'}, 'enable': 'true', 'created': '2025-08-04T02:59:29', 'last-modified': '2025-08-04T02:59:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.967405'}
2025-08-04 02:59:31,318 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:31,324 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17760392996686417530', 'uuid-lslong': '11622509865565423959'}, 'enable': 'true', 'created': '2025-08-04T02:59:29', 'last-modified': '2025-08-04T02:59:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.987800'}
2025-08-04 02:59:31,338 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 02:59:31,338 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-68017943 passed
2025-08-04 02:59:31,338 - DEBUG - ====Verifying policy data for ctest-VN2-68017943 in API_Server ======
2025-08-04 02:59:31,338 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:31,345 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:31,358 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:31,454 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:31,467 - DEBUG - =>VN ctest-VN2-68017943 has no policy to be verified
2025-08-04 02:59:31,467 - DEBUG - Verifying the vn in opserver
2025-08-04 02:59:31,467 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943 virtual network link through opserver 10.0.0.27
2025-08-04 02:59:31,467 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:31,485 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943?flat'}
2025-08-04 02:59:31,485 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943 virtual network link through opserver 10.0.0.38
2025-08-04 02:59:31,485 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:31,501 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943?flat'}
2025-08-04 02:59:31,501 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943 virtual network link through opserver 10.0.0.61
2025-08-04 02:59:31,501 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:31,516 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943?flat'}
2025-08-04 02:59:31,516 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943 is found in opserver
2025-08-04 02:59:31,543 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 02:59:31,559 - DEBUG - VRF ids for VN ctest-VN2-68017943: {}
2025-08-04 02:59:31,559 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:31,567 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:31,580 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:31,668 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:31,689 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:31,700 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcc1df7f-6246-4d11-a089-277ee329b10c
2025-08-04 02:59:31,709 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcc1df7f-6246-4d11-a089-277ee329b10c
2025-08-04 02:59:31,718 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/a3c28606-c7a3-4eac-b8fe-f8b6a072457e
2025-08-04 02:59:31,726 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:31,726 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:31,738 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcc1df7f-6246-4d11-a089-277ee329b10c
2025-08-04 02:59:31,759 - INFO - Verified VN network id 15 for VN 6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:31,759 - INFO - Verifications in API Server for VN ctest-VN3-47154532 passed
2025-08-04 02:59:31,759 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:31,772 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcc1df7f-6246-4d11-a089-277ee329b10c
2025-08-04 02:59:31,783 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcc1df7f-6246-4d11-a089-277ee329b10c
2025-08-04 02:59:31,793 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/a3c28606-c7a3-4eac-b8fe-f8b6a072457e
2025-08-04 02:59:31,809 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7835105887565925713', 'uuid-lslong': '12370672698100511023'}, 'enable': 'true', 'created': '2025-08-04T02:59:29', 'last-modified': '2025-08-04T02:59:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.635396'}
2025-08-04 02:59:31,818 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:31,827 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7835105887565925713', 'uuid-lslong': '12370672698100511023'}, 'enable': 'true', 'created': '2025-08-04T02:59:29', 'last-modified': '2025-08-04T02:59:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.656207'}
2025-08-04 02:59:31,835 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:31,844 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-76815164', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7835105887565925713', 'uuid-lslong': '12370672698100511023'}, 'enable': 'true', 'created': '2025-08-04T02:59:29', 'last-modified': '2025-08-04T02:59:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.658209'}
2025-08-04 02:59:31,852 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-04 02:59:31,852 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-47154532 passed
2025-08-04 02:59:31,852 - DEBUG - ====Verifying policy data for ctest-VN3-47154532 in API_Server ======
2025-08-04 02:59:31,852 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:31,860 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:31,875 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:31,986 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6cbbe34a-e5f0-4d51-abad-7546bae7712f
2025-08-04 02:59:31,998 - DEBUG - =>VN ctest-VN3-47154532 has no policy to be verified
2025-08-04 02:59:31,998 - DEBUG - Verifying the vn in opserver
2025-08-04 02:59:31,998 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532 virtual network link through opserver 10.0.0.27
2025-08-04 02:59:31,998 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 02:59:32,017 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532?flat'}
2025-08-04 02:59:32,017 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532 virtual network link through opserver 10.0.0.38
2025-08-04 02:59:32,017 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 02:59:32,044 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532?flat'}
2025-08-04 02:59:32,045 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532 virtual network link through opserver 10.0.0.61
2025-08-04 02:59:32,045 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 02:59:32,062 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532?flat'}
2025-08-04 02:59:32,062 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-76815164:ctest-VN3-47154532 is found in opserver
2025-08-04 02:59:32,088 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 02:59:32,101 - DEBUG - VRF ids for VN ctest-VN3-47154532: {}
2025-08-04 02:59:32,109 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907, 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-TestPolicyAcl-76815164:ctest-VN2-68017943, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-04 02:59:32,431 - DEBUG - No destination network defined
2025-08-04 02:59:32,432 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-76815164:ctest-VN2-68017943, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = None, security_group = None, network_policy = default-domain:ctest-TestPolicyAcl-76815164:ctest-policy13-49291592, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-04 02:59:32,748 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-76815164:ctest-VN1-80683907, 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-TestPolicyAcl-76815164:ctest-VN3-47154532, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-04 02:59:32,998 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:33,007 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:33,022 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:33,129 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:33,148 - DEBUG - Setup step: Associating the policy to VN'
2025-08-04 02:59:33,424 - INFO - Associated Policy to ctest-VN1-80683907
2025-08-04 02:59:33,424 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:33,434 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:33,455 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:33,571 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:33,586 - DEBUG - Setup step: Associating the policy to VN'
2025-08-04 02:59:33,725 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f6799754-f6d4-4e7a-a14b-7324df800957', 'name': 'ctest-VN2-68017943', 'tenant_id': '22641a4155bc4646ad675a86da311710', 'project_id': '22641a4155bc4646ad675a86da311710', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3ff3f8c5-d02b-4959-8e44-eed313b1856d'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-VN2-68017943'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy21-83151902']], 'subnet_ipam': [{'subnet_cidr': '95.100.234.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-76815164', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2025-08-04 02:59:33,851 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy21-83151902']] to ctest-VN2-68017943
2025-08-04 02:59:33,960 - DEBUG - Image by name ubuntu not found
2025-08-04 02:59:33,961 - DEBUG - Installing image ubuntu
2025-08-04 02:59:33,961 - DEBUG - Image info {'name': 'ubuntu.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'ubuntu', 'password': 'ubuntu', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-04 02:59:33,961 - DEBUG - Image build path file:///contrail-test/images/ubuntu.vmdk.gz
2025-08-04 02:59:33,961 - DEBUG - Download image from local file file:///contrail-test/images/ubuntu.vmdk.gz
2025-08-04 02:59:33,961 - DEBUG - Unzip image
2025-08-04 02:59:33,961 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu.vmdk.gz
2025-08-04 02:59:33,980 - INFO - image /contrail-test/images/ubuntu.vmdk is not found
2025-08-04 02:59:33,981 - DEBUG - Detaching the Policy for VN :ctest-VN2-68017943
2025-08-04 02:59:33,981 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:33,994 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:34,009 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:34,115 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f6799754-f6d4-4e7a-a14b-7324df800957
2025-08-04 02:59:34,130 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/a32d2f6b-9435-4624-adc1-8326ef69de5e
2025-08-04 02:59:34,337 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-76815164', 'ctest-policy21-83151902']] from ctest-VN2-68017943
2025-08-04 02:59:34,337 - DEBUG - Detaching the Policy for VN :ctest-VN1-80683907
2025-08-04 02:59:34,337 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:34,347 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:34,381 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:34,506 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9024c057-16c7-4e98-ab8a-fe91a847baea
2025-08-04 02:59:34,732 - INFO - Detached Policy from ctest-VN1-80683907
2025-08-04 02:59:34,792 - INFO - Deleted policy ctest-policy13-49291592
2025-08-04 02:59:34,860 - INFO - Deleted policy ctest-policy21-83151902
2025-08-04 02:59:34,918 - INFO - Deleted policy ctest-policy12-12700498
2025-08-04 02:59:34,918 - INFO - Deleting VN ctest-VN3-47154532
2025-08-04 02:59:35,124 - DEBUG - Response for deleting network ()
2025-08-04 02:59:35,125 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/fcc1df7f-6246-4d11-a089-277ee329b10c
2025-08-04 02:59:35,148 - DEBUG - Response Code: 404
2025-08-04 02:59:35,148 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:35,161 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:35,184 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:35,312 - INFO - Validated that VN ctest-VN3-47154532 is not found in API Server
2025-08-04 02:59:35,327 - DEBUG - VN ctest-VN3-47154532 is not present in Agent 10.0.0.41
2025-08-04 02:59:35,345 - DEBUG - VN ctest-VN3-47154532 is not present in Agent 10.0.0.57
2025-08-04 02:59:35,345 - INFO - Validated that VN ctest-VN3-47154532 is not in any agent
2025-08-04 02:59:35,415 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-47154532 info
2025-08-04 02:59:35,415 - INFO - Deleting VN ctest-VN2-68017943
2025-08-04 02:59:35,594 - DEBUG - Response for deleting network ()
2025-08-04 02:59:35,594 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bca78626-d9f9-4241-bd06-d659c916e2fa
2025-08-04 02:59:35,609 - DEBUG - Response Code: 404
2025-08-04 02:59:35,610 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:35,621 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:35,647 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:35,757 - INFO - Validated that VN ctest-VN2-68017943 is not found in API Server
2025-08-04 02:59:35,774 - DEBUG - VN ctest-VN2-68017943 is not present in Agent 10.0.0.41
2025-08-04 02:59:35,790 - DEBUG - VN ctest-VN2-68017943 is not present in Agent 10.0.0.57
2025-08-04 02:59:35,790 - INFO - Validated that VN ctest-VN2-68017943 is not in any agent
2025-08-04 02:59:35,848 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-68017943 info
2025-08-04 02:59:35,848 - INFO - Deleting VN ctest-VN1-80683907
2025-08-04 02:59:36,001 - DEBUG - Response for deleting network ()
2025-08-04 02:59:36,001 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b7696193-c619-4d82-b5a9-6fc7b235d5bf
2025-08-04 02:59:36,017 - DEBUG - Response Code: 404
2025-08-04 02:59:36,017 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 02:59:36,029 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 02:59:36,046 - DEBUG - Requesting: http://10.0.0.27:8082/project/22641a41-55bc-4646-ad67-5a86da311710
2025-08-04 02:59:36,170 - INFO - Validated that VN ctest-VN1-80683907 is not found in API Server
2025-08-04 02:59:36,191 - DEBUG - VN ctest-VN1-80683907 is not present in Agent 10.0.0.41
2025-08-04 02:59:36,205 - DEBUG - VN ctest-VN1-80683907 is not present in Agent 10.0.0.57
2025-08-04 02:59:36,205 - INFO - Validated that VN ctest-VN1-80683907 is not in any agent
2025-08-04 02:59:36,269 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-80683907 info
2025-08-04 02:59:36,384 - INFO - IPAM: ipam3 is not found in API Server
2025-08-04 02:59:36,429 - INFO - IPAM:ipam3 is not found in control node
2025-08-04 02:59:36,560 - INFO - IPAM: ipam2 is not found in API Server
2025-08-04 02:59:36,596 - INFO - IPAM:ipam2 is not found in control node
2025-08-04 02:59:36,740 - INFO - IPAM: ipam1 is not found in API Server
2025-08-04 02:59:36,771 - INFO - IPAM:ipam1 is not found in control node
2025-08-04 02:59:38,070 - DEBUG - No XMPP flaps were noticed during the test
2025-08-04 02:59:38,070 - INFO -
2025-08-04 02:59:38,070 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : SKIPPED[0:00:13]
2025-08-04 02:59:38,070 - INFO - --------------------------------------------------------------------------------
2025-08-04 02:59:38,864 - INFO - Deleted project: ctest-TestPolicyAcl-76815164, ID : 22641a41-55bc-4646-ad67-5a86da311710