2025-08-04 03:03:00,369 - INFO - Domain Default found not creating
2025-08-04 03:03:00,537 - INFO - Project ctest-TestDetailedPolicy1-91113546 not found, creating it
2025-08-04 03:03:01,099 - INFO - Created Project:ctest-TestDetailedPolicy1-91113546, ID : 5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:02,918 - INFO - ================================================================================
2025-08-04 03:03:02,918 - INFO - STARTING TEST : test_multi_vn_repeated_policy_update_with_ping
2025-08-04 03:03:02,918 - INFO - TEST DESCRIPTION : Call repeated_policy_update_test_with_ping with multi VN scenario.
2025-08-04 03:03:04,216 - 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 03:03:04,216 - INFO - Initial checks done. Running the testcase now
2025-08-04 03:03:04,216 - INFO -
2025-08-04 03:03:04,216 - INFO - Scenario for the test used is:
2025-08-04 03:03:04,217 - DEBUG - Starting setup
2025-08-04 03:03:05,010 - INFO - Using existing project ['default-domain', 'ctest-TestDetailedPolicy1-91113546'](5002257d-aa98-46f4-b9d9-3d40637d8400)
2025-08-04 03:03:05,010 - INFO - provision user admin with role as admin in tenant ctest-TestDetailedPolicy1-91113546
2025-08-04 03:03:06,112 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:06,464 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:06,539 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:06,560 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:06,659 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-04 03:03:06,971 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-04 03:03:07,013 - DEBUG - Requesting: http://10.0.0.38:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:07,039 - DEBUG - Requesting: http://10.0.0.38:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:07,142 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-08-04 03:03:07,453 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-08-04 03:03:07,490 - DEBUG - Requesting: http://10.0.0.61:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:07,507 - DEBUG - Requesting: http://10.0.0.61:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:07,604 - INFO - Verification of project ctest-TestDetailedPolicy1-91113546 in API Server 10.0.0.61 passed
2025-08-04 03:03:07,900 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:07,908 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:07,922 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:08,022 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/c6803470-8c57-415b-8695-0603009a3865
2025-08-04 03:03:08,034 - INFO - Verifications in API Server for IPAM: ctest-TestDetailedPolicy1-91113546-default-ipam passed
2025-08-04 03:03:08,044 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-TestDetailedPolicy1-91113546-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestDetailedPolicy1-91113546', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14303490074524205403', 'uuid-lslong': '9697663982577006693'}, 'enable': 'true', 'created': '2025-08-04T03:03:07', 'last-modified': '2025-08-04T03:03:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.172466'}
2025-08-04 03:03:08,053 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-TestDetailedPolicy1-91113546-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestDetailedPolicy1-91113546', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14303490074524205403', 'uuid-lslong': '9697663982577006693'}, 'enable': 'true', 'created': '2025-08-04T03:03:07', 'last-modified': '2025-08-04T03:03:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.180628'}
2025-08-04 03:03:08,060 - DEBUG - Control-node 10.0.0.61 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-TestDetailedPolicy1-91113546-default-ipam', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestDetailedPolicy1-91113546', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14303490074524205403', 'uuid-lslong': '9697663982577006693'}, 'enable': 'true', 'created': '2025-08-04T03:03:07', 'last-modified': '2025-08-04T03:03:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.190079'}
2025-08-04 03:03:08,060 - INFO - Verifications in Control node for IPAM: ctest-TestDetailedPolicy1-91113546-default-ipam passed
2025-08-04 03:03:08,751 - DEBUG - Response for create_network : {'network': {'id': '8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0', 'name': 'ctest-vnet0-10629781', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'project_id': '5002257daa9846f4b9d93d40637d8400', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-vnet0-10629781'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:03:09,165 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a3278dad-51c7-4eed-a441-df0716cb1785', 'name': '', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'network_id': '8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '5002257daa9846f4b9d93d40637d8400'}}
2025-08-04 03:03:09,381 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b0084ae8-0bb7-4f9b-9572-955c6fd41bf2', 'name': '', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'network_id': '8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0', 'ip_version': 4, 'cidr': '11.1.1.0/24', 'allocation_pools': [{'start': '11.1.1.2', 'end': '11.1.1.254'}], 'gateway_ip': '11.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.1.1.2', 'tags': [], 'project_id': '5002257daa9846f4b9d93d40637d8400'}}
2025-08-04 03:03:09,412 - INFO - Created VN ctest-vnet0-10629781
2025-08-04 03:03:09,430 - DEBUG - VN ctest-vnet0-10629781 UUID is 8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:09,556 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:09,854 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:09,891 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:09,905 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:10,006 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:10,027 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:10,047 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/73ed1f68-e0b4-4afa-9d57-ca08f2931fc3
2025-08-04 03:03:10,058 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/73ed1f68-e0b4-4afa-9d57-ca08f2931fc3
2025-08-04 03:03:10,068 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/5f23f841-93d1-4905-9141-39bbbbf5e8f1
2025-08-04 03:03:10,081 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 03:03:10,081 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:10,097 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/73ed1f68-e0b4-4afa-9d57-ca08f2931fc3
2025-08-04 03:03:10,126 - INFO - Verified VN network id 13 for VN 8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:10,126 - INFO - Verifications in API Server for VN ctest-vnet0-10629781 passed
2025-08-04 03:03:10,126 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:10,143 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/73ed1f68-e0b4-4afa-9d57-ca08f2931fc3
2025-08-04 03:03:10,153 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/73ed1f68-e0b4-4afa-9d57-ca08f2931fc3
2025-08-04 03:03:10,163 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/5f23f841-93d1-4905-9141-39bbbbf5e8f1
2025-08-04 03:03:10,182 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'admin', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10092246773186118567', 'uuid-lslong': '10520562384368517584'}, 'enable': 'true', 'created': '2025-08-04T03:03:08', 'last-modified': '2025-08-04T03:03:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.852230'}
2025-08-04 03:03:10,191 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 03:03:10,199 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'admin', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10092246773186118567', 'uuid-lslong': '10520562384368517584'}, 'enable': 'true', 'created': '2025-08-04T03:03:08', 'last-modified': '2025-08-04T03:03:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.863611'}
2025-08-04 03:03:10,205 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 03:03:10,213 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'admin', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10092246773186118567', 'uuid-lslong': '10520562384368517584'}, 'enable': 'true', 'created': '2025-08-04T03:03:08', 'last-modified': '2025-08-04T03:03:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.883667'}
2025-08-04 03:03:10,223 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-04 03:03:10,223 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet0-10629781 passed
2025-08-04 03:03:10,223 - DEBUG - ====Verifying policy data for ctest-vnet0-10629781 in API_Server ======
2025-08-04 03:03:10,223 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:10,234 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:10,259 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:10,378 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:10,398 - DEBUG - =>VN ctest-vnet0-10629781 has no policy to be verified
2025-08-04 03:03:10,398 - DEBUG - Verifying the vn in opserver
2025-08-04 03:03:10,398 - DEBUG - Verifying the default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781 virtual network link through opserver 10.0.0.27
2025-08-04 03:03:10,399 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 03:03:10,701 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 03:03:10,774 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781?flat'}
2025-08-04 03:03:10,774 - DEBUG - Verifying the default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781 virtual network link through opserver 10.0.0.38
2025-08-04 03:03:10,774 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 03:03:11,079 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 03:03:11,146 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781?flat'}
2025-08-04 03:03:11,146 - DEBUG - Verifying the default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781 virtual network link through opserver 10.0.0.61
2025-08-04 03:03:11,146 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 03:03:11,457 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 03:03:11,532 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781?flat'}
2025-08-04 03:03:11,532 - INFO - Validated that VN default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet0-10629781 is found in opserver
2025-08-04 03:03:11,560 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 03:03:11,575 - DEBUG - VRF ids for VN ctest-vnet0-10629781: {}
2025-08-04 03:03:11,872 - DEBUG - Response for create_network : {'network': {'id': '3dc124a2-292c-4ba5-b4df-bc04d8d8a963', 'name': 'ctest-vnet1-12636930', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'project_id': '5002257daa9846f4b9d93d40637d8400', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-vnet1-12636930'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:03:12,142 - DEBUG - Response for create_subnet : {'subnet': {'id': '4852741b-09de-4684-95f0-b1250f2d1791', 'name': '', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'network_id': '3dc124a2-292c-4ba5-b4df-bc04d8d8a963', 'ip_version': 4, 'cidr': '12.1.1.0/24', 'allocation_pools': [{'start': '12.1.1.2', 'end': '12.1.1.254'}], 'gateway_ip': '12.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '12.1.1.2', 'tags': [], 'project_id': '5002257daa9846f4b9d93d40637d8400'}}
2025-08-04 03:03:12,344 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de1f4be5-42ad-4793-a9a6-5176b2fccfcb', 'name': '', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'network_id': '3dc124a2-292c-4ba5-b4df-bc04d8d8a963', 'ip_version': 4, 'cidr': '13.1.1.0/24', 'allocation_pools': [{'start': '13.1.1.2', 'end': '13.1.1.254'}], 'gateway_ip': '13.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.1.1.2', 'tags': [], 'project_id': '5002257daa9846f4b9d93d40637d8400'}}
2025-08-04 03:03:12,393 - INFO - Created VN ctest-vnet1-12636930
2025-08-04 03:03:12,413 - DEBUG - VN ctest-vnet1-12636930 UUID is 3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:12,548 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:12,559 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:12,577 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:12,682 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:12,702 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:12,717 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b103d17b-f360-4afe-9029-ec61aecb7314
2025-08-04 03:03:12,727 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b103d17b-f360-4afe-9029-ec61aecb7314
2025-08-04 03:03:12,737 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/4a2bb341-25b7-4db1-99ad-3ffab2e988bf
2025-08-04 03:03:12,747 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 03:03:12,748 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:12,761 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b103d17b-f360-4afe-9029-ec61aecb7314
2025-08-04 03:03:12,782 - INFO - Verified VN network id 14 for VN 3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:12,782 - INFO - Verifications in API Server for VN ctest-vnet1-12636930 passed
2025-08-04 03:03:12,783 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:12,797 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b103d17b-f360-4afe-9029-ec61aecb7314
2025-08-04 03:03:12,808 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b103d17b-f360-4afe-9029-ec61aecb7314
2025-08-04 03:03:12,817 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/4a2bb341-25b7-4db1-99ad-3ffab2e988bf
2025-08-04 03:03:12,833 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'admin', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4449878185712831397', 'uuid-lslong': '13033342575637473635'}, 'enable': 'true', 'created': '2025-08-04T03:03:11', 'last-modified': '2025-08-04T03:03:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.546330'}
2025-08-04 03:03:12,842 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 03:03:12,850 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'admin', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4449878185712831397', 'uuid-lslong': '13033342575637473635'}, 'enable': 'true', 'created': '2025-08-04T03:03:11', 'last-modified': '2025-08-04T03:03:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563364'}
2025-08-04 03:03:12,857 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 03:03:12,865 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'admin', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4449878185712831397', 'uuid-lslong': '13033342575637473635'}, 'enable': 'true', 'created': '2025-08-04T03:03:11', 'last-modified': '2025-08-04T03:03:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.578325'}
2025-08-04 03:03:12,873 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-04 03:03:12,873 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vnet1-12636930 passed
2025-08-04 03:03:12,874 - DEBUG - ====Verifying policy data for ctest-vnet1-12636930 in API_Server ======
2025-08-04 03:03:12,874 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:12,882 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:12,901 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:13,003 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:13,020 - DEBUG - =>VN ctest-vnet1-12636930 has no policy to be verified
2025-08-04 03:03:13,020 - DEBUG - Verifying the vn in opserver
2025-08-04 03:03:13,020 - DEBUG - Verifying the default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930 virtual network link through opserver 10.0.0.27
2025-08-04 03:03:13,021 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-04 03:03:13,090 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930?flat'}
2025-08-04 03:03:13,091 - DEBUG - Verifying the default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930 virtual network link through opserver 10.0.0.38
2025-08-04 03:03:13,093 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-04 03:03:13,155 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930?flat'}
2025-08-04 03:03:13,155 - DEBUG - Verifying the default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930 virtual network link through opserver 10.0.0.61
2025-08-04 03:03:13,155 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-08-04 03:03:13,221 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930?flat'}
2025-08-04 03:03:13,221 - INFO - Validated that VN default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930 is found in opserver
2025-08-04 03:03:13,247 - DEBUG - Do not have enough data to verify VN in agent
2025-08-04 03:03:13,260 - DEBUG - VRF ids for VN ctest-vnet1-12636930: {}
2025-08-04 03:03:13,267 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:13,556 - DEBUG - ====Verifying data for policy0 in API_Server ======
2025-08-04 03:03:13,556 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:13,565 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:13,585 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:13,698 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/480b2b42-e8d7-470d-b404-2c2f31943009
2025-08-04 03:03:13,713 - INFO - Verified policy policy0 in API Server, Result: True
2025-08-04 03:03:13,723 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:14,003 - DEBUG - ====Verifying data for policy1 in API_Server ======
2025-08-04 03:03:14,003 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:14,011 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:14,025 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:14,141 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/bcf36c57-86ac-4aa8-8635-b23c85bdf68c
2025-08-04 03:03:14,156 - INFO - Verified policy policy1 in API Server, Result: True
2025-08-04 03:03:14,164 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:14,412 - DEBUG - ====Verifying data for policy2 in API_Server ======
2025-08-04 03:03:14,412 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:14,420 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:14,435 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:14,537 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/16e36d86-f299-41f6-9904-9c0f60aca715
2025-08-04 03:03:14,552 - INFO - Verified policy policy2 in API Server, Result: True
2025-08-04 03:03:14,561 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:14,860 - DEBUG - ====Verifying data for policy3 in API_Server ======
2025-08-04 03:03:14,860 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:14,867 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:14,881 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:14,985 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/be4285c1-c3bb-4353-814b-281f7b01569a
2025-08-04 03:03:15,001 - INFO - Verified policy policy3 in API Server, Result: True
2025-08-04 03:03:15,011 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, 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 = None, virtual_network = any, 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 03:03:15,258 - DEBUG - ====Verifying data for policy100 in API_Server ======
2025-08-04 03:03:15,258 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:15,267 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:15,286 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:15,393 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/b70f9816-7461-4b0c-899d-ff2ede5190eb
2025-08-04 03:03:15,417 - INFO - Verified policy policy100 in API Server, Result: True
2025-08-04 03:03:15,425 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:15,711 - DEBUG - ====Verifying data for policy10 in API_Server ======
2025-08-04 03:03:15,711 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:15,719 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:15,736 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:15,843 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/613c0ee9-9e05-490d-beb4-44ffa04fd074
2025-08-04 03:03:15,857 - INFO - Verified policy policy10 in API Server, Result: True
2025-08-04 03:03:15,864 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:16,116 - DEBUG - ====Verifying data for policy11 in API_Server ======
2025-08-04 03:03:16,116 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:16,123 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:16,141 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:16,246 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/d9d00dab-9f35-410d-9f36-debf489fd492
2025-08-04 03:03:16,263 - INFO - Verified policy policy11 in API Server, Result: True
2025-08-04 03:03:16,272 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:16,547 - DEBUG - ====Verifying data for policy12 in API_Server ======
2025-08-04 03:03:16,548 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:16,555 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:16,568 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:16,669 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/e1da5eb3-913c-42a0-8bde-125271fd1a34
2025-08-04 03:03:16,681 - INFO - Verified policy policy12 in API Server, Result: True
2025-08-04 03:03:16,689 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = tcp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestDetailedPolicy1-91113546:ctest-vnet1-12636930, 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-TestDetailedPolicy1-91113546:ctest-vnet0-10629781, 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 03:03:16,919 - DEBUG - ====Verifying data for policy13 in API_Server ======
2025-08-04 03:03:16,920 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:16,929 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:16,945 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:17,055 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/03c52964-ffab-483f-85dc-24b999e45534
2025-08-04 03:03:17,068 - INFO - Verified policy policy13 in API Server, Result: True
2025-08-04 03:03:17,075 - DEBUG - Policy np_rules : []
2025-08-04 03:03:17,352 - DEBUG - ====Verifying data for policy1000 in API_Server ======
2025-08-04 03:03:17,352 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:17,361 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:17,380 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:17,495 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/21ddee5c-071c-4bf6-a039-3a7be6331ce3
2025-08-04 03:03:17,506 - INFO - Verified policy policy1000 in API Server, Result: True
2025-08-04 03:03:17,506 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:17,514 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:17,528 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:17,621 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:17,635 - DEBUG - Setup step: Associating the policy to VN'
2025-08-04 03:03:17,779 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0', 'name': 'ctest-vnet0-10629781', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'project_id': '5002257daa9846f4b9d93d40637d8400', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['a3278dad-51c7-4eed-a441-df0716cb1785', 'b0084ae8-0bb7-4f9b-9572-955c6fd41bf2'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-vnet0-10629781'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'policy0']], 'subnet_ipam': [{'subnet_cidr': '10.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-TestDetailedPolicy1-91113546-default-ipam']}, {'subnet_cidr': '11.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-TestDetailedPolicy1-91113546-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:03:17,897 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'policy0']] to ctest-vnet0-10629781
2025-08-04 03:03:17,897 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:17,906 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:17,921 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:18,024 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:18,037 - DEBUG - Setup step: Associating the policy to VN'
2025-08-04 03:03:18,168 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '3dc124a2-292c-4ba5-b4df-bc04d8d8a963', 'name': 'ctest-vnet1-12636930', 'tenant_id': '5002257daa9846f4b9d93d40637d8400', 'project_id': '5002257daa9846f4b9d93d40637d8400', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['4852741b-09de-4684-95f0-b1250f2d1791', 'de1f4be5-42ad-4793-a9a6-5176b2fccfcb'], 'fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-vnet1-12636930'], 'policys': [['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'policy100']], 'subnet_ipam': [{'subnet_cidr': '12.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-TestDetailedPolicy1-91113546-default-ipam']}, {'subnet_cidr': '13.1.1.0/24', 'ipam_fq_name': ['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'ctest-TestDetailedPolicy1-91113546-default-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:03:18,296 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'policy100']] to ctest-vnet1-12636930
2025-08-04 03:03:18,324 - DEBUG - Services list from nova: [, , , ]
2025-08-04 03:03:18,324 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3750-2', 'an-jenkins-deploy-platform-ansible-os-3750-1']}
2025-08-04 03:03:18,460 - DEBUG - Services list from nova: [, , , ]
2025-08-04 03:03:18,488 - DEBUG - Image by name ubuntu-traffic-py3 not found
2025-08-04 03:03:18,488 - DEBUG - Installing image ubuntu-traffic-py3
2025-08-04 03:03:18,488 - DEBUG - Image info {'name': 'ubuntu-traffic-py3.qcow2.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'ubuntu', 'password': 'ubuntu', 'type': 'os', 'flavor': 'contrail_flavor_small', 'params': '--container-format ovf --disk-format qcow2 --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-04 03:03:18,489 - DEBUG - Image build path file:///contrail-test/images/ubuntu-traffic-py3.qcow2.gz
2025-08-04 03:03:18,489 - DEBUG - Download image from local file file:///contrail-test/images/ubuntu-traffic-py3.qcow2.gz
2025-08-04 03:03:18,489 - DEBUG - Unzip image
2025-08-04 03:03:18,489 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-traffic-py3.qcow2.gz
2025-08-04 03:03:18,508 - INFO - image /contrail-test/images/ubuntu-traffic-py3.qcow2 is not found
2025-08-04 03:03:18,509 - DEBUG - Detaching the Policy for VN :ctest-vnet1-12636930
2025-08-04 03:03:18,509 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:18,518 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:18,533 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:18,641 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/3dc124a2-292c-4ba5-b4df-bc04d8d8a963
2025-08-04 03:03:18,847 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'policy100']] from ctest-vnet1-12636930
2025-08-04 03:03:18,847 - DEBUG - Detaching the Policy for VN :ctest-vnet0-10629781
2025-08-04 03:03:18,847 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:18,855 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:18,870 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:18,973 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8c0edcf8-1d57-43a7-9200-8bbf8d9f41d0
2025-08-04 03:03:19,183 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestDetailedPolicy1-91113546', 'policy0']] from ctest-vnet0-10629781
2025-08-04 03:03:19,273 - INFO - Deleted policy policy1000
2025-08-04 03:03:19,434 - DEBUG - policy policy1000 is not found in API Server
2025-08-04 03:03:19,487 - INFO - Deleted policy policy13
2025-08-04 03:03:19,651 - DEBUG - policy policy13 is not found in API Server
2025-08-04 03:03:19,734 - INFO - Deleted policy policy12
2025-08-04 03:03:19,914 - DEBUG - policy policy12 is not found in API Server
2025-08-04 03:03:19,962 - INFO - Deleted policy policy11
2025-08-04 03:03:20,160 - DEBUG - policy policy11 is not found in API Server
2025-08-04 03:03:20,209 - INFO - Deleted policy policy10
2025-08-04 03:03:20,400 - DEBUG - policy policy10 is not found in API Server
2025-08-04 03:03:20,433 - INFO - Deleted policy policy100
2025-08-04 03:03:20,644 - DEBUG - policy policy100 is not found in API Server
2025-08-04 03:03:20,688 - INFO - Deleted policy policy3
2025-08-04 03:03:20,896 - DEBUG - policy policy3 is not found in API Server
2025-08-04 03:03:20,949 - INFO - Deleted policy policy2
2025-08-04 03:03:21,144 - DEBUG - policy policy2 is not found in API Server
2025-08-04 03:03:21,196 - INFO - Deleted policy policy1
2025-08-04 03:03:21,396 - DEBUG - policy policy1 is not found in API Server
2025-08-04 03:03:21,442 - INFO - Deleted policy policy0
2025-08-04 03:03:21,636 - DEBUG - policy policy0 is not found in API Server
2025-08-04 03:03:21,636 - INFO - Deleting VN ctest-vnet1-12636930
2025-08-04 03:03:21,842 - DEBUG - Response for deleting network ()
2025-08-04 03:03:21,842 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b103d17b-f360-4afe-9029-ec61aecb7314
2025-08-04 03:03:21,849 - DEBUG - Response Code: 404
2025-08-04 03:03:21,849 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:21,859 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:21,877 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:22,001 - INFO - Validated that VN ctest-vnet1-12636930 is not found in API Server
2025-08-04 03:03:22,019 - DEBUG - VN ctest-vnet1-12636930 is not present in Agent 10.0.0.41
2025-08-04 03:03:22,032 - DEBUG - VN ctest-vnet1-12636930 is not present in Agent 10.0.0.57
2025-08-04 03:03:22,032 - INFO - Validated that VN ctest-vnet1-12636930 is not in any agent
2025-08-04 03:03:22,071 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet1-12636930 info
2025-08-04 03:03:22,071 - INFO - Deleting VN ctest-vnet0-10629781
2025-08-04 03:03:22,239 - DEBUG - Response for deleting network ()
2025-08-04 03:03:22,239 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/73ed1f68-e0b4-4afa-9d57-ca08f2931fc3
2025-08-04 03:03:22,246 - DEBUG - Response Code: 404
2025-08-04 03:03:22,247 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-04 03:03:22,253 - DEBUG - Requesting: http://10.0.0.27:8082/domain/8d00e278-1e00-4abc-a644-cc23d080d5d1
2025-08-04 03:03:22,279 - DEBUG - Requesting: http://10.0.0.27:8082/project/5002257d-aa98-46f4-b9d9-3d40637d8400
2025-08-04 03:03:22,372 - INFO - Validated that VN ctest-vnet0-10629781 is not found in API Server
2025-08-04 03:03:22,391 - DEBUG - VN ctest-vnet0-10629781 is not present in Agent 10.0.0.41
2025-08-04 03:03:22,404 - DEBUG - VN ctest-vnet0-10629781 is not present in Agent 10.0.0.57
2025-08-04 03:03:22,404 - INFO - Validated that VN ctest-vnet0-10629781 is not in any agent
2025-08-04 03:03:22,444 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vnet0-10629781 info
2025-08-04 03:03:22,540 - INFO - IPAM: ctest-TestDetailedPolicy1-91113546-default-ipam is not found in API Server
2025-08-04 03:03:22,576 - INFO - IPAM:ctest-TestDetailedPolicy1-91113546-default-ipam is not found in control node
2025-08-04 03:03:23,058 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestDetailedPolicy1-91113546']
2025-08-04 03:03:23,058 - DEBUG - Skipping the deletion of User admin
2025-08-04 03:03:24,355 - DEBUG - No XMPP flaps were noticed during the test
2025-08-04 03:03:24,355 - INFO -
2025-08-04 03:03:24,355 - INFO - END TEST : test_multi_vn_repeated_policy_update_with_ping : SKIPPED[0:00:22]
2025-08-04 03:03:24,355 - INFO - --------------------------------------------------------------------------------
2025-08-04 03:03:25,660 - INFO - Deleted project: ctest-TestDetailedPolicy1-91113546, ID : 5002257d-aa98-46f4-b9d9-3d40637d8400