2025-07-23 17:53:20,100 - INFO - Domain Default found not creating 2025-07-23 17:53:20,284 - INFO - Project ctest-TestMd5testsOnControl-28263721 not found, creating it 2025-07-23 17:53:20,879 - INFO - Created Project:ctest-TestMd5testsOnControl-28263721, ID : b8962a73-d6c5-4cb4-a0df-a1d9bafac7c1 2025-07-23 17:53:22,978 - DEBUG - Response for create_network : {'network': {'id': '175afba6-f651-4ce6-b290-658c0b9c12b6', 'name': 'test_vnv6sr', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:53:23,261 - DEBUG - Response for create_subnet : {'subnet': {'id': '7eb5e4d7-2c81-4c59-b85a-081eed4bd4d1', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '175afba6-f651-4ce6-b290-658c0b9c12b6', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:53:23,560 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c5e72891-4dbc-4c16-b320-88b3c1e7fdab', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '175afba6-f651-4ce6-b290-658c0b9c12b6', 'ip_version': 4, 'cidr': '162.214.37.128/26', 'allocation_pools': [{'start': '162.214.37.130', 'end': '162.214.37.190'}], 'gateway_ip': '162.214.37.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.214.37.130', 'tags': [], 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:53:23,583 - INFO - Created VN test_vnv6sr 2025-07-23 17:53:23,603 - DEBUG - VN test_vnv6sr UUID is 175afba6-f651-4ce6-b290-658c0b9c12b6 2025-07-23 17:53:24,022 - DEBUG - Response for create_network : {'network': {'id': '7f9ba509-9e10-47b7-9f00-5deb5e7f05b9', 'name': 'test_vnv6dn', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:53:24,244 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd91c180a-e568-428b-b8b2-3fbc9b9b273a', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '7f9ba509-9e10-47b7-9f00-5deb5e7f05b9', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:53:24,465 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c940b510-d07b-42b5-8172-a62098bf4c2e', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '7f9ba509-9e10-47b7-9f00-5deb5e7f05b9', 'ip_version': 4, 'cidr': '153.67.115.64/26', 'allocation_pools': [{'start': '153.67.115.66', 'end': '153.67.115.126'}], 'gateway_ip': '153.67.115.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '153.67.115.66', 'tags': [], 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:53:24,493 - INFO - Created VN test_vnv6dn 2025-07-23 17:53:24,505 - DEBUG - VN test_vnv6dn UUID is 7f9ba509-9e10-47b7-9f00-5deb5e7f05b9 2025-07-23 17:53:24,721 - DEBUG - Services list from nova: [, , , ] 2025-07-23 17:53:25,881 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-2) 2025-07-23 17:53:27,368 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-1) 2025-07-23 17:53:27,368 - INFO - Waiting for VM source_vm to be up.. 2025-07-23 17:53:27,447 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-23 17:53:32,532 - DEBUG - VM is in ACTIVE state now 2025-07-23 17:53:32,532 - INFO - VM name : source_vm 2025-07-23 17:53:32,613 - DEBUG - VM source_vm ID is 2a8216e3-79ef-4987-9fed-f9ffa5f18af9 2025-07-23 17:53:32,638 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3704-2 2025-07-23 17:53:32,724 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/2a8216e3-79ef-4987-9fed-f9ffa5f18af9 2025-07-23 17:53:33,016 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/2a8216e3-79ef-4987-9fed-f9ffa5f18af9 2025-07-23 17:53:33,054 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/7f5f7368-df43-40bc-a2ba-ee8b48e20316 2025-07-23 17:53:36,228 - 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 1015ms') 2025-07-23 17:53:36,229 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-07-23 17:53:36,244 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28263721:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-07-23 17:53:36,244 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28263721:test_vnv6sr is 162.214.37.129 and allocation pool is NOT set 2025-07-23 17:53:38,306 - 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.10 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.426 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.426/1.765/3.104/1.339 ms') 2025-07-23 17:53:38,307 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-07-23 17:53:38,377 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:53:38,377 - DEBUG - Waiting to SSH to VM source_vm, IP 162.214.37.131, Port 22 2025-07-23 17:53:38,534 - DEBUG - VM source_vm is ready for SSH connections 2025-07-23 17:53:38,534 - INFO - Waiting for VM dest_vm to be up.. 2025-07-23 17:53:38,618 - DEBUG - VM is in ACTIVE state now 2025-07-23 17:53:38,618 - INFO - VM name : dest_vm 2025-07-23 17:53:38,700 - DEBUG - VM dest_vm ID is a9782939-96e9-441c-a3c6-8824ae1e91fb 2025-07-23 17:53:38,700 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3704-1 2025-07-23 17:53:38,776 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/a9782939-96e9-441c-a3c6-8824ae1e91fb 2025-07-23 17:53:38,787 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/791e4ee4-2d81-4d68-a958-94d09015245a 2025-07-23 17:53:39,982 - 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=6.19 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.58 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.575/4.383/6.192/1.808 ms') 2025-07-23 17:53:39,982 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-07-23 17:53:40,058 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:53:40,058 - DEBUG - Waiting to SSH to VM dest_vm, IP 153.67.115.67, Port 22 2025-07-23 17:53:40,124 - 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-07-23 17:53:40,210 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 17:53:45,211 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:53:45,211 - DEBUG - Waiting to SSH to VM dest_vm, IP 153.67.115.67, Port 22 2025-07-23 17:53:45,415 - DEBUG - VM dest_vm is ready for SSH connections 2025-07-23 17:53:45,422 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28263721: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-28263721: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-07-23 17:53:45,759 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-07-23 17:53:46,131 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-07-23 17:53:46,399 - INFO - Configure the policy with allow any 2025-07-23 17:53:46,727 - DEBUG - Response for create_network : {'network': {'id': '57abaaca-de0f-4c1d-bf6b-f4c577fb5835', 'name': 'vn1', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:53:46,967 - DEBUG - Response for create_subnet : {'subnet': {'id': '427e3c3e-7d64-45a5-9a7c-3480b4f5a366', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '57abaaca-de0f-4c1d-bf6b-f4c577fb5835', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:53:46,989 - INFO - Created VN vn1 2025-07-23 17:53:47,002 - DEBUG - VN vn1 UUID is 57abaaca-de0f-4c1d-bf6b-f4c577fb5835 2025-07-23 17:53:47,430 - DEBUG - Response for create_network : {'network': {'id': 'a7283b07-8b19-4103-893f-fd28dbe5ed89', 'name': 'vn2', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:53:47,668 - DEBUG - Response for create_subnet : {'subnet': {'id': '5b7d4c70-9d80-4468-966e-4e70dfe7040d', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': 'a7283b07-8b19-4103-893f-fd28dbe5ed89', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:53:47,694 - INFO - Created VN vn2 2025-07-23 17:53:47,708 - DEBUG - VN vn2 UUID is a7283b07-8b19-4103-893f-fd28dbe5ed89 2025-07-23 17:53:47,789 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28263721: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-28263721: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-07-23 17:53:48,127 - INFO - Attaching policy sec_grp_policy_ctest-random-64489503 to vn vn1 2025-07-23 17:53:48,503 - INFO - Attaching policy sec_grp_policy_ctest-random-64489503 to vn vn2 2025-07-23 17:53:50,091 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-2) 2025-07-23 17:53:51,645 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-1) 2025-07-23 17:53:51,712 - INFO - ================================================================================ 2025-07-23 17:53:51,713 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-07-23 17:53:51,713 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-07-23 17:53:53,059 - DEBUG - Skipping xmpp flap check 2025-07-23 17:53:53,059 - INFO - Initial checks done. Running the testcase now 2025-07-23 17:53:53,059 - INFO - 2025-07-23 17:53:53,059 - INFO - config per peer with auth_data None 2025-07-23 17:53:53,631 - INFO - configure MD5 on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:53:53,719 - INFO - config per peer with auth_data None 2025-07-23 17:53:54,348 - INFO - configure MD5 on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:53:54,431 - INFO - config per peer with auth_data None 2025-07-23 17:53:55,047 - INFO - configure MD5 on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:53:55,131 - INFO - check BGP peering is up before setting md5 config 2025-07-23 17:53:55,132 - INFO - Check BGP staus between peers 2025-07-23 17:53:55,147 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-07-23 17:53:55,739 - INFO - Check BGP staus between peers 2025-07-23 17:53:55,762 - DEBUG - Check the BGP connection on 10.0.0.23 2025-07-23 17:53:55,762 - DEBUG - Check the BGP connection on 10.0.0.23 2025-07-23 17:54:05,763 - INFO - Check BGP staus between peers 2025-07-23 17:54:05,776 - INFO - remove md5 configured on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:05,776 - INFO - config per peer with auth_data None 2025-07-23 17:54:06,351 - INFO - configure MD5 on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:06,439 - INFO - remove md5 configured on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:06,439 - INFO - config per peer with auth_data None 2025-07-23 17:54:07,111 - INFO - configure MD5 on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:07,195 - INFO - remove md5 configured on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:07,196 - INFO - config per peer with auth_data None 2025-07-23 17:54:07,851 - INFO - configure MD5 on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:07,947 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-07-23 17:54:07,947 - INFO - Deleting VM vn2_vm_0 2025-07-23 17:54:07,948 - ERROR - VM vn2_vm_0 has failed to come up 2025-07-23 17:54:07,948 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:07,948 - ERROR - VM vn2_vm_0 has failed to come up 2025-07-23 17:54:07,948 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:08,009 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-07-23 17:54:08,009 - INFO - Deleting VM vn1_vm_0 2025-07-23 17:54:08,009 - ERROR - VM vn1_vm_0 has failed to come up 2025-07-23 17:54:08,009 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:08,009 - ERROR - VM vn1_vm_0 has failed to come up 2025-07-23 17:54:08,009 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:08,075 - INFO - Dettaching policy sec_grp_policy_ctest-random-64489503 from vn vn2 2025-07-23 17:54:08,241 - INFO - Dettaching policy sec_grp_policy_ctest-random-64489503 from vn vn1 2025-07-23 17:54:08,548 - INFO - Deleted policy sec_grp_policy_ctest-random-64489503 2025-07-23 17:54:08,548 - INFO - Deleting VN vn2 2025-07-23 17:54:08,607 - DEBUG - VN a7283b07-8b19-4103-893f-fd28dbe5ed89 still in use: Unable to complete operation on network a7283b07-8b19-4103-893f-fd28dbe5ed89. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f8b33567-ac9c-4c8d-a8d8-e4a2526381e8'] 2025-07-23 17:54:08,607 - WARNING - Deleting VN vn2 failed..Will retry 2025-07-23 17:54:10,779 - DEBUG - Response for deleting network () 2025-07-23 17:54:10,779 - INFO - Deleting VN vn1 2025-07-23 17:54:11,018 - DEBUG - Response for deleting network () 2025-07-23 17:54:11,018 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-07-23 17:54:11,248 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-07-23 17:54:11,524 - INFO - Deleted policy allow_all 2025-07-23 17:54:11,524 - INFO - Deleting VM dest_vm 2025-07-23 17:54:11,593 - INFO - Deleting VM source_vm 2025-07-23 17:54:11,655 - INFO - Deleting VN test_vnv6dn 2025-07-23 17:54:11,731 - DEBUG - VN 7f9ba509-9e10-47b7-9f00-5deb5e7f05b9 still in use: Unable to complete operation on network 7f9ba509-9e10-47b7-9f00-5deb5e7f05b9. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-dfcb0f5f-0be3-490d-9e0c-578cec183648'] 2025-07-23 17:54:11,731 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-07-23 17:54:13,976 - DEBUG - Response for deleting network () 2025-07-23 17:54:13,976 - INFO - Deleting VN test_vnv6sr 2025-07-23 17:54:14,190 - DEBUG - Response for deleting network () 2025-07-23 17:54:15,517 - DEBUG - Skipping xmpp flap check
2025-07-23 17:54:15,517 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-07-23 17:54:15,517 - INFO - -------------------------------------------------------------------------------- 2025-07-23 17:54:15,788 - DEBUG - Response for create_network : {'network': {'id': '9574dc2b-c919-4a65-a327-9064de1a2824', 'name': 'test_vnv6sr', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:54:16,054 - DEBUG - Response for create_subnet : {'subnet': {'id': '1c95475e-085b-43c2-bc12-5ff020335602', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '9574dc2b-c919-4a65-a327-9064de1a2824', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:54:16,262 - DEBUG - Response for create_subnet : {'subnet': {'id': '87f39318-026c-4d5d-b849-ddfd356af160', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '9574dc2b-c919-4a65-a327-9064de1a2824', 'ip_version': 4, 'cidr': '184.52.123.128/26', 'allocation_pools': [{'start': '184.52.123.130', 'end': '184.52.123.190'}], 'gateway_ip': '184.52.123.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '184.52.123.130', 'tags': [], 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:54:16,290 - INFO - Created VN test_vnv6sr 2025-07-23 17:54:16,304 - DEBUG - VN test_vnv6sr UUID is 9574dc2b-c919-4a65-a327-9064de1a2824 2025-07-23 17:54:16,707 - DEBUG - Response for create_network : {'network': {'id': '94fd6bf6-d48d-4f49-aca3-3eed48acddc6', 'name': 'test_vnv6dn', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:54:16,918 - DEBUG - Response for create_subnet : {'subnet': {'id': '0aaf0884-cc24-4100-b955-be47708e5c70', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '94fd6bf6-d48d-4f49-aca3-3eed48acddc6', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:54:17,147 - DEBUG - Response for create_subnet : {'subnet': {'id': '5607955d-9229-473e-ac90-72d0659165ac', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '94fd6bf6-d48d-4f49-aca3-3eed48acddc6', 'ip_version': 4, 'cidr': '193.145.209.64/26', 'allocation_pools': [{'start': '193.145.209.66', 'end': '193.145.209.126'}], 'gateway_ip': '193.145.209.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.145.209.66', 'tags': [], 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:54:17,171 - INFO - Created VN test_vnv6dn 2025-07-23 17:54:17,186 - DEBUG - VN test_vnv6dn UUID is 94fd6bf6-d48d-4f49-aca3-3eed48acddc6 2025-07-23 17:54:18,433 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-2) 2025-07-23 17:54:19,896 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-1) 2025-07-23 17:54:19,896 - INFO - Waiting for VM source_vm to be up.. 2025-07-23 17:54:19,992 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-23 17:54:25,071 - DEBUG - VM is in ACTIVE state now 2025-07-23 17:54:25,072 - INFO - VM name : source_vm 2025-07-23 17:54:25,147 - DEBUG - VM source_vm ID is 50a484b1-3e6a-4446-a215-77bf4b02c0d3 2025-07-23 17:54:25,147 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3704-2 2025-07-23 17:54:25,227 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/50a484b1-3e6a-4446-a215-77bf4b02c0d3 2025-07-23 17:54:25,238 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/e7329bdb-00f4-4f9c-a48e-5952ef2a6499 2025-07-23 17:54:28,422 - 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 1017ms') 2025-07-23 17:54:28,422 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-07-23 17:54:28,436 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28263721:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-07-23 17:54:28,436 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28263721:test_vnv6sr is 184.52.123.129 and allocation pool is NOT set 2025-07-23 17:54:30,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=3.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.506 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.506/1.996/3.486/1.490 ms') 2025-07-23 17:54:30,501 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-07-23 17:54:30,572 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:54:30,572 - DEBUG - Waiting to SSH to VM source_vm, IP 184.52.123.131, Port 22 2025-07-23 17:54:30,728 - DEBUG - VM source_vm is ready for SSH connections 2025-07-23 17:54:30,728 - INFO - Waiting for VM dest_vm to be up.. 2025-07-23 17:54:30,812 - DEBUG - VM is in ACTIVE state now 2025-07-23 17:54:30,812 - INFO - VM name : dest_vm 2025-07-23 17:54:30,898 - DEBUG - VM dest_vm ID is 8726771d-3618-4760-aac4-e9fec41be9bf 2025-07-23 17:54:30,898 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3704-1 2025-07-23 17:54:30,986 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/8726771d-3618-4760-aac4-e9fec41be9bf 2025-07-23 17:54:30,999 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/6080d947-f662-4f6d-9802-2295aad66b69 2025-07-23 17:54:32,239 - 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=15.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.980 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.980/8.150/15.321/7.170 ms') 2025-07-23 17:54:32,239 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-07-23 17:54:32,324 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:54:32,324 - DEBUG - Waiting to SSH to VM dest_vm, IP 193.145.209.67, Port 22 2025-07-23 17:54:32,529 - DEBUG - VM dest_vm is ready for SSH connections 2025-07-23 17:54:32,535 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28263721: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-28263721: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-07-23 17:54:32,889 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-07-23 17:54:33,260 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-07-23 17:54:33,533 - INFO - Configure the policy with allow any 2025-07-23 17:54:33,889 - DEBUG - Response for create_network : {'network': {'id': 'd5af6aab-036c-45d5-b303-283ce7c9d0c0', 'name': 'vn1', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:54:34,151 - DEBUG - Response for create_subnet : {'subnet': {'id': '7b2defc4-5009-484e-ae29-fa70115cf07c', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': 'd5af6aab-036c-45d5-b303-283ce7c9d0c0', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:54:34,174 - INFO - Created VN vn1 2025-07-23 17:54:34,189 - DEBUG - VN vn1 UUID is d5af6aab-036c-45d5-b303-283ce7c9d0c0 2025-07-23 17:54:34,595 - DEBUG - Response for create_network : {'network': {'id': '3284d280-ca03-494a-83b0-6f38c1f2d06f', 'name': 'vn2', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'project_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28263721', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:54:34,833 - DEBUG - Response for create_subnet : {'subnet': {'id': '31ac4cfd-5b9c-4642-a6d0-c0ac002b2287', 'name': '', 'tenant_id': 'b8962a73d6c54cb4a0dfa1d9bafac7c1', 'network_id': '3284d280-ca03-494a-83b0-6f38c1f2d06f', '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': 'b8962a73d6c54cb4a0dfa1d9bafac7c1'}} 2025-07-23 17:54:34,856 - INFO - Created VN vn2 2025-07-23 17:54:34,869 - DEBUG - VN vn2 UUID is 3284d280-ca03-494a-83b0-6f38c1f2d06f 2025-07-23 17:54:34,952 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28263721: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-28263721: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-07-23 17:54:35,300 - INFO - Attaching policy sec_grp_policy_ctest-random-29987840 to vn vn1 2025-07-23 17:54:35,686 - INFO - Attaching policy sec_grp_policy_ctest-random-29987840 to vn vn2 2025-07-23 17:54:37,331 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-2) 2025-07-23 17:54:38,818 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-1) 2025-07-23 17:54:38,881 - INFO - ================================================================================ 2025-07-23 17:54:38,881 - INFO - STARTING TEST : test_create_md5_on_control 2025-07-23 17:54:38,881 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-07-23 17:54:40,219 - DEBUG - Skipping xmpp flap check 2025-07-23 17:54:40,219 - INFO - Initial checks done. Running the testcase now 2025-07-23 17:54:40,219 - INFO - 2025-07-23 17:54:40,220 - INFO - config md5 on each host with auth_data None 2025-07-23 17:54:40,220 - INFO - config per peer with auth_data None 2025-07-23 17:54:40,817 - INFO - configure MD5 on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:40,927 - INFO - config per peer with auth_data None 2025-07-23 17:54:41,618 - INFO - configure MD5 on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:41,723 - INFO - config per peer with auth_data None 2025-07-23 17:54:42,411 - INFO - configure MD5 on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:42,515 - INFO - check if BGP between peers are up before setting md5 2025-07-23 17:54:42,516 - INFO - Check BGP staus between peers 2025-07-23 17:54:42,528 - INFO - setting MD5 config on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:42,528 - INFO - configure MD5 on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:42,611 - INFO - setting MD5 config on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:42,612 - INFO - configure MD5 on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:42,691 - INFO - setting MD5 config on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:42,691 - INFO - configure MD5 on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:42,783 - INFO - check if BGP peering is up after md5 config 2025-07-23 17:54:42,783 - INFO - Check BGP staus between peers 2025-07-23 17:54:42,795 - DEBUG - Check the BGP connection on 10.0.0.23 2025-07-23 17:54:42,795 - DEBUG - Check the BGP connection on 10.0.0.23 2025-07-23 17:54:52,795 - INFO - Check BGP staus between peers 2025-07-23 17:54:52,808 - INFO - remove md5 configured on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:52,808 - INFO - config per peer with auth_data None 2025-07-23 17:54:53,359 - INFO - configure MD5 on host d82e0a64-7944-417f-9174-935fd0852e1f 2025-07-23 17:54:53,447 - INFO - remove md5 configured on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:53,447 - INFO - config per peer with auth_data None 2025-07-23 17:54:54,047 - INFO - configure MD5 on host 3d2ea6cd-6267-49c8-9510-76011f7e5209 2025-07-23 17:54:54,131 - INFO - remove md5 configured on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:54,131 - INFO - config per peer with auth_data None 2025-07-23 17:54:54,715 - INFO - configure MD5 on host f9da54d2-33b1-4f00-b75c-44699945b4cb 2025-07-23 17:54:54,811 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-07-23 17:54:54,811 - INFO - Deleting VM vn2_vm_0 2025-07-23 17:54:54,812 - ERROR - VM vn2_vm_0 has failed to come up 2025-07-23 17:54:54,812 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:54,812 - ERROR - VM vn2_vm_0 has failed to come up 2025-07-23 17:54:54,812 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:54,881 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-07-23 17:54:54,881 - INFO - Deleting VM vn1_vm_0 2025-07-23 17:54:54,881 - ERROR - VM vn1_vm_0 has failed to come up 2025-07-23 17:54:54,881 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:54,882 - ERROR - VM vn1_vm_0 has failed to come up 2025-07-23 17:54:54,882 - ERROR - Nova failed to get host of the VM 2025-07-23 17:54:54,953 - INFO - Dettaching policy sec_grp_policy_ctest-random-29987840 from vn vn2 2025-07-23 17:54:55,130 - INFO - Dettaching policy sec_grp_policy_ctest-random-29987840 from vn vn1 2025-07-23 17:54:55,394 - INFO - Deleted policy sec_grp_policy_ctest-random-29987840 2025-07-23 17:54:55,394 - INFO - Deleting VN vn2 2025-07-23 17:54:55,450 - DEBUG - VN 3284d280-ca03-494a-83b0-6f38c1f2d06f still in use: Unable to complete operation on network 3284d280-ca03-494a-83b0-6f38c1f2d06f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-10d0e1e4-5204-4119-ab06-177c07adcdf4'] 2025-07-23 17:54:55,450 - WARNING - Deleting VN vn2 failed..Will retry 2025-07-23 17:54:57,615 - DEBUG - Response for deleting network () 2025-07-23 17:54:57,615 - INFO - Deleting VN vn1 2025-07-23 17:54:57,779 - DEBUG - Response for deleting network () 2025-07-23 17:54:57,779 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-07-23 17:54:57,971 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-07-23 17:54:58,208 - INFO - Deleted policy allow_all 2025-07-23 17:54:58,208 - INFO - Deleting VM dest_vm 2025-07-23 17:54:58,277 - INFO - Deleting VM source_vm 2025-07-23 17:54:58,349 - INFO - Deleting VN test_vnv6dn 2025-07-23 17:54:58,397 - DEBUG - VN 94fd6bf6-d48d-4f49-aca3-3eed48acddc6 still in use: Unable to complete operation on network 94fd6bf6-d48d-4f49-aca3-3eed48acddc6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-fa097254-4f69-4c03-97b8-24953de408c6'] 2025-07-23 17:54:58,397 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-07-23 17:55:00,601 - DEBUG - Response for deleting network () 2025-07-23 17:55:00,601 - INFO - Deleting VN test_vnv6sr 2025-07-23 17:55:00,789 - DEBUG - Response for deleting network () 2025-07-23 17:55:02,053 - DEBUG - Skipping xmpp flap check
2025-07-23 17:55:02,053 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-07-23 17:55:02,053 - INFO - -------------------------------------------------------------------------------- 2025-07-23 17:55:02,785 - INFO - Deleted project: ctest-TestMd5testsOnControl-28263721, ID : b8962a73-d6c5-4cb4-a0df-a1d9bafac7c1