2026-03-19 04:23:25,652 - INFO - Domain Default found not creating 2026-03-19 04:23:25,858 - INFO - Project ctest-TestMd5testsOnControl-96408383 not found, creating it 2026-03-19 04:23:26,473 - INFO - Created Project:ctest-TestMd5testsOnControl-96408383, ID : 9673ea6e-ff60-401f-832c-19edb55a7b23 2026-03-19 04:23:29,609 - DEBUG - Response for create_network : {'network': {'id': '319dcdb6-955f-4028-b863-a29208fb20b3', 'name': 'test_vnv6sr', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:23:29,960 - DEBUG - Response for create_subnet : {'subnet': {'id': '2ed49f65-b221-41c8-a857-50c84ce79daf', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '319dcdb6-955f-4028-b863-a29208fb20b3', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:23:30,294 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ff0a1947-1cbe-4d20-9fb6-d45edfe9d847', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '319dcdb6-955f-4028-b863-a29208fb20b3', 'ip_version': 4, 'cidr': '175.197.71.128/26', 'allocation_pools': [{'start': '175.197.71.130', 'end': '175.197.71.190'}], 'gateway_ip': '175.197.71.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '175.197.71.130', 'tags': [], 'project_id': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:23:30,320 - INFO - Created VN test_vnv6sr 2026-03-19 04:23:30,332 - DEBUG - VN test_vnv6sr UUID is 319dcdb6-955f-4028-b863-a29208fb20b3 2026-03-19 04:23:30,772 - DEBUG - Response for create_network : {'network': {'id': '116c40df-23fa-4e61-8b9c-3ffd2c9d8545', 'name': 'test_vnv6dn', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:23:31,023 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd79f262d-f2b2-4a7e-b0b1-306b08456f14', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '116c40df-23fa-4e61-8b9c-3ffd2c9d8545', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:23:31,227 - DEBUG - Response for create_subnet : {'subnet': {'id': '41ae4396-74ce-4823-9838-df2abce67266', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '116c40df-23fa-4e61-8b9c-3ffd2c9d8545', 'ip_version': 4, 'cidr': '7.56.92.128/26', 'allocation_pools': [{'start': '7.56.92.130', 'end': '7.56.92.190'}], 'gateway_ip': '7.56.92.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '7.56.92.130', 'tags': [], 'project_id': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:23:31,253 - INFO - Created VN test_vnv6dn 2026-03-19 04:23:31,269 - DEBUG - VN test_vnv6dn UUID is 116c40df-23fa-4e61-8b9c-3ffd2c9d8545 2026-03-19 04:23:31,461 - DEBUG - Services list from nova: [, , , ] 2026-03-19 04:23:32,736 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-2) 2026-03-19 04:23:34,253 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-1) 2026-03-19 04:23:34,254 - INFO - Waiting for VM source_vm to be up.. 2026-03-19 04:23:34,359 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-19 04:23:39,448 - DEBUG - VM is in ACTIVE state now 2026-03-19 04:23:39,448 - INFO - VM name : source_vm 2026-03-19 04:23:39,596 - DEBUG - VM source_vm ID is 7d78b673-769c-4342-8b2b-68e0a0a53ffd 2026-03-19 04:23:39,625 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5493-2 2026-03-19 04:23:39,718 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/7d78b673-769c-4342-8b2b-68e0a0a53ffd 2026-03-19 04:23:40,051 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/7d78b673-769c-4342-8b2b-68e0a0a53ffd 2026-03-19 04:23:40,098 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/c8d440f6-96a1-4fcc-b5e4-3344b0963149 2026-03-19 04:23:43,271 - 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 1010ms') 2026-03-19 04:23:43,271 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-03-19 04:23:43,288 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96408383:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-03-19 04:23:43,288 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96408383:test_vnv6sr is 175.197.71.129 and allocation pool is NOT set 2026-03-19 04:23:45,347 - 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=10.4 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.80 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 = 2.797/6.591/10.386/3.794 ms') 2026-03-19 04:23:45,347 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-03-19 04:23:45,414 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 04:23:45,415 - DEBUG - Waiting to SSH to VM source_vm, IP 175.197.71.131, Port 22 2026-03-19 04:23:45,573 - DEBUG - VM source_vm is ready for SSH connections 2026-03-19 04:23:45,573 - INFO - Waiting for VM dest_vm to be up.. 2026-03-19 04:23:45,684 - DEBUG - VM is in ACTIVE state now 2026-03-19 04:23:45,684 - INFO - VM name : dest_vm 2026-03-19 04:23:45,792 - DEBUG - VM dest_vm ID is 40fbfdbe-544b-4f2b-9973-0cdee0693096 2026-03-19 04:23:45,792 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5493-1 2026-03-19 04:23:45,899 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/40fbfdbe-544b-4f2b-9973-0cdee0693096 2026-03-19 04:23:45,909 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/0abf52bb-583f-4d41-b56a-ce98919921f6 2026-03-19 04:23:47,087 - 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.24 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.72 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 = 1.716/2.976/4.237/1.260 ms') 2026-03-19 04:23:47,087 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-03-19 04:23:47,156 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 04:23:47,156 - DEBUG - Waiting to SSH to VM dest_vm, IP 7.56.92.131, Port 22 2026-03-19 04:23:47,222 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-19 04:23:47,322 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-03-19 04:23:52,323 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 04:23:52,323 - DEBUG - Waiting to SSH to VM dest_vm, IP 7.56.92.131, Port 22 2026-03-19 04:23:52,495 - DEBUG - VM dest_vm is ready for SSH connections 2026-03-19 04:23:52,501 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96408383: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-96408383: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] 2026-03-19 04:23:52,850 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-03-19 04:23:53,242 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-03-19 04:23:53,514 - INFO - Configure the policy with allow any 2026-03-19 04:23:53,828 - DEBUG - Response for create_network : {'network': {'id': '6afe8136-5d5b-4f6d-a719-14c3dc69de4b', 'name': 'vn1', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:23:54,103 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c35da5f0-9011-43ef-a682-3084dd41a34b', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '6afe8136-5d5b-4f6d-a719-14c3dc69de4b', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:23:54,130 - INFO - Created VN vn1 2026-03-19 04:23:54,148 - DEBUG - VN vn1 UUID is 6afe8136-5d5b-4f6d-a719-14c3dc69de4b 2026-03-19 04:23:54,576 - DEBUG - Response for create_network : {'network': {'id': 'dde998c2-b442-436c-b887-890a4bec732d', 'name': 'vn2', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:23:54,871 - DEBUG - Response for create_subnet : {'subnet': {'id': '6f1e32f1-f3ee-4018-aa6b-b18e9b22fd9b', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': 'dde998c2-b442-436c-b887-890a4bec732d', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:23:54,895 - INFO - Created VN vn2 2026-03-19 04:23:54,913 - DEBUG - VN vn2 UUID is dde998c2-b442-436c-b887-890a4bec732d 2026-03-19 04:23:55,005 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96408383: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-96408383: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] 2026-03-19 04:23:55,345 - INFO - Attaching policy sec_grp_policy_ctest-random-67117994 to vn vn1 2026-03-19 04:23:55,722 - INFO - Attaching policy sec_grp_policy_ctest-random-67117994 to vn vn2 2026-03-19 04:23:57,491 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-2) 2026-03-19 04:23:59,018 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-1) 2026-03-19 04:23:59,083 - INFO - ================================================================================ 2026-03-19 04:23:59,083 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-03-19 04:23:59,083 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-03-19 04:24:00,427 - DEBUG - Skipping xmpp flap check 2026-03-19 04:24:00,427 - INFO - Initial checks done. Running the testcase now 2026-03-19 04:24:00,427 - INFO - 2026-03-19 04:24:00,427 - INFO - config per peer with auth_data None 2026-03-19 04:24:01,091 - INFO - configure MD5 on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:24:01,183 - INFO - config per peer with auth_data None 2026-03-19 04:24:01,875 - INFO - configure MD5 on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:24:01,982 - INFO - config per peer with auth_data None 2026-03-19 04:24:02,610 - INFO - configure MD5 on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:24:02,698 - INFO - check BGP peering is up before setting md5 config 2026-03-19 04:24:02,700 - INFO - Check BGP staus between peers 2026-03-19 04:24:02,715 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-03-19 04:24:03,346 - INFO - Check BGP staus between peers 2026-03-19 04:24:03,364 - DEBUG - Check the BGP connection on 10.0.0.41 2026-03-19 04:24:03,364 - DEBUG - Check the BGP connection on 10.0.0.41 2026-03-19 04:24:13,367 - INFO - Check BGP staus between peers 2026-03-19 04:24:13,378 - INFO - remove md5 configured on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:24:13,379 - INFO - config per peer with auth_data None 2026-03-19 04:24:13,966 - INFO - configure MD5 on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:24:14,070 - INFO - remove md5 configured on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:24:14,071 - INFO - config per peer with auth_data None 2026-03-19 04:24:14,679 - INFO - configure MD5 on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:24:14,762 - INFO - remove md5 configured on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:24:14,762 - INFO - config per peer with auth_data None 2026-03-19 04:24:15,374 - INFO - configure MD5 on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:24:15,462 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-03-19 04:24:15,462 - INFO - Deleting VM vn2_vm_0 2026-03-19 04:24:15,462 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-19 04:24:15,462 - ERROR - Nova failed to get host of the VM 2026-03-19 04:24:15,462 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-19 04:24:15,463 - ERROR - Nova failed to get host of the VM 2026-03-19 04:24:15,561 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-03-19 04:24:15,562 - INFO - Deleting VM vn1_vm_0 2026-03-19 04:24:15,562 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-19 04:24:15,562 - ERROR - Nova failed to get host of the VM 2026-03-19 04:24:15,562 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-19 04:24:15,562 - ERROR - Nova failed to get host of the VM 2026-03-19 04:24:15,650 - INFO - Dettaching policy sec_grp_policy_ctest-random-67117994 from vn vn2 2026-03-19 04:24:15,828 - INFO - Dettaching policy sec_grp_policy_ctest-random-67117994 from vn vn1 2026-03-19 04:24:16,097 - INFO - Deleted policy sec_grp_policy_ctest-random-67117994 2026-03-19 04:24:16,097 - INFO - Deleting VN vn2 2026-03-19 04:24:16,145 - DEBUG - VN dde998c2-b442-436c-b887-890a4bec732d still in use: Unable to complete operation on network dde998c2-b442-436c-b887-890a4bec732d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9fa7b252-2d14-4b8c-bc39-bb2c2c12c9f2'] 2026-03-19 04:24:16,145 - WARNING - Deleting VN vn2 failed..Will retry 2026-03-19 04:24:18,317 - DEBUG - Response for deleting network () 2026-03-19 04:24:18,317 - INFO - Deleting VN vn1 2026-03-19 04:24:18,484 - DEBUG - Response for deleting network () 2026-03-19 04:24:18,484 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-03-19 04:24:18,677 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-03-19 04:24:18,993 - INFO - Deleted policy allow_all 2026-03-19 04:24:18,993 - INFO - Deleting VM dest_vm 2026-03-19 04:24:19,082 - INFO - Deleting VM source_vm 2026-03-19 04:24:19,155 - INFO - Deleting VN test_vnv6dn 2026-03-19 04:24:19,215 - DEBUG - VN 116c40df-23fa-4e61-8b9c-3ffd2c9d8545 still in use: Unable to complete operation on network 116c40df-23fa-4e61-8b9c-3ffd2c9d8545. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2153e7d2-379e-44c7-a5b1-d1d9e030e9b2'] 2026-03-19 04:24:19,215 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-03-19 04:24:21,433 - DEBUG - Response for deleting network () 2026-03-19 04:24:21,433 - INFO - Deleting VN test_vnv6sr 2026-03-19 04:24:21,676 - DEBUG - Response for deleting network () 2026-03-19 04:24:22,950 - DEBUG - Skipping xmpp flap check
2026-03-19 04:24:22,950 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2026-03-19 04:24:22,950 - INFO - -------------------------------------------------------------------------------- 2026-03-19 04:24:23,240 - DEBUG - Response for create_network : {'network': {'id': '1383fa6c-360d-4aa4-8b01-861c0367cb6d', 'name': 'test_vnv6sr', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:24:23,549 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f9351f5d-c414-45bb-b8c6-9a8a508fcc1a', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '1383fa6c-360d-4aa4-8b01-861c0367cb6d', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:24:23,777 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a98ed8fa-38ba-4690-a0aa-2a53f649df4c', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '1383fa6c-360d-4aa4-8b01-861c0367cb6d', 'ip_version': 4, 'cidr': '2.144.42.0/26', 'allocation_pools': [{'start': '2.144.42.2', 'end': '2.144.42.62'}], 'gateway_ip': '2.144.42.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2.144.42.2', 'tags': [], 'project_id': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:24:23,807 - INFO - Created VN test_vnv6sr 2026-03-19 04:24:23,818 - DEBUG - VN test_vnv6sr UUID is 1383fa6c-360d-4aa4-8b01-861c0367cb6d 2026-03-19 04:24:24,223 - DEBUG - Response for create_network : {'network': {'id': 'dacf1dd5-d933-46c4-89c4-2afa4cca8b79', 'name': 'test_vnv6dn', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:24:24,471 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c0ecc609-3ac9-469e-8fe2-5e9e15a89a58', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': 'dacf1dd5-d933-46c4-89c4-2afa4cca8b79', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:24:24,729 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ddf92b1b-9680-441a-91b4-45aed39139e1', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': 'dacf1dd5-d933-46c4-89c4-2afa4cca8b79', 'ip_version': 4, 'cidr': '141.108.183.192/26', 'allocation_pools': [{'start': '141.108.183.194', 'end': '141.108.183.254'}], 'gateway_ip': '141.108.183.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '141.108.183.194', 'tags': [], 'project_id': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:24:24,756 - INFO - Created VN test_vnv6dn 2026-03-19 04:24:24,768 - DEBUG - VN test_vnv6dn UUID is dacf1dd5-d933-46c4-89c4-2afa4cca8b79 2026-03-19 04:24:26,125 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-2) 2026-03-19 04:24:27,791 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-1) 2026-03-19 04:24:27,791 - INFO - Waiting for VM source_vm to be up.. 2026-03-19 04:24:27,942 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-19 04:24:33,037 - DEBUG - VM is in ACTIVE state now 2026-03-19 04:24:33,037 - INFO - VM name : source_vm 2026-03-19 04:24:33,135 - DEBUG - VM source_vm ID is f635afd9-2c58-4439-8cc1-b498f3cb4a90 2026-03-19 04:24:33,135 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5493-2 2026-03-19 04:24:33,233 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/f635afd9-2c58-4439-8cc1-b498f3cb4a90 2026-03-19 04:24:33,246 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/6b9feba9-34e8-45dc-9987-155cccc65211 2026-03-19 04:24:36,421 - 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 1007ms') 2026-03-19 04:24:36,421 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-03-19 04:24:36,437 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96408383:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-03-19 04:24:36,437 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96408383:test_vnv6sr is 2.144.42.1 and allocation pool is NOT set 2026-03-19 04:24:38,501 - 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.89 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.876 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.876/2.884/4.893/2.008 ms') 2026-03-19 04:24:38,501 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-03-19 04:24:38,572 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 04:24:38,572 - DEBUG - Waiting to SSH to VM source_vm, IP 2.144.42.3, Port 22 2026-03-19 04:24:38,729 - DEBUG - VM source_vm is ready for SSH connections 2026-03-19 04:24:38,729 - INFO - Waiting for VM dest_vm to be up.. 2026-03-19 04:24:38,843 - DEBUG - VM is in ACTIVE state now 2026-03-19 04:24:38,843 - INFO - VM name : dest_vm 2026-03-19 04:24:38,958 - DEBUG - VM dest_vm ID is 60e58d84-b767-412f-8aee-a1d7bb083039 2026-03-19 04:24:38,959 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5493-1 2026-03-19 04:24:39,069 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/60e58d84-b767-412f-8aee-a1d7bb083039 2026-03-19 04:24:39,080 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/bc5a279b-8726-4733-b6d8-b51df696dd86 2026-03-19 04:24:40,246 - 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.26 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.601 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.601/3.932/7.264/3.331 ms') 2026-03-19 04:24:40,246 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-03-19 04:24:40,317 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 04:24:40,318 - DEBUG - Waiting to SSH to VM dest_vm, IP 141.108.183.195, Port 22 2026-03-19 04:24:40,486 - DEBUG - VM dest_vm is ready for SSH connections 2026-03-19 04:24:40,493 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96408383: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-96408383: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] 2026-03-19 04:24:40,823 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-03-19 04:24:41,209 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-03-19 04:24:41,485 - INFO - Configure the policy with allow any 2026-03-19 04:24:41,800 - DEBUG - Response for create_network : {'network': {'id': '950d949b-4deb-4504-a5c8-f020eede6338', 'name': 'vn1', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:24:42,054 - DEBUG - Response for create_subnet : {'subnet': {'id': '4a4297f8-6e1b-4124-b2b9-f618a20ae6cc', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '950d949b-4deb-4504-a5c8-f020eede6338', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:24:42,083 - INFO - Created VN vn1 2026-03-19 04:24:42,099 - DEBUG - VN vn1 UUID is 950d949b-4deb-4504-a5c8-f020eede6338 2026-03-19 04:24:42,493 - DEBUG - Response for create_network : {'network': {'id': '77fef36f-429e-4e44-967d-3a6fd5cb9a4f', 'name': 'vn2', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'project_id': '9673ea6eff60401f832c19edb55a7b23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96408383', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-03-19 04:24:42,757 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a72fdd78-54e8-41a5-8c32-4dd17d3423dc', 'name': '', 'tenant_id': '9673ea6eff60401f832c19edb55a7b23', 'network_id': '77fef36f-429e-4e44-967d-3a6fd5cb9a4f', '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': '9673ea6eff60401f832c19edb55a7b23'}} 2026-03-19 04:24:42,785 - INFO - Created VN vn2 2026-03-19 04:24:42,798 - DEBUG - VN vn2 UUID is 77fef36f-429e-4e44-967d-3a6fd5cb9a4f 2026-03-19 04:24:42,897 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96408383: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-96408383: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] 2026-03-19 04:24:43,230 - INFO - Attaching policy sec_grp_policy_ctest-random-57908520 to vn vn1 2026-03-19 04:24:43,594 - INFO - Attaching policy sec_grp_policy_ctest-random-57908520 to vn vn2 2026-03-19 04:24:45,319 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-2) 2026-03-19 04:24:46,885 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-1) 2026-03-19 04:24:46,952 - INFO - ================================================================================ 2026-03-19 04:24:46,952 - INFO - STARTING TEST : test_create_md5_on_control 2026-03-19 04:24:46,952 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-03-19 04:24:48,259 - DEBUG - Skipping xmpp flap check 2026-03-19 04:24:48,259 - INFO - Initial checks done. Running the testcase now 2026-03-19 04:24:48,259 - INFO - 2026-03-19 04:24:48,259 - INFO - config md5 on each host with auth_data None 2026-03-19 04:24:48,259 - INFO - config per peer with auth_data None 2026-03-19 04:24:48,827 - INFO - configure MD5 on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:24:48,915 - INFO - config per peer with auth_data None 2026-03-19 04:24:49,538 - INFO - configure MD5 on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:24:49,630 - INFO - config per peer with auth_data None 2026-03-19 04:24:50,254 - INFO - configure MD5 on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:24:50,338 - INFO - check if BGP between peers are up before setting md5 2026-03-19 04:24:50,338 - INFO - Check BGP staus between peers 2026-03-19 04:24:50,349 - INFO - setting MD5 config on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:24:50,349 - INFO - configure MD5 on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:24:50,430 - INFO - setting MD5 config on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:24:50,431 - INFO - configure MD5 on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:24:50,506 - INFO - setting MD5 config on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:24:50,506 - INFO - configure MD5 on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:24:50,582 - INFO - check if BGP peering is up after md5 config 2026-03-19 04:24:50,582 - INFO - Check BGP staus between peers 2026-03-19 04:24:50,593 - DEBUG - Check the BGP connection on 10.0.0.41 2026-03-19 04:24:50,593 - DEBUG - Check the BGP connection on 10.0.0.41 2026-03-19 04:25:00,594 - INFO - Check BGP staus between peers 2026-03-19 04:25:00,610 - INFO - remove md5 configured on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:25:00,610 - INFO - config per peer with auth_data None 2026-03-19 04:25:01,206 - INFO - configure MD5 on host 36694d76-0a0e-4cd6-8e51-38bf493d0e25 2026-03-19 04:25:01,310 - INFO - remove md5 configured on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:25:01,312 - INFO - config per peer with auth_data None 2026-03-19 04:25:02,026 - INFO - configure MD5 on host a214ad0f-975f-4b88-8dae-00f2ee6dfe01 2026-03-19 04:25:02,135 - INFO - remove md5 configured on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:25:02,135 - INFO - config per peer with auth_data None 2026-03-19 04:25:02,800 - INFO - configure MD5 on host 31d57456-5d4a-4868-98dc-c326a1ec843d 2026-03-19 04:25:02,890 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-03-19 04:25:02,891 - INFO - Deleting VM vn2_vm_0 2026-03-19 04:25:02,891 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-19 04:25:02,891 - ERROR - Nova failed to get host of the VM 2026-03-19 04:25:02,891 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-19 04:25:02,891 - ERROR - Nova failed to get host of the VM 2026-03-19 04:25:02,975 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-03-19 04:25:02,975 - INFO - Deleting VM vn1_vm_0 2026-03-19 04:25:02,975 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-19 04:25:02,975 - ERROR - Nova failed to get host of the VM 2026-03-19 04:25:02,975 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-19 04:25:02,975 - ERROR - Nova failed to get host of the VM 2026-03-19 04:25:03,058 - INFO - Dettaching policy sec_grp_policy_ctest-random-57908520 from vn vn2 2026-03-19 04:25:03,224 - INFO - Dettaching policy sec_grp_policy_ctest-random-57908520 from vn vn1 2026-03-19 04:25:03,501 - INFO - Deleted policy sec_grp_policy_ctest-random-57908520 2026-03-19 04:25:03,502 - INFO - Deleting VN vn2 2026-03-19 04:25:03,574 - DEBUG - VN 77fef36f-429e-4e44-967d-3a6fd5cb9a4f still in use: Unable to complete operation on network 77fef36f-429e-4e44-967d-3a6fd5cb9a4f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9fe3f11a-ab6d-4efd-93d4-e1c434fb8ecb'] 2026-03-19 04:25:03,574 - WARNING - Deleting VN vn2 failed..Will retry 2026-03-19 04:25:05,740 - DEBUG - Response for deleting network () 2026-03-19 04:25:05,740 - INFO - Deleting VN vn1 2026-03-19 04:25:05,897 - DEBUG - Response for deleting network () 2026-03-19 04:25:05,897 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-03-19 04:25:06,093 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-03-19 04:25:06,354 - INFO - Deleted policy allow_all 2026-03-19 04:25:06,354 - INFO - Deleting VM dest_vm 2026-03-19 04:25:06,463 - INFO - Deleting VM source_vm 2026-03-19 04:25:06,544 - INFO - Deleting VN test_vnv6dn 2026-03-19 04:25:06,599 - DEBUG - VN dacf1dd5-d933-46c4-89c4-2afa4cca8b79 still in use: Unable to complete operation on network dacf1dd5-d933-46c4-89c4-2afa4cca8b79. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0358e034-ea4f-422f-838e-7ebe474f539e'] 2026-03-19 04:25:06,599 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-03-19 04:25:08,799 - DEBUG - Response for deleting network () 2026-03-19 04:25:08,799 - INFO - Deleting VN test_vnv6sr 2026-03-19 04:25:09,005 - DEBUG - Response for deleting network () 2026-03-19 04:25:10,285 - DEBUG - Skipping xmpp flap check
2026-03-19 04:25:10,285 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2026-03-19 04:25:10,285 - INFO - -------------------------------------------------------------------------------- 2026-03-19 04:25:11,069 - INFO - Deleted project: ctest-TestMd5testsOnControl-96408383, ID : 9673ea6e-ff60-401f-832c-19edb55a7b23