2026-07-20 02:25:10,100 - INFO - Domain Default found not creating 2026-07-20 02:25:10,248 - INFO - Project ctest-TestBasicPolicy-67761293 not found, creating it 2026-07-20 02:25:10,714 - INFO - Created Project:ctest-TestBasicPolicy-67761293, ID : bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:25:12,275 - INFO - ================================================================================ 2026-07-20 02:25:12,275 - INFO - STARTING TEST : test_basic_policy_allow_deny 2026-07-20 02:25:12,275 - 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-07-20 02:25:12,533 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with 2026-07-20 02:25:12,533 - INFO - Initial checks done. Running the testcase now 2026-07-20 02:25:12,533 - INFO - 2026-07-20 02:25:13,147 - DEBUG - Response for create_network : {'network': {'id': 'ae12b4d8-193e-44be-9e66-b62462b59355', 'name': 'ctest-vn-61475639', 'tenant_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'project_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-vn-61475639'], 'port_security_enabled': True, 'description': ''}} 2026-07-20 02:25:13,355 - DEBUG - Response for create_subnet : {'subnet': {'id': '7a8ef0a7-5fde-4b9b-861b-af97507f3b3f', 'name': '', 'tenant_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'network_id': 'ae12b4d8-193e-44be-9e66-b62462b59355', 'ip_version': 4, 'cidr': '70.15.112.128/26', 'allocation_pools': [{'start': '70.15.112.130', 'end': '70.15.112.190'}], 'gateway_ip': '70.15.112.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.15.112.130', 'tags': [], 'project_id': 'bb2a1cc2c6504af08feb1a4747ee64ff'}} 2026-07-20 02:25:13,371 - INFO - Created VN ctest-vn-61475639 2026-07-20 02:25:13,423 - DEBUG - VN ctest-vn-61475639 UUID is ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:13,707 - DEBUG - Response for create_network : {'network': {'id': 'a5eac8e9-c917-4c73-8ee8-8e7af80b121e', 'name': 'ctest-vn-96722750', 'tenant_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'project_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-vn-96722750'], 'port_security_enabled': True, 'description': ''}} 2026-07-20 02:25:13,945 - DEBUG - Response for create_subnet : {'subnet': {'id': '50dfeb95-f49e-460d-9655-d0cf56753c89', 'name': '', 'tenant_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'network_id': 'a5eac8e9-c917-4c73-8ee8-8e7af80b121e', 'ip_version': 4, 'cidr': '66.167.108.128/26', 'allocation_pools': [{'start': '66.167.108.130', 'end': '66.167.108.190'}], 'gateway_ip': '66.167.108.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '66.167.108.130', 'tags': [], 'project_id': 'bb2a1cc2c6504af08feb1a4747ee64ff'}} 2026-07-20 02:25:13,965 - INFO - Created VN ctest-vn-96722750 2026-07-20 02:25:14,019 - DEBUG - VN ctest-vn-96722750 UUID is a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:14,171 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639, 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-67761293:ctest-vn-96722750, 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-07-20 02:25:14,483 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'ae12b4d8-193e-44be-9e66-b62462b59355', 'name': 'ctest-vn-61475639', 'tenant_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'project_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['7a8ef0a7-5fde-4b9b-861b-af97507f3b3f'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-vn-61475639'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-policy-allow-all-44702346']], 'subnet_ipam': [{'subnet_cidr': '70.15.112.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-07-20 02:25:14,708 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'a5eac8e9-c917-4c73-8ee8-8e7af80b121e', 'name': 'ctest-vn-96722750', 'tenant_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'project_id': 'bb2a1cc2c6504af08feb1a4747ee64ff', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['50dfeb95-f49e-460d-9655-d0cf56753c89'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-vn-96722750'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-policy-allow-all-44702346']], 'subnet_ipam': [{'subnet_cidr': '66.167.108.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-07-20 02:25:14,887 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:25:15,217 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:25:15,245 - DEBUG - Requesting: http://10.0.0.22:8082/domain/763c6432-4151-4fd2-bc40-7ca1e44099b6 2026-07-20 02:25:15,258 - DEBUG - Requesting: http://10.0.0.22:8082/project/bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:25:15,332 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:15,345 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:15,354 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/8259936b-788f-44e3-9fc3-27affb3d4499 2026-07-20 02:25:15,362 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/8259936b-788f-44e3-9fc3-27affb3d4499 2026-07-20 02:25:15,369 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/3d080987-ed52-4f35-92b9-477de8b6f57c 2026-07-20 02:25:15,376 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-07-20 02:25:15,376 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:15,384 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/8259936b-788f-44e3-9fc3-27affb3d4499 2026-07-20 02:25:15,398 - INFO - Verified VN network id 11 for VN ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:15,398 - INFO - Verifications in API Server for VN ctest-vn-61475639 passed 2026-07-20 02:25:15,398 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:15,408 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/8259936b-788f-44e3-9fc3-27affb3d4499 2026-07-20 02:25:15,416 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/8259936b-788f-44e3-9fc3-27affb3d4499 2026-07-20 02:25:15,425 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/3d080987-ed52-4f35-92b9-477de8b6f57c 2026-07-20 02:25:15,439 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-67761293', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12543286752409699518', 'uuid-lslong': '11414010573008245589'}, 'enable': 'true', 'created': '2026-07-20T02:25:13', 'last-modified': '2026-07-20T02:25:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.983200'} 2026-07-20 02:25:15,446 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-07-20 02:25:15,447 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-61475639 passed 2026-07-20 02:25:15,447 - DEBUG - ====Verifying policy data for ctest-vn-61475639 in API_Server ====== 2026-07-20 02:25:15,447 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:25:15,452 - DEBUG - Requesting: http://10.0.0.22:8082/domain/763c6432-4151-4fd2-bc40-7ca1e44099b6 2026-07-20 02:25:15,461 - DEBUG - Requesting: http://10.0.0.22:8082/project/bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:25:15,529 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/ae12b4d8-193e-44be-9e66-b62462b59355 2026-07-20 02:25:15,540 - DEBUG - ==>Verifying data for policy with id: 07e97d05-a3bd-4526-a056-46cb86c2cd60, fqn: ['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-policy-allow-all-44702346'] 2026-07-20 02:25:15,540 - INFO - VN ctest-vn-61475639 Policy verification: verify_vn_policy_in_api_server, status: True 2026-07-20 02:25:15,540 - DEBUG - Verifying the vn in opserver 2026-07-20 02:25:15,540 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639 virtual network link through opserver 10.0.0.22 2026-07-20 02:25:15,540 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-07-20 02:25:15,862 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-07-20 02:25:15,911 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639?flat'} 2026-07-20 02:25:15,911 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639 is found in opserver 2026-07-20 02:25:15,922 - DEBUG - Do not have enough data to verify VN in agent 2026-07-20 02:25:15,927 - DEBUG - VRF ids for VN ctest-vn-61475639: {} 2026-07-20 02:25:15,927 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:25:15,933 - DEBUG - Requesting: http://10.0.0.22:8082/domain/763c6432-4151-4fd2-bc40-7ca1e44099b6 2026-07-20 02:25:15,943 - DEBUG - Requesting: http://10.0.0.22:8082/project/bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:25:16,016 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:16,027 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:16,036 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/124b0f0f-5dc6-461a-b6fb-64b6bd76b129 2026-07-20 02:25:16,043 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/124b0f0f-5dc6-461a-b6fb-64b6bd76b129 2026-07-20 02:25:16,050 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/fe540b46-e34d-4984-97e4-366080134da3 2026-07-20 02:25:16,057 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-07-20 02:25:16,057 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:16,068 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/124b0f0f-5dc6-461a-b6fb-64b6bd76b129 2026-07-20 02:25:16,088 - INFO - Verified VN network id 12 for VN a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:16,088 - INFO - Verifications in API Server for VN ctest-vn-96722750 passed 2026-07-20 02:25:16,088 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:16,099 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/124b0f0f-5dc6-461a-b6fb-64b6bd76b129 2026-07-20 02:25:16,106 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/124b0f0f-5dc6-461a-b6fb-64b6bd76b129 2026-07-20 02:25:16,114 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/fe540b46-e34d-4984-97e4-366080134da3 2026-07-20 02:25:16,127 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-96722750', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-67761293', 'owner-access': '7', 'group': 'manager', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11955589067235085427', 'uuid-lslong': '10297637206781268510'}, 'enable': 'true', 'created': '2026-07-20T02:25:13', 'last-modified': '2026-07-20T02:25:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.440960'} 2026-07-20 02:25:16,133 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-07-20 02:25:16,133 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-96722750 passed 2026-07-20 02:25:16,133 - DEBUG - ====Verifying policy data for ctest-vn-96722750 in API_Server ====== 2026-07-20 02:25:16,133 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:25:16,138 - DEBUG - Requesting: http://10.0.0.22:8082/domain/763c6432-4151-4fd2-bc40-7ca1e44099b6 2026-07-20 02:25:16,149 - DEBUG - Requesting: http://10.0.0.22:8082/project/bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:25:16,245 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/a5eac8e9-c917-4c73-8ee8-8e7af80b121e 2026-07-20 02:25:16,254 - DEBUG - ==>Verifying data for policy with id: 07e97d05-a3bd-4526-a056-46cb86c2cd60, fqn: ['default-domain', 'ctest-TestBasicPolicy-67761293', 'ctest-policy-allow-all-44702346'] 2026-07-20 02:25:16,255 - INFO - VN ctest-vn-96722750 Policy verification: verify_vn_policy_in_api_server, status: True 2026-07-20 02:25:16,255 - DEBUG - Verifying the vn in opserver 2026-07-20 02:25:16,255 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-96722750 virtual network link through opserver 10.0.0.22 2026-07-20 02:25:16,255 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-07-20 02:25:16,265 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-96722750', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-96722750?flat'} 2026-07-20 02:25:16,265 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-96722750 is found in opserver 2026-07-20 02:25:16,278 - DEBUG - Do not have enough data to verify VN in agent 2026-07-20 02:25:16,283 - DEBUG - VRF ids for VN ctest-vn-96722750: {} 2026-07-20 02:25:16,340 - DEBUG - Services list from nova: [, , ] 2026-07-20 02:25:16,759 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6110-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6110-1) 2026-07-20 02:25:17,128 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6110-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6110-1) 2026-07-20 02:25:17,129 - INFO - Waiting for VM ctest-TestBasicPolicy-67761293-29410552 to be up.. 2026-07-20 02:25:17,167 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:22,239 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:27,324 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:32,398 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:37,481 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:42,552 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:47,627 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:52,700 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-20 02:25:57,775 - DEBUG - VM is in ACTIVE state now 2026-07-20 02:25:57,775 - INFO - VM name : ctest-TestBasicPolicy-67761293-29410552 2026-07-20 02:25:57,848 - DEBUG - VM ctest-TestBasicPolicy-67761293-29410552 ID is d90a056e-9d28-4a2b-b4e9-23a962f5fcbb 2026-07-20 02:25:57,870 - DEBUG - VM ctest-TestBasicPolicy-67761293-29410552 launched on Node cn-jenkins-deploy-platform-ansible-os-6110-1 2026-07-20 02:25:57,941 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/d90a056e-9d28-4a2b-b4e9-23a962f5fcbb 2026-07-20 02:25:57,950 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/9d5a94c8-902b-40b0-87e9-20ec83f9f5e4 2026-07-20 02:26:01,210 - 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 1013ms') 2026-07-20 02:26:01,210 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-67761293-29410552 failed! 2026-07-20 02:26:01,267 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-67761293:ctest-vn-61475639 is 70.15.112.129 and allocation pool is NOT set 2026-07-20 02:26:03,329 - 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=5.76 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.460 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.460/3.108/5.756/2.648 ms') 2026-07-20 02:26:03,329 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-67761293-29410552 passed 2026-07-20 02:26:03,487 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-20 02:26:03,487 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-67761293-29410552, IP 70.15.112.131, Port 22 2026-07-20 02:26:03,643 - DEBUG - VM ctest-TestBasicPolicy-67761293-29410552 is ready for SSH connections 2026-07-20 02:26:03,643 - INFO - Waiting for VM ctest-TestBasicPolicy-67761293-61989214 to be up.. 2026-07-20 02:26:03,719 - DEBUG - VM is in ACTIVE state now 2026-07-20 02:26:03,719 - INFO - VM name : ctest-TestBasicPolicy-67761293-61989214 2026-07-20 02:26:03,795 - DEBUG - VM ctest-TestBasicPolicy-67761293-61989214 ID is 82b31f62-13ca-4476-956f-b1d939bf1400 2026-07-20 02:26:03,795 - DEBUG - VM ctest-TestBasicPolicy-67761293-61989214 launched on Node cn-jenkins-deploy-platform-ansible-os-6110-1 2026-07-20 02:26:03,866 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/82b31f62-13ca-4476-956f-b1d939bf1400 2026-07-20 02:26:03,875 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/90644485-f8f1-4a3e-99a7-49b5025281b6 2026-07-20 02:26:05,118 - 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.53 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.617 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.617/2.075/3.533/1.458 ms') 2026-07-20 02:26:05,118 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-67761293-61989214 passed 2026-07-20 02:26:05,271 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-20 02:26:05,272 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-67761293-61989214, IP 66.167.108.131, Port 22 2026-07-20 02:26:05,427 - DEBUG - VM ctest-TestBasicPolicy-67761293-61989214 is ready for SSH connections 2026-07-20 02:26:05,428 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-20 02:26:05,428 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 66.167.108.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2026-07-20 02:26:05,428 - DEBUG - ping -s 56 -c 3 -W 1 66.167.108.131 2026-07-20 02:26:09,354 - DEBUG - PING 66.167.108.131 (66.167.108.131): 56 data bytes 64 bytes from 66.167.108.131: seq=0 ttl=63 time=4.273 ms 64 bytes from 66.167.108.131: seq=1 ttl=63 time=0.866 ms 64 bytes from 66.167.108.131: seq=2 ttl=63 time=1.264 ms --- 66.167.108.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.866/2.134/4.273 ms 2026-07-20 02:26:09,354 - INFO - Ping to IP 66.167.108.131 from VM ctest-TestBasicPolicy-67761293-29410552 passed 2026-07-20 02:26:09,539 - DEBUG - Policy Update Response {"network-policy": {"uuid": "07e97d05-a3bd-4526-a056-46cb86c2cd60", "href": "http://10.0.0.22:8082/network-policy/07e97d05-a3bd-4526-a056-46cb86c2cd60"}} 2026-07-20 02:26:09,539 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-20 02:26:09,540 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 66.167.108.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2026-07-20 02:26:09,540 - DEBUG - ping -s 56 -c 3 -W 1 66.167.108.131 2026-07-20 02:26:12,664 - DEBUG - PING 66.167.108.131 (66.167.108.131): 56 data bytes 64 bytes from 66.167.108.131: seq=0 ttl=63 time=1.670 ms --- 66.167.108.131 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.670/1.670/1.670 ms 2026-07-20 02:26:12,664 - WARNING - Ping to IP 66.167.108.131 from VM ctest-TestBasicPolicy-67761293-29410552 failed 2026-07-20 02:26:12,664 - INFO - Deleting VM ctest-TestBasicPolicy-67761293-61989214 2026-07-20 02:26:12,726 - INFO - Deleting VM ctest-TestBasicPolicy-67761293-29410552 2026-07-20 02:26:13,107 - INFO - Deleted policy ctest-policy-allow-all-44702346 2026-07-20 02:26:13,107 - INFO - Deleting VN ctest-vn-96722750 2026-07-20 02:26:13,140 - DEBUG - VN a5eac8e9-c917-4c73-8ee8-8e7af80b121e still in use: Unable to complete operation on network a5eac8e9-c917-4c73-8ee8-8e7af80b121e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-accbb3dc-6bad-45dc-abc6-fddd44b554a3'] 2026-07-20 02:26:13,140 - WARNING - Deleting VN ctest-vn-96722750 failed..Will retry 2026-07-20 02:26:15,234 - DEBUG - Response for deleting network () 2026-07-20 02:26:15,234 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/124b0f0f-5dc6-461a-b6fb-64b6bd76b129 2026-07-20 02:26:15,239 - DEBUG - Response Code: 404 2026-07-20 02:26:15,239 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:26:15,252 - DEBUG - Requesting: http://10.0.0.22:8082/domain/763c6432-4151-4fd2-bc40-7ca1e44099b6 2026-07-20 02:26:15,305 - DEBUG - Requesting: http://10.0.0.22:8082/project/bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:26:15,381 - INFO - Validated that VN ctest-vn-96722750 is not found in API Server 2026-07-20 02:26:15,391 - DEBUG - VN ctest-vn-96722750 is not present in Agent 10.0.0.22 2026-07-20 02:26:15,391 - INFO - Validated that VN ctest-vn-96722750 is not in any agent 2026-07-20 02:26:15,405 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-96722750 info 2026-07-20 02:26:15,406 - INFO - Deleting VN ctest-vn-61475639 2026-07-20 02:26:15,509 - DEBUG - Response for deleting network () 2026-07-20 02:26:15,509 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/8259936b-788f-44e3-9fc3-27affb3d4499 2026-07-20 02:26:15,518 - DEBUG - Response Code: 404 2026-07-20 02:26:15,518 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2026-07-20 02:26:15,525 - DEBUG - Requesting: http://10.0.0.22:8082/domain/763c6432-4151-4fd2-bc40-7ca1e44099b6 2026-07-20 02:26:15,581 - DEBUG - Requesting: http://10.0.0.22:8082/project/bb2a1cc2-c650-4af0-8feb-1a4747ee64ff 2026-07-20 02:26:15,651 - INFO - Validated that VN ctest-vn-61475639 is not found in API Server 2026-07-20 02:26:15,663 - DEBUG - VN ctest-vn-61475639 is not present in Agent 10.0.0.22 2026-07-20 02:26:15,663 - INFO - Validated that VN ctest-vn-61475639 is not in any agent 2026-07-20 02:26:15,677 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-61475639 info 2026-07-20 02:26:15,938 - DEBUG - No XMPP flaps were noticed during the test
2026-07-20 02:26:15,938 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:01:03]
2026-07-20 02:26:15,938 - INFO - -------------------------------------------------------------------------------- 2026-07-20 02:26:16,638 - INFO - Deleted project: ctest-TestBasicPolicy-67761293, ID : bb2a1cc2-c650-4af0-8feb-1a4747ee64ff