2025-10-14 13:24:08,712 - INFO - Domain Default found not creating 2025-10-14 13:24:08,890 - INFO - Project ctest-TestMd5testsOnControl-07324503 not found, creating it 2025-10-14 13:24:09,481 - INFO - Created Project:ctest-TestMd5testsOnControl-07324503, ID : 1fab3feb-5806-4ff9-923a-bf62dab92b81 2025-10-14 13:24:11,800 - DEBUG - Response for create_network : {'network': {'id': '9777ef02-97da-41ae-a803-91ff3309867d', 'name': 'test_vnv6sr', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:24:12,174 - DEBUG - Response for create_subnet : {'subnet': {'id': 'baba210a-8cea-4c86-ab2b-c423222de7af', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '9777ef02-97da-41ae-a803-91ff3309867d', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:24:12,479 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de68262c-c854-4408-acee-48113d69b588', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '9777ef02-97da-41ae-a803-91ff3309867d', 'ip_version': 4, 'cidr': '83.48.106.64/26', 'allocation_pools': [{'start': '83.48.106.66', 'end': '83.48.106.126'}], 'gateway_ip': '83.48.106.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '83.48.106.66', 'tags': [], 'project_id': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:24:12,518 - INFO - Created VN test_vnv6sr 2025-10-14 13:24:12,540 - DEBUG - VN test_vnv6sr UUID is 9777ef02-97da-41ae-a803-91ff3309867d 2025-10-14 13:24:13,026 - DEBUG - Response for create_network : {'network': {'id': '354104dd-0c76-49e9-95eb-7bed2663d27e', 'name': 'test_vnv6dn', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:24:13,290 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ee5017d2-2685-488e-b7c6-6640be9125a2', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '354104dd-0c76-49e9-95eb-7bed2663d27e', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:24:13,555 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ef68de7a-72a6-4013-b5f6-456e6084e7c9', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '354104dd-0c76-49e9-95eb-7bed2663d27e', 'ip_version': 4, 'cidr': '29.160.195.64/26', 'allocation_pools': [{'start': '29.160.195.66', 'end': '29.160.195.126'}], 'gateway_ip': '29.160.195.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.160.195.66', 'tags': [], 'project_id': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:24:13,583 - INFO - Created VN test_vnv6dn 2025-10-14 13:24:13,601 - DEBUG - VN test_vnv6dn UUID is 354104dd-0c76-49e9-95eb-7bed2663d27e 2025-10-14 13:24:13,910 - DEBUG - Services list from nova: [, , , ] 2025-10-14 13:24:15,071 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-30), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-30) 2025-10-14 13:24:16,503 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-1) 2025-10-14 13:24:16,503 - INFO - Waiting for VM source_vm to be up.. 2025-10-14 13:24:16,599 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-14 13:24:21,693 - DEBUG - VM is in ACTIVE state now 2025-10-14 13:24:21,693 - INFO - VM name : source_vm 2025-10-14 13:24:21,781 - DEBUG - VM source_vm ID is 5ca963ab-55a1-4cd3-b281-1b1d21c36470 2025-10-14 13:24:21,806 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4312-30 2025-10-14 13:24:21,892 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/5ca963ab-55a1-4cd3-b281-1b1d21c36470 2025-10-14 13:24:22,190 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/5ca963ab-55a1-4cd3-b281-1b1d21c36470 2025-10-14 13:24:22,224 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/67e19f8e-fb92-404e-84a4-eb22ee72890c 2025-10-14 13:24:25,394 - 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') 2025-10-14 13:24:25,394 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-14 13:24:25,414 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-07324503:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-14 13:24:25,414 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-07324503:test_vnv6sr is 83.48.106.65 and allocation pool is NOT set 2025-10-14 13:24:27,483 - 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.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.429 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.429/1.973/3.517/1.544 ms') 2025-10-14 13:24:27,483 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-14 13:24:27,557 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-14 13:24:27,558 - DEBUG - Waiting to SSH to VM source_vm, IP 83.48.106.67, Port 22 2025-10-14 13:24:27,727 - DEBUG - VM source_vm is ready for SSH connections 2025-10-14 13:24:27,727 - INFO - Waiting for VM dest_vm to be up.. 2025-10-14 13:24:27,815 - DEBUG - VM is in ACTIVE state now 2025-10-14 13:24:27,816 - INFO - VM name : dest_vm 2025-10-14 13:24:27,905 - DEBUG - VM dest_vm ID is 2ad9ff8e-d4f6-47c2-9f93-18826f349502 2025-10-14 13:24:27,905 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4312-1 2025-10-14 13:24:27,991 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/2ad9ff8e-d4f6-47c2-9f93-18826f349502 2025-10-14 13:24:28,004 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/3ad393d9-626d-43cd-bd7d-59d0a11d4f8c 2025-10-14 13:24:29,190 - 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=4.08 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.46 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.463/2.769/4.076/1.306 ms') 2025-10-14 13:24:29,190 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-14 13:24:29,265 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-14 13:24:29,265 - DEBUG - Waiting to SSH to VM dest_vm, IP 29.160.195.67, Port 22 2025-10-14 13:24:29,331 - 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-10-14 13:24:29,417 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-14 13:24:34,419 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-14 13:24:34,419 - DEBUG - Waiting to SSH to VM dest_vm, IP 29.160.195.67, Port 22 2025-10-14 13:24:34,592 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-14 13:24:34,599 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-07324503: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-07324503: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-10-14 13:24:34,979 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-14 13:24:35,399 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-14 13:24:35,698 - INFO - Configure the policy with allow any 2025-10-14 13:24:36,127 - DEBUG - Response for create_network : {'network': {'id': '9bce7059-83c9-496d-b9ff-668b05331031', 'name': 'vn1', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:24:36,411 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bf8c16e3-195b-4644-bb0c-6655a06c385b', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '9bce7059-83c9-496d-b9ff-668b05331031', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:24:36,435 - INFO - Created VN vn1 2025-10-14 13:24:36,449 - DEBUG - VN vn1 UUID is 9bce7059-83c9-496d-b9ff-668b05331031 2025-10-14 13:24:36,862 - DEBUG - Response for create_network : {'network': {'id': '4e4b62e5-bcf4-420c-8216-1dab11ca0e7b', 'name': 'vn2', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:24:37,122 - DEBUG - Response for create_subnet : {'subnet': {'id': '1d9c9e9e-2fc5-4841-84ea-0976ceba6ca3', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '4e4b62e5-bcf4-420c-8216-1dab11ca0e7b', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:24:37,148 - INFO - Created VN vn2 2025-10-14 13:24:37,172 - DEBUG - VN vn2 UUID is 4e4b62e5-bcf4-420c-8216-1dab11ca0e7b 2025-10-14 13:24:37,271 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-07324503: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-07324503: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-10-14 13:24:37,642 - INFO - Attaching policy sec_grp_policy_ctest-random-85469352 to vn vn1 2025-10-14 13:24:38,035 - INFO - Attaching policy sec_grp_policy_ctest-random-85469352 to vn vn2 2025-10-14 13:24:39,635 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-30), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-30) 2025-10-14 13:24:41,049 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-1) 2025-10-14 13:24:41,111 - INFO - ================================================================================ 2025-10-14 13:24:41,111 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-10-14 13:24:41,111 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-10-14 13:24:42,422 - DEBUG - Skipping xmpp flap check 2025-10-14 13:24:42,422 - INFO - Initial checks done. Running the testcase now 2025-10-14 13:24:42,422 - INFO - 2025-10-14 13:24:42,422 - INFO - config per peer with auth_data None 2025-10-14 13:24:43,102 - INFO - configure MD5 on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:24:43,194 - INFO - config per peer with auth_data None 2025-10-14 13:24:43,898 - INFO - configure MD5 on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:24:44,002 - INFO - config per peer with auth_data None 2025-10-14 13:24:44,682 - INFO - configure MD5 on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:24:44,814 - INFO - check BGP peering is up before setting md5 config 2025-10-14 13:24:44,814 - INFO - Check BGP staus between peers 2025-10-14 13:24:44,827 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-10-14 13:24:45,514 - INFO - Check BGP staus between peers 2025-10-14 13:24:45,527 - DEBUG - Check the BGP connection on 10.0.0.254 2025-10-14 13:24:45,527 - DEBUG - Check the BGP connection on 10.0.0.254 2025-10-14 13:24:55,528 - INFO - Check BGP staus between peers 2025-10-14 13:24:55,540 - INFO - remove md5 configured on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:24:55,540 - INFO - config per peer with auth_data None 2025-10-14 13:24:56,170 - INFO - configure MD5 on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:24:56,286 - INFO - remove md5 configured on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:24:56,286 - INFO - config per peer with auth_data None 2025-10-14 13:24:56,974 - INFO - configure MD5 on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:24:57,106 - INFO - remove md5 configured on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:24:57,106 - INFO - config per peer with auth_data None 2025-10-14 13:24:57,762 - INFO - configure MD5 on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:24:57,854 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-14 13:24:57,854 - INFO - Deleting VM vn2_vm_0 2025-10-14 13:24:57,854 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-14 13:24:57,854 - ERROR - Nova failed to get host of the VM 2025-10-14 13:24:57,854 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-14 13:24:57,854 - ERROR - Nova failed to get host of the VM 2025-10-14 13:24:57,923 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-14 13:24:57,923 - INFO - Deleting VM vn1_vm_0 2025-10-14 13:24:57,923 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-14 13:24:57,923 - ERROR - Nova failed to get host of the VM 2025-10-14 13:24:57,923 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-14 13:24:57,923 - ERROR - Nova failed to get host of the VM 2025-10-14 13:24:57,985 - INFO - Dettaching policy sec_grp_policy_ctest-random-85469352 from vn vn2 2025-10-14 13:24:58,158 - INFO - Dettaching policy sec_grp_policy_ctest-random-85469352 from vn vn1 2025-10-14 13:24:58,454 - INFO - Deleted policy sec_grp_policy_ctest-random-85469352 2025-10-14 13:24:58,455 - INFO - Deleting VN vn2 2025-10-14 13:24:58,513 - DEBUG - VN 4e4b62e5-bcf4-420c-8216-1dab11ca0e7b still in use: Unable to complete operation on network 4e4b62e5-bcf4-420c-8216-1dab11ca0e7b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ccebec64-227c-45b0-8744-781d47ad8609'] 2025-10-14 13:24:58,514 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-14 13:25:00,712 - DEBUG - Response for deleting network () 2025-10-14 13:25:00,712 - INFO - Deleting VN vn1 2025-10-14 13:25:00,953 - DEBUG - Response for deleting network () 2025-10-14 13:25:00,953 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-14 13:25:01,186 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-14 13:25:01,467 - INFO - Deleted policy allow_all 2025-10-14 13:25:01,468 - INFO - Deleting VM dest_vm 2025-10-14 13:25:01,552 - INFO - Deleting VM source_vm 2025-10-14 13:25:01,623 - INFO - Deleting VN test_vnv6dn 2025-10-14 13:25:01,681 - DEBUG - VN 354104dd-0c76-49e9-95eb-7bed2663d27e still in use: Unable to complete operation on network 354104dd-0c76-49e9-95eb-7bed2663d27e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-78159b12-213c-4b66-afb9-b86d46829421'] 2025-10-14 13:25:01,681 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-14 13:25:03,917 - DEBUG - Response for deleting network () 2025-10-14 13:25:03,917 - INFO - Deleting VN test_vnv6sr 2025-10-14 13:25:04,130 - DEBUG - Response for deleting network () 2025-10-14 13:25:05,421 - DEBUG - Skipping xmpp flap check
2025-10-14 13:25:05,422 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-10-14 13:25:05,422 - INFO - -------------------------------------------------------------------------------- 2025-10-14 13:25:05,727 - DEBUG - Response for create_network : {'network': {'id': '540e3d79-68d5-4c7a-95c8-66908d7601ad', 'name': 'test_vnv6sr', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:25:06,022 - DEBUG - Response for create_subnet : {'subnet': {'id': '4d94cbed-d3d9-4116-98c9-007550a91816', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '540e3d79-68d5-4c7a-95c8-66908d7601ad', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:25:06,242 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b5f60121-47e7-4280-9232-9ea88a5a514e', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '540e3d79-68d5-4c7a-95c8-66908d7601ad', 'ip_version': 4, 'cidr': '122.152.1.192/26', 'allocation_pools': [{'start': '122.152.1.194', 'end': '122.152.1.254'}], 'gateway_ip': '122.152.1.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '122.152.1.194', 'tags': [], 'project_id': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:25:06,273 - INFO - Created VN test_vnv6sr 2025-10-14 13:25:06,286 - DEBUG - VN test_vnv6sr UUID is 540e3d79-68d5-4c7a-95c8-66908d7601ad 2025-10-14 13:25:06,735 - DEBUG - Response for create_network : {'network': {'id': '472970cb-415d-4d94-aae8-5fad32215de9', 'name': 'test_vnv6dn', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:25:06,976 - DEBUG - Response for create_subnet : {'subnet': {'id': '596ce7de-1517-4805-915c-9232f29b6dbb', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '472970cb-415d-4d94-aae8-5fad32215de9', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:25:07,214 - DEBUG - Response for create_subnet : {'subnet': {'id': '5a9481d7-22a9-4d6f-84f0-deb2d6bc7dec', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '472970cb-415d-4d94-aae8-5fad32215de9', 'ip_version': 4, 'cidr': '55.2.77.192/26', 'allocation_pools': [{'start': '55.2.77.194', 'end': '55.2.77.254'}], 'gateway_ip': '55.2.77.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '55.2.77.194', 'tags': [], 'project_id': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:25:07,240 - INFO - Created VN test_vnv6dn 2025-10-14 13:25:07,253 - DEBUG - VN test_vnv6dn UUID is 472970cb-415d-4d94-aae8-5fad32215de9 2025-10-14 13:25:08,800 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-30), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-30) 2025-10-14 13:25:10,332 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-1) 2025-10-14 13:25:10,332 - INFO - Waiting for VM source_vm to be up.. 2025-10-14 13:25:10,433 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-14 13:25:15,539 - DEBUG - VM is in ACTIVE state now 2025-10-14 13:25:15,539 - INFO - VM name : source_vm 2025-10-14 13:25:15,657 - DEBUG - VM source_vm ID is 04e6e028-2509-48e9-bc39-0ebb2c721208 2025-10-14 13:25:15,657 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4312-30 2025-10-14 13:25:15,744 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/04e6e028-2509-48e9-bc39-0ebb2c721208 2025-10-14 13:25:15,754 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/d2f4d1bb-366c-46cf-a883-a4e4fe7bb552 2025-10-14 13:25:18,929 - 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-10-14 13:25:18,929 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-14 13:25:18,946 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-07324503:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-14 13:25:18,946 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-07324503:test_vnv6sr is 122.152.1.193 and allocation pool is NOT set 2025-10-14 13:25:21,021 - 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=4.57 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.449 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.449/2.508/4.567/2.059 ms') 2025-10-14 13:25:21,021 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-14 13:25:21,105 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-14 13:25:21,105 - DEBUG - Waiting to SSH to VM source_vm, IP 122.152.1.195, Port 22 2025-10-14 13:25:21,257 - DEBUG - VM source_vm is ready for SSH connections 2025-10-14 13:25:21,257 - INFO - Waiting for VM dest_vm to be up.. 2025-10-14 13:25:21,374 - DEBUG - VM is in ACTIVE state now 2025-10-14 13:25:21,374 - INFO - VM name : dest_vm 2025-10-14 13:25:21,493 - DEBUG - VM dest_vm ID is dbc1c925-a25a-43fc-a655-a95caf810d61 2025-10-14 13:25:21,493 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4312-1 2025-10-14 13:25:21,612 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/dbc1c925-a25a-43fc-a655-a95caf810d61 2025-10-14 13:25:21,628 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/8767a498-7cc2-4769-8ce2-97ee8a79ecd7 2025-10-14 13:25:22,822 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=8.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.33 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 = 2.327/5.223/8.119/2.896 ms') 2025-10-14 13:25:22,823 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-14 13:25:22,896 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-14 13:25:22,896 - DEBUG - Waiting to SSH to VM dest_vm, IP 55.2.77.195, Port 22 2025-10-14 13:25:22,964 - 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-10-14 13:25:23,046 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-14 13:25:28,047 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-14 13:25:28,047 - DEBUG - Waiting to SSH to VM dest_vm, IP 55.2.77.195, Port 22 2025-10-14 13:25:28,210 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-14 13:25:28,216 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-07324503: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-07324503: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-10-14 13:25:28,574 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-14 13:25:29,009 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-14 13:25:29,351 - INFO - Configure the policy with allow any 2025-10-14 13:25:29,774 - DEBUG - Response for create_network : {'network': {'id': '7da71393-09fc-4771-9a38-27858f239391', 'name': 'vn1', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:25:30,028 - DEBUG - Response for create_subnet : {'subnet': {'id': '4bd21bfd-2bd7-46bb-8fe0-759f993fffe3', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': '7da71393-09fc-4771-9a38-27858f239391', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:25:30,070 - INFO - Created VN vn1 2025-10-14 13:25:30,093 - DEBUG - VN vn1 UUID is 7da71393-09fc-4771-9a38-27858f239391 2025-10-14 13:25:30,489 - DEBUG - Response for create_network : {'network': {'id': 'e4b36d23-560f-4fcb-a4f0-c52f65b30de6', 'name': 'vn2', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'project_id': '1fab3feb58064ff9923abf62dab92b81', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-07324503', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-14 13:25:30,797 - DEBUG - Response for create_subnet : {'subnet': {'id': '78816884-d8d1-4e0f-8ff4-772a9a1625f2', 'name': '', 'tenant_id': '1fab3feb58064ff9923abf62dab92b81', 'network_id': 'e4b36d23-560f-4fcb-a4f0-c52f65b30de6', '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': '1fab3feb58064ff9923abf62dab92b81'}} 2025-10-14 13:25:30,826 - INFO - Created VN vn2 2025-10-14 13:25:30,844 - DEBUG - VN vn2 UUID is e4b36d23-560f-4fcb-a4f0-c52f65b30de6 2025-10-14 13:25:30,937 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-07324503: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-07324503: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-10-14 13:25:31,319 - INFO - Attaching policy sec_grp_policy_ctest-random-41686809 to vn vn1 2025-10-14 13:25:31,707 - INFO - Attaching policy sec_grp_policy_ctest-random-41686809 to vn vn2 2025-10-14 13:25:33,571 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-30), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-30) 2025-10-14 13:25:35,048 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4312-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4312-1) 2025-10-14 13:25:35,114 - INFO - ================================================================================ 2025-10-14 13:25:35,115 - INFO - STARTING TEST : test_create_md5_on_control 2025-10-14 13:25:35,115 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-10-14 13:25:36,433 - DEBUG - Skipping xmpp flap check 2025-10-14 13:25:36,433 - INFO - Initial checks done. Running the testcase now 2025-10-14 13:25:36,433 - INFO - 2025-10-14 13:25:36,433 - INFO - config md5 on each host with auth_data None 2025-10-14 13:25:36,434 - INFO - config per peer with auth_data None 2025-10-14 13:25:37,067 - INFO - configure MD5 on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:25:37,174 - INFO - config per peer with auth_data None 2025-10-14 13:25:37,810 - INFO - configure MD5 on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:25:37,914 - INFO - config per peer with auth_data None 2025-10-14 13:25:38,626 - INFO - configure MD5 on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:25:38,726 - INFO - check if BGP between peers are up before setting md5 2025-10-14 13:25:38,726 - INFO - Check BGP staus between peers 2025-10-14 13:25:38,737 - INFO - setting MD5 config on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:25:38,737 - INFO - configure MD5 on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:25:38,822 - INFO - setting MD5 config on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:25:38,823 - INFO - configure MD5 on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:25:38,914 - INFO - setting MD5 config on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:25:38,914 - INFO - configure MD5 on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:25:39,007 - INFO - check if BGP peering is up after md5 config 2025-10-14 13:25:39,007 - INFO - Check BGP staus between peers 2025-10-14 13:25:39,017 - DEBUG - Check the BGP connection on 10.0.0.254 2025-10-14 13:25:39,018 - DEBUG - Check the BGP connection on 10.0.0.254 2025-10-14 13:25:49,018 - INFO - Check BGP staus between peers 2025-10-14 13:25:49,042 - INFO - remove md5 configured on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:25:49,043 - INFO - config per peer with auth_data None 2025-10-14 13:25:49,670 - INFO - configure MD5 on host 223aec52-81da-4967-9e8a-3daa23decaff 2025-10-14 13:25:49,762 - INFO - remove md5 configured on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:25:49,762 - INFO - config per peer with auth_data None 2025-10-14 13:25:50,442 - INFO - configure MD5 on host 1657c2c7-bc52-4f29-9f64-928f3e19c2c8 2025-10-14 13:25:50,562 - INFO - remove md5 configured on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:25:50,562 - INFO - config per peer with auth_data None 2025-10-14 13:25:51,262 - INFO - configure MD5 on host e8ba0685-28aa-4e9d-9ee3-d350daaa45e8 2025-10-14 13:25:51,370 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-14 13:25:51,370 - INFO - Deleting VM vn2_vm_0 2025-10-14 13:25:51,370 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-14 13:25:51,370 - ERROR - Nova failed to get host of the VM 2025-10-14 13:25:51,371 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-14 13:25:51,371 - ERROR - Nova failed to get host of the VM 2025-10-14 13:25:51,442 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-14 13:25:51,443 - INFO - Deleting VM vn1_vm_0 2025-10-14 13:25:51,443 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-14 13:25:51,443 - ERROR - Nova failed to get host of the VM 2025-10-14 13:25:51,443 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-14 13:25:51,443 - ERROR - Nova failed to get host of the VM 2025-10-14 13:25:51,524 - INFO - Dettaching policy sec_grp_policy_ctest-random-41686809 from vn vn2 2025-10-14 13:25:51,724 - INFO - Dettaching policy sec_grp_policy_ctest-random-41686809 from vn vn1 2025-10-14 13:25:51,992 - INFO - Deleted policy sec_grp_policy_ctest-random-41686809 2025-10-14 13:25:51,992 - INFO - Deleting VN vn2 2025-10-14 13:25:52,052 - DEBUG - VN e4b36d23-560f-4fcb-a4f0-c52f65b30de6 still in use: Unable to complete operation on network e4b36d23-560f-4fcb-a4f0-c52f65b30de6. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-fabaad3c-abe0-4710-ab6d-67e8985179db'] 2025-10-14 13:25:52,052 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-14 13:25:54,353 - DEBUG - Response for deleting network () 2025-10-14 13:25:54,353 - INFO - Deleting VN vn1 2025-10-14 13:25:54,559 - DEBUG - Response for deleting network () 2025-10-14 13:25:54,560 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-14 13:25:54,767 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-14 13:25:55,090 - INFO - Deleted policy allow_all 2025-10-14 13:25:55,091 - INFO - Deleting VM dest_vm 2025-10-14 13:25:55,193 - INFO - Deleting VM source_vm 2025-10-14 13:25:55,279 - INFO - Deleting VN test_vnv6dn 2025-10-14 13:25:55,334 - DEBUG - VN 472970cb-415d-4d94-aae8-5fad32215de9 still in use: Unable to complete operation on network 472970cb-415d-4d94-aae8-5fad32215de9. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b6b8a868-27bd-40bc-b306-3f4dcb069c2b'] 2025-10-14 13:25:55,334 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-14 13:25:57,549 - DEBUG - Response for deleting network () 2025-10-14 13:25:57,549 - INFO - Deleting VN test_vnv6sr 2025-10-14 13:25:57,756 - DEBUG - Response for deleting network () 2025-10-14 13:25:59,029 - DEBUG - Skipping xmpp flap check
2025-10-14 13:25:59,029 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-10-14 13:25:59,029 - INFO - -------------------------------------------------------------------------------- 2025-10-14 13:25:59,812 - INFO - Deleted project: ctest-TestMd5testsOnControl-07324503, ID : 1fab3feb-5806-4ff9-923a-bf62dab92b81