2025-11-06 04:09:19,898 - INFO - Domain Default found not creating 2025-11-06 04:09:20,067 - INFO - Project ctest-TestMd5testsOnControl-64869538 not found, creating it 2025-11-06 04:09:20,667 - INFO - Created Project:ctest-TestMd5testsOnControl-64869538, ID : e0cf5943-b651-451e-9377-8db854410b07 2025-11-06 04:09:22,854 - DEBUG - Response for create_network : {'network': {'id': '03af111a-8f79-4f82-b4f5-bcdbb8066b3c', 'name': 'test_vnv6sr', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:09:23,159 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dd39600d-d85a-4f85-8107-91269f512c2a', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '03af111a-8f79-4f82-b4f5-bcdbb8066b3c', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:09:23,435 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a0e84ae1-5398-4580-9b61-d94b678c7806', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '03af111a-8f79-4f82-b4f5-bcdbb8066b3c', 'ip_version': 4, 'cidr': '189.214.132.192/26', 'allocation_pools': [{'start': '189.214.132.194', 'end': '189.214.132.254'}], 'gateway_ip': '189.214.132.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '189.214.132.194', 'tags': [], 'project_id': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:09:23,459 - INFO - Created VN test_vnv6sr 2025-11-06 04:09:23,478 - DEBUG - VN test_vnv6sr UUID is 03af111a-8f79-4f82-b4f5-bcdbb8066b3c 2025-11-06 04:09:23,944 - DEBUG - Response for create_network : {'network': {'id': '816c0f30-2901-4b86-a93c-7c49edba10f4', 'name': 'test_vnv6dn', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:09:24,266 - DEBUG - Response for create_subnet : {'subnet': {'id': '13fb9d7e-e67c-443f-b287-cf74f2bc5aa7', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '816c0f30-2901-4b86-a93c-7c49edba10f4', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:09:24,568 - DEBUG - Response for create_subnet : {'subnet': {'id': '25fcd980-cff5-4f9d-b4a4-9fc4d6ad5901', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '816c0f30-2901-4b86-a93c-7c49edba10f4', 'ip_version': 4, 'cidr': '151.85.103.0/26', 'allocation_pools': [{'start': '151.85.103.2', 'end': '151.85.103.62'}], 'gateway_ip': '151.85.103.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '151.85.103.2', 'tags': [], 'project_id': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:09:24,599 - INFO - Created VN test_vnv6dn 2025-11-06 04:09:24,615 - DEBUG - VN test_vnv6dn UUID is 816c0f30-2901-4b86-a93c-7c49edba10f4 2025-11-06 04:09:24,936 - DEBUG - Services list from nova: [, , , ] 2025-11-06 04:09:26,569 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-2) 2025-11-06 04:09:28,274 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-1) 2025-11-06 04:09:28,274 - INFO - Waiting for VM source_vm to be up.. 2025-11-06 04:09:28,371 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-06 04:09:33,457 - DEBUG - VM is in ACTIVE state now 2025-11-06 04:09:33,457 - INFO - VM name : source_vm 2025-11-06 04:09:33,553 - DEBUG - VM source_vm ID is 7bbac053-931c-4ae5-a05f-df3a37e31b24 2025-11-06 04:09:33,586 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4662-2 2025-11-06 04:09:33,702 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/7bbac053-931c-4ae5-a05f-df3a37e31b24 2025-11-06 04:09:34,011 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/7bbac053-931c-4ae5-a05f-df3a37e31b24 2025-11-06 04:09:34,350 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/0efeb039-d936-4969-9dbf-acdf62df57ef 2025-11-06 04:09:37,516 - 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 1001ms') 2025-11-06 04:09:37,516 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-11-06 04:09:37,536 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-64869538:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-11-06 04:09:37,536 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-64869538:test_vnv6sr is 189.214.132.193 and allocation pool is NOT set 2025-11-06 04:09:39,601 - 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.16 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.509 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.509/1.836/3.163/1.327 ms') 2025-11-06 04:09:39,602 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-11-06 04:09:39,672 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 04:09:39,672 - DEBUG - Waiting to SSH to VM source_vm, IP 189.214.132.195, Port 22 2025-11-06 04:09:39,738 - 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-06 04:09:39,832 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-11-06 04:09:44,833 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 04:09:44,833 - DEBUG - Waiting to SSH to VM source_vm, IP 189.214.132.195, Port 22 2025-11-06 04:09:45,005 - DEBUG - VM source_vm is ready for SSH connections 2025-11-06 04:09:45,005 - INFO - Waiting for VM dest_vm to be up.. 2025-11-06 04:09:45,107 - DEBUG - VM is in ACTIVE state now 2025-11-06 04:09:45,107 - INFO - VM name : dest_vm 2025-11-06 04:09:45,195 - DEBUG - VM dest_vm ID is daf5fd60-179f-4b33-ac28-df2c12987274 2025-11-06 04:09:45,195 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4662-1 2025-11-06 04:09:45,288 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/daf5fd60-179f-4b33-ac28-df2c12987274 2025-11-06 04:09:45,299 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/1f1c8b5c-f527-4d58-9858-dff065eae578 2025-11-06 04:09:46,458 - 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.88 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.523 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.523/1.702/2.881/1.179 ms') 2025-11-06 04:09:46,458 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-11-06 04:09:46,533 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 04:09:46,533 - DEBUG - Waiting to SSH to VM dest_vm, IP 151.85.103.3, Port 22 2025-11-06 04:09:46,695 - DEBUG - VM dest_vm is ready for SSH connections 2025-11-06 04:09:46,702 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-64869538: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-64869538: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-06 04:09:47,066 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-11-06 04:09:47,489 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-11-06 04:09:47,810 - INFO - Configure the policy with allow any 2025-11-06 04:09:48,210 - DEBUG - Response for create_network : {'network': {'id': '3bbd0bba-5336-4c73-a671-7800b7e8a80e', 'name': 'vn1', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:09:48,425 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a13aa583-9b9f-4674-a825-f6a00a472a0b', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '3bbd0bba-5336-4c73-a671-7800b7e8a80e', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:09:48,450 - INFO - Created VN vn1 2025-11-06 04:09:48,462 - DEBUG - VN vn1 UUID is 3bbd0bba-5336-4c73-a671-7800b7e8a80e 2025-11-06 04:09:48,963 - DEBUG - Response for create_network : {'network': {'id': '1b2cdf6f-608f-4622-82d2-fd035d78425d', 'name': 'vn2', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:09:49,188 - DEBUG - Response for create_subnet : {'subnet': {'id': '340a7ea0-c32d-4523-901d-bbffa9b36ceb', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '1b2cdf6f-608f-4622-82d2-fd035d78425d', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:09:49,209 - INFO - Created VN vn2 2025-11-06 04:09:49,220 - DEBUG - VN vn2 UUID is 1b2cdf6f-608f-4622-82d2-fd035d78425d 2025-11-06 04:09:49,299 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-64869538: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-64869538: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-06 04:09:49,670 - INFO - Attaching policy sec_grp_policy_ctest-random-78066559 to vn vn1 2025-11-06 04:09:50,081 - INFO - Attaching policy sec_grp_policy_ctest-random-78066559 to vn vn2 2025-11-06 04:09:51,624 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-2) 2025-11-06 04:09:53,043 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-1) 2025-11-06 04:09:53,118 - INFO - ================================================================================ 2025-11-06 04:09:53,118 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-11-06 04:09:53,119 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-11-06 04:09:54,415 - DEBUG - Skipping xmpp flap check 2025-11-06 04:09:54,415 - INFO - Initial checks done. Running the testcase now 2025-11-06 04:09:54,415 - INFO - 2025-11-06 04:09:54,416 - INFO - config per peer with auth_data None 2025-11-06 04:09:55,082 - INFO - configure MD5 on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:09:55,178 - INFO - config per peer with auth_data None 2025-11-06 04:09:55,842 - INFO - configure MD5 on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:09:55,930 - INFO - config per peer with auth_data None 2025-11-06 04:09:56,626 - INFO - configure MD5 on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:09:56,726 - INFO - check BGP peering is up before setting md5 config 2025-11-06 04:09:56,726 - INFO - Check BGP staus between peers 2025-11-06 04:09:56,739 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-11-06 04:09:57,414 - INFO - Check BGP staus between peers 2025-11-06 04:09:57,431 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-06 04:09:57,431 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-06 04:10:07,432 - INFO - Check BGP staus between peers 2025-11-06 04:10:07,442 - INFO - remove md5 configured on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:10:07,443 - INFO - config per peer with auth_data None 2025-11-06 04:10:08,110 - INFO - configure MD5 on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:10:08,211 - INFO - remove md5 configured on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:10:08,211 - INFO - config per peer with auth_data None 2025-11-06 04:10:08,838 - INFO - configure MD5 on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:10:08,926 - INFO - remove md5 configured on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:10:08,926 - INFO - config per peer with auth_data None 2025-11-06 04:10:09,542 - INFO - configure MD5 on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:10:09,618 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-11-06 04:10:09,618 - INFO - Deleting VM vn2_vm_0 2025-11-06 04:10:09,618 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-06 04:10:09,619 - ERROR - Nova failed to get host of the VM 2025-11-06 04:10:09,619 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-06 04:10:09,619 - ERROR - Nova failed to get host of the VM 2025-11-06 04:10:09,681 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-11-06 04:10:09,681 - INFO - Deleting VM vn1_vm_0 2025-11-06 04:10:09,681 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-06 04:10:09,681 - ERROR - Nova failed to get host of the VM 2025-11-06 04:10:09,682 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-06 04:10:09,682 - ERROR - Nova failed to get host of the VM 2025-11-06 04:10:09,752 - INFO - Dettaching policy sec_grp_policy_ctest-random-78066559 from vn vn2 2025-11-06 04:10:09,914 - INFO - Dettaching policy sec_grp_policy_ctest-random-78066559 from vn vn1 2025-11-06 04:10:10,170 - INFO - Deleted policy sec_grp_policy_ctest-random-78066559 2025-11-06 04:10:10,170 - INFO - Deleting VN vn2 2025-11-06 04:10:10,223 - DEBUG - VN 1b2cdf6f-608f-4622-82d2-fd035d78425d still in use: Unable to complete operation on network 1b2cdf6f-608f-4622-82d2-fd035d78425d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-cb22cf57-4a34-425f-a8c2-f8a79754a164'] 2025-11-06 04:10:10,223 - WARNING - Deleting VN vn2 failed..Will retry 2025-11-06 04:10:12,405 - DEBUG - Response for deleting network () 2025-11-06 04:10:12,405 - INFO - Deleting VN vn1 2025-11-06 04:10:12,587 - DEBUG - Response for deleting network () 2025-11-06 04:10:12,587 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-11-06 04:10:12,838 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-11-06 04:10:13,134 - INFO - Deleted policy allow_all 2025-11-06 04:10:13,134 - INFO - Deleting VM dest_vm 2025-11-06 04:10:13,202 - INFO - Deleting VM source_vm 2025-11-06 04:10:13,265 - INFO - Deleting VN test_vnv6dn 2025-11-06 04:10:13,315 - DEBUG - VN 816c0f30-2901-4b86-a93c-7c49edba10f4 still in use: Unable to complete operation on network 816c0f30-2901-4b86-a93c-7c49edba10f4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ba039dfa-3543-4e86-970f-a8f155045110'] 2025-11-06 04:10:13,315 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-11-06 04:10:15,504 - DEBUG - Response for deleting network () 2025-11-06 04:10:15,504 - INFO - Deleting VN test_vnv6sr 2025-11-06 04:10:15,704 - DEBUG - Response for deleting network () 2025-11-06 04:10:16,998 - DEBUG - Skipping xmpp flap check
2025-11-06 04:10:16,999 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-11-06 04:10:16,999 - INFO - -------------------------------------------------------------------------------- 2025-11-06 04:10:17,291 - DEBUG - Response for create_network : {'network': {'id': '7371ceba-5ac1-4c94-a674-8593c7c8d10a', 'name': 'test_vnv6sr', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:10:17,606 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd77a6d5e-3ece-4d69-bcb5-e3d1b02242b7', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '7371ceba-5ac1-4c94-a674-8593c7c8d10a', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:10:17,858 - DEBUG - Response for create_subnet : {'subnet': {'id': '2741a457-4e22-489a-aa22-2afbae42fda8', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '7371ceba-5ac1-4c94-a674-8593c7c8d10a', 'ip_version': 4, 'cidr': '26.95.90.0/26', 'allocation_pools': [{'start': '26.95.90.2', 'end': '26.95.90.62'}], 'gateway_ip': '26.95.90.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.95.90.2', 'tags': [], 'project_id': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:10:17,886 - INFO - Created VN test_vnv6sr 2025-11-06 04:10:17,902 - DEBUG - VN test_vnv6sr UUID is 7371ceba-5ac1-4c94-a674-8593c7c8d10a 2025-11-06 04:10:18,307 - DEBUG - Response for create_network : {'network': {'id': '3c0ea65f-e13a-4cfa-b494-5e79d64f6a9e', 'name': 'test_vnv6dn', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:10:18,532 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e14b23b8-aec2-4351-9d96-d36299a0cd7b', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '3c0ea65f-e13a-4cfa-b494-5e79d64f6a9e', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:10:18,746 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c06058ec-3a78-4ac1-9218-248975125d07', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '3c0ea65f-e13a-4cfa-b494-5e79d64f6a9e', 'ip_version': 4, 'cidr': '33.216.63.0/26', 'allocation_pools': [{'start': '33.216.63.2', 'end': '33.216.63.62'}], 'gateway_ip': '33.216.63.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '33.216.63.2', 'tags': [], 'project_id': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:10:18,774 - INFO - Created VN test_vnv6dn 2025-11-06 04:10:18,793 - DEBUG - VN test_vnv6dn UUID is 3c0ea65f-e13a-4cfa-b494-5e79d64f6a9e 2025-11-06 04:10:20,043 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-2) 2025-11-06 04:10:21,406 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-1) 2025-11-06 04:10:21,406 - INFO - Waiting for VM source_vm to be up.. 2025-11-06 04:10:21,509 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-06 04:10:26,606 - DEBUG - VM is in ACTIVE state now 2025-11-06 04:10:26,607 - INFO - VM name : source_vm 2025-11-06 04:10:26,699 - DEBUG - VM source_vm ID is 934ea2d4-2410-4950-8c14-588e085d3f1c 2025-11-06 04:10:26,699 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4662-2 2025-11-06 04:10:26,801 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/934ea2d4-2410-4950-8c14-588e085d3f1c 2025-11-06 04:10:26,816 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/3a1c7b85-e088-41e1-9282-08e27f07c70b 2025-11-06 04:10:29,998 - 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 1006ms') 2025-11-06 04:10:29,998 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-11-06 04:10:30,022 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-64869538:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-11-06 04:10:30,022 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-64869538:test_vnv6sr is 26.95.90.1 and allocation pool is NOT set 2025-11-06 04:10:32,090 - 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.75 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.09 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 = 3.088/4.420/5.753/1.332 ms') 2025-11-06 04:10:32,091 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-11-06 04:10:32,164 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 04:10:32,164 - DEBUG - Waiting to SSH to VM source_vm, IP 26.95.90.3, Port 22 2025-11-06 04:10:32,237 - 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-06 04:10:32,331 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-11-06 04:10:37,332 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 04:10:37,332 - DEBUG - Waiting to SSH to VM source_vm, IP 26.95.90.3, Port 22 2025-11-06 04:10:37,501 - DEBUG - VM source_vm is ready for SSH connections 2025-11-06 04:10:37,501 - INFO - Waiting for VM dest_vm to be up.. 2025-11-06 04:10:37,596 - DEBUG - VM is in ACTIVE state now 2025-11-06 04:10:37,596 - INFO - VM name : dest_vm 2025-11-06 04:10:37,676 - DEBUG - VM dest_vm ID is f7476e22-d236-447a-a1f9-7bcd1fa12a74 2025-11-06 04:10:37,677 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4662-1 2025-11-06 04:10:37,753 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f7476e22-d236-447a-a1f9-7bcd1fa12a74 2025-11-06 04:10:37,764 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/5dc37783-5c62-48ae-a813-ad54f2d5c9d3 2025-11-06 04:10:38,933 - 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.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.382 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.382/1.721/3.061/1.339 ms') 2025-11-06 04:10:38,933 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-11-06 04:10:39,014 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 04:10:39,014 - DEBUG - Waiting to SSH to VM dest_vm, IP 33.216.63.3, Port 22 2025-11-06 04:10:39,172 - DEBUG - VM dest_vm is ready for SSH connections 2025-11-06 04:10:39,178 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-64869538: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-64869538: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-06 04:10:39,544 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-11-06 04:10:39,931 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-11-06 04:10:40,239 - INFO - Configure the policy with allow any 2025-11-06 04:10:40,587 - DEBUG - Response for create_network : {'network': {'id': '7fddd4c5-070b-40a2-9458-c413dcfc827a', 'name': 'vn1', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:10:40,907 - DEBUG - Response for create_subnet : {'subnet': {'id': '39aa89f9-da7d-4281-9775-87311fe086aa', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': '7fddd4c5-070b-40a2-9458-c413dcfc827a', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:10:40,930 - INFO - Created VN vn1 2025-11-06 04:10:40,944 - DEBUG - VN vn1 UUID is 7fddd4c5-070b-40a2-9458-c413dcfc827a 2025-11-06 04:10:41,366 - DEBUG - Response for create_network : {'network': {'id': 'ae4ae86b-e77e-4599-9661-d45f0fece3ed', 'name': 'vn2', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'project_id': 'e0cf5943b651451e93778db854410b07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-64869538', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 04:10:41,619 - DEBUG - Response for create_subnet : {'subnet': {'id': '9a071059-b36a-43b5-bef5-6f269528442e', 'name': '', 'tenant_id': 'e0cf5943b651451e93778db854410b07', 'network_id': 'ae4ae86b-e77e-4599-9661-d45f0fece3ed', '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': 'e0cf5943b651451e93778db854410b07'}} 2025-11-06 04:10:41,642 - INFO - Created VN vn2 2025-11-06 04:10:41,660 - DEBUG - VN vn2 UUID is ae4ae86b-e77e-4599-9661-d45f0fece3ed 2025-11-06 04:10:41,763 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-64869538: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-64869538: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-06 04:10:42,140 - INFO - Attaching policy sec_grp_policy_ctest-random-86244527 to vn vn1 2025-11-06 04:10:42,551 - INFO - Attaching policy sec_grp_policy_ctest-random-86244527 to vn vn2 2025-11-06 04:10:44,231 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-2) 2025-11-06 04:10:45,793 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4662-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4662-1) 2025-11-06 04:10:45,857 - INFO - ================================================================================ 2025-11-06 04:10:45,857 - INFO - STARTING TEST : test_create_md5_on_control 2025-11-06 04:10:45,857 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-11-06 04:10:47,171 - DEBUG - Skipping xmpp flap check 2025-11-06 04:10:47,171 - INFO - Initial checks done. Running the testcase now 2025-11-06 04:10:47,171 - INFO - 2025-11-06 04:10:47,171 - INFO - config md5 on each host with auth_data None 2025-11-06 04:10:47,171 - INFO - config per peer with auth_data None 2025-11-06 04:10:47,860 - INFO - configure MD5 on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:10:47,966 - INFO - config per peer with auth_data None 2025-11-06 04:10:48,714 - INFO - configure MD5 on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:10:48,822 - INFO - config per peer with auth_data None 2025-11-06 04:10:49,542 - INFO - configure MD5 on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:10:49,642 - INFO - check if BGP between peers are up before setting md5 2025-11-06 04:10:49,642 - INFO - Check BGP staus between peers 2025-11-06 04:10:49,653 - INFO - setting MD5 config on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:10:49,654 - INFO - configure MD5 on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:10:49,738 - INFO - setting MD5 config on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:10:49,738 - INFO - configure MD5 on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:10:49,834 - INFO - setting MD5 config on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:10:49,834 - INFO - configure MD5 on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:10:49,922 - INFO - check if BGP peering is up after md5 config 2025-11-06 04:10:49,922 - INFO - Check BGP staus between peers 2025-11-06 04:10:49,933 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-06 04:10:49,933 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-06 04:10:59,935 - INFO - Check BGP staus between peers 2025-11-06 04:10:59,946 - INFO - remove md5 configured on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:10:59,946 - INFO - config per peer with auth_data None 2025-11-06 04:11:00,638 - INFO - configure MD5 on host e338b561-87c5-4183-9423-b810fd4bcaf5 2025-11-06 04:11:00,758 - INFO - remove md5 configured on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:11:00,758 - INFO - config per peer with auth_data None 2025-11-06 04:11:01,450 - INFO - configure MD5 on host 4c1446e0-4509-4bc9-a4bb-f7194572ed31 2025-11-06 04:11:01,554 - INFO - remove md5 configured on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:11:01,554 - INFO - config per peer with auth_data None 2025-11-06 04:11:02,249 - INFO - configure MD5 on host 632dc211-92fa-4496-8e6c-daf81c4dd44b 2025-11-06 04:11:02,366 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-11-06 04:11:02,367 - INFO - Deleting VM vn2_vm_0 2025-11-06 04:11:02,367 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-06 04:11:02,367 - ERROR - Nova failed to get host of the VM 2025-11-06 04:11:02,367 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-06 04:11:02,367 - ERROR - Nova failed to get host of the VM 2025-11-06 04:11:02,459 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-11-06 04:11:02,459 - INFO - Deleting VM vn1_vm_0 2025-11-06 04:11:02,459 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-06 04:11:02,459 - ERROR - Nova failed to get host of the VM 2025-11-06 04:11:02,459 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-06 04:11:02,459 - ERROR - Nova failed to get host of the VM 2025-11-06 04:11:02,537 - INFO - Dettaching policy sec_grp_policy_ctest-random-86244527 from vn vn2 2025-11-06 04:11:02,732 - INFO - Dettaching policy sec_grp_policy_ctest-random-86244527 from vn vn1 2025-11-06 04:11:03,044 - INFO - Deleted policy sec_grp_policy_ctest-random-86244527 2025-11-06 04:11:03,044 - INFO - Deleting VN vn2 2025-11-06 04:11:03,137 - DEBUG - VN ae4ae86b-e77e-4599-9661-d45f0fece3ed still in use: Unable to complete operation on network ae4ae86b-e77e-4599-9661-d45f0fece3ed. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9ef9ba78-c4c3-4fbb-bd77-b4502b75de75'] 2025-11-06 04:11:03,137 - WARNING - Deleting VN vn2 failed..Will retry 2025-11-06 04:11:05,305 - DEBUG - Response for deleting network () 2025-11-06 04:11:05,306 - INFO - Deleting VN vn1 2025-11-06 04:11:05,483 - DEBUG - Response for deleting network () 2025-11-06 04:11:05,483 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-11-06 04:11:05,693 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-11-06 04:11:05,978 - INFO - Deleted policy allow_all 2025-11-06 04:11:05,978 - INFO - Deleting VM dest_vm 2025-11-06 04:11:06,051 - INFO - Deleting VM source_vm 2025-11-06 04:11:06,127 - INFO - Deleting VN test_vnv6dn 2025-11-06 04:11:06,177 - DEBUG - VN 3c0ea65f-e13a-4cfa-b494-5e79d64f6a9e still in use: Unable to complete operation on network 3c0ea65f-e13a-4cfa-b494-5e79d64f6a9e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-223d44de-ce79-4048-ad52-43cc5571f52d'] 2025-11-06 04:11:06,178 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-11-06 04:11:08,440 - DEBUG - Response for deleting network () 2025-11-06 04:11:08,441 - INFO - Deleting VN test_vnv6sr 2025-11-06 04:11:08,655 - DEBUG - Response for deleting network () 2025-11-06 04:11:09,958 - DEBUG - Skipping xmpp flap check
2025-11-06 04:11:09,958 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-11-06 04:11:09,958 - INFO - -------------------------------------------------------------------------------- 2025-11-06 04:11:10,772 - INFO - Deleted project: ctest-TestMd5testsOnControl-64869538, ID : e0cf5943-b651-451e-9377-8db854410b07