2026-10-05 05:23:30,778 - INFO - Domain Default found not creating 2026-10-05 05:23:30,974 - INFO - Project ctest-TestMd5testsOnControl-31997696 not found, creating it 2026-10-05 05:23:31,721 - INFO - Created Project:ctest-TestMd5testsOnControl-31997696, ID : 95b5e0cd-daf0-416b-b9b3-b3a3b78a7649 2026-10-05 05:23:34,502 - DEBUG - Response for create_network : {'network': {'id': '4ece06c4-db16-4ce3-9bcb-a2dff98397b3', 'name': 'test_vnv6sr', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:23:34,843 - DEBUG - Response for create_subnet : {'subnet': {'id': '87af2ab2-6d44-4021-934f-923de823be09', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '4ece06c4-db16-4ce3-9bcb-a2dff98397b3', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:23:35,108 - DEBUG - Response for create_subnet : {'subnet': {'id': '7c00679e-8274-46d0-9f6e-b9aca7e82a0b', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '4ece06c4-db16-4ce3-9bcb-a2dff98397b3', 'ip_version': 4, 'cidr': '112.102.60.128/26', 'allocation_pools': [{'start': '112.102.60.130', 'end': '112.102.60.190'}], 'gateway_ip': '112.102.60.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.102.60.130', 'tags': [], 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:23:35,137 - INFO - Created VN test_vnv6sr 2026-10-05 05:23:35,152 - DEBUG - VN test_vnv6sr UUID is 4ece06c4-db16-4ce3-9bcb-a2dff98397b3 2026-10-05 05:23:35,667 - DEBUG - Response for create_network : {'network': {'id': '7e684cfc-2678-4284-ae9a-d3209186eb9d', 'name': 'test_vnv6dn', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:23:35,975 - DEBUG - Response for create_subnet : {'subnet': {'id': '152eefb2-3795-4fec-b0a0-12241f0ad392', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '7e684cfc-2678-4284-ae9a-d3209186eb9d', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:23:36,249 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd0198e0f-e96e-4c52-b8ae-b58ee26727fb', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '7e684cfc-2678-4284-ae9a-d3209186eb9d', 'ip_version': 4, 'cidr': '64.20.117.64/26', 'allocation_pools': [{'start': '64.20.117.66', 'end': '64.20.117.126'}], 'gateway_ip': '64.20.117.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '64.20.117.66', 'tags': [], 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:23:36,281 - INFO - Created VN test_vnv6dn 2026-10-05 05:23:36,299 - DEBUG - VN test_vnv6dn UUID is 7e684cfc-2678-4284-ae9a-d3209186eb9d 2026-10-05 05:23:36,651 - DEBUG - Services list from nova: [, , , ] 2026-10-05 05:23:37,341 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-1) 2026-10-05 05:23:38,114 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-2) 2026-10-05 05:23:38,115 - INFO - Waiting for VM source_vm to be up.. 2026-10-05 05:23:38,182 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:23:43,287 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:23:48,383 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:23:53,483 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:23:58,673 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:03,769 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:08,878 - DEBUG - VM is in ACTIVE state now 2026-10-05 05:24:08,879 - INFO - VM name : source_vm 2026-10-05 05:24:08,983 - DEBUG - VM source_vm ID is fdf05e92-85d6-4baf-8ac2-79438ef0c81b 2026-10-05 05:24:09,019 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-6343-1 2026-10-05 05:24:09,126 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine/fdf05e92-85d6-4baf-8ac2-79438ef0c81b 2026-10-05 05:24:09,517 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine/fdf05e92-85d6-4baf-8ac2-79438ef0c81b 2026-10-05 05:24:09,575 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine-interface/7aa6021a-7c0c-41a2-ac93-318b86a52d42 2026-10-05 05:24:12,800 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1024ms') 2026-10-05 05:24:12,800 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM source_vm failed! 2026-10-05 05:24:12,817 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31997696:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-10-05 05:24:12,817 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31997696:test_vnv6sr is 112.102.60.129 and allocation pool is NOT set 2026-10-05 05:24:14,878 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=7.16 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=0.450 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.450/3.802/7.155/3.352 ms') 2026-10-05 05:24:14,878 - INFO - Ping to Metadata IP 169.254.0.7 of VM source_vm passed 2026-10-05 05:24:14,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-05 05:24:14,954 - DEBUG - Waiting to SSH to VM source_vm, IP 112.102.60.131, Port 22 2026-10-05 05:24:15,128 - DEBUG - VM source_vm is ready for SSH connections 2026-10-05 05:24:15,128 - INFO - Waiting for VM dest_vm to be up.. 2026-10-05 05:24:15,186 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:20,246 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:25,310 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:30,377 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:35,435 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:40,514 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:45,572 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:50,631 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:24:55,688 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:00,757 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:05,825 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:10,895 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:15,956 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:21,019 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:26,080 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:31,138 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:36,202 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:41,266 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:46,325 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:51,399 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:25:56,461 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:01,530 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:06,600 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:11,714 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:16,774 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:21,835 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:26,907 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:31,973 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:37,040 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:42,102 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:47,158 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:26:47,158 - ERROR - VM is not in ACTIVE state 2026-10-05 05:26:47,247 - ERROR - Fatal Nova Exception while getting VM detail 2026-10-05 05:26:47,248 - DEBUG - None 2026-10-05 05:26:47,255 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31997696: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-31997696: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-10-05 05:26:47,639 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-10-05 05:26:48,054 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-10-05 05:26:48,407 - INFO - Configure the policy with allow any 2026-10-05 05:26:48,826 - DEBUG - Response for create_network : {'network': {'id': '1665f665-67db-4869-bc69-1ef07ed33074', 'name': 'vn1', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:26:49,108 - DEBUG - Response for create_subnet : {'subnet': {'id': '8c9613da-399d-4b21-9cc2-6e5cfe37acac', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '1665f665-67db-4869-bc69-1ef07ed33074', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:26:49,131 - INFO - Created VN vn1 2026-10-05 05:26:49,153 - DEBUG - VN vn1 UUID is 1665f665-67db-4869-bc69-1ef07ed33074 2026-10-05 05:26:49,577 - DEBUG - Response for create_network : {'network': {'id': '5eb99b54-f58f-400f-a2d0-f70f5f18ee4c', 'name': 'vn2', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:26:49,858 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd05fd1c-5549-41f2-974f-a5ef39284b2f', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '5eb99b54-f58f-400f-a2d0-f70f5f18ee4c', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:26:49,880 - INFO - Created VN vn2 2026-10-05 05:26:49,896 - DEBUG - VN vn2 UUID is 5eb99b54-f58f-400f-a2d0-f70f5f18ee4c 2026-10-05 05:26:49,980 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31997696: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-31997696: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-10-05 05:26:50,351 - INFO - Attaching policy sec_grp_policy_ctest-random-55020961 to vn vn1 2026-10-05 05:26:50,743 - INFO - Attaching policy sec_grp_policy_ctest-random-55020961 to vn vn2 2026-10-05 05:26:51,865 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-1) 2026-10-05 05:26:52,589 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-2) 2026-10-05 05:26:52,656 - INFO - ================================================================================ 2026-10-05 05:26:52,657 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-10-05 05:26:52,657 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-10-05 05:26:53,973 - DEBUG - Skipping xmpp flap check 2026-10-05 05:26:53,973 - INFO - Initial checks done. Running the testcase now 2026-10-05 05:26:53,973 - INFO - 2026-10-05 05:26:53,973 - INFO - config per peer with auth_data None 2026-10-05 05:26:54,582 - INFO - configure MD5 on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:26:54,674 - INFO - config per peer with auth_data None 2026-10-05 05:26:55,342 - INFO - configure MD5 on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:26:55,458 - INFO - config per peer with auth_data None 2026-10-05 05:26:56,130 - INFO - configure MD5 on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:26:56,250 - INFO - check BGP peering is up before setting md5 config 2026-10-05 05:26:56,250 - INFO - Check BGP staus between peers 2026-10-05 05:26:56,287 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-10-05 05:26:57,055 - INFO - Check BGP staus between peers 2026-10-05 05:26:57,085 - DEBUG - Check the BGP connection on 10.0.0.177 2026-10-05 05:26:57,085 - DEBUG - Check the BGP connection on 10.0.0.177 2026-10-05 05:27:07,086 - INFO - Check BGP staus between peers 2026-10-05 05:27:07,098 - INFO - remove md5 configured on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:27:07,098 - INFO - config per peer with auth_data None 2026-10-05 05:27:07,722 - INFO - configure MD5 on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:27:07,834 - INFO - remove md5 configured on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:27:07,834 - INFO - config per peer with auth_data None 2026-10-05 05:27:08,466 - INFO - configure MD5 on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:27:08,558 - INFO - remove md5 configured on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:27:08,558 - INFO - config per peer with auth_data None 2026-10-05 05:27:09,242 - INFO - configure MD5 on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:27:09,334 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-10-05 05:27:09,334 - INFO - Deleting VM vn2_vm_0 2026-10-05 05:27:09,334 - ERROR - VM vn2_vm_0 has failed to come up 2026-10-05 05:27:09,334 - ERROR - Nova failed to get host of the VM 2026-10-05 05:27:09,334 - ERROR - VM vn2_vm_0 has failed to come up 2026-10-05 05:27:09,334 - ERROR - Nova failed to get host of the VM 2026-10-05 05:27:09,503 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-10-05 05:27:09,503 - INFO - Deleting VM vn1_vm_0 2026-10-05 05:27:09,503 - ERROR - VM vn1_vm_0 has failed to come up 2026-10-05 05:27:09,504 - ERROR - Nova failed to get host of the VM 2026-10-05 05:27:09,504 - ERROR - VM vn1_vm_0 has failed to come up 2026-10-05 05:27:09,504 - ERROR - Nova failed to get host of the VM 2026-10-05 05:27:09,602 - INFO - Dettaching policy sec_grp_policy_ctest-random-55020961 from vn vn2 2026-10-05 05:27:09,811 - INFO - Dettaching policy sec_grp_policy_ctest-random-55020961 from vn vn1 2026-10-05 05:27:10,145 - INFO - Deleted policy sec_grp_policy_ctest-random-55020961 2026-10-05 05:27:10,145 - INFO - Deleting VN vn2 2026-10-05 05:27:10,347 - DEBUG - Response for deleting network () 2026-10-05 05:27:10,347 - INFO - Deleting VN vn1 2026-10-05 05:27:10,426 - DEBUG - VN 1665f665-67db-4869-bc69-1ef07ed33074 still in use: Unable to complete operation on network 1665f665-67db-4869-bc69-1ef07ed33074. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-1f73848f-745f-4574-b13e-4da61afd1191'] 2026-10-05 05:27:10,426 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:27:12,510 - DEBUG - VN 1665f665-67db-4869-bc69-1ef07ed33074 still in use: Unable to complete operation on network 1665f665-67db-4869-bc69-1ef07ed33074. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e8827ae4-a70c-46d6-a07d-4704b93f71b8'] 2026-10-05 05:27:12,510 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:27:14,557 - DEBUG - VN 1665f665-67db-4869-bc69-1ef07ed33074 still in use: Unable to complete operation on network 1665f665-67db-4869-bc69-1ef07ed33074. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b444782b-2296-49e3-9639-00a002dde064'] 2026-10-05 05:27:14,557 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:27:16,604 - DEBUG - VN 1665f665-67db-4869-bc69-1ef07ed33074 still in use: Unable to complete operation on network 1665f665-67db-4869-bc69-1ef07ed33074. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ff4845f7-2e47-4157-a8c8-9a1740390571'] 2026-10-05 05:27:16,605 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:27:18,649 - DEBUG - VN 1665f665-67db-4869-bc69-1ef07ed33074 still in use: Unable to complete operation on network 1665f665-67db-4869-bc69-1ef07ed33074. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4c0ae02a-e258-490c-9ef2-57f742b8631e'] 2026-10-05 05:27:18,650 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:27:20,836 - DEBUG - Response for deleting network () 2026-10-05 05:27:20,836 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-10-05 05:27:21,069 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-10-05 05:27:21,360 - INFO - Deleted policy allow_all 2026-10-05 05:27:21,360 - INFO - Skip interface_detach for VM dest_vm in state BUILD 2026-10-05 05:27:21,360 - INFO - Deleting VM dest_vm 2026-10-05 05:27:21,360 - ERROR - VM dest_vm has failed to come up 2026-10-05 05:27:21,360 - ERROR - Nova failed to get host of the VM 2026-10-05 05:27:21,360 - ERROR - VM dest_vm has failed to come up 2026-10-05 05:27:21,360 - ERROR - Nova failed to get host of the VM 2026-10-05 05:27:21,537 - INFO - Deleting VM source_vm 2026-10-05 05:27:21,640 - INFO - Deleting VN test_vnv6dn 2026-10-05 05:27:21,843 - DEBUG - Response for deleting network () 2026-10-05 05:27:21,843 - INFO - Deleting VN test_vnv6sr 2026-10-05 05:27:21,894 - DEBUG - VN 4ece06c4-db16-4ce3-9bcb-a2dff98397b3 still in use: Unable to complete operation on network 4ece06c4-db16-4ce3-9bcb-a2dff98397b3. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5eca25f3-b111-4dd5-ad0c-433ed93ab34c'] 2026-10-05 05:27:21,894 - WARNING - Deleting VN test_vnv6sr failed..Will retry 2026-10-05 05:27:24,019 - DEBUG - VN 4ece06c4-db16-4ce3-9bcb-a2dff98397b3 still in use: Unable to complete operation on network 4ece06c4-db16-4ce3-9bcb-a2dff98397b3. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2982e0e8-94f6-41e7-b216-a1597b798236'] 2026-10-05 05:27:24,019 - WARNING - Deleting VN test_vnv6sr failed..Will retry 2026-10-05 05:27:26,258 - DEBUG - Response for deleting network () 2026-10-05 05:27:27,607 - DEBUG - Skipping xmpp flap check
2026-10-05 05:27:27,607 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:35]
2026-10-05 05:27:27,608 - INFO - -------------------------------------------------------------------------------- 2026-10-05 05:27:27,980 - DEBUG - Response for create_network : {'network': {'id': '4d43ec5f-6d3b-4efa-9692-067a7586ec7a', 'name': 'test_vnv6sr', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:27:28,276 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f685ab9a-0f05-4c96-b4d8-dd30780565c4', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '4d43ec5f-6d3b-4efa-9692-067a7586ec7a', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:27:28,564 - DEBUG - Response for create_subnet : {'subnet': {'id': '0ad7a65c-483d-411c-b1a2-758084de96e6', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '4d43ec5f-6d3b-4efa-9692-067a7586ec7a', 'ip_version': 4, 'cidr': '167.197.15.64/26', 'allocation_pools': [{'start': '167.197.15.66', 'end': '167.197.15.126'}], 'gateway_ip': '167.197.15.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '167.197.15.66', 'tags': [], 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:27:28,592 - INFO - Created VN test_vnv6sr 2026-10-05 05:27:28,607 - DEBUG - VN test_vnv6sr UUID is 4d43ec5f-6d3b-4efa-9692-067a7586ec7a 2026-10-05 05:27:29,045 - DEBUG - Response for create_network : {'network': {'id': 'ad2af906-8c50-4fd7-9a12-2d45618d1aa8', 'name': 'test_vnv6dn', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:27:29,318 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b8839450-3de0-4757-8efa-92bc4084d473', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': 'ad2af906-8c50-4fd7-9a12-2d45618d1aa8', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:27:29,534 - DEBUG - Response for create_subnet : {'subnet': {'id': '543e5c15-4226-4ac6-962a-caa790381748', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': 'ad2af906-8c50-4fd7-9a12-2d45618d1aa8', 'ip_version': 4, 'cidr': '198.111.153.64/26', 'allocation_pools': [{'start': '198.111.153.66', 'end': '198.111.153.126'}], 'gateway_ip': '198.111.153.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.111.153.66', 'tags': [], 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:27:29,562 - INFO - Created VN test_vnv6dn 2026-10-05 05:27:29,579 - DEBUG - VN test_vnv6dn UUID is ad2af906-8c50-4fd7-9a12-2d45618d1aa8 2026-10-05 05:27:30,579 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-1) 2026-10-05 05:27:31,312 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-2) 2026-10-05 05:27:31,313 - INFO - Waiting for VM source_vm to be up.. 2026-10-05 05:27:31,369 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:27:36,479 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:27:41,576 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:27:46,702 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:27:51,810 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:27:56,907 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:02,010 - DEBUG - VM is in ACTIVE state now 2026-10-05 05:28:02,010 - INFO - VM name : source_vm 2026-10-05 05:28:02,123 - DEBUG - VM source_vm ID is a8e3e98f-70f4-441f-9c63-8ac043d7b8c6 2026-10-05 05:28:02,123 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-6343-1 2026-10-05 05:28:02,223 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine/a8e3e98f-70f4-441f-9c63-8ac043d7b8c6 2026-10-05 05:28:02,238 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine-interface/eee4a004-ceb8-4f97-ac73-d31e209acd52 2026-10-05 05:28:05,442 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-10-05 05:28:05,442 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM source_vm failed! 2026-10-05 05:28:05,462 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31997696:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-10-05 05:28:05,462 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-31997696:test_vnv6sr is 167.197.15.65 and allocation pool is NOT set 2026-10-05 05:28:07,523 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=7.19 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=1.31 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.311/4.252/7.193/2.941 ms') 2026-10-05 05:28:07,523 - INFO - Ping to Metadata IP 169.254.0.7 of VM source_vm passed 2026-10-05 05:28:07,598 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-05 05:28:07,598 - DEBUG - Waiting to SSH to VM source_vm, IP 167.197.15.67, Port 22 2026-10-05 05:28:07,772 - DEBUG - VM source_vm is ready for SSH connections 2026-10-05 05:28:07,772 - INFO - Waiting for VM dest_vm to be up.. 2026-10-05 05:28:07,838 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:12,922 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:17,985 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:23,045 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:28,123 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:33,183 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:38,296 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:43,388 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:48,472 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:53,532 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:28:58,591 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:03,649 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:08,707 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:13,766 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:18,829 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:23,887 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:28,949 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:34,007 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:39,067 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:44,130 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:49,192 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:54,250 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:29:59,312 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:04,373 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:09,446 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:14,509 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:19,570 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:24,636 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:29,702 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:34,779 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:39,839 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:30:39,839 - ERROR - VM is not in ACTIVE state 2026-10-05 05:30:39,919 - ERROR - Fatal Nova Exception while getting VM detail 2026-10-05 05:30:39,919 - DEBUG - None 2026-10-05 05:30:39,952 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31997696: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-31997696: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-10-05 05:30:40,356 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-10-05 05:30:40,783 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-10-05 05:30:41,110 - INFO - Configure the policy with allow any 2026-10-05 05:30:41,508 - DEBUG - Response for create_network : {'network': {'id': '574c83d8-4f0a-4729-a1e5-adfd17fdb401', 'name': 'vn1', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:30:41,775 - DEBUG - Response for create_subnet : {'subnet': {'id': '206dc163-466b-4314-9076-db004f20f7db', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '574c83d8-4f0a-4729-a1e5-adfd17fdb401', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:30:41,802 - INFO - Created VN vn1 2026-10-05 05:30:41,817 - DEBUG - VN vn1 UUID is 574c83d8-4f0a-4729-a1e5-adfd17fdb401 2026-10-05 05:30:42,290 - DEBUG - Response for create_network : {'network': {'id': '225500fd-e552-4524-8057-f85ec4c4b095', 'name': 'vn2', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'project_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-31997696', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:30:42,581 - DEBUG - Response for create_subnet : {'subnet': {'id': '7e157e24-8581-47e9-9a89-19e3f1837843', 'name': '', 'tenant_id': '95b5e0cddaf0416bb9b3b3a3b78a7649', 'network_id': '225500fd-e552-4524-8057-f85ec4c4b095', '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': '95b5e0cddaf0416bb9b3b3a3b78a7649'}} 2026-10-05 05:30:42,606 - INFO - Created VN vn2 2026-10-05 05:30:42,625 - DEBUG - VN vn2 UUID is 225500fd-e552-4524-8057-f85ec4c4b095 2026-10-05 05:30:42,717 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-31997696: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-31997696: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-10-05 05:30:43,096 - INFO - Attaching policy sec_grp_policy_ctest-random-35158505 to vn vn1 2026-10-05 05:30:43,515 - INFO - Attaching policy sec_grp_policy_ctest-random-35158505 to vn vn2 2026-10-05 05:30:44,809 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-1) 2026-10-05 05:30:45,789 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-2) 2026-10-05 05:30:45,857 - INFO - ================================================================================ 2026-10-05 05:30:45,857 - INFO - STARTING TEST : test_create_md5_on_control 2026-10-05 05:30:45,857 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-10-05 05:30:47,192 - DEBUG - Skipping xmpp flap check 2026-10-05 05:30:47,192 - INFO - Initial checks done. Running the testcase now 2026-10-05 05:30:47,192 - INFO - 2026-10-05 05:30:47,192 - INFO - config md5 on each host with auth_data None 2026-10-05 05:30:47,192 - INFO - config per peer with auth_data None 2026-10-05 05:30:47,838 - INFO - configure MD5 on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:30:47,938 - INFO - config per peer with auth_data None 2026-10-05 05:30:48,602 - INFO - configure MD5 on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:30:48,690 - INFO - config per peer with auth_data None 2026-10-05 05:30:49,482 - INFO - configure MD5 on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:30:49,574 - INFO - check if BGP between peers are up before setting md5 2026-10-05 05:30:49,576 - INFO - Check BGP staus between peers 2026-10-05 05:30:49,596 - INFO - setting MD5 config on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:30:49,596 - INFO - configure MD5 on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:30:49,698 - INFO - setting MD5 config on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:30:49,698 - INFO - configure MD5 on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:30:49,802 - INFO - setting MD5 config on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:30:49,802 - INFO - configure MD5 on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:30:49,962 - INFO - check if BGP peering is up after md5 config 2026-10-05 05:30:49,962 - INFO - Check BGP staus between peers 2026-10-05 05:30:49,974 - DEBUG - Check the BGP connection on 10.0.0.177 2026-10-05 05:30:49,974 - DEBUG - Check the BGP connection on 10.0.0.177 2026-10-05 05:30:59,975 - INFO - Check BGP staus between peers 2026-10-05 05:30:59,988 - INFO - remove md5 configured on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:30:59,988 - INFO - config per peer with auth_data None 2026-10-05 05:31:00,634 - INFO - configure MD5 on host a88563b4-7c9d-41d9-81dd-3fe9337fddf2 2026-10-05 05:31:00,734 - INFO - remove md5 configured on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:31:00,734 - INFO - config per peer with auth_data None 2026-10-05 05:31:01,398 - INFO - configure MD5 on host d4107bf2-af6a-4118-9a9c-9fec319e2ec9 2026-10-05 05:31:01,510 - INFO - remove md5 configured on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:31:01,510 - INFO - config per peer with auth_data None 2026-10-05 05:31:02,258 - INFO - configure MD5 on host 137e9a12-9b53-4c45-aced-4e7afe1dbcc3 2026-10-05 05:31:02,370 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-10-05 05:31:02,370 - INFO - Deleting VM vn2_vm_0 2026-10-05 05:31:02,371 - ERROR - VM vn2_vm_0 has failed to come up 2026-10-05 05:31:02,371 - ERROR - Nova failed to get host of the VM 2026-10-05 05:31:02,371 - ERROR - VM vn2_vm_0 has failed to come up 2026-10-05 05:31:02,371 - ERROR - Nova failed to get host of the VM 2026-10-05 05:31:02,552 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-10-05 05:31:02,552 - INFO - Deleting VM vn1_vm_0 2026-10-05 05:31:02,553 - ERROR - VM vn1_vm_0 has failed to come up 2026-10-05 05:31:02,553 - ERROR - Nova failed to get host of the VM 2026-10-05 05:31:02,553 - ERROR - VM vn1_vm_0 has failed to come up 2026-10-05 05:31:02,553 - ERROR - Nova failed to get host of the VM 2026-10-05 05:31:02,642 - INFO - Dettaching policy sec_grp_policy_ctest-random-35158505 from vn vn2 2026-10-05 05:31:02,826 - INFO - Dettaching policy sec_grp_policy_ctest-random-35158505 from vn vn1 2026-10-05 05:31:03,117 - INFO - Deleted policy sec_grp_policy_ctest-random-35158505 2026-10-05 05:31:03,118 - INFO - Deleting VN vn2 2026-10-05 05:31:03,310 - DEBUG - Response for deleting network () 2026-10-05 05:31:03,310 - INFO - Deleting VN vn1 2026-10-05 05:31:03,364 - DEBUG - VN 574c83d8-4f0a-4729-a1e5-adfd17fdb401 still in use: Unable to complete operation on network 574c83d8-4f0a-4729-a1e5-adfd17fdb401. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9681e479-dbaf-43c8-b6cf-51f5e3a15f9a'] 2026-10-05 05:31:03,365 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:31:05,412 - DEBUG - VN 574c83d8-4f0a-4729-a1e5-adfd17fdb401 still in use: Unable to complete operation on network 574c83d8-4f0a-4729-a1e5-adfd17fdb401. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-cc86c2bd-c2f6-4d2c-aa47-5cf10cd82473'] 2026-10-05 05:31:05,413 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:31:07,466 - DEBUG - VN 574c83d8-4f0a-4729-a1e5-adfd17fdb401 still in use: Unable to complete operation on network 574c83d8-4f0a-4729-a1e5-adfd17fdb401. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a48206d9-6f6a-44c7-a1fd-fc63bfee0056'] 2026-10-05 05:31:07,467 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:31:09,526 - DEBUG - VN 574c83d8-4f0a-4729-a1e5-adfd17fdb401 still in use: Unable to complete operation on network 574c83d8-4f0a-4729-a1e5-adfd17fdb401. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-76c04fdc-6c17-4d6f-8c7d-7a0b58865356'] 2026-10-05 05:31:09,526 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:31:11,576 - DEBUG - VN 574c83d8-4f0a-4729-a1e5-adfd17fdb401 still in use: Unable to complete operation on network 574c83d8-4f0a-4729-a1e5-adfd17fdb401. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a1574c45-a415-4d27-9b27-e165e30a6e47'] 2026-10-05 05:31:11,576 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:31:13,632 - DEBUG - VN 574c83d8-4f0a-4729-a1e5-adfd17fdb401 still in use: Unable to complete operation on network 574c83d8-4f0a-4729-a1e5-adfd17fdb401. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f22c11cc-f9c2-4ce6-b746-07e5f5f8ea6d'] 2026-10-05 05:31:13,632 - WARNING - Deleting VN vn1 failed..Will retry 2026-10-05 05:31:15,848 - DEBUG - Response for deleting network () 2026-10-05 05:31:15,849 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-10-05 05:31:16,046 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-10-05 05:31:16,348 - INFO - Deleted policy allow_all 2026-10-05 05:31:16,348 - INFO - Skip interface_detach for VM dest_vm in state BUILD 2026-10-05 05:31:16,348 - INFO - Deleting VM dest_vm 2026-10-05 05:31:16,348 - ERROR - VM dest_vm has failed to come up 2026-10-05 05:31:16,348 - ERROR - Nova failed to get host of the VM 2026-10-05 05:31:16,349 - ERROR - VM dest_vm has failed to come up 2026-10-05 05:31:16,349 - ERROR - Nova failed to get host of the VM 2026-10-05 05:31:16,501 - INFO - Deleting VM source_vm 2026-10-05 05:31:16,598 - INFO - Deleting VN test_vnv6dn 2026-10-05 05:31:16,810 - DEBUG - Response for deleting network () 2026-10-05 05:31:16,811 - INFO - Deleting VN test_vnv6sr 2026-10-05 05:31:16,874 - DEBUG - VN 4d43ec5f-6d3b-4efa-9692-067a7586ec7a still in use: Unable to complete operation on network 4d43ec5f-6d3b-4efa-9692-067a7586ec7a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5d144f82-612e-445a-906f-0a9dbc0591cf'] 2026-10-05 05:31:16,874 - WARNING - Deleting VN test_vnv6sr failed..Will retry 2026-10-05 05:31:18,931 - DEBUG - VN 4d43ec5f-6d3b-4efa-9692-067a7586ec7a still in use: Unable to complete operation on network 4d43ec5f-6d3b-4efa-9692-067a7586ec7a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f61ea516-6c2e-4844-9d1c-a4a09fa48a8b'] 2026-10-05 05:31:18,931 - WARNING - Deleting VN test_vnv6sr failed..Will retry 2026-10-05 05:31:21,208 - DEBUG - Response for deleting network () 2026-10-05 05:31:22,527 - DEBUG - Skipping xmpp flap check
2026-10-05 05:31:22,527 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:37]
2026-10-05 05:31:22,527 - INFO - -------------------------------------------------------------------------------- 2026-10-05 05:31:23,576 - INFO - Deleted project: ctest-TestMd5testsOnControl-31997696, ID : 95b5e0cd-daf0-416b-b9b3-b3a3b78a7649