2026-02-09 04:01:48,682 - INFO - Domain Default found not creating 2026-02-09 04:01:48,871 - INFO - Project ctest-TestMd5testsOnControl-79024215 not found, creating it 2026-02-09 04:01:50,089 - INFO - Created Project:ctest-TestMd5testsOnControl-79024215, ID : 8bf922ad-0678-4fa6-b00a-e94ae4d336e6 2026-02-09 04:01:52,411 - DEBUG - Response for create_network : {'network': {'id': '6b7b379b-13e2-408c-8a7a-a70e7853ca94', 'name': 'test_vnv6sr', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:01:52,710 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e73b31e1-cd43-4697-ad5b-25cab669e561', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '6b7b379b-13e2-408c-8a7a-a70e7853ca94', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:01:52,948 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ae8f3bac-48ed-4c6d-a9f2-f6efd78696ff', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '6b7b379b-13e2-408c-8a7a-a70e7853ca94', 'ip_version': 4, 'cidr': '112.51.183.192/26', 'allocation_pools': [{'start': '112.51.183.194', 'end': '112.51.183.254'}], 'gateway_ip': '112.51.183.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.51.183.194', 'tags': [], 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:01:52,974 - INFO - Created VN test_vnv6sr 2026-02-09 04:01:52,986 - DEBUG - VN test_vnv6sr UUID is 6b7b379b-13e2-408c-8a7a-a70e7853ca94 2026-02-09 04:01:53,359 - DEBUG - Response for create_network : {'network': {'id': '7f8b7f06-f9da-4a82-bee4-d3198b0b05f6', 'name': 'test_vnv6dn', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:01:53,685 - DEBUG - Response for create_subnet : {'subnet': {'id': '591456b4-7b22-4693-8ec1-b4ba9fc63f75', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '7f8b7f06-f9da-4a82-bee4-d3198b0b05f6', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:01:53,906 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b5d3dbd4-6162-4046-8278-fa8027a3c7b6', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '7f8b7f06-f9da-4a82-bee4-d3198b0b05f6', 'ip_version': 4, 'cidr': '165.176.90.128/26', 'allocation_pools': [{'start': '165.176.90.130', 'end': '165.176.90.190'}], 'gateway_ip': '165.176.90.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '165.176.90.130', 'tags': [], 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:01:53,929 - INFO - Created VN test_vnv6dn 2026-02-09 04:01:53,941 - DEBUG - VN test_vnv6dn UUID is 7f8b7f06-f9da-4a82-bee4-d3198b0b05f6 2026-02-09 04:01:54,098 - DEBUG - Services list from nova: [, , , ] 2026-02-09 04:01:55,263 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1) 2026-02-09 04:01:56,664 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2) 2026-02-09 04:01:56,664 - INFO - Waiting for VM source_vm to be up.. 2026-02-09 04:01:56,758 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-09 04:02:01,857 - DEBUG - VM is in ACTIVE state now 2026-02-09 04:02:01,857 - INFO - VM name : source_vm 2026-02-09 04:02:01,976 - DEBUG - VM source_vm ID is cb9e3026-da4e-460d-8e10-c1c81db8a74f 2026-02-09 04:02:02,012 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5238-1 2026-02-09 04:02:02,109 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/cb9e3026-da4e-460d-8e10-c1c81db8a74f 2026-02-09 04:02:02,438 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/cb9e3026-da4e-460d-8e10-c1c81db8a74f 2026-02-09 04:02:02,482 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/8ec02f95-38a7-4bf6-9138-d5c768aad734 2026-02-09 04:02:05,650 - 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-09 04:02:05,650 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-02-09 04:02:05,665 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-79024215:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-02-09 04:02:05,665 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-79024215:test_vnv6sr is 112.51.183.193 and allocation pool is NOT set 2026-02-09 04:02:07,725 - 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=8.56 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.40 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.396/5.477/8.559/3.081 ms') 2026-02-09 04:02:07,725 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-02-09 04:02:07,793 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 04:02:07,793 - DEBUG - Waiting to SSH to VM source_vm, IP 112.51.183.195, Port 22 2026-02-09 04:02:07,953 - DEBUG - VM source_vm is ready for SSH connections 2026-02-09 04:02:07,953 - INFO - Waiting for VM dest_vm to be up.. 2026-02-09 04:02:08,064 - DEBUG - VM is in ACTIVE state now 2026-02-09 04:02:08,064 - INFO - VM name : dest_vm 2026-02-09 04:02:08,142 - DEBUG - VM dest_vm ID is da5d9de8-6918-45fc-b2fa-1335d3722046 2026-02-09 04:02:08,142 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5238-2 2026-02-09 04:02:08,225 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/da5d9de8-6918-45fc-b2fa-1335d3722046 2026-02-09 04:02:08,236 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/e88ad01f-2626-467b-82b7-b69412494288 2026-02-09 04:02:09,396 - 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.61 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.718 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.718/3.665/6.613/2.947 ms') 2026-02-09 04:02:09,397 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-02-09 04:02:09,468 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 04:02:09,468 - DEBUG - Waiting to SSH to VM dest_vm, IP 165.176.90.131, Port 22 2026-02-09 04:02:09,639 - DEBUG - VM dest_vm is ready for SSH connections 2026-02-09 04:02:09,647 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-79024215: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-79024215: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-09 04:02:09,982 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-02-09 04:02:10,345 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-02-09 04:02:10,645 - INFO - Configure the policy with allow any 2026-02-09 04:02:10,981 - DEBUG - Response for create_network : {'network': {'id': '84594f8e-859e-4d87-be65-8bfae3ff7679', 'name': 'vn1', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:02:11,228 - DEBUG - Response for create_subnet : {'subnet': {'id': '37b8a7cb-258e-4622-a2cb-0983f9ee9892', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '84594f8e-859e-4d87-be65-8bfae3ff7679', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:02:11,248 - INFO - Created VN vn1 2026-02-09 04:02:11,263 - DEBUG - VN vn1 UUID is 84594f8e-859e-4d87-be65-8bfae3ff7679 2026-02-09 04:02:11,639 - DEBUG - Response for create_network : {'network': {'id': '8f12692c-bf58-49fe-a1e0-3bdc0cdadb2f', 'name': 'vn2', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:02:11,865 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c246cf37-ea11-433c-b467-4684d6bda08f', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '8f12692c-bf58-49fe-a1e0-3bdc0cdadb2f', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:02:11,886 - INFO - Created VN vn2 2026-02-09 04:02:11,897 - DEBUG - VN vn2 UUID is 8f12692c-bf58-49fe-a1e0-3bdc0cdadb2f 2026-02-09 04:02:11,982 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-79024215: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-79024215: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-09 04:02:12,333 - INFO - Attaching policy sec_grp_policy_ctest-random-99619708 to vn vn1 2026-02-09 04:02:12,706 - INFO - Attaching policy sec_grp_policy_ctest-random-99619708 to vn vn2 2026-02-09 04:02:14,180 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1) 2026-02-09 04:02:15,572 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2) 2026-02-09 04:02:15,634 - INFO - ================================================================================ 2026-02-09 04:02:15,634 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-02-09 04:02:15,634 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-02-09 04:02:16,935 - DEBUG - Skipping xmpp flap check 2026-02-09 04:02:16,936 - INFO - Initial checks done. Running the testcase now 2026-02-09 04:02:16,936 - INFO - 2026-02-09 04:02:16,936 - INFO - config per peer with auth_data None 2026-02-09 04:02:17,490 - INFO - configure MD5 on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:02:17,578 - INFO - config per peer with auth_data None 2026-02-09 04:02:18,130 - INFO - configure MD5 on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:02:18,206 - INFO - config per peer with auth_data None 2026-02-09 04:02:18,758 - INFO - configure MD5 on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:02:18,838 - INFO - check BGP peering is up before setting md5 config 2026-02-09 04:02:18,839 - INFO - Check BGP staus between peers 2026-02-09 04:02:18,851 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-02-09 04:02:19,451 - INFO - Check BGP staus between peers 2026-02-09 04:02:19,459 - DEBUG - Check the BGP connection on 10.0.0.19 2026-02-09 04:02:19,459 - DEBUG - Check the BGP connection on 10.0.0.19 2026-02-09 04:02:29,460 - INFO - Check BGP staus between peers 2026-02-09 04:02:29,477 - INFO - remove md5 configured on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:02:29,477 - INFO - config per peer with auth_data None 2026-02-09 04:02:30,030 - INFO - configure MD5 on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:02:30,118 - INFO - remove md5 configured on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:02:30,118 - INFO - config per peer with auth_data None 2026-02-09 04:02:30,723 - INFO - configure MD5 on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:02:30,814 - INFO - remove md5 configured on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:02:30,815 - INFO - config per peer with auth_data None 2026-02-09 04:02:31,424 - INFO - configure MD5 on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:02:31,515 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-02-09 04:02:31,515 - INFO - Deleting VM vn2_vm_0 2026-02-09 04:02:31,515 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-09 04:02:31,515 - ERROR - Nova failed to get host of the VM 2026-02-09 04:02:31,515 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-09 04:02:31,515 - ERROR - Nova failed to get host of the VM 2026-02-09 04:02:31,599 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-02-09 04:02:31,600 - INFO - Deleting VM vn1_vm_0 2026-02-09 04:02:31,600 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-09 04:02:31,600 - ERROR - Nova failed to get host of the VM 2026-02-09 04:02:31,600 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-09 04:02:31,600 - ERROR - Nova failed to get host of the VM 2026-02-09 04:02:31,670 - INFO - Dettaching policy sec_grp_policy_ctest-random-99619708 from vn vn2 2026-02-09 04:02:31,849 - INFO - Dettaching policy sec_grp_policy_ctest-random-99619708 from vn vn1 2026-02-09 04:02:32,106 - INFO - Deleted policy sec_grp_policy_ctest-random-99619708 2026-02-09 04:02:32,106 - INFO - Deleting VN vn2 2026-02-09 04:02:32,165 - DEBUG - VN 8f12692c-bf58-49fe-a1e0-3bdc0cdadb2f still in use: Unable to complete operation on network 8f12692c-bf58-49fe-a1e0-3bdc0cdadb2f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6ffdcb86-0ead-4adb-af6d-6d033cac9429'] 2026-02-09 04:02:32,165 - WARNING - Deleting VN vn2 failed..Will retry 2026-02-09 04:02:34,323 - DEBUG - Response for deleting network () 2026-02-09 04:02:34,323 - INFO - Deleting VN vn1 2026-02-09 04:02:34,479 - DEBUG - Response for deleting network () 2026-02-09 04:02:34,479 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-02-09 04:02:34,674 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-02-09 04:02:34,942 - INFO - Deleted policy allow_all 2026-02-09 04:02:34,942 - INFO - Deleting VM dest_vm 2026-02-09 04:02:35,013 - INFO - Deleting VM source_vm 2026-02-09 04:02:35,089 - INFO - Deleting VN test_vnv6dn 2026-02-09 04:02:35,135 - DEBUG - VN 7f8b7f06-f9da-4a82-bee4-d3198b0b05f6 still in use: Unable to complete operation on network 7f8b7f06-f9da-4a82-bee4-d3198b0b05f6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9ada004f-9d2a-4feb-a6b5-13c63a001527'] 2026-02-09 04:02:35,135 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-02-09 04:02:37,314 - DEBUG - Response for deleting network () 2026-02-09 04:02:37,314 - INFO - Deleting VN test_vnv6sr 2026-02-09 04:02:37,507 - DEBUG - Response for deleting network () 2026-02-09 04:02:38,762 - DEBUG - Skipping xmpp flap check
2026-02-09 04:02:38,762 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2026-02-09 04:02:38,762 - INFO - -------------------------------------------------------------------------------- 2026-02-09 04:02:39,063 - DEBUG - Response for create_network : {'network': {'id': '599724c4-127b-4662-911c-ae8dce4c0b05', 'name': 'test_vnv6sr', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:02:39,272 - DEBUG - Response for create_subnet : {'subnet': {'id': '4be994f4-d095-414a-a71b-d32efd5c77cb', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '599724c4-127b-4662-911c-ae8dce4c0b05', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:02:39,507 - DEBUG - Response for create_subnet : {'subnet': {'id': '74f6d435-9ff3-4feb-b245-1babb2e84a92', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '599724c4-127b-4662-911c-ae8dce4c0b05', 'ip_version': 4, 'cidr': '32.62.215.0/26', 'allocation_pools': [{'start': '32.62.215.2', 'end': '32.62.215.62'}], 'gateway_ip': '32.62.215.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '32.62.215.2', 'tags': [], 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:02:39,528 - INFO - Created VN test_vnv6sr 2026-02-09 04:02:39,539 - DEBUG - VN test_vnv6sr UUID is 599724c4-127b-4662-911c-ae8dce4c0b05 2026-02-09 04:02:39,925 - DEBUG - Response for create_network : {'network': {'id': 'a783070f-7d11-4605-9ce1-719b913b3187', 'name': 'test_vnv6dn', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:02:40,195 - DEBUG - Response for create_subnet : {'subnet': {'id': '5d184ebd-5e42-42ca-b5c8-81b6415096f6', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': 'a783070f-7d11-4605-9ce1-719b913b3187', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:02:40,439 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd68c3c1-4bcb-4e5e-b445-a833ca6df51b', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': 'a783070f-7d11-4605-9ce1-719b913b3187', 'ip_version': 4, 'cidr': '217.187.33.0/26', 'allocation_pools': [{'start': '217.187.33.2', 'end': '217.187.33.62'}], 'gateway_ip': '217.187.33.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '217.187.33.2', 'tags': [], 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:02:40,462 - INFO - Created VN test_vnv6dn 2026-02-09 04:02:40,474 - DEBUG - VN test_vnv6dn UUID is a783070f-7d11-4605-9ce1-719b913b3187 2026-02-09 04:02:41,638 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1) 2026-02-09 04:02:42,971 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2) 2026-02-09 04:02:42,971 - INFO - Waiting for VM source_vm to be up.. 2026-02-09 04:02:43,064 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-09 04:02:48,150 - DEBUG - VM is in ACTIVE state now 2026-02-09 04:02:48,150 - INFO - VM name : source_vm 2026-02-09 04:02:48,229 - DEBUG - VM source_vm ID is 27dfaea3-737a-40a8-badb-7ba830abd1e6 2026-02-09 04:02:48,229 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5238-1 2026-02-09 04:02:48,307 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/27dfaea3-737a-40a8-badb-7ba830abd1e6 2026-02-09 04:02:48,319 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/1b2a1504-b4f0-43c6-b90d-468e0caa8c40 2026-02-09 04:02:51,509 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-02-09 04:02:51,509 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-02-09 04:02:51,524 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-79024215:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-02-09 04:02:51,524 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-79024215:test_vnv6sr is 32.62.215.1 and allocation pool is NOT set 2026-02-09 04:02:53,588 - 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=7.66 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 1002ms\r\nrtt min/avg/max/mdev = 0.576/4.116/7.656/3.540 ms') 2026-02-09 04:02:53,588 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-02-09 04:02:53,655 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 04:02:53,655 - DEBUG - Waiting to SSH to VM source_vm, IP 32.62.215.3, Port 22 2026-02-09 04:02:53,724 - 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-09 04:02:53,796 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-02-09 04:02:58,797 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 04:02:58,797 - DEBUG - Waiting to SSH to VM source_vm, IP 32.62.215.3, Port 22 2026-02-09 04:02:58,954 - DEBUG - VM source_vm is ready for SSH connections 2026-02-09 04:02:58,954 - INFO - Waiting for VM dest_vm to be up.. 2026-02-09 04:02:59,033 - DEBUG - VM is in ACTIVE state now 2026-02-09 04:02:59,033 - INFO - VM name : dest_vm 2026-02-09 04:02:59,112 - DEBUG - VM dest_vm ID is af3e8c46-5d43-47bf-bc19-5f9845145235 2026-02-09 04:02:59,112 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5238-2 2026-02-09 04:02:59,187 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af3e8c46-5d43-47bf-bc19-5f9845145235 2026-02-09 04:02:59,199 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/8160edb7-0fa3-4ea8-b9c2-ccab6e093073 2026-02-09 04:03:00,356 - 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.55 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.456 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 = 0.456/2.002/3.548/1.546 ms') 2026-02-09 04:03:00,356 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-02-09 04:03:00,428 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 04:03:00,428 - DEBUG - Waiting to SSH to VM dest_vm, IP 217.187.33.3, Port 22 2026-02-09 04:03:00,596 - DEBUG - VM dest_vm is ready for SSH connections 2026-02-09 04:03:00,607 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-79024215: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-79024215: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-09 04:03:00,949 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-02-09 04:03:01,321 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-02-09 04:03:01,680 - INFO - Configure the policy with allow any 2026-02-09 04:03:02,074 - DEBUG - Response for create_network : {'network': {'id': '6f033d49-07db-4f5d-a053-bd8cad31c592', 'name': 'vn1', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:03:02,334 - DEBUG - Response for create_subnet : {'subnet': {'id': '8cf24cad-1cf9-4e2f-a413-df5b0fd94bbd', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '6f033d49-07db-4f5d-a053-bd8cad31c592', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:03:02,360 - INFO - Created VN vn1 2026-02-09 04:03:02,375 - DEBUG - VN vn1 UUID is 6f033d49-07db-4f5d-a053-bd8cad31c592 2026-02-09 04:03:02,798 - DEBUG - Response for create_network : {'network': {'id': '5000b086-4783-417a-b1b4-451b160bcba6', 'name': 'vn2', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'project_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-79024215', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 04:03:03,108 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f340c230-2366-4e19-a7be-caca10809e8c', 'name': '', 'tenant_id': '8bf922ad06784fa6b00ae94ae4d336e6', 'network_id': '5000b086-4783-417a-b1b4-451b160bcba6', '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': '8bf922ad06784fa6b00ae94ae4d336e6'}} 2026-02-09 04:03:03,136 - INFO - Created VN vn2 2026-02-09 04:03:03,150 - DEBUG - VN vn2 UUID is 5000b086-4783-417a-b1b4-451b160bcba6 2026-02-09 04:03:03,237 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-79024215: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-79024215: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-09 04:03:03,650 - INFO - Attaching policy sec_grp_policy_ctest-random-18371324 to vn vn1 2026-02-09 04:03:04,079 - INFO - Attaching policy sec_grp_policy_ctest-random-18371324 to vn vn2 2026-02-09 04:03:05,718 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1) 2026-02-09 04:03:07,208 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2) 2026-02-09 04:03:07,271 - INFO - ================================================================================ 2026-02-09 04:03:07,271 - INFO - STARTING TEST : test_create_md5_on_control 2026-02-09 04:03:07,271 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-02-09 04:03:08,547 - DEBUG - Skipping xmpp flap check 2026-02-09 04:03:08,547 - INFO - Initial checks done. Running the testcase now 2026-02-09 04:03:08,547 - INFO - 2026-02-09 04:03:08,548 - INFO - config md5 on each host with auth_data None 2026-02-09 04:03:08,548 - INFO - config per peer with auth_data None 2026-02-09 04:03:09,132 - INFO - configure MD5 on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:03:09,214 - INFO - config per peer with auth_data None 2026-02-09 04:03:09,815 - INFO - configure MD5 on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:03:09,902 - INFO - config per peer with auth_data None 2026-02-09 04:03:10,478 - INFO - configure MD5 on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:03:10,554 - INFO - check if BGP between peers are up before setting md5 2026-02-09 04:03:10,555 - INFO - Check BGP staus between peers 2026-02-09 04:03:10,563 - INFO - setting MD5 config on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:03:10,563 - INFO - configure MD5 on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:03:10,638 - INFO - setting MD5 config on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:03:10,639 - INFO - configure MD5 on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:03:10,714 - INFO - setting MD5 config on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:03:10,715 - INFO - configure MD5 on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:03:10,798 - INFO - check if BGP peering is up after md5 config 2026-02-09 04:03:10,798 - INFO - Check BGP staus between peers 2026-02-09 04:03:10,807 - DEBUG - Check the BGP connection on 10.0.0.19 2026-02-09 04:03:10,807 - DEBUG - Check the BGP connection on 10.0.0.19 2026-02-09 04:03:20,808 - INFO - Check BGP staus between peers 2026-02-09 04:03:20,819 - INFO - remove md5 configured on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:03:20,819 - INFO - config per peer with auth_data None 2026-02-09 04:03:21,343 - INFO - configure MD5 on host 909f98a6-1617-4bd9-946a-7c2b2de52060 2026-02-09 04:03:21,426 - INFO - remove md5 configured on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:03:21,427 - INFO - config per peer with auth_data None 2026-02-09 04:03:21,990 - INFO - configure MD5 on host 07311818-3f12-4a0d-ba2e-68d91c539b8f 2026-02-09 04:03:22,074 - INFO - remove md5 configured on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:03:22,075 - INFO - config per peer with auth_data None 2026-02-09 04:03:22,676 - INFO - configure MD5 on host 8cd8171c-c071-4a4d-89bd-53ae03ec2817 2026-02-09 04:03:22,770 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-02-09 04:03:22,771 - INFO - Deleting VM vn2_vm_0 2026-02-09 04:03:22,771 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-09 04:03:22,771 - ERROR - Nova failed to get host of the VM 2026-02-09 04:03:22,771 - ERROR - VM vn2_vm_0 has failed to come up 2026-02-09 04:03:22,771 - ERROR - Nova failed to get host of the VM 2026-02-09 04:03:22,838 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-02-09 04:03:22,839 - INFO - Deleting VM vn1_vm_0 2026-02-09 04:03:22,839 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-09 04:03:22,839 - ERROR - Nova failed to get host of the VM 2026-02-09 04:03:22,839 - ERROR - VM vn1_vm_0 has failed to come up 2026-02-09 04:03:22,839 - ERROR - Nova failed to get host of the VM 2026-02-09 04:03:22,906 - INFO - Dettaching policy sec_grp_policy_ctest-random-18371324 from vn vn2 2026-02-09 04:03:23,076 - INFO - Dettaching policy sec_grp_policy_ctest-random-18371324 from vn vn1 2026-02-09 04:03:23,325 - INFO - Deleted policy sec_grp_policy_ctest-random-18371324 2026-02-09 04:03:23,325 - INFO - Deleting VN vn2 2026-02-09 04:03:23,376 - DEBUG - VN 5000b086-4783-417a-b1b4-451b160bcba6 still in use: Unable to complete operation on network 5000b086-4783-417a-b1b4-451b160bcba6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f8096dae-f38f-4cac-8ce0-a243cf7d07a7'] 2026-02-09 04:03:23,376 - WARNING - Deleting VN vn2 failed..Will retry 2026-02-09 04:03:25,541 - DEBUG - Response for deleting network () 2026-02-09 04:03:25,541 - INFO - Deleting VN vn1 2026-02-09 04:03:25,740 - DEBUG - Response for deleting network () 2026-02-09 04:03:25,740 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-02-09 04:03:25,937 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-02-09 04:03:26,200 - INFO - Deleted policy allow_all 2026-02-09 04:03:26,200 - INFO - Deleting VM dest_vm 2026-02-09 04:03:26,271 - INFO - Deleting VM source_vm 2026-02-09 04:03:26,345 - INFO - Deleting VN test_vnv6dn 2026-02-09 04:03:26,394 - DEBUG - VN a783070f-7d11-4605-9ce1-719b913b3187 still in use: Unable to complete operation on network a783070f-7d11-4605-9ce1-719b913b3187. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-171039ff-9bc5-43d9-83b9-6bc058c336c8'] 2026-02-09 04:03:26,395 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-02-09 04:03:28,592 - DEBUG - Response for deleting network () 2026-02-09 04:03:28,592 - INFO - Deleting VN test_vnv6sr 2026-02-09 04:03:28,766 - DEBUG - Response for deleting network () 2026-02-09 04:03:30,013 - DEBUG - Skipping xmpp flap check
2026-02-09 04:03:30,013 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-02-09 04:03:30,013 - INFO - -------------------------------------------------------------------------------- 2026-02-09 04:03:30,779 - INFO - Deleted project: ctest-TestMd5testsOnControl-79024215, ID : 8bf922ad-0678-4fa6-b00a-e94ae4d336e6