2026-09-01 21:28:36,780 - INFO - Domain Default found not creating 2026-09-01 21:28:36,973 - INFO - Project ctest-TestBasicPolicy-62564761 not found, creating it 2026-09-01 21:28:37,469 - INFO - Created Project:ctest-TestBasicPolicy-62564761, ID : ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:28:39,239 - INFO - ================================================================================ 2026-09-01 21:28:39,240 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-09-01 21:28:39,240 - INFO - TEST DESCRIPTION : Create 2 Vns and allow icmp traffic between them and validate with pings Update the policy to deny the same traffic Check that pings fail 2026-09-01 21:28:39,517 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.25': '0'}} with 2026-09-01 21:28:39,517 - INFO - Initial checks done. Running the testcase now 2026-09-01 21:28:39,517 - INFO - 2026-09-01 21:28:40,192 - DEBUG - Response for create_network : {'network': {'id': '94b79366-2903-455d-8266-afb7a38ce213', 'name': 'ctest-vn-85382226', 'tenant_id': 'ebf596ae7171494aa26a7b91bf59112c', 'project_id': 'ebf596ae7171494aa26a7b91bf59112c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-vn-85382226'], 'port_security_enabled': True, 'description': ''}} 2026-09-01 21:28:40,376 - DEBUG - Response for create_subnet : {'subnet': {'id': '59d1c2a0-e0ea-45dd-b09d-c30f32a8650c', 'name': '', 'tenant_id': 'ebf596ae7171494aa26a7b91bf59112c', 'network_id': '94b79366-2903-455d-8266-afb7a38ce213', 'ip_version': 4, 'cidr': '95.188.43.192/26', 'allocation_pools': [{'start': '95.188.43.194', 'end': '95.188.43.254'}], 'gateway_ip': '95.188.43.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.188.43.194', 'tags': [], 'project_id': 'ebf596ae7171494aa26a7b91bf59112c'}} 2026-09-01 21:28:40,395 - INFO - Created VN ctest-vn-85382226 2026-09-01 21:28:40,451 - DEBUG - VN ctest-vn-85382226 UUID is 94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:40,778 - DEBUG - Response for create_network : {'network': {'id': 'f0214bd1-eb57-40dd-abb7-165efe89cf35', 'name': 'ctest-vn-40968296', 'tenant_id': 'ebf596ae7171494aa26a7b91bf59112c', 'project_id': 'ebf596ae7171494aa26a7b91bf59112c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-vn-40968296'], 'port_security_enabled': True, 'description': ''}} 2026-09-01 21:28:41,022 - DEBUG - Response for create_subnet : {'subnet': {'id': '972593ed-6d59-4ee6-a4eb-dd8338b02380', 'name': '', 'tenant_id': 'ebf596ae7171494aa26a7b91bf59112c', 'network_id': 'f0214bd1-eb57-40dd-abb7-165efe89cf35', 'ip_version': 4, 'cidr': '46.129.221.192/26', 'allocation_pools': [{'start': '46.129.221.194', 'end': '46.129.221.254'}], 'gateway_ip': '46.129.221.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.129.221.194', 'tags': [], 'project_id': 'ebf596ae7171494aa26a7b91bf59112c'}} 2026-09-01 21:28:41,041 - INFO - Created VN ctest-vn-40968296 2026-09-01 21:28:41,094 - DEBUG - VN ctest-vn-40968296 UUID is f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:41,211 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226, 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-TestBasicPolicy-62564761:ctest-vn-40968296, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-09-01 21:28:41,541 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '94b79366-2903-455d-8266-afb7a38ce213', 'name': 'ctest-vn-85382226', 'tenant_id': 'ebf596ae7171494aa26a7b91bf59112c', 'project_id': 'ebf596ae7171494aa26a7b91bf59112c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['59d1c2a0-e0ea-45dd-b09d-c30f32a8650c'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-vn-85382226'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-policy-allow-all-70529629']], 'subnet_ipam': [{'subnet_cidr': '95.188.43.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-09-01 21:28:41,786 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f0214bd1-eb57-40dd-abb7-165efe89cf35', 'name': 'ctest-vn-40968296', 'tenant_id': 'ebf596ae7171494aa26a7b91bf59112c', 'project_id': 'ebf596ae7171494aa26a7b91bf59112c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['972593ed-6d59-4ee6-a4eb-dd8338b02380'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-vn-40968296'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-policy-allow-all-70529629']], 'subnet_ipam': [{'subnet_cidr': '46.129.221.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-09-01 21:28:41,958 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:28:42,324 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:28:42,358 - DEBUG - Requesting: http://10.0.0.50:8082/domain/7cb0c5bf-a2e3-48eb-8736-e100863ca599 2026-09-01 21:28:42,372 - DEBUG - Requesting: http://10.0.0.50:8082/project/ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:28:42,454 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:42,469 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:42,479 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6d7dd2cc-f4e2-4b29-895e-af811a9118de 2026-09-01 21:28:42,489 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6d7dd2cc-f4e2-4b29-895e-af811a9118de 2026-09-01 21:28:42,497 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/35dad83a-a903-4747-af01-d15d9f1ca4d7 2026-09-01 21:28:42,505 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-01 21:28:42,505 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:42,515 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6d7dd2cc-f4e2-4b29-895e-af811a9118de 2026-09-01 21:28:42,532 - INFO - Verified VN network id 12 for VN 94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:42,532 - INFO - Verifications in API Server for VN ctest-vn-85382226 passed 2026-09-01 21:28:42,532 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:42,543 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6d7dd2cc-f4e2-4b29-895e-af811a9118de 2026-09-01 21:28:42,552 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6d7dd2cc-f4e2-4b29-895e-af811a9118de 2026-09-01 21:28:42,561 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/35dad83a-a903-4747-af01-d15d9f1ca4d7 2026-09-01 21:28:42,576 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-62564761', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10716195905335412061', 'uuid-lslong': '9396390875812913683'}, 'enable': 'true', 'created': '2026-09-01T21:28:40', 'last-modified': '2026-09-01T21:28:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.073123'} 2026-09-01 21:28:42,585 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-01 21:28:42,585 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-85382226 passed 2026-09-01 21:28:42,585 - DEBUG - ====Verifying policy data for ctest-vn-85382226 in API_Server ====== 2026-09-01 21:28:42,585 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:28:42,592 - DEBUG - Requesting: http://10.0.0.50:8082/domain/7cb0c5bf-a2e3-48eb-8736-e100863ca599 2026-09-01 21:28:42,606 - DEBUG - Requesting: http://10.0.0.50:8082/project/ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:28:42,690 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/94b79366-2903-455d-8266-afb7a38ce213 2026-09-01 21:28:42,702 - DEBUG - ==>Verifying data for policy with id: c1b1d50b-6970-4f16-8488-abb3b366d070, fqn: ['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-policy-allow-all-70529629'] 2026-09-01 21:28:42,702 - INFO - VN ctest-vn-85382226 Policy verification: verify_vn_policy_in_api_server, status: True 2026-09-01 21:28:42,702 - DEBUG - Verifying the vn in opserver 2026-09-01 21:28:42,702 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226 virtual network link through opserver 10.0.0.50 2026-09-01 21:28:42,702 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-09-01 21:28:43,071 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-09-01 21:28:43,133 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226?flat'} 2026-09-01 21:28:43,133 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226 is found in opserver 2026-09-01 21:28:43,144 - DEBUG - Do not have enough data to verify VN in agent 2026-09-01 21:28:43,150 - DEBUG - VRF ids for VN ctest-vn-85382226: {} 2026-09-01 21:28:43,150 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:28:43,156 - DEBUG - Requesting: http://10.0.0.50:8082/domain/7cb0c5bf-a2e3-48eb-8736-e100863ca599 2026-09-01 21:28:43,169 - DEBUG - Requesting: http://10.0.0.50:8082/project/ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:28:43,257 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:43,270 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:43,282 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d84a9c5c-4372-42f7-b429-5ef37cb38c9f 2026-09-01 21:28:43,292 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d84a9c5c-4372-42f7-b429-5ef37cb38c9f 2026-09-01 21:28:43,301 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/1bbfa62a-0da5-4670-99c5-199a3cd27759 2026-09-01 21:28:43,308 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-09-01 21:28:43,308 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:43,319 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d84a9c5c-4372-42f7-b429-5ef37cb38c9f 2026-09-01 21:28:43,336 - INFO - Verified VN network id 13 for VN f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:43,337 - INFO - Verifications in API Server for VN ctest-vn-40968296 passed 2026-09-01 21:28:43,337 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:43,348 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d84a9c5c-4372-42f7-b429-5ef37cb38c9f 2026-09-01 21:28:43,356 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d84a9c5c-4372-42f7-b429-5ef37cb38c9f 2026-09-01 21:28:43,365 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/1bbfa62a-0da5-4670-99c5-199a3cd27759 2026-09-01 21:28:43,380 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-40968296', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-62564761', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17303194608302768349', 'uuid-lslong': '12373383098476908341'}, 'enable': 'true', 'created': '2026-09-01T21:28:40', 'last-modified': '2026-09-01T21:28:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.621218'} 2026-09-01 21:28:43,386 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-09-01 21:28:43,386 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-40968296 passed 2026-09-01 21:28:43,387 - DEBUG - ====Verifying policy data for ctest-vn-40968296 in API_Server ====== 2026-09-01 21:28:43,387 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:28:43,393 - DEBUG - Requesting: http://10.0.0.50:8082/domain/7cb0c5bf-a2e3-48eb-8736-e100863ca599 2026-09-01 21:28:43,404 - DEBUG - Requesting: http://10.0.0.50:8082/project/ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:28:43,488 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f0214bd1-eb57-40dd-abb7-165efe89cf35 2026-09-01 21:28:43,499 - DEBUG - ==>Verifying data for policy with id: c1b1d50b-6970-4f16-8488-abb3b366d070, fqn: ['default-domain', 'ctest-TestBasicPolicy-62564761', 'ctest-policy-allow-all-70529629'] 2026-09-01 21:28:43,499 - INFO - VN ctest-vn-40968296 Policy verification: verify_vn_policy_in_api_server, status: True 2026-09-01 21:28:43,500 - DEBUG - Verifying the vn in opserver 2026-09-01 21:28:43,500 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-40968296 virtual network link through opserver 10.0.0.50 2026-09-01 21:28:43,500 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-09-01 21:28:43,512 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-40968296', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-40968296?flat'} 2026-09-01 21:28:43,512 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-40968296 is found in opserver 2026-09-01 21:28:43,525 - DEBUG - Do not have enough data to verify VN in agent 2026-09-01 21:28:43,531 - DEBUG - VRF ids for VN ctest-vn-40968296: {} 2026-09-01 21:28:43,597 - DEBUG - Services list from nova: [, , ] 2026-09-01 21:28:44,106 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6241-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6241-1) 2026-09-01 21:28:44,537 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6241-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6241-1) 2026-09-01 21:28:44,537 - INFO - Waiting for VM ctest-TestBasicPolicy-62564761-51565455 to be up.. 2026-09-01 21:28:44,581 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:28:49,677 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:28:54,764 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:28:59,860 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:29:04,956 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:29:10,052 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:29:15,143 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:29:20,225 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-01 21:29:25,310 - DEBUG - VM is in ACTIVE state now 2026-09-01 21:29:25,311 - INFO - VM name : ctest-TestBasicPolicy-62564761-51565455 2026-09-01 21:29:25,405 - DEBUG - VM ctest-TestBasicPolicy-62564761-51565455 ID is d2916213-5371-43ef-9e7a-5e2e030b7252 2026-09-01 21:29:25,428 - DEBUG - VM ctest-TestBasicPolicy-62564761-51565455 launched on Node cn-jenkins-deploy-platform-ansible-os-6241-1 2026-09-01 21:29:25,513 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/d2916213-5371-43ef-9e7a-5e2e030b7252 2026-09-01 21:29:25,523 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/508bebaf-2d5b-4e95-87ef-45bde3e05cd9 2026-09-01 21:29:28,796 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-09-01 21:29:28,797 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-62564761-51565455 failed! 2026-09-01 21:29:28,854 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-62564761:ctest-vn-85382226 is 95.188.43.193 and allocation pool is NOT set 2026-09-01 21:29:32,920 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.44 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 5.440/5.440/5.440/0.000 ms') 2026-09-01 21:29:32,921 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-62564761-51565455 passed 2026-09-01 21:29:33,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-01 21:29:33,074 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-62564761-51565455, IP 95.188.43.195, Port 22 2026-09-01 21:29:33,248 - DEBUG - VM ctest-TestBasicPolicy-62564761-51565455 is ready for SSH connections 2026-09-01 21:29:33,248 - INFO - Waiting for VM ctest-TestBasicPolicy-62564761-41327717 to be up.. 2026-09-01 21:29:33,339 - DEBUG - VM is in ACTIVE state now 2026-09-01 21:29:33,340 - INFO - VM name : ctest-TestBasicPolicy-62564761-41327717 2026-09-01 21:29:33,433 - DEBUG - VM ctest-TestBasicPolicy-62564761-41327717 ID is 7346e29c-66f2-427b-a3df-7145d57d612b 2026-09-01 21:29:33,433 - DEBUG - VM ctest-TestBasicPolicy-62564761-41327717 launched on Node cn-jenkins-deploy-platform-ansible-os-6241-1 2026-09-01 21:29:33,527 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/7346e29c-66f2-427b-a3df-7145d57d612b 2026-09-01 21:29:33,539 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/f72f4e71-3b1b-4a8b-a9d4-e977817a45cb 2026-09-01 21:29:34,796 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.87 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.688 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.688/2.277/3.866/1.589 ms') 2026-09-01 21:29:34,796 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-62564761-41327717 passed 2026-09-01 21:29:34,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-01 21:29:34,954 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-62564761-41327717, IP 46.129.221.195, Port 22 2026-09-01 21:29:35,124 - DEBUG - VM ctest-TestBasicPolicy-62564761-41327717 is ready for SSH connections 2026-09-01 21:29:35,124 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-01 21:29:35,124 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 46.129.221.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-09-01 21:29:35,125 - DEBUG - ping -s 56 -c 3 -W 1 46.129.221.195 2026-09-01 21:29:39,181 - DEBUG - PING 46.129.221.195 (46.129.221.195): 56 data bytes 64 bytes from 46.129.221.195: seq=0 ttl=63 time=4.487 ms 64 bytes from 46.129.221.195: seq=1 ttl=63 time=1.856 ms 64 bytes from 46.129.221.195: seq=2 ttl=63 time=1.007 ms --- 46.129.221.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.007/2.450/4.487 ms 2026-09-01 21:29:39,181 - INFO - Ping to IP 46.129.221.195 from VM ctest-TestBasicPolicy-62564761-51565455 passed 2026-09-01 21:29:39,378 - DEBUG - Policy Update Response {"network-policy": {"uuid": "c1b1d50b-6970-4f16-8488-abb3b366d070", "href": "http://10.0.0.50:8082/network-policy/c1b1d50b-6970-4f16-8488-abb3b366d070"}} 2026-09-01 21:29:39,378 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-01 21:29:39,378 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 46.129.221.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-09-01 21:29:39,378 - DEBUG - ping -s 56 -c 3 -W 1 46.129.221.195 2026-09-01 21:29:42,529 - DEBUG - PING 46.129.221.195 (46.129.221.195): 56 data bytes 64 bytes from 46.129.221.195: seq=0 ttl=63 time=5.251 ms --- 46.129.221.195 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 5.251/5.251/5.251 ms 2026-09-01 21:29:42,529 - WARNING - Ping to IP 46.129.221.195 from VM ctest-TestBasicPolicy-62564761-51565455 failed 2026-09-01 21:29:42,529 - INFO - Deleting VM ctest-TestBasicPolicy-62564761-41327717 2026-09-01 21:29:42,614 - INFO - Deleting VM ctest-TestBasicPolicy-62564761-51565455 2026-09-01 21:29:43,030 - INFO - Deleted policy ctest-policy-allow-all-70529629 2026-09-01 21:29:43,030 - INFO - Deleting VN ctest-vn-40968296 2026-09-01 21:29:43,064 - DEBUG - VN f0214bd1-eb57-40dd-abb7-165efe89cf35 still in use: Unable to complete operation on network f0214bd1-eb57-40dd-abb7-165efe89cf35. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-096885da-f33d-440f-b8e2-802048ff7e30'] 2026-09-01 21:29:43,064 - WARNING - Deleting VN ctest-vn-40968296 failed..Will retry 2026-09-01 21:29:45,182 - DEBUG - Response for deleting network () 2026-09-01 21:29:45,183 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d84a9c5c-4372-42f7-b429-5ef37cb38c9f 2026-09-01 21:29:45,197 - DEBUG - Response Code: 404 2026-09-01 21:29:45,198 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:29:45,205 - DEBUG - Requesting: http://10.0.0.50:8082/domain/7cb0c5bf-a2e3-48eb-8736-e100863ca599 2026-09-01 21:29:45,267 - DEBUG - Requesting: http://10.0.0.50:8082/project/ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:29:45,352 - INFO - Validated that VN ctest-vn-40968296 is not found in API Server 2026-09-01 21:29:45,363 - DEBUG - VN ctest-vn-40968296 is not present in Agent 10.0.0.50 2026-09-01 21:29:45,364 - INFO - Validated that VN ctest-vn-40968296 is not in any agent 2026-09-01 21:29:45,383 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-40968296 info 2026-09-01 21:29:45,383 - INFO - Deleting VN ctest-vn-85382226 2026-09-01 21:29:45,513 - DEBUG - Response for deleting network () 2026-09-01 21:29:45,513 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6d7dd2cc-f4e2-4b29-895e-af811a9118de 2026-09-01 21:29:45,519 - DEBUG - Response Code: 404 2026-09-01 21:29:45,520 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-09-01 21:29:45,527 - DEBUG - Requesting: http://10.0.0.50:8082/domain/7cb0c5bf-a2e3-48eb-8736-e100863ca599 2026-09-01 21:29:45,579 - DEBUG - Requesting: http://10.0.0.50:8082/project/ebf596ae-7171-494a-a26a-7b91bf59112c 2026-09-01 21:29:45,658 - INFO - Validated that VN ctest-vn-85382226 is not found in API Server 2026-09-01 21:29:45,669 - DEBUG - VN ctest-vn-85382226 is not present in Agent 10.0.0.50 2026-09-01 21:29:45,669 - INFO - Validated that VN ctest-vn-85382226 is not in any agent 2026-09-01 21:29:45,686 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-85382226 info 2026-09-01 21:29:45,954 - DEBUG - No XMPP flaps were noticed during the test
2026-09-01 21:29:45,955 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:06]
2026-09-01 21:29:45,955 - INFO - -------------------------------------------------------------------------------- 2026-09-01 21:29:46,763 - INFO - Deleted project: ctest-TestBasicPolicy-62564761, ID : ebf596ae-7171-494a-a26a-7b91bf59112c