2025-11-10 02:00:58,583 - INFO - Domain Default found not creating 2025-11-10 02:00:58,737 - INFO - Project ctest-TestBasicPolicy-84209261 not found, creating it 2025-11-10 02:00:59,235 - INFO - Created Project:ctest-TestBasicPolicy-84209261, ID : 781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:00,797 - INFO - ================================================================================ 2025-11-10 02:01:00,797 - INFO - STARTING TEST : test_basic_policy_allow_deny 2025-11-10 02:01:00,797 - 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-11-10 02:01:01,072 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.192': '0'}} with 2025-11-10 02:01:01,073 - INFO - Initial checks done. Running the testcase now 2025-11-10 02:01:01,073 - INFO - 2025-11-10 02:01:01,660 - DEBUG - Response for create_network : {'network': {'id': 'af536213-d7f4-4502-b70c-319a7c6c9cde', 'name': 'ctest-vn-79024018', 'tenant_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'project_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-vn-79024018'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 02:01:01,892 - DEBUG - Response for create_subnet : {'subnet': {'id': '14810aaf-1d14-485f-9489-24992833e00d', 'name': '', 'tenant_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'network_id': 'af536213-d7f4-4502-b70c-319a7c6c9cde', 'ip_version': 4, 'cidr': '190.91.255.64/26', 'allocation_pools': [{'start': '190.91.255.66', 'end': '190.91.255.126'}], 'gateway_ip': '190.91.255.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '190.91.255.66', 'tags': [], 'project_id': '781e7a80f86144a7b9b41e4a8886f8cd'}} 2025-11-10 02:01:01,913 - INFO - Created VN ctest-vn-79024018 2025-11-10 02:01:01,968 - DEBUG - VN ctest-vn-79024018 UUID is af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:02,285 - DEBUG - Response for create_network : {'network': {'id': '1cca994a-ea91-4f4c-bc4b-335455a2cb4e', 'name': 'ctest-vn-50434426', 'tenant_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'project_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-vn-50434426'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 02:01:02,495 - DEBUG - Response for create_subnet : {'subnet': {'id': '298bec82-005d-4139-afad-ca6d0a465223', 'name': '', 'tenant_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'network_id': '1cca994a-ea91-4f4c-bc4b-335455a2cb4e', 'ip_version': 4, 'cidr': '149.32.25.192/26', 'allocation_pools': [{'start': '149.32.25.194', 'end': '149.32.25.254'}], 'gateway_ip': '149.32.25.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '149.32.25.194', 'tags': [], 'project_id': '781e7a80f86144a7b9b41e4a8886f8cd'}} 2025-11-10 02:01:02,517 - INFO - Created VN ctest-vn-50434426 2025-11-10 02:01:02,576 - DEBUG - VN ctest-vn-50434426 UUID is 1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:02,703 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018, 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-84209261:ctest-vn-50434426, 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-11-10 02:01:03,023 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'af536213-d7f4-4502-b70c-319a7c6c9cde', 'name': 'ctest-vn-79024018', 'tenant_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'project_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['14810aaf-1d14-485f-9489-24992833e00d'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-vn-79024018'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-policy-allow-all-94137731']], 'subnet_ipam': [{'subnet_cidr': '190.91.255.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-11-10 02:01:03,311 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '1cca994a-ea91-4f4c-bc4b-335455a2cb4e', 'name': 'ctest-vn-50434426', 'tenant_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'project_id': '781e7a80f86144a7b9b41e4a8886f8cd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['298bec82-005d-4139-afad-ca6d0a465223'], 'fq_name': ['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-vn-50434426'], 'policys': [['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-policy-allow-all-94137731']], 'subnet_ipam': [{'subnet_cidr': '149.32.25.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2025-11-10 02:01:03,455 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:03,755 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:03,785 - DEBUG - Requesting: http://10.0.0.27:8082/domain/1f84de75-c6ff-42e3-bab3-332c35fea5e6 2025-11-10 02:01:03,797 - DEBUG - Requesting: http://10.0.0.27:8082/project/781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:03,875 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:03,886 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:03,895 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e7cf85e2-89c2-4dae-9de4-4f0d2fa65e43 2025-11-10 02:01:03,903 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e7cf85e2-89c2-4dae-9de4-4f0d2fa65e43 2025-11-10 02:01:03,911 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/eb453d3f-34c3-4ebd-9e23-1c0f9bafdcf7 2025-11-10 02:01:03,918 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-11-10 02:01:03,918 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:03,928 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e7cf85e2-89c2-4dae-9de4-4f0d2fa65e43 2025-11-10 02:01:03,943 - INFO - Verified VN network id 11 for VN af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:03,943 - INFO - Verifications in API Server for VN ctest-vn-79024018 passed 2025-11-10 02:01:03,943 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:03,953 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e7cf85e2-89c2-4dae-9de4-4f0d2fa65e43 2025-11-10 02:01:03,960 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e7cf85e2-89c2-4dae-9de4-4f0d2fa65e43 2025-11-10 02:01:03,968 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/eb453d3f-34c3-4ebd-9e23-1c0f9bafdcf7 2025-11-10 02:01:03,980 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-84209261', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12633549217071383810', 'uuid-lslong': '13189971948243557598'}, 'enable': 'true', 'created': '2025-11-10T02:01:01', 'last-modified': '2025-11-10T02:01:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.984672'} 2025-11-10 02:01:03,987 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-11-10 02:01:03,987 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-79024018 passed 2025-11-10 02:01:03,988 - DEBUG - ====Verifying policy data for ctest-vn-79024018 in API_Server ====== 2025-11-10 02:01:03,988 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:03,994 - DEBUG - Requesting: http://10.0.0.27:8082/domain/1f84de75-c6ff-42e3-bab3-332c35fea5e6 2025-11-10 02:01:04,005 - DEBUG - Requesting: http://10.0.0.27:8082/project/781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:04,076 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/af536213-d7f4-4502-b70c-319a7c6c9cde 2025-11-10 02:01:04,088 - DEBUG - ==>Verifying data for policy with id: e631f0c1-8a29-4b43-a7b4-a3fa5487c0f5, fqn: ['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-policy-allow-all-94137731'] 2025-11-10 02:01:04,088 - INFO - VN ctest-vn-79024018 Policy verification: verify_vn_policy_in_api_server, status: True 2025-11-10 02:01:04,088 - DEBUG - Verifying the vn in opserver 2025-11-10 02:01:04,088 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018 virtual network link through opserver 10.0.0.27 2025-11-10 02:01:04,088 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-11-10 02:01:04,391 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-11-10 02:01:04,449 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018?flat'} 2025-11-10 02:01:04,449 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018 is found in opserver 2025-11-10 02:01:04,459 - DEBUG - Do not have enough data to verify VN in agent 2025-11-10 02:01:04,463 - DEBUG - VRF ids for VN ctest-vn-79024018: {} 2025-11-10 02:01:04,463 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:04,469 - DEBUG - Requesting: http://10.0.0.27:8082/domain/1f84de75-c6ff-42e3-bab3-332c35fea5e6 2025-11-10 02:01:04,481 - DEBUG - Requesting: http://10.0.0.27:8082/project/781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:04,551 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:04,561 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:04,570 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7fc4789c-244b-4894-a1d0-b25beef66c13 2025-11-10 02:01:04,577 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7fc4789c-244b-4894-a1d0-b25beef66c13 2025-11-10 02:01:04,584 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/31e78f2e-e380-4584-8179-7054f3dbe3bd 2025-11-10 02:01:04,592 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-11-10 02:01:04,593 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:04,604 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7fc4789c-244b-4894-a1d0-b25beef66c13 2025-11-10 02:01:04,620 - INFO - Verified VN network id 12 for VN 1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:04,620 - INFO - Verifications in API Server for VN ctest-vn-50434426 passed 2025-11-10 02:01:04,620 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:04,630 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7fc4789c-244b-4894-a1d0-b25beef66c13 2025-11-10 02:01:04,636 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7fc4789c-244b-4894-a1d0-b25beef66c13 2025-11-10 02:01:04,643 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/31e78f2e-e380-4584-8179-7054f3dbe3bd 2025-11-10 02:01:04,655 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-50434426', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicPolicy-84209261', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2074639125399555916', 'uuid-lslong': '13567994739690752846'}, 'enable': 'true', 'created': '2025-11-10T02:01:02', 'last-modified': '2025-11-10T02:01:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.417795'} 2025-11-10 02:01:04,660 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-11-10 02:01:04,660 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-50434426 passed 2025-11-10 02:01:04,660 - DEBUG - ====Verifying policy data for ctest-vn-50434426 in API_Server ====== 2025-11-10 02:01:04,661 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:04,666 - DEBUG - Requesting: http://10.0.0.27:8082/domain/1f84de75-c6ff-42e3-bab3-332c35fea5e6 2025-11-10 02:01:04,678 - DEBUG - Requesting: http://10.0.0.27:8082/project/781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:04,750 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1cca994a-ea91-4f4c-bc4b-335455a2cb4e 2025-11-10 02:01:04,759 - DEBUG - ==>Verifying data for policy with id: e631f0c1-8a29-4b43-a7b4-a3fa5487c0f5, fqn: ['default-domain', 'ctest-TestBasicPolicy-84209261', 'ctest-policy-allow-all-94137731'] 2025-11-10 02:01:04,759 - INFO - VN ctest-vn-50434426 Policy verification: verify_vn_policy_in_api_server, status: True 2025-11-10 02:01:04,759 - DEBUG - Verifying the vn in opserver 2025-11-10 02:01:04,759 - DEBUG - Verifying the default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-50434426 virtual network link through opserver 10.0.0.27 2025-11-10 02:01:04,759 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-11-10 02:01:04,769 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-50434426', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-50434426?flat'} 2025-11-10 02:01:04,769 - INFO - Validated that VN default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-50434426 is found in opserver 2025-11-10 02:01:04,779 - DEBUG - Do not have enough data to verify VN in agent 2025-11-10 02:01:04,784 - DEBUG - VRF ids for VN ctest-vn-50434426: {} 2025-11-10 02:01:04,842 - DEBUG - Services list from nova: [, , ] 2025-11-10 02:01:05,964 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4677-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4677-1) 2025-11-10 02:01:07,237 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4677-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4677-1) 2025-11-10 02:01:07,237 - INFO - Waiting for VM ctest-TestBasicPolicy-84209261-36242741 to be up.. 2025-11-10 02:01:07,334 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-10 02:01:12,416 - DEBUG - VM is in ACTIVE state now 2025-11-10 02:01:12,416 - INFO - VM name : ctest-TestBasicPolicy-84209261-36242741 2025-11-10 02:01:12,495 - DEBUG - VM ctest-TestBasicPolicy-84209261-36242741 ID is 56a3d46e-08fa-4562-af6f-9c108ff2c3a7 2025-11-10 02:01:12,520 - DEBUG - VM ctest-TestBasicPolicy-84209261-36242741 launched on Node cn-jenkins-deploy-platform-ansible-os-4677-1 2025-11-10 02:01:12,606 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/56a3d46e-08fa-4562-af6f-9c108ff2c3a7 2025-11-10 02:01:12,615 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/2a9d7ea1-00e7-4269-8cc3-6399205c155b 2025-11-10 02:01:15,878 - 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 1029ms') 2025-11-10 02:01:15,878 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-84209261-36242741 failed! 2025-11-10 02:01:15,932 - DEBUG - Gateway for vn default-domain:ctest-TestBasicPolicy-84209261:ctest-vn-79024018 is 190.91.255.65 and allocation pool is NOT set 2025-11-10 02:01:17,998 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=8.19 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.502 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.502/4.346/8.191/3.844 ms') 2025-11-10 02:01:17,998 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicPolicy-84209261-36242741 passed 2025-11-10 02:01:18,151 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 02:01:18,152 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-84209261-36242741, IP 190.91.255.67, Port 22 2025-11-10 02:01:18,322 - DEBUG - VM ctest-TestBasicPolicy-84209261-36242741 is ready for SSH connections 2025-11-10 02:01:18,322 - INFO - Waiting for VM ctest-TestBasicPolicy-84209261-55226477 to be up.. 2025-11-10 02:01:18,399 - DEBUG - VM is in ACTIVE state now 2025-11-10 02:01:18,399 - INFO - VM name : ctest-TestBasicPolicy-84209261-55226477 2025-11-10 02:01:18,473 - DEBUG - VM ctest-TestBasicPolicy-84209261-55226477 ID is b428add0-4c4c-4540-a958-8f017cd97862 2025-11-10 02:01:18,473 - DEBUG - VM ctest-TestBasicPolicy-84209261-55226477 launched on Node cn-jenkins-deploy-platform-ansible-os-4677-1 2025-11-10 02:01:18,554 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/b428add0-4c4c-4540-a958-8f017cd97862 2025-11-10 02:01:18,563 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/21c8d99c-2df2-4e41-a224-6df6ac786c06 2025-11-10 02:01:19,806 - 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.99 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.10 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.098/2.546/3.994/1.448 ms') 2025-11-10 02:01:19,806 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicPolicy-84209261-55226477 passed 2025-11-10 02:01:19,959 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 02:01:19,960 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-84209261-55226477, IP 149.32.25.195, Port 22 2025-11-10 02:01:20,020 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-11-10 02:01:20,096 - DEBUG - VM ctest-TestBasicPolicy-84209261-55226477 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-10 02:01:25,097 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 02:01:25,098 - DEBUG - Waiting to SSH to VM ctest-TestBasicPolicy-84209261-55226477, IP 149.32.25.195, Port 22 2025-11-10 02:01:25,270 - DEBUG - VM ctest-TestBasicPolicy-84209261-55226477 is ready for SSH connections 2025-11-10 02:01:25,270 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 02:01:25,270 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 149.32.25.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-11-10 02:01:25,270 - DEBUG - ping -s 56 -c 3 -W 1 149.32.25.195 2025-11-10 02:01:29,335 - DEBUG - PING 149.32.25.195 (149.32.25.195): 56 data bytes 64 bytes from 149.32.25.195: seq=0 ttl=63 time=6.031 ms 64 bytes from 149.32.25.195: seq=1 ttl=63 time=0.892 ms 64 bytes from 149.32.25.195: seq=2 ttl=63 time=1.215 ms --- 149.32.25.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.892/2.712/6.031 ms 2025-11-10 02:01:29,335 - INFO - Ping to IP 149.32.25.195 from VM ctest-TestBasicPolicy-84209261-36242741 passed 2025-11-10 02:01:29,515 - DEBUG - Policy Update Response {"network-policy": {"uuid": "e631f0c1-8a29-4b43-a7b4-a3fa5487c0f5", "href": "http://10.0.0.27:8082/network-policy/e631f0c1-8a29-4b43-a7b4-a3fa5487c0f5"}} 2025-11-10 02:01:29,515 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 02:01:29,515 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 149.32.25.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-11-10 02:01:29,515 - DEBUG - ping -s 56 -c 3 -W 1 149.32.25.195 2025-11-10 02:01:32,738 - DEBUG - PING 149.32.25.195 (149.32.25.195): 56 data bytes --- 149.32.25.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-10 02:01:32,739 - WARNING - Ping to IP 149.32.25.195 from VM ctest-TestBasicPolicy-84209261-36242741 failed 2025-11-10 02:01:32,739 - INFO - Deleting VM ctest-TestBasicPolicy-84209261-55226477 2025-11-10 02:01:32,822 - INFO - Deleting VM ctest-TestBasicPolicy-84209261-36242741 2025-11-10 02:01:33,316 - INFO - Deleted policy ctest-policy-allow-all-94137731 2025-11-10 02:01:33,317 - INFO - Deleting VN ctest-vn-50434426 2025-11-10 02:01:33,371 - DEBUG - VN 1cca994a-ea91-4f4c-bc4b-335455a2cb4e still in use: Unable to complete operation on network 1cca994a-ea91-4f4c-bc4b-335455a2cb4e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-14ef99a4-005a-4919-8c86-e4e8f699f022'] 2025-11-10 02:01:33,372 - WARNING - Deleting VN ctest-vn-50434426 failed..Will retry 2025-11-10 02:01:35,500 - DEBUG - Response for deleting network () 2025-11-10 02:01:35,500 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/7fc4789c-244b-4894-a1d0-b25beef66c13 2025-11-10 02:01:35,507 - DEBUG - Response Code: 404 2025-11-10 02:01:35,507 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:35,513 - DEBUG - Requesting: http://10.0.0.27:8082/domain/1f84de75-c6ff-42e3-bab3-332c35fea5e6 2025-11-10 02:01:35,569 - DEBUG - Requesting: http://10.0.0.27:8082/project/781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:35,664 - INFO - Validated that VN ctest-vn-50434426 is not found in API Server 2025-11-10 02:01:35,674 - DEBUG - VN ctest-vn-50434426 is not present in Agent 10.0.0.27 2025-11-10 02:01:35,674 - INFO - Validated that VN ctest-vn-50434426 is not in any agent 2025-11-10 02:01:35,688 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-50434426 info 2025-11-10 02:01:35,688 - INFO - Deleting VN ctest-vn-79024018 2025-11-10 02:01:35,798 - DEBUG - Response for deleting network () 2025-11-10 02:01:35,798 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/e7cf85e2-89c2-4dae-9de4-4f0d2fa65e43 2025-11-10 02:01:35,804 - DEBUG - Response Code: 404 2025-11-10 02:01:35,804 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-11-10 02:01:35,811 - DEBUG - Requesting: http://10.0.0.27:8082/domain/1f84de75-c6ff-42e3-bab3-332c35fea5e6 2025-11-10 02:01:35,877 - DEBUG - Requesting: http://10.0.0.27:8082/project/781e7a80-f861-44a7-b9b4-1e4a8886f8cd 2025-11-10 02:01:35,951 - INFO - Validated that VN ctest-vn-79024018 is not found in API Server 2025-11-10 02:01:35,961 - DEBUG - VN ctest-vn-79024018 is not present in Agent 10.0.0.27 2025-11-10 02:01:35,961 - INFO - Validated that VN ctest-vn-79024018 is not in any agent 2025-11-10 02:01:35,977 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-79024018 info 2025-11-10 02:01:36,247 - DEBUG - No XMPP flaps were noticed during the test
2025-11-10 02:01:36,247 - INFO - END TEST : test_basic_policy_allow_deny : PASSED[0:00:36]
2025-11-10 02:01:36,247 - INFO - -------------------------------------------------------------------------------- 2025-11-10 02:01:36,927 - INFO - Deleted project: ctest-TestBasicPolicy-84209261, ID : 781e7a80-f861-44a7-b9b4-1e4a8886f8cd