2025-12-15 04:17:05,682 - INFO - Domain Default found not creating 2025-12-15 04:17:05,823 - INFO - Project ctest-TestMd5testsOnControl-77311779 not found, creating it 2025-12-15 04:17:06,393 - INFO - Created Project:ctest-TestMd5testsOnControl-77311779, ID : 9ef9dd61-9fb8-476e-a8ed-87cb258e48e5 2025-12-15 04:17:08,444 - DEBUG - Response for create_network : {'network': {'id': '48f86dd4-b835-4d11-80b9-8c5ff8be8918', 'name': 'test_vnv6sr', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:17:09,074 - DEBUG - Response for create_subnet : {'subnet': {'id': '7f3f9e34-6ad2-46be-8761-afc201b2e840', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '48f86dd4-b835-4d11-80b9-8c5ff8be8918', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:17:09,515 - DEBUG - Response for create_subnet : {'subnet': {'id': '35ea5cab-2b10-4287-a816-d58459b8468e', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '48f86dd4-b835-4d11-80b9-8c5ff8be8918', 'ip_version': 4, 'cidr': '95.229.16.128/26', 'allocation_pools': [{'start': '95.229.16.130', 'end': '95.229.16.190'}], 'gateway_ip': '95.229.16.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.229.16.130', 'tags': [], 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:17:09,540 - INFO - Created VN test_vnv6sr 2025-12-15 04:17:09,552 - DEBUG - VN test_vnv6sr UUID is 48f86dd4-b835-4d11-80b9-8c5ff8be8918 2025-12-15 04:17:09,963 - DEBUG - Response for create_network : {'network': {'id': 'c2cc5f36-0b6f-46a4-9e5b-b6d02543419f', 'name': 'test_vnv6dn', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:17:10,221 - DEBUG - Response for create_subnet : {'subnet': {'id': '9f403857-2366-4044-a156-eac8e5d96bcb', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': 'c2cc5f36-0b6f-46a4-9e5b-b6d02543419f', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:17:10,449 - DEBUG - Response for create_subnet : {'subnet': {'id': '6e3d3a74-680a-4ea3-9a03-83bb464bd146', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': 'c2cc5f36-0b6f-46a4-9e5b-b6d02543419f', 'ip_version': 4, 'cidr': '181.214.222.192/26', 'allocation_pools': [{'start': '181.214.222.194', 'end': '181.214.222.254'}], 'gateway_ip': '181.214.222.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '181.214.222.194', 'tags': [], 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:17:10,474 - INFO - Created VN test_vnv6dn 2025-12-15 04:17:10,485 - DEBUG - VN test_vnv6dn UUID is c2cc5f36-0b6f-46a4-9e5b-b6d02543419f 2025-12-15 04:17:10,631 - DEBUG - Services list from nova: [, , , ] 2025-12-15 04:17:11,717 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-2) 2025-12-15 04:17:13,105 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-1) 2025-12-15 04:17:13,105 - INFO - Waiting for VM source_vm to be up.. 2025-12-15 04:17:13,199 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-15 04:17:18,279 - DEBUG - VM is in ACTIVE state now 2025-12-15 04:17:18,279 - INFO - VM name : source_vm 2025-12-15 04:17:18,353 - DEBUG - VM source_vm ID is f3b70042-5120-4542-8ee8-f0e69e70e47b 2025-12-15 04:17:18,377 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4894-2 2025-12-15 04:17:18,446 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/f3b70042-5120-4542-8ee8-f0e69e70e47b 2025-12-15 04:17:18,738 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/f3b70042-5120-4542-8ee8-f0e69e70e47b 2025-12-15 04:17:18,772 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/fb00553e-1006-43f1-b55a-c69d3936f22a 2025-12-15 04:17:21,937 - 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 1005ms') 2025-12-15 04:17:21,937 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-12-15 04:17:21,952 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-77311779:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-12-15 04:17:21,952 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-77311779:test_vnv6sr is 95.229.16.129 and allocation pool is NOT set 2025-12-15 04:17:24,020 - 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.30 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.60 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.597/5.447/9.297/3.850 ms') 2025-12-15 04:17:24,020 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-12-15 04:17:24,087 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-15 04:17:24,088 - DEBUG - Waiting to SSH to VM source_vm, IP 95.229.16.131, Port 22 2025-12-15 04:17:24,166 - 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': ''} 2025-12-15 04:17:24,279 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-15 04:17:29,280 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-15 04:17:29,280 - DEBUG - Waiting to SSH to VM source_vm, IP 95.229.16.131, Port 22 2025-12-15 04:17:29,448 - DEBUG - VM source_vm is ready for SSH connections 2025-12-15 04:17:29,449 - INFO - Waiting for VM dest_vm to be up.. 2025-12-15 04:17:29,541 - DEBUG - VM is in ACTIVE state now 2025-12-15 04:17:29,541 - INFO - VM name : dest_vm 2025-12-15 04:17:29,629 - DEBUG - VM dest_vm ID is c4323287-ed3f-4197-8d77-ec26825b3356 2025-12-15 04:17:29,629 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4894-1 2025-12-15 04:17:29,703 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/c4323287-ed3f-4197-8d77-ec26825b3356 2025-12-15 04:17:29,713 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/bf32711b-55e1-4bf2-8e12-b2bd10b977a0 2025-12-15 04:17:30,873 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.34 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/1.882/3.340/1.458 ms') 2025-12-15 04:17:30,873 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-12-15 04:17:30,944 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-15 04:17:30,944 - DEBUG - Waiting to SSH to VM dest_vm, IP 181.214.222.195, Port 22 2025-12-15 04:17:31,112 - DEBUG - VM dest_vm is ready for SSH connections 2025-12-15 04:17:31,118 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-77311779: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-77311779: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] 2025-12-15 04:17:31,451 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-12-15 04:17:31,818 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-12-15 04:17:32,121 - INFO - Configure the policy with allow any 2025-12-15 04:17:32,464 - DEBUG - Response for create_network : {'network': {'id': '3eb0a99d-4b28-4616-9968-516eae2f6803', 'name': 'vn1', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:17:32,762 - DEBUG - Response for create_subnet : {'subnet': {'id': '24f4cfcf-a885-4841-8138-4f5406a6b9cb', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '3eb0a99d-4b28-4616-9968-516eae2f6803', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:17:32,782 - INFO - Created VN vn1 2025-12-15 04:17:32,796 - DEBUG - VN vn1 UUID is 3eb0a99d-4b28-4616-9968-516eae2f6803 2025-12-15 04:17:33,191 - DEBUG - Response for create_network : {'network': {'id': '7c6949fa-e442-4e8b-b298-aea097f9ff31', 'name': 'vn2', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:17:33,422 - DEBUG - Response for create_subnet : {'subnet': {'id': '937a5107-ea04-416d-8fb3-c6215c0df618', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '7c6949fa-e442-4e8b-b298-aea097f9ff31', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:17:33,442 - INFO - Created VN vn2 2025-12-15 04:17:33,456 - DEBUG - VN vn2 UUID is 7c6949fa-e442-4e8b-b298-aea097f9ff31 2025-12-15 04:17:33,567 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-77311779: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-77311779: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] 2025-12-15 04:17:33,902 - INFO - Attaching policy sec_grp_policy_ctest-random-18106140 to vn vn1 2025-12-15 04:17:34,275 - INFO - Attaching policy sec_grp_policy_ctest-random-18106140 to vn vn2 2025-12-15 04:17:35,805 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-2) 2025-12-15 04:17:37,167 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-1) 2025-12-15 04:17:37,230 - INFO - ================================================================================ 2025-12-15 04:17:37,230 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-12-15 04:17:37,230 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-12-15 04:17:38,522 - DEBUG - Skipping xmpp flap check 2025-12-15 04:17:38,522 - INFO - Initial checks done. Running the testcase now 2025-12-15 04:17:38,522 - INFO - 2025-12-15 04:17:38,522 - INFO - config per peer with auth_data None 2025-12-15 04:17:39,095 - INFO - configure MD5 on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:17:39,187 - INFO - config per peer with auth_data None 2025-12-15 04:17:39,807 - INFO - configure MD5 on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:17:39,907 - INFO - config per peer with auth_data None 2025-12-15 04:17:40,488 - INFO - configure MD5 on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:17:40,583 - INFO - check BGP peering is up before setting md5 config 2025-12-15 04:17:40,583 - INFO - Check BGP staus between peers 2025-12-15 04:17:40,593 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-12-15 04:17:41,227 - INFO - Check BGP staus between peers 2025-12-15 04:17:41,244 - DEBUG - Check the BGP connection on 10.0.0.23 2025-12-15 04:17:41,244 - DEBUG - Check the BGP connection on 10.0.0.23 2025-12-15 04:17:51,245 - INFO - Check BGP staus between peers 2025-12-15 04:17:51,256 - INFO - remove md5 configured on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:17:51,256 - INFO - config per peer with auth_data None 2025-12-15 04:17:51,872 - INFO - configure MD5 on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:17:51,959 - INFO - remove md5 configured on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:17:51,959 - INFO - config per peer with auth_data None 2025-12-15 04:17:52,551 - INFO - configure MD5 on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:17:52,647 - INFO - remove md5 configured on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:17:52,647 - INFO - config per peer with auth_data None 2025-12-15 04:17:53,279 - INFO - configure MD5 on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:17:53,363 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-12-15 04:17:53,363 - INFO - Deleting VM vn2_vm_0 2025-12-15 04:17:53,363 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-15 04:17:53,363 - ERROR - Nova failed to get host of the VM 2025-12-15 04:17:53,363 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-15 04:17:53,363 - ERROR - Nova failed to get host of the VM 2025-12-15 04:17:53,436 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-12-15 04:17:53,436 - INFO - Deleting VM vn1_vm_0 2025-12-15 04:17:53,436 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-15 04:17:53,436 - ERROR - Nova failed to get host of the VM 2025-12-15 04:17:53,436 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-15 04:17:53,436 - ERROR - Nova failed to get host of the VM 2025-12-15 04:17:53,500 - INFO - Dettaching policy sec_grp_policy_ctest-random-18106140 from vn vn2 2025-12-15 04:17:53,666 - INFO - Dettaching policy sec_grp_policy_ctest-random-18106140 from vn vn1 2025-12-15 04:17:53,884 - INFO - Deleted policy sec_grp_policy_ctest-random-18106140 2025-12-15 04:17:53,884 - INFO - Deleting VN vn2 2025-12-15 04:17:53,939 - DEBUG - VN 7c6949fa-e442-4e8b-b298-aea097f9ff31 still in use: Unable to complete operation on network 7c6949fa-e442-4e8b-b298-aea097f9ff31. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-130a231d-7725-4ccc-9efb-3e35a5ab1c6f'] 2025-12-15 04:17:53,939 - WARNING - Deleting VN vn2 failed..Will retry 2025-12-15 04:17:56,135 - DEBUG - Response for deleting network () 2025-12-15 04:17:56,135 - INFO - Deleting VN vn1 2025-12-15 04:17:56,339 - DEBUG - Response for deleting network () 2025-12-15 04:17:56,339 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-12-15 04:17:56,538 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-12-15 04:17:56,852 - INFO - Deleted policy allow_all 2025-12-15 04:17:56,852 - INFO - Deleting VM dest_vm 2025-12-15 04:17:56,947 - INFO - Deleting VM source_vm 2025-12-15 04:17:57,014 - INFO - Deleting VN test_vnv6dn 2025-12-15 04:17:57,070 - DEBUG - VN c2cc5f36-0b6f-46a4-9e5b-b6d02543419f still in use: Unable to complete operation on network c2cc5f36-0b6f-46a4-9e5b-b6d02543419f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f213c219-ac0c-4579-88d6-34d35e260e17'] 2025-12-15 04:17:57,070 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-12-15 04:17:59,268 - DEBUG - Response for deleting network () 2025-12-15 04:17:59,268 - INFO - Deleting VN test_vnv6sr 2025-12-15 04:17:59,478 - DEBUG - Response for deleting network () 2025-12-15 04:18:00,758 - DEBUG - Skipping xmpp flap check
2025-12-15 04:18:00,758 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-12-15 04:18:00,758 - INFO - -------------------------------------------------------------------------------- 2025-12-15 04:18:01,032 - DEBUG - Response for create_network : {'network': {'id': '32a5b467-de01-43ac-9ccb-88f7e2e58da2', 'name': 'test_vnv6sr', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:18:01,313 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ff3d3f75-c1fe-4f63-ba94-2abddf77a631', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '32a5b467-de01-43ac-9ccb-88f7e2e58da2', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:18:01,548 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e3525780-bde7-42ca-a7ef-bd0f691b5fe6', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '32a5b467-de01-43ac-9ccb-88f7e2e58da2', 'ip_version': 4, 'cidr': '79.161.152.192/26', 'allocation_pools': [{'start': '79.161.152.194', 'end': '79.161.152.254'}], 'gateway_ip': '79.161.152.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '79.161.152.194', 'tags': [], 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:18:01,573 - INFO - Created VN test_vnv6sr 2025-12-15 04:18:01,591 - DEBUG - VN test_vnv6sr UUID is 32a5b467-de01-43ac-9ccb-88f7e2e58da2 2025-12-15 04:18:02,067 - DEBUG - Response for create_network : {'network': {'id': '027b83c7-1399-4c68-94b5-4ec10b94da38', 'name': 'test_vnv6dn', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:18:02,331 - DEBUG - Response for create_subnet : {'subnet': {'id': '48226ab5-c46d-422b-9409-fc97af10d44a', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '027b83c7-1399-4c68-94b5-4ec10b94da38', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:18:02,570 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ab91ddb8-f63a-4bcd-a263-ad17cec6e1e2', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '027b83c7-1399-4c68-94b5-4ec10b94da38', 'ip_version': 4, 'cidr': '52.104.16.192/26', 'allocation_pools': [{'start': '52.104.16.194', 'end': '52.104.16.254'}], 'gateway_ip': '52.104.16.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.104.16.194', 'tags': [], 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:18:02,601 - INFO - Created VN test_vnv6dn 2025-12-15 04:18:02,617 - DEBUG - VN test_vnv6dn UUID is 027b83c7-1399-4c68-94b5-4ec10b94da38 2025-12-15 04:18:03,767 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-2) 2025-12-15 04:18:05,055 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-1) 2025-12-15 04:18:05,055 - INFO - Waiting for VM source_vm to be up.. 2025-12-15 04:18:05,138 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-15 04:18:10,219 - DEBUG - VM is in ACTIVE state now 2025-12-15 04:18:10,219 - INFO - VM name : source_vm 2025-12-15 04:18:10,301 - DEBUG - VM source_vm ID is fe4dba3b-225e-4662-b3ca-87fce2e44d3d 2025-12-15 04:18:10,302 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4894-2 2025-12-15 04:18:10,379 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/fe4dba3b-225e-4662-b3ca-87fce2e44d3d 2025-12-15 04:18:10,389 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/f3ef9b3b-7c9c-49cc-8b9d-a173a4803be4 2025-12-15 04:18:13,556 - 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 1007ms') 2025-12-15 04:18:13,556 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-12-15 04:18:13,570 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-77311779:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-12-15 04:18:13,570 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-77311779:test_vnv6sr is 79.161.152.193 and allocation pool is NOT set 2025-12-15 04:18:15,630 - 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.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.408 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.408/2.963/5.518/2.555 ms') 2025-12-15 04:18:15,630 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-12-15 04:18:15,701 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-15 04:18:15,701 - DEBUG - Waiting to SSH to VM source_vm, IP 79.161.152.195, Port 22 2025-12-15 04:18:15,768 - 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': ''} 2025-12-15 04:18:15,844 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-15 04:18:20,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-15 04:18:20,845 - DEBUG - Waiting to SSH to VM source_vm, IP 79.161.152.195, Port 22 2025-12-15 04:18:21,002 - DEBUG - VM source_vm is ready for SSH connections 2025-12-15 04:18:21,002 - INFO - Waiting for VM dest_vm to be up.. 2025-12-15 04:18:21,085 - DEBUG - VM is in ACTIVE state now 2025-12-15 04:18:21,085 - INFO - VM name : dest_vm 2025-12-15 04:18:21,166 - DEBUG - VM dest_vm ID is 70543344-f821-41e1-a74f-9e961dbd7cf5 2025-12-15 04:18:21,166 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4894-1 2025-12-15 04:18:21,243 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/70543344-f821-41e1-a74f-9e961dbd7cf5 2025-12-15 04:18:21,254 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/0ddb087e-b3e2-4bd1-8236-3ba474b4fc6b 2025-12-15 04:18:22,415 - 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=2.95 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.393 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.393/1.673/2.953/1.280 ms') 2025-12-15 04:18:22,415 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-12-15 04:18:22,488 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-15 04:18:22,488 - DEBUG - Waiting to SSH to VM dest_vm, IP 52.104.16.195, Port 22 2025-12-15 04:18:22,660 - DEBUG - VM dest_vm is ready for SSH connections 2025-12-15 04:18:22,667 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-77311779: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-77311779: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] 2025-12-15 04:18:23,006 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-12-15 04:18:23,399 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-12-15 04:18:23,733 - INFO - Configure the policy with allow any 2025-12-15 04:18:24,118 - DEBUG - Response for create_network : {'network': {'id': '738f4687-0af5-4b92-b289-7bf428311825', 'name': 'vn1', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:18:24,374 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bb76a119-7af8-487d-a589-28c661fe68e8', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '738f4687-0af5-4b92-b289-7bf428311825', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:18:24,397 - INFO - Created VN vn1 2025-12-15 04:18:24,409 - DEBUG - VN vn1 UUID is 738f4687-0af5-4b92-b289-7bf428311825 2025-12-15 04:18:24,834 - DEBUG - Response for create_network : {'network': {'id': '6d515420-87f7-48fe-9c33-05da924091eb', 'name': 'vn2', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'project_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-77311779', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-12-15 04:18:25,064 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f847a6aa-9b75-4c1d-b48f-00a4d782f756', 'name': '', 'tenant_id': '9ef9dd619fb8476ea8ed87cb258e48e5', 'network_id': '6d515420-87f7-48fe-9c33-05da924091eb', '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': '9ef9dd619fb8476ea8ed87cb258e48e5'}} 2025-12-15 04:18:25,091 - INFO - Created VN vn2 2025-12-15 04:18:25,105 - DEBUG - VN vn2 UUID is 6d515420-87f7-48fe-9c33-05da924091eb 2025-12-15 04:18:25,188 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-77311779: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-77311779: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] 2025-12-15 04:18:25,573 - INFO - Attaching policy sec_grp_policy_ctest-random-12940555 to vn vn1 2025-12-15 04:18:25,958 - INFO - Attaching policy sec_grp_policy_ctest-random-12940555 to vn vn2 2025-12-15 04:18:27,466 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-2) 2025-12-15 04:18:28,930 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4894-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4894-1) 2025-12-15 04:18:29,000 - INFO - ================================================================================ 2025-12-15 04:18:29,000 - INFO - STARTING TEST : test_create_md5_on_control 2025-12-15 04:18:29,000 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-12-15 04:18:30,270 - DEBUG - Skipping xmpp flap check 2025-12-15 04:18:30,270 - INFO - Initial checks done. Running the testcase now 2025-12-15 04:18:30,270 - INFO - 2025-12-15 04:18:30,270 - INFO - config md5 on each host with auth_data None 2025-12-15 04:18:30,270 - INFO - config per peer with auth_data None 2025-12-15 04:18:30,807 - INFO - configure MD5 on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:18:30,895 - INFO - config per peer with auth_data None 2025-12-15 04:18:31,475 - INFO - configure MD5 on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:18:31,555 - INFO - config per peer with auth_data None 2025-12-15 04:18:32,195 - INFO - configure MD5 on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:18:32,295 - INFO - check if BGP between peers are up before setting md5 2025-12-15 04:18:32,295 - INFO - Check BGP staus between peers 2025-12-15 04:18:32,305 - INFO - setting MD5 config on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:18:32,305 - INFO - configure MD5 on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:18:32,379 - INFO - setting MD5 config on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:18:32,379 - INFO - configure MD5 on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:18:32,459 - INFO - setting MD5 config on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:18:32,459 - INFO - configure MD5 on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:18:32,555 - INFO - check if BGP peering is up after md5 config 2025-12-15 04:18:32,555 - INFO - Check BGP staus between peers 2025-12-15 04:18:32,565 - DEBUG - Check the BGP connection on 10.0.0.23 2025-12-15 04:18:32,565 - DEBUG - Check the BGP connection on 10.0.0.23 2025-12-15 04:18:42,566 - INFO - Check BGP staus between peers 2025-12-15 04:18:42,575 - INFO - remove md5 configured on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:18:42,576 - INFO - config per peer with auth_data None 2025-12-15 04:18:43,139 - INFO - configure MD5 on host 34c7702f-890c-4ecd-8ab9-335dc11dfb55 2025-12-15 04:18:43,235 - INFO - remove md5 configured on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:18:43,235 - INFO - config per peer with auth_data None 2025-12-15 04:18:43,911 - INFO - configure MD5 on host 17471a3e-ba10-4751-8ea7-774905410c07 2025-12-15 04:18:44,007 - INFO - remove md5 configured on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:18:44,007 - INFO - config per peer with auth_data None 2025-12-15 04:18:44,654 - INFO - configure MD5 on host dab21ccc-180a-43a3-8c6d-4b9d8393ccc3 2025-12-15 04:18:44,747 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-12-15 04:18:44,747 - INFO - Deleting VM vn2_vm_0 2025-12-15 04:18:44,747 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-15 04:18:44,747 - ERROR - Nova failed to get host of the VM 2025-12-15 04:18:44,747 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-15 04:18:44,748 - ERROR - Nova failed to get host of the VM 2025-12-15 04:18:44,814 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-12-15 04:18:44,814 - INFO - Deleting VM vn1_vm_0 2025-12-15 04:18:44,814 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-15 04:18:44,814 - ERROR - Nova failed to get host of the VM 2025-12-15 04:18:44,815 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-15 04:18:44,815 - ERROR - Nova failed to get host of the VM 2025-12-15 04:18:44,880 - INFO - Dettaching policy sec_grp_policy_ctest-random-12940555 from vn vn2 2025-12-15 04:18:45,037 - INFO - Dettaching policy sec_grp_policy_ctest-random-12940555 from vn vn1 2025-12-15 04:18:45,291 - INFO - Deleted policy sec_grp_policy_ctest-random-12940555 2025-12-15 04:18:45,291 - INFO - Deleting VN vn2 2025-12-15 04:18:45,336 - DEBUG - VN 6d515420-87f7-48fe-9c33-05da924091eb still in use: Unable to complete operation on network 6d515420-87f7-48fe-9c33-05da924091eb. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c63b5013-5c41-486b-87de-d2b5fc9484e4'] 2025-12-15 04:18:45,337 - WARNING - Deleting VN vn2 failed..Will retry 2025-12-15 04:18:47,496 - DEBUG - Response for deleting network () 2025-12-15 04:18:47,496 - INFO - Deleting VN vn1 2025-12-15 04:18:47,660 - DEBUG - Response for deleting network () 2025-12-15 04:18:47,661 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-12-15 04:18:47,847 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-12-15 04:18:48,118 - INFO - Deleted policy allow_all 2025-12-15 04:18:48,118 - INFO - Deleting VM dest_vm 2025-12-15 04:18:48,186 - INFO - Deleting VM source_vm 2025-12-15 04:18:48,263 - INFO - Deleting VN test_vnv6dn 2025-12-15 04:18:48,319 - DEBUG - VN 027b83c7-1399-4c68-94b5-4ec10b94da38 still in use: Unable to complete operation on network 027b83c7-1399-4c68-94b5-4ec10b94da38. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c130a20b-368d-444d-8c22-961dffa432a9'] 2025-12-15 04:18:48,319 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-12-15 04:18:50,501 - DEBUG - Response for deleting network () 2025-12-15 04:18:50,501 - INFO - Deleting VN test_vnv6sr 2025-12-15 04:18:50,700 - DEBUG - Response for deleting network () 2025-12-15 04:18:51,954 - DEBUG - Skipping xmpp flap check
2025-12-15 04:18:51,954 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:22]
2025-12-15 04:18:51,954 - INFO - -------------------------------------------------------------------------------- 2025-12-15 04:18:52,650 - INFO - Deleted project: ctest-TestMd5testsOnControl-77311779, ID : 9ef9dd61-9fb8-476e-a8ed-87cb258e48e5