2025-12-18 20:53:29,867 - INFO - Domain Default found not creating 2025-12-18 20:53:30,036 - INFO - Project ctest-TestBasicPolicy-03986053 not found, creating it 2025-12-18 20:53:30,499 - INFO - Created Project:ctest-TestBasicPolicy-03986053, ID : af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:53:31,994 - INFO - ================================================================================ 2025-12-18 20:53:31,994 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-12-18 20:53:31,994 - 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-12-18 20:53:32,252 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with 2025-12-18 20:53:32,252 - INFO - Initial checks done. Running the testcase now 2025-12-18 20:53:32,252 - INFO - 2025-12-18 20:53:32,808 - DEBUG - Response for create_network : {'network': {'id': 'cd0cf107-abb7-4a05-8df1-cffa94e9b360', 'name': 'ctest-vn-50177793', 'tenant_id': 'af3047035ec348f5a6a87fdc7106636b', 'project_id': 'af3047035ec348f5a6a87fdc7106636b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-vn-50177793'], 'port_security_enabled': True, 'description': ''}} 2025-12-18 20:53:33,010 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b17eee0f-0d02-44b0-b546-fb8d0c5df216', 'name': '', 'tenant_id': 'af3047035ec348f5a6a87fdc7106636b', 'network_id': 'cd0cf107-abb7-4a05-8df1-cffa94e9b360', 'ip_version': 4, 'cidr': '63.152.72.192/26', 'allocation_pools': [{'start': '63.152.72.194', 'end': '63.152.72.254'}], 'gateway_ip': '63.152.72.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.152.72.194', 'tags': [], 'project_id': 'af3047035ec348f5a6a87fdc7106636b'}} 2025-12-18 20:53:33,028 - INFO - Created VN ctest-vn-50177793 2025-12-18 20:53:33,081 - DEBUG - VN ctest-vn-50177793 UUID is cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:33,374 - DEBUG - Response for create_network : {'network': {'id': '9a9d7939-2c0e-440c-a36a-768399935e23', 'name': 'ctest-vn-99133211', 'tenant_id': 'af3047035ec348f5a6a87fdc7106636b', 'project_id': 'af3047035ec348f5a6a87fdc7106636b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-vn-99133211'], 'port_security_enabled': True, 'description': ''}} 2025-12-18 20:53:33,587 - DEBUG - Response for create_subnet : {'subnet': {'id': '95122633-f510-4cd4-829a-ad5f00aac75e', 'name': '', 'tenant_id': 'af3047035ec348f5a6a87fdc7106636b', 'network_id': '9a9d7939-2c0e-440c-a36a-768399935e23', 'ip_version': 4, 'cidr': '164.89.79.0/26', 'allocation_pools': [{'start': '164.89.79.2', 'end': '164.89.79.62'}], 'gateway_ip': '164.89.79.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.89.79.2', 'tags': [], 'project_id': 'af3047035ec348f5a6a87fdc7106636b'}} 2025-12-18 20:53:33,605 - INFO - Created VN ctest-vn-99133211 2025-12-18 20:53:33,657 - DEBUG - VN ctest-vn-99133211 UUID is 9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:33,775 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793, 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-03986053:ctest-vn-99133211, 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-12-18 20:53:34,065 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'cd0cf107-abb7-4a05-8df1-cffa94e9b360', 'name': 'ctest-vn-50177793', 'tenant_id': 'af3047035ec348f5a6a87fdc7106636b', 'project_id': 'af3047035ec348f5a6a87fdc7106636b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['b17eee0f-0d02-44b0-b546-fb8d0c5df216'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-vn-50177793'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-policy-allow-all-00485710']], 'subnet_ipam': [{'subnet_cidr': '63.152.72.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-12-18 20:53:34,337 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '9a9d7939-2c0e-440c-a36a-768399935e23', 'name': 'ctest-vn-99133211', 'tenant_id': 'af3047035ec348f5a6a87fdc7106636b', 'project_id': 'af3047035ec348f5a6a87fdc7106636b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['95122633-f510-4cd4-829a-ad5f00aac75e'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-vn-99133211'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-policy-allow-all-00485710']], 'subnet_ipam': [{'subnet_cidr': '164.89.79.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-12-18 20:53:34,489 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:53:34,785 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:53:34,821 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f4a81b16-a41d-4778-a812-69361b3c910b 2025-12-18 20:53:34,837 - DEBUG - Requesting: http://10.0.0.38:8082/project/af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:53:34,907 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:34,918 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:34,927 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/464d520f-5782-4f4c-b07b-b834b30cb454 2025-12-18 20:53:34,934 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/464d520f-5782-4f4c-b07b-b834b30cb454 2025-12-18 20:53:34,942 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/73b48625-2387-495e-8523-3e3ffae041c1 2025-12-18 20:53:34,949 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-18 20:53:34,949 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:34,959 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/464d520f-5782-4f4c-b07b-b834b30cb454 2025-12-18 20:53:34,974 - INFO - Verified VN network id 10 for VN cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:34,974 - INFO - Verifications in API Server for VN ctest-vn-50177793 passed 2025-12-18 20:53:34,974 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:34,985 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/464d520f-5782-4f4c-b07b-b834b30cb454 2025-12-18 20:53:34,995 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/464d520f-5782-4f4c-b07b-b834b30cb454 2025-12-18 20:53:35,002 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/73b48625-2387-495e-8523-3e3ffae041c1 2025-12-18 20:53:35,013 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-03986053', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14775449492743735813', 'uuid-lslong': '10228184903882224480'}, 'enable': 'true', 'created': '2025-12-18T20:53:32', 'last-modified': '2025-12-18T20:53:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.969563'} 2025-12-18 20:53:35,019 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-18 20:53:35,019 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-50177793 passed 2025-12-18 20:53:35,020 - DEBUG - ====Verifying policy data for ctest-vn-50177793 in API_Server ====== 2025-12-18 20:53:35,020 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:53:35,025 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f4a81b16-a41d-4778-a812-69361b3c910b 2025-12-18 20:53:35,036 - DEBUG - Requesting: http://10.0.0.38:8082/project/af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:53:35,109 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/cd0cf107-abb7-4a05-8df1-cffa94e9b360 2025-12-18 20:53:35,118 - DEBUG - ==>Verifying data for policy with id: d3741419-b77d-40bb-a9f3-ca9dc45ad8f2, fqn: ['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-policy-allow-all-00485710'] 2025-12-18 20:53:35,119 - INFO - VN ctest-vn-50177793 Policy verification: verify_vn_policy_in_api_server, status: True 2025-12-18 20:53:35,119 - DEBUG - Verifying the vn in opserver 2025-12-18 20:53:35,119 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793 virtual network link through opserver 10.0.0.38 2025-12-18 20:53:35,119 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-12-18 20:53:35,412 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-12-18 20:53:35,475 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793?flat'} 2025-12-18 20:53:35,476 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793 is found in opserver 2025-12-18 20:53:35,486 - DEBUG - Do not have enough data to verify VN in agent 2025-12-18 20:53:35,491 - DEBUG - VRF ids for VN ctest-vn-50177793: {} 2025-12-18 20:53:35,491 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:53:35,496 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f4a81b16-a41d-4778-a812-69361b3c910b 2025-12-18 20:53:35,506 - DEBUG - Requesting: http://10.0.0.38:8082/project/af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:53:35,576 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:35,589 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:35,599 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e08a6cf-4399-45df-aaaa-9d76af7c0cb3 2025-12-18 20:53:35,607 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e08a6cf-4399-45df-aaaa-9d76af7c0cb3 2025-12-18 20:53:35,613 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/93f00671-011a-42a1-a483-cb52b2d680b2 2025-12-18 20:53:35,620 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-18 20:53:35,620 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:35,628 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e08a6cf-4399-45df-aaaa-9d76af7c0cb3 2025-12-18 20:53:35,644 - INFO - Verified VN network id 11 for VN 9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:35,645 - INFO - Verifications in API Server for VN ctest-vn-99133211 passed 2025-12-18 20:53:35,645 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:35,653 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e08a6cf-4399-45df-aaaa-9d76af7c0cb3 2025-12-18 20:53:35,659 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e08a6cf-4399-45df-aaaa-9d76af7c0cb3 2025-12-18 20:53:35,665 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/93f00671-011a-42a1-a483-cb52b2d680b2 2025-12-18 20:53:35,677 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-99133211', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-03986053', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11141194339643704332', 'uuid-lslong': '11775354483302948387'}, 'enable': 'true', 'created': '2025-12-18T20:53:33', 'last-modified': '2025-12-18T20:53:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.409273'} 2025-12-18 20:53:35,681 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-18 20:53:35,681 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-99133211 passed 2025-12-18 20:53:35,682 - DEBUG - ====Verifying policy data for ctest-vn-99133211 in API_Server ====== 2025-12-18 20:53:35,682 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:53:35,687 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f4a81b16-a41d-4778-a812-69361b3c910b 2025-12-18 20:53:35,697 - DEBUG - Requesting: http://10.0.0.38:8082/project/af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:53:35,768 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/9a9d7939-2c0e-440c-a36a-768399935e23 2025-12-18 20:53:35,777 - DEBUG - ==>Verifying data for policy with id: d3741419-b77d-40bb-a9f3-ca9dc45ad8f2, fqn: ['default-domain', 'ctest-TestBasicPolicy-03986053', 'ctest-policy-allow-all-00485710'] 2025-12-18 20:53:35,777 - INFO - VN ctest-vn-99133211 Policy verification: verify_vn_policy_in_api_server, status: True 2025-12-18 20:53:35,777 - DEBUG - Verifying the vn in opserver 2025-12-18 20:53:35,777 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-99133211 virtual network link through opserver 10.0.0.38 2025-12-18 20:53:35,777 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-12-18 20:53:35,787 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-99133211', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-99133211?flat'} 2025-12-18 20:53:35,787 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-99133211 is found in opserver 2025-12-18 20:53:35,796 - DEBUG - Do not have enough data to verify VN in agent 2025-12-18 20:53:35,799 - DEBUG - VRF ids for VN ctest-vn-99133211: {} 2025-12-18 20:53:35,858 - DEBUG - Services list from nova: [, , ] 2025-12-18 20:53:36,930 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4907-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4907-1) 2025-12-18 20:53:38,061 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4907-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4907-1) 2025-12-18 20:53:38,061 - INFO - Waiting for VM ctest-TestBasicPolicy-03986053-32535093 to be up.. 2025-12-18 20:53:38,139 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-18 20:53:43,232 - DEBUG - VM is in ACTIVE state now 2025-12-18 20:53:43,232 - INFO - VM name : ctest-TestBasicPolicy-03986053-32535093 2025-12-18 20:53:43,307 - DEBUG - VM ctest-TestBasicPolicy-03986053-32535093 ID is e590ef0d-c7b0-451b-bfda-da8716a2d6ab 2025-12-18 20:53:43,334 - DEBUG - VM ctest-TestBasicPolicy-03986053-32535093 launched on Node cn-jenkins-deploy-platform-ansible-os-4907-1 2025-12-18 20:53:43,411 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e590ef0d-c7b0-451b-bfda-da8716a2d6ab 2025-12-18 20:53:43,422 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/76fe0b6b-0326-4c6c-8f5b-358456ef2641 2025-12-18 20:53:46,686 - 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 1030ms') 2025-12-18 20:53:46,686 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-03986053-32535093 failed! 2025-12-18 20:53:46,741 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-03986053:ctest-vn-50177793 is 63.152.72.193 and allocation pool is NOT set 2025-12-18 20:53:48,800 - 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=6.91 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.47 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 = 3.467/5.187/6.908/1.720 ms') 2025-12-18 20:53:48,800 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-03986053-32535093 passed 2025-12-18 20:53:48,953 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 20:53:48,953 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-03986053-32535093, IP 63.152.72.195, Port 22 2025-12-18 20:53:49,124 - DEBUG - VM ctest-TestBasicPolicy-03986053-32535093 is ready for SSH connections 2025-12-18 20:53:49,124 - INFO - Waiting for VM ctest-TestBasicPolicy-03986053-94260990 to be up.. 2025-12-18 20:53:49,215 - DEBUG - VM is in ACTIVE state now 2025-12-18 20:53:49,215 - INFO - VM name : ctest-TestBasicPolicy-03986053-94260990 2025-12-18 20:53:49,291 - DEBUG - VM ctest-TestBasicPolicy-03986053-94260990 ID is 331886b6-f7e5-4099-be44-70e566e1963c 2025-12-18 20:53:49,291 - DEBUG - VM ctest-TestBasicPolicy-03986053-94260990 launched on Node cn-jenkins-deploy-platform-ansible-os-4907-1 2025-12-18 20:53:49,369 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/331886b6-f7e5-4099-be44-70e566e1963c 2025-12-18 20:53:49,381 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/c7784541-d727-4a5d-b3ed-213f2d326e46 2025-12-18 20:53:50,630 - 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=4.52 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.27 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 = 2.274/3.399/4.524/1.125 ms') 2025-12-18 20:53:50,631 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-03986053-94260990 passed 2025-12-18 20:53:50,781 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 20:53:50,781 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-03986053-94260990, IP 164.89.79.3, Port 22 2025-12-18 20:53:50,941 - DEBUG - VM ctest-TestBasicPolicy-03986053-94260990 is ready for SSH connections 2025-12-18 20:53:50,941 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 20:53:50,942 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 164.89.79.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-12-18 20:53:50,942 - DEBUG - ping -s 56 -c 3 -W 1 164.89.79.3 2025-12-18 20:53:54,655 - DEBUG - PING 164.89.79.3 (164.89.79.3): 56 data bytes 64 bytes from 164.89.79.3: seq=0 ttl=63 time=3.797 ms 64 bytes from 164.89.79.3: seq=1 ttl=63 time=0.930 ms 64 bytes from 164.89.79.3: seq=2 ttl=63 time=1.013 ms --- 164.89.79.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.930/1.913/3.797 ms 2025-12-18 20:53:54,655 - INFO - Ping to IP 164.89.79.3 from VM ctest-TestBasicPolicy-03986053-32535093 passed 2025-12-18 20:53:54,845 - DEBUG - Policy Update Response {"network-policy": {"uuid": "d3741419-b77d-40bb-a9f3-ca9dc45ad8f2", "href": "http://10.0.0.38:8082/network-policy/d3741419-b77d-40bb-a9f3-ca9dc45ad8f2"}} 2025-12-18 20:53:54,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 20:53:54,845 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 164.89.79.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-12-18 20:53:54,845 - DEBUG - ping -s 56 -c 3 -W 1 164.89.79.3 2025-12-18 20:53:58,057 - DEBUG - PING 164.89.79.3 (164.89.79.3): 56 data bytes --- 164.89.79.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-12-18 20:53:58,057 - WARNING - Ping to IP 164.89.79.3 from VM ctest-TestBasicPolicy-03986053-32535093 failed 2025-12-18 20:53:58,057 - INFO - Deleting VM ctest-TestBasicPolicy-03986053-94260990 2025-12-18 20:53:58,125 - INFO - Deleting VM ctest-TestBasicPolicy-03986053-32535093 2025-12-18 20:53:58,461 - INFO - Deleted policy ctest-policy-allow-all-00485710 2025-12-18 20:53:58,462 - INFO - Deleting VN ctest-vn-99133211 2025-12-18 20:53:58,497 - DEBUG - VN 9a9d7939-2c0e-440c-a36a-768399935e23 still in use: Unable to complete operation on network 9a9d7939-2c0e-440c-a36a-768399935e23. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9344fa33-8269-43c7-8d3e-961bee6a4a0a'] 2025-12-18 20:53:58,497 - WARNING - Deleting VN ctest-vn-99133211 failed..Will retry 2025-12-18 20:54:00,599 - DEBUG - Response for deleting network () 2025-12-18 20:54:00,599 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e08a6cf-4399-45df-aaaa-9d76af7c0cb3 2025-12-18 20:54:00,606 - DEBUG - Response Code: 404 2025-12-18 20:54:00,606 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:54:00,611 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f4a81b16-a41d-4778-a812-69361b3c910b 2025-12-18 20:54:00,666 - DEBUG - Requesting: http://10.0.0.38:8082/project/af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:54:00,738 - INFO - Validated that VN ctest-vn-99133211 is not found in API Server 2025-12-18 20:54:00,747 - DEBUG - VN ctest-vn-99133211 is not present in Agent 10.0.0.38 2025-12-18 20:54:00,747 - INFO - Validated that VN ctest-vn-99133211 is not in any agent 2025-12-18 20:54:00,760 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-99133211 info 2025-12-18 20:54:00,760 - INFO - Deleting VN ctest-vn-50177793 2025-12-18 20:54:00,856 - DEBUG - Response for deleting network () 2025-12-18 20:54:00,856 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/464d520f-5782-4f4c-b07b-b834b30cb454 2025-12-18 20:54:00,861 - DEBUG - Response Code: 404 2025-12-18 20:54:00,861 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-12-18 20:54:00,870 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f4a81b16-a41d-4778-a812-69361b3c910b 2025-12-18 20:54:00,927 - DEBUG - Requesting: http://10.0.0.38:8082/project/af304703-5ec3-48f5-a6a8-7fdc7106636b 2025-12-18 20:54:00,998 - INFO - Validated that VN ctest-vn-50177793 is not found in API Server 2025-12-18 20:54:01,006 - DEBUG - VN ctest-vn-50177793 is not present in Agent 10.0.0.38 2025-12-18 20:54:01,006 - INFO - Validated that VN ctest-vn-50177793 is not in any agent 2025-12-18 20:54:01,020 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-50177793 info 2025-12-18 20:54:01,287 - DEBUG - No XMPP flaps were noticed during the test
2025-12-18 20:54:01,287 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:30]
2025-12-18 20:54:01,287 - INFO - -------------------------------------------------------------------------------- 2025-12-18 20:54:01,993 - INFO - Deleted project: ctest-TestBasicPolicy-03986053, ID : af304703-5ec3-48f5-a6a8-7fdc7106636b