2026-05-07 04:47:29,639 - INFO - Domain Default found not creating 2026-05-07 04:47:29,858 - INFO - Project ctest-TestMd5testsOnControl-28277397 not found, creating it 2026-05-07 04:47:30,488 - INFO - Created Project:ctest-TestMd5testsOnControl-28277397, ID : 2ae9a4b1-6b9a-4f15-9e81-55d511cd9973 2026-05-07 04:47:33,081 - DEBUG - Response for create_network : {'network': {'id': 'be4e85cd-af57-4d69-8af1-68878e5d1f87', 'name': 'test_vnv6sr', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:47:33,451 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea67971c-cb37-46be-a849-e97a9a3362ce', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'be4e85cd-af57-4d69-8af1-68878e5d1f87', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:47:33,822 - DEBUG - Response for create_subnet : {'subnet': {'id': '23095188-7d12-4ee5-803b-611d0bd58a7e', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'be4e85cd-af57-4d69-8af1-68878e5d1f87', 'ip_version': 4, 'cidr': '197.98.120.192/26', 'allocation_pools': [{'start': '197.98.120.194', 'end': '197.98.120.254'}], 'gateway_ip': '197.98.120.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '197.98.120.194', 'tags': [], 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:47:33,884 - INFO - Created VN test_vnv6sr 2026-05-07 04:47:33,907 - DEBUG - VN test_vnv6sr UUID is be4e85cd-af57-4d69-8af1-68878e5d1f87 2026-05-07 04:47:34,403 - DEBUG - Response for create_network : {'network': {'id': '236efaf5-fba6-416c-8f83-ae6d7f337531', 'name': 'test_vnv6dn', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:47:34,680 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ac23e989-a9ba-492f-b4d7-a3532e295b54', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': '236efaf5-fba6-416c-8f83-ae6d7f337531', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:47:34,986 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd545515c-0c39-4cf9-8a80-4330c1e92d79', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': '236efaf5-fba6-416c-8f83-ae6d7f337531', 'ip_version': 4, 'cidr': '92.239.208.64/26', 'allocation_pools': [{'start': '92.239.208.66', 'end': '92.239.208.126'}], 'gateway_ip': '92.239.208.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '92.239.208.66', 'tags': [], 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:47:35,022 - INFO - Created VN test_vnv6dn 2026-05-07 04:47:35,043 - DEBUG - VN test_vnv6dn UUID is 236efaf5-fba6-416c-8f83-ae6d7f337531 2026-05-07 04:47:35,424 - DEBUG - Services list from nova: [, , , ] 2026-05-07 04:47:37,005 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-2) 2026-05-07 04:47:38,871 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-1) 2026-05-07 04:47:38,871 - INFO - Waiting for VM source_vm to be up.. 2026-05-07 04:47:38,986 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-05-07 04:47:44,101 - DEBUG - VM is in ACTIVE state now 2026-05-07 04:47:44,102 - INFO - VM name : source_vm 2026-05-07 04:47:44,215 - DEBUG - VM source_vm ID is d7630c3e-8392-43c4-a9c6-ca1ad6fe450a 2026-05-07 04:47:44,263 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5754-2 2026-05-07 04:47:44,390 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/d7630c3e-8392-43c4-a9c6-ca1ad6fe450a 2026-05-07 04:47:44,703 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/d7630c3e-8392-43c4-a9c6-ca1ad6fe450a 2026-05-07 04:47:44,752 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/85b2faf8-5d3f-4e23-b6f8-90321564bb2f 2026-05-07 04:47:47,947 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1002ms') 2026-05-07 04:47:47,947 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM source_vm failed! 2026-05-07 04:47:47,966 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28277397:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-05-07 04:47:47,966 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28277397:test_vnv6sr is 197.98.120.193 and allocation pool is NOT set 2026-05-07 04:47:50,039 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=4.31 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.978 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.978/2.642/4.307/1.664 ms') 2026-05-07 04:47:50,039 - INFO - Ping to Metadata IP 169.254.0.4 of VM source_vm passed 2026-05-07 04:47:50,117 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-07 04:47:50,117 - DEBUG - Waiting to SSH to VM source_vm, IP 197.98.120.195, Port 22 2026-05-07 04:47:50,287 - DEBUG - VM source_vm is ready for SSH connections 2026-05-07 04:47:50,287 - INFO - Waiting for VM dest_vm to be up.. 2026-05-07 04:47:50,430 - DEBUG - VM is in ACTIVE state now 2026-05-07 04:47:50,430 - INFO - VM name : dest_vm 2026-05-07 04:47:50,549 - DEBUG - VM dest_vm ID is b292f56d-62c2-43e9-9fe5-659a21efb57a 2026-05-07 04:47:50,549 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5754-1 2026-05-07 04:47:50,686 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/b292f56d-62c2-43e9-9fe5-659a21efb57a 2026-05-07 04:47:50,706 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/05638df8-d811-4c05-bda3-df7b150ae1d2 2026-05-07 04:47:53,943 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.62 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1031ms\r\nrtt min/avg/max/mdev = 3.624/3.624/3.624/0.000 ms') 2026-05-07 04:47:53,943 - INFO - Ping to Metadata IP 169.254.0.4 of VM dest_vm passed 2026-05-07 04:47:54,022 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-07 04:47:54,022 - DEBUG - Waiting to SSH to VM dest_vm, IP 92.239.208.67, Port 22 2026-05-07 04:47:54,196 - DEBUG - VM dest_vm is ready for SSH connections 2026-05-07 04:47:54,210 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28277397: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-28277397: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-05-07 04:47:54,601 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-05-07 04:47:55,091 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-05-07 04:47:55,407 - INFO - Configure the policy with allow any 2026-05-07 04:47:55,835 - DEBUG - Response for create_network : {'network': {'id': 'ad8bc106-a203-488c-abe1-414f4cd6967d', 'name': 'vn1', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:47:56,136 - DEBUG - Response for create_subnet : {'subnet': {'id': '0d449aa4-0a4e-484d-a80a-f5f430f5c4f8', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'ad8bc106-a203-488c-abe1-414f4cd6967d', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:47:56,166 - INFO - Created VN vn1 2026-05-07 04:47:56,186 - DEBUG - VN vn1 UUID is ad8bc106-a203-488c-abe1-414f4cd6967d 2026-05-07 04:47:56,692 - DEBUG - Response for create_network : {'network': {'id': 'b7510d48-9269-4450-904c-2f2f1cb99c1d', 'name': 'vn2', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:47:56,998 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ad67a75d-00eb-40bb-9144-f999acc43228', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'b7510d48-9269-4450-904c-2f2f1cb99c1d', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:47:57,024 - INFO - Created VN vn2 2026-05-07 04:47:57,038 - DEBUG - VN vn2 UUID is b7510d48-9269-4450-904c-2f2f1cb99c1d 2026-05-07 04:47:57,145 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28277397: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-28277397: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-05-07 04:47:57,531 - INFO - Attaching policy sec_grp_policy_ctest-random-35860038 to vn vn1 2026-05-07 04:47:57,960 - INFO - Attaching policy sec_grp_policy_ctest-random-35860038 to vn vn2 2026-05-07 04:47:59,833 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-2) 2026-05-07 04:48:01,784 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-1) 2026-05-07 04:48:01,855 - INFO - ================================================================================ 2026-05-07 04:48:01,856 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-05-07 04:48:01,857 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-05-07 04:48:03,209 - DEBUG - Skipping xmpp flap check 2026-05-07 04:48:03,209 - INFO - Initial checks done. Running the testcase now 2026-05-07 04:48:03,209 - INFO - 2026-05-07 04:48:03,209 - INFO - config per peer with auth_data None 2026-05-07 04:48:03,886 - INFO - configure MD5 on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:48:03,999 - INFO - config per peer with auth_data None 2026-05-07 04:48:04,778 - INFO - configure MD5 on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:48:04,886 - INFO - config per peer with auth_data None 2026-05-07 04:48:05,650 - INFO - configure MD5 on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:48:05,766 - INFO - check BGP peering is up before setting md5 config 2026-05-07 04:48:05,768 - INFO - Check BGP staus between peers 2026-05-07 04:48:05,801 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-05-07 04:48:06,550 - INFO - Check BGP staus between peers 2026-05-07 04:48:06,571 - DEBUG - Check the BGP connection on 10.0.0.141 2026-05-07 04:48:06,571 - DEBUG - Check the BGP connection on 10.0.0.141 2026-05-07 04:48:16,572 - INFO - Check BGP staus between peers 2026-05-07 04:48:16,589 - INFO - remove md5 configured on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:48:16,589 - INFO - config per peer with auth_data None 2026-05-07 04:48:17,250 - INFO - configure MD5 on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:48:17,350 - INFO - remove md5 configured on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:48:17,350 - INFO - config per peer with auth_data None 2026-05-07 04:48:18,040 - INFO - configure MD5 on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:48:18,150 - INFO - remove md5 configured on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:48:18,151 - INFO - config per peer with auth_data None 2026-05-07 04:48:18,862 - INFO - configure MD5 on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:48:18,967 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-05-07 04:48:18,967 - INFO - Deleting VM vn2_vm_0 2026-05-07 04:48:18,967 - ERROR - VM vn2_vm_0 has failed to come up 2026-05-07 04:48:18,967 - ERROR - Nova failed to get host of the VM 2026-05-07 04:48:18,967 - ERROR - VM vn2_vm_0 has failed to come up 2026-05-07 04:48:18,967 - ERROR - Nova failed to get host of the VM 2026-05-07 04:48:19,069 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-05-07 04:48:19,069 - INFO - Deleting VM vn1_vm_0 2026-05-07 04:48:19,069 - ERROR - VM vn1_vm_0 has failed to come up 2026-05-07 04:48:19,069 - ERROR - Nova failed to get host of the VM 2026-05-07 04:48:19,069 - ERROR - VM vn1_vm_0 has failed to come up 2026-05-07 04:48:19,069 - ERROR - Nova failed to get host of the VM 2026-05-07 04:48:19,166 - INFO - Dettaching policy sec_grp_policy_ctest-random-35860038 from vn vn2 2026-05-07 04:48:19,371 - INFO - Dettaching policy sec_grp_policy_ctest-random-35860038 from vn vn1 2026-05-07 04:48:19,683 - INFO - Deleted policy sec_grp_policy_ctest-random-35860038 2026-05-07 04:48:19,684 - INFO - Deleting VN vn2 2026-05-07 04:48:19,808 - DEBUG - VN b7510d48-9269-4450-904c-2f2f1cb99c1d still in use: Unable to complete operation on network b7510d48-9269-4450-904c-2f2f1cb99c1d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-1c9bbdca-5bcb-431b-a98d-7a0037cccd99'] 2026-05-07 04:48:19,808 - WARNING - Deleting VN vn2 failed..Will retry 2026-05-07 04:48:21,995 - DEBUG - Response for deleting network () 2026-05-07 04:48:21,995 - INFO - Deleting VN vn1 2026-05-07 04:48:22,224 - DEBUG - Response for deleting network () 2026-05-07 04:48:22,224 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-05-07 04:48:22,451 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-05-07 04:48:22,767 - INFO - Deleted policy allow_all 2026-05-07 04:48:22,767 - INFO - Deleting VM dest_vm 2026-05-07 04:48:22,888 - INFO - Deleting VM source_vm 2026-05-07 04:48:23,015 - INFO - Deleting VN test_vnv6dn 2026-05-07 04:48:23,074 - DEBUG - VN 236efaf5-fba6-416c-8f83-ae6d7f337531 still in use: Unable to complete operation on network 236efaf5-fba6-416c-8f83-ae6d7f337531. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a5471b89-8177-4adc-a9c4-1df13d27a699'] 2026-05-07 04:48:23,074 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-05-07 04:48:25,334 - DEBUG - Response for deleting network () 2026-05-07 04:48:25,334 - INFO - Deleting VN test_vnv6sr 2026-05-07 04:48:25,560 - DEBUG - Response for deleting network () 2026-05-07 04:48:26,877 - DEBUG - Skipping xmpp flap check
2026-05-07 04:48:26,877 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:25]
2026-05-07 04:48:26,877 - INFO - -------------------------------------------------------------------------------- 2026-05-07 04:48:27,229 - DEBUG - Response for create_network : {'network': {'id': '7a33df74-48e2-47f1-be14-5e4580751dfd', 'name': 'test_vnv6sr', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:48:27,491 - DEBUG - Response for create_subnet : {'subnet': {'id': '3a9bc6c9-0d3f-4854-8290-e8d54ea34312', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': '7a33df74-48e2-47f1-be14-5e4580751dfd', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:48:27,736 - DEBUG - Response for create_subnet : {'subnet': {'id': 'db61de65-fdbe-4d05-8e17-520f9f126662', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': '7a33df74-48e2-47f1-be14-5e4580751dfd', 'ip_version': 4, 'cidr': '52.86.132.64/26', 'allocation_pools': [{'start': '52.86.132.66', 'end': '52.86.132.126'}], 'gateway_ip': '52.86.132.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.86.132.66', 'tags': [], 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:48:27,769 - INFO - Created VN test_vnv6sr 2026-05-07 04:48:27,787 - DEBUG - VN test_vnv6sr UUID is 7a33df74-48e2-47f1-be14-5e4580751dfd 2026-05-07 04:48:28,280 - DEBUG - Response for create_network : {'network': {'id': 'ba1fb498-b6ce-48eb-aea5-3c0033126a88', 'name': 'test_vnv6dn', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:48:28,576 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eebddd8d-ae7d-4bc6-b0c2-bdc373159c81', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'ba1fb498-b6ce-48eb-aea5-3c0033126a88', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:48:28,888 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c3833beb-4041-41db-bb63-7c2b4b50f31c', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'ba1fb498-b6ce-48eb-aea5-3c0033126a88', 'ip_version': 4, 'cidr': '12.62.235.0/26', 'allocation_pools': [{'start': '12.62.235.2', 'end': '12.62.235.62'}], 'gateway_ip': '12.62.235.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '12.62.235.2', 'tags': [], 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:48:28,936 - INFO - Created VN test_vnv6dn 2026-05-07 04:48:28,957 - DEBUG - VN test_vnv6dn UUID is ba1fb498-b6ce-48eb-aea5-3c0033126a88 2026-05-07 04:48:30,513 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-2) 2026-05-07 04:48:32,237 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-1) 2026-05-07 04:48:32,237 - INFO - Waiting for VM source_vm to be up.. 2026-05-07 04:48:32,410 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-05-07 04:48:37,521 - DEBUG - VM is in ACTIVE state now 2026-05-07 04:48:37,521 - INFO - VM name : source_vm 2026-05-07 04:48:37,645 - DEBUG - VM source_vm ID is 1ab4f47d-10e9-4cc1-bccd-83912d9b79c5 2026-05-07 04:48:37,645 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5754-2 2026-05-07 04:48:37,759 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/1ab4f47d-10e9-4cc1-bccd-83912d9b79c5 2026-05-07 04:48:37,773 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/38db65fa-0aa7-4a6d-810b-5edc11fa1e37 2026-05-07 04:48:41,002 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2026-05-07 04:48:41,002 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM source_vm failed! 2026-05-07 04:48:41,019 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28277397:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-05-07 04:48:41,020 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-28277397:test_vnv6sr is 52.86.132.65 and allocation pool is NOT set 2026-05-07 04:48:43,087 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=7.81 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.303 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.303/4.054/7.805/3.751 ms') 2026-05-07 04:48:43,087 - INFO - Ping to Metadata IP 169.254.0.4 of VM source_vm passed 2026-05-07 04:48:43,168 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-07 04:48:43,168 - DEBUG - Waiting to SSH to VM source_vm, IP 52.86.132.67, Port 22 2026-05-07 04:48:43,337 - DEBUG - VM source_vm is ready for SSH connections 2026-05-07 04:48:43,337 - INFO - Waiting for VM dest_vm to be up.. 2026-05-07 04:48:43,458 - DEBUG - VM is in ACTIVE state now 2026-05-07 04:48:43,458 - INFO - VM name : dest_vm 2026-05-07 04:48:43,571 - DEBUG - VM dest_vm ID is 0c67d057-8712-4edd-85a6-960f6768f89b 2026-05-07 04:48:43,571 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5754-1 2026-05-07 04:48:43,699 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0c67d057-8712-4edd-85a6-960f6768f89b 2026-05-07 04:48:43,714 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/9d803d13-20fd-4733-98ef-82f9fa51b545 2026-05-07 04:48:44,900 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=6.38 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.16 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.157/3.770/6.384/2.613 ms') 2026-05-07 04:48:44,900 - INFO - Ping to Metadata IP 169.254.0.4 of VM dest_vm passed 2026-05-07 04:48:44,978 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-07 04:48:44,979 - DEBUG - Waiting to SSH to VM dest_vm, IP 12.62.235.3, Port 22 2026-05-07 04:48:45,048 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-05-07 04:48:45,160 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-05-07 04:48:50,160 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-07 04:48:50,160 - DEBUG - Waiting to SSH to VM dest_vm, IP 12.62.235.3, Port 22 2026-05-07 04:48:50,318 - DEBUG - VM dest_vm is ready for SSH connections 2026-05-07 04:48:50,328 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28277397: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-28277397: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-05-07 04:48:50,724 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-05-07 04:48:51,176 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-05-07 04:48:51,455 - INFO - Configure the policy with allow any 2026-05-07 04:48:51,819 - DEBUG - Response for create_network : {'network': {'id': 'cdd95961-d8c1-4879-993b-1e6f48f14d63', 'name': 'vn1', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:48:52,165 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cc522e72-ac36-4b6b-a06a-6f2f535d871a', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'cdd95961-d8c1-4879-993b-1e6f48f14d63', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:48:52,208 - INFO - Created VN vn1 2026-05-07 04:48:52,223 - DEBUG - VN vn1 UUID is cdd95961-d8c1-4879-993b-1e6f48f14d63 2026-05-07 04:48:52,697 - DEBUG - Response for create_network : {'network': {'id': 'd9498181-9c94-4951-8f05-82acb58473f4', 'name': 'vn2', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'project_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-28277397', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-05-07 04:48:52,937 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a9993a73-c70c-4986-b69c-9a82862d6471', 'name': '', 'tenant_id': '2ae9a4b16b9a4f159e8155d511cd9973', 'network_id': 'd9498181-9c94-4951-8f05-82acb58473f4', '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': '2ae9a4b16b9a4f159e8155d511cd9973'}} 2026-05-07 04:48:52,966 - INFO - Created VN vn2 2026-05-07 04:48:52,986 - DEBUG - VN vn2 UUID is d9498181-9c94-4951-8f05-82acb58473f4 2026-05-07 04:48:53,089 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-28277397: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-28277397: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-05-07 04:48:53,462 - INFO - Attaching policy sec_grp_policy_ctest-random-75331026 to vn vn1 2026-05-07 04:48:53,864 - INFO - Attaching policy sec_grp_policy_ctest-random-75331026 to vn vn2 2026-05-07 04:48:55,688 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-2) 2026-05-07 04:48:57,442 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5754-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5754-1) 2026-05-07 04:48:57,522 - INFO - ================================================================================ 2026-05-07 04:48:57,523 - INFO - STARTING TEST : test_create_md5_on_control 2026-05-07 04:48:57,523 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-05-07 04:48:58,854 - DEBUG - Skipping xmpp flap check 2026-05-07 04:48:58,854 - INFO - Initial checks done. Running the testcase now 2026-05-07 04:48:58,854 - INFO - 2026-05-07 04:48:58,854 - INFO - config md5 on each host with auth_data None 2026-05-07 04:48:58,854 - INFO - config per peer with auth_data None 2026-05-07 04:48:59,542 - INFO - configure MD5 on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:48:59,648 - INFO - config per peer with auth_data None 2026-05-07 04:49:00,386 - INFO - configure MD5 on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:49:00,490 - INFO - config per peer with auth_data None 2026-05-07 04:49:01,258 - INFO - configure MD5 on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:49:01,362 - INFO - check if BGP between peers are up before setting md5 2026-05-07 04:49:01,363 - INFO - Check BGP staus between peers 2026-05-07 04:49:01,378 - INFO - setting MD5 config on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:49:01,378 - INFO - configure MD5 on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:49:01,474 - INFO - setting MD5 config on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:49:01,475 - INFO - configure MD5 on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:49:01,566 - INFO - setting MD5 config on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:49:01,566 - INFO - configure MD5 on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:49:01,662 - INFO - check if BGP peering is up after md5 config 2026-05-07 04:49:01,662 - INFO - Check BGP staus between peers 2026-05-07 04:49:01,672 - DEBUG - Check the BGP connection on 10.0.0.141 2026-05-07 04:49:01,672 - DEBUG - Check the BGP connection on 10.0.0.141 2026-05-07 04:49:11,672 - INFO - Check BGP staus between peers 2026-05-07 04:49:11,684 - INFO - remove md5 configured on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:49:11,684 - INFO - config per peer with auth_data None 2026-05-07 04:49:12,370 - INFO - configure MD5 on host 30153f86-2567-48d0-b446-fa5227497dd2 2026-05-07 04:49:12,487 - INFO - remove md5 configured on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:49:12,487 - INFO - config per peer with auth_data None 2026-05-07 04:49:13,178 - INFO - configure MD5 on host d4f79f6c-c7b7-488d-a929-a1e37c883025 2026-05-07 04:49:13,298 - INFO - remove md5 configured on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:49:13,298 - INFO - config per peer with auth_data None 2026-05-07 04:49:14,006 - INFO - configure MD5 on host 33778e82-2b1a-4704-80a9-641e5e09fa6e 2026-05-07 04:49:14,098 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-05-07 04:49:14,098 - INFO - Deleting VM vn2_vm_0 2026-05-07 04:49:14,098 - ERROR - VM vn2_vm_0 has failed to come up 2026-05-07 04:49:14,098 - ERROR - Nova failed to get host of the VM 2026-05-07 04:49:14,099 - ERROR - VM vn2_vm_0 has failed to come up 2026-05-07 04:49:14,099 - ERROR - Nova failed to get host of the VM 2026-05-07 04:49:14,206 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-05-07 04:49:14,207 - INFO - Deleting VM vn1_vm_0 2026-05-07 04:49:14,207 - ERROR - VM vn1_vm_0 has failed to come up 2026-05-07 04:49:14,207 - ERROR - Nova failed to get host of the VM 2026-05-07 04:49:14,207 - ERROR - VM vn1_vm_0 has failed to come up 2026-05-07 04:49:14,207 - ERROR - Nova failed to get host of the VM 2026-05-07 04:49:14,318 - INFO - Dettaching policy sec_grp_policy_ctest-random-75331026 from vn vn2 2026-05-07 04:49:14,546 - INFO - Dettaching policy sec_grp_policy_ctest-random-75331026 from vn vn1 2026-05-07 04:49:14,873 - INFO - Deleted policy sec_grp_policy_ctest-random-75331026 2026-05-07 04:49:14,873 - INFO - Deleting VN vn2 2026-05-07 04:49:14,955 - DEBUG - VN d9498181-9c94-4951-8f05-82acb58473f4 still in use: Unable to complete operation on network d9498181-9c94-4951-8f05-82acb58473f4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-31fbaa34-845b-4994-b77a-f8db6735e95c'] 2026-05-07 04:49:14,955 - WARNING - Deleting VN vn2 failed..Will retry 2026-05-07 04:49:17,168 - DEBUG - Response for deleting network () 2026-05-07 04:49:17,168 - INFO - Deleting VN vn1 2026-05-07 04:49:17,393 - DEBUG - Response for deleting network () 2026-05-07 04:49:17,393 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-05-07 04:49:17,618 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-05-07 04:49:17,903 - INFO - Deleted policy allow_all 2026-05-07 04:49:17,903 - INFO - Deleting VM dest_vm 2026-05-07 04:49:18,026 - INFO - Deleting VM source_vm 2026-05-07 04:49:18,151 - INFO - Deleting VN test_vnv6dn 2026-05-07 04:49:18,241 - DEBUG - VN ba1fb498-b6ce-48eb-aea5-3c0033126a88 still in use: Unable to complete operation on network ba1fb498-b6ce-48eb-aea5-3c0033126a88. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2eb54b4d-1bec-442c-8399-f0ea058c2e8c'] 2026-05-07 04:49:18,242 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-05-07 04:49:20,489 - DEBUG - Response for deleting network () 2026-05-07 04:49:20,490 - INFO - Deleting VN test_vnv6sr 2026-05-07 04:49:20,806 - DEBUG - Response for deleting network () 2026-05-07 04:49:22,117 - DEBUG - Skipping xmpp flap check
2026-05-07 04:49:22,118 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:25]
2026-05-07 04:49:22,118 - INFO - -------------------------------------------------------------------------------- 2026-05-07 04:49:22,981 - INFO - Deleted project: ctest-TestMd5testsOnControl-28277397, ID : 2ae9a4b1-6b9a-4f15-9e81-55d511cd9973