2025-11-10 03:52:53,848 - INFO - Domain Default found not creating 2025-11-10 03:52:54,010 - INFO - Project ctest-TestMd5testsOnControl-01986778 not found, creating it 2025-11-10 03:52:54,726 - INFO - Created Project:ctest-TestMd5testsOnControl-01986778, ID : 1434ea6e-7b4c-475c-8151-f8cf97221b2a 2025-11-10 03:52:56,936 - DEBUG - Response for create_network : {'network': {'id': '05c9df1c-30d3-4c04-be9f-67c5250951ff', 'name': 'test_vnv6sr', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:52:57,235 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd5371402-38d8-4f64-88bf-21782e5d2598', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '05c9df1c-30d3-4c04-be9f-67c5250951ff', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:52:57,478 - DEBUG - Response for create_subnet : {'subnet': {'id': '57b4af57-1ef4-47de-a61a-b5af06a2b9ec', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '05c9df1c-30d3-4c04-be9f-67c5250951ff', 'ip_version': 4, 'cidr': '79.161.141.128/26', 'allocation_pools': [{'start': '79.161.141.130', 'end': '79.161.141.190'}], 'gateway_ip': '79.161.141.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '79.161.141.130', 'tags': [], 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:52:57,505 - INFO - Created VN test_vnv6sr 2025-11-10 03:52:57,517 - DEBUG - VN test_vnv6sr UUID is 05c9df1c-30d3-4c04-be9f-67c5250951ff 2025-11-10 03:52:57,934 - DEBUG - Response for create_network : {'network': {'id': 'ccb5e2ea-6c47-450b-a39a-6c619e74856d', 'name': 'test_vnv6dn', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:52:58,190 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cc7fe84a-d54a-4b41-97f8-5d6d12a5640d', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': 'ccb5e2ea-6c47-450b-a39a-6c619e74856d', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:52:58,404 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f0332ee7-eef4-47f9-9601-94fdd31a4961', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': 'ccb5e2ea-6c47-450b-a39a-6c619e74856d', 'ip_version': 4, 'cidr': '40.230.106.0/26', 'allocation_pools': [{'start': '40.230.106.2', 'end': '40.230.106.62'}], 'gateway_ip': '40.230.106.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '40.230.106.2', 'tags': [], 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:52:58,434 - INFO - Created VN test_vnv6dn 2025-11-10 03:52:58,445 - DEBUG - VN test_vnv6dn UUID is ccb5e2ea-6c47-450b-a39a-6c619e74856d 2025-11-10 03:52:58,638 - DEBUG - Services list from nova: [, , , ] 2025-11-10 03:52:59,821 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1) 2025-11-10 03:53:01,417 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2) 2025-11-10 03:53:01,417 - INFO - Waiting for VM source_vm to be up.. 2025-11-10 03:53:01,535 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-10 03:53:06,645 - DEBUG - VM is in ACTIVE state now 2025-11-10 03:53:06,645 - INFO - VM name : source_vm 2025-11-10 03:53:06,744 - DEBUG - VM source_vm ID is 89537cce-3402-4da1-9bbb-f4587cd0ea50 2025-11-10 03:53:06,780 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4674-1 2025-11-10 03:53:06,917 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/89537cce-3402-4da1-9bbb-f4587cd0ea50 2025-11-10 03:53:07,236 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/89537cce-3402-4da1-9bbb-f4587cd0ea50 2025-11-10 03:53:07,269 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/5c2c4790-cf6a-4329-b0e8-62b64b33d5c0 2025-11-10 03:53:10,448 - 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') 2025-11-10 03:53:10,448 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-11-10 03:53:10,465 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01986778:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-11-10 03:53:10,465 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01986778:test_vnv6sr is 79.161.141.129 and allocation pool is NOT set 2025-11-10 03:53:12,527 - 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.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.759 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 = 0.759/2.622/4.486/1.863 ms') 2025-11-10 03:53:12,527 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-11-10 03:53:12,598 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 03:53:12,598 - DEBUG - Waiting to SSH to VM source_vm, IP 79.161.141.131, Port 22 2025-11-10 03:53:12,767 - DEBUG - VM source_vm is ready for SSH connections 2025-11-10 03:53:12,767 - INFO - Waiting for VM dest_vm to be up.. 2025-11-10 03:53:12,851 - DEBUG - VM is in ACTIVE state now 2025-11-10 03:53:12,851 - INFO - VM name : dest_vm 2025-11-10 03:53:12,941 - DEBUG - VM dest_vm ID is f2cb452a-b4f7-4018-ab0f-e45fa92091a7 2025-11-10 03:53:12,941 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4674-2 2025-11-10 03:53:13,036 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/f2cb452a-b4f7-4018-ab0f-e45fa92091a7 2025-11-10 03:53:13,051 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/23b2c86f-81fe-4749-9bbc-b100b77d7540 2025-11-10 03:53:14,228 - 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.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.453 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.453/2.794/5.135/2.341 ms') 2025-11-10 03:53:14,228 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-11-10 03:53:14,305 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 03:53:14,306 - DEBUG - Waiting to SSH to VM dest_vm, IP 40.230.106.3, Port 22 2025-11-10 03:53:14,473 - DEBUG - VM dest_vm is ready for SSH connections 2025-11-10 03:53:14,481 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01986778: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-01986778: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-11-10 03:53:14,834 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-11-10 03:53:15,223 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-11-10 03:53:15,556 - INFO - Configure the policy with allow any 2025-11-10 03:53:15,861 - DEBUG - Response for create_network : {'network': {'id': 'e9ef3d27-ae9a-4551-b320-0c061ab9426c', 'name': 'vn1', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:53:16,138 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f7201463-4eed-482f-8526-d0fa262334d6', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': 'e9ef3d27-ae9a-4551-b320-0c061ab9426c', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:53:16,163 - INFO - Created VN vn1 2025-11-10 03:53:16,177 - DEBUG - VN vn1 UUID is e9ef3d27-ae9a-4551-b320-0c061ab9426c 2025-11-10 03:53:16,551 - DEBUG - Response for create_network : {'network': {'id': 'ff29cd8a-3bc2-4dcc-8041-95c86341d71a', 'name': 'vn2', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:53:16,795 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd67badc3-dfd3-42b8-914d-db610ca58dcb', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': 'ff29cd8a-3bc2-4dcc-8041-95c86341d71a', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:53:16,825 - INFO - Created VN vn2 2025-11-10 03:53:16,839 - DEBUG - VN vn2 UUID is ff29cd8a-3bc2-4dcc-8041-95c86341d71a 2025-11-10 03:53:16,926 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01986778: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-01986778: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-11-10 03:53:17,263 - INFO - Attaching policy sec_grp_policy_ctest-random-92749195 to vn vn1 2025-11-10 03:53:17,622 - INFO - Attaching policy sec_grp_policy_ctest-random-92749195 to vn vn2 2025-11-10 03:53:19,195 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1) 2025-11-10 03:53:20,596 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2) 2025-11-10 03:53:20,657 - INFO - ================================================================================ 2025-11-10 03:53:20,657 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-11-10 03:53:20,657 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-11-10 03:53:21,955 - DEBUG - Skipping xmpp flap check 2025-11-10 03:53:21,956 - INFO - Initial checks done. Running the testcase now 2025-11-10 03:53:21,956 - INFO - 2025-11-10 03:53:21,956 - INFO - config per peer with auth_data None 2025-11-10 03:53:22,533 - INFO - configure MD5 on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:53:22,629 - INFO - config per peer with auth_data None 2025-11-10 03:53:23,266 - INFO - configure MD5 on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:53:23,373 - INFO - config per peer with auth_data None 2025-11-10 03:53:24,020 - INFO - configure MD5 on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:53:24,113 - INFO - check BGP peering is up before setting md5 config 2025-11-10 03:53:24,113 - INFO - Check BGP staus between peers 2025-11-10 03:53:24,126 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-11-10 03:53:24,740 - INFO - Check BGP staus between peers 2025-11-10 03:53:24,752 - DEBUG - Check the BGP connection on 10.0.0.22 2025-11-10 03:53:24,752 - DEBUG - Check the BGP connection on 10.0.0.22 2025-11-10 03:53:34,753 - INFO - Check BGP staus between peers 2025-11-10 03:53:34,762 - INFO - remove md5 configured on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:53:34,762 - INFO - config per peer with auth_data None 2025-11-10 03:53:35,360 - INFO - configure MD5 on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:53:35,476 - INFO - remove md5 configured on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:53:35,477 - INFO - config per peer with auth_data None 2025-11-10 03:53:36,528 - INFO - configure MD5 on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:53:36,628 - INFO - remove md5 configured on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:53:36,629 - INFO - config per peer with auth_data None 2025-11-10 03:53:37,305 - INFO - configure MD5 on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:53:37,392 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-11-10 03:53:37,393 - INFO - Deleting VM vn2_vm_0 2025-11-10 03:53:37,393 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-10 03:53:37,393 - ERROR - Nova failed to get host of the VM 2025-11-10 03:53:37,393 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-10 03:53:37,393 - ERROR - Nova failed to get host of the VM 2025-11-10 03:53:37,515 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-11-10 03:53:37,515 - INFO - Deleting VM vn1_vm_0 2025-11-10 03:53:37,515 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-10 03:53:37,515 - ERROR - Nova failed to get host of the VM 2025-11-10 03:53:37,515 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-10 03:53:37,515 - ERROR - Nova failed to get host of the VM 2025-11-10 03:53:37,621 - INFO - Dettaching policy sec_grp_policy_ctest-random-92749195 from vn vn2 2025-11-10 03:53:37,816 - INFO - Dettaching policy sec_grp_policy_ctest-random-92749195 from vn vn1 2025-11-10 03:53:38,119 - INFO - Deleted policy sec_grp_policy_ctest-random-92749195 2025-11-10 03:53:38,120 - INFO - Deleting VN vn2 2025-11-10 03:53:38,246 - DEBUG - VN ff29cd8a-3bc2-4dcc-8041-95c86341d71a still in use: Unable to complete operation on network ff29cd8a-3bc2-4dcc-8041-95c86341d71a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-86c3c0c7-605a-4baf-b5a2-bba25eae589c'] 2025-11-10 03:53:38,246 - WARNING - Deleting VN vn2 failed..Will retry 2025-11-10 03:53:40,404 - DEBUG - Response for deleting network () 2025-11-10 03:53:40,405 - INFO - Deleting VN vn1 2025-11-10 03:53:40,571 - DEBUG - Response for deleting network () 2025-11-10 03:53:40,571 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-11-10 03:53:40,767 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-11-10 03:53:41,041 - INFO - Deleted policy allow_all 2025-11-10 03:53:41,042 - INFO - Deleting VM dest_vm 2025-11-10 03:53:41,181 - INFO - Deleting VM source_vm 2025-11-10 03:53:41,303 - INFO - Deleting VN test_vnv6dn 2025-11-10 03:53:41,357 - DEBUG - VN ccb5e2ea-6c47-450b-a39a-6c619e74856d still in use: Unable to complete operation on network ccb5e2ea-6c47-450b-a39a-6c619e74856d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c0865a72-72dd-4b30-b553-ac794733b2d5'] 2025-11-10 03:53:41,357 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-11-10 03:53:43,559 - DEBUG - Response for deleting network () 2025-11-10 03:53:43,559 - INFO - Deleting VN test_vnv6sr 2025-11-10 03:53:43,744 - DEBUG - Response for deleting network () 2025-11-10 03:53:45,004 - DEBUG - Skipping xmpp flap check
2025-11-10 03:53:45,004 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:25]
2025-11-10 03:53:45,005 - INFO - -------------------------------------------------------------------------------- 2025-11-10 03:53:45,275 - DEBUG - Response for create_network : {'network': {'id': '58bcb171-506b-42c4-a09b-c406d629ed73', 'name': 'test_vnv6sr', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:53:45,512 - DEBUG - Response for create_subnet : {'subnet': {'id': '402432ab-7ea2-477b-8676-661d0fd04914', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '58bcb171-506b-42c4-a09b-c406d629ed73', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:53:45,747 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f01027ef-3809-44d9-a92b-8300b6fcb4e7', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '58bcb171-506b-42c4-a09b-c406d629ed73', 'ip_version': 4, 'cidr': '216.24.14.192/26', 'allocation_pools': [{'start': '216.24.14.194', 'end': '216.24.14.254'}], 'gateway_ip': '216.24.14.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.24.14.194', 'tags': [], 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:53:45,774 - INFO - Created VN test_vnv6sr 2025-11-10 03:53:45,787 - DEBUG - VN test_vnv6sr UUID is 58bcb171-506b-42c4-a09b-c406d629ed73 2025-11-10 03:53:46,196 - DEBUG - Response for create_network : {'network': {'id': '4995054d-a028-4243-843f-4babd35c8585', 'name': 'test_vnv6dn', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:53:46,509 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a8ecedbc-4916-496d-9e49-21d30bc1bbba', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '4995054d-a028-4243-843f-4babd35c8585', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:53:46,693 - DEBUG - Response for create_subnet : {'subnet': {'id': '42840a73-7e81-4187-ad9f-46c47a55380f', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '4995054d-a028-4243-843f-4babd35c8585', 'ip_version': 4, 'cidr': '201.75.14.64/26', 'allocation_pools': [{'start': '201.75.14.66', 'end': '201.75.14.126'}], 'gateway_ip': '201.75.14.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '201.75.14.66', 'tags': [], 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:53:46,720 - INFO - Created VN test_vnv6dn 2025-11-10 03:53:46,734 - DEBUG - VN test_vnv6dn UUID is 4995054d-a028-4243-843f-4babd35c8585 2025-11-10 03:53:47,906 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1) 2025-11-10 03:53:49,346 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2) 2025-11-10 03:53:49,346 - INFO - Waiting for VM source_vm to be up.. 2025-11-10 03:53:49,444 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-10 03:53:54,563 - DEBUG - VM is in ACTIVE state now 2025-11-10 03:53:54,563 - INFO - VM name : source_vm 2025-11-10 03:53:54,661 - DEBUG - VM source_vm ID is 6a789434-dc83-49dc-8570-4b930965b33e 2025-11-10 03:53:54,661 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4674-1 2025-11-10 03:53:54,758 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/6a789434-dc83-49dc-8570-4b930965b33e 2025-11-10 03:53:54,776 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f1b40ac3-b01c-4c07-9dc8-e42cefdc2973 2025-11-10 03:53:57,968 - 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') 2025-11-10 03:53:57,968 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-11-10 03:53:57,988 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01986778:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-11-10 03:53:57,988 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-01986778:test_vnv6sr is 216.24.14.193 and allocation pool is NOT set 2025-11-10 03:54:00,051 - 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.09 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.60 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.597/2.843/4.089/1.246 ms') 2025-11-10 03:54:00,051 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-11-10 03:54:00,127 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 03:54:00,127 - DEBUG - Waiting to SSH to VM source_vm, IP 216.24.14.195, Port 22 2025-11-10 03:54:00,195 - 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': ''} 2025-11-10 03:54:00,281 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-11-10 03:54:05,281 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 03:54:05,281 - DEBUG - Waiting to SSH to VM source_vm, IP 216.24.14.195, Port 22 2025-11-10 03:54:05,447 - DEBUG - VM source_vm is ready for SSH connections 2025-11-10 03:54:05,448 - INFO - Waiting for VM dest_vm to be up.. 2025-11-10 03:54:05,551 - DEBUG - VM is in ACTIVE state now 2025-11-10 03:54:05,551 - INFO - VM name : dest_vm 2025-11-10 03:54:05,675 - DEBUG - VM dest_vm ID is 3a6f7448-4958-4901-a1c9-0b27cfb9d690 2025-11-10 03:54:05,675 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4674-2 2025-11-10 03:54:05,764 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/3a6f7448-4958-4901-a1c9-0b27cfb9d690 2025-11-10 03:54:05,775 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/dd97f63a-e954-465e-8b0f-e791477dc262 2025-11-10 03:54:06,925 - 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=2.87 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.517 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.517/1.691/2.865/1.174 ms') 2025-11-10 03:54:06,926 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-11-10 03:54:07,007 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-10 03:54:07,007 - DEBUG - Waiting to SSH to VM dest_vm, IP 201.75.14.67, Port 22 2025-11-10 03:54:07,167 - DEBUG - VM dest_vm is ready for SSH connections 2025-11-10 03:54:07,175 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01986778: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-01986778: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-11-10 03:54:07,517 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-11-10 03:54:07,879 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-11-10 03:54:08,167 - INFO - Configure the policy with allow any 2025-11-10 03:54:08,473 - DEBUG - Response for create_network : {'network': {'id': '78d47195-eb5f-4cc1-8841-afd66384ce30', 'name': 'vn1', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:54:08,706 - DEBUG - Response for create_subnet : {'subnet': {'id': '7a902a0c-5f5a-45a6-b60b-843b28e7d856', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '78d47195-eb5f-4cc1-8841-afd66384ce30', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:54:08,730 - INFO - Created VN vn1 2025-11-10 03:54:08,745 - DEBUG - VN vn1 UUID is 78d47195-eb5f-4cc1-8841-afd66384ce30 2025-11-10 03:54:09,163 - DEBUG - Response for create_network : {'network': {'id': '9c372a80-f1ca-4f3e-8180-ce448c75fa79', 'name': 'vn2', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'project_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-01986778', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-11-10 03:54:09,423 - DEBUG - Response for create_subnet : {'subnet': {'id': '75e3da88-c15a-4257-a675-be245dbcc6c0', 'name': '', 'tenant_id': '1434ea6e7b4c475c8151f8cf97221b2a', 'network_id': '9c372a80-f1ca-4f3e-8180-ce448c75fa79', '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': '1434ea6e7b4c475c8151f8cf97221b2a'}} 2025-11-10 03:54:09,446 - INFO - Created VN vn2 2025-11-10 03:54:09,460 - DEBUG - VN vn2 UUID is 9c372a80-f1ca-4f3e-8180-ce448c75fa79 2025-11-10 03:54:09,549 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-01986778: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-01986778: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-11-10 03:54:09,888 - INFO - Attaching policy sec_grp_policy_ctest-random-61323365 to vn vn1 2025-11-10 03:54:10,253 - INFO - Attaching policy sec_grp_policy_ctest-random-61323365 to vn vn2 2025-11-10 03:54:11,889 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1) 2025-11-10 03:54:13,503 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2) 2025-11-10 03:54:13,578 - INFO - ================================================================================ 2025-11-10 03:54:13,579 - INFO - STARTING TEST : test_create_md5_on_control 2025-11-10 03:54:13,579 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-11-10 03:54:14,899 - DEBUG - Skipping xmpp flap check 2025-11-10 03:54:14,900 - INFO - Initial checks done. Running the testcase now 2025-11-10 03:54:14,900 - INFO - 2025-11-10 03:54:14,900 - INFO - config md5 on each host with auth_data None 2025-11-10 03:54:14,900 - INFO - config per peer with auth_data None 2025-11-10 03:54:15,484 - INFO - configure MD5 on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:54:15,588 - INFO - config per peer with auth_data None 2025-11-10 03:54:16,208 - INFO - configure MD5 on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:54:16,305 - INFO - config per peer with auth_data None 2025-11-10 03:54:16,936 - INFO - configure MD5 on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:54:17,024 - INFO - check if BGP between peers are up before setting md5 2025-11-10 03:54:17,025 - INFO - Check BGP staus between peers 2025-11-10 03:54:17,047 - INFO - setting MD5 config on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:54:17,050 - INFO - configure MD5 on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:54:17,140 - INFO - setting MD5 config on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:54:17,141 - INFO - configure MD5 on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:54:17,228 - INFO - setting MD5 config on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:54:17,229 - INFO - configure MD5 on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:54:17,320 - INFO - check if BGP peering is up after md5 config 2025-11-10 03:54:17,321 - INFO - Check BGP staus between peers 2025-11-10 03:54:17,332 - DEBUG - Check the BGP connection on 10.0.0.22 2025-11-10 03:54:17,332 - DEBUG - Check the BGP connection on 10.0.0.22 2025-11-10 03:54:27,333 - INFO - Check BGP staus between peers 2025-11-10 03:54:27,342 - INFO - remove md5 configured on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:54:27,343 - INFO - config per peer with auth_data None 2025-11-10 03:54:27,960 - INFO - configure MD5 on host efe266c5-8832-4a38-b682-7a86337f713b 2025-11-10 03:54:28,056 - INFO - remove md5 configured on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:54:28,057 - INFO - config per peer with auth_data None 2025-11-10 03:54:28,697 - INFO - configure MD5 on host c2e1e9c7-ce19-4425-ab70-02681d917f2a 2025-11-10 03:54:28,796 - INFO - remove md5 configured on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:54:28,797 - INFO - config per peer with auth_data None 2025-11-10 03:54:29,424 - INFO - configure MD5 on host 38cc2aeb-3536-42b4-a92d-d23a9ac083cd 2025-11-10 03:54:29,513 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-11-10 03:54:29,513 - INFO - Deleting VM vn2_vm_0 2025-11-10 03:54:29,513 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-10 03:54:29,513 - ERROR - Nova failed to get host of the VM 2025-11-10 03:54:29,514 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-10 03:54:29,514 - ERROR - Nova failed to get host of the VM 2025-11-10 03:54:29,610 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-11-10 03:54:29,610 - INFO - Deleting VM vn1_vm_0 2025-11-10 03:54:29,610 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-10 03:54:29,610 - ERROR - Nova failed to get host of the VM 2025-11-10 03:54:29,610 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-10 03:54:29,610 - ERROR - Nova failed to get host of the VM 2025-11-10 03:54:29,688 - INFO - Dettaching policy sec_grp_policy_ctest-random-61323365 from vn vn2 2025-11-10 03:54:29,888 - INFO - Dettaching policy sec_grp_policy_ctest-random-61323365 from vn vn1 2025-11-10 03:54:30,188 - INFO - Deleted policy sec_grp_policy_ctest-random-61323365 2025-11-10 03:54:30,188 - INFO - Deleting VN vn2 2025-11-10 03:54:30,376 - DEBUG - VN 9c372a80-f1ca-4f3e-8180-ce448c75fa79 still in use: Unable to complete operation on network 9c372a80-f1ca-4f3e-8180-ce448c75fa79. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-466a5ec1-82d4-4b6f-99bd-d678fbbd241f'] 2025-11-10 03:54:30,377 - WARNING - Deleting VN vn2 failed..Will retry 2025-11-10 03:54:32,584 - DEBUG - Response for deleting network () 2025-11-10 03:54:32,584 - INFO - Deleting VN vn1 2025-11-10 03:54:32,761 - DEBUG - Response for deleting network () 2025-11-10 03:54:32,761 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-11-10 03:54:32,980 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-11-10 03:54:33,248 - INFO - Deleted policy allow_all 2025-11-10 03:54:33,248 - INFO - Deleting VM dest_vm 2025-11-10 03:54:33,353 - INFO - Deleting VM source_vm 2025-11-10 03:54:33,464 - INFO - Deleting VN test_vnv6dn 2025-11-10 03:54:33,523 - DEBUG - VN 4995054d-a028-4243-843f-4babd35c8585 still in use: Unable to complete operation on network 4995054d-a028-4243-843f-4babd35c8585. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f7aa90d0-2d85-4bcc-92d2-e02e91343201'] 2025-11-10 03:54:33,523 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-11-10 03:54:35,728 - DEBUG - Response for deleting network () 2025-11-10 03:54:35,728 - INFO - Deleting VN test_vnv6sr 2025-11-10 03:54:35,930 - DEBUG - Response for deleting network () 2025-11-10 03:54:37,232 - DEBUG - Skipping xmpp flap check
2025-11-10 03:54:37,232 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-11-10 03:54:37,233 - INFO - -------------------------------------------------------------------------------- 2025-11-10 03:54:38,053 - INFO - Deleted project: ctest-TestMd5testsOnControl-01986778, ID : 1434ea6e-7b4c-475c-8151-f8cf97221b2a