2026-03-23 04:20:08,278 - INFO - Domain Default found not creating 2026-03-23 04:20:08,492 - INFO - Project ctest-TestMd5testsOnControl-06643888 not found, creating it 2026-03-23 04:20:08,992 - INFO - Created Project:ctest-TestMd5testsOnControl-06643888, ID : f85fd48c-2e3c-4a00-b887-6411bd049528 2026-03-23 04:20:12,131 - DEBUG - Response for create_network : {'network': {'id': '24f96abf-7c64-4018-a455-4af1b5b410e5', 'name': 'test_vnv6sr', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:20:12,459 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd359561e-6a99-422b-a2e8-99c124eaf2e6', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '24f96abf-7c64-4018-a455-4af1b5b410e5', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:20:12,724 - DEBUG - Response for create_subnet : {'subnet': {'id': '5893f5c6-7f20-4346-aeb5-0b9aa7ffac0b', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '24f96abf-7c64-4018-a455-4af1b5b410e5', 'ip_version': 4, 'cidr': '195.201.230.128/26', 'allocation_pools': [{'start': '195.201.230.130', 'end': '195.201.230.190'}], 'gateway_ip': '195.201.230.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '195.201.230.130', 'tags': [], 'project_id': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:20:12,781 - INFO - Created VN test_vnv6sr 2026-03-23 04:20:12,794 - DEBUG - VN test_vnv6sr UUID is 24f96abf-7c64-4018-a455-4af1b5b410e5 2026-03-23 04:20:13,206 - DEBUG - Response for create_network : {'network': {'id': 'e1009cb3-8500-4601-94c0-03048d1de748', 'name': 'test_vnv6dn', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:20:13,496 - DEBUG - Response for create_subnet : {'subnet': {'id': '9526395a-9b5e-402f-890c-8dbca9d23dc8', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': 'e1009cb3-8500-4601-94c0-03048d1de748', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:20:13,744 - DEBUG - Response for create_subnet : {'subnet': {'id': '475ba140-e3cd-497e-8be2-898eb7c2fc24', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': 'e1009cb3-8500-4601-94c0-03048d1de748', 'ip_version': 4, 'cidr': '210.73.217.192/26', 'allocation_pools': [{'start': '210.73.217.194', 'end': '210.73.217.254'}], 'gateway_ip': '210.73.217.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '210.73.217.194', 'tags': [], 'project_id': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:20:13,792 - INFO - Created VN test_vnv6dn 2026-03-23 04:20:13,805 - DEBUG - VN test_vnv6dn UUID is e1009cb3-8500-4601-94c0-03048d1de748 2026-03-23 04:20:13,993 - DEBUG - Services list from nova: [, , , ] 2026-03-23 04:20:15,323 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:20:17,032 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:20:17,033 - INFO - Waiting for VM source_vm to be up.. 2026-03-23 04:20:17,142 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:20:22,241 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:20:22,241 - INFO - VM name : source_vm 2026-03-23 04:20:22,335 - DEBUG - VM source_vm ID is 41064977-a7e3-4d07-92e3-af9a5df7585d 2026-03-23 04:20:22,367 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5514-2 2026-03-23 04:20:22,470 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/41064977-a7e3-4d07-92e3-af9a5df7585d 2026-03-23 04:20:22,784 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/41064977-a7e3-4d07-92e3-af9a5df7585d 2026-03-23 04:20:22,831 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/20ab2565-9eb4-42eb-8bbb-a812275458c9 2026-03-23 04:20:26,029 - 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 1016ms') 2026-03-23 04:20:26,030 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-03-23 04:20:26,047 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06643888:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-03-23 04:20:26,048 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06643888:test_vnv6sr is 195.201.230.129 and allocation pool is NOT set 2026-03-23 04:20:28,120 - 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.81 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.79 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 3.787/5.297/6.807/1.510 ms') 2026-03-23 04:20:28,121 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-03-23 04:20:28,196 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:20:28,196 - DEBUG - Waiting to SSH to VM source_vm, IP 195.201.230.131, Port 22 2026-03-23 04:20:28,376 - DEBUG - VM source_vm is ready for SSH connections 2026-03-23 04:20:28,377 - INFO - Waiting for VM dest_vm to be up.. 2026-03-23 04:20:28,474 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:20:28,474 - INFO - VM name : dest_vm 2026-03-23 04:20:28,602 - DEBUG - VM dest_vm ID is e298c951-d6cb-48d3-88f1-1962c12e085b 2026-03-23 04:20:28,602 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5514-1 2026-03-23 04:20:28,701 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/e298c951-d6cb-48d3-88f1-1962c12e085b 2026-03-23 04:20:28,711 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f629c874-f767-4b5c-81ee-1a8c5d9f5043 2026-03-23 04:20:29,892 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=9.77 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.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 = 3.399/6.584/9.769/3.185 ms') 2026-03-23 04:20:29,892 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-03-23 04:20:29,967 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:20:29,967 - DEBUG - Waiting to SSH to VM dest_vm, IP 210.73.217.195, Port 22 2026-03-23 04:20:30,034 - 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-03-23 04:20:30,137 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:20:35,138 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:20:35,138 - DEBUG - Waiting to SSH to VM dest_vm, IP 210.73.217.195, Port 22 2026-03-23 04:20:35,306 - DEBUG - VM dest_vm is ready for SSH connections 2026-03-23 04:20:35,313 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06643888: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-06643888: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-03-23 04:20:35,662 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-03-23 04:20:36,047 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-03-23 04:20:36,359 - INFO - Configure the policy with allow any 2026-03-23 04:20:36,733 - DEBUG - Response for create_network : {'network': {'id': 'ffb7d76c-af4e-48b8-ab9a-3149601a63ff', 'name': 'vn1', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:20:37,106 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e771943c-bb54-4f48-b975-34ff8978fb53', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': 'ffb7d76c-af4e-48b8-ab9a-3149601a63ff', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:20:37,138 - INFO - Created VN vn1 2026-03-23 04:20:37,155 - DEBUG - VN vn1 UUID is ffb7d76c-af4e-48b8-ab9a-3149601a63ff 2026-03-23 04:20:37,611 - DEBUG - Response for create_network : {'network': {'id': '98200f3c-9034-4420-a799-f3d6db13c071', 'name': 'vn2', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:20:37,844 - DEBUG - Response for create_subnet : {'subnet': {'id': '026e7af4-9478-45bb-a1f1-20ddcac1856c', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '98200f3c-9034-4420-a799-f3d6db13c071', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:20:37,872 - INFO - Created VN vn2 2026-03-23 04:20:37,883 - DEBUG - VN vn2 UUID is 98200f3c-9034-4420-a799-f3d6db13c071 2026-03-23 04:20:37,978 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06643888: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-06643888: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-03-23 04:20:38,314 - INFO - Attaching policy sec_grp_policy_ctest-random-04327863 to vn vn1 2026-03-23 04:20:38,674 - INFO - Attaching policy sec_grp_policy_ctest-random-04327863 to vn vn2 2026-03-23 04:20:40,419 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:20:42,428 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:20:42,491 - INFO - ================================================================================ 2026-03-23 04:20:42,491 - INFO - STARTING TEST : test_check_per_peer_on_control 2026-03-23 04:20:42,492 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2026-03-23 04:20:43,795 - DEBUG - Skipping xmpp flap check 2026-03-23 04:20:43,795 - INFO - Initial checks done. Running the testcase now 2026-03-23 04:20:43,795 - INFO - 2026-03-23 04:20:43,795 - INFO - config per peer with auth_data None 2026-03-23 04:20:44,671 - INFO - configure MD5 on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:20:44,763 - INFO - config per peer with auth_data None 2026-03-23 04:20:45,384 - INFO - configure MD5 on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:20:45,479 - INFO - config per peer with auth_data None 2026-03-23 04:20:46,103 - INFO - configure MD5 on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:20:46,199 - INFO - check BGP peering is up before setting md5 config 2026-03-23 04:20:46,199 - INFO - Check BGP staus between peers 2026-03-23 04:20:46,219 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2026-03-23 04:20:46,831 - INFO - Check BGP staus between peers 2026-03-23 04:20:46,844 - DEBUG - Check the BGP connection on 10.0.0.27 2026-03-23 04:20:46,845 - DEBUG - Check the BGP connection on 10.0.0.27 2026-03-23 04:20:56,845 - INFO - Check BGP staus between peers 2026-03-23 04:20:56,859 - INFO - remove md5 configured on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:20:56,859 - INFO - config per peer with auth_data None 2026-03-23 04:20:57,420 - INFO - configure MD5 on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:20:57,515 - INFO - remove md5 configured on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:20:57,515 - INFO - config per peer with auth_data None 2026-03-23 04:20:58,123 - INFO - configure MD5 on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:20:58,211 - INFO - remove md5 configured on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:20:58,211 - INFO - config per peer with auth_data None 2026-03-23 04:20:58,791 - INFO - configure MD5 on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:20:58,891 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-03-23 04:20:58,891 - INFO - Deleting VM vn2_vm_0 2026-03-23 04:20:58,891 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-23 04:20:58,891 - ERROR - Nova failed to get host of the VM 2026-03-23 04:20:58,891 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-23 04:20:58,892 - ERROR - Nova failed to get host of the VM 2026-03-23 04:20:58,982 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-03-23 04:20:58,982 - INFO - Deleting VM vn1_vm_0 2026-03-23 04:20:58,982 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-23 04:20:58,982 - ERROR - Nova failed to get host of the VM 2026-03-23 04:20:58,982 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-23 04:20:58,982 - ERROR - Nova failed to get host of the VM 2026-03-23 04:20:59,068 - INFO - Dettaching policy sec_grp_policy_ctest-random-04327863 from vn vn2 2026-03-23 04:20:59,242 - INFO - Dettaching policy sec_grp_policy_ctest-random-04327863 from vn vn1 2026-03-23 04:20:59,483 - INFO - Deleted policy sec_grp_policy_ctest-random-04327863 2026-03-23 04:20:59,483 - INFO - Deleting VN vn2 2026-03-23 04:20:59,565 - DEBUG - VN 98200f3c-9034-4420-a799-f3d6db13c071 still in use: Unable to complete operation on network 98200f3c-9034-4420-a799-f3d6db13c071. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f0ec5b8e-dba6-489b-8fbe-23dae48f26b3'] 2026-03-23 04:20:59,565 - WARNING - Deleting VN vn2 failed..Will retry 2026-03-23 04:21:01,769 - DEBUG - Response for deleting network () 2026-03-23 04:21:01,769 - INFO - Deleting VN vn1 2026-03-23 04:21:01,956 - DEBUG - Response for deleting network () 2026-03-23 04:21:01,956 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-03-23 04:21:02,242 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-03-23 04:21:02,557 - INFO - Deleted policy allow_all 2026-03-23 04:21:02,557 - INFO - Deleting VM dest_vm 2026-03-23 04:21:02,674 - INFO - Deleting VM source_vm 2026-03-23 04:21:02,791 - INFO - Deleting VN test_vnv6dn 2026-03-23 04:21:02,860 - DEBUG - VN e1009cb3-8500-4601-94c0-03048d1de748 still in use: Unable to complete operation on network e1009cb3-8500-4601-94c0-03048d1de748. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a635811e-368e-4b76-b90a-db3a6bc1e36e'] 2026-03-23 04:21:02,860 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-03-23 04:21:05,063 - DEBUG - Response for deleting network () 2026-03-23 04:21:05,064 - INFO - Deleting VN test_vnv6sr 2026-03-23 04:21:05,269 - DEBUG - Response for deleting network () 2026-03-23 04:21:06,549 - DEBUG - Skipping xmpp flap check
2026-03-23 04:21:06,549 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2026-03-23 04:21:06,549 - INFO - -------------------------------------------------------------------------------- 2026-03-23 04:21:06,874 - DEBUG - Response for create_network : {'network': {'id': '899a3c25-72c4-42c1-90e5-11a69469794a', 'name': 'test_vnv6sr', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:21:07,113 - DEBUG - Response for create_subnet : {'subnet': {'id': '293f26ae-9743-4a00-9490-9c7ac8ff6c5d', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '899a3c25-72c4-42c1-90e5-11a69469794a', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:21:07,335 - DEBUG - Response for create_subnet : {'subnet': {'id': '6ff21077-c7ca-4603-a922-d3408d5fad08', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '899a3c25-72c4-42c1-90e5-11a69469794a', 'ip_version': 4, 'cidr': '159.68.28.192/26', 'allocation_pools': [{'start': '159.68.28.194', 'end': '159.68.28.254'}], 'gateway_ip': '159.68.28.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '159.68.28.194', 'tags': [], 'project_id': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:21:07,406 - INFO - Created VN test_vnv6sr 2026-03-23 04:21:07,418 - DEBUG - VN test_vnv6sr UUID is 899a3c25-72c4-42c1-90e5-11a69469794a 2026-03-23 04:21:07,871 - DEBUG - Response for create_network : {'network': {'id': '982efea3-1973-4527-9a3e-bde9bcfcfe6f', 'name': 'test_vnv6dn', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:21:08,096 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd65a54fa-8966-4351-9d8a-30d2583a4549', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '982efea3-1973-4527-9a3e-bde9bcfcfe6f', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:21:08,308 - DEBUG - Response for create_subnet : {'subnet': {'id': '6823de89-2dbf-477b-829b-2c05549d6b96', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '982efea3-1973-4527-9a3e-bde9bcfcfe6f', 'ip_version': 4, 'cidr': '106.243.216.192/26', 'allocation_pools': [{'start': '106.243.216.194', 'end': '106.243.216.254'}], 'gateway_ip': '106.243.216.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '106.243.216.194', 'tags': [], 'project_id': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:21:08,339 - INFO - Created VN test_vnv6dn 2026-03-23 04:21:08,351 - DEBUG - VN test_vnv6dn UUID is 982efea3-1973-4527-9a3e-bde9bcfcfe6f 2026-03-23 04:21:09,644 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:21:11,250 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:21:11,250 - INFO - Waiting for VM source_vm to be up.. 2026-03-23 04:21:11,379 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:21:16,488 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:21:16,488 - INFO - VM name : source_vm 2026-03-23 04:21:16,586 - DEBUG - VM source_vm ID is df96c4e8-4f9f-44b3-bf8f-7be8b85e027f 2026-03-23 04:21:16,587 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-5514-2 2026-03-23 04:21:16,693 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/df96c4e8-4f9f-44b3-bf8f-7be8b85e027f 2026-03-23 04:21:16,706 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/ad623b3a-a86a-4eb4-9bbb-e9ec8570bc4f 2026-03-23 04:21:19,883 - 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 1003ms') 2026-03-23 04:21:19,883 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2026-03-23 04:21:19,902 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06643888:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2026-03-23 04:21:19,903 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-06643888:test_vnv6sr is 159.68.28.193 and allocation pool is NOT set 2026-03-23 04:21:21,966 - 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=10.5 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.22 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 = 3.219/6.879/10.540/3.660 ms') 2026-03-23 04:21:21,966 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2026-03-23 04:21:22,041 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:21:22,041 - DEBUG - Waiting to SSH to VM source_vm, IP 159.68.28.195, Port 22 2026-03-23 04:21:22,107 - 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-03-23 04:21:22,222 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:21:27,223 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:21:27,224 - DEBUG - Waiting to SSH to VM source_vm, IP 159.68.28.195, Port 22 2026-03-23 04:21:27,392 - DEBUG - VM source_vm is ready for SSH connections 2026-03-23 04:21:27,392 - INFO - Waiting for VM dest_vm to be up.. 2026-03-23 04:21:27,513 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:21:27,513 - INFO - VM name : dest_vm 2026-03-23 04:21:27,626 - DEBUG - VM dest_vm ID is d1ed873f-fece-4d13-93a4-b30ac1563abb 2026-03-23 04:21:27,626 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-5514-1 2026-03-23 04:21:27,749 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d1ed873f-fece-4d13-93a4-b30ac1563abb 2026-03-23 04:21:27,763 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/c8b53562-d8c2-4f70-b039-c7731f389a9c 2026-03-23 04:21:28,935 - 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=5.01 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.424 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.424/2.719/5.014/2.295 ms') 2026-03-23 04:21:28,935 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2026-03-23 04:21:29,009 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:21:29,009 - DEBUG - Waiting to SSH to VM dest_vm, IP 106.243.216.195, Port 22 2026-03-23 04:21:29,179 - DEBUG - VM dest_vm is ready for SSH connections 2026-03-23 04:21:29,186 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06643888: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-06643888: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-03-23 04:21:29,518 - INFO - Attaching policy allow_all to vn test_vnv6sr 2026-03-23 04:21:29,882 - INFO - Attaching policy allow_all to vn test_vnv6dn 2026-03-23 04:21:30,155 - INFO - Configure the policy with allow any 2026-03-23 04:21:30,457 - DEBUG - Response for create_network : {'network': {'id': '54d62551-6074-42cb-a7af-f5ef6253a3aa', 'name': 'vn1', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:21:30,741 - DEBUG - Response for create_subnet : {'subnet': {'id': '5ac32bef-f911-4c32-9cb7-67f845b869d6', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '54d62551-6074-42cb-a7af-f5ef6253a3aa', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:21:30,769 - INFO - Created VN vn1 2026-03-23 04:21:30,786 - DEBUG - VN vn1 UUID is 54d62551-6074-42cb-a7af-f5ef6253a3aa 2026-03-23 04:21:31,187 - DEBUG - Response for create_network : {'network': {'id': '2320093f-3ed1-407b-b6e0-9015ecb9db21', 'name': 'vn2', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'project_id': 'f85fd48c2e3c4a00b8876411bd049528', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-06643888', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:21:31,460 - DEBUG - Response for create_subnet : {'subnet': {'id': '53b793cf-5b88-4817-a0ec-24ba92f50200', 'name': '', 'tenant_id': 'f85fd48c2e3c4a00b8876411bd049528', 'network_id': '2320093f-3ed1-407b-b6e0-9015ecb9db21', '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': 'f85fd48c2e3c4a00b8876411bd049528'}} 2026-03-23 04:21:31,486 - INFO - Created VN vn2 2026-03-23 04:21:31,499 - DEBUG - VN vn2 UUID is 2320093f-3ed1-407b-b6e0-9015ecb9db21 2026-03-23 04:21:31,595 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-06643888: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-06643888: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-03-23 04:21:31,966 - INFO - Attaching policy sec_grp_policy_ctest-random-98362066 to vn vn1 2026-03-23 04:21:32,355 - INFO - Attaching policy sec_grp_policy_ctest-random-98362066 to vn vn2 2026-03-23 04:21:34,080 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:21:35,760 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:21:35,829 - INFO - ================================================================================ 2026-03-23 04:21:35,829 - INFO - STARTING TEST : test_create_md5_on_control 2026-03-23 04:21:35,829 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2026-03-23 04:21:37,133 - DEBUG - Skipping xmpp flap check 2026-03-23 04:21:37,133 - INFO - Initial checks done. Running the testcase now 2026-03-23 04:21:37,133 - INFO - 2026-03-23 04:21:37,134 - INFO - config md5 on each host with auth_data None 2026-03-23 04:21:37,134 - INFO - config per peer with auth_data None 2026-03-23 04:21:37,675 - INFO - configure MD5 on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:21:37,759 - INFO - config per peer with auth_data None 2026-03-23 04:21:38,379 - INFO - configure MD5 on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:21:38,475 - INFO - config per peer with auth_data None 2026-03-23 04:21:39,147 - INFO - configure MD5 on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:21:39,227 - INFO - check if BGP between peers are up before setting md5 2026-03-23 04:21:39,227 - INFO - Check BGP staus between peers 2026-03-23 04:21:39,242 - INFO - setting MD5 config on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:21:39,242 - INFO - configure MD5 on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:21:39,331 - INFO - setting MD5 config on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:21:39,331 - INFO - configure MD5 on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:21:39,419 - INFO - setting MD5 config on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:21:39,419 - INFO - configure MD5 on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:21:39,499 - INFO - check if BGP peering is up after md5 config 2026-03-23 04:21:39,499 - INFO - Check BGP staus between peers 2026-03-23 04:21:39,512 - DEBUG - Check the BGP connection on 10.0.0.27 2026-03-23 04:21:39,513 - DEBUG - Check the BGP connection on 10.0.0.27 2026-03-23 04:21:49,514 - INFO - Check BGP staus between peers 2026-03-23 04:21:49,532 - INFO - remove md5 configured on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:21:49,532 - INFO - config per peer with auth_data None 2026-03-23 04:21:50,071 - INFO - configure MD5 on host 4d7928ab-7618-425e-af57-0434636a2aa9 2026-03-23 04:21:50,159 - INFO - remove md5 configured on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:21:50,159 - INFO - config per peer with auth_data None 2026-03-23 04:21:50,739 - INFO - configure MD5 on host 596aef79-8cce-46f6-95e9-bbe654545822 2026-03-23 04:21:50,835 - INFO - remove md5 configured on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:21:50,835 - INFO - config per peer with auth_data None 2026-03-23 04:21:51,459 - INFO - configure MD5 on host ee06ad42-0d21-4402-ab78-43fa963d6f88 2026-03-23 04:21:51,555 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2026-03-23 04:21:51,555 - INFO - Deleting VM vn2_vm_0 2026-03-23 04:21:51,555 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-23 04:21:51,555 - ERROR - Nova failed to get host of the VM 2026-03-23 04:21:51,555 - ERROR - VM vn2_vm_0 has failed to come up 2026-03-23 04:21:51,555 - ERROR - Nova failed to get host of the VM 2026-03-23 04:21:51,642 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2026-03-23 04:21:51,642 - INFO - Deleting VM vn1_vm_0 2026-03-23 04:21:51,643 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-23 04:21:51,643 - ERROR - Nova failed to get host of the VM 2026-03-23 04:21:51,643 - ERROR - VM vn1_vm_0 has failed to come up 2026-03-23 04:21:51,643 - ERROR - Nova failed to get host of the VM 2026-03-23 04:21:51,723 - INFO - Dettaching policy sec_grp_policy_ctest-random-98362066 from vn vn2 2026-03-23 04:21:51,887 - INFO - Dettaching policy sec_grp_policy_ctest-random-98362066 from vn vn1 2026-03-23 04:21:52,152 - INFO - Deleted policy sec_grp_policy_ctest-random-98362066 2026-03-23 04:21:52,152 - INFO - Deleting VN vn2 2026-03-23 04:21:52,203 - DEBUG - VN 2320093f-3ed1-407b-b6e0-9015ecb9db21 still in use: Unable to complete operation on network 2320093f-3ed1-407b-b6e0-9015ecb9db21. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e19f7aef-42d3-45ae-a78e-d489ae476a20'] 2026-03-23 04:21:52,203 - WARNING - Deleting VN vn2 failed..Will retry 2026-03-23 04:21:54,383 - DEBUG - Response for deleting network () 2026-03-23 04:21:54,383 - INFO - Deleting VN vn1 2026-03-23 04:21:54,524 - DEBUG - Response for deleting network () 2026-03-23 04:21:54,524 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2026-03-23 04:21:54,738 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2026-03-23 04:21:54,991 - INFO - Deleted policy allow_all 2026-03-23 04:21:54,991 - INFO - Deleting VM dest_vm 2026-03-23 04:21:55,076 - INFO - Deleting VM source_vm 2026-03-23 04:21:55,166 - INFO - Deleting VN test_vnv6dn 2026-03-23 04:21:55,219 - DEBUG - VN 982efea3-1973-4527-9a3e-bde9bcfcfe6f still in use: Unable to complete operation on network 982efea3-1973-4527-9a3e-bde9bcfcfe6f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6c48d2c2-6cdb-4e05-9fc8-bed7270412c7'] 2026-03-23 04:21:55,220 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2026-03-23 04:21:57,409 - DEBUG - Response for deleting network () 2026-03-23 04:21:57,409 - INFO - Deleting VN test_vnv6sr 2026-03-23 04:21:57,619 - DEBUG - Response for deleting network () 2026-03-23 04:21:58,901 - DEBUG - Skipping xmpp flap check
2026-03-23 04:21:58,902 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2026-03-23 04:21:58,902 - INFO - -------------------------------------------------------------------------------- 2026-03-23 04:21:59,768 - INFO - Deleted project: ctest-TestMd5testsOnControl-06643888, ID : f85fd48c-2e3c-4a00-b887-6411bd049528