2025-04-08 16:37:01,518 - INFO - Domain Default found not creating 2025-04-08 16:37:01,832 - INFO - Project ctest-TestBasicPolicy-11021731 not found, creating it 2025-04-08 16:37:02,598 - INFO - Created Project:ctest-TestBasicPolicy-11021731, ID : b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:04,651 - INFO - ================================================================================ 2025-04-08 16:37:04,652 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-04-08 16:37:04,652 - 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 2025-04-08 16:37:04,999 - DEBUG - Nothing to compare xmpp stats {'10.0.0.12': {'10.20.0.35': '0'}} with 2025-04-08 16:37:04,999 - INFO - Initial checks done. Running the testcase now 2025-04-08 16:37:04,999 - INFO - 2025-04-08 16:37:05,836 - DEBUG - Response for create_network : {'network': {'id': '7facf8f3-f3ea-4e4b-b9f4-de009e3a626f', 'name': 'ctest-vn-61532816', 'tenant_id': 'b8c012c856b541e6983f357407fe2d52', 'project_id': 'b8c012c856b541e6983f357407fe2d52', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-vn-61532816'], 'port_security_enabled': True, 'description': ''}} 2025-04-08 16:37:06,059 - DEBUG - Response for create_subnet : {'subnet': {'id': '3e07135a-a33d-4e07-b7ca-de35091f258d', 'name': '', 'tenant_id': 'b8c012c856b541e6983f357407fe2d52', 'network_id': '7facf8f3-f3ea-4e4b-b9f4-de009e3a626f', 'ip_version': 4, 'cidr': '143.127.38.64/26', 'allocation_pools': [{'start': '143.127.38.66', 'end': '143.127.38.126'}], 'gateway_ip': '143.127.38.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '143.127.38.66', 'tags': [], 'project_id': 'b8c012c856b541e6983f357407fe2d52'}} 2025-04-08 16:37:06,091 - INFO - Created VN ctest-vn-61532816 2025-04-08 16:37:06,159 - DEBUG - VN ctest-vn-61532816 UUID is 7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:06,708 - DEBUG - Response for create_network : {'network': {'id': 'fc9678a2-d58a-4071-b2b9-d4908e684d9b', 'name': 'ctest-vn-06789441', 'tenant_id': 'b8c012c856b541e6983f357407fe2d52', 'project_id': 'b8c012c856b541e6983f357407fe2d52', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-vn-06789441'], 'port_security_enabled': True, 'description': ''}} 2025-04-08 16:37:06,930 - DEBUG - Response for create_subnet : {'subnet': {'id': '8b0af087-fe5c-4c2d-a95a-a07607a77579', 'name': '', 'tenant_id': 'b8c012c856b541e6983f357407fe2d52', 'network_id': 'fc9678a2-d58a-4071-b2b9-d4908e684d9b', 'ip_version': 4, 'cidr': '109.189.71.0/26', 'allocation_pools': [{'start': '109.189.71.2', 'end': '109.189.71.62'}], 'gateway_ip': '109.189.71.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '109.189.71.2', 'tags': [], 'project_id': 'b8c012c856b541e6983f357407fe2d52'}} 2025-04-08 16:37:06,954 - INFO - Created VN ctest-vn-06789441 2025-04-08 16:37:07,015 - DEBUG - VN ctest-vn-06789441 UUID is fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:07,199 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816, 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-11021731:ctest-vn-06789441, 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-04-08 16:37:07,622 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '7facf8f3-f3ea-4e4b-b9f4-de009e3a626f', 'name': 'ctest-vn-61532816', 'tenant_id': 'b8c012c856b541e6983f357407fe2d52', 'project_id': 'b8c012c856b541e6983f357407fe2d52', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3e07135a-a33d-4e07-b7ca-de35091f258d'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-vn-61532816'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-policy-allow-all-88069705']], 'subnet_ipam': [{'subnet_cidr': '143.127.38.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-04-08 16:37:07,998 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'fc9678a2-d58a-4071-b2b9-d4908e684d9b', 'name': 'ctest-vn-06789441', 'tenant_id': 'b8c012c856b541e6983f357407fe2d52', 'project_id': 'b8c012c856b541e6983f357407fe2d52', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['8b0af087-fe5c-4c2d-a95a-a07607a77579'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-vn-06789441'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-policy-allow-all-88069705']], 'subnet_ipam': [{'subnet_cidr': '109.189.71.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-04-08 16:37:08,210 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:08,531 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:08,584 - DEBUG - Requesting: http://10.0.0.12:8082/domain/b00a2cc9-a99e-4eb0-bc61-80c555d844f9 2025-04-08 16:37:08,615 - DEBUG - Requesting: http://10.0.0.12:8082/project/b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:08,732 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:08,759 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:08,786 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/ce5c4c2b-d1c6-4d0f-ac8a-9fc638ea38b1 2025-04-08 16:37:08,808 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/ce5c4c2b-d1c6-4d0f-ac8a-9fc638ea38b1 2025-04-08 16:37:08,826 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/807ad1d0-c457-4bc2-82ff-f98b843fb09d 2025-04-08 16:37:08,844 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-04-08 16:37:08,844 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:08,869 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/ce5c4c2b-d1c6-4d0f-ac8a-9fc638ea38b1 2025-04-08 16:37:08,898 - INFO - Verified VN network id 8 for VN 7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:08,899 - INFO - Verifications in API Server for VN ctest-vn-61532816 passed 2025-04-08 16:37:08,899 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:08,925 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/ce5c4c2b-d1c6-4d0f-ac8a-9fc638ea38b1 2025-04-08 16:37:08,942 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/ce5c4c2b-d1c6-4d0f-ac8a-9fc638ea38b1 2025-04-08 16:37:08,959 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/807ad1d0-c457-4bc2-82ff-f98b843fb09d 2025-04-08 16:37:08,990 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-11021731', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9200001865464041035', 'uuid-lslong': '13399578885570060911'}, 'enable': 'true', 'created': '2025-04-08T16:37:05', 'last-modified': '2025-04-08T16:37:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.417276'} 2025-04-08 16:37:09,011 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-04-08 16:37:09,011 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-61532816 passed 2025-04-08 16:37:09,012 - DEBUG - ====Verifying policy data for ctest-vn-61532816 in API_Server ====== 2025-04-08 16:37:09,012 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:09,024 - DEBUG - Requesting: http://10.0.0.12:8082/domain/b00a2cc9-a99e-4eb0-bc61-80c555d844f9 2025-04-08 16:37:09,050 - DEBUG - Requesting: http://10.0.0.12:8082/project/b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:09,174 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/7facf8f3-f3ea-4e4b-b9f4-de009e3a626f 2025-04-08 16:37:09,203 - DEBUG - ==>Verifying data for policy with id: e5cb7a95-6197-4339-8696-74d910cae6cc, fqn: ['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-policy-allow-all-88069705'] 2025-04-08 16:37:09,203 - INFO - VN ctest-vn-61532816 Policy verification: verify_vn_policy_in_api_server, status: True 2025-04-08 16:37:09,203 - DEBUG - Verifying the vn in opserver 2025-04-08 16:37:09,203 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816 virtual network link through opserver 10.0.0.12 2025-04-08 16:37:09,204 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks 2025-04-08 16:37:09,559 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks 2025-04-08 16:37:09,668 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816?flat'} 2025-04-08 16:37:09,669 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816 is found in opserver 2025-04-08 16:37:09,712 - DEBUG - Do not have enough data to verify VN in agent 2025-04-08 16:37:09,724 - DEBUG - VRF ids for VN ctest-vn-61532816: {} 2025-04-08 16:37:09,724 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:09,734 - DEBUG - Requesting: http://10.0.0.12:8082/domain/b00a2cc9-a99e-4eb0-bc61-80c555d844f9 2025-04-08 16:37:09,759 - DEBUG - Requesting: http://10.0.0.12:8082/project/b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:09,846 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:09,868 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:09,885 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/1a15405d-b410-4a4b-80e0-2379eae12377 2025-04-08 16:37:09,898 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/1a15405d-b410-4a4b-80e0-2379eae12377 2025-04-08 16:37:09,908 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/177b3fda-218b-46b7-b8a9-ff8badf14491 2025-04-08 16:37:09,918 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-08 16:37:09,918 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:09,932 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/1a15405d-b410-4a4b-80e0-2379eae12377 2025-04-08 16:37:09,951 - INFO - Verified VN network id 9 for VN fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:09,952 - INFO - Verifications in API Server for VN ctest-vn-06789441 passed 2025-04-08 16:37:09,952 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:09,965 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/1a15405d-b410-4a4b-80e0-2379eae12377 2025-04-08 16:37:09,975 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/1a15405d-b410-4a4b-80e0-2379eae12377 2025-04-08 16:37:09,985 - DEBUG - Requesting: http://10.0.0.12:8082/route-target/177b3fda-218b-46b7-b8a9-ff8badf14491 2025-04-08 16:37:10,010 - DEBUG - Control-node 10.0.0.12 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-06789441', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-11021731', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18200867584827080817', 'uuid-lslong': '12878558326772223387'}, 'enable': 'true', 'created': '2025-04-08T16:37:06', 'last-modified': '2025-04-08T16:37:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.061076'} 2025-04-08 16:37:10,024 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-08 16:37:10,026 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-06789441 passed 2025-04-08 16:37:10,026 - DEBUG - ====Verifying policy data for ctest-vn-06789441 in API_Server ====== 2025-04-08 16:37:10,026 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:10,038 - DEBUG - Requesting: http://10.0.0.12:8082/domain/b00a2cc9-a99e-4eb0-bc61-80c555d844f9 2025-04-08 16:37:10,074 - DEBUG - Requesting: http://10.0.0.12:8082/project/b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:10,215 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-network/fc9678a2-d58a-4071-b2b9-d4908e684d9b 2025-04-08 16:37:10,246 - DEBUG - ==>Verifying data for policy with id: e5cb7a95-6197-4339-8696-74d910cae6cc, fqn: ['default-domain', 'ctest-TestBasicPolicy-11021731', 'ctest-policy-allow-all-88069705'] 2025-04-08 16:37:10,247 - INFO - VN ctest-vn-06789441 Policy verification: verify_vn_policy_in_api_server, status: True 2025-04-08 16:37:10,247 - DEBUG - Verifying the vn in opserver 2025-04-08 16:37:10,247 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-06789441 virtual network link through opserver 10.0.0.12 2025-04-08 16:37:10,247 - DEBUG - Requesting: http://10.0.0.12:8081/analytics/uves/virtual-networks 2025-04-08 16:37:10,282 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-06789441', 'href': 'http://10.0.0.12:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-06789441?flat'} 2025-04-08 16:37:10,282 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-06789441 is found in opserver 2025-04-08 16:37:10,326 - DEBUG - Do not have enough data to verify VN in agent 2025-04-08 16:37:10,346 - DEBUG - VRF ids for VN ctest-vn-06789441: {} 2025-04-08 16:37:10,508 - DEBUG - Services list from nova: [, , ] 2025-04-08 16:37:12,219 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3056-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3056-1) 2025-04-08 16:37:13,875 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3056-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3056-1) 2025-04-08 16:37:13,876 - INFO - Waiting for VM ctest-TestBasicPolicy-11021731-20654576 to be up.. 2025-04-08 16:37:13,992 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-04-08 16:37:19,228 - DEBUG - VM is in ACTIVE state now 2025-04-08 16:37:19,229 - INFO - VM name : ctest-TestBasicPolicy-11021731-20654576 2025-04-08 16:37:19,402 - DEBUG - VM ctest-TestBasicPolicy-11021731-20654576 ID is 2848731c-bd97-44c9-b6a2-ad13de3b523c 2025-04-08 16:37:19,428 - DEBUG - VM ctest-TestBasicPolicy-11021731-20654576 launched on Node cn-jenkins-deploy-platform-ansible-os-3056-1 2025-04-08 16:37:19,554 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/2848731c-bd97-44c9-b6a2-ad13de3b523c 2025-04-08 16:37:19,568 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/0e4b1bb8-1c52-42ac-b917-cbba4c7f01c3 2025-04-08 16:37:22,889 - 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 1005ms') 2025-04-08 16:37:22,889 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-11021731-20654576 failed! 2025-04-08 16:37:22,972 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-11021731:ctest-vn-61532816 is 143.127.38.65 and allocation pool is NOT set 2025-04-08 16:37:25,051 - 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=14.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.68 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.676/8.299/14.922/6.623 ms') 2025-04-08 16:37:25,052 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-11021731-20654576 passed 2025-04-08 16:37:25,223 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-08 16:37:25,223 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-11021731-20654576, IP 143.127.38.67, Port 22 2025-04-08 16:37:25,420 - DEBUG - VM ctest-TestBasicPolicy-11021731-20654576 is ready for SSH connections 2025-04-08 16:37:25,420 - INFO - Waiting for VM ctest-TestBasicPolicy-11021731-90391072 to be up.. 2025-04-08 16:37:25,615 - DEBUG - VM is in ACTIVE state now 2025-04-08 16:37:25,615 - INFO - VM name : ctest-TestBasicPolicy-11021731-90391072 2025-04-08 16:37:25,720 - DEBUG - VM ctest-TestBasicPolicy-11021731-90391072 ID is ce75670f-4860-46a2-820e-c91c021ba21f 2025-04-08 16:37:25,720 - DEBUG - VM ctest-TestBasicPolicy-11021731-90391072 launched on Node cn-jenkins-deploy-platform-ansible-os-3056-1 2025-04-08 16:37:25,873 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine/ce75670f-4860-46a2-820e-c91c021ba21f 2025-04-08 16:37:25,894 - DEBUG - Requesting: http://10.0.0.12:8082/virtual-machine-interface/d9566ee8-136e-4ab2-bb20-88306af79aa4 2025-04-08 16:37:27,259 - 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=5.94 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.24 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 1.241/3.592/5.944/2.351 ms') 2025-04-08 16:37:27,259 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-11021731-90391072 passed 2025-04-08 16:37:27,426 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-08 16:37:27,426 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-11021731-90391072, IP 109.189.71.3, Port 22 2025-04-08 16:37:27,613 - DEBUG - VM ctest-TestBasicPolicy-11021731-90391072 is ready for SSH connections 2025-04-08 16:37:27,613 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-08 16:37:27,614 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 109.189.71.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123 2025-04-08 16:37:27,614 - DEBUG - ping -s 56 -c 3 -W 1 109.189.71.3 2025-04-08 16:37:32,134 - DEBUG - PING 109.189.71.3 (109.189.71.3): 56 data bytes 64 bytes from 109.189.71.3: seq=0 ttl=63 time=5.563 ms 64 bytes from 109.189.71.3: seq=1 ttl=63 time=1.688 ms 64 bytes from 109.189.71.3: seq=2 ttl=63 time=2.301 ms --- 109.189.71.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.688/3.184/5.563 ms 2025-04-08 16:37:32,135 - INFO - Ping to IP 109.189.71.3 from VM ctest-TestBasicPolicy-11021731-20654576 passed 2025-04-08 16:37:32,410 - DEBUG - Policy Update Response {"network-policy": {"uuid": "e5cb7a95-6197-4339-8696-74d910cae6cc", "href": "http://10.0.0.12:8082/network-policy/e5cb7a95-6197-4339-8696-74d910cae6cc"}} 2025-04-08 16:37:32,410 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-08 16:37:32,410 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 109.189.71.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.12, gateway password: c0ntrail123 2025-04-08 16:37:32,410 - DEBUG - ping -s 56 -c 3 -W 1 109.189.71.3 2025-04-08 16:37:35,720 - DEBUG - PING 109.189.71.3 (109.189.71.3): 56 data bytes --- 109.189.71.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-04-08 16:37:35,720 - WARNING - Ping to IP 109.189.71.3 from VM ctest-TestBasicPolicy-11021731-20654576 failed 2025-04-08 16:37:35,720 - INFO - Deleting VM ctest-TestBasicPolicy-11021731-90391072 2025-04-08 16:37:35,849 - INFO - Deleting VM ctest-TestBasicPolicy-11021731-20654576 2025-04-08 16:37:36,478 - INFO - Deleted policy ctest-policy-allow-all-88069705 2025-04-08 16:37:36,478 - INFO - Deleting VN ctest-vn-06789441 2025-04-08 16:37:36,521 - DEBUG - VN fc9678a2-d58a-4071-b2b9-d4908e684d9b still in use: Unable to complete operation on network fc9678a2-d58a-4071-b2b9-d4908e684d9b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6fabc232-1b16-4ffb-a9d0-5d20b99dfb00'] 2025-04-08 16:37:36,521 - WARNING - Deleting VN ctest-vn-06789441 failed..Will retry 2025-04-08 16:37:38,726 - DEBUG - Response for deleting network () 2025-04-08 16:37:38,727 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/1a15405d-b410-4a4b-80e0-2379eae12377 2025-04-08 16:37:38,737 - DEBUG - Response Code: 404 2025-04-08 16:37:38,738 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:38,744 - DEBUG - Requesting: http://10.0.0.12:8082/domain/b00a2cc9-a99e-4eb0-bc61-80c555d844f9 2025-04-08 16:37:38,804 - DEBUG - Requesting: http://10.0.0.12:8082/project/b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:38,909 - INFO - Validated that VN ctest-vn-06789441 is not found in API Server 2025-04-08 16:37:38,930 - DEBUG - VN ctest-vn-06789441 is not present in Agent 10.0.0.12 2025-04-08 16:37:38,930 - INFO - Validated that VN ctest-vn-06789441 is not in any agent 2025-04-08 16:37:38,957 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-06789441 info 2025-04-08 16:37:38,957 - INFO - Deleting VN ctest-vn-61532816 2025-04-08 16:37:39,182 - DEBUG - Response for deleting network () 2025-04-08 16:37:39,182 - DEBUG - Requesting: http://10.0.0.12:8082/routing-instance/ce5c4c2b-d1c6-4d0f-ac8a-9fc638ea38b1 2025-04-08 16:37:39,191 - DEBUG - Response Code: 404 2025-04-08 16:37:39,191 - DEBUG - Requesting: http://10.0.0.12:8082/domains 2025-04-08 16:37:39,200 - DEBUG - Requesting: http://10.0.0.12:8082/domain/b00a2cc9-a99e-4eb0-bc61-80c555d844f9 2025-04-08 16:37:39,266 - DEBUG - Requesting: http://10.0.0.12:8082/project/b8c012c8-56b5-41e6-983f-357407fe2d52 2025-04-08 16:37:39,369 - INFO - Validated that VN ctest-vn-61532816 is not found in API Server 2025-04-08 16:37:39,394 - DEBUG - VN ctest-vn-61532816 is not present in Agent 10.0.0.12 2025-04-08 16:37:39,395 - INFO - Validated that VN ctest-vn-61532816 is not in any agent 2025-04-08 16:37:39,435 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-61532816 info 2025-04-08 16:37:39,767 - DEBUG - No XMPP flaps were noticed during the test
2025-04-08 16:37:39,767 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:35]
2025-04-08 16:37:39,767 - INFO - -------------------------------------------------------------------------------- 2025-04-08 16:37:40,728 - INFO - Deleted project: ctest-TestBasicPolicy-11021731, ID : b8c012c8-56b5-41e6-983f-357407fe2d52