2025-02-10 02:26:28,166 - INFO - Domain Default found not creating 2025-02-10 02:26:28,320 - INFO - Project ctest-TestBasicPolicy-97454212 not found, creating it 2025-02-10 02:26:28,823 - INFO - Created Project:ctest-TestBasicPolicy-97454212, ID : 7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:26:30,464 - INFO - ================================================================================ 2025-02-10 02:26:30,464 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-02-10 02:26:30,464 - 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-02-10 02:26:30,730 - DEBUG - Nothing to compare xmpp stats {'10.0.0.36': {'10.0.0.36': '0'}} with 2025-02-10 02:26:30,730 - INFO - Initial checks done. Running the testcase now 2025-02-10 02:26:30,730 - INFO - 2025-02-10 02:26:31,351 - DEBUG - Response for create_network : {'network': {'id': '32b91736-3a0a-4cfc-b90f-a13ede77548a', 'name': 'ctest-vn-07007357', 'tenant_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'project_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-vn-07007357'], 'port_security_enabled': True, 'description': ''}} 2025-02-10 02:26:31,521 - DEBUG - Response for create_subnet : {'subnet': {'id': '0bdc0e38-6449-463d-8fa7-92536b3cdc35', 'name': '', 'tenant_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'network_id': '32b91736-3a0a-4cfc-b90f-a13ede77548a', 'ip_version': 4, 'cidr': '18.243.197.192/26', 'allocation_pools': [{'start': '18.243.197.194', 'end': '18.243.197.254'}], 'gateway_ip': '18.243.197.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '18.243.197.194', 'tags': [], 'project_id': '7c4207655c8d4ccda606c3f9f12abc9a'}} 2025-02-10 02:26:31,540 - INFO - Created VN ctest-vn-07007357 2025-02-10 02:26:31,591 - DEBUG - VN ctest-vn-07007357 UUID is 32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:31,885 - DEBUG - Response for create_network : {'network': {'id': '4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b', 'name': 'ctest-vn-16344497', 'tenant_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'project_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-vn-16344497'], 'port_security_enabled': True, 'description': ''}} 2025-02-10 02:26:32,117 - DEBUG - Response for create_subnet : {'subnet': {'id': '29d135c5-fa00-44e2-b588-e4174f68f257', 'name': '', 'tenant_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'network_id': '4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b', 'ip_version': 4, 'cidr': '185.123.72.192/26', 'allocation_pools': [{'start': '185.123.72.194', 'end': '185.123.72.254'}], 'gateway_ip': '185.123.72.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '185.123.72.194', 'tags': [], 'project_id': '7c4207655c8d4ccda606c3f9f12abc9a'}} 2025-02-10 02:26:32,139 - INFO - Created VN ctest-vn-16344497 2025-02-10 02:26:32,195 - DEBUG - VN ctest-vn-16344497 UUID is 4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:32,319 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357, 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-97454212:ctest-vn-16344497, 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-02-10 02:26:32,639 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '32b91736-3a0a-4cfc-b90f-a13ede77548a', 'name': 'ctest-vn-07007357', 'tenant_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'project_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['0bdc0e38-6449-463d-8fa7-92536b3cdc35'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-vn-07007357'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-policy-allow-all-48938278']], 'subnet_ipam': [{'subnet_cidr': '18.243.197.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-02-10 02:26:32,936 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b', 'name': 'ctest-vn-16344497', 'tenant_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'project_id': '7c4207655c8d4ccda606c3f9f12abc9a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['29d135c5-fa00-44e2-b588-e4174f68f257'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-vn-16344497'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-policy-allow-all-48938278']], 'subnet_ipam': [{'subnet_cidr': '185.123.72.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-02-10 02:26:33,091 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:26:33,439 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:26:33,472 - DEBUG - Requesting: http://10.0.0.36:8082/domain/77883bac-58e5-4a12-9065-4e365e19ca29 2025-02-10 02:26:33,486 - DEBUG - Requesting: http://10.0.0.36:8082/project/7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:26:33,561 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:33,574 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:33,584 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/bc43124f-b5cb-49f3-a5b9-2968b74b09ae 2025-02-10 02:26:33,592 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/bc43124f-b5cb-49f3-a5b9-2968b74b09ae 2025-02-10 02:26:33,600 - DEBUG - Requesting: http://10.0.0.36:8082/route-target/067b111d-0873-4971-93b4-a0d4d1aac4a6 2025-02-10 02:26:33,606 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-02-10 02:26:33,607 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:33,616 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/bc43124f-b5cb-49f3-a5b9-2968b74b09ae 2025-02-10 02:26:33,632 - INFO - Verified VN network id 8 for VN 32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:33,632 - INFO - Verifications in API Server for VN ctest-vn-07007357 passed 2025-02-10 02:26:33,632 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:33,642 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/bc43124f-b5cb-49f3-a5b9-2968b74b09ae 2025-02-10 02:26:33,649 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/bc43124f-b5cb-49f3-a5b9-2968b74b09ae 2025-02-10 02:26:33,656 - DEBUG - Requesting: http://10.0.0.36:8082/route-target/067b111d-0873-4971-93b4-a0d4d1aac4a6 2025-02-10 02:26:33,670 - DEBUG - Control-node 10.0.0.36 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-97454212', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3654978094257294588', 'uuid-lslong': '13335054313059734666'}, 'enable': 'true', 'created': '2025-02-10T02:26:31', 'last-modified': '2025-02-10T02:26:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.062981'} 2025-02-10 02:26:33,679 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-02-10 02:26:33,679 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-07007357 passed 2025-02-10 02:26:33,679 - DEBUG - ====Verifying policy data for ctest-vn-07007357 in API_Server ====== 2025-02-10 02:26:33,679 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:26:33,688 - DEBUG - Requesting: http://10.0.0.36:8082/domain/77883bac-58e5-4a12-9065-4e365e19ca29 2025-02-10 02:26:33,702 - DEBUG - Requesting: http://10.0.0.36:8082/project/7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:26:33,775 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/32b91736-3a0a-4cfc-b90f-a13ede77548a 2025-02-10 02:26:33,787 - DEBUG - ==>Verifying data for policy with id: 1ddbe67b-7d00-4387-9602-04ee03a159bd, fqn: ['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-policy-allow-all-48938278'] 2025-02-10 02:26:33,787 - INFO - VN ctest-vn-07007357 Policy verification: verify_vn_policy_in_api_server, status: True 2025-02-10 02:26:33,787 - DEBUG - Verifying the vn in opserver 2025-02-10 02:26:33,787 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357 virtual network link through opserver 10.0.0.36 2025-02-10 02:26:33,788 - DEBUG - Requesting: http://10.0.0.36:8081/analytics/uves/virtual-networks 2025-02-10 02:26:34,121 - DEBUG - Requesting: http://10.0.0.36:8081/analytics/uves/virtual-networks 2025-02-10 02:26:34,179 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357', 'href': 'http://10.0.0.36:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357?flat'} 2025-02-10 02:26:34,179 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357 is found in opserver 2025-02-10 02:26:34,191 - DEBUG - Do not have enough data to verify VN in agent 2025-02-10 02:26:34,197 - DEBUG - VRF ids for VN ctest-vn-07007357: {} 2025-02-10 02:26:34,197 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:26:34,204 - DEBUG - Requesting: http://10.0.0.36:8082/domain/77883bac-58e5-4a12-9065-4e365e19ca29 2025-02-10 02:26:34,218 - DEBUG - Requesting: http://10.0.0.36:8082/project/7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:26:34,296 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:34,310 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:34,321 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/baeb1907-03d1-4ef8-8dab-747076be9005 2025-02-10 02:26:34,329 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/baeb1907-03d1-4ef8-8dab-747076be9005 2025-02-10 02:26:34,337 - DEBUG - Requesting: http://10.0.0.36:8082/route-target/aa583fae-2b67-4fcc-8cf1-3a06668a5c01 2025-02-10 02:26:34,344 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-02-10 02:26:34,344 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:34,354 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/baeb1907-03d1-4ef8-8dab-747076be9005 2025-02-10 02:26:34,369 - INFO - Verified VN network id 9 for VN 4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:34,369 - INFO - Verifications in API Server for VN ctest-vn-16344497 passed 2025-02-10 02:26:34,370 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:34,381 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/baeb1907-03d1-4ef8-8dab-747076be9005 2025-02-10 02:26:34,388 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/baeb1907-03d1-4ef8-8dab-747076be9005 2025-02-10 02:26:34,396 - DEBUG - Requesting: http://10.0.0.36:8082/route-target/aa583fae-2b67-4fcc-8cf1-3a06668a5c01 2025-02-10 02:26:34,410 - DEBUG - Control-node 10.0.0.36 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-16344497', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-97454212', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5641811686137613779', 'uuid-lslong': '12970926101328781067'}, 'enable': 'true', 'created': '2025-02-10T02:26:31', 'last-modified': '2025-02-10T02:26:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.547373'} 2025-02-10 02:26:34,419 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-02-10 02:26:34,419 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-16344497 passed 2025-02-10 02:26:34,420 - DEBUG - ====Verifying policy data for ctest-vn-16344497 in API_Server ====== 2025-02-10 02:26:34,420 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:26:34,427 - DEBUG - Requesting: http://10.0.0.36:8082/domain/77883bac-58e5-4a12-9065-4e365e19ca29 2025-02-10 02:26:34,440 - DEBUG - Requesting: http://10.0.0.36:8082/project/7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:26:34,520 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-network/4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b 2025-02-10 02:26:34,530 - DEBUG - ==>Verifying data for policy with id: 1ddbe67b-7d00-4387-9602-04ee03a159bd, fqn: ['default-domain', 'ctest-TestBasicPolicy-97454212', 'ctest-policy-allow-all-48938278'] 2025-02-10 02:26:34,530 - INFO - VN ctest-vn-16344497 Policy verification: verify_vn_policy_in_api_server, status: True 2025-02-10 02:26:34,530 - DEBUG - Verifying the vn in opserver 2025-02-10 02:26:34,531 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-16344497 virtual network link through opserver 10.0.0.36 2025-02-10 02:26:34,531 - DEBUG - Requesting: http://10.0.0.36:8081/analytics/uves/virtual-networks 2025-02-10 02:26:34,541 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-16344497', 'href': 'http://10.0.0.36:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-16344497?flat'} 2025-02-10 02:26:34,541 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-16344497 is found in opserver 2025-02-10 02:26:34,553 - DEBUG - Do not have enough data to verify VN in agent 2025-02-10 02:26:34,558 - DEBUG - VRF ids for VN ctest-vn-16344497: {} 2025-02-10 02:26:34,620 - DEBUG - Services list from nova: [, , ] 2025-02-10 02:26:35,733 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2709-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2709-1) 2025-02-10 02:26:37,162 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2709-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2709-1) 2025-02-10 02:26:37,162 - INFO - Waiting for VM ctest-TestBasicPolicy-97454212-11644599 to be up.. 2025-02-10 02:26:37,269 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-02-10 02:26:42,379 - DEBUG - VM is in ACTIVE state now 2025-02-10 02:26:42,379 - INFO - VM name : ctest-TestBasicPolicy-97454212-11644599 2025-02-10 02:26:42,468 - DEBUG - VM ctest-TestBasicPolicy-97454212-11644599 ID is b34b3df7-31fd-4a09-bc02-d35f4c224955 2025-02-10 02:26:42,490 - DEBUG - VM ctest-TestBasicPolicy-97454212-11644599 launched on Node cn-jenkins-deploy-platform-ansible-os-2709-1. 2025-02-10 02:26:42,580 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-machine/b34b3df7-31fd-4a09-bc02-d35f4c224955 2025-02-10 02:26:42,592 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-machine-interface/c335edd5-4212-45c3-b2b1-16765598dc87 2025-02-10 02:26:45,856 - 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 1014ms') 2025-02-10 02:26:45,857 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-97454212-11644599 failed! 2025-02-10 02:26:45,915 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-97454212:ctest-vn-07007357 is 18.243.197.193 and allocation pool is NOT set 2025-02-10 02:26:47,984 - 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=7.45 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.523 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.523/3.987/7.452/3.464 ms') 2025-02-10 02:26:47,984 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-97454212-11644599 passed 2025-02-10 02:26:48,139 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-02-10 02:26:48,139 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-97454212-11644599, IP 18.243.197.195, Port 22 2025-02-10 02:26:48,303 - DEBUG - VM ctest-TestBasicPolicy-97454212-11644599 is ready for SSH connections 2025-02-10 02:26:48,303 - INFO - Waiting for VM ctest-TestBasicPolicy-97454212-99334592 to be up.. 2025-02-10 02:26:48,398 - DEBUG - VM is in ACTIVE state now 2025-02-10 02:26:48,398 - INFO - VM name : ctest-TestBasicPolicy-97454212-99334592 2025-02-10 02:26:48,490 - DEBUG - VM ctest-TestBasicPolicy-97454212-99334592 ID is 17876274-7016-4a27-8432-0ed21bec49fc 2025-02-10 02:26:48,490 - DEBUG - VM ctest-TestBasicPolicy-97454212-99334592 launched on Node cn-jenkins-deploy-platform-ansible-os-2709-1. 2025-02-10 02:26:48,575 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-machine/17876274-7016-4a27-8432-0ed21bec49fc 2025-02-10 02:26:48,588 - DEBUG - Requesting: http://10.0.0.36:8082/virtual-machine-interface/3cda4118-4822-4e93-80c8-26ac42335f20 2025-02-10 02:26:49,848 - 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.35 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.38 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 = 1.378/2.864/4.351/1.486 ms') 2025-02-10 02:26:49,848 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-97454212-99334592 passed 2025-02-10 02:26:50,012 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-02-10 02:26:50,012 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-97454212-99334592, IP 185.123.72.195, Port 22 2025-02-10 02:26:50,171 - DEBUG - VM ctest-TestBasicPolicy-97454212-99334592 is ready for SSH connections 2025-02-10 02:26:50,171 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-02-10 02:26:50,171 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 185.123.72.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.36, gateway password: c0ntrail123 2025-02-10 02:26:50,171 - DEBUG - ping -s 56 -c 3 -W 1 185.123.72.195 2025-02-10 02:26:53,873 - DEBUG - PING 185.123.72.195 (185.123.72.195): 56 data bytes 64 bytes from 185.123.72.195: seq=0 ttl=63 time=3.577 ms 64 bytes from 185.123.72.195: seq=1 ttl=63 time=0.875 ms 64 bytes from 185.123.72.195: seq=2 ttl=63 time=0.882 ms --- 185.123.72.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.875/1.778/3.577 ms 2025-02-10 02:26:53,873 - INFO - Ping to IP 185.123.72.195 from VM ctest-TestBasicPolicy-97454212-11644599 passed 2025-02-10 02:26:54,063 - DEBUG - Policy Update Response {"network-policy": {"uuid": "1ddbe67b-7d00-4387-9602-04ee03a159bd", "href": "http://10.0.0.36:8082/network-policy/1ddbe67b-7d00-4387-9602-04ee03a159bd"}} 2025-02-10 02:26:54,063 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-02-10 02:26:54,063 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 185.123.72.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.36, gateway password: c0ntrail123 2025-02-10 02:26:54,064 - DEBUG - ping -s 56 -c 3 -W 1 185.123.72.195 2025-02-10 02:26:57,326 - DEBUG - PING 185.123.72.195 (185.123.72.195): 56 data bytes 64 bytes from 185.123.72.195: seq=0 ttl=63 time=1.411 ms --- 185.123.72.195 ping statistics --- 3 packets transmitted, 1 packets received, 66% packet loss round-trip min/avg/max = 1.411/1.411/1.411 ms 2025-02-10 02:26:57,326 - WARNING - Ping to IP 185.123.72.195 from VM ctest-TestBasicPolicy-97454212-11644599 failed 2025-02-10 02:26:57,326 - INFO - Deleting VM ctest-TestBasicPolicy-97454212-99334592 2025-02-10 02:26:57,416 - INFO - Deleting VM ctest-TestBasicPolicy-97454212-11644599 2025-02-10 02:26:57,853 - INFO - Deleted policy ctest-policy-allow-all-48938278 2025-02-10 02:26:57,853 - INFO - Deleting VN ctest-vn-16344497 2025-02-10 02:26:57,900 - DEBUG - VN 4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b still in use: Unable to complete operation on network 4e4bbdd6-41e1-4dd3-b401-fc90f58b5f0b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b4bb091d-4d28-4aba-b05b-d1d247849b75'] 2025-02-10 02:26:57,900 - WARNING - Deleting VN ctest-vn-16344497 failed..Will retry 2025-02-10 02:27:00,015 - DEBUG - Response for deleting network () 2025-02-10 02:27:00,015 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/baeb1907-03d1-4ef8-8dab-747076be9005 2025-02-10 02:27:00,023 - DEBUG - Response Code: 404 2025-02-10 02:27:00,023 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:27:00,031 - DEBUG - Requesting: http://10.0.0.36:8082/domain/77883bac-58e5-4a12-9065-4e365e19ca29 2025-02-10 02:27:00,091 - DEBUG - Requesting: http://10.0.0.36:8082/project/7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:27:00,172 - INFO - Validated that VN ctest-vn-16344497 is not found in API Server 2025-02-10 02:27:00,185 - DEBUG - VN ctest-vn-16344497 is not present in Agent 10.0.0.36 2025-02-10 02:27:00,186 - INFO - Validated that VN ctest-vn-16344497 is not in any agent 2025-02-10 02:27:00,204 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-16344497 info 2025-02-10 02:27:00,204 - INFO - Deleting VN ctest-vn-07007357 2025-02-10 02:27:00,306 - DEBUG - Response for deleting network () 2025-02-10 02:27:00,307 - DEBUG - Requesting: http://10.0.0.36:8082/routing-instance/bc43124f-b5cb-49f3-a5b9-2968b74b09ae 2025-02-10 02:27:00,313 - DEBUG - Response Code: 404 2025-02-10 02:27:00,313 - DEBUG - Requesting: http://10.0.0.36:8082/domains 2025-02-10 02:27:00,324 - DEBUG - Requesting: http://10.0.0.36:8082/domain/77883bac-58e5-4a12-9065-4e365e19ca29 2025-02-10 02:27:00,381 - DEBUG - Requesting: http://10.0.0.36:8082/project/7c420765-5c8d-4ccd-a606-c3f9f12abc9a 2025-02-10 02:27:00,460 - INFO - Validated that VN ctest-vn-07007357 is not found in API Server 2025-02-10 02:27:00,473 - DEBUG - VN ctest-vn-07007357 is not present in Agent 10.0.0.36 2025-02-10 02:27:00,473 - INFO - Validated that VN ctest-vn-07007357 is not in any agent 2025-02-10 02:27:00,489 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-07007357 info 2025-02-10 02:27:00,746 - DEBUG - No XMPP flaps were noticed during the test
2025-02-10 02:27:00,746 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:30]
2025-02-10 02:27:00,746 - INFO - -------------------------------------------------------------------------------- 2025-02-10 02:27:01,473 - INFO - Deleted project: ctest-TestBasicPolicy-97454212, ID : 7c420765-5c8d-4ccd-a606-c3f9f12abc9a