2025-10-20 03:58:05,466 - INFO - Domain Default found not creating 2025-10-20 03:58:05,625 - INFO - Project ctest-TestMd5testsOnControl-09617125 not found, creating it 2025-10-20 03:58:06,109 - INFO - Created Project:ctest-TestMd5testsOnControl-09617125, ID : d76b6e7a-805f-4248-91cf-5a1e80c2d980 2025-10-20 03:58:08,199 - DEBUG - Response for create_network : {'network': {'id': '556f23c4-99c0-4f49-bd50-95ace22d7d25', 'name': 'test_vnv6sr', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:58:08,425 - DEBUG - Response for create_subnet : {'subnet': {'id': '42989d09-333d-47fc-842d-403686ddb9aa', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '556f23c4-99c0-4f49-bd50-95ace22d7d25', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:08,643 - DEBUG - Response for create_subnet : {'subnet': {'id': '23303fdc-b27f-477b-802b-cea8cfc404a9', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '556f23c4-99c0-4f49-bd50-95ace22d7d25', 'ip_version': 4, 'cidr': '153.85.115.0/26', 'allocation_pools': [{'start': '153.85.115.2', 'end': '153.85.115.62'}], 'gateway_ip': '153.85.115.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '153.85.115.2', 'tags': [], 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:08,667 - INFO - Created VN test_vnv6sr 2025-10-20 03:58:08,679 - DEBUG - VN test_vnv6sr UUID is 556f23c4-99c0-4f49-bd50-95ace22d7d25 2025-10-20 03:58:09,052 - DEBUG - Response for create_network : {'network': {'id': 'b15a2977-ecb6-4114-97aa-5a50e11a9933', 'name': 'test_vnv6dn', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:58:09,270 - DEBUG - Response for create_subnet : {'subnet': {'id': '10c3a7c9-54c9-4ddd-b143-61395d9bade8', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': 'b15a2977-ecb6-4114-97aa-5a50e11a9933', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:09,500 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd9c7411d-3e90-40e0-9b35-522ff8d8edd9', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': 'b15a2977-ecb6-4114-97aa-5a50e11a9933', 'ip_version': 4, 'cidr': '104.111.87.128/26', 'allocation_pools': [{'start': '104.111.87.130', 'end': '104.111.87.190'}], 'gateway_ip': '104.111.87.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '104.111.87.130', 'tags': [], 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:09,527 - INFO - Created VN test_vnv6dn 2025-10-20 03:58:09,539 - DEBUG - VN test_vnv6dn UUID is b15a2977-ecb6-4114-97aa-5a50e11a9933 2025-10-20 03:58:09,685 - DEBUG - Services list from nova: [, , , ] 2025-10-20 03:58:10,710 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2) 2025-10-20 03:58:12,020 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1) 2025-10-20 03:58:12,021 - INFO - Waiting for VM source_vm to be up.. 2025-10-20 03:58:12,125 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-20 03:58:17,210 - DEBUG - VM is in ACTIVE state now 2025-10-20 03:58:17,210 - INFO - VM name : source_vm 2025-10-20 03:58:17,291 - DEBUG - VM source_vm ID is 3838e241-f82a-46a0-830c-3840e07a716f 2025-10-20 03:58:17,313 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4370-2 2025-10-20 03:58:17,392 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/3838e241-f82a-46a0-830c-3840e07a716f 2025-10-20 03:58:17,682 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/3838e241-f82a-46a0-830c-3840e07a716f 2025-10-20 03:58:17,716 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/aba63599-685b-41eb-a465-4904173fdf3a 2025-10-20 03:58:20,898 - 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 1019ms') 2025-10-20 03:58:20,898 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-20 03:58:20,911 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-09617125:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-20 03:58:20,911 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-09617125:test_vnv6sr is 153.85.115.1 and allocation pool is NOT set 2025-10-20 03:58:22,968 - 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=3.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.383 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.383/1.943/3.503/1.560 ms') 2025-10-20 03:58:22,969 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-20 03:58:23,037 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-20 03:58:23,037 - DEBUG - Waiting to SSH to VM source_vm, IP 153.85.115.3, Port 22 2025-10-20 03:58:23,199 - DEBUG - VM source_vm is ready for SSH connections 2025-10-20 03:58:23,200 - INFO - Waiting for VM dest_vm to be up.. 2025-10-20 03:58:23,316 - DEBUG - VM is in ACTIVE state now 2025-10-20 03:58:23,316 - INFO - VM name : dest_vm 2025-10-20 03:58:23,419 - DEBUG - VM dest_vm ID is 93938c33-bee2-4046-bcf4-4a7d12fff835 2025-10-20 03:58:23,419 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4370-1 2025-10-20 03:58:23,506 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/93938c33-bee2-4046-bcf4-4a7d12fff835 2025-10-20 03:58:23,515 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b073e904-41ec-4cfe-9b90-f6457837a9b5 2025-10-20 03:58:24,673 - 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=3.39 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.482 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.482/1.938/3.394/1.456 ms') 2025-10-20 03:58:24,673 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-20 03:58:24,740 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-20 03:58:24,740 - DEBUG - Waiting to SSH to VM dest_vm, IP 104.111.87.131, Port 22 2025-10-20 03:58:24,905 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-20 03:58:24,911 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-09617125: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-09617125: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-10-20 03:58:25,235 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-20 03:58:25,600 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-20 03:58:25,891 - INFO - Configure the policy with allow any 2025-10-20 03:58:26,235 - DEBUG - Response for create_network : {'network': {'id': '57df0803-4d4c-4eb6-a322-6c8244380540', 'name': 'vn1', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:58:26,512 - DEBUG - Response for create_subnet : {'subnet': {'id': '99a6cf7c-3fbe-4806-9c09-9dde9d96e9a7', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '57df0803-4d4c-4eb6-a322-6c8244380540', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:26,539 - INFO - Created VN vn1 2025-10-20 03:58:26,550 - DEBUG - VN vn1 UUID is 57df0803-4d4c-4eb6-a322-6c8244380540 2025-10-20 03:58:26,915 - DEBUG - Response for create_network : {'network': {'id': '2aba7c80-931a-497d-908c-ae77133c5f30', 'name': 'vn2', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:58:27,137 - DEBUG - Response for create_subnet : {'subnet': {'id': '77d655a0-0abd-4757-8123-70dad5c36f32', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '2aba7c80-931a-497d-908c-ae77133c5f30', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:27,159 - INFO - Created VN vn2 2025-10-20 03:58:27,171 - DEBUG - VN vn2 UUID is 2aba7c80-931a-497d-908c-ae77133c5f30 2025-10-20 03:58:27,281 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-09617125: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-09617125: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-10-20 03:58:27,619 - INFO - Attaching policy sec_grp_policy_ctest-random-29466546 to vn vn1 2025-10-20 03:58:27,959 - INFO - Attaching policy sec_grp_policy_ctest-random-29466546 to vn vn2 2025-10-20 03:58:29,375 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2) 2025-10-20 03:58:30,666 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1) 2025-10-20 03:58:30,731 - INFO - ================================================================================ 2025-10-20 03:58:30,731 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-10-20 03:58:30,731 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-10-20 03:58:32,013 - DEBUG - Skipping xmpp flap check 2025-10-20 03:58:32,013 - INFO - Initial checks done. Running the testcase now 2025-10-20 03:58:32,014 - INFO - 2025-10-20 03:58:32,014 - INFO - config per peer with auth_data None 2025-10-20 03:58:32,537 - INFO - configure MD5 on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:58:32,617 - INFO - config per peer with auth_data None 2025-10-20 03:58:33,202 - INFO - configure MD5 on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:58:33,289 - INFO - config per peer with auth_data None 2025-10-20 03:58:33,873 - INFO - configure MD5 on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:58:33,949 - INFO - check BGP peering is up before setting md5 config 2025-10-20 03:58:33,949 - INFO - Check BGP staus between peers 2025-10-20 03:58:33,960 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-10-20 03:58:34,549 - INFO - Check BGP staus between peers 2025-10-20 03:58:34,561 - DEBUG - Check the BGP connection on 10.0.0.22 2025-10-20 03:58:34,561 - DEBUG - Check the BGP connection on 10.0.0.22 2025-10-20 03:58:44,561 - INFO - Check BGP staus between peers 2025-10-20 03:58:44,571 - INFO - remove md5 configured on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:58:44,571 - INFO - config per peer with auth_data None 2025-10-20 03:58:45,077 - INFO - configure MD5 on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:58:45,157 - INFO - remove md5 configured on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:58:45,157 - INFO - config per peer with auth_data None 2025-10-20 03:58:45,721 - INFO - configure MD5 on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:58:45,801 - INFO - remove md5 configured on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:58:45,801 - INFO - config per peer with auth_data None 2025-10-20 03:58:46,441 - INFO - configure MD5 on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:58:46,525 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-20 03:58:46,525 - INFO - Deleting VM vn2_vm_0 2025-10-20 03:58:46,525 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-20 03:58:46,525 - ERROR - Nova failed to get host of the VM 2025-10-20 03:58:46,525 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-20 03:58:46,525 - ERROR - Nova failed to get host of the VM 2025-10-20 03:58:46,591 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-20 03:58:46,591 - INFO - Deleting VM vn1_vm_0 2025-10-20 03:58:46,591 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-20 03:58:46,591 - ERROR - Nova failed to get host of the VM 2025-10-20 03:58:46,592 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-20 03:58:46,592 - ERROR - Nova failed to get host of the VM 2025-10-20 03:58:46,654 - INFO - Dettaching policy sec_grp_policy_ctest-random-29466546 from vn vn2 2025-10-20 03:58:46,811 - INFO - Dettaching policy sec_grp_policy_ctest-random-29466546 from vn vn1 2025-10-20 03:58:47,077 - INFO - Deleted policy sec_grp_policy_ctest-random-29466546 2025-10-20 03:58:47,077 - INFO - Deleting VN vn2 2025-10-20 03:58:47,133 - DEBUG - VN 2aba7c80-931a-497d-908c-ae77133c5f30 still in use: Unable to complete operation on network 2aba7c80-931a-497d-908c-ae77133c5f30. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c8c91f0f-5dc1-468b-8c94-7b38bae7a767'] 2025-10-20 03:58:47,133 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-20 03:58:49,265 - DEBUG - Response for deleting network () 2025-10-20 03:58:49,265 - INFO - Deleting VN vn1 2025-10-20 03:58:49,427 - DEBUG - Response for deleting network () 2025-10-20 03:58:49,428 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-20 03:58:49,589 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-20 03:58:49,812 - INFO - Deleted policy allow_all 2025-10-20 03:58:49,813 - INFO - Deleting VM dest_vm 2025-10-20 03:58:49,881 - INFO - Deleting VM source_vm 2025-10-20 03:58:49,950 - INFO - Deleting VN test_vnv6dn 2025-10-20 03:58:50,003 - DEBUG - VN b15a2977-ecb6-4114-97aa-5a50e11a9933 still in use: Unable to complete operation on network b15a2977-ecb6-4114-97aa-5a50e11a9933. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c2d93731-df9d-4c0c-8249-65853f62ec24'] 2025-10-20 03:58:50,003 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-20 03:58:52,201 - DEBUG - Response for deleting network () 2025-10-20 03:58:52,202 - INFO - Deleting VN test_vnv6sr 2025-10-20 03:58:52,392 - DEBUG - Response for deleting network () 2025-10-20 03:58:53,661 - DEBUG - Skipping xmpp flap check
2025-10-20 03:58:53,661 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-10-20 03:58:53,661 - INFO - -------------------------------------------------------------------------------- 2025-10-20 03:58:53,922 - DEBUG - Response for create_network : {'network': {'id': 'e3f3802f-22d7-4f61-b4c5-f766a3ae177f', 'name': 'test_vnv6sr', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:58:54,182 - DEBUG - Response for create_subnet : {'subnet': {'id': '6d3cb041-f141-4b4f-94bc-694c69693452', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': 'e3f3802f-22d7-4f61-b4c5-f766a3ae177f', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:54,442 - DEBUG - Response for create_subnet : {'subnet': {'id': '0af3d65f-6ff7-4251-8a1e-82c7dbbeca4b', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': 'e3f3802f-22d7-4f61-b4c5-f766a3ae177f', 'ip_version': 4, 'cidr': '181.130.95.64/26', 'allocation_pools': [{'start': '181.130.95.66', 'end': '181.130.95.126'}], 'gateway_ip': '181.130.95.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '181.130.95.66', 'tags': [], 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:54,468 - INFO - Created VN test_vnv6sr 2025-10-20 03:58:54,479 - DEBUG - VN test_vnv6sr UUID is e3f3802f-22d7-4f61-b4c5-f766a3ae177f 2025-10-20 03:58:54,839 - DEBUG - Response for create_network : {'network': {'id': '14a57af7-536a-4702-9102-38aabd3d825e', 'name': 'test_vnv6dn', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:58:55,077 - DEBUG - Response for create_subnet : {'subnet': {'id': '8dac2fd3-69c1-4ebd-a15a-0231e71299be', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '14a57af7-536a-4702-9102-38aabd3d825e', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:55,332 - DEBUG - Response for create_subnet : {'subnet': {'id': '4506023c-8f9b-4979-be10-64ddc14795af', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '14a57af7-536a-4702-9102-38aabd3d825e', 'ip_version': 4, 'cidr': '137.50.125.64/26', 'allocation_pools': [{'start': '137.50.125.66', 'end': '137.50.125.126'}], 'gateway_ip': '137.50.125.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '137.50.125.66', 'tags': [], 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:58:55,358 - INFO - Created VN test_vnv6dn 2025-10-20 03:58:55,368 - DEBUG - VN test_vnv6dn UUID is 14a57af7-536a-4702-9102-38aabd3d825e 2025-10-20 03:58:56,464 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2) 2025-10-20 03:58:57,712 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1) 2025-10-20 03:58:57,712 - INFO - Waiting for VM source_vm to be up.. 2025-10-20 03:58:57,805 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-20 03:59:02,891 - DEBUG - VM is in ACTIVE state now 2025-10-20 03:59:02,891 - INFO - VM name : source_vm 2025-10-20 03:59:02,965 - DEBUG - VM source_vm ID is ba7d6d28-a39b-4cc8-b961-5d26e5978f92 2025-10-20 03:59:02,965 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4370-2 2025-10-20 03:59:03,039 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ba7d6d28-a39b-4cc8-b961-5d26e5978f92 2025-10-20 03:59:03,048 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/cc257c8a-7f27-4b5a-8558-7b818b6777e2 2025-10-20 03:59:06,213 - 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-10-20 03:59:06,213 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-20 03:59:06,228 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-09617125:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-20 03:59:06,228 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-09617125:test_vnv6sr is 181.130.95.65 and allocation pool is NOT set 2025-10-20 03:59:08,291 - 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.04 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.38 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.382/2.711/4.041/1.329 ms') 2025-10-20 03:59:08,291 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-20 03:59:08,356 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-20 03:59:08,357 - DEBUG - Waiting to SSH to VM source_vm, IP 181.130.95.67, Port 22 2025-10-20 03:59:08,519 - DEBUG - VM source_vm is ready for SSH connections 2025-10-20 03:59:08,519 - INFO - Waiting for VM dest_vm to be up.. 2025-10-20 03:59:08,602 - DEBUG - VM is in ACTIVE state now 2025-10-20 03:59:08,602 - INFO - VM name : dest_vm 2025-10-20 03:59:08,698 - DEBUG - VM dest_vm ID is 8a4558d5-9576-4246-9faf-7a3f829ffa9a 2025-10-20 03:59:08,698 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4370-1 2025-10-20 03:59:08,771 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/8a4558d5-9576-4246-9faf-7a3f829ffa9a 2025-10-20 03:59:08,783 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/83ba1489-534b-47c9-bad3-991f64869694 2025-10-20 03:59:09,932 - 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=3.73 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.544 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.544/2.136/3.729/1.592 ms') 2025-10-20 03:59:09,932 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-20 03:59:10,002 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-20 03:59:10,002 - DEBUG - Waiting to SSH to VM dest_vm, IP 137.50.125.67, Port 22 2025-10-20 03:59:10,164 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-20 03:59:10,169 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-09617125: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-09617125: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-10-20 03:59:10,503 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-20 03:59:10,860 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-20 03:59:11,119 - INFO - Configure the policy with allow any 2025-10-20 03:59:11,428 - DEBUG - Response for create_network : {'network': {'id': '6002b53b-2930-4be1-b9fb-597094cf0b37', 'name': 'vn1', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:59:11,643 - DEBUG - Response for create_subnet : {'subnet': {'id': '91e6b2ad-1753-454b-b34d-23a57f2eb33a', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '6002b53b-2930-4be1-b9fb-597094cf0b37', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:59:11,663 - INFO - Created VN vn1 2025-10-20 03:59:11,678 - DEBUG - VN vn1 UUID is 6002b53b-2930-4be1-b9fb-597094cf0b37 2025-10-20 03:59:12,020 - DEBUG - Response for create_network : {'network': {'id': '24a51c1e-df14-4ce8-be6e-4dedd2fccdf2', 'name': 'vn2', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'project_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-09617125', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-20 03:59:12,228 - DEBUG - Response for create_subnet : {'subnet': {'id': '34f5b6f6-7ec9-4578-8900-59845ae49282', 'name': '', 'tenant_id': 'd76b6e7a805f424891cf5a1e80c2d980', 'network_id': '24a51c1e-df14-4ce8-be6e-4dedd2fccdf2', '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': 'd76b6e7a805f424891cf5a1e80c2d980'}} 2025-10-20 03:59:12,249 - INFO - Created VN vn2 2025-10-20 03:59:12,258 - DEBUG - VN vn2 UUID is 24a51c1e-df14-4ce8-be6e-4dedd2fccdf2 2025-10-20 03:59:12,369 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-09617125: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-09617125: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-10-20 03:59:12,707 - INFO - Attaching policy sec_grp_policy_ctest-random-82570266 to vn vn1 2025-10-20 03:59:13,047 - INFO - Attaching policy sec_grp_policy_ctest-random-82570266 to vn vn2 2025-10-20 03:59:14,472 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2) 2025-10-20 03:59:15,718 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1) 2025-10-20 03:59:15,781 - INFO - ================================================================================ 2025-10-20 03:59:15,781 - INFO - STARTING TEST : test_create_md5_on_control 2025-10-20 03:59:15,781 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-10-20 03:59:17,069 - DEBUG - Skipping xmpp flap check 2025-10-20 03:59:17,069 - INFO - Initial checks done. Running the testcase now 2025-10-20 03:59:17,069 - INFO - 2025-10-20 03:59:17,069 - INFO - config md5 on each host with auth_data None 2025-10-20 03:59:17,069 - INFO - config per peer with auth_data None 2025-10-20 03:59:17,577 - INFO - configure MD5 on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:59:17,673 - INFO - config per peer with auth_data None 2025-10-20 03:59:18,245 - INFO - configure MD5 on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:59:18,329 - INFO - config per peer with auth_data None 2025-10-20 03:59:18,909 - INFO - configure MD5 on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:59:18,993 - INFO - check if BGP between peers are up before setting md5 2025-10-20 03:59:18,993 - INFO - Check BGP staus between peers 2025-10-20 03:59:19,003 - INFO - setting MD5 config on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:59:19,003 - INFO - configure MD5 on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:59:19,077 - INFO - setting MD5 config on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:59:19,077 - INFO - configure MD5 on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:59:19,161 - INFO - setting MD5 config on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:59:19,161 - INFO - configure MD5 on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:59:19,237 - INFO - check if BGP peering is up after md5 config 2025-10-20 03:59:19,237 - INFO - Check BGP staus between peers 2025-10-20 03:59:19,248 - DEBUG - Check the BGP connection on 10.0.0.22 2025-10-20 03:59:19,248 - DEBUG - Check the BGP connection on 10.0.0.22 2025-10-20 03:59:29,249 - INFO - Check BGP staus between peers 2025-10-20 03:59:29,261 - INFO - remove md5 configured on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:59:29,261 - INFO - config per peer with auth_data None 2025-10-20 03:59:29,757 - INFO - configure MD5 on host 166a3acf-fb8b-4ca4-b10e-aa35ab893a0f 2025-10-20 03:59:29,849 - INFO - remove md5 configured on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:59:29,849 - INFO - config per peer with auth_data None 2025-10-20 03:59:30,429 - INFO - configure MD5 on host a9a08e70-1139-4998-b7bf-e3d6b61f0e32 2025-10-20 03:59:30,517 - INFO - remove md5 configured on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:59:30,517 - INFO - config per peer with auth_data None 2025-10-20 03:59:31,073 - INFO - configure MD5 on host a600627d-f664-42a4-8120-9c1af5e1f8be 2025-10-20 03:59:31,157 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-20 03:59:31,157 - INFO - Deleting VM vn2_vm_0 2025-10-20 03:59:31,157 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-20 03:59:31,157 - ERROR - Nova failed to get host of the VM 2025-10-20 03:59:31,157 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-20 03:59:31,157 - ERROR - Nova failed to get host of the VM 2025-10-20 03:59:31,228 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-20 03:59:31,228 - INFO - Deleting VM vn1_vm_0 2025-10-20 03:59:31,228 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-20 03:59:31,228 - ERROR - Nova failed to get host of the VM 2025-10-20 03:59:31,228 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-20 03:59:31,228 - ERROR - Nova failed to get host of the VM 2025-10-20 03:59:31,320 - INFO - Dettaching policy sec_grp_policy_ctest-random-82570266 from vn vn2 2025-10-20 03:59:31,471 - INFO - Dettaching policy sec_grp_policy_ctest-random-82570266 from vn vn1 2025-10-20 03:59:31,686 - INFO - Deleted policy sec_grp_policy_ctest-random-82570266 2025-10-20 03:59:31,686 - INFO - Deleting VN vn2 2025-10-20 03:59:31,741 - DEBUG - VN 24a51c1e-df14-4ce8-be6e-4dedd2fccdf2 still in use: Unable to complete operation on network 24a51c1e-df14-4ce8-be6e-4dedd2fccdf2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9ce8634e-06ca-406b-8758-5839a9320fdd'] 2025-10-20 03:59:31,741 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-20 03:59:33,904 - DEBUG - Response for deleting network () 2025-10-20 03:59:33,904 - INFO - Deleting VN vn1 2025-10-20 03:59:34,074 - DEBUG - Response for deleting network () 2025-10-20 03:59:34,074 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-20 03:59:34,239 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-20 03:59:34,499 - INFO - Deleted policy allow_all 2025-10-20 03:59:34,499 - INFO - Deleting VM dest_vm 2025-10-20 03:59:34,563 - INFO - Deleting VM source_vm 2025-10-20 03:59:34,632 - INFO - Deleting VN test_vnv6dn 2025-10-20 03:59:34,676 - DEBUG - VN 14a57af7-536a-4702-9102-38aabd3d825e still in use: Unable to complete operation on network 14a57af7-536a-4702-9102-38aabd3d825e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5e71a997-4391-465b-b5f5-542593771f47'] 2025-10-20 03:59:34,676 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-20 03:59:36,843 - DEBUG - Response for deleting network () 2025-10-20 03:59:36,843 - INFO - Deleting VN test_vnv6sr 2025-10-20 03:59:37,029 - DEBUG - Response for deleting network () 2025-10-20 03:59:38,281 - DEBUG - Skipping xmpp flap check
2025-10-20 03:59:38,281 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-10-20 03:59:38,281 - INFO - -------------------------------------------------------------------------------- 2025-10-20 03:59:38,988 - INFO - Deleted project: ctest-TestMd5testsOnControl-09617125, ID : d76b6e7a-805f-4248-91cf-5a1e80c2d980