2026-04-20 04:11:31,325 - INFO - Domain Default found not creating 2026-04-20 04:11:31,503 - INFO - Project ctest-TestMd5testsOnControl-45723797 not found, creating it 2026-04-20 04:11:32,040 - INFO - Created Project:ctest-TestMd5testsOnControl-45723797, ID : ed6bad6e-0634-48af-b9d5-df46db28ab42 2026-04-20 04:11:34,108 - DEBUG - Response for create_network : {'network': {'id': '7ce6a352-1534-43af-9764-eff2eb53d893', 'name': 'test_vnv6sr', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:11:34,401 - DEBUG - Response for create_subnet : {'subnet': {'id': '1b232514-2368-4d64-bb33-43350e7b416d', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '7ce6a352-1534-43af-9764-eff2eb53d893', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:11:34,649 - DEBUG - Response for create_subnet : {'subnet': {'id': '6ce188f6-715c-4a5d-85a6-41e0af67134b', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '7ce6a352-1534-43af-9764-eff2eb53d893', 'ip_version': 4, 'cidr': '6.61.153.192/26', 'allocation_pools': [{'start': '6.61.153.194', 'end': '6.61.153.254'}], 'gateway_ip': '6.61.153.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.61.153.194', 'tags': [], 'project_id': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:11:34,672 - INFO - Created VN test_vnv6sr 2026-04-20 04:11:34,682 - DEBUG - VN test_vnv6sr UUID is 7ce6a352-1534-43af-9764-eff2eb53d893 2026-04-20 04:11:35,007 - DEBUG - Response for create_network : {'network': {'id': '484ded4c-1b31-492a-8fdb-cf1b11050e9c', 'name': 'test_vnv6dn', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:11:35,215 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd393fe9-a681-43ac-9b9a-99d7042cd9d1', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '484ded4c-1b31-492a-8fdb-cf1b11050e9c', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:11:35,405 - DEBUG - Response for create_subnet : {'subnet': {'id': '2deffabe-2924-407a-b917-266ab7bf12d7', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '484ded4c-1b31-492a-8fdb-cf1b11050e9c', 'ip_version': 4, 'cidr': '181.250.35.64/26', 'allocation_pools': [{'start': '181.250.35.66', 'end': '181.250.35.126'}], 'gateway_ip': '181.250.35.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '181.250.35.66', 'tags': [], 'project_id': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:11:35,430 - INFO - Created VN test_vnv6dn 2026-04-20 04:11:35,440 - DEBUG - VN test_vnv6dn UUID is 484ded4c-1b31-492a-8fdb-cf1b11050e9c 2026-04-20 04:11:35,736 - DEBUG - Services list from nova: [, , , ] 2026-04-20 04:11:36,861 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-2) 2026-04-20 04:11:38,236 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-1) 2026-04-20 04:11:38,237 - INFO - Waiting for VM source_vm to be up.. 2026-04-20 04:11:38,333 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-20 04:11:43,418 - DEBUG - VM is in ACTIVE state now 2026-04-20 04:11:43,418 - INFO - VM name : source_vm 2026-04-20 04:11:43,518 - DEBUG - VM source_vm ID is 819465cf-f887-46c9-9c63-7128e31262e7 2026-04-20 04:11:43,554 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5644-2 2026-04-20 04:11:43,633 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819465cf-f887-46c9-9c63-7128e31262e7 2026-04-20 04:11:43,929 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819465cf-f887-46c9-9c63-7128e31262e7 2026-04-20 04:11:43,975 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b4dcda93-face-47d0-ad11-55d68de211d3 2026-04-20 04:11:47,136 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1003ms') 2026-04-20 04:11:47,136 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM source_vm failed! 2026-04-20 04:11:47,150 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-45723797:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-04-20 04:11:47,150 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-45723797:test_vnv6sr is 6.61.153.193 and allocation pool is NOT set 2026-04-20 04:11:49,218 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=11.7 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=2.22 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.216/6.956/11.697/4.740 ms') 2026-04-20 04:11:49,218 - INFO - Ping to Metadata IP 169.254.0.5 of VM source_vm passed 2026-04-20 04:11:49,286 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 04:11:49,286 - DEBUG - Waiting to SSH to VM source_vm, IP 6.61.153.195, Port 22 2026-04-20 04:11:49,354 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-04-20 04:11:49,448 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-04-20 04:11:54,449 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 04:11:54,449 - DEBUG - Waiting to SSH to VM source_vm, IP 6.61.153.195, Port 22 2026-04-20 04:11:54,615 - DEBUG - VM source_vm is ready for SSH connections 2026-04-20 04:11:54,615 - INFO - Waiting for VM dest_vm to be up.. 2026-04-20 04:11:54,697 - DEBUG - VM is in ACTIVE state now 2026-04-20 04:11:54,697 - INFO - VM name : dest_vm 2026-04-20 04:11:54,776 - DEBUG - VM dest_vm ID is b7b0dbc6-cba7-4eb8-ac41-3ada3a6621a9 2026-04-20 04:11:54,776 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5644-1 2026-04-20 04:11:54,861 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b7b0dbc6-cba7-4eb8-ac41-3ada3a6621a9 2026-04-20 04:11:54,871 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/26465af8-7369-4429-a063-af53ef240656 2026-04-20 04:11:56,027 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.21 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.484 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.484/1.847/3.211/1.363 ms') 2026-04-20 04:11:56,027 - INFO - Ping to Metadata IP 169.254.0.4 of VM dest_vm passed 2026-04-20 04:11:56,103 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 04:11:56,103 - DEBUG - Waiting to SSH to VM dest_vm, IP 181.250.35.67, Port 22 2026-04-20 04:11:56,275 - DEBUG - VM dest_vm is ready for SSH connections 2026-04-20 04:11:56,281 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-45723797: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-45723797: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-20 04:11:56,622 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-04-20 04:11:56,969 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-04-20 04:11:57,223 - INFO - Configure the policy with allow any 2026-04-20 04:11:57,519 - DEBUG - Response for create_network : {'network': {'id': 'fd9264ff-cbcf-4eb3-b4ec-ad157dfc6393', 'name': 'vn1', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:11:57,712 - DEBUG - Response for create_subnet : {'subnet': {'id': '6ebb8a58-45d4-41e0-b3be-89178d55e10c', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': 'fd9264ff-cbcf-4eb3-b4ec-ad157dfc6393', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:11:57,734 - INFO - Created VN vn1 2026-04-20 04:11:57,746 - DEBUG - VN vn1 UUID is fd9264ff-cbcf-4eb3-b4ec-ad157dfc6393 2026-04-20 04:11:58,097 - DEBUG - Response for create_network : {'network': {'id': 'd4cb9054-c3f5-4064-9283-4b38076d525e', 'name': 'vn2', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:11:58,414 - DEBUG - Response for create_subnet : {'subnet': {'id': '6e4f1f36-72be-4f87-a7e1-4919d1dcf84b', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': 'd4cb9054-c3f5-4064-9283-4b38076d525e', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:11:58,433 - INFO - Created VN vn2 2026-04-20 04:11:58,442 - DEBUG - VN vn2 UUID is d4cb9054-c3f5-4064-9283-4b38076d525e 2026-04-20 04:11:58,556 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-45723797: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-45723797: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-20 04:11:58,895 - INFO - Attaching policy sec_grp_policy_ctest-random-28208443 to vn vn1 2026-04-20 04:11:59,248 - INFO - Attaching policy sec_grp_policy_ctest-random-28208443 to vn vn2 2026-04-20 04:12:00,757 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-2) 2026-04-20 04:12:02,142 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-1) 2026-04-20 04:12:02,212 - INFO - ================================================================================ 2026-04-20 04:12:02,213 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-04-20 04:12:02,213 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-04-20 04:12:03,508 - DEBUG - Skipping xmpp flap check 2026-04-20 04:12:03,508 - INFO - Initial checks done. Running the testcase now 2026-04-20 04:12:03,508 - INFO - 2026-04-20 04:12:03,509 - INFO - config per peer with auth_data None 2026-04-20 04:12:04,080 - INFO - configure MD5 on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:12:04,176 - INFO - config per peer with auth_data None 2026-04-20 04:12:04,736 - INFO - configure MD5 on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:12:04,816 - INFO - config per peer with auth_data None 2026-04-20 04:12:05,376 - INFO - configure MD5 on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:12:05,456 - INFO - check BGP peering is up before setting md5 config 2026-04-20 04:12:05,456 - INFO - Check BGP staus between peers 2026-04-20 04:12:05,467 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-04-20 04:12:06,056 - INFO - Check BGP staus between peers 2026-04-20 04:12:06,069 - DEBUG - Check the BGP connection on 10.0.0.38 2026-04-20 04:12:06,070 - DEBUG - Check the BGP connection on 10.0.0.38 2026-04-20 04:12:16,071 - INFO - Check BGP staus between peers 2026-04-20 04:12:16,083 - INFO - remove md5 configured on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:12:16,083 - INFO - config per peer with auth_data None 2026-04-20 04:12:16,660 - INFO - configure MD5 on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:12:16,752 - INFO - remove md5 configured on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:12:16,752 - INFO - config per peer with auth_data None 2026-04-20 04:12:17,316 - INFO - configure MD5 on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:12:17,404 - INFO - remove md5 configured on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:12:17,404 - INFO - config per peer with auth_data None 2026-04-20 04:12:18,049 - INFO - configure MD5 on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:12:18,144 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-04-20 04:12:18,144 - INFO - Deleting VM vn2_vm_0 2026-04-20 04:12:18,144 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-20 04:12:18,144 - ERROR - Nova failed to get host of the VM 2026-04-20 04:12:18,144 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-20 04:12:18,144 - ERROR - Nova failed to get host of the VM 2026-04-20 04:12:18,214 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-04-20 04:12:18,216 - INFO - Deleting VM vn1_vm_0 2026-04-20 04:12:18,216 - ERROR - VM vn1_vm_0 has failed to come up 2026-04-20 04:12:18,216 - ERROR - Nova failed to get host of the VM 2026-04-20 04:12:18,216 - ERROR - VM vn1_vm_0 has failed to come up 2026-04-20 04:12:18,216 - ERROR - Nova failed to get host of the VM 2026-04-20 04:12:18,322 - INFO - Dettaching policy sec_grp_policy_ctest-random-28208443 from vn vn2 2026-04-20 04:12:18,488 - INFO - Dettaching policy sec_grp_policy_ctest-random-28208443 from vn vn1 2026-04-20 04:12:18,750 - INFO - Deleted policy sec_grp_policy_ctest-random-28208443 2026-04-20 04:12:18,750 - INFO - Deleting VN vn2 2026-04-20 04:12:18,822 - DEBUG - VN d4cb9054-c3f5-4064-9283-4b38076d525e still in use: Unable to complete operation on network d4cb9054-c3f5-4064-9283-4b38076d525e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b48933a5-eaa1-49b6-b74d-b0c4e6a15e03'] 2026-04-20 04:12:18,822 - WARNING - Deleting VN vn2 failed..Will retry 2026-04-20 04:12:20,976 - DEBUG - Response for deleting network () 2026-04-20 04:12:20,976 - INFO - Deleting VN vn1 2026-04-20 04:12:21,135 - DEBUG - Response for deleting network () 2026-04-20 04:12:21,135 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-04-20 04:12:21,319 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-04-20 04:12:21,561 - INFO - Deleted policy allow_all 2026-04-20 04:12:21,561 - INFO - Deleting VM dest_vm 2026-04-20 04:12:21,630 - INFO - Deleting VM source_vm 2026-04-20 04:12:21,703 - INFO - Deleting VN test_vnv6dn 2026-04-20 04:12:21,751 - DEBUG - VN 484ded4c-1b31-492a-8fdb-cf1b11050e9c still in use: Unable to complete operation on network 484ded4c-1b31-492a-8fdb-cf1b11050e9c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-69b6dc0b-d3b0-43ae-878a-090644143e12'] 2026-04-20 04:12:21,752 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-04-20 04:12:23,907 - DEBUG - Response for deleting network () 2026-04-20 04:12:23,907 - INFO - Deleting VN test_vnv6sr 2026-04-20 04:12:24,123 - DEBUG - Response for deleting network () 2026-04-20 04:12:25,386 - DEBUG - Skipping xmpp flap check
2026-04-20 04:12:25,386 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2026-04-20 04:12:25,387 - INFO - -------------------------------------------------------------------------------- 2026-04-20 04:12:25,686 - DEBUG - Response for create_network : {'network': {'id': '3a3c81d2-8db5-4d51-9323-63ce6adb173c', 'name': 'test_vnv6sr', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:12:25,911 - DEBUG - Response for create_subnet : {'subnet': {'id': '6a87f00c-5822-445c-9f1f-179f3f0a05bf', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '3a3c81d2-8db5-4d51-9323-63ce6adb173c', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:12:26,160 - DEBUG - Response for create_subnet : {'subnet': {'id': '61f5ce11-ada8-4578-b38b-4d5b56a9334d', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '3a3c81d2-8db5-4d51-9323-63ce6adb173c', 'ip_version': 4, 'cidr': '168.65.78.128/26', 'allocation_pools': [{'start': '168.65.78.130', 'end': '168.65.78.190'}], 'gateway_ip': '168.65.78.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.65.78.130', 'tags': [], 'project_id': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:12:26,204 - INFO - Created VN test_vnv6sr 2026-04-20 04:12:26,213 - DEBUG - VN test_vnv6sr UUID is 3a3c81d2-8db5-4d51-9323-63ce6adb173c 2026-04-20 04:12:26,685 - DEBUG - Response for create_network : {'network': {'id': '45ced17c-397a-4f14-b381-30663f675651', 'name': 'test_vnv6dn', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:12:26,945 - DEBUG - Response for create_subnet : {'subnet': {'id': '26b535b2-6002-4fed-9b22-ddaf4b2b74e3', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '45ced17c-397a-4f14-b381-30663f675651', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:12:27,173 - DEBUG - Response for create_subnet : {'subnet': {'id': '545ae54d-3475-4c50-8f14-27284762f05e', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '45ced17c-397a-4f14-b381-30663f675651', 'ip_version': 4, 'cidr': '143.103.167.192/26', 'allocation_pools': [{'start': '143.103.167.194', 'end': '143.103.167.254'}], 'gateway_ip': '143.103.167.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '143.103.167.194', 'tags': [], 'project_id': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:12:27,196 - INFO - Created VN test_vnv6dn 2026-04-20 04:12:27,206 - DEBUG - VN test_vnv6dn UUID is 45ced17c-397a-4f14-b381-30663f675651 2026-04-20 04:12:28,480 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-2) 2026-04-20 04:12:29,808 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-1) 2026-04-20 04:12:29,809 - INFO - Waiting for VM source_vm to be up.. 2026-04-20 04:12:29,890 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-20 04:12:34,980 - DEBUG - VM is in ACTIVE state now 2026-04-20 04:12:34,980 - INFO - VM name : source_vm 2026-04-20 04:12:35,077 - DEBUG - VM source_vm ID is 7cfa3db7-d9f6-430d-954b-7b59ba89fff0 2026-04-20 04:12:35,077 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5644-2 2026-04-20 04:12:35,164 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7cfa3db7-d9f6-430d-954b-7b59ba89fff0 2026-04-20 04:12:35,173 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/951e6d53-e5ea-41f5-8964-33a7cddc7d92 2026-04-20 04:12:38,364 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1033ms') 2026-04-20 04:12:38,364 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM source_vm failed! 2026-04-20 04:12:38,378 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-45723797:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-04-20 04:12:38,379 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-45723797:test_vnv6sr is 168.65.78.129 and allocation pool is NOT set 2026-04-20 04:12:40,439 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=5.43 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.409 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.409/2.921/5.433/2.512 ms') 2026-04-20 04:12:40,439 - INFO - Ping to Metadata IP 169.254.0.5 of VM source_vm passed 2026-04-20 04:12:40,510 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 04:12:40,510 - DEBUG - Waiting to SSH to VM source_vm, IP 168.65.78.131, Port 22 2026-04-20 04:12:40,690 - DEBUG - VM source_vm is ready for SSH connections 2026-04-20 04:12:40,690 - INFO - Waiting for VM dest_vm to be up.. 2026-04-20 04:12:40,780 - DEBUG - VM is in ACTIVE state now 2026-04-20 04:12:40,781 - INFO - VM name : dest_vm 2026-04-20 04:12:40,867 - DEBUG - VM dest_vm ID is b188fd8a-d344-4724-9e2d-cf2432fcd438 2026-04-20 04:12:40,867 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5644-1 2026-04-20 04:12:40,948 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b188fd8a-d344-4724-9e2d-cf2432fcd438 2026-04-20 04:12:40,958 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/0ce8202e-9c54-47eb-9c2e-5f534bcc9943 2026-04-20 04:12:42,111 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=4.36 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.852 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.852/2.606/4.360/1.754 ms') 2026-04-20 04:12:42,111 - INFO - Ping to Metadata IP 169.254.0.4 of VM dest_vm passed 2026-04-20 04:12:42,180 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 04:12:42,180 - DEBUG - Waiting to SSH to VM dest_vm, IP 143.103.167.195, Port 22 2026-04-20 04:12:42,337 - DEBUG - VM dest_vm is ready for SSH connections 2026-04-20 04:12:42,343 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-45723797: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-45723797: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-20 04:12:42,664 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-04-20 04:12:43,026 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-04-20 04:12:43,280 - INFO - Configure the policy with allow any 2026-04-20 04:12:43,639 - DEBUG - Response for create_network : {'network': {'id': '819626f0-0efc-4f01-a331-a96469fa3a9c', 'name': 'vn1', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:12:43,921 - DEBUG - Response for create_subnet : {'subnet': {'id': '334795c4-9ea7-4b4d-b9a8-1620b4c06b5e', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '819626f0-0efc-4f01-a331-a96469fa3a9c', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:12:43,951 - INFO - Created VN vn1 2026-04-20 04:12:43,962 - DEBUG - VN vn1 UUID is 819626f0-0efc-4f01-a331-a96469fa3a9c 2026-04-20 04:12:44,315 - DEBUG - Response for create_network : {'network': {'id': '0f1d9217-1131-4d09-8a38-941c4fa913cb', 'name': 'vn2', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'project_id': 'ed6bad6e063448afb9d5df46db28ab42', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-45723797', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 04:12:44,603 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd5e30148-9c92-4ff7-913e-b1a67e88953f', 'name': '', 'tenant_id': 'ed6bad6e063448afb9d5df46db28ab42', 'network_id': '0f1d9217-1131-4d09-8a38-941c4fa913cb', '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': 'ed6bad6e063448afb9d5df46db28ab42'}} 2026-04-20 04:12:44,630 - INFO - Created VN vn2 2026-04-20 04:12:44,643 - DEBUG - VN vn2 UUID is 0f1d9217-1131-4d09-8a38-941c4fa913cb 2026-04-20 04:12:44,717 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-45723797: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-45723797: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-20 04:12:45,058 - INFO - Attaching policy sec_grp_policy_ctest-random-93039679 to vn vn1 2026-04-20 04:12:45,466 - INFO - Attaching policy sec_grp_policy_ctest-random-93039679 to vn vn2 2026-04-20 04:12:47,113 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-2) 2026-04-20 04:12:48,536 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-1) 2026-04-20 04:12:48,595 - INFO - ================================================================================ 2026-04-20 04:12:48,595 - INFO - STARTING TEST : test_create_md5_on_control 2026-04-20 04:12:48,595 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-04-20 04:12:49,885 - DEBUG - Skipping xmpp flap check 2026-04-20 04:12:49,885 - INFO - Initial checks done. Running the testcase now 2026-04-20 04:12:49,885 - INFO - 2026-04-20 04:12:49,885 - INFO - config md5 on each host with auth_data None 2026-04-20 04:12:49,885 - INFO - config per peer with auth_data None 2026-04-20 04:12:50,404 - INFO - configure MD5 on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:12:50,484 - INFO - config per peer with auth_data None 2026-04-20 04:12:51,056 - INFO - configure MD5 on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:12:51,152 - INFO - config per peer with auth_data None 2026-04-20 04:12:51,820 - INFO - configure MD5 on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:12:51,900 - INFO - check if BGP between peers are up before setting md5 2026-04-20 04:12:51,900 - INFO - Check BGP staus between peers 2026-04-20 04:12:51,911 - INFO - setting MD5 config on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:12:51,911 - INFO - configure MD5 on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:12:51,992 - INFO - setting MD5 config on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:12:51,992 - INFO - configure MD5 on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:12:52,072 - INFO - setting MD5 config on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:12:52,072 - INFO - configure MD5 on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:12:52,152 - INFO - check if BGP peering is up after md5 config 2026-04-20 04:12:52,152 - INFO - Check BGP staus between peers 2026-04-20 04:12:52,162 - DEBUG - Check the BGP connection on 10.0.0.38 2026-04-20 04:12:52,162 - DEBUG - Check the BGP connection on 10.0.0.38 2026-04-20 04:13:02,163 - INFO - Check BGP staus between peers 2026-04-20 04:13:02,176 - INFO - remove md5 configured on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:13:02,177 - INFO - config per peer with auth_data None 2026-04-20 04:13:02,676 - INFO - configure MD5 on host 4daee73f-91c0-481a-8de1-06874cbd765b 2026-04-20 04:13:02,760 - INFO - remove md5 configured on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:13:02,760 - INFO - config per peer with auth_data None 2026-04-20 04:13:03,320 - INFO - configure MD5 on host 54f7dc3d-0b53-42f3-8c06-11421636c7e0 2026-04-20 04:13:03,416 - INFO - remove md5 configured on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:13:03,416 - INFO - config per peer with auth_data None 2026-04-20 04:13:03,972 - INFO - configure MD5 on host b3388f66-8e57-4e20-97bc-d4be6207b12e 2026-04-20 04:13:04,056 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-04-20 04:13:04,056 - INFO - Deleting VM vn2_vm_0 2026-04-20 04:13:04,056 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-20 04:13:04,056 - ERROR - Nova failed to get host of the VM 2026-04-20 04:13:04,056 - ERROR - VM vn2_vm_0 has failed to come up 2026-04-20 04:13:04,056 - ERROR - Nova failed to get host of the VM 2026-04-20 04:13:04,135 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-04-20 04:13:04,135 - INFO - Deleting VM vn1_vm_0 2026-04-20 04:13:04,135 - ERROR - VM vn1_vm_0 has failed to come up 2026-04-20 04:13:04,135 - ERROR - Nova failed to get host of the VM 2026-04-20 04:13:04,135 - ERROR - VM vn1_vm_0 has failed to come up 2026-04-20 04:13:04,135 - ERROR - Nova failed to get host of the VM 2026-04-20 04:13:04,199 - INFO - Dettaching policy sec_grp_policy_ctest-random-93039679 from vn vn2 2026-04-20 04:13:04,375 - INFO - Dettaching policy sec_grp_policy_ctest-random-93039679 from vn vn1 2026-04-20 04:13:04,638 - INFO - Deleted policy sec_grp_policy_ctest-random-93039679 2026-04-20 04:13:04,639 - INFO - Deleting VN vn2 2026-04-20 04:13:04,686 - DEBUG - VN 0f1d9217-1131-4d09-8a38-941c4fa913cb still in use: Unable to complete operation on network 0f1d9217-1131-4d09-8a38-941c4fa913cb. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f052d97b-620c-44e8-bd33-66680e481553'] 2026-04-20 04:13:04,686 - WARNING - Deleting VN vn2 failed..Will retry 2026-04-20 04:13:06,840 - DEBUG - Response for deleting network () 2026-04-20 04:13:06,840 - INFO - Deleting VN vn1 2026-04-20 04:13:06,999 - DEBUG - Response for deleting network () 2026-04-20 04:13:06,999 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-04-20 04:13:07,180 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-04-20 04:13:07,431 - INFO - Deleted policy allow_all 2026-04-20 04:13:07,431 - INFO - Deleting VM dest_vm 2026-04-20 04:13:07,500 - INFO - Deleting VM source_vm 2026-04-20 04:13:07,569 - INFO - Deleting VN test_vnv6dn 2026-04-20 04:13:07,612 - DEBUG - VN 45ced17c-397a-4f14-b381-30663f675651 still in use: Unable to complete operation on network 45ced17c-397a-4f14-b381-30663f675651. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-82ec779b-98dd-44ad-8878-275d74ca0e8b'] 2026-04-20 04:13:07,612 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-04-20 04:13:09,809 - DEBUG - Response for deleting network () 2026-04-20 04:13:09,809 - INFO - Deleting VN test_vnv6sr 2026-04-20 04:13:10,006 - DEBUG - Response for deleting network () 2026-04-20 04:13:11,272 - DEBUG - Skipping xmpp flap check
2026-04-20 04:13:11,272 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-04-20 04:13:11,272 - INFO - -------------------------------------------------------------------------------- 2026-04-20 04:13:11,996 - INFO - Deleted project: ctest-TestMd5testsOnControl-45723797, ID : ed6bad6e-0634-48af-b9d5-df46db28ab42