2026-05-08 19:33:01,424 - INFO - Domain Default found not creating 2026-05-08 19:33:01,575 - INFO - Project ctest-TestBasicPolicy-52240686 not found, creating it 2026-05-08 19:33:02,029 - INFO - Created Project:ctest-TestBasicPolicy-52240686, ID : daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:03,487 - INFO - ================================================================================ 2026-05-08 19:33:03,487 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-05-08 19:33:03,487 - 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-05-08 19:33:03,744 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.17': '0'}} with 2026-05-08 19:33:03,745 - INFO - Initial checks done. Running the testcase now 2026-05-08 19:33:03,745 - INFO - 2026-05-08 19:33:04,324 - DEBUG - Response for create_network : {'network': {'id': 'dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a', 'name': 'ctest-vn-06904764', 'tenant_id': 'daca2454c24d45598dd344270be5ccc3', 'project_id': 'daca2454c24d45598dd344270be5ccc3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-vn-06904764'], 'port_security_enabled': True, 'description': ''}} 2026-05-08 19:33:04,532 - DEBUG - Response for create_subnet : {'subnet': {'id': '52768c0f-f1a8-4e95-876b-f960e14cca36', 'name': '', 'tenant_id': 'daca2454c24d45598dd344270be5ccc3', 'network_id': 'dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a', 'ip_version': 4, 'cidr': '154.85.77.128/26', 'allocation_pools': [{'start': '154.85.77.130', 'end': '154.85.77.190'}], 'gateway_ip': '154.85.77.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '154.85.77.130', 'tags': [], 'project_id': 'daca2454c24d45598dd344270be5ccc3'}} 2026-05-08 19:33:04,548 - INFO - Created VN ctest-vn-06904764 2026-05-08 19:33:04,599 - DEBUG - VN ctest-vn-06904764 UUID is dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:04,926 - DEBUG - Response for create_network : {'network': {'id': '2e8ac3f9-ffeb-4549-8134-53ffe4cb57db', 'name': 'ctest-vn-79546171', 'tenant_id': 'daca2454c24d45598dd344270be5ccc3', 'project_id': 'daca2454c24d45598dd344270be5ccc3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-vn-79546171'], 'port_security_enabled': True, 'description': ''}} 2026-05-08 19:33:05,126 - DEBUG - Response for create_subnet : {'subnet': {'id': '54f49e15-3be0-424f-8cbd-bab4e700a2f8', 'name': '', 'tenant_id': 'daca2454c24d45598dd344270be5ccc3', 'network_id': '2e8ac3f9-ffeb-4549-8134-53ffe4cb57db', 'ip_version': 4, 'cidr': '87.225.169.128/26', 'allocation_pools': [{'start': '87.225.169.130', 'end': '87.225.169.190'}], 'gateway_ip': '87.225.169.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.225.169.130', 'tags': [], 'project_id': 'daca2454c24d45598dd344270be5ccc3'}} 2026-05-08 19:33:05,143 - INFO - Created VN ctest-vn-79546171 2026-05-08 19:33:05,194 - DEBUG - VN ctest-vn-79546171 UUID is 2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:05,342 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764, 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-52240686:ctest-vn-79546171, 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-05-08 19:33:05,630 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a', 'name': 'ctest-vn-06904764', 'tenant_id': 'daca2454c24d45598dd344270be5ccc3', 'project_id': 'daca2454c24d45598dd344270be5ccc3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['52768c0f-f1a8-4e95-876b-f960e14cca36'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-vn-06904764'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-policy-allow-all-11662705']], 'subnet_ipam': [{'subnet_cidr': '154.85.77.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-05-08 19:33:05,855 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '2e8ac3f9-ffeb-4549-8134-53ffe4cb57db', 'name': 'ctest-vn-79546171', 'tenant_id': 'daca2454c24d45598dd344270be5ccc3', 'project_id': 'daca2454c24d45598dd344270be5ccc3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['54f49e15-3be0-424f-8cbd-bab4e700a2f8'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-vn-79546171'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-policy-allow-all-11662705']], 'subnet_ipam': [{'subnet_cidr': '87.225.169.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-05-08 19:33:06,019 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:06,312 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:06,344 - DEBUG - Requesting: http://10.0.0.50:8082/domain/fa040dd8-e6fd-4b87-8557-631a6565bdb7 2026-05-08 19:33:06,355 - DEBUG - Requesting: http://10.0.0.50:8082/project/daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:06,428 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:06,439 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:06,448 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c5652bde-1fd5-4d56-9e61-a1d19359db1b 2026-05-08 19:33:06,454 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c5652bde-1fd5-4d56-9e61-a1d19359db1b 2026-05-08 19:33:06,461 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/ad6892b1-9368-408b-9ce9-bf93e249bae0 2026-05-08 19:33:06,476 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-05-08 19:33:06,476 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:06,486 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c5652bde-1fd5-4d56-9e61-a1d19359db1b 2026-05-08 19:33:06,501 - INFO - Verified VN network id 11 for VN dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:06,501 - INFO - Verifications in API Server for VN ctest-vn-06904764 passed 2026-05-08 19:33:06,502 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:06,512 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c5652bde-1fd5-4d56-9e61-a1d19359db1b 2026-05-08 19:33:06,519 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c5652bde-1fd5-4d56-9e61-a1d19359db1b 2026-05-08 19:33:06,527 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/ad6892b1-9368-408b-9ce9-bf93e249bae0 2026-05-08 19:33:06,546 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-52240686', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15847873790146595276', 'uuid-lslong': '13826450296485072506'}, 'enable': 'true', 'created': '2026-05-08T19:33:04', 'last-modified': '2026-05-08T19:33:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.937635'} 2026-05-08 19:33:06,552 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-05-08 19:33:06,552 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-06904764 passed 2026-05-08 19:33:06,552 - DEBUG - ====Verifying policy data for ctest-vn-06904764 in API_Server ====== 2026-05-08 19:33:06,552 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:06,557 - DEBUG - Requesting: http://10.0.0.50:8082/domain/fa040dd8-e6fd-4b87-8557-631a6565bdb7 2026-05-08 19:33:06,567 - DEBUG - Requesting: http://10.0.0.50:8082/project/daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:06,638 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/dbeef53f-1ae9-49cc-bfe1-6b49fa964e7a 2026-05-08 19:33:06,648 - DEBUG - ==>Verifying data for policy with id: 2b0fa881-0547-4fc9-90a5-fb5a453993dd, fqn: ['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-policy-allow-all-11662705'] 2026-05-08 19:33:06,648 - INFO - VN ctest-vn-06904764 Policy verification: verify_vn_policy_in_api_server, status: True 2026-05-08 19:33:06,648 - DEBUG - Verifying the vn in opserver 2026-05-08 19:33:06,648 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764 virtual network link through opserver 10.0.0.50 2026-05-08 19:33:06,648 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-05-08 19:33:06,944 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-05-08 19:33:07,000 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764?flat'} 2026-05-08 19:33:07,000 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764 is found in opserver 2026-05-08 19:33:07,009 - DEBUG - Do not have enough data to verify VN in agent 2026-05-08 19:33:07,013 - DEBUG - VRF ids for VN ctest-vn-06904764: {} 2026-05-08 19:33:07,013 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:07,018 - DEBUG - Requesting: http://10.0.0.50:8082/domain/fa040dd8-e6fd-4b87-8557-631a6565bdb7 2026-05-08 19:33:07,027 - DEBUG - Requesting: http://10.0.0.50:8082/project/daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:07,095 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:07,106 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:07,114 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b9bbcd59-e574-4e76-8591-126f6bc48e1d 2026-05-08 19:33:07,121 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b9bbcd59-e574-4e76-8591-126f6bc48e1d 2026-05-08 19:33:07,128 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/406ebc3a-295e-45b8-9b13-2ca2e80b500f 2026-05-08 19:33:07,134 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-05-08 19:33:07,134 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:07,142 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b9bbcd59-e574-4e76-8591-126f6bc48e1d 2026-05-08 19:33:07,154 - INFO - Verified VN network id 12 for VN 2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:07,154 - INFO - Verifications in API Server for VN ctest-vn-79546171 passed 2026-05-08 19:33:07,154 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:07,164 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b9bbcd59-e574-4e76-8591-126f6bc48e1d 2026-05-08 19:33:07,170 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b9bbcd59-e574-4e76-8591-126f6bc48e1d 2026-05-08 19:33:07,175 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/406ebc3a-295e-45b8-9b13-2ca2e80b500f 2026-05-08 19:33:07,184 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-79546171', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-52240686', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3353708351038637385', 'uuid-lslong': '9310158688201955291'}, 'enable': 'true', 'created': '2026-05-08T19:33:04', 'last-modified': '2026-05-08T19:33:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.348021'} 2026-05-08 19:33:07,189 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-05-08 19:33:07,189 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-79546171 passed 2026-05-08 19:33:07,189 - DEBUG - ====Verifying policy data for ctest-vn-79546171 in API_Server ====== 2026-05-08 19:33:07,190 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:07,195 - DEBUG - Requesting: http://10.0.0.50:8082/domain/fa040dd8-e6fd-4b87-8557-631a6565bdb7 2026-05-08 19:33:07,204 - DEBUG - Requesting: http://10.0.0.50:8082/project/daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:07,271 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2e8ac3f9-ffeb-4549-8134-53ffe4cb57db 2026-05-08 19:33:07,280 - DEBUG - ==>Verifying data for policy with id: 2b0fa881-0547-4fc9-90a5-fb5a453993dd, fqn: ['default-domain', 'ctest-TestBasicPolicy-52240686', 'ctest-policy-allow-all-11662705'] 2026-05-08 19:33:07,280 - INFO - VN ctest-vn-79546171 Policy verification: verify_vn_policy_in_api_server, status: True 2026-05-08 19:33:07,280 - DEBUG - Verifying the vn in opserver 2026-05-08 19:33:07,280 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-79546171 virtual network link through opserver 10.0.0.50 2026-05-08 19:33:07,280 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-05-08 19:33:07,290 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-79546171', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-79546171?flat'} 2026-05-08 19:33:07,290 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-79546171 is found in opserver 2026-05-08 19:33:07,299 - DEBUG - Do not have enough data to verify VN in agent 2026-05-08 19:33:07,302 - DEBUG - VRF ids for VN ctest-vn-79546171: {} 2026-05-08 19:33:07,360 - DEBUG - Services list from nova: [, , ] 2026-05-08 19:33:08,376 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5759-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5759-1) 2026-05-08 19:33:09,485 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5759-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5759-1) 2026-05-08 19:33:09,485 - INFO - Waiting for VM ctest-TestBasicPolicy-52240686-03126191 to be up.. 2026-05-08 19:33:09,556 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-05-08 19:33:14,629 - DEBUG - VM is in ACTIVE state now 2026-05-08 19:33:14,629 - INFO - VM name : ctest-TestBasicPolicy-52240686-03126191 2026-05-08 19:33:14,695 - DEBUG - VM ctest-TestBasicPolicy-52240686-03126191 ID is 68fd6990-9b80-44e0-96a4-2c56855af373 2026-05-08 19:33:14,712 - DEBUG - VM ctest-TestBasicPolicy-52240686-03126191 launched on Node cn-jenkins-deploy-platform-ansible-os-5759-1 2026-05-08 19:33:14,781 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/68fd6990-9b80-44e0-96a4-2c56855af373 2026-05-08 19:33:14,790 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ed23d5fd-0d7c-4933-941e-df1346bf317f 2026-05-08 19:33:18,036 - 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 1011ms') 2026-05-08 19:33:18,037 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-52240686-03126191 failed! 2026-05-08 19:33:18,091 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-52240686:ctest-vn-06904764 is 154.85.77.129 and allocation pool is NOT set 2026-05-08 19:33:20,148 - 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=1 ttl=63 time=2.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.482 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.482/1.666/2.850/1.184 ms') 2026-05-08 19:33:20,148 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-52240686-03126191 passed 2026-05-08 19:33:20,295 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-08 19:33:20,295 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-52240686-03126191, IP 154.85.77.131, Port 22 2026-05-08 19:33:20,450 - DEBUG - VM ctest-TestBasicPolicy-52240686-03126191 is ready for SSH connections 2026-05-08 19:33:20,450 - INFO - Waiting for VM ctest-TestBasicPolicy-52240686-37651747 to be up.. 2026-05-08 19:33:20,519 - DEBUG - VM is in ACTIVE state now 2026-05-08 19:33:20,519 - INFO - VM name : ctest-TestBasicPolicy-52240686-37651747 2026-05-08 19:33:20,586 - DEBUG - VM ctest-TestBasicPolicy-52240686-37651747 ID is a7a3d56e-ad43-46ea-ac02-21013b185a7b 2026-05-08 19:33:20,586 - DEBUG - VM ctest-TestBasicPolicy-52240686-37651747 launched on Node cn-jenkins-deploy-platform-ansible-os-5759-1 2026-05-08 19:33:20,655 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/a7a3d56e-ad43-46ea-ac02-21013b185a7b 2026-05-08 19:33:20,663 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e99f5ad4-f8c2-4005-be10-4d8b1d49408b 2026-05-08 19:33:21,899 - 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=2.60 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.416 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.416/1.509/2.602/1.093 ms') 2026-05-08 19:33:21,900 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-52240686-37651747 passed 2026-05-08 19:33:22,047 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-08 19:33:22,047 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-52240686-37651747, IP 87.225.169.131, Port 22 2026-05-08 19:33:22,206 - DEBUG - VM ctest-TestBasicPolicy-52240686-37651747 is ready for SSH connections 2026-05-08 19:33:22,206 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-08 19:33:22,206 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 87.225.169.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-08 19:33:22,207 - DEBUG - ping -s 56 -c 3 -W 1 87.225.169.131 2026-05-08 19:33:26,198 - DEBUG - PING 87.225.169.131 (87.225.169.131): 56 data bytes 64 bytes from 87.225.169.131: seq=0 ttl=63 time=3.519 ms 64 bytes from 87.225.169.131: seq=1 ttl=63 time=0.804 ms 64 bytes from 87.225.169.131: seq=2 ttl=63 time=0.911 ms --- 87.225.169.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.804/1.744/3.519 ms 2026-05-08 19:33:26,198 - INFO - Ping to IP 87.225.169.131 from VM ctest-TestBasicPolicy-52240686-03126191 passed 2026-05-08 19:33:26,370 - DEBUG - Policy Update Response {"network-policy": {"uuid": "2b0fa881-0547-4fc9-90a5-fb5a453993dd", "href": "http://10.0.0.50:8082/network-policy/2b0fa881-0547-4fc9-90a5-fb5a453993dd"}} 2026-05-08 19:33:26,371 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-08 19:33:26,371 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 87.225.169.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-08 19:33:26,371 - DEBUG - ping -s 56 -c 3 -W 1 87.225.169.131 2026-05-08 19:33:29,584 - DEBUG - PING 87.225.169.131 (87.225.169.131): 56 data bytes --- 87.225.169.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-05-08 19:33:29,584 - WARNING - Ping to IP 87.225.169.131 from VM ctest-TestBasicPolicy-52240686-03126191 failed 2026-05-08 19:33:29,584 - INFO - Deleting VM ctest-TestBasicPolicy-52240686-37651747 2026-05-08 19:33:29,652 - INFO - Deleting VM ctest-TestBasicPolicy-52240686-03126191 2026-05-08 19:33:30,015 - INFO - Deleted policy ctest-policy-allow-all-11662705 2026-05-08 19:33:30,015 - INFO - Deleting VN ctest-vn-79546171 2026-05-08 19:33:30,045 - DEBUG - VN 2e8ac3f9-ffeb-4549-8134-53ffe4cb57db still in use: Unable to complete operation on network 2e8ac3f9-ffeb-4549-8134-53ffe4cb57db. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d10c4448-ed79-4eed-b7e1-4f6956496f2b'] 2026-05-08 19:33:30,045 - WARNING - Deleting VN ctest-vn-79546171 failed..Will retry 2026-05-08 19:33:32,135 - DEBUG - Response for deleting network () 2026-05-08 19:33:32,136 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/b9bbcd59-e574-4e76-8591-126f6bc48e1d 2026-05-08 19:33:32,141 - DEBUG - Response Code: 404 2026-05-08 19:33:32,141 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:32,147 - DEBUG - Requesting: http://10.0.0.50:8082/domain/fa040dd8-e6fd-4b87-8557-631a6565bdb7 2026-05-08 19:33:32,203 - DEBUG - Requesting: http://10.0.0.50:8082/project/daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:32,279 - INFO - Validated that VN ctest-vn-79546171 is not found in API Server 2026-05-08 19:33:32,288 - DEBUG - VN ctest-vn-79546171 is not present in Agent 10.0.0.50 2026-05-08 19:33:32,288 - INFO - Validated that VN ctest-vn-79546171 is not in any agent 2026-05-08 19:33:32,302 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-79546171 info 2026-05-08 19:33:32,302 - INFO - Deleting VN ctest-vn-06904764 2026-05-08 19:33:32,392 - DEBUG - Response for deleting network () 2026-05-08 19:33:32,393 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c5652bde-1fd5-4d56-9e61-a1d19359db1b 2026-05-08 19:33:32,402 - DEBUG - Response Code: 404 2026-05-08 19:33:32,402 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2026-05-08 19:33:32,407 - DEBUG - Requesting: http://10.0.0.50:8082/domain/fa040dd8-e6fd-4b87-8557-631a6565bdb7 2026-05-08 19:33:32,460 - DEBUG - Requesting: http://10.0.0.50:8082/project/daca2454-c24d-4559-8dd3-44270be5ccc3 2026-05-08 19:33:32,527 - INFO - Validated that VN ctest-vn-06904764 is not found in API Server 2026-05-08 19:33:32,536 - DEBUG - VN ctest-vn-06904764 is not present in Agent 10.0.0.50 2026-05-08 19:33:32,536 - INFO - Validated that VN ctest-vn-06904764 is not in any agent 2026-05-08 19:33:32,547 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-06904764 info 2026-05-08 19:33:32,799 - DEBUG - No XMPP flaps were noticed during the test
2026-05-08 19:33:32,799 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:29]
2026-05-08 19:33:32,799 - INFO - -------------------------------------------------------------------------------- 2026-05-08 19:33:33,457 - INFO - Deleted project: ctest-TestBasicPolicy-52240686, ID : daca2454-c24d-4559-8dd3-44270be5ccc3