2025-04-11 14:59:00,884 - INFO - Domain Default found not creating 2025-04-11 14:59:01,075 - INFO - Project ctest-TestBasicPolicy-12267776 not found, creating it 2025-04-11 14:59:01,600 - INFO - Created Project:ctest-TestBasicPolicy-12267776, ID : 714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:03,443 - INFO - ================================================================================ 2025-04-11 14:59:03,443 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-04-11 14:59:03,443 - 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-11 14:59:03,709 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.14': '0'}} with 2025-04-11 14:59:03,709 - INFO - Initial checks done. Running the testcase now 2025-04-11 14:59:03,710 - INFO - 2025-04-11 14:59:04,351 - DEBUG - Response for create_network : {'network': {'id': '83390412-34c3-4290-9be8-7d081574fdef', 'name': 'ctest-vn-39321830', 'tenant_id': '714dc4a54ade4405941ff25afe823f3f', 'project_id': '714dc4a54ade4405941ff25afe823f3f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-vn-39321830'], 'port_security_enabled': True, 'description': ''}} 2025-04-11 14:59:04,575 - DEBUG - Response for create_subnet : {'subnet': {'id': '0dc358ef-cd2e-4f0c-8af5-9b6b034cf6e9', 'name': '', 'tenant_id': '714dc4a54ade4405941ff25afe823f3f', 'network_id': '83390412-34c3-4290-9be8-7d081574fdef', 'ip_version': 4, 'cidr': '29.173.68.64/26', 'allocation_pools': [{'start': '29.173.68.66', 'end': '29.173.68.126'}], 'gateway_ip': '29.173.68.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.173.68.66', 'tags': [], 'project_id': '714dc4a54ade4405941ff25afe823f3f'}} 2025-04-11 14:59:04,597 - INFO - Created VN ctest-vn-39321830 2025-04-11 14:59:04,654 - DEBUG - VN ctest-vn-39321830 UUID is 83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:05,011 - DEBUG - Response for create_network : {'network': {'id': 'fee74277-a64c-4214-9a6e-895a9a0174ca', 'name': 'ctest-vn-31314247', 'tenant_id': '714dc4a54ade4405941ff25afe823f3f', 'project_id': '714dc4a54ade4405941ff25afe823f3f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-vn-31314247'], 'port_security_enabled': True, 'description': ''}} 2025-04-11 14:59:05,266 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f11d699f-6f47-45e4-9087-534489ea1f32', 'name': '', 'tenant_id': '714dc4a54ade4405941ff25afe823f3f', 'network_id': 'fee74277-a64c-4214-9a6e-895a9a0174ca', 'ip_version': 4, 'cidr': '180.128.232.192/26', 'allocation_pools': [{'start': '180.128.232.194', 'end': '180.128.232.254'}], 'gateway_ip': '180.128.232.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '180.128.232.194', 'tags': [], 'project_id': '714dc4a54ade4405941ff25afe823f3f'}} 2025-04-11 14:59:05,289 - INFO - Created VN ctest-vn-31314247 2025-04-11 14:59:05,350 - DEBUG - VN ctest-vn-31314247 UUID is fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:05,487 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830, 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-12267776:ctest-vn-31314247, 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-11 14:59:05,857 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '83390412-34c3-4290-9be8-7d081574fdef', 'name': 'ctest-vn-39321830', 'tenant_id': '714dc4a54ade4405941ff25afe823f3f', 'project_id': '714dc4a54ade4405941ff25afe823f3f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['0dc358ef-cd2e-4f0c-8af5-9b6b034cf6e9'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-vn-39321830'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-policy-allow-all-75384722']], 'subnet_ipam': [{'subnet_cidr': '29.173.68.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-04-11 14:59:06,135 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'fee74277-a64c-4214-9a6e-895a9a0174ca', 'name': 'ctest-vn-31314247', 'tenant_id': '714dc4a54ade4405941ff25afe823f3f', 'project_id': '714dc4a54ade4405941ff25afe823f3f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['f11d699f-6f47-45e4-9087-534489ea1f32'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-vn-31314247'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-policy-allow-all-75384722']], 'subnet_ipam': [{'subnet_cidr': '180.128.232.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-04-11 14:59:06,318 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:06,633 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:06,675 - DEBUG - Requesting: http://10.0.0.22:8082/domain/1247c032-58e4-4531-9532-bdd80c4adf7b 2025-04-11 14:59:06,692 - DEBUG - Requesting: http://10.0.0.22:8082/project/714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:06,776 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:06,791 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:06,805 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6dc85f3d-50e9-42f3-b08a-8b90e90abe38 2025-04-11 14:59:06,815 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6dc85f3d-50e9-42f3-b08a-8b90e90abe38 2025-04-11 14:59:06,825 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/2ffd5ae0-d11a-45b9-a561-cecdc24bf547 2025-04-11 14:59:06,834 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-11 14:59:06,834 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:06,849 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6dc85f3d-50e9-42f3-b08a-8b90e90abe38 2025-04-11 14:59:06,870 - INFO - Verified VN network id 9 for VN 83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:06,870 - INFO - Verifications in API Server for VN ctest-vn-39321830 passed 2025-04-11 14:59:06,870 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:06,883 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6dc85f3d-50e9-42f3-b08a-8b90e90abe38 2025-04-11 14:59:06,893 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6dc85f3d-50e9-42f3-b08a-8b90e90abe38 2025-04-11 14:59:06,903 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/2ffd5ae0-d11a-45b9-a561-cecdc24bf547 2025-04-11 14:59:06,920 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-12267776', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9455593368882201232', 'uuid-lslong': '11234366744148901359'}, 'enable': 'true', 'created': '2025-04-11T14:59:04', 'last-modified': '2025-04-11T14:59:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.101813'} 2025-04-11 14:59:06,928 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-11 14:59:06,928 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-39321830 passed 2025-04-11 14:59:06,928 - DEBUG - ====Verifying policy data for ctest-vn-39321830 in API_Server ====== 2025-04-11 14:59:06,928 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:06,936 - DEBUG - Requesting: http://10.0.0.22:8082/domain/1247c032-58e4-4531-9532-bdd80c4adf7b 2025-04-11 14:59:06,950 - DEBUG - Requesting: http://10.0.0.22:8082/project/714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:07,030 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/83390412-34c3-4290-9be8-7d081574fdef 2025-04-11 14:59:07,046 - DEBUG - ==>Verifying data for policy with id: 761a4390-fb5c-423c-9c9c-6d658616b7a8, fqn: ['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-policy-allow-all-75384722'] 2025-04-11 14:59:07,046 - INFO - VN ctest-vn-39321830 Policy verification: verify_vn_policy_in_api_server, status: True 2025-04-11 14:59:07,046 - DEBUG - Verifying the vn in opserver 2025-04-11 14:59:07,046 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830 virtual network link through opserver 10.0.0.22 2025-04-11 14:59:07,046 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-04-11 14:59:07,356 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-04-11 14:59:07,425 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830?flat'} 2025-04-11 14:59:07,425 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830 is found in opserver 2025-04-11 14:59:07,437 - DEBUG - Do not have enough data to verify VN in agent 2025-04-11 14:59:07,442 - DEBUG - VRF ids for VN ctest-vn-39321830: {} 2025-04-11 14:59:07,443 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:07,449 - DEBUG - Requesting: http://10.0.0.22:8082/domain/1247c032-58e4-4531-9532-bdd80c4adf7b 2025-04-11 14:59:07,463 - DEBUG - Requesting: http://10.0.0.22:8082/project/714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:07,546 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:07,561 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:07,573 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f5388ea-0ca9-4747-922e-1e0abfb48dd8 2025-04-11 14:59:07,584 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f5388ea-0ca9-4747-922e-1e0abfb48dd8 2025-04-11 14:59:07,594 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/b8deb0e1-4ee8-4406-be75-f7b3b1c603ac 2025-04-11 14:59:07,602 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-04-11 14:59:07,602 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:07,613 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f5388ea-0ca9-4747-922e-1e0abfb48dd8 2025-04-11 14:59:07,632 - INFO - Verified VN network id 10 for VN fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:07,632 - INFO - Verifications in API Server for VN ctest-vn-31314247 passed 2025-04-11 14:59:07,632 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:07,645 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f5388ea-0ca9-4747-922e-1e0abfb48dd8 2025-04-11 14:59:07,655 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f5388ea-0ca9-4747-922e-1e0abfb48dd8 2025-04-11 14:59:07,665 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/b8deb0e1-4ee8-4406-be75-f7b3b1c603ac 2025-04-11 14:59:07,680 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-31314247', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-12267776', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18367722686912414228', 'uuid-lslong': '11127982751502922954'}, 'enable': 'true', 'created': '2025-04-11T14:59:04', 'last-modified': '2025-04-11T14:59:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.582645'} 2025-04-11 14:59:07,689 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-04-11 14:59:07,689 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-31314247 passed 2025-04-11 14:59:07,689 - DEBUG - ====Verifying policy data for ctest-vn-31314247 in API_Server ====== 2025-04-11 14:59:07,690 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:07,697 - DEBUG - Requesting: http://10.0.0.22:8082/domain/1247c032-58e4-4531-9532-bdd80c4adf7b 2025-04-11 14:59:07,713 - DEBUG - Requesting: http://10.0.0.22:8082/project/714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:07,804 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fee74277-a64c-4214-9a6e-895a9a0174ca 2025-04-11 14:59:07,818 - DEBUG - ==>Verifying data for policy with id: 761a4390-fb5c-423c-9c9c-6d658616b7a8, fqn: ['default-domain', 'ctest-TestBasicPolicy-12267776', 'ctest-policy-allow-all-75384722'] 2025-04-11 14:59:07,818 - INFO - VN ctest-vn-31314247 Policy verification: verify_vn_policy_in_api_server, status: True 2025-04-11 14:59:07,818 - DEBUG - Verifying the vn in opserver 2025-04-11 14:59:07,818 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-31314247 virtual network link through opserver 10.0.0.22 2025-04-11 14:59:07,818 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-04-11 14:59:07,835 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-31314247', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-31314247?flat'} 2025-04-11 14:59:07,835 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-31314247 is found in opserver 2025-04-11 14:59:07,849 - DEBUG - Do not have enough data to verify VN in agent 2025-04-11 14:59:07,856 - DEBUG - VRF ids for VN ctest-vn-31314247: {} 2025-04-11 14:59:07,923 - DEBUG - Services list from nova: [, , ] 2025-04-11 14:59:09,108 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3081-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3081-1) 2025-04-11 14:59:10,396 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3081-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3081-1) 2025-04-11 14:59:10,396 - INFO - Waiting for VM ctest-TestBasicPolicy-12267776-63644758 to be up.. 2025-04-11 14:59:10,490 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-04-11 14:59:15,597 - DEBUG - VM is in ACTIVE state now 2025-04-11 14:59:15,597 - INFO - VM name : ctest-TestBasicPolicy-12267776-63644758 2025-04-11 14:59:15,699 - DEBUG - VM ctest-TestBasicPolicy-12267776-63644758 ID is 31269cb3-38c4-4ce4-8a9e-1ad762a08cdd 2025-04-11 14:59:15,721 - DEBUG - VM ctest-TestBasicPolicy-12267776-63644758 launched on Node cn-jenkins-deploy-platform-ansible-os-3081-1 2025-04-11 14:59:15,826 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/31269cb3-38c4-4ce4-8a9e-1ad762a08cdd 2025-04-11 14:59:15,837 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/65a5047e-9b73-4527-9b25-d2e91f0436fc 2025-04-11 14:59:19,105 - 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 1018ms') 2025-04-11 14:59:19,106 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-12267776-63644758 failed! 2025-04-11 14:59:19,166 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-12267776:ctest-vn-39321830 is 29.173.68.65 and allocation pool is NOT set 2025-04-11 14:59:21,233 - 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=10.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.698 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 = 0.698/5.498/10.298/4.800 ms') 2025-04-11 14:59:21,233 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-12267776-63644758 passed 2025-04-11 14:59:21,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-11 14:59:21,406 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-12267776-63644758, IP 29.173.68.67, Port 22 2025-04-11 14:59:21,572 - DEBUG - VM ctest-TestBasicPolicy-12267776-63644758 is ready for SSH connections 2025-04-11 14:59:21,572 - INFO - Waiting for VM ctest-TestBasicPolicy-12267776-95899420 to be up.. 2025-04-11 14:59:21,690 - DEBUG - VM is in ACTIVE state now 2025-04-11 14:59:21,691 - INFO - VM name : ctest-TestBasicPolicy-12267776-95899420 2025-04-11 14:59:21,794 - DEBUG - VM ctest-TestBasicPolicy-12267776-95899420 ID is bbd4dcbd-3d40-4306-8fc0-08335da6ee3d 2025-04-11 14:59:21,794 - DEBUG - VM ctest-TestBasicPolicy-12267776-95899420 launched on Node cn-jenkins-deploy-platform-ansible-os-3081-1 2025-04-11 14:59:21,893 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/bbd4dcbd-3d40-4306-8fc0-08335da6ee3d 2025-04-11 14:59:21,915 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/6b822110-780b-42ac-a910-08c31ff5e276 2025-04-11 14:59:23,178 - 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=9.69 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.44 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 = 2.442/6.063/9.685/3.621 ms') 2025-04-11 14:59:23,178 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-12267776-95899420 passed 2025-04-11 14:59:23,338 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-11 14:59:23,338 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-12267776-95899420, IP 180.128.232.195, Port 22 2025-04-11 14:59:23,394 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-04-11 14:59:23,502 - DEBUG - VM ctest-TestBasicPolicy-12267776-95899420 is NOT ready for SSH connections, VM status: ACTIVE 2025-04-11 14:59:28,503 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-11 14:59:28,503 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-12267776-95899420, IP 180.128.232.195, Port 22 2025-04-11 14:59:28,680 - DEBUG - VM ctest-TestBasicPolicy-12267776-95899420 is ready for SSH connections 2025-04-11 14:59:28,681 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-11 14:59:28,681 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 180.128.232.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-04-11 14:59:28,681 - DEBUG - ping -s 56 -c 3 -W 1 180.128.232.195 2025-04-11 14:59:32,396 - DEBUG - PING 180.128.232.195 (180.128.232.195): 56 data bytes 64 bytes from 180.128.232.195: seq=0 ttl=63 time=4.238 ms 64 bytes from 180.128.232.195: seq=1 ttl=63 time=1.285 ms 64 bytes from 180.128.232.195: seq=2 ttl=63 time=1.293 ms --- 180.128.232.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.285/2.272/4.238 ms 2025-04-11 14:59:32,396 - INFO - Ping to IP 180.128.232.195 from VM ctest-TestBasicPolicy-12267776-63644758 passed 2025-04-11 14:59:32,585 - DEBUG - Policy Update Response {"network-policy": {"uuid": "761a4390-fb5c-423c-9c9c-6d658616b7a8", "href": "http://10.0.0.22:8082/network-policy/761a4390-fb5c-423c-9c9c-6d658616b7a8"}} 2025-04-11 14:59:32,586 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-11 14:59:32,586 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 180.128.232.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-04-11 14:59:32,586 - DEBUG - ping -s 56 -c 3 -W 1 180.128.232.195 2025-04-11 14:59:35,809 - DEBUG - PING 180.128.232.195 (180.128.232.195): 56 data bytes 64 bytes from 180.128.232.195: seq=0 ttl=63 time=1.975 ms --- 180.128.232.195 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.975/1.975/1.975 ms 2025-04-11 14:59:35,810 - WARNING - Ping to IP 180.128.232.195 from VM ctest-TestBasicPolicy-12267776-63644758 failed 2025-04-11 14:59:35,810 - INFO - Deleting VM ctest-TestBasicPolicy-12267776-95899420 2025-04-11 14:59:35,907 - INFO - Deleting VM ctest-TestBasicPolicy-12267776-63644758 2025-04-11 14:59:36,414 - INFO - Deleted policy ctest-policy-allow-all-75384722 2025-04-11 14:59:36,414 - INFO - Deleting VN ctest-vn-31314247 2025-04-11 14:59:36,467 - DEBUG - VN fee74277-a64c-4214-9a6e-895a9a0174ca still in use: Unable to complete operation on network fee74277-a64c-4214-9a6e-895a9a0174ca. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-22c64675-4b5e-40a4-8ccd-c4f59625fa1c'] 2025-04-11 14:59:36,467 - WARNING - Deleting VN ctest-vn-31314247 failed..Will retry 2025-04-11 14:59:38,611 - DEBUG - Response for deleting network () 2025-04-11 14:59:38,611 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2f5388ea-0ca9-4747-922e-1e0abfb48dd8 2025-04-11 14:59:38,620 - DEBUG - Response Code: 404 2025-04-11 14:59:38,620 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:38,628 - DEBUG - Requesting: http://10.0.0.22:8082/domain/1247c032-58e4-4531-9532-bdd80c4adf7b 2025-04-11 14:59:38,690 - DEBUG - Requesting: http://10.0.0.22:8082/project/714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:38,773 - INFO - Validated that VN ctest-vn-31314247 is not found in API Server 2025-04-11 14:59:38,787 - DEBUG - VN ctest-vn-31314247 is not present in Agent 10.0.0.22 2025-04-11 14:59:38,788 - INFO - Validated that VN ctest-vn-31314247 is not in any agent 2025-04-11 14:59:38,807 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-31314247 info 2025-04-11 14:59:38,807 - INFO - Deleting VN ctest-vn-39321830 2025-04-11 14:59:38,940 - DEBUG - Response for deleting network () 2025-04-11 14:59:38,940 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6dc85f3d-50e9-42f3-b08a-8b90e90abe38 2025-04-11 14:59:38,949 - DEBUG - Response Code: 404 2025-04-11 14:59:38,949 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-04-11 14:59:38,957 - DEBUG - Requesting: http://10.0.0.22:8082/domain/1247c032-58e4-4531-9532-bdd80c4adf7b 2025-04-11 14:59:39,016 - DEBUG - Requesting: http://10.0.0.22:8082/project/714dc4a5-4ade-4405-941f-f25afe823f3f 2025-04-11 14:59:39,091 - INFO - Validated that VN ctest-vn-39321830 is not found in API Server 2025-04-11 14:59:39,103 - DEBUG - VN ctest-vn-39321830 is not present in Agent 10.0.0.22 2025-04-11 14:59:39,103 - INFO - Validated that VN ctest-vn-39321830 is not in any agent 2025-04-11 14:59:39,122 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-39321830 info 2025-04-11 14:59:39,395 - DEBUG - No XMPP flaps were noticed during the test
2025-04-11 14:59:39,395 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:36]
2025-04-11 14:59:39,396 - INFO - -------------------------------------------------------------------------------- 2025-04-11 14:59:40,135 - INFO - Deleted project: ctest-TestBasicPolicy-12267776, ID : 714dc4a5-4ade-4405-941f-f25afe823f3f