2025-06-30 16:34:56,597 - INFO - Domain Default found not creating 2025-06-30 16:34:56,825 - INFO - Project ctest-TestBasicPolicy-17343104 not found, creating it 2025-06-30 16:34:57,338 - INFO - Created Project:ctest-TestBasicPolicy-17343104, ID : 38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:34:59,018 - INFO - ================================================================================ 2025-06-30 16:34:59,018 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-06-30 16:34:59,018 - 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-06-30 16:34:59,283 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.0.0.38': '0'}} with 2025-06-30 16:34:59,283 - INFO - Initial checks done. Running the testcase now 2025-06-30 16:34:59,283 - INFO - 2025-06-30 16:34:59,912 - DEBUG - Response for create_network : {'network': {'id': '97163478-d9d6-4df5-80a9-53828a4767ca', 'name': 'ctest-vn-96683647', 'tenant_id': '38b7bfecc50a49f5950a07f330872290', 'project_id': '38b7bfecc50a49f5950a07f330872290', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-vn-96683647'], 'port_security_enabled': True, 'description': ''}} 2025-06-30 16:35:00,126 - DEBUG - Response for create_subnet : {'subnet': {'id': '0a4bde89-59d1-4ae4-adc4-a5e103937864', 'name': '', 'tenant_id': '38b7bfecc50a49f5950a07f330872290', 'network_id': '97163478-d9d6-4df5-80a9-53828a4767ca', 'ip_version': 4, 'cidr': '164.55.95.192/26', 'allocation_pools': [{'start': '164.55.95.194', 'end': '164.55.95.254'}], 'gateway_ip': '164.55.95.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.55.95.194', 'tags': [], 'project_id': '38b7bfecc50a49f5950a07f330872290'}} 2025-06-30 16:35:00,147 - INFO - Created VN ctest-vn-96683647 2025-06-30 16:35:00,202 - DEBUG - VN ctest-vn-96683647 UUID is 97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:00,517 - DEBUG - Response for create_network : {'network': {'id': '332a75ee-2c3d-4287-9e25-9e58c12ea858', 'name': 'ctest-vn-47362684', 'tenant_id': '38b7bfecc50a49f5950a07f330872290', 'project_id': '38b7bfecc50a49f5950a07f330872290', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-vn-47362684'], 'port_security_enabled': True, 'description': ''}} 2025-06-30 16:35:00,713 - DEBUG - Response for create_subnet : {'subnet': {'id': '9d5f9c1e-8a0c-490d-95db-15a9e0059549', 'name': '', 'tenant_id': '38b7bfecc50a49f5950a07f330872290', 'network_id': '332a75ee-2c3d-4287-9e25-9e58c12ea858', 'ip_version': 4, 'cidr': '122.133.176.0/26', 'allocation_pools': [{'start': '122.133.176.2', 'end': '122.133.176.62'}], 'gateway_ip': '122.133.176.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '122.133.176.2', 'tags': [], 'project_id': '38b7bfecc50a49f5950a07f330872290'}} 2025-06-30 16:35:00,735 - INFO - Created VN ctest-vn-47362684 2025-06-30 16:35:00,789 - DEBUG - VN ctest-vn-47362684 UUID is 332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:00,924 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647, 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-17343104:ctest-vn-47362684, 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-06-30 16:35:01,232 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '97163478-d9d6-4df5-80a9-53828a4767ca', 'name': 'ctest-vn-96683647', 'tenant_id': '38b7bfecc50a49f5950a07f330872290', 'project_id': '38b7bfecc50a49f5950a07f330872290', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['0a4bde89-59d1-4ae4-adc4-a5e103937864'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-vn-96683647'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-policy-allow-all-13351857']], 'subnet_ipam': [{'subnet_cidr': '164.55.95.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-06-30 16:35:01,477 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '332a75ee-2c3d-4287-9e25-9e58c12ea858', 'name': 'ctest-vn-47362684', 'tenant_id': '38b7bfecc50a49f5950a07f330872290', 'project_id': '38b7bfecc50a49f5950a07f330872290', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['9d5f9c1e-8a0c-490d-95db-15a9e0059549'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-vn-47362684'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-policy-allow-all-13351857']], 'subnet_ipam': [{'subnet_cidr': '122.133.176.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-06-30 16:35:01,630 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:01,930 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:01,959 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ad6718b7-0413-433b-bf30-9d76cb024daf 2025-06-30 16:35:01,972 - DEBUG - Requesting: http://10.0.0.38:8082/project/38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:35:02,050 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:02,061 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:02,070 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5d75a048-dc6e-46e1-a3f3-1caa5d49b313 2025-06-30 16:35:02,077 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5d75a048-dc6e-46e1-a3f3-1caa5d49b313 2025-06-30 16:35:02,084 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/47ecfca9-ff34-4ab0-8e94-20311e396e2d 2025-06-30 16:35:02,090 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-06-30 16:35:02,090 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:02,098 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5d75a048-dc6e-46e1-a3f3-1caa5d49b313 2025-06-30 16:35:02,112 - INFO - Verified VN network id 8 for VN 97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:02,112 - INFO - Verifications in API Server for VN ctest-vn-96683647 passed 2025-06-30 16:35:02,112 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:02,124 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5d75a048-dc6e-46e1-a3f3-1caa5d49b313 2025-06-30 16:35:02,131 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5d75a048-dc6e-46e1-a3f3-1caa5d49b313 2025-06-30 16:35:02,139 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/47ecfca9-ff34-4ab0-8e94-20311e396e2d 2025-06-30 16:35:02,151 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-17343104', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10886946842870173173', 'uuid-lslong': '9271033128049665994'}, 'enable': 'true', 'created': '2025-06-30T16:34:59', 'last-modified': '2025-06-30T16:35:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.949603'} 2025-06-30 16:35:02,157 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-06-30 16:35:02,157 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-96683647 passed 2025-06-30 16:35:02,157 - DEBUG - ====Verifying policy data for ctest-vn-96683647 in API_Server ====== 2025-06-30 16:35:02,157 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:02,163 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ad6718b7-0413-433b-bf30-9d76cb024daf 2025-06-30 16:35:02,173 - DEBUG - Requesting: http://10.0.0.38:8082/project/38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:35:02,249 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/97163478-d9d6-4df5-80a9-53828a4767ca 2025-06-30 16:35:02,259 - DEBUG - ==>Verifying data for policy with id: 91354b07-86d7-45a0-8c82-a453795e5006, fqn: ['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-policy-allow-all-13351857'] 2025-06-30 16:35:02,259 - INFO - VN ctest-vn-96683647 Policy verification: verify_vn_policy_in_api_server, status: True 2025-06-30 16:35:02,259 - DEBUG - Verifying the vn in opserver 2025-06-30 16:35:02,259 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647 virtual network link through opserver 10.0.0.38 2025-06-30 16:35:02,259 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-30 16:35:02,571 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-30 16:35:02,651 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647?flat'} 2025-06-30 16:35:02,651 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647 is found in opserver 2025-06-30 16:35:02,664 - DEBUG - Do not have enough data to verify VN in agent 2025-06-30 16:35:02,670 - DEBUG - VRF ids for VN ctest-vn-96683647: {} 2025-06-30 16:35:02,670 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:02,681 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ad6718b7-0413-433b-bf30-9d76cb024daf 2025-06-30 16:35:02,694 - DEBUG - Requesting: http://10.0.0.38:8082/project/38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:35:02,778 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:02,795 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:02,808 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6767ea3c-a02f-4aa9-96bc-2a8a3d6b2091 2025-06-30 16:35:02,818 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6767ea3c-a02f-4aa9-96bc-2a8a3d6b2091 2025-06-30 16:35:02,831 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/49c97395-2bd5-4ad4-a2e6-523524e660dc 2025-06-30 16:35:02,843 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-06-30 16:35:02,843 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:02,858 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6767ea3c-a02f-4aa9-96bc-2a8a3d6b2091 2025-06-30 16:35:02,876 - INFO - Verified VN network id 9 for VN 332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:02,876 - INFO - Verifications in API Server for VN ctest-vn-47362684 passed 2025-06-30 16:35:02,876 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:02,888 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6767ea3c-a02f-4aa9-96bc-2a8a3d6b2091 2025-06-30 16:35:02,897 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6767ea3c-a02f-4aa9-96bc-2a8a3d6b2091 2025-06-30 16:35:02,904 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/49c97395-2bd5-4ad4-a2e6-523524e660dc 2025-06-30 16:35:02,917 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-47362684', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-17343104', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3686888910761050759', 'uuid-lslong': '11395688536166279256'}, 'enable': 'true', 'created': '2025-06-30T16:35:00', 'last-modified': '2025-06-30T16:35:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.470965'} 2025-06-30 16:35:02,924 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-06-30 16:35:02,924 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-47362684 passed 2025-06-30 16:35:02,924 - DEBUG - ====Verifying policy data for ctest-vn-47362684 in API_Server ====== 2025-06-30 16:35:02,924 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:02,931 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ad6718b7-0413-433b-bf30-9d76cb024daf 2025-06-30 16:35:02,946 - DEBUG - Requesting: http://10.0.0.38:8082/project/38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:35:03,018 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/332a75ee-2c3d-4287-9e25-9e58c12ea858 2025-06-30 16:35:03,030 - DEBUG - ==>Verifying data for policy with id: 91354b07-86d7-45a0-8c82-a453795e5006, fqn: ['default-domain', 'ctest-TestBasicPolicy-17343104', 'ctest-policy-allow-all-13351857'] 2025-06-30 16:35:03,030 - INFO - VN ctest-vn-47362684 Policy verification: verify_vn_policy_in_api_server, status: True 2025-06-30 16:35:03,031 - DEBUG - Verifying the vn in opserver 2025-06-30 16:35:03,031 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-47362684 virtual network link through opserver 10.0.0.38 2025-06-30 16:35:03,031 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-30 16:35:03,042 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-47362684', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-47362684?flat'} 2025-06-30 16:35:03,042 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-47362684 is found in opserver 2025-06-30 16:35:03,054 - DEBUG - Do not have enough data to verify VN in agent 2025-06-30 16:35:03,060 - DEBUG - VRF ids for VN ctest-vn-47362684: {} 2025-06-30 16:35:03,124 - DEBUG - Services list from nova: [, , ] 2025-06-30 16:35:04,207 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3582-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3582-1) 2025-06-30 16:35:05,614 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3582-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3582-1) 2025-06-30 16:35:05,614 - INFO - Waiting for VM ctest-TestBasicPolicy-17343104-40325059 to be up.. 2025-06-30 16:35:05,702 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-06-30 16:35:10,793 - DEBUG - VM is in ACTIVE state now 2025-06-30 16:35:10,793 - INFO - VM name : ctest-TestBasicPolicy-17343104-40325059 2025-06-30 16:35:10,888 - DEBUG - VM ctest-TestBasicPolicy-17343104-40325059 ID is 29f2e46e-b959-4719-86e7-06e965f135ce 2025-06-30 16:35:10,908 - DEBUG - VM ctest-TestBasicPolicy-17343104-40325059 launched on Node cn-jenkins-deploy-platform-ansible-os-3582-1. 2025-06-30 16:35:10,997 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/29f2e46e-b959-4719-86e7-06e965f135ce 2025-06-30 16:35:11,006 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/2648edfd-e33c-4373-a043-3ad429e5a696 2025-06-30 16:35:14,277 - 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 1026ms') 2025-06-30 16:35:14,277 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-17343104-40325059 failed! 2025-06-30 16:35:14,334 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-17343104:ctest-vn-96683647 is 164.55.95.193 and allocation pool is NOT set 2025-06-30 16:35:16,397 - 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.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.999 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.999/4.811/8.624/3.812 ms') 2025-06-30 16:35:16,397 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-17343104-40325059 passed 2025-06-30 16:35:16,554 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:35:16,554 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-17343104-40325059, IP 164.55.95.195, Port 22 2025-06-30 16:35:16,624 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-06-30 16:35:16,718 - DEBUG - VM ctest-TestBasicPolicy-17343104-40325059 is NOT ready for SSH connections, VM status: ACTIVE 2025-06-30 16:35:21,718 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:35:21,719 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-17343104-40325059, IP 164.55.95.195, Port 22 2025-06-30 16:35:21,892 - DEBUG - VM ctest-TestBasicPolicy-17343104-40325059 is ready for SSH connections 2025-06-30 16:35:21,892 - INFO - Waiting for VM ctest-TestBasicPolicy-17343104-32610963 to be up.. 2025-06-30 16:35:22,003 - DEBUG - VM is in ACTIVE state now 2025-06-30 16:35:22,003 - INFO - VM name : ctest-TestBasicPolicy-17343104-32610963 2025-06-30 16:35:22,092 - DEBUG - VM ctest-TestBasicPolicy-17343104-32610963 ID is 5a6c4c32-9f00-4114-a873-20e0930f252b 2025-06-30 16:35:22,093 - DEBUG - VM ctest-TestBasicPolicy-17343104-32610963 launched on Node cn-jenkins-deploy-platform-ansible-os-3582-1. 2025-06-30 16:35:22,193 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/5a6c4c32-9f00-4114-a873-20e0930f252b 2025-06-30 16:35:22,206 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/d0e607e5-fa89-4860-967e-b92ed2edcf71 2025-06-30 16:35:23,462 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.65 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.383 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.383/2.014/3.646/1.631 ms') 2025-06-30 16:35:23,462 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-17343104-32610963 passed 2025-06-30 16:35:23,618 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:35:23,618 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-17343104-32610963, IP 122.133.176.3, Port 22 2025-06-30 16:35:23,787 - DEBUG - VM ctest-TestBasicPolicy-17343104-32610963 is ready for SSH connections 2025-06-30 16:35:23,788 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:35:23,788 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 122.133.176.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-06-30 16:35:23,788 - DEBUG - ping -s 56 -c 3 -W 1 122.133.176.3 2025-06-30 16:35:27,944 - DEBUG - PING 122.133.176.3 (122.133.176.3): 56 data bytes 64 bytes from 122.133.176.3: seq=0 ttl=63 time=5.634 ms 64 bytes from 122.133.176.3: seq=1 ttl=63 time=1.634 ms 64 bytes from 122.133.176.3: seq=2 ttl=63 time=1.071 ms --- 122.133.176.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.071/2.779/5.634 ms 2025-06-30 16:35:27,945 - INFO - Ping to IP 122.133.176.3 from VM ctest-TestBasicPolicy-17343104-40325059 passed 2025-06-30 16:35:28,133 - DEBUG - Policy Update Response {"network-policy": {"uuid": "91354b07-86d7-45a0-8c82-a453795e5006", "href": "http://10.0.0.38:8082/network-policy/91354b07-86d7-45a0-8c82-a453795e5006"}} 2025-06-30 16:35:28,133 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:35:28,134 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 122.133.176.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-06-30 16:35:28,134 - DEBUG - ping -s 56 -c 3 -W 1 122.133.176.3 2025-06-30 16:35:31,352 - DEBUG - PING 122.133.176.3 (122.133.176.3): 56 data bytes --- 122.133.176.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-06-30 16:35:31,352 - WARNING - Ping to IP 122.133.176.3 from VM ctest-TestBasicPolicy-17343104-40325059 failed 2025-06-30 16:35:31,352 - INFO - Deleting VM ctest-TestBasicPolicy-17343104-32610963 2025-06-30 16:35:31,429 - INFO - Deleting VM ctest-TestBasicPolicy-17343104-40325059 2025-06-30 16:35:31,826 - INFO - Deleted policy ctest-policy-allow-all-13351857 2025-06-30 16:35:31,826 - INFO - Deleting VN ctest-vn-47362684 2025-06-30 16:35:31,866 - DEBUG - VN 332a75ee-2c3d-4287-9e25-9e58c12ea858 still in use: Unable to complete operation on network 332a75ee-2c3d-4287-9e25-9e58c12ea858. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9e470935-0360-4b4c-a0ff-f0569bf8d19d'] 2025-06-30 16:35:31,866 - WARNING - Deleting VN ctest-vn-47362684 failed..Will retry 2025-06-30 16:35:34,000 - DEBUG - Response for deleting network () 2025-06-30 16:35:34,000 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6767ea3c-a02f-4aa9-96bc-2a8a3d6b2091 2025-06-30 16:35:34,008 - DEBUG - Response Code: 404 2025-06-30 16:35:34,009 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:34,014 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ad6718b7-0413-433b-bf30-9d76cb024daf 2025-06-30 16:35:34,067 - DEBUG - Requesting: http://10.0.0.38:8082/project/38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:35:34,150 - INFO - Validated that VN ctest-vn-47362684 is not found in API Server 2025-06-30 16:35:34,162 - DEBUG - VN ctest-vn-47362684 is not present in Agent 10.0.0.38 2025-06-30 16:35:34,162 - INFO - Validated that VN ctest-vn-47362684 is not in any agent 2025-06-30 16:35:34,178 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-47362684 info 2025-06-30 16:35:34,178 - INFO - Deleting VN ctest-vn-96683647 2025-06-30 16:35:34,397 - DEBUG - Response for deleting network () 2025-06-30 16:35:34,397 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5d75a048-dc6e-46e1-a3f3-1caa5d49b313 2025-06-30 16:35:34,408 - DEBUG - Response Code: 404 2025-06-30 16:35:34,408 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-30 16:35:34,416 - DEBUG - Requesting: http://10.0.0.38:8082/domain/ad6718b7-0413-433b-bf30-9d76cb024daf 2025-06-30 16:35:34,477 - DEBUG - Requesting: http://10.0.0.38:8082/project/38b7bfec-c50a-49f5-950a-07f330872290 2025-06-30 16:35:34,542 - INFO - Validated that VN ctest-vn-96683647 is not found in API Server 2025-06-30 16:35:34,553 - DEBUG - VN ctest-vn-96683647 is not present in Agent 10.0.0.38 2025-06-30 16:35:34,553 - INFO - Validated that VN ctest-vn-96683647 is not in any agent 2025-06-30 16:35:34,572 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-96683647 info 2025-06-30 16:35:34,846 - DEBUG - No XMPP flaps were noticed during the test
2025-06-30 16:35:34,846 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:35]
2025-06-30 16:35:34,846 - INFO - -------------------------------------------------------------------------------- 2025-06-30 16:35:35,557 - INFO - Deleted project: ctest-TestBasicPolicy-17343104, ID : 38b7bfec-c50a-49f5-950a-07f330872290