2025-01-01 22:26:32,994 - INFO - Domain Default found not creating 2025-01-01 22:26:33,126 - INFO - Project ctest-TestBasicPolicy-99416813 not found, creating it 2025-01-01 22:26:33,579 - INFO - Created Project:ctest-TestBasicPolicy-99416813, ID : 86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:26:35,092 - INFO - ================================================================================ 2025-01-01 22:26:35,093 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-01-01 22:26:35,093 - 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-01-01 22:26:35,347 - DEBUG - Nothing to compare xmpp stats {'10.0.0.34': {'10.20.0.32': '0'}} with 2025-01-01 22:26:35,347 - INFO - Initial checks done. Running the testcase now 2025-01-01 22:26:35,347 - INFO - 2025-01-01 22:26:35,913 - DEBUG - Response for create_network : {'network': {'id': '7cb72fb5-fee4-4a13-8238-9ee0d0ecf493', 'name': 'ctest-vn-18510640', 'tenant_id': '86338af884d44afda433dbac5e7cb6d0', 'project_id': '86338af884d44afda433dbac5e7cb6d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-vn-18510640'], 'port_security_enabled': True, 'description': ''}} 2025-01-01 22:26:36,132 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b050da4f-1a92-448e-875b-dbd4010407c1', 'name': '', 'tenant_id': '86338af884d44afda433dbac5e7cb6d0', 'network_id': '7cb72fb5-fee4-4a13-8238-9ee0d0ecf493', 'ip_version': 4, 'cidr': '130.1.193.0/26', 'allocation_pools': [{'start': '130.1.193.2', 'end': '130.1.193.62'}], 'gateway_ip': '130.1.193.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.1.193.2', 'tags': [], 'project_id': '86338af884d44afda433dbac5e7cb6d0'}} 2025-01-01 22:26:36,150 - INFO - Created VN ctest-vn-18510640 2025-01-01 22:26:36,204 - DEBUG - VN ctest-vn-18510640 UUID is 7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:36,528 - DEBUG - Response for create_network : {'network': {'id': 'dad7422d-f413-4511-a471-79546d785fc8', 'name': 'ctest-vn-93060871', 'tenant_id': '86338af884d44afda433dbac5e7cb6d0', 'project_id': '86338af884d44afda433dbac5e7cb6d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-vn-93060871'], 'port_security_enabled': True, 'description': ''}} 2025-01-01 22:26:36,669 - DEBUG - Response for create_subnet : {'subnet': {'id': '4dcda7fc-9be6-4232-a3be-c417027bdf0c', 'name': '', 'tenant_id': '86338af884d44afda433dbac5e7cb6d0', 'network_id': 'dad7422d-f413-4511-a471-79546d785fc8', 'ip_version': 4, 'cidr': '53.68.69.128/26', 'allocation_pools': [{'start': '53.68.69.130', 'end': '53.68.69.190'}], 'gateway_ip': '53.68.69.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '53.68.69.130', 'tags': [], 'project_id': '86338af884d44afda433dbac5e7cb6d0'}} 2025-01-01 22:26:36,687 - INFO - Created VN ctest-vn-93060871 2025-01-01 22:26:36,740 - DEBUG - VN ctest-vn-93060871 UUID is dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:36,892 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640, 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-99416813:ctest-vn-93060871, 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-01-01 22:26:37,190 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '7cb72fb5-fee4-4a13-8238-9ee0d0ecf493', 'name': 'ctest-vn-18510640', 'tenant_id': '86338af884d44afda433dbac5e7cb6d0', 'project_id': '86338af884d44afda433dbac5e7cb6d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['b050da4f-1a92-448e-875b-dbd4010407c1'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-vn-18510640'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-policy-allow-all-94469864']], 'subnet_ipam': [{'subnet_cidr': '130.1.193.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-01-01 22:26:37,423 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'dad7422d-f413-4511-a471-79546d785fc8', 'name': 'ctest-vn-93060871', 'tenant_id': '86338af884d44afda433dbac5e7cb6d0', 'project_id': '86338af884d44afda433dbac5e7cb6d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['4dcda7fc-9be6-4232-a3be-c417027bdf0c'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-vn-93060871'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-policy-allow-all-94469864']], 'subnet_ipam': [{'subnet_cidr': '53.68.69.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-01-01 22:26:37,592 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:26:37,930 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:26:37,957 - DEBUG - Requesting: http://10.0.0.34:8082/domain/e1cacb4f-ebcd-426a-8de0-44595684bc30 2025-01-01 22:26:37,970 - DEBUG - Requesting: http://10.0.0.34:8082/project/86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:26:38,044 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:38,055 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:38,064 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/75576f33-74e6-4c1d-a83d-a6e8c62af71e 2025-01-01 22:26:38,071 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/75576f33-74e6-4c1d-a83d-a6e8c62af71e 2025-01-01 22:26:38,077 - DEBUG - Requesting: http://10.0.0.34:8082/route-target/57349cd9-1208-49a0-96cb-fc64d67a828c 2025-01-01 22:26:38,083 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-01-01 22:26:38,083 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:38,092 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/75576f33-74e6-4c1d-a83d-a6e8c62af71e 2025-01-01 22:26:38,105 - INFO - Verified VN network id 8 for VN 7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:38,105 - INFO - Verifications in API Server for VN ctest-vn-18510640 passed 2025-01-01 22:26:38,105 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:38,115 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/75576f33-74e6-4c1d-a83d-a6e8c62af71e 2025-01-01 22:26:38,122 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/75576f33-74e6-4c1d-a83d-a6e8c62af71e 2025-01-01 22:26:38,128 - DEBUG - Requesting: http://10.0.0.34:8082/route-target/57349cd9-1208-49a0-96cb-fc64d67a828c 2025-01-01 22:26:38,140 - DEBUG - Control-node 10.0.0.34 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-99416813', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8986704040153074195', 'uuid-lslong': '9383424512041481363'}, 'enable': 'true', 'created': '2025-01-01T22:26:35', 'last-modified': '2025-01-01T22:26:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.978828'} 2025-01-01 22:26:38,146 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-01-01 22:26:38,147 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-18510640 passed 2025-01-01 22:26:38,147 - DEBUG - ====Verifying policy data for ctest-vn-18510640 in API_Server ====== 2025-01-01 22:26:38,147 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:26:38,152 - DEBUG - Requesting: http://10.0.0.34:8082/domain/e1cacb4f-ebcd-426a-8de0-44595684bc30 2025-01-01 22:26:38,163 - DEBUG - Requesting: http://10.0.0.34:8082/project/86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:26:38,244 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/7cb72fb5-fee4-4a13-8238-9ee0d0ecf493 2025-01-01 22:26:38,257 - DEBUG - ==>Verifying data for policy with id: 2fa3a98b-f32f-4cc5-b266-07ce46e96c37, fqn: ['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-policy-allow-all-94469864'] 2025-01-01 22:26:38,257 - INFO - VN ctest-vn-18510640 Policy verification: verify_vn_policy_in_api_server, status: True 2025-01-01 22:26:38,257 - DEBUG - Verifying the vn in opserver 2025-01-01 22:26:38,257 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640 virtual network link through opserver 10.0.0.34 2025-01-01 22:26:38,257 - DEBUG - Requesting: http://10.0.0.34:8081/analytics/uves/virtual-networks 2025-01-01 22:26:38,588 - DEBUG - Requesting: http://10.0.0.34:8081/analytics/uves/virtual-networks 2025-01-01 22:26:38,640 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640', 'href': 'http://10.0.0.34:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640?flat'} 2025-01-01 22:26:38,640 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640 is found in opserver 2025-01-01 22:26:38,653 - DEBUG - Do not have enough data to verify VN in agent 2025-01-01 22:26:38,659 - DEBUG - VRF ids for VN ctest-vn-18510640: {} 2025-01-01 22:26:38,659 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:26:38,665 - DEBUG - Requesting: http://10.0.0.34:8082/domain/e1cacb4f-ebcd-426a-8de0-44595684bc30 2025-01-01 22:26:38,676 - DEBUG - Requesting: http://10.0.0.34:8082/project/86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:26:38,750 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:38,762 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:38,771 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/ed2f78fb-596a-4625-ac99-c48422a1d041 2025-01-01 22:26:38,778 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/ed2f78fb-596a-4625-ac99-c48422a1d041 2025-01-01 22:26:38,784 - DEBUG - Requesting: http://10.0.0.34:8082/route-target/aa2bb9a3-3192-491a-9b12-d70c988f7ba6 2025-01-01 22:26:38,790 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-01-01 22:26:38,790 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:38,799 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/ed2f78fb-596a-4625-ac99-c48422a1d041 2025-01-01 22:26:38,812 - INFO - Verified VN network id 9 for VN dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:38,812 - INFO - Verifications in API Server for VN ctest-vn-93060871 passed 2025-01-01 22:26:38,812 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:38,822 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/ed2f78fb-596a-4625-ac99-c48422a1d041 2025-01-01 22:26:38,828 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/ed2f78fb-596a-4625-ac99-c48422a1d041 2025-01-01 22:26:38,835 - DEBUG - Requesting: http://10.0.0.34:8082/route-target/aa2bb9a3-3192-491a-9b12-d70c988f7ba6 2025-01-01 22:26:38,848 - DEBUG - Control-node 10.0.0.34 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-93060871', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-99416813', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15769145385396946193', 'uuid-lslong': '11849385498109304776'}, 'enable': 'true', 'created': '2025-01-01T22:26:36', 'last-modified': '2025-01-01T22:26:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.451586'} 2025-01-01 22:26:38,854 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-01-01 22:26:38,854 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-93060871 passed 2025-01-01 22:26:38,854 - DEBUG - ====Verifying policy data for ctest-vn-93060871 in API_Server ====== 2025-01-01 22:26:38,854 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:26:38,859 - DEBUG - Requesting: http://10.0.0.34:8082/domain/e1cacb4f-ebcd-426a-8de0-44595684bc30 2025-01-01 22:26:38,870 - DEBUG - Requesting: http://10.0.0.34:8082/project/86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:26:38,944 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-network/dad7422d-f413-4511-a471-79546d785fc8 2025-01-01 22:26:38,954 - DEBUG - ==>Verifying data for policy with id: 2fa3a98b-f32f-4cc5-b266-07ce46e96c37, fqn: ['default-domain', 'ctest-TestBasicPolicy-99416813', 'ctest-policy-allow-all-94469864'] 2025-01-01 22:26:38,954 - INFO - VN ctest-vn-93060871 Policy verification: verify_vn_policy_in_api_server, status: True 2025-01-01 22:26:38,954 - DEBUG - Verifying the vn in opserver 2025-01-01 22:26:38,954 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-93060871 virtual network link through opserver 10.0.0.34 2025-01-01 22:26:38,954 - DEBUG - Requesting: http://10.0.0.34:8081/analytics/uves/virtual-networks 2025-01-01 22:26:38,963 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-93060871', 'href': 'http://10.0.0.34:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-93060871?flat'} 2025-01-01 22:26:38,963 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-93060871 is found in opserver 2025-01-01 22:26:38,974 - DEBUG - Do not have enough data to verify VN in agent 2025-01-01 22:26:38,979 - DEBUG - VRF ids for VN ctest-vn-93060871: {} 2025-01-01 22:26:39,032 - DEBUG - Services list from nova: [, , ] 2025-01-01 22:26:40,085 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2505-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2505-1) 2025-01-01 22:26:41,366 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2505-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2505-1) 2025-01-01 22:26:41,366 - INFO - Waiting for VM ctest-TestBasicPolicy-99416813-28854828 to be up.. 2025-01-01 22:26:41,447 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-01-01 22:26:46,523 - DEBUG - VM is in ACTIVE state now 2025-01-01 22:26:46,523 - INFO - VM name : ctest-TestBasicPolicy-99416813-28854828 2025-01-01 22:26:46,596 - DEBUG - VM ctest-TestBasicPolicy-99416813-28854828 ID is e0db49cd-fcc3-442f-a82e-259c4ff5e86c 2025-01-01 22:26:46,614 - DEBUG - VM ctest-TestBasicPolicy-99416813-28854828 launched on Node cn-jenkins-deploy-platform-ansible-os-2505-1 2025-01-01 22:26:46,687 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-machine/e0db49cd-fcc3-442f-a82e-259c4ff5e86c 2025-01-01 22:26:46,696 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-machine-interface/1be12122-3c10-4bf9-8886-6c2daf2652ba 2025-01-01 22:26:49,953 - 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 1023ms') 2025-01-01 22:26:49,953 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-99416813-28854828 failed! 2025-01-01 22:26:50,008 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-99416813:ctest-vn-18510640 is 130.1.193.1 and allocation pool is NOT set 2025-01-01 22:26:52,069 - 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=8.00 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.565 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.565/4.284/8.003/3.719 ms') 2025-01-01 22:26:52,069 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-99416813-28854828 passed 2025-01-01 22:26:52,220 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-01 22:26:52,220 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-99416813-28854828, IP 130.1.193.3, Port 22 2025-01-01 22:26:52,384 - DEBUG - VM ctest-TestBasicPolicy-99416813-28854828 is ready for SSH connections 2025-01-01 22:26:52,384 - INFO - Waiting for VM ctest-TestBasicPolicy-99416813-41860320 to be up.. 2025-01-01 22:26:52,461 - DEBUG - VM is in ACTIVE state now 2025-01-01 22:26:52,461 - INFO - VM name : ctest-TestBasicPolicy-99416813-41860320 2025-01-01 22:26:52,539 - DEBUG - VM ctest-TestBasicPolicy-99416813-41860320 ID is 232033f1-84ca-4065-809b-1560f2209efb 2025-01-01 22:26:52,539 - DEBUG - VM ctest-TestBasicPolicy-99416813-41860320 launched on Node cn-jenkins-deploy-platform-ansible-os-2505-1 2025-01-01 22:26:52,612 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-machine/232033f1-84ca-4065-809b-1560f2209efb 2025-01-01 22:26:52,620 - DEBUG - Requesting: http://10.0.0.34:8082/virtual-machine-interface/683f8c37-175f-4933-83bd-ee93290ffed0 2025-01-01 22:26:53,855 - 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.92 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.371 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.371/1.646/2.921/1.275 ms') 2025-01-01 22:26:53,855 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-99416813-41860320 passed 2025-01-01 22:26:54,004 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-01 22:26:54,004 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-99416813-41860320, IP 53.68.69.131, Port 22 2025-01-01 22:26:54,164 - DEBUG - VM ctest-TestBasicPolicy-99416813-41860320 is ready for SSH connections 2025-01-01 22:26:54,165 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-01 22:26:54,165 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.68.69.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.34, gateway password: c0ntrail123 2025-01-01 22:26:54,165 - DEBUG - ping -s 56 -c 3 -W 1 53.68.69.131 2025-01-01 22:26:57,885 - DEBUG - PING 53.68.69.131 (53.68.69.131): 56 data bytes 64 bytes from 53.68.69.131: seq=0 ttl=63 time=3.874 ms 64 bytes from 53.68.69.131: seq=1 ttl=63 time=0.764 ms 64 bytes from 53.68.69.131: seq=2 ttl=63 time=0.764 ms --- 53.68.69.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.764/1.800/3.874 ms 2025-01-01 22:26:57,885 - INFO - Ping to IP 53.68.69.131 from VM ctest-TestBasicPolicy-99416813-28854828 passed 2025-01-01 22:26:58,056 - DEBUG - Policy Update Response {"network-policy": {"uuid": "2fa3a98b-f32f-4cc5-b266-07ce46e96c37", "href": "http://10.0.0.34:8082/network-policy/2fa3a98b-f32f-4cc5-b266-07ce46e96c37"}} 2025-01-01 22:26:58,056 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-01 22:26:58,056 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.68.69.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.34, gateway password: c0ntrail123 2025-01-01 22:26:58,056 - DEBUG - ping -s 56 -c 3 -W 1 53.68.69.131 2025-01-01 22:27:01,271 - DEBUG - PING 53.68.69.131 (53.68.69.131): 56 data bytes --- 53.68.69.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-01-01 22:27:01,271 - WARNING - Ping to IP 53.68.69.131 from VM ctest-TestBasicPolicy-99416813-28854828 failed 2025-01-01 22:27:01,271 - INFO - Deleting VM ctest-TestBasicPolicy-99416813-41860320 2025-01-01 22:27:01,335 - INFO - Deleting VM ctest-TestBasicPolicy-99416813-28854828 2025-01-01 22:27:01,705 - INFO - Deleted policy ctest-policy-allow-all-94469864 2025-01-01 22:27:01,706 - INFO - Deleting VN ctest-vn-93060871 2025-01-01 22:27:01,744 - DEBUG - VN dad7422d-f413-4511-a471-79546d785fc8 still in use: Unable to complete operation on network dad7422d-f413-4511-a471-79546d785fc8. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e74f4550-934b-4f13-84c7-805edc8010ff'] 2025-01-01 22:27:01,744 - WARNING - Deleting VN ctest-vn-93060871 failed..Will retry 2025-01-01 22:27:03,863 - DEBUG - Response for deleting network () 2025-01-01 22:27:03,863 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/ed2f78fb-596a-4625-ac99-c48422a1d041 2025-01-01 22:27:03,871 - DEBUG - Response Code: 404 2025-01-01 22:27:03,871 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:27:03,877 - DEBUG - Requesting: http://10.0.0.34:8082/domain/e1cacb4f-ebcd-426a-8de0-44595684bc30 2025-01-01 22:27:03,934 - DEBUG - Requesting: http://10.0.0.34:8082/project/86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:27:04,013 - INFO - Validated that VN ctest-vn-93060871 is not found in API Server 2025-01-01 22:27:04,024 - DEBUG - VN ctest-vn-93060871 is not present in Agent 10.0.0.34 2025-01-01 22:27:04,024 - INFO - Validated that VN ctest-vn-93060871 is not in any agent 2025-01-01 22:27:04,040 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-93060871 info 2025-01-01 22:27:04,040 - INFO - Deleting VN ctest-vn-18510640 2025-01-01 22:27:04,141 - DEBUG - Response for deleting network () 2025-01-01 22:27:04,142 - DEBUG - Requesting: http://10.0.0.34:8082/routing-instance/75576f33-74e6-4c1d-a83d-a6e8c62af71e 2025-01-01 22:27:04,156 - DEBUG - Response Code: 404 2025-01-01 22:27:04,156 - DEBUG - Requesting: http://10.0.0.34:8082/domains 2025-01-01 22:27:04,166 - DEBUG - Requesting: http://10.0.0.34:8082/domain/e1cacb4f-ebcd-426a-8de0-44595684bc30 2025-01-01 22:27:04,222 - DEBUG - Requesting: http://10.0.0.34:8082/project/86338af8-84d4-4afd-a433-dbac5e7cb6d0 2025-01-01 22:27:04,300 - INFO - Validated that VN ctest-vn-18510640 is not found in API Server 2025-01-01 22:27:04,312 - DEBUG - VN ctest-vn-18510640 is not present in Agent 10.0.0.34 2025-01-01 22:27:04,312 - INFO - Validated that VN ctest-vn-18510640 is not in any agent 2025-01-01 22:27:04,329 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-18510640 info 2025-01-01 22:27:04,582 - DEBUG - No XMPP flaps were noticed during the test
2025-01-01 22:27:04,582 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:29]
2025-01-01 22:27:04,582 - INFO - -------------------------------------------------------------------------------- 2025-01-01 22:27:05,318 - INFO - Deleted project: ctest-TestBasicPolicy-99416813, ID : 86338af8-84d4-4afd-a433-dbac5e7cb6d0