2026-04-06 04:27:15,733 - INFO - Domain Default found not creating 2026-04-06 04:27:15,883 - INFO - Project ctest-TestMd5testsOnControl-06821378 not found, creating it 2026-04-06 04:27:16,427 - INFO - Created Project:ctest-TestMd5testsOnControl-06821378, ID : fd70ed1e-6f96-4404-93fc-d804e87e4312 2026-04-06 04:27:18,611 - DEBUG - Response for create_network : {'network': {'id': '75180765-a445-4cc7-be26-b1b61894d8f5', 'name': 'test_vnv6sr', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:27:18,932 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4439264-0c3e-4d55-9700-c3fd01e3d0ab', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '75180765-a445-4cc7-be26-b1b61894d8f5', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:27:19,223 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f5c95864-697a-4fca-aa82-c909290d04cd', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '75180765-a445-4cc7-be26-b1b61894d8f5', 'ip_version': 4, 'cidr': '21.104.68.128/26', 'allocation_pools': [{'start': '21.104.68.130', 'end': '21.104.68.190'}], 'gateway_ip': '21.104.68.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '21.104.68.130', 'tags': [], 'project_id': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:27:19,253 - INFO - Created VN test_vnv6sr 2026-04-06 04:27:19,269 - DEBUG - VN test_vnv6sr UUID is 75180765-a445-4cc7-be26-b1b61894d8f5 2026-04-06 04:27:19,642 - DEBUG - Response for create_network : {'network': {'id': '48347392-efc3-47b2-a75f-110c6c2e5602', 'name': 'test_vnv6dn', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:27:19,865 - DEBUG - Response for create_subnet : {'subnet': {'id': '4fcda4a7-157c-4f03-8564-1a85efd75da1', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '48347392-efc3-47b2-a75f-110c6c2e5602', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:27:20,138 - DEBUG - Response for create_subnet : {'subnet': {'id': '14238b4d-4c87-40ba-99e9-431ee37b0812', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '48347392-efc3-47b2-a75f-110c6c2e5602', 'ip_version': 4, 'cidr': '151.97.247.128/26', 'allocation_pools': [{'start': '151.97.247.130', 'end': '151.97.247.190'}], 'gateway_ip': '151.97.247.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '151.97.247.130', 'tags': [], 'project_id': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:27:20,172 - INFO - Created VN test_vnv6dn 2026-04-06 04:27:20,187 - DEBUG - VN test_vnv6dn UUID is 48347392-efc3-47b2-a75f-110c6c2e5602 2026-04-06 04:27:20,542 - DEBUG - Services list from nova: [, , , ] 2026-04-06 04:27:21,669 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-2) 2026-04-06 04:27:23,062 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-1) 2026-04-06 04:27:23,062 - INFO - Waiting for VM source_vm to be up.. 2026-04-06 04:27:23,155 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-06 04:27:28,244 - DEBUG - VM is in ACTIVE state now 2026-04-06 04:27:28,244 - INFO - VM name : source_vm 2026-04-06 04:27:28,324 - DEBUG - VM source_vm ID is e2dda073-eaee-4ada-aa2c-7ff1f8f31de2 2026-04-06 04:27:28,350 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5596-2 2026-04-06 04:27:28,429 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/e2dda073-eaee-4ada-aa2c-7ff1f8f31de2 2026-04-06 04:27:28,723 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/e2dda073-eaee-4ada-aa2c-7ff1f8f31de2 2026-04-06 04:27:28,775 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/ebcc4468-cadd-4c82-96e6-0c8c324588aa 2026-04-06 04:27:31,970 - 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 1022ms') 2026-04-06 04:27:31,970 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-04-06 04:27:31,983 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06821378:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-04-06 04:27:31,983 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06821378:test_vnv6sr is 21.104.68.129 and allocation pool is NOT set 2026-04-06 04:27:34,046 - 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.22 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.18 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 = 1.179/2.697/4.215/1.518 ms') 2026-04-06 04:27:34,046 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-04-06 04:27:34,122 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 04:27:34,122 - DEBUG - Waiting to SSH to VM source_vm, IP 21.104.68.131, Port 22 2026-04-06 04:27:34,305 - DEBUG - VM source_vm is ready for SSH connections 2026-04-06 04:27:34,305 - INFO - Waiting for VM dest_vm to be up.. 2026-04-06 04:27:34,392 - DEBUG - VM is in ACTIVE state now 2026-04-06 04:27:34,392 - INFO - VM name : dest_vm 2026-04-06 04:27:34,485 - DEBUG - VM dest_vm ID is 158ff0b3-0ef7-4a22-bd61-c312a1c35520 2026-04-06 04:27:34,486 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5596-1 2026-04-06 04:27:34,577 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/158ff0b3-0ef7-4a22-bd61-c312a1c35520 2026-04-06 04:27:34,588 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/db528fa3-b277-4da7-bd30-e381064396a4 2026-04-06 04:27:35,755 - 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.22 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.576 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.576/2.399/4.223/1.823 ms') 2026-04-06 04:27:35,755 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-04-06 04:27:35,830 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 04:27:35,831 - DEBUG - Waiting to SSH to VM dest_vm, IP 151.97.247.131, Port 22 2026-04-06 04:27:35,986 - DEBUG - VM dest_vm is ready for SSH connections 2026-04-06 04:27:35,992 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06821378: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-06821378: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-04-06 04:27:36,351 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-04-06 04:27:36,731 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-04-06 04:27:37,030 - INFO - Configure the policy with allow any 2026-04-06 04:27:37,351 - DEBUG - Response for create_network : {'network': {'id': '37994366-581d-45e3-8742-6675418c6f2d', 'name': 'vn1', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:27:37,601 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd7deadf3-3a5a-4a04-9bb8-a688f3f53593', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '37994366-581d-45e3-8742-6675418c6f2d', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:27:37,625 - INFO - Created VN vn1 2026-04-06 04:27:37,639 - DEBUG - VN vn1 UUID is 37994366-581d-45e3-8742-6675418c6f2d 2026-04-06 04:27:38,038 - DEBUG - Response for create_network : {'network': {'id': '2c8bcbca-2744-4b38-b580-cb5c08b79d0b', 'name': 'vn2', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:27:38,252 - DEBUG - Response for create_subnet : {'subnet': {'id': '4cfeb7da-9947-43ab-8ddf-3d4c3a54d475', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '2c8bcbca-2744-4b38-b580-cb5c08b79d0b', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:27:38,278 - INFO - Created VN vn2 2026-04-06 04:27:38,290 - DEBUG - VN vn2 UUID is 2c8bcbca-2744-4b38-b580-cb5c08b79d0b 2026-04-06 04:27:38,374 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06821378: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-06821378: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-04-06 04:27:38,723 - INFO - Attaching policy sec_grp_policy_ctest-random-21057605 to vn vn1 2026-04-06 04:27:39,113 - INFO - Attaching policy sec_grp_policy_ctest-random-21057605 to vn vn2 2026-04-06 04:27:40,870 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-2) 2026-04-06 04:27:42,393 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-1) 2026-04-06 04:27:42,455 - INFO - ================================================================================ 2026-04-06 04:27:42,456 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-04-06 04:27:42,456 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-04-06 04:27:43,771 - DEBUG - Skipping xmpp flap check 2026-04-06 04:27:43,771 - INFO - Initial checks done. Running the testcase now 2026-04-06 04:27:43,771 - INFO - 2026-04-06 04:27:43,771 - INFO - config per peer with auth_data None 2026-04-06 04:27:44,571 - INFO - configure MD5 on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:27:44,667 - INFO - config per peer with auth_data None 2026-04-06 04:27:45,363 - INFO - configure MD5 on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:27:45,463 - INFO - config per peer with auth_data None 2026-04-06 04:27:46,091 - INFO - configure MD5 on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:27:46,191 - INFO - check BGP peering is up before setting md5 config 2026-04-06 04:27:46,192 - INFO - Check BGP staus between peers 2026-04-06 04:27:46,200 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-04-06 04:27:46,876 - INFO - Check BGP staus between peers 2026-04-06 04:27:46,884 - DEBUG - Check the BGP connection on 10.0.0.249 2026-04-06 04:27:46,884 - DEBUG - Check the BGP connection on 10.0.0.249 2026-04-06 04:27:56,885 - INFO - Check BGP staus between peers 2026-04-06 04:27:56,893 - INFO - remove md5 configured on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:27:56,893 - INFO - config per peer with auth_data None 2026-04-06 04:27:57,559 - INFO - configure MD5 on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:27:57,675 - INFO - remove md5 configured on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:27:57,675 - INFO - config per peer with auth_data None 2026-04-06 04:27:58,363 - INFO - configure MD5 on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:27:58,451 - INFO - remove md5 configured on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:27:58,451 - INFO - config per peer with auth_data None 2026-04-06 04:27:59,071 - INFO - configure MD5 on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:27:59,159 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-04-06 04:27:59,159 - INFO - Deleting VM vn2_vm_0 2026-04-06 04:27:59,159 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-06 04:27:59,159 - ERROR - Nova failed to get host of the VM 2026-04-06 04:27:59,159 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-06 04:27:59,159 - ERROR - Nova failed to get host of the VM 2026-04-06 04:27:59,230 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-04-06 04:27:59,231 - INFO - Deleting VM vn1_vm_0 2026-04-06 04:27:59,231 - ERROR - VM vn1_vm_0 has failed to come up 2026-04-06 04:27:59,231 - ERROR - Nova failed to get host of the VM 2026-04-06 04:27:59,231 - ERROR - VM vn1_vm_0 has failed to come up 2026-04-06 04:27:59,231 - ERROR - Nova failed to get host of the VM 2026-04-06 04:27:59,298 - INFO - Dettaching policy sec_grp_policy_ctest-random-21057605 from vn vn2 2026-04-06 04:27:59,493 - INFO - Dettaching policy sec_grp_policy_ctest-random-21057605 from vn vn1 2026-04-06 04:27:59,747 - INFO - Deleted policy sec_grp_policy_ctest-random-21057605 2026-04-06 04:27:59,748 - INFO - Deleting VN vn2 2026-04-06 04:27:59,795 - DEBUG - VN 2c8bcbca-2744-4b38-b580-cb5c08b79d0b still in use: Unable to complete operation on network 2c8bcbca-2744-4b38-b580-cb5c08b79d0b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2cdf134d-0d60-4ec4-9e9f-bc08d9846840'] 2026-04-06 04:27:59,795 - WARNING - Deleting VN vn2 failed..Will retry 2026-04-06 04:28:01,948 - DEBUG - Response for deleting network () 2026-04-06 04:28:01,948 - INFO - Deleting VN vn1 2026-04-06 04:28:02,128 - DEBUG - Response for deleting network () 2026-04-06 04:28:02,128 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-04-06 04:28:02,326 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-04-06 04:28:02,589 - INFO - Deleted policy allow_all 2026-04-06 04:28:02,589 - INFO - Deleting VM dest_vm 2026-04-06 04:28:02,654 - INFO - Deleting VM source_vm 2026-04-06 04:28:02,717 - INFO - Deleting VN test_vnv6dn 2026-04-06 04:28:02,764 - DEBUG - VN 48347392-efc3-47b2-a75f-110c6c2e5602 still in use: Unable to complete operation on network 48347392-efc3-47b2-a75f-110c6c2e5602. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3a6ef822-2fba-497d-837b-8daa0e6babf1'] 2026-04-06 04:28:02,764 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-04-06 04:28:04,982 - DEBUG - Response for deleting network () 2026-04-06 04:28:04,983 - INFO - Deleting VN test_vnv6sr 2026-04-06 04:28:05,164 - DEBUG - Response for deleting network () 2026-04-06 04:28:06,427 - DEBUG - Skipping xmpp flap check
2026-04-06 04:28:06,428 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2026-04-06 04:28:06,428 - INFO - -------------------------------------------------------------------------------- 2026-04-06 04:28:06,741 - DEBUG - Response for create_network : {'network': {'id': 'b4b8c76d-3ad8-4e1d-aee1-b90939b756fb', 'name': 'test_vnv6sr', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:28:06,958 - DEBUG - Response for create_subnet : {'subnet': {'id': '35734c44-34b0-47ef-ad3d-b74a8bab81ab', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': 'b4b8c76d-3ad8-4e1d-aee1-b90939b756fb', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:28:07,235 - DEBUG - Response for create_subnet : {'subnet': {'id': '31f6f643-9fd3-465a-9c06-627b5f34711b', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': 'b4b8c76d-3ad8-4e1d-aee1-b90939b756fb', 'ip_version': 4, 'cidr': '114.14.72.64/26', 'allocation_pools': [{'start': '114.14.72.66', 'end': '114.14.72.126'}], 'gateway_ip': '114.14.72.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.14.72.66', 'tags': [], 'project_id': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:28:07,261 - INFO - Created VN test_vnv6sr 2026-04-06 04:28:07,273 - DEBUG - VN test_vnv6sr UUID is b4b8c76d-3ad8-4e1d-aee1-b90939b756fb 2026-04-06 04:28:07,696 - DEBUG - Response for create_network : {'network': {'id': '481cea58-4b63-4e5d-a97c-dbfeccc162b1', 'name': 'test_vnv6dn', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:28:07,988 - DEBUG - Response for create_subnet : {'subnet': {'id': '875288ba-6603-4a9b-821f-b4919c6d6932', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '481cea58-4b63-4e5d-a97c-dbfeccc162b1', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:28:08,236 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a50bad59-2550-4bd8-bd9a-92714b8edc65', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '481cea58-4b63-4e5d-a97c-dbfeccc162b1', 'ip_version': 4, 'cidr': '117.175.242.192/26', 'allocation_pools': [{'start': '117.175.242.194', 'end': '117.175.242.254'}], 'gateway_ip': '117.175.242.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '117.175.242.194', 'tags': [], 'project_id': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:28:08,262 - INFO - Created VN test_vnv6dn 2026-04-06 04:28:08,274 - DEBUG - VN test_vnv6dn UUID is 481cea58-4b63-4e5d-a97c-dbfeccc162b1 2026-04-06 04:28:09,582 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-2) 2026-04-06 04:28:10,965 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-1) 2026-04-06 04:28:10,965 - INFO - Waiting for VM source_vm to be up.. 2026-04-06 04:28:11,047 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-06 04:28:16,131 - DEBUG - VM is in ACTIVE state now 2026-04-06 04:28:16,131 - INFO - VM name : source_vm 2026-04-06 04:28:16,230 - DEBUG - VM source_vm ID is e4b34963-4e03-4467-9741-7837733bedbd 2026-04-06 04:28:16,230 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5596-2 2026-04-06 04:28:16,311 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/e4b34963-4e03-4467-9741-7837733bedbd 2026-04-06 04:28:16,322 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/d1065bd0-d79b-4d4e-9143-51f3dce782ff 2026-04-06 04:28:19,489 - 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 1002ms') 2026-04-06 04:28:19,489 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-04-06 04:28:19,505 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06821378:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-04-06 04:28:19,505 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06821378:test_vnv6sr is 114.14.72.65 and allocation pool is NOT set 2026-04-06 04:28:21,569 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.17 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.853 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.853/3.512/6.172/2.659 ms') 2026-04-06 04:28:21,569 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-04-06 04:28:21,639 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 04:28:21,639 - DEBUG - Waiting to SSH to VM source_vm, IP 114.14.72.67, Port 22 2026-04-06 04:28:21,805 - DEBUG - VM source_vm is ready for SSH connections 2026-04-06 04:28:21,806 - INFO - Waiting for VM dest_vm to be up.. 2026-04-06 04:28:21,885 - DEBUG - VM is in ACTIVE state now 2026-04-06 04:28:21,885 - INFO - VM name : dest_vm 2026-04-06 04:28:21,968 - DEBUG - VM dest_vm ID is af4a5185-d324-47af-bba4-c05ab75d099b 2026-04-06 04:28:21,969 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5596-1 2026-04-06 04:28:22,064 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/af4a5185-d324-47af-bba4-c05ab75d099b 2026-04-06 04:28:22,077 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/18523db2-d55f-401f-bea7-9008a9b3ba79 2026-04-06 04:28:23,255 - 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=9.33 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.69 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.687/5.509/9.332/3.822 ms') 2026-04-06 04:28:23,255 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-04-06 04:28:23,324 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 04:28:23,324 - DEBUG - Waiting to SSH to VM dest_vm, IP 117.175.242.195, Port 22 2026-04-06 04:28:23,392 - 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-04-06 04:28:23,486 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-04-06 04:28:28,487 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 04:28:28,487 - DEBUG - Waiting to SSH to VM dest_vm, IP 117.175.242.195, Port 22 2026-04-06 04:28:28,653 - DEBUG - VM dest_vm is ready for SSH connections 2026-04-06 04:28:28,660 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06821378: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-06821378: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-04-06 04:28:29,008 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-04-06 04:28:29,391 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-04-06 04:28:29,710 - INFO - Configure the policy with allow any 2026-04-06 04:28:30,041 - DEBUG - Response for create_network : {'network': {'id': '3101aafa-643b-4bad-a148-5b1e0cc8f3f0', 'name': 'vn1', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:28:30,280 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e400c625-8419-42dc-9e93-90255eea80cf', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': '3101aafa-643b-4bad-a148-5b1e0cc8f3f0', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:28:30,304 - INFO - Created VN vn1 2026-04-06 04:28:30,318 - DEBUG - VN vn1 UUID is 3101aafa-643b-4bad-a148-5b1e0cc8f3f0 2026-04-06 04:28:30,722 - DEBUG - Response for create_network : {'network': {'id': 'c9fea640-c87a-42d4-9284-1f4dcf4f6954', 'name': 'vn2', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'project_id': 'fd70ed1e6f96440493fcd804e87e4312', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06821378', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 04:28:30,992 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f9f741ea-6d4a-468e-9f49-6600e0b398f5', 'name': '', 'tenant_id': 'fd70ed1e6f96440493fcd804e87e4312', 'network_id': 'c9fea640-c87a-42d4-9284-1f4dcf4f6954', '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': 'fd70ed1e6f96440493fcd804e87e4312'}} 2026-04-06 04:28:31,016 - INFO - Created VN vn2 2026-04-06 04:28:31,027 - DEBUG - VN vn2 UUID is c9fea640-c87a-42d4-9284-1f4dcf4f6954 2026-04-06 04:28:31,099 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06821378: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-06821378: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-04-06 04:28:31,442 - INFO - Attaching policy sec_grp_policy_ctest-random-98529079 to vn vn1 2026-04-06 04:28:31,795 - INFO - Attaching policy sec_grp_policy_ctest-random-98529079 to vn vn2 2026-04-06 04:28:38,496 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-2) 2026-04-06 04:28:39,731 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-1) 2026-04-06 04:28:39,796 - INFO - ================================================================================ 2026-04-06 04:28:39,796 - INFO - STARTING TEST : test_create_md5_on_control 2026-04-06 04:28:39,796 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-04-06 04:28:41,079 - DEBUG - Skipping xmpp flap check 2026-04-06 04:28:41,079 - INFO - Initial checks done. Running the testcase now 2026-04-06 04:28:41,079 - INFO - 2026-04-06 04:28:41,079 - INFO - config md5 on each host with auth_data None 2026-04-06 04:28:41,079 - INFO - config per peer with auth_data None 2026-04-06 04:28:41,667 - INFO - configure MD5 on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:28:41,795 - INFO - config per peer with auth_data None 2026-04-06 04:28:42,395 - INFO - configure MD5 on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:28:42,487 - INFO - config per peer with auth_data None 2026-04-06 04:28:43,144 - INFO - configure MD5 on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:28:43,251 - INFO - check if BGP between peers are up before setting md5 2026-04-06 04:28:43,251 - INFO - Check BGP staus between peers 2026-04-06 04:28:43,258 - INFO - setting MD5 config on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:28:43,258 - INFO - configure MD5 on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:28:43,339 - INFO - setting MD5 config on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:28:43,339 - INFO - configure MD5 on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:28:43,419 - INFO - setting MD5 config on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:28:43,419 - INFO - configure MD5 on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:28:43,499 - INFO - check if BGP peering is up after md5 config 2026-04-06 04:28:43,499 - INFO - Check BGP staus between peers 2026-04-06 04:28:43,506 - DEBUG - Check the BGP connection on 10.0.0.249 2026-04-06 04:28:43,506 - DEBUG - Check the BGP connection on 10.0.0.249 2026-04-06 04:28:53,506 - INFO - Check BGP staus between peers 2026-04-06 04:28:53,516 - INFO - remove md5 configured on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:28:53,516 - INFO - config per peer with auth_data None 2026-04-06 04:28:54,051 - INFO - configure MD5 on host a1df12bc-a8f9-4e3a-abf9-ad3e9e4edafd 2026-04-06 04:28:54,151 - INFO - remove md5 configured on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:28:54,151 - INFO - config per peer with auth_data None 2026-04-06 04:28:54,755 - INFO - configure MD5 on host 925dafc1-65c0-40c2-be96-d5d1cf5f480b 2026-04-06 04:28:54,843 - INFO - remove md5 configured on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:28:54,843 - INFO - config per peer with auth_data None 2026-04-06 04:28:55,495 - INFO - configure MD5 on host f6cec5c7-d5d7-42e4-b45d-3d4fbdba36c2 2026-04-06 04:28:55,607 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-04-06 04:28:55,607 - INFO - Deleting VM vn2_vm_0 2026-04-06 04:28:55,607 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-06 04:28:55,607 - ERROR - Nova failed to get host of the VM 2026-04-06 04:28:55,607 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-06 04:28:55,607 - ERROR - Nova failed to get host of the VM 2026-04-06 04:28:55,696 - INFO - Skip interface_detach for VM vn1_vm_0 in state ACTIVE 2026-04-06 04:28:55,696 - INFO - Deleting VM vn1_vm_0 2026-04-06 04:28:55,773 - INFO - Dettaching policy sec_grp_policy_ctest-random-98529079 from vn vn2 2026-04-06 04:28:55,987 - INFO - Dettaching policy sec_grp_policy_ctest-random-98529079 from vn vn1 2026-04-06 04:28:56,267 - INFO - Deleted policy sec_grp_policy_ctest-random-98529079 2026-04-06 04:28:56,267 - INFO - Deleting VN vn2 2026-04-06 04:28:56,359 - DEBUG - VN c9fea640-c87a-42d4-9284-1f4dcf4f6954 still in use: Unable to complete operation on network c9fea640-c87a-42d4-9284-1f4dcf4f6954. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9a9e00b0-1f22-45a6-bbb9-bbc4717797d8'] 2026-04-06 04:28:56,359 - WARNING - Deleting VN vn2 failed..Will retry 2026-04-06 04:28:58,546 - DEBUG - Response for deleting network () 2026-04-06 04:28:58,547 - INFO - Deleting VN vn1 2026-04-06 04:28:58,702 - DEBUG - Response for deleting network () 2026-04-06 04:28:58,702 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-04-06 04:28:58,882 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-04-06 04:28:59,152 - INFO - Deleted policy allow_all 2026-04-06 04:28:59,152 - INFO - Deleting VM dest_vm 2026-04-06 04:28:59,244 - INFO - Deleting VM source_vm 2026-04-06 04:28:59,309 - INFO - Deleting VN test_vnv6dn 2026-04-06 04:28:59,362 - DEBUG - VN 481cea58-4b63-4e5d-a97c-dbfeccc162b1 still in use: Unable to complete operation on network 481cea58-4b63-4e5d-a97c-dbfeccc162b1. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-7b16c5fa-f040-42d0-97db-e3372982fb5e'] 2026-04-06 04:28:59,362 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-04-06 04:29:01,539 - DEBUG - Response for deleting network () 2026-04-06 04:29:01,539 - INFO - Deleting VN test_vnv6sr 2026-04-06 04:29:01,754 - DEBUG - Response for deleting network () 2026-04-06 04:29:03,023 - DEBUG - Skipping xmpp flap check
2026-04-06 04:29:03,023 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2026-04-06 04:29:03,023 - INFO - -------------------------------------------------------------------------------- 2026-04-06 04:29:03,805 - INFO - Deleted project: ctest-TestMd5testsOnControl-06821378, ID : fd70ed1e-6f96-4404-93fc-d804e87e4312