2026-02-02 04:06:45,099 - INFO - Domain Default found not creating 2026-02-02 04:06:45,278 - INFO - Project ctest-TestMd5testsOnControl-42281926 not found, creating it 2026-02-02 04:06:45,819 - INFO - Created Project:ctest-TestMd5testsOnControl-42281926, ID : 5c2d5f0b-069e-477a-ac0a-c242bfd76b97 2026-02-02 04:06:47,978 - DEBUG - Response for create_network : {'network': {'id': 'aa7bbaee-71ee-4efe-8ca6-2227b97befd7', 'name': 'test_vnv6sr', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:06:48,253 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd06a911b-4071-4981-a631-ea90b7fd96bd', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'aa7bbaee-71ee-4efe-8ca6-2227b97befd7', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:06:48,437 - DEBUG - Response for create_subnet : {'subnet': {'id': '4f0c6ac6-b033-4ddf-a2a0-0acd377d981e', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'aa7bbaee-71ee-4efe-8ca6-2227b97befd7', 'ip_version': 4, 'cidr': '182.47.156.192/26', 'allocation_pools': [{'start': '182.47.156.194', 'end': '182.47.156.254'}], 'gateway_ip': '182.47.156.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '182.47.156.194', 'tags': [], 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:06:48,461 - INFO - Created VN test_vnv6sr 2026-02-02 04:06:48,472 - DEBUG - VN test_vnv6sr UUID is aa7bbaee-71ee-4efe-8ca6-2227b97befd7 2026-02-02 04:06:48,843 - DEBUG - Response for create_network : {'network': {'id': 'c79d260a-a36e-48d4-a100-fcbf208bb77f', 'name': 'test_vnv6dn', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:06:49,099 - DEBUG - Response for create_subnet : {'subnet': {'id': '388a5ff9-af5e-41a2-a65f-a9dc36418905', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'c79d260a-a36e-48d4-a100-fcbf208bb77f', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:06:49,279 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f759dfa8-541b-47f2-a2e3-9a5fadff89e4', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'c79d260a-a36e-48d4-a100-fcbf208bb77f', 'ip_version': 4, 'cidr': '20.117.87.64/26', 'allocation_pools': [{'start': '20.117.87.66', 'end': '20.117.87.126'}], 'gateway_ip': '20.117.87.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.117.87.66', 'tags': [], 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:06:49,311 - INFO - Created VN test_vnv6dn 2026-02-02 04:06:49,322 - DEBUG - VN test_vnv6dn UUID is c79d260a-a36e-48d4-a100-fcbf208bb77f 2026-02-02 04:06:49,503 - DEBUG - Services list from nova: [, , , ] 2026-02-02 04:06:50,665 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-1) 2026-02-02 04:06:52,018 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-2) 2026-02-02 04:06:52,019 - INFO - Waiting for VM source_vm to be up.. 2026-02-02 04:06:52,123 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-02 04:06:57,212 - DEBUG - VM is in ACTIVE state now 2026-02-02 04:06:57,212 - INFO - VM name : source_vm 2026-02-02 04:06:57,329 - DEBUG - VM source_vm ID is 9ce632b2-05a8-4e13-ade1-26495493b8cc 2026-02-02 04:06:57,359 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5167-1 2026-02-02 04:06:57,446 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9ce632b2-05a8-4e13-ade1-26495493b8cc 2026-02-02 04:06:57,745 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9ce632b2-05a8-4e13-ade1-26495493b8cc 2026-02-02 04:06:57,779 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d8af6862-cc0b-499d-9f27-3e7cbcd7161b 2026-02-02 04:07:00,954 - 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') 2026-02-02 04:07:00,954 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-02-02 04:07:00,967 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-42281926:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-02-02 04:07:00,968 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-42281926:test_vnv6sr is 182.47.156.193 and allocation pool is NOT set 2026-02-02 04:07:03,031 - 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.04 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.400 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.400/1.718/3.036/1.318 ms') 2026-02-02 04:07:03,031 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-02-02 04:07:03,101 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-02 04:07:03,101 - DEBUG - Waiting to SSH to VM source_vm, IP 182.47.156.195, Port 22 2026-02-02 04:07:03,270 - DEBUG - VM source_vm is ready for SSH connections 2026-02-02 04:07:03,270 - INFO - Waiting for VM dest_vm to be up.. 2026-02-02 04:07:03,358 - DEBUG - VM is in ACTIVE state now 2026-02-02 04:07:03,358 - INFO - VM name : dest_vm 2026-02-02 04:07:03,444 - DEBUG - VM dest_vm ID is be61a186-7cbd-44be-93dc-df8fab468a2d 2026-02-02 04:07:03,444 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5167-2 2026-02-02 04:07:03,527 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/be61a186-7cbd-44be-93dc-df8fab468a2d 2026-02-02 04:07:03,537 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/cb758d84-4c34-4cc7-aefe-ccc3651b914e 2026-02-02 04:07:04,700 - 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.88 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.20 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.203/4.040/5.877/1.837 ms') 2026-02-02 04:07:04,700 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-02-02 04:07:04,777 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-02 04:07:04,778 - DEBUG - Waiting to SSH to VM dest_vm, IP 20.117.87.67, Port 22 2026-02-02 04:07:04,845 - 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-02-02 04:07:04,945 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-02-02 04:07:09,946 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-02 04:07:09,946 - DEBUG - Waiting to SSH to VM dest_vm, IP 20.117.87.67, Port 22 2026-02-02 04:07:10,113 - DEBUG - VM dest_vm is ready for SSH connections 2026-02-02 04:07:10,119 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-42281926: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-42281926: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-02-02 04:07:10,436 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-02-02 04:07:10,799 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-02-02 04:07:11,056 - INFO - Configure the policy with allow any 2026-02-02 04:07:11,344 - DEBUG - Response for create_network : {'network': {'id': '47a1e3e0-8409-426f-b534-677ab4293a0c', 'name': 'vn1', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:07:11,596 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e40cdb40-f476-4605-a8fe-80aa47730e5f', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': '47a1e3e0-8409-426f-b534-677ab4293a0c', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:07:11,620 - INFO - Created VN vn1 2026-02-02 04:07:11,632 - DEBUG - VN vn1 UUID is 47a1e3e0-8409-426f-b534-677ab4293a0c 2026-02-02 04:07:11,957 - DEBUG - Response for create_network : {'network': {'id': 'deba6457-8e54-4aa6-be62-62bd469dee74', 'name': 'vn2', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:07:12,182 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd5f1942e-5169-426a-879c-59a6d30c4afd', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'deba6457-8e54-4aa6-be62-62bd469dee74', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:07:12,203 - INFO - Created VN vn2 2026-02-02 04:07:12,213 - DEBUG - VN vn2 UUID is deba6457-8e54-4aa6-be62-62bd469dee74 2026-02-02 04:07:12,329 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-42281926: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-42281926: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-02-02 04:07:12,651 - INFO - Attaching policy sec_grp_policy_ctest-random-27973752 to vn vn1 2026-02-02 04:07:13,006 - INFO - Attaching policy sec_grp_policy_ctest-random-27973752 to vn vn2 2026-02-02 04:07:14,548 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-1) 2026-02-02 04:07:16,343 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-2) 2026-02-02 04:07:16,404 - INFO - ================================================================================ 2026-02-02 04:07:16,404 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-02-02 04:07:16,404 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-02-02 04:07:17,718 - DEBUG - Skipping xmpp flap check 2026-02-02 04:07:17,718 - INFO - Initial checks done. Running the testcase now 2026-02-02 04:07:17,719 - INFO - 2026-02-02 04:07:17,719 - INFO - config per peer with auth_data None 2026-02-02 04:07:18,308 - INFO - configure MD5 on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:07:18,398 - INFO - config per peer with auth_data None 2026-02-02 04:07:18,997 - INFO - configure MD5 on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:07:19,077 - INFO - config per peer with auth_data None 2026-02-02 04:07:19,641 - INFO - configure MD5 on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:07:19,721 - INFO - check BGP peering is up before setting md5 config 2026-02-02 04:07:19,721 - INFO - Check BGP staus between peers 2026-02-02 04:07:19,732 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-02-02 04:07:20,325 - INFO - Check BGP staus between peers 2026-02-02 04:07:20,334 - DEBUG - Check the BGP connection on 10.0.0.141 2026-02-02 04:07:20,334 - DEBUG - Check the BGP connection on 10.0.0.141 2026-02-02 04:07:30,335 - INFO - Check BGP staus between peers 2026-02-02 04:07:30,344 - INFO - remove md5 configured on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:07:30,344 - INFO - config per peer with auth_data None 2026-02-02 04:07:30,921 - INFO - configure MD5 on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:07:31,013 - INFO - remove md5 configured on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:07:31,013 - INFO - config per peer with auth_data None 2026-02-02 04:07:31,633 - INFO - configure MD5 on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:07:31,729 - INFO - remove md5 configured on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:07:31,729 - INFO - config per peer with auth_data None 2026-02-02 04:07:32,369 - INFO - configure MD5 on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:07:32,465 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-02-02 04:07:32,465 - INFO - Deleting VM vn2_vm_0 2026-02-02 04:07:32,465 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-02 04:07:32,465 - ERROR - Nova failed to get host of the VM 2026-02-02 04:07:32,465 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-02 04:07:32,465 - ERROR - Nova failed to get host of the VM 2026-02-02 04:07:32,548 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-02-02 04:07:32,548 - INFO - Deleting VM vn1_vm_0 2026-02-02 04:07:32,548 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-02 04:07:32,548 - ERROR - Nova failed to get host of the VM 2026-02-02 04:07:32,549 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-02 04:07:32,549 - ERROR - Nova failed to get host of the VM 2026-02-02 04:07:32,620 - INFO - Dettaching policy sec_grp_policy_ctest-random-27973752 from vn vn2 2026-02-02 04:07:32,823 - INFO - Dettaching policy sec_grp_policy_ctest-random-27973752 from vn vn1 2026-02-02 04:07:33,111 - INFO - Deleted policy sec_grp_policy_ctest-random-27973752 2026-02-02 04:07:33,111 - INFO - Deleting VN vn2 2026-02-02 04:07:33,172 - DEBUG - VN deba6457-8e54-4aa6-be62-62bd469dee74 still in use: Unable to complete operation on network deba6457-8e54-4aa6-be62-62bd469dee74. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-25842085-4eda-4303-82cc-145e8066c604'] 2026-02-02 04:07:33,172 - WARNING - Deleting VN vn2 failed..Will retry 2026-02-02 04:07:35,315 - DEBUG - Response for deleting network () 2026-02-02 04:07:35,315 - INFO - Deleting VN vn1 2026-02-02 04:07:35,454 - DEBUG - Response for deleting network () 2026-02-02 04:07:35,455 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-02-02 04:07:35,639 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-02-02 04:07:35,901 - INFO - Deleted policy allow_all 2026-02-02 04:07:35,901 - INFO - Deleting VM dest_vm 2026-02-02 04:07:35,979 - INFO - Deleting VM source_vm 2026-02-02 04:07:36,055 - INFO - Deleting VN test_vnv6dn 2026-02-02 04:07:36,107 - DEBUG - VN c79d260a-a36e-48d4-a100-fcbf208bb77f still in use: Unable to complete operation on network c79d260a-a36e-48d4-a100-fcbf208bb77f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-54a04b36-988c-4ad7-b6fc-6ed91f096a65'] 2026-02-02 04:07:36,107 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-02-02 04:07:38,298 - DEBUG - Response for deleting network () 2026-02-02 04:07:38,298 - INFO - Deleting VN test_vnv6sr 2026-02-02 04:07:38,477 - DEBUG - Response for deleting network () 2026-02-02 04:07:39,765 - DEBUG - Skipping xmpp flap check
2026-02-02 04:07:39,765 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2026-02-02 04:07:39,765 - INFO - -------------------------------------------------------------------------------- 2026-02-02 04:07:40,022 - DEBUG - Response for create_network : {'network': {'id': '5e6c1bbc-c523-429f-bd52-93d7ef0d08d5', 'name': 'test_vnv6sr', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:07:40,266 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b401ace4-91e7-473d-92ff-ac711afe21c4', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': '5e6c1bbc-c523-429f-bd52-93d7ef0d08d5', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:07:40,483 - DEBUG - Response for create_subnet : {'subnet': {'id': '58ed4d2a-edf5-489e-a5e0-e0a396983ded', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': '5e6c1bbc-c523-429f-bd52-93d7ef0d08d5', 'ip_version': 4, 'cidr': '64.81.168.128/26', 'allocation_pools': [{'start': '64.81.168.130', 'end': '64.81.168.190'}], 'gateway_ip': '64.81.168.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '64.81.168.130', 'tags': [], 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:07:40,515 - INFO - Created VN test_vnv6sr 2026-02-02 04:07:40,527 - DEBUG - VN test_vnv6sr UUID is 5e6c1bbc-c523-429f-bd52-93d7ef0d08d5 2026-02-02 04:07:40,898 - DEBUG - Response for create_network : {'network': {'id': 'a31b19d1-fb4e-4d04-bf2f-a94800e09078', 'name': 'test_vnv6dn', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:07:41,127 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c79c14d9-6f32-4ca9-b3e9-b4960c4d3b8f', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'a31b19d1-fb4e-4d04-bf2f-a94800e09078', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:07:41,302 - DEBUG - Response for create_subnet : {'subnet': {'id': '23c871b0-a659-4bc2-bf47-9582a0232e43', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'a31b19d1-fb4e-4d04-bf2f-a94800e09078', 'ip_version': 4, 'cidr': '91.60.236.64/26', 'allocation_pools': [{'start': '91.60.236.66', 'end': '91.60.236.126'}], 'gateway_ip': '91.60.236.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.60.236.66', 'tags': [], 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:07:41,335 - INFO - Created VN test_vnv6dn 2026-02-02 04:07:41,347 - DEBUG - VN test_vnv6dn UUID is a31b19d1-fb4e-4d04-bf2f-a94800e09078 2026-02-02 04:07:42,525 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-1) 2026-02-02 04:07:43,942 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-2) 2026-02-02 04:07:43,942 - INFO - Waiting for VM source_vm to be up.. 2026-02-02 04:07:44,038 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-02 04:07:49,133 - DEBUG - VM is in ACTIVE state now 2026-02-02 04:07:49,133 - INFO - VM name : source_vm 2026-02-02 04:07:49,218 - DEBUG - VM source_vm ID is 17a9ab17-39b1-4d0b-b112-cf1570c3e6ff 2026-02-02 04:07:49,218 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5167-1 2026-02-02 04:07:49,296 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/17a9ab17-39b1-4d0b-b112-cf1570c3e6ff 2026-02-02 04:07:49,306 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/8ba6645f-db09-4aa5-89a2-5d133c48344c 2026-02-02 04:07:52,470 - 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') 2026-02-02 04:07:52,470 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-02-02 04:07:52,483 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-42281926:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-02-02 04:07:52,484 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-42281926:test_vnv6sr is 64.81.168.129 and allocation pool is NOT set 2026-02-02 04:07:54,554 - 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.94 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.403 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.403/2.171/3.939/1.768 ms') 2026-02-02 04:07:54,554 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-02-02 04:07:54,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-02 04:07:54,625 - DEBUG - Waiting to SSH to VM source_vm, IP 64.81.168.131, Port 22 2026-02-02 04:07:54,787 - DEBUG - VM source_vm is ready for SSH connections 2026-02-02 04:07:54,787 - INFO - Waiting for VM dest_vm to be up.. 2026-02-02 04:07:54,886 - DEBUG - VM is in ACTIVE state now 2026-02-02 04:07:54,886 - INFO - VM name : dest_vm 2026-02-02 04:07:54,971 - DEBUG - VM dest_vm ID is 7d91b540-31e6-4022-bbe5-5064b622c901 2026-02-02 04:07:54,971 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5167-2 2026-02-02 04:07:55,050 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7d91b540-31e6-4022-bbe5-5064b622c901 2026-02-02 04:07:55,060 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d9c129ef-f84f-40f4-937a-f75c779685b2 2026-02-02 04:07:56,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=4.46 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.04 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.039/3.751/4.464/0.712 ms') 2026-02-02 04:07:56,231 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-02-02 04:07:56,302 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-02 04:07:56,303 - DEBUG - Waiting to SSH to VM dest_vm, IP 91.60.236.67, Port 22 2026-02-02 04:07:56,369 - 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-02-02 04:07:56,468 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-02-02 04:08:01,468 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-02 04:08:01,468 - DEBUG - Waiting to SSH to VM dest_vm, IP 91.60.236.67, Port 22 2026-02-02 04:08:01,645 - DEBUG - VM dest_vm is ready for SSH connections 2026-02-02 04:08:01,652 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-42281926: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-42281926: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-02-02 04:08:02,011 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-02-02 04:08:02,368 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-02-02 04:08:02,643 - INFO - Configure the policy with allow any 2026-02-02 04:08:02,936 - DEBUG - Response for create_network : {'network': {'id': '4c2c6b99-7cc7-425f-ba73-66247ee25652', 'name': 'vn1', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:08:03,193 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c9cd2f72-d097-4f76-bee1-928308b21c58', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': '4c2c6b99-7cc7-425f-ba73-66247ee25652', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:08:03,217 - INFO - Created VN vn1 2026-02-02 04:08:03,230 - DEBUG - VN vn1 UUID is 4c2c6b99-7cc7-425f-ba73-66247ee25652 2026-02-02 04:08:03,553 - DEBUG - Response for create_network : {'network': {'id': 'e4c262c0-901f-4b6d-9388-deec1fec9541', 'name': 'vn2', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'project_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-42281926', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-02-02 04:08:03,789 - DEBUG - Response for create_subnet : {'subnet': {'id': '00afdd1b-4aa8-4d7a-9006-1fa6ce05b303', 'name': '', 'tenant_id': '5c2d5f0b069e477aac0ac242bfd76b97', 'network_id': 'e4c262c0-901f-4b6d-9388-deec1fec9541', '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': '5c2d5f0b069e477aac0ac242bfd76b97'}} 2026-02-02 04:08:03,810 - INFO - Created VN vn2 2026-02-02 04:08:03,821 - DEBUG - VN vn2 UUID is e4c262c0-901f-4b6d-9388-deec1fec9541 2026-02-02 04:08:03,905 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-42281926: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-42281926: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-02-02 04:08:04,253 - INFO - Attaching policy sec_grp_policy_ctest-random-79937649 to vn vn1 2026-02-02 04:08:04,608 - INFO - Attaching policy sec_grp_policy_ctest-random-79937649 to vn vn2 2026-02-02 04:08:06,177 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-1) 2026-02-02 04:08:07,684 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5167-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5167-2) 2026-02-02 04:08:07,745 - INFO - ================================================================================ 2026-02-02 04:08:07,745 - INFO - STARTING TEST : test_create_md5_on_control 2026-02-02 04:08:07,745 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-02-02 04:08:09,033 - DEBUG - Skipping xmpp flap check 2026-02-02 04:08:09,033 - INFO - Initial checks done. Running the testcase now 2026-02-02 04:08:09,033 - INFO - 2026-02-02 04:08:09,033 - INFO - config md5 on each host with auth_data None 2026-02-02 04:08:09,033 - INFO - config per peer with auth_data None 2026-02-02 04:08:09,573 - INFO - configure MD5 on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:08:09,669 - INFO - config per peer with auth_data None 2026-02-02 04:08:10,261 - INFO - configure MD5 on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:08:10,345 - INFO - config per peer with auth_data None 2026-02-02 04:08:10,958 - INFO - configure MD5 on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:08:11,049 - INFO - check if BGP between peers are up before setting md5 2026-02-02 04:08:11,049 - INFO - Check BGP staus between peers 2026-02-02 04:08:11,058 - INFO - setting MD5 config on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:08:11,058 - INFO - configure MD5 on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:08:11,137 - INFO - setting MD5 config on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:08:11,137 - INFO - configure MD5 on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:08:11,217 - INFO - setting MD5 config on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:08:11,217 - INFO - configure MD5 on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:08:11,297 - INFO - check if BGP peering is up after md5 config 2026-02-02 04:08:11,297 - INFO - Check BGP staus between peers 2026-02-02 04:08:11,307 - DEBUG - Check the BGP connection on 10.0.0.141 2026-02-02 04:08:11,307 - DEBUG - Check the BGP connection on 10.0.0.141 2026-02-02 04:08:21,307 - INFO - Check BGP staus between peers 2026-02-02 04:08:21,317 - INFO - remove md5 configured on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:08:21,318 - INFO - config per peer with auth_data None 2026-02-02 04:08:21,853 - INFO - configure MD5 on host 25875195-1ffb-4d61-821a-6cb353c15b76 2026-02-02 04:08:21,933 - INFO - remove md5 configured on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:08:21,933 - INFO - config per peer with auth_data None 2026-02-02 04:08:22,505 - INFO - configure MD5 on host 0c6ce2d3-a644-44f9-b95f-0dbb4d176f74 2026-02-02 04:08:22,589 - INFO - remove md5 configured on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:08:22,589 - INFO - config per peer with auth_data None 2026-02-02 04:08:23,169 - INFO - configure MD5 on host 3e85cb7d-ccd7-44c5-abb8-9e8cbe77eb64 2026-02-02 04:08:23,253 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-02-02 04:08:23,253 - INFO - Deleting VM vn2_vm_0 2026-02-02 04:08:23,253 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-02 04:08:23,253 - ERROR - Nova failed to get host of the VM 2026-02-02 04:08:23,253 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-02 04:08:23,253 - ERROR - Nova failed to get host of the VM 2026-02-02 04:08:23,325 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-02-02 04:08:23,325 - INFO - Deleting VM vn1_vm_0 2026-02-02 04:08:23,325 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-02 04:08:23,325 - ERROR - Nova failed to get host of the VM 2026-02-02 04:08:23,325 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-02 04:08:23,325 - ERROR - Nova failed to get host of the VM 2026-02-02 04:08:23,398 - INFO - Dettaching policy sec_grp_policy_ctest-random-79937649 from vn vn2 2026-02-02 04:08:23,565 - INFO - Dettaching policy sec_grp_policy_ctest-random-79937649 from vn vn1 2026-02-02 04:08:23,792 - INFO - Deleted policy sec_grp_policy_ctest-random-79937649 2026-02-02 04:08:23,792 - INFO - Deleting VN vn2 2026-02-02 04:08:23,849 - DEBUG - VN e4c262c0-901f-4b6d-9388-deec1fec9541 still in use: Unable to complete operation on network e4c262c0-901f-4b6d-9388-deec1fec9541. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0a35b68c-91b0-4984-92c8-a527b68aa007'] 2026-02-02 04:08:23,850 - WARNING - Deleting VN vn2 failed..Will retry 2026-02-02 04:08:25,987 - DEBUG - Response for deleting network () 2026-02-02 04:08:25,987 - INFO - Deleting VN vn1 2026-02-02 04:08:26,128 - DEBUG - Response for deleting network () 2026-02-02 04:08:26,128 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-02-02 04:08:26,307 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-02-02 04:08:26,527 - INFO - Deleted policy allow_all 2026-02-02 04:08:26,527 - INFO - Deleting VM dest_vm 2026-02-02 04:08:26,605 - INFO - Deleting VM source_vm 2026-02-02 04:08:26,670 - INFO - Deleting VN test_vnv6dn 2026-02-02 04:08:26,714 - DEBUG - VN a31b19d1-fb4e-4d04-bf2f-a94800e09078 still in use: Unable to complete operation on network a31b19d1-fb4e-4d04-bf2f-a94800e09078. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-39905d18-865e-48ca-9dd4-3df5c2752f3c'] 2026-02-02 04:08:26,714 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-02-02 04:08:28,870 - DEBUG - Response for deleting network () 2026-02-02 04:08:28,870 - INFO - Deleting VN test_vnv6sr 2026-02-02 04:08:29,053 - DEBUG - Response for deleting network () 2026-02-02 04:08:30,333 - DEBUG - Skipping xmpp flap check
2026-02-02 04:08:30,333 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-02-02 04:08:30,333 - INFO - -------------------------------------------------------------------------------- 2026-02-02 04:08:31,072 - INFO - Deleted project: ctest-TestMd5testsOnControl-42281926, ID : 5c2d5f0b-069e-477a-ac0a-c242bfd76b97