2026-09-16 13:48:58,094 - INFO - Domain Default found not creating 2026-09-16 13:48:58,266 - INFO - Project ctest-TestBasicPolicy-44288715 not found, creating it 2026-09-16 13:48:58,760 - INFO - Created Project:ctest-TestBasicPolicy-44288715, ID : 11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:49:00,449 - INFO - ================================================================================ 2026-09-16 13:49:00,449 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-09-16 13:49:00,449 - 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 2026-09-16 13:49:00,727 - DEBUG - Nothing to compare xmpp stats {'10.0.0.177': {'10.20.0.17': '0'}} with 2026-09-16 13:49:00,727 - INFO - Initial checks done. Running the testcase now 2026-09-16 13:49:00,727 - INFO - 2026-09-16 13:49:01,363 - DEBUG - Response for create_network : {'network': {'id': '00008f9a-23c3-4d49-913b-65db37b2aaed', 'name': 'ctest-vn-81162446', 'tenant_id': '11b16dc74849408ea61492e06fcf2e0f', 'project_id': '11b16dc74849408ea61492e06fcf2e0f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-vn-81162446'], 'port_security_enabled': True, 'description': ''}} 2026-09-16 13:49:01,524 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b0de4824-995c-45c5-9e18-00932f90c7aa', 'name': '', 'tenant_id': '11b16dc74849408ea61492e06fcf2e0f', 'network_id': '00008f9a-23c3-4d49-913b-65db37b2aaed', 'ip_version': 4, 'cidr': '163.248.104.192/26', 'allocation_pools': [{'start': '163.248.104.194', 'end': '163.248.104.254'}], 'gateway_ip': '163.248.104.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '163.248.104.194', 'tags': [], 'project_id': '11b16dc74849408ea61492e06fcf2e0f'}} 2026-09-16 13:49:01,542 - INFO - Created VN ctest-vn-81162446 2026-09-16 13:49:01,597 - DEBUG - VN ctest-vn-81162446 UUID is 00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:01,930 - DEBUG - Response for create_network : {'network': {'id': 'c4660aeb-45cc-433c-8d39-167346de66fc', 'name': 'ctest-vn-73005531', 'tenant_id': '11b16dc74849408ea61492e06fcf2e0f', 'project_id': '11b16dc74849408ea61492e06fcf2e0f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-vn-73005531'], 'port_security_enabled': True, 'description': ''}} 2026-09-16 13:49:02,172 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fd0fa2e6-ec0c-4204-a39f-0c9c1864913b', 'name': '', 'tenant_id': '11b16dc74849408ea61492e06fcf2e0f', 'network_id': 'c4660aeb-45cc-433c-8d39-167346de66fc', 'ip_version': 4, 'cidr': '75.152.5.192/26', 'allocation_pools': [{'start': '75.152.5.194', 'end': '75.152.5.254'}], 'gateway_ip': '75.152.5.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '75.152.5.194', 'tags': [], 'project_id': '11b16dc74849408ea61492e06fcf2e0f'}} 2026-09-16 13:49:02,189 - INFO - Created VN ctest-vn-73005531 2026-09-16 13:49:02,241 - DEBUG - VN ctest-vn-73005531 UUID is c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:02,358 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446, 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-44288715:ctest-vn-73005531, 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] 2026-09-16 13:49:02,686 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '00008f9a-23c3-4d49-913b-65db37b2aaed', 'name': 'ctest-vn-81162446', 'tenant_id': '11b16dc74849408ea61492e06fcf2e0f', 'project_id': '11b16dc74849408ea61492e06fcf2e0f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['b0de4824-995c-45c5-9e18-00932f90c7aa'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-vn-81162446'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-policy-allow-all-25302964']], 'subnet_ipam': [{'subnet_cidr': '163.248.104.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-09-16 13:49:02,941 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'c4660aeb-45cc-433c-8d39-167346de66fc', 'name': 'ctest-vn-73005531', 'tenant_id': '11b16dc74849408ea61492e06fcf2e0f', 'project_id': '11b16dc74849408ea61492e06fcf2e0f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': ['fd0fa2e6-ec0c-4204-a39f-0c9c1864913b'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-vn-73005531'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-policy-allow-all-25302964']], 'subnet_ipam': [{'subnet_cidr': '75.152.5.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-09-16 13:49:03,109 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:49:03,488 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:49:03,522 - DEBUG - Requesting: http://10.0.0.177:8082/domain/bbb654c8-6af1-4af3-bd16-6ed059a899ed 2026-09-16 13:49:03,537 - DEBUG - Requesting: http://10.0.0.177:8082/project/11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:49:03,611 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:03,627 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:03,636 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/bdc1913c-0ded-4bd9-a74c-ecc129213b64 2026-09-16 13:49:03,644 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/bdc1913c-0ded-4bd9-a74c-ecc129213b64 2026-09-16 13:49:03,652 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/d6f88ae2-09cf-4d5c-b753-80960cfbe179 2026-09-16 13:49:03,660 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-16 13:49:03,660 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:03,671 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/bdc1913c-0ded-4bd9-a74c-ecc129213b64 2026-09-16 13:49:03,689 - INFO - Verified VN network id 12 for VN 00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:03,689 - INFO - Verifications in API Server for VN ctest-vn-81162446 passed 2026-09-16 13:49:03,689 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:03,699 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/bdc1913c-0ded-4bd9-a74c-ecc129213b64 2026-09-16 13:49:03,707 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/bdc1913c-0ded-4bd9-a74c-ecc129213b64 2026-09-16 13:49:03,716 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/d6f88ae2-09cf-4d5c-b753-80960cfbe179 2026-09-16 13:49:03,731 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-44288715', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '157892187737417', 'uuid-lslong': '10465070151332178669'}, 'enable': 'true', 'created': '2026-09-16T13:49:01', 'last-modified': '2026-09-16T13:49:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.069761'} 2026-09-16 13:49:03,740 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-09-16 13:49:03,740 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-81162446 passed 2026-09-16 13:49:03,740 - DEBUG - ====Verifying policy data for ctest-vn-81162446 in API_Server ====== 2026-09-16 13:49:03,740 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:49:03,748 - DEBUG - Requesting: http://10.0.0.177:8082/domain/bbb654c8-6af1-4af3-bd16-6ed059a899ed 2026-09-16 13:49:03,763 - DEBUG - Requesting: http://10.0.0.177:8082/project/11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:49:03,845 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/00008f9a-23c3-4d49-913b-65db37b2aaed 2026-09-16 13:49:03,856 - DEBUG - ==>Verifying data for policy with id: 26ac3ea0-3403-4caa-b7e5-85f80d0dd7be, fqn: ['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-policy-allow-all-25302964'] 2026-09-16 13:49:03,856 - INFO - VN ctest-vn-81162446 Policy verification: verify_vn_policy_in_api_server, status: True 2026-09-16 13:49:03,856 - DEBUG - Verifying the vn in opserver 2026-09-16 13:49:03,856 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446 virtual network link through opserver 10.0.0.177 2026-09-16 13:49:03,856 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-09-16 13:49:04,228 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-09-16 13:49:04,289 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446?flat'} 2026-09-16 13:49:04,289 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446 is found in opserver 2026-09-16 13:49:04,300 - DEBUG - Do not have enough data to verify VN in agent 2026-09-16 13:49:04,305 - DEBUG - VRF ids for VN ctest-vn-81162446: {} 2026-09-16 13:49:04,306 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:49:04,311 - DEBUG - Requesting: http://10.0.0.177:8082/domain/bbb654c8-6af1-4af3-bd16-6ed059a899ed 2026-09-16 13:49:04,322 - DEBUG - Requesting: http://10.0.0.177:8082/project/11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:49:04,399 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:04,410 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:04,419 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/46b1b85b-c76a-443b-b795-dc53489fac20 2026-09-16 13:49:04,426 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/46b1b85b-c76a-443b-b795-dc53489fac20 2026-09-16 13:49:04,432 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/770ba3e3-3bbe-49e9-93bb-3d8d5e503c17 2026-09-16 13:49:04,437 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-09-16 13:49:04,437 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:04,445 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/46b1b85b-c76a-443b-b795-dc53489fac20 2026-09-16 13:49:04,458 - INFO - Verified VN network id 13 for VN c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:04,458 - INFO - Verifications in API Server for VN ctest-vn-73005531 passed 2026-09-16 13:49:04,458 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:04,465 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/46b1b85b-c76a-443b-b795-dc53489fac20 2026-09-16 13:49:04,471 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/46b1b85b-c76a-443b-b795-dc53489fac20 2026-09-16 13:49:04,477 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/770ba3e3-3bbe-49e9-93bb-3d8d5e503c17 2026-09-16 13:49:04,489 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-73005531', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-44288715', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14152010884662969148', 'uuid-lslong': '10176189517386376956'}, 'enable': 'true', 'created': '2026-09-16T13:49:01', 'last-modified': '2026-09-16T13:49:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.583174'} 2026-09-16 13:49:04,496 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-09-16 13:49:04,496 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-73005531 passed 2026-09-16 13:49:04,496 - DEBUG - ====Verifying policy data for ctest-vn-73005531 in API_Server ====== 2026-09-16 13:49:04,496 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:49:04,502 - DEBUG - Requesting: http://10.0.0.177:8082/domain/bbb654c8-6af1-4af3-bd16-6ed059a899ed 2026-09-16 13:49:04,512 - DEBUG - Requesting: http://10.0.0.177:8082/project/11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:49:04,595 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/c4660aeb-45cc-433c-8d39-167346de66fc 2026-09-16 13:49:04,604 - DEBUG - ==>Verifying data for policy with id: 26ac3ea0-3403-4caa-b7e5-85f80d0dd7be, fqn: ['default-domain', 'ctest-TestBasicPolicy-44288715', 'ctest-policy-allow-all-25302964'] 2026-09-16 13:49:04,604 - INFO - VN ctest-vn-73005531 Policy verification: verify_vn_policy_in_api_server, status: True 2026-09-16 13:49:04,604 - DEBUG - Verifying the vn in opserver 2026-09-16 13:49:04,604 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-73005531 virtual network link through opserver 10.0.0.177 2026-09-16 13:49:04,604 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-09-16 13:49:04,615 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-73005531', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-73005531?flat'} 2026-09-16 13:49:04,615 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-73005531 is found in opserver 2026-09-16 13:49:04,623 - DEBUG - Do not have enough data to verify VN in agent 2026-09-16 13:49:04,627 - DEBUG - VRF ids for VN ctest-vn-73005531: {} 2026-09-16 13:49:04,691 - DEBUG - Services list from nova: [, , ] 2026-09-16 13:49:05,151 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6294-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6294-1) 2026-09-16 13:49:05,512 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6294-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6294-1) 2026-09-16 13:49:05,513 - INFO - Waiting for VM ctest-TestBasicPolicy-44288715-22794144 to be up.. 2026-09-16 13:49:05,550 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:10,626 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:15,707 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:20,789 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:25,871 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:30,964 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:36,040 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:41,117 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:49:46,198 - DEBUG - VM is in ACTIVE state now 2026-09-16 13:49:46,198 - INFO - VM name : ctest-TestBasicPolicy-44288715-22794144 2026-09-16 13:49:46,270 - DEBUG - VM ctest-TestBasicPolicy-44288715-22794144 ID is c178d535-89e7-4d53-a610-3cd7d46c13fd 2026-09-16 13:49:46,290 - DEBUG - VM ctest-TestBasicPolicy-44288715-22794144 launched on Node cn-jenkins-deploy-platform-ansible-os-6294-1 2026-09-16 13:49:46,370 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-machine/c178d535-89e7-4d53-a610-3cd7d46c13fd 2026-09-16 13:49:46,379 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-machine-interface/9c5f87f5-345f-45d5-b8a6-a79803195481 2026-09-16 13:49:49,643 - 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') 2026-09-16 13:49:49,643 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-44288715-22794144 failed! 2026-09-16 13:49:49,701 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-44288715:ctest-vn-81162446 is 163.248.104.193 and allocation pool is NOT set 2026-09-16 13:49:51,766 - 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=4.46 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.574 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.574/2.518/4.463/1.944 ms') 2026-09-16 13:49:51,766 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-44288715-22794144 passed 2026-09-16 13:49:51,925 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:49:51,925 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-44288715-22794144, IP 163.248.104.195, Port 22 2026-09-16 13:49:52,095 - DEBUG - VM ctest-TestBasicPolicy-44288715-22794144 is ready for SSH connections 2026-09-16 13:49:52,095 - INFO - Waiting for VM ctest-TestBasicPolicy-44288715-34507409 to be up.. 2026-09-16 13:49:52,180 - DEBUG - VM is in ACTIVE state now 2026-09-16 13:49:52,180 - INFO - VM name : ctest-TestBasicPolicy-44288715-34507409 2026-09-16 13:49:52,257 - DEBUG - VM ctest-TestBasicPolicy-44288715-34507409 ID is e905d11a-82fd-4006-b86e-b342c4611ec9 2026-09-16 13:49:52,257 - DEBUG - VM ctest-TestBasicPolicy-44288715-34507409 launched on Node cn-jenkins-deploy-platform-ansible-os-6294-1 2026-09-16 13:49:52,336 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-machine/e905d11a-82fd-4006-b86e-b342c4611ec9 2026-09-16 13:49:52,346 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-machine-interface/dfb212d1-1546-473a-bc26-cd0542e716ac 2026-09-16 13:49:53,603 - 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.82 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.529 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.529/2.174/3.819/1.645 ms') 2026-09-16 13:49:53,603 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-44288715-34507409 passed 2026-09-16 13:49:53,757 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:49:53,758 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-44288715-34507409, IP 75.152.5.195, Port 22 2026-09-16 13:49:53,925 - DEBUG - VM ctest-TestBasicPolicy-44288715-34507409 is ready for SSH connections 2026-09-16 13:49:53,925 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:49:53,925 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 75.152.5.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.177, gateway password: c0ntrail123 2026-09-16 13:49:53,925 - DEBUG - ping -s 56 -c 3 -W 1 75.152.5.195 2026-09-16 13:49:57,893 - DEBUG - PING 75.152.5.195 (75.152.5.195): 56 data bytes 64 bytes from 75.152.5.195: seq=0 ttl=63 time=4.602 ms 64 bytes from 75.152.5.195: seq=1 ttl=63 time=1.079 ms 64 bytes from 75.152.5.195: seq=2 ttl=63 time=1.273 ms --- 75.152.5.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.079/2.318/4.602 ms 2026-09-16 13:49:57,894 - INFO - Ping to IP 75.152.5.195 from VM ctest-TestBasicPolicy-44288715-22794144 passed 2026-09-16 13:49:58,081 - DEBUG - Policy Update Response {"network-policy": {"uuid": "26ac3ea0-3403-4caa-b7e5-85f80d0dd7be", "href": "http://10.0.0.177:8082/network-policy/26ac3ea0-3403-4caa-b7e5-85f80d0dd7be"}} 2026-09-16 13:49:58,081 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:49:58,081 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 75.152.5.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.177, gateway password: c0ntrail123 2026-09-16 13:49:58,081 - DEBUG - ping -s 56 -c 3 -W 1 75.152.5.195 2026-09-16 13:50:01,200 - DEBUG - PING 75.152.5.195 (75.152.5.195): 56 data bytes 64 bytes from 75.152.5.195: seq=0 ttl=63 time=1.524 ms --- 75.152.5.195 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.524/1.524/1.524 ms 2026-09-16 13:50:01,200 - WARNING - Ping to IP 75.152.5.195 from VM ctest-TestBasicPolicy-44288715-22794144 failed 2026-09-16 13:50:01,200 - INFO - Deleting VM ctest-TestBasicPolicy-44288715-34507409 2026-09-16 13:50:01,277 - INFO - Deleting VM ctest-TestBasicPolicy-44288715-22794144 2026-09-16 13:50:01,687 - INFO - Deleted policy ctest-policy-allow-all-25302964 2026-09-16 13:50:01,688 - INFO - Deleting VN ctest-vn-73005531 2026-09-16 13:50:01,724 - DEBUG - VN c4660aeb-45cc-433c-8d39-167346de66fc still in use: Unable to complete operation on network c4660aeb-45cc-433c-8d39-167346de66fc. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5108f6ae-0d3e-4af4-b6d8-d0fbbc8e9446'] 2026-09-16 13:50:01,724 - WARNING - Deleting VN ctest-vn-73005531 failed..Will retry 2026-09-16 13:50:03,857 - DEBUG - Response for deleting network () 2026-09-16 13:50:03,857 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/46b1b85b-c76a-443b-b795-dc53489fac20 2026-09-16 13:50:03,874 - DEBUG - Response Code: 404 2026-09-16 13:50:03,874 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:50:03,880 - DEBUG - Requesting: http://10.0.0.177:8082/domain/bbb654c8-6af1-4af3-bd16-6ed059a899ed 2026-09-16 13:50:03,938 - DEBUG - Requesting: http://10.0.0.177:8082/project/11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:50:04,020 - INFO - Validated that VN ctest-vn-73005531 is not found in API Server 2026-09-16 13:50:04,029 - DEBUG - VN ctest-vn-73005531 is not present in Agent 10.0.0.177 2026-09-16 13:50:04,030 - INFO - Validated that VN ctest-vn-73005531 is not in any agent 2026-09-16 13:50:04,048 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-73005531 info 2026-09-16 13:50:04,049 - INFO - Deleting VN ctest-vn-81162446 2026-09-16 13:50:04,196 - DEBUG - Response for deleting network () 2026-09-16 13:50:04,196 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/bdc1913c-0ded-4bd9-a74c-ecc129213b64 2026-09-16 13:50:04,210 - DEBUG - Response Code: 404 2026-09-16 13:50:04,210 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-09-16 13:50:04,218 - DEBUG - Requesting: http://10.0.0.177:8082/domain/bbb654c8-6af1-4af3-bd16-6ed059a899ed 2026-09-16 13:50:04,276 - DEBUG - Requesting: http://10.0.0.177:8082/project/11b16dc7-4849-408e-a614-92e06fcf2e0f 2026-09-16 13:50:04,364 - INFO - Validated that VN ctest-vn-81162446 is not found in API Server 2026-09-16 13:50:04,374 - DEBUG - VN ctest-vn-81162446 is not present in Agent 10.0.0.177 2026-09-16 13:50:04,374 - INFO - Validated that VN ctest-vn-81162446 is not in any agent 2026-09-16 13:50:04,391 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-81162446 info 2026-09-16 13:50:04,669 - DEBUG - No XMPP flaps were noticed during the test
2026-09-16 13:50:04,669 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:04]
2026-09-16 13:50:04,671 - INFO - -------------------------------------------------------------------------------- 2026-09-16 13:50:05,514 - INFO - Deleted project: ctest-TestBasicPolicy-44288715, ID : 11b16dc7-4849-408e-a614-92e06fcf2e0f