2025-08-06 10:04:53,461 - INFO - Domain Default found not creating 2025-08-06 10:04:53,675 - INFO - Project ctest-TestMd5testsOnControl-01063076 not found, creating it 2025-08-06 10:04:54,231 - INFO - Created Project:ctest-TestMd5testsOnControl-01063076, ID : 3660dee9-c8d3-4d45-97e5-0a2e006064b2 2025-08-06 10:04:56,733 - DEBUG - Response for create_network : {'network': {'id': '2fb8f69e-7ebd-47e4-8c35-86969cb09baf', 'name': 'test_vnv6sr', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:04:57,132 - DEBUG - Response for create_subnet : {'subnet': {'id': 'efcf1c6e-08bb-4f76-88ba-156a79fdf4f3', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '2fb8f69e-7ebd-47e4-8c35-86969cb09baf', 'ip_version': 6, 'cidr': '2001::101:0/120', 'allocation_pools': [{'start': '2001::101:2', 'end': '2001::101:fe'}], 'gateway_ip': '2001::101:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::101:2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:04:57,420 - DEBUG - Response for create_subnet : {'subnet': {'id': '4b32c77f-797a-4c21-a6dc-e2fb6c524042', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '2fb8f69e-7ebd-47e4-8c35-86969cb09baf', 'ip_version': 4, 'cidr': '170.182.70.192/26', 'allocation_pools': [{'start': '170.182.70.194', 'end': '170.182.70.254'}], 'gateway_ip': '170.182.70.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '170.182.70.194', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:04:57,460 - INFO - Created VN test_vnv6sr 2025-08-06 10:04:57,474 - DEBUG - VN test_vnv6sr UUID is 2fb8f69e-7ebd-47e4-8c35-86969cb09baf 2025-08-06 10:04:57,954 - DEBUG - Response for create_network : {'network': {'id': 'ee7e6cba-abd0-455f-b1ff-6a04ab8dfd07', 'name': 'test_vnv6dn', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:04:58,252 - DEBUG - Response for create_subnet : {'subnet': {'id': '9b5edd8d-f68d-4262-ab86-6deed8654444', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': 'ee7e6cba-abd0-455f-b1ff-6a04ab8dfd07', 'ip_version': 6, 'cidr': '2001::201:0/120', 'allocation_pools': [{'start': '2001::201:2', 'end': '2001::201:fe'}], 'gateway_ip': '2001::201:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::201:2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:04:58,494 - DEBUG - Response for create_subnet : {'subnet': {'id': '7f10ceda-cb47-491a-8ecc-805a8ac7e5b4', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': 'ee7e6cba-abd0-455f-b1ff-6a04ab8dfd07', 'ip_version': 4, 'cidr': '92.206.81.0/26', 'allocation_pools': [{'start': '92.206.81.2', 'end': '92.206.81.62'}], 'gateway_ip': '92.206.81.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '92.206.81.2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:04:58,528 - INFO - Created VN test_vnv6dn 2025-08-06 10:04:58,544 - DEBUG - VN test_vnv6dn UUID is ee7e6cba-abd0-455f-b1ff-6a04ab8dfd07 2025-08-06 10:04:58,939 - DEBUG - Services list from nova: [, , , ] 2025-08-06 10:05:00,194 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1) 2025-08-06 10:05:01,822 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2) 2025-08-06 10:05:01,822 - INFO - Waiting for VM source_vm to be up.. 2025-08-06 10:05:01,939 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-06 10:05:07,081 - DEBUG - VM is in ACTIVE state now 2025-08-06 10:05:07,081 - INFO - VM name : source_vm 2025-08-06 10:05:07,184 - DEBUG - VM source_vm ID is 10aa1059-a7e6-4242-9664-e921557c1ab2 2025-08-06 10:05:07,217 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3761-1 2025-08-06 10:05:07,331 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/10aa1059-a7e6-4242-9664-e921557c1ab2 2025-08-06 10:05:07,650 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/10aa1059-a7e6-4242-9664-e921557c1ab2 2025-08-06 10:05:07,688 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/0208f041-8e64-4d4f-a0f4-2d260489879e 2025-08-06 10:05:10,909 - 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 1023ms') 2025-08-06 10:05:10,909 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-06 10:05:10,929 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-06 10:05:10,929 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6sr is 170.182.70.193 and allocation pool is NOT set 2025-08-06 10:05:12,991 - 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.92 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.451 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.451/4.183/7.915/3.732 ms') 2025-08-06 10:05:12,992 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-06 10:05:13,070 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 10:05:13,071 - DEBUG - Waiting to SSH to VM source_vm, IP 170.182.70.195, Port 22 2025-08-06 10:05:13,228 - DEBUG - VM source_vm is ready for SSH connections 2025-08-06 10:05:13,228 - INFO - Waiting for VM dest_vm to be up.. 2025-08-06 10:05:13,375 - DEBUG - VM is in ACTIVE state now 2025-08-06 10:05:13,376 - INFO - VM name : dest_vm 2025-08-06 10:05:13,484 - DEBUG - VM dest_vm ID is e9282be3-5518-4880-94d2-43b7e5481de0 2025-08-06 10:05:13,485 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3761-2 2025-08-06 10:05:13,597 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e9282be3-5518-4880-94d2-43b7e5481de0 2025-08-06 10:05:13,611 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7da5394d-a017-4f7b-8681-2170b7ae237f 2025-08-06 10:05:14,803 - 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.32 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.492 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.492/2.405/4.319/1.913 ms') 2025-08-06 10:05:14,803 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-06 10:05:14,884 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 10:05:14,884 - DEBUG - Waiting to SSH to VM dest_vm, IP 92.206.81.3, Port 22 2025-08-06 10:05:15,043 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-06 10:05:15,051 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6sr, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, 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-08-06 10:05:15,436 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-06 10:05:15,840 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-06 10:05:16,144 - INFO - Configure the policy with allow any 2025-08-06 10:05:16,518 - DEBUG - Response for create_network : {'network': {'id': '1e93131e-9b8a-4e01-a317-9711d4d36434', 'name': 'vn1', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:05:16,817 - DEBUG - Response for create_subnet : {'subnet': {'id': '6399d3d4-7c71-4477-a7b2-50ccb3c272bb', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '1e93131e-9b8a-4e01-a317-9711d4d36434', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:05:16,847 - INFO - Created VN vn1 2025-08-06 10:05:16,863 - DEBUG - VN vn1 UUID is 1e93131e-9b8a-4e01-a317-9711d4d36434 2025-08-06 10:05:17,302 - DEBUG - Response for create_network : {'network': {'id': '52979420-71d6-4b95-9624-2b31c77c5fb5', 'name': 'vn2', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:05:17,611 - DEBUG - Response for create_subnet : {'subnet': {'id': '89da7a7b-5ee7-485b-8d11-8c15518fe810', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '52979420-71d6-4b95-9624-2b31c77c5fb5', 'ip_version': 4, 'cidr': '20.1.1.0/24', 'allocation_pools': [{'start': '20.1.1.2', 'end': '20.1.1.254'}], 'gateway_ip': '20.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.1.1.2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:05:17,642 - INFO - Created VN vn2 2025-08-06 10:05:17,659 - DEBUG - VN vn2 UUID is 52979420-71d6-4b95-9624-2b31c77c5fb5 2025-08-06 10:05:17,770 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:vn1, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, 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-08-06 10:05:18,146 - INFO - Attaching policy sec_grp_policy_ctest-random-63975149 to vn vn1 2025-08-06 10:05:18,552 - INFO - Attaching policy sec_grp_policy_ctest-random-63975149 to vn vn2 2025-08-06 10:05:20,500 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1) 2025-08-06 10:05:22,130 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2) 2025-08-06 10:05:22,197 - INFO - ================================================================================ 2025-08-06 10:05:22,197 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-08-06 10:05:22,197 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-08-06 10:05:23,519 - DEBUG - Skipping xmpp flap check 2025-08-06 10:05:23,519 - INFO - Initial checks done. Running the testcase now 2025-08-06 10:05:23,519 - INFO - 2025-08-06 10:05:23,520 - INFO - config per peer with auth_data None 2025-08-06 10:05:24,192 - INFO - configure MD5 on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:05:24,286 - INFO - config per peer with auth_data None 2025-08-06 10:05:24,946 - INFO - configure MD5 on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:05:25,063 - INFO - config per peer with auth_data None 2025-08-06 10:05:25,730 - INFO - configure MD5 on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:05:25,826 - INFO - check BGP peering is up before setting md5 config 2025-08-06 10:05:25,829 - INFO - Check BGP staus between peers 2025-08-06 10:05:25,848 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-08-06 10:05:26,567 - INFO - Check BGP staus between peers 2025-08-06 10:05:26,582 - DEBUG - Check the BGP connection on 10.0.0.48 2025-08-06 10:05:26,582 - DEBUG - Check the BGP connection on 10.0.0.48 2025-08-06 10:05:36,584 - INFO - Check BGP staus between peers 2025-08-06 10:05:36,600 - INFO - remove md5 configured on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:05:36,601 - INFO - config per peer with auth_data None 2025-08-06 10:05:37,254 - INFO - configure MD5 on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:05:37,362 - INFO - remove md5 configured on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:05:37,362 - INFO - config per peer with auth_data None 2025-08-06 10:05:38,086 - INFO - configure MD5 on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:05:38,182 - INFO - remove md5 configured on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:05:38,182 - INFO - config per peer with auth_data None 2025-08-06 10:05:38,834 - INFO - configure MD5 on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:05:38,942 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-06 10:05:38,943 - INFO - Deleting VM vn2_vm_0 2025-08-06 10:05:38,943 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 10:05:38,943 - ERROR - Nova failed to get host of the VM 2025-08-06 10:05:38,943 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 10:05:38,943 - ERROR - Nova failed to get host of the VM 2025-08-06 10:05:39,064 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-06 10:05:39,064 - INFO - Deleting VM vn1_vm_0 2025-08-06 10:05:39,064 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 10:05:39,065 - ERROR - Nova failed to get host of the VM 2025-08-06 10:05:39,065 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 10:05:39,065 - ERROR - Nova failed to get host of the VM 2025-08-06 10:05:39,172 - INFO - Dettaching policy sec_grp_policy_ctest-random-63975149 from vn vn2 2025-08-06 10:05:39,375 - INFO - Dettaching policy sec_grp_policy_ctest-random-63975149 from vn vn1 2025-08-06 10:05:39,674 - INFO - Deleted policy sec_grp_policy_ctest-random-63975149 2025-08-06 10:05:39,675 - INFO - Deleting VN vn2 2025-08-06 10:05:39,753 - DEBUG - VN 52979420-71d6-4b95-9624-2b31c77c5fb5 still in use: Unable to complete operation on network 52979420-71d6-4b95-9624-2b31c77c5fb5. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ca1cc2b9-fb64-47ff-bbdd-c33d16b6f6ac'] 2025-08-06 10:05:39,753 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-06 10:05:41,946 - DEBUG - Response for deleting network () 2025-08-06 10:05:41,946 - INFO - Deleting VN vn1 2025-08-06 10:05:42,188 - DEBUG - Response for deleting network () 2025-08-06 10:05:42,188 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-06 10:05:42,395 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-06 10:05:42,696 - INFO - Deleted policy allow_all 2025-08-06 10:05:42,696 - INFO - Deleting VM dest_vm 2025-08-06 10:05:42,875 - INFO - Deleting VM source_vm 2025-08-06 10:05:43,036 - INFO - Deleting VN test_vnv6dn 2025-08-06 10:05:43,094 - DEBUG - VN ee7e6cba-abd0-455f-b1ff-6a04ab8dfd07 still in use: Unable to complete operation on network ee7e6cba-abd0-455f-b1ff-6a04ab8dfd07. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-aea761d8-6666-40d7-a53b-906dc8af1d3e'] 2025-08-06 10:05:43,094 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-06 10:05:45,309 - DEBUG - Response for deleting network () 2025-08-06 10:05:45,310 - INFO - Deleting VN test_vnv6sr 2025-08-06 10:05:45,548 - DEBUG - Response for deleting network () 2025-08-06 10:05:46,831 - DEBUG - Skipping xmpp flap check
2025-08-06 10:05:46,831 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-08-06 10:05:46,831 - INFO - -------------------------------------------------------------------------------- 2025-08-06 10:05:47,213 - DEBUG - Response for create_network : {'network': {'id': '83a6abad-8812-4f26-93f7-d515dfa38b62', 'name': 'test_vnv6sr', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:05:47,547 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ce66d32b-916e-436e-ad65-27096de0b37d', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '83a6abad-8812-4f26-93f7-d515dfa38b62', 'ip_version': 6, 'cidr': '2001::101:0/120', 'allocation_pools': [{'start': '2001::101:2', 'end': '2001::101:fe'}], 'gateway_ip': '2001::101:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::101:2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:05:47,786 - DEBUG - Response for create_subnet : {'subnet': {'id': '9ffd5934-089e-49dd-aff2-299d89b681c6', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '83a6abad-8812-4f26-93f7-d515dfa38b62', 'ip_version': 4, 'cidr': '91.10.232.128/26', 'allocation_pools': [{'start': '91.10.232.130', 'end': '91.10.232.190'}], 'gateway_ip': '91.10.232.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.10.232.130', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:05:47,818 - INFO - Created VN test_vnv6sr 2025-08-06 10:05:47,834 - DEBUG - VN test_vnv6sr UUID is 83a6abad-8812-4f26-93f7-d515dfa38b62 2025-08-06 10:05:48,393 - DEBUG - Response for create_network : {'network': {'id': '5643a726-1b88-4c84-b5b8-c1fabd5015cd', 'name': 'test_vnv6dn', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:05:48,798 - DEBUG - Response for create_subnet : {'subnet': {'id': '38b8ed66-2ebb-4595-80cc-1e26043210ac', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '5643a726-1b88-4c84-b5b8-c1fabd5015cd', 'ip_version': 6, 'cidr': '2001::201:0/120', 'allocation_pools': [{'start': '2001::201:2', 'end': '2001::201:fe'}], 'gateway_ip': '2001::201:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::201:2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:05:49,064 - DEBUG - Response for create_subnet : {'subnet': {'id': '056f25c6-7bfe-4eaf-bfb3-0704dd50ccdb', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': '5643a726-1b88-4c84-b5b8-c1fabd5015cd', 'ip_version': 4, 'cidr': '11.145.110.64/26', 'allocation_pools': [{'start': '11.145.110.66', 'end': '11.145.110.126'}], 'gateway_ip': '11.145.110.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.145.110.66', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:05:49,114 - INFO - Created VN test_vnv6dn 2025-08-06 10:05:49,133 - DEBUG - VN test_vnv6dn UUID is 5643a726-1b88-4c84-b5b8-c1fabd5015cd 2025-08-06 10:05:50,892 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1) 2025-08-06 10:05:53,285 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2) 2025-08-06 10:05:53,285 - INFO - Waiting for VM source_vm to be up.. 2025-08-06 10:05:53,408 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-06 10:05:58,530 - DEBUG - VM is in ACTIVE state now 2025-08-06 10:05:58,530 - INFO - VM name : source_vm 2025-08-06 10:05:58,660 - DEBUG - VM source_vm ID is b30b4775-e682-4679-862c-62b122b743a3 2025-08-06 10:05:58,661 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3761-1 2025-08-06 10:05:58,762 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b30b4775-e682-4679-862c-62b122b743a3 2025-08-06 10:05:58,776 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/3f2d31d8-1040-49c4-b7c4-3995a05bd345 2025-08-06 10:06:01,974 - 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 1009ms') 2025-08-06 10:06:01,974 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-06 10:06:01,991 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-06 10:06:01,991 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6sr is 91.10.232.129 and allocation pool is NOT set 2025-08-06 10:06:04,053 - 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.96 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.834 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.834/2.898/4.963/2.064 ms') 2025-08-06 10:06:04,053 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-06 10:06:04,135 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 10:06:04,135 - DEBUG - Waiting to SSH to VM source_vm, IP 91.10.232.131, Port 22 2025-08-06 10:06:04,292 - DEBUG - VM source_vm is ready for SSH connections 2025-08-06 10:06:04,292 - INFO - Waiting for VM dest_vm to be up.. 2025-08-06 10:06:04,397 - DEBUG - VM is in ACTIVE state now 2025-08-06 10:06:04,397 - INFO - VM name : dest_vm 2025-08-06 10:06:04,511 - DEBUG - VM dest_vm ID is 6d1147a5-5e6f-4911-a6f1-4d24e5274a9c 2025-08-06 10:06:04,511 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3761-2 2025-08-06 10:06:04,622 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6d1147a5-5e6f-4911-a6f1-4d24e5274a9c 2025-08-06 10:06:04,637 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/fff2cde8-d036-4707-9a63-0885da74f61c 2025-08-06 10:06:05,825 - 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.43 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.26 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 = 2.261/3.846/5.431/1.585 ms') 2025-08-06 10:06:05,825 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-06 10:06:05,902 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 10:06:05,902 - DEBUG - Waiting to SSH to VM dest_vm, IP 11.145.110.67, Port 22 2025-08-06 10:06:06,077 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-06 10:06:06,087 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6sr, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, 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-08-06 10:06:06,451 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-06 10:06:06,859 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-06 10:06:07,227 - INFO - Configure the policy with allow any 2025-08-06 10:06:07,583 - DEBUG - Response for create_network : {'network': {'id': 'cfe00748-894f-4b37-b1e0-186dca54d531', 'name': 'vn1', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:06:07,845 - DEBUG - Response for create_subnet : {'subnet': {'id': '3626176a-d6bd-4f2b-a9af-20c20ec9bf2c', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': 'cfe00748-894f-4b37-b1e0-186dca54d531', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:06:07,876 - INFO - Created VN vn1 2025-08-06 10:06:07,891 - DEBUG - VN vn1 UUID is cfe00748-894f-4b37-b1e0-186dca54d531 2025-08-06 10:06:08,299 - DEBUG - Response for create_network : {'network': {'id': 'da6f080b-35a0-4f65-8cdb-3bcf109ec5e6', 'name': 'vn2', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'project_id': '3660dee9c8d34d4597e50a2e006064b2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01063076', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 10:06:08,629 - DEBUG - Response for create_subnet : {'subnet': {'id': '7a7dc876-52b5-4af6-9b31-e0984455b163', 'name': '', 'tenant_id': '3660dee9c8d34d4597e50a2e006064b2', 'network_id': 'da6f080b-35a0-4f65-8cdb-3bcf109ec5e6', 'ip_version': 4, 'cidr': '20.1.1.0/24', 'allocation_pools': [{'start': '20.1.1.2', 'end': '20.1.1.254'}], 'gateway_ip': '20.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.1.1.2', 'tags': [], 'project_id': '3660dee9c8d34d4597e50a2e006064b2'}} 2025-08-06 10:06:08,661 - INFO - Created VN vn2 2025-08-06 10:06:08,678 - DEBUG - VN vn2 UUID is da6f080b-35a0-4f65-8cdb-3bcf109ec5e6 2025-08-06 10:06:08,786 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:vn1, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01063076:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, 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-08-06 10:06:09,132 - INFO - Attaching policy sec_grp_policy_ctest-random-75032567 to vn vn1 2025-08-06 10:06:09,527 - INFO - Attaching policy sec_grp_policy_ctest-random-75032567 to vn vn2 2025-08-06 10:06:11,399 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1) 2025-08-06 10:06:13,118 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2) 2025-08-06 10:06:13,188 - INFO - ================================================================================ 2025-08-06 10:06:13,189 - INFO - STARTING TEST : test_create_md5_on_control 2025-08-06 10:06:13,189 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-08-06 10:06:14,531 - DEBUG - Skipping xmpp flap check 2025-08-06 10:06:14,531 - INFO - Initial checks done. Running the testcase now 2025-08-06 10:06:14,531 - INFO - 2025-08-06 10:06:14,531 - INFO - config md5 on each host with auth_data None 2025-08-06 10:06:14,532 - INFO - config per peer with auth_data None 2025-08-06 10:06:15,182 - INFO - configure MD5 on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:06:15,290 - INFO - config per peer with auth_data None 2025-08-06 10:06:15,942 - INFO - configure MD5 on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:06:16,038 - INFO - config per peer with auth_data None 2025-08-06 10:06:16,706 - INFO - configure MD5 on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:06:16,795 - INFO - check if BGP between peers are up before setting md5 2025-08-06 10:06:16,796 - INFO - Check BGP staus between peers 2025-08-06 10:06:16,814 - INFO - setting MD5 config on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:06:16,815 - INFO - configure MD5 on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:06:16,910 - INFO - setting MD5 config on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:06:16,911 - INFO - configure MD5 on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:06:16,994 - INFO - setting MD5 config on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:06:16,994 - INFO - configure MD5 on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:06:17,082 - INFO - check if BGP peering is up after md5 config 2025-08-06 10:06:17,082 - INFO - Check BGP staus between peers 2025-08-06 10:06:17,099 - DEBUG - Check the BGP connection on 10.0.0.48 2025-08-06 10:06:17,100 - DEBUG - Check the BGP connection on 10.0.0.48 2025-08-06 10:06:27,101 - INFO - Check BGP staus between peers 2025-08-06 10:06:27,116 - INFO - remove md5 configured on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:06:27,116 - INFO - config per peer with auth_data None 2025-08-06 10:06:27,754 - INFO - configure MD5 on host 7f0c8d90-02cc-4e57-a259-ce031a23a11a 2025-08-06 10:06:27,862 - INFO - remove md5 configured on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:06:27,863 - INFO - config per peer with auth_data None 2025-08-06 10:06:28,483 - INFO - configure MD5 on host 278a9fe2-9f1d-4512-bc76-e138a98d174f 2025-08-06 10:06:28,582 - INFO - remove md5 configured on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:06:28,583 - INFO - config per peer with auth_data None 2025-08-06 10:06:29,244 - INFO - configure MD5 on host 64d536ee-be31-43c5-b4d0-dd47f1d425b1 2025-08-06 10:06:29,330 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-06 10:06:29,330 - INFO - Deleting VM vn2_vm_0 2025-08-06 10:06:29,331 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 10:06:29,331 - ERROR - Nova failed to get host of the VM 2025-08-06 10:06:29,331 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 10:06:29,331 - ERROR - Nova failed to get host of the VM 2025-08-06 10:06:29,518 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-06 10:06:29,519 - INFO - Deleting VM vn1_vm_0 2025-08-06 10:06:29,519 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 10:06:29,519 - ERROR - Nova failed to get host of the VM 2025-08-06 10:06:29,519 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 10:06:29,519 - ERROR - Nova failed to get host of the VM 2025-08-06 10:06:29,651 - INFO - Dettaching policy sec_grp_policy_ctest-random-75032567 from vn vn2 2025-08-06 10:06:29,839 - INFO - Dettaching policy sec_grp_policy_ctest-random-75032567 from vn vn1 2025-08-06 10:06:30,138 - INFO - Deleted policy sec_grp_policy_ctest-random-75032567 2025-08-06 10:06:30,138 - INFO - Deleting VN vn2 2025-08-06 10:06:30,219 - DEBUG - VN da6f080b-35a0-4f65-8cdb-3bcf109ec5e6 still in use: Unable to complete operation on network da6f080b-35a0-4f65-8cdb-3bcf109ec5e6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9589b9ed-c750-4fee-9efd-05516f7637ea'] 2025-08-06 10:06:30,219 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-06 10:06:32,384 - DEBUG - Response for deleting network () 2025-08-06 10:06:32,384 - INFO - Deleting VN vn1 2025-08-06 10:06:32,585 - DEBUG - Response for deleting network () 2025-08-06 10:06:32,585 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-06 10:06:32,769 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-06 10:06:33,034 - INFO - Deleted policy allow_all 2025-08-06 10:06:33,035 - INFO - Deleting VM dest_vm 2025-08-06 10:06:33,154 - INFO - Deleting VM source_vm 2025-08-06 10:06:33,240 - INFO - Deleting VN test_vnv6dn 2025-08-06 10:06:33,299 - DEBUG - VN 5643a726-1b88-4c84-b5b8-c1fabd5015cd still in use: Unable to complete operation on network 5643a726-1b88-4c84-b5b8-c1fabd5015cd. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4ffef290-b426-4aee-9345-154a3c5c200d'] 2025-08-06 10:06:33,299 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-06 10:06:35,565 - DEBUG - Response for deleting network () 2025-08-06 10:06:35,566 - INFO - Deleting VN test_vnv6sr 2025-08-06 10:06:35,857 - DEBUG - Response for deleting network () 2025-08-06 10:06:37,127 - DEBUG - Skipping xmpp flap check
2025-08-06 10:06:37,127 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-08-06 10:06:37,127 - INFO - -------------------------------------------------------------------------------- 2025-08-06 10:06:37,892 - INFO - Deleted project: ctest-TestMd5testsOnControl-01063076, ID : 3660dee9-c8d3-4d45-97e5-0a2e006064b2