2026-03-05 04:01:10,569 - INFO - Domain Default found not creating 2026-03-05 04:01:10,729 - INFO - Project ctest-TestMd5testsOnControl-15681732 not found, creating it 2026-03-05 04:01:11,269 - INFO - Created Project:ctest-TestMd5testsOnControl-15681732, ID : 65272903-bd39-4eb5-b11f-2efe07aa3b18 2026-03-05 04:01:13,445 - DEBUG - Response for create_network : {'network': {'id': '64f5a4ba-e734-4dec-a318-ab55de8f91bf', 'name': 'test_vnv6sr', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:01:13,686 - DEBUG - Response for create_subnet : {'subnet': {'id': '1792704b-747d-4f98-b32f-d9465cce495d', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '64f5a4ba-e734-4dec-a318-ab55de8f91bf', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:01:13,973 - DEBUG - Response for create_subnet : {'subnet': {'id': '6760f44c-3e3b-4af9-9db4-c31eb6d326fd', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '64f5a4ba-e734-4dec-a318-ab55de8f91bf', 'ip_version': 4, 'cidr': '189.212.5.192/26', 'allocation_pools': [{'start': '189.212.5.194', 'end': '189.212.5.254'}], 'gateway_ip': '189.212.5.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '189.212.5.194', 'tags': [], 'project_id': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:01:13,997 - INFO - Created VN test_vnv6sr 2026-03-05 04:01:14,007 - DEBUG - VN test_vnv6sr UUID is 64f5a4ba-e734-4dec-a318-ab55de8f91bf 2026-03-05 04:01:14,360 - DEBUG - Response for create_network : {'network': {'id': '516f9b08-71cd-47ca-9e35-b93ace75db56', 'name': 'test_vnv6dn', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:01:14,593 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ba2ad3a5-54ac-4680-b4c0-76e87bed6c99', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '516f9b08-71cd-47ca-9e35-b93ace75db56', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:01:14,800 - DEBUG - Response for create_subnet : {'subnet': {'id': '238a819a-f176-4de7-b709-c7a18e166ca6', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '516f9b08-71cd-47ca-9e35-b93ace75db56', 'ip_version': 4, 'cidr': '123.241.195.64/26', 'allocation_pools': [{'start': '123.241.195.66', 'end': '123.241.195.126'}], 'gateway_ip': '123.241.195.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '123.241.195.66', 'tags': [], 'project_id': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:01:14,832 - INFO - Created VN test_vnv6dn 2026-03-05 04:01:14,851 - DEBUG - VN test_vnv6dn UUID is 516f9b08-71cd-47ca-9e35-b93ace75db56 2026-03-05 04:01:15,113 - DEBUG - Services list from nova: [, , , ] 2026-03-05 04:01:16,836 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-2) 2026-03-05 04:01:18,329 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-1) 2026-03-05 04:01:18,329 - INFO - Waiting for VM source_vm to be up.. 2026-03-05 04:01:18,420 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-05 04:01:23,509 - DEBUG - VM is in ACTIVE state now 2026-03-05 04:01:23,509 - INFO - VM name : source_vm 2026-03-05 04:01:23,605 - DEBUG - VM source_vm ID is 95ee011e-bbb6-45ae-8f37-a715ede2a7aa 2026-03-05 04:01:23,634 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5433-2 2026-03-05 04:01:23,713 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/95ee011e-bbb6-45ae-8f37-a715ede2a7aa 2026-03-05 04:01:24,055 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/95ee011e-bbb6-45ae-8f37-a715ede2a7aa 2026-03-05 04:01:24,095 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/b9baf0e5-ee42-486f-ab2b-f77287032195 2026-03-05 04:01:27,298 - 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 1020ms') 2026-03-05 04:01:27,298 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-03-05 04:01:27,313 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-15681732:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-03-05 04:01:27,313 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-15681732:test_vnv6sr is 189.212.5.193 and allocation pool is NOT set 2026-03-05 04:01:29,384 - 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.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.50 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 = 2.503/3.562/4.621/1.059 ms') 2026-03-05 04:01:29,384 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-03-05 04:01:29,449 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 04:01:29,450 - DEBUG - Waiting to SSH to VM source_vm, IP 189.212.5.195, Port 22 2026-03-05 04:01:29,519 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-05 04:01:29,602 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-03-05 04:01:34,603 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 04:01:34,603 - DEBUG - Waiting to SSH to VM source_vm, IP 189.212.5.195, Port 22 2026-03-05 04:01:34,786 - DEBUG - VM source_vm is ready for SSH connections 2026-03-05 04:01:34,787 - INFO - Waiting for VM dest_vm to be up.. 2026-03-05 04:01:34,879 - DEBUG - VM is in ACTIVE state now 2026-03-05 04:01:34,879 - INFO - VM name : dest_vm 2026-03-05 04:01:34,968 - DEBUG - VM dest_vm ID is 092bc09e-77e9-4747-8d02-16e98200bf12 2026-03-05 04:01:34,969 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5433-1 2026-03-05 04:01:35,059 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/092bc09e-77e9-4747-8d02-16e98200bf12 2026-03-05 04:01:35,071 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/2ec164f1-6dc5-4959-9280-8f9dc425d2ce 2026-03-05 04:01:36,231 - 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.24 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.404 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.404/1.820/3.237/1.416 ms') 2026-03-05 04:01:36,232 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-03-05 04:01:36,297 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 04:01:36,297 - DEBUG - Waiting to SSH to VM dest_vm, IP 123.241.195.67, Port 22 2026-03-05 04:01:36,469 - DEBUG - VM dest_vm is ready for SSH connections 2026-03-05 04:01:36,474 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-15681732: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-15681732:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-03-05 04:01:36,808 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-03-05 04:01:37,177 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-03-05 04:01:37,458 - INFO - Configure the policy with allow any 2026-03-05 04:01:37,751 - DEBUG - Response for create_network : {'network': {'id': 'c92369cc-ebba-43c0-bc77-cfa96436d8f3', 'name': 'vn1', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:01:37,963 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a4045d4c-f3a0-4bd0-8953-12ca25c9cad3', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': 'c92369cc-ebba-43c0-bc77-cfa96436d8f3', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:01:37,985 - INFO - Created VN vn1 2026-03-05 04:01:37,996 - DEBUG - VN vn1 UUID is c92369cc-ebba-43c0-bc77-cfa96436d8f3 2026-03-05 04:01:38,339 - DEBUG - Response for create_network : {'network': {'id': '1a105244-afaf-48ef-bd6a-8634a3d18554', 'name': 'vn2', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:01:38,541 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c34a2150-85f3-4956-9b56-8c511b33f9b8', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '1a105244-afaf-48ef-bd6a-8634a3d18554', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:01:38,565 - INFO - Created VN vn2 2026-03-05 04:01:38,577 - DEBUG - VN vn2 UUID is 1a105244-afaf-48ef-bd6a-8634a3d18554 2026-03-05 04:01:38,659 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-15681732: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-15681732:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-03-05 04:01:38,999 - INFO - Attaching policy sec_grp_policy_ctest-random-22462659 to vn vn1 2026-03-05 04:01:39,372 - INFO - Attaching policy sec_grp_policy_ctest-random-22462659 to vn vn2 2026-03-05 04:01:40,985 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-2) 2026-03-05 04:01:42,531 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-1) 2026-03-05 04:01:42,593 - INFO - ================================================================================ 2026-03-05 04:01:42,593 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-03-05 04:01:42,593 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-03-05 04:01:43,872 - DEBUG - Skipping xmpp flap check 2026-03-05 04:01:43,872 - INFO - Initial checks done. Running the testcase now 2026-03-05 04:01:43,872 - INFO - 2026-03-05 04:01:43,872 - INFO - config per peer with auth_data None 2026-03-05 04:01:44,377 - INFO - configure MD5 on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:01:44,473 - INFO - config per peer with auth_data None 2026-03-05 04:01:45,025 - INFO - configure MD5 on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:01:45,117 - INFO - config per peer with auth_data None 2026-03-05 04:01:45,701 - INFO - configure MD5 on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:01:45,785 - INFO - check BGP peering is up before setting md5 config 2026-03-05 04:01:45,785 - INFO - Check BGP staus between peers 2026-03-05 04:01:45,798 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-03-05 04:01:46,385 - INFO - Check BGP staus between peers 2026-03-05 04:01:46,401 - DEBUG - Check the BGP connection on 10.0.0.65 2026-03-05 04:01:46,402 - DEBUG - Check the BGP connection on 10.0.0.65 2026-03-05 04:01:56,403 - INFO - Check BGP staus between peers 2026-03-05 04:01:56,413 - INFO - remove md5 configured on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:01:56,414 - INFO - config per peer with auth_data None 2026-03-05 04:01:56,941 - INFO - configure MD5 on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:01:57,025 - INFO - remove md5 configured on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:01:57,026 - INFO - config per peer with auth_data None 2026-03-05 04:01:57,617 - INFO - configure MD5 on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:01:57,697 - INFO - remove md5 configured on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:01:57,698 - INFO - config per peer with auth_data None 2026-03-05 04:01:58,261 - INFO - configure MD5 on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:01:58,353 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-03-05 04:01:58,356 - INFO - Deleting VM vn2_vm_0 2026-03-05 04:01:58,356 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-05 04:01:58,356 - ERROR - Nova failed to get host of the VM 2026-03-05 04:01:58,356 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-05 04:01:58,356 - ERROR - Nova failed to get host of the VM 2026-03-05 04:01:58,432 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-03-05 04:01:58,432 - INFO - Deleting VM vn1_vm_0 2026-03-05 04:01:58,432 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-05 04:01:58,433 - ERROR - Nova failed to get host of the VM 2026-03-05 04:01:58,433 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-05 04:01:58,433 - ERROR - Nova failed to get host of the VM 2026-03-05 04:01:58,505 - INFO - Dettaching policy sec_grp_policy_ctest-random-22462659 from vn vn2 2026-03-05 04:01:58,668 - INFO - Dettaching policy sec_grp_policy_ctest-random-22462659 from vn vn1 2026-03-05 04:01:58,915 - INFO - Deleted policy sec_grp_policy_ctest-random-22462659 2026-03-05 04:01:58,916 - INFO - Deleting VN vn2 2026-03-05 04:01:58,984 - DEBUG - VN 1a105244-afaf-48ef-bd6a-8634a3d18554 still in use: Unable to complete operation on network 1a105244-afaf-48ef-bd6a-8634a3d18554. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-10d0f8cc-f63c-492e-9da7-d46b325c8e4f'] 2026-03-05 04:01:58,984 - WARNING - Deleting VN vn2 failed..Will retry 2026-03-05 04:02:01,113 - DEBUG - Response for deleting network () 2026-03-05 04:02:01,113 - INFO - Deleting VN vn1 2026-03-05 04:02:01,279 - DEBUG - Response for deleting network () 2026-03-05 04:02:01,279 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-03-05 04:02:01,447 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-03-05 04:02:01,685 - INFO - Deleted policy allow_all 2026-03-05 04:02:01,685 - INFO - Deleting VM dest_vm 2026-03-05 04:02:01,798 - INFO - Deleting VM source_vm 2026-03-05 04:02:01,871 - INFO - Deleting VN test_vnv6dn 2026-03-05 04:02:01,911 - DEBUG - VN 516f9b08-71cd-47ca-9e35-b93ace75db56 still in use: Unable to complete operation on network 516f9b08-71cd-47ca-9e35-b93ace75db56. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-fd37a165-d2b2-440f-814f-8bc5a0ad7ca7'] 2026-03-05 04:02:01,912 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-03-05 04:02:04,064 - DEBUG - Response for deleting network () 2026-03-05 04:02:04,064 - INFO - Deleting VN test_vnv6sr 2026-03-05 04:02:04,240 - DEBUG - Response for deleting network () 2026-03-05 04:02:05,504 - DEBUG - Skipping xmpp flap check
2026-03-05 04:02:05,504 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2026-03-05 04:02:05,504 - INFO - -------------------------------------------------------------------------------- 2026-03-05 04:02:05,755 - DEBUG - Response for create_network : {'network': {'id': '00104e4a-ab1d-4cbd-8718-7a3555c4fff8', 'name': 'test_vnv6sr', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:02:05,987 - DEBUG - Response for create_subnet : {'subnet': {'id': '09a771fc-bcd3-4c13-8a6e-daf967f3c1ff', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '00104e4a-ab1d-4cbd-8718-7a3555c4fff8', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:02:06,201 - DEBUG - Response for create_subnet : {'subnet': {'id': '2ef8524b-1415-4441-9660-2f22fb8a79cb', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '00104e4a-ab1d-4cbd-8718-7a3555c4fff8', 'ip_version': 4, 'cidr': '21.142.152.64/26', 'allocation_pools': [{'start': '21.142.152.66', 'end': '21.142.152.126'}], 'gateway_ip': '21.142.152.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '21.142.152.66', 'tags': [], 'project_id': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:02:06,225 - INFO - Created VN test_vnv6sr 2026-03-05 04:02:06,235 - DEBUG - VN test_vnv6sr UUID is 00104e4a-ab1d-4cbd-8718-7a3555c4fff8 2026-03-05 04:02:06,572 - DEBUG - Response for create_network : {'network': {'id': '6baaf55d-666b-4625-b90a-961651f7af39', 'name': 'test_vnv6dn', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:02:06,828 - DEBUG - Response for create_subnet : {'subnet': {'id': '1fffde9d-3be8-4cb6-8ec6-ea22a7c7364e', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '6baaf55d-666b-4625-b90a-961651f7af39', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:02:06,996 - DEBUG - Response for create_subnet : {'subnet': {'id': '6371b814-ab78-4c6e-ab66-aaa0bc671218', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '6baaf55d-666b-4625-b90a-961651f7af39', 'ip_version': 4, 'cidr': '121.231.14.64/26', 'allocation_pools': [{'start': '121.231.14.66', 'end': '121.231.14.126'}], 'gateway_ip': '121.231.14.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.231.14.66', 'tags': [], 'project_id': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:02:07,020 - INFO - Created VN test_vnv6dn 2026-03-05 04:02:07,033 - DEBUG - VN test_vnv6dn UUID is 6baaf55d-666b-4625-b90a-961651f7af39 2026-03-05 04:02:08,205 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-2) 2026-03-05 04:02:09,503 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-1) 2026-03-05 04:02:09,503 - INFO - Waiting for VM source_vm to be up.. 2026-03-05 04:02:09,588 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-05 04:02:14,673 - DEBUG - VM is in ACTIVE state now 2026-03-05 04:02:14,673 - INFO - VM name : source_vm 2026-03-05 04:02:14,757 - DEBUG - VM source_vm ID is 52fcebe3-d2f5-4c34-aa60-5a8fad632293 2026-03-05 04:02:14,757 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5433-2 2026-03-05 04:02:14,836 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/52fcebe3-d2f5-4c34-aa60-5a8fad632293 2026-03-05 04:02:14,845 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/2a4806f4-2f6c-483a-8ed7-abdd359cb6bc 2026-03-05 04:02:18,010 - 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 1014ms') 2026-03-05 04:02:18,010 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-03-05 04:02:18,028 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-15681732:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-03-05 04:02:18,028 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-15681732:test_vnv6sr is 21.142.152.65 and allocation pool is NOT set 2026-03-05 04:02:20,086 - 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=11.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.29 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.289/6.599/11.909/5.310 ms') 2026-03-05 04:02:20,086 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-03-05 04:02:20,158 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 04:02:20,158 - DEBUG - Waiting to SSH to VM source_vm, IP 21.142.152.67, Port 22 2026-03-05 04:02:20,226 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-05 04:02:20,307 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-03-05 04:02:25,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 04:02:25,308 - DEBUG - Waiting to SSH to VM source_vm, IP 21.142.152.67, Port 22 2026-03-05 04:02:25,478 - DEBUG - VM source_vm is ready for SSH connections 2026-03-05 04:02:25,478 - INFO - Waiting for VM dest_vm to be up.. 2026-03-05 04:02:25,561 - DEBUG - VM is in ACTIVE state now 2026-03-05 04:02:25,561 - INFO - VM name : dest_vm 2026-03-05 04:02:25,645 - DEBUG - VM dest_vm ID is a516e670-f8c5-4d9a-a212-3c65b40a8dea 2026-03-05 04:02:25,645 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5433-1 2026-03-05 04:02:25,730 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/a516e670-f8c5-4d9a-a212-3c65b40a8dea 2026-03-05 04:02:25,739 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/7a790979-ca67-47a9-a8ca-67d1f1e80f23 2026-03-05 04:02:26,895 - 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.99 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.357 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.357/1.675/2.994/1.318 ms') 2026-03-05 04:02:26,895 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-03-05 04:02:26,966 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 04:02:26,966 - DEBUG - Waiting to SSH to VM dest_vm, IP 121.231.14.67, Port 22 2026-03-05 04:02:27,136 - DEBUG - VM dest_vm is ready for SSH connections 2026-03-05 04:02:27,142 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-15681732: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-15681732:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-03-05 04:02:27,480 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-03-05 04:02:27,820 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-03-05 04:02:28,080 - INFO - Configure the policy with allow any 2026-03-05 04:02:28,393 - DEBUG - Response for create_network : {'network': {'id': 'ce8e76ec-0380-442b-b037-c41149674938', 'name': 'vn1', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:02:28,647 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fdcfab10-d9ee-40fb-9801-eab1b3613ee1', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': 'ce8e76ec-0380-442b-b037-c41149674938', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:02:28,674 - INFO - Created VN vn1 2026-03-05 04:02:28,685 - DEBUG - VN vn1 UUID is ce8e76ec-0380-442b-b037-c41149674938 2026-03-05 04:02:29,049 - DEBUG - Response for create_network : {'network': {'id': '9da5ea73-746d-459c-a509-0983ec1960ef', 'name': 'vn2', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'project_id': '65272903bd394eb5b11f2efe07aa3b18', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-15681732', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 04:02:29,275 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c26dcca1-ef42-4c4f-a9a2-83930e96bbcb', 'name': '', 'tenant_id': '65272903bd394eb5b11f2efe07aa3b18', 'network_id': '9da5ea73-746d-459c-a509-0983ec1960ef', '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': '65272903bd394eb5b11f2efe07aa3b18'}} 2026-03-05 04:02:29,296 - INFO - Created VN vn2 2026-03-05 04:02:29,309 - DEBUG - VN vn2 UUID is 9da5ea73-746d-459c-a509-0983ec1960ef 2026-03-05 04:02:29,421 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-15681732: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-15681732:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-03-05 04:02:29,764 - INFO - Attaching policy sec_grp_policy_ctest-random-77874085 to vn vn1 2026-03-05 04:02:30,092 - INFO - Attaching policy sec_grp_policy_ctest-random-77874085 to vn vn2 2026-03-05 04:02:31,641 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-2) 2026-03-05 04:02:33,127 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5433-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5433-1) 2026-03-05 04:02:33,188 - INFO - ================================================================================ 2026-03-05 04:02:33,188 - INFO - STARTING TEST : test_create_md5_on_control 2026-03-05 04:02:33,188 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-03-05 04:02:34,471 - DEBUG - Skipping xmpp flap check 2026-03-05 04:02:34,471 - INFO - Initial checks done. Running the testcase now 2026-03-05 04:02:34,472 - INFO - 2026-03-05 04:02:34,472 - INFO - config md5 on each host with auth_data None 2026-03-05 04:02:34,472 - INFO - config per peer with auth_data None 2026-03-05 04:02:35,073 - INFO - configure MD5 on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:02:35,157 - INFO - config per peer with auth_data None 2026-03-05 04:02:35,750 - INFO - configure MD5 on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:02:35,841 - INFO - config per peer with auth_data None 2026-03-05 04:02:36,425 - INFO - configure MD5 on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:02:36,525 - INFO - check if BGP between peers are up before setting md5 2026-03-05 04:02:36,525 - INFO - Check BGP staus between peers 2026-03-05 04:02:36,536 - INFO - setting MD5 config on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:02:36,536 - INFO - configure MD5 on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:02:36,613 - INFO - setting MD5 config on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:02:36,613 - INFO - configure MD5 on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:02:36,685 - INFO - setting MD5 config on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:02:36,685 - INFO - configure MD5 on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:02:36,757 - INFO - check if BGP peering is up after md5 config 2026-03-05 04:02:36,757 - INFO - Check BGP staus between peers 2026-03-05 04:02:36,767 - DEBUG - Check the BGP connection on 10.0.0.65 2026-03-05 04:02:36,767 - DEBUG - Check the BGP connection on 10.0.0.65 2026-03-05 04:02:46,768 - INFO - Check BGP staus between peers 2026-03-05 04:02:46,784 - INFO - remove md5 configured on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:02:46,785 - INFO - config per peer with auth_data None 2026-03-05 04:02:47,325 - INFO - configure MD5 on host 234d3566-3e1d-45d2-b4be-d366515d481e 2026-03-05 04:02:47,409 - INFO - remove md5 configured on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:02:47,409 - INFO - config per peer with auth_data None 2026-03-05 04:02:48,005 - INFO - configure MD5 on host 8d0c28e9-41ce-4de0-a2e9-53267b0cc7ca 2026-03-05 04:02:48,097 - INFO - remove md5 configured on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:02:48,097 - INFO - config per peer with auth_data None 2026-03-05 04:02:48,642 - INFO - configure MD5 on host c921710d-9c1e-4b8e-a8e4-46aac240e8c9 2026-03-05 04:02:48,721 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-03-05 04:02:48,721 - INFO - Deleting VM vn2_vm_0 2026-03-05 04:02:48,721 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-05 04:02:48,721 - ERROR - Nova failed to get host of the VM 2026-03-05 04:02:48,721 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-05 04:02:48,722 - ERROR - Nova failed to get host of the VM 2026-03-05 04:02:48,796 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-03-05 04:02:48,796 - INFO - Deleting VM vn1_vm_0 2026-03-05 04:02:48,796 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-05 04:02:48,796 - ERROR - Nova failed to get host of the VM 2026-03-05 04:02:48,796 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-05 04:02:48,796 - ERROR - Nova failed to get host of the VM 2026-03-05 04:02:48,866 - INFO - Dettaching policy sec_grp_policy_ctest-random-77874085 from vn vn2 2026-03-05 04:02:49,021 - INFO - Dettaching policy sec_grp_policy_ctest-random-77874085 from vn vn1 2026-03-05 04:02:49,264 - INFO - Deleted policy sec_grp_policy_ctest-random-77874085 2026-03-05 04:02:49,264 - INFO - Deleting VN vn2 2026-03-05 04:02:49,307 - DEBUG - VN 9da5ea73-746d-459c-a509-0983ec1960ef still in use: Unable to complete operation on network 9da5ea73-746d-459c-a509-0983ec1960ef. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a82e6070-d307-4de8-8d23-3df7e9a58f3b'] 2026-03-05 04:02:49,308 - WARNING - Deleting VN vn2 failed..Will retry 2026-03-05 04:02:51,445 - DEBUG - Response for deleting network () 2026-03-05 04:02:51,445 - INFO - Deleting VN vn1 2026-03-05 04:02:51,590 - DEBUG - Response for deleting network () 2026-03-05 04:02:51,590 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-03-05 04:02:51,765 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-03-05 04:02:52,001 - INFO - Deleted policy allow_all 2026-03-05 04:02:52,001 - INFO - Deleting VM dest_vm 2026-03-05 04:02:52,095 - INFO - Deleting VM source_vm 2026-03-05 04:02:52,173 - INFO - Deleting VN test_vnv6dn 2026-03-05 04:02:52,215 - DEBUG - VN 6baaf55d-666b-4625-b90a-961651f7af39 still in use: Unable to complete operation on network 6baaf55d-666b-4625-b90a-961651f7af39. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-dd846bb0-cb6e-4ccd-812f-cbd87286a810'] 2026-03-05 04:02:52,215 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-03-05 04:02:54,360 - DEBUG - Response for deleting network () 2026-03-05 04:02:54,360 - INFO - Deleting VN test_vnv6sr 2026-03-05 04:02:54,548 - DEBUG - Response for deleting network () 2026-03-05 04:02:55,828 - DEBUG - Skipping xmpp flap check
2026-03-05 04:02:55,828 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:22]
2026-03-05 04:02:55,828 - INFO - -------------------------------------------------------------------------------- 2026-03-05 04:02:56,517 - INFO - Deleted project: ctest-TestMd5testsOnControl-15681732, ID : 65272903-bd39-4eb5-b11f-2efe07aa3b18