2025-12-29 04:11:49,631 - INFO - Domain Default found not creating 2025-12-29 04:11:49,798 - INFO - Project ctest-TestMd5testsOnControl-29432819 not found, creating it 2025-12-29 04:11:50,336 - INFO - Created Project:ctest-TestMd5testsOnControl-29432819, ID : fead9b6c-5f63-4dc1-9a0c-78255e70bbf4 2025-12-29 04:11:52,578 - DEBUG - Response for create_network : {'network': {'id': '19e109e7-bddb-405d-a03a-7f28919eb35b', 'name': 'test_vnv6sr', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:11:52,917 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd879b9a9-0b4e-437b-82a8-dad05f9634f1', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '19e109e7-bddb-405d-a03a-7f28919eb35b', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:11:53,168 - DEBUG - Response for create_subnet : {'subnet': {'id': '504e8809-7b67-429a-a5d3-ca8a1ce8850b', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '19e109e7-bddb-405d-a03a-7f28919eb35b', 'ip_version': 4, 'cidr': '223.91.74.64/26', 'allocation_pools': [{'start': '223.91.74.66', 'end': '223.91.74.126'}], 'gateway_ip': '223.91.74.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '223.91.74.66', 'tags': [], 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:11:53,192 - INFO - Created VN test_vnv6sr 2025-12-29 04:11:53,204 - DEBUG - VN test_vnv6sr UUID is 19e109e7-bddb-405d-a03a-7f28919eb35b 2025-12-29 04:11:53,558 - DEBUG - Response for create_network : {'network': {'id': '0642814f-2ef4-44f5-8285-3eb5d77601b4', 'name': 'test_vnv6dn', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:11:53,831 - DEBUG - Response for create_subnet : {'subnet': {'id': '31056955-0c92-458b-ae88-ec5c1a4d4e45', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '0642814f-2ef4-44f5-8285-3eb5d77601b4', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:11:54,024 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fb970938-f78c-4b1f-af94-15a25604fe33', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '0642814f-2ef4-44f5-8285-3eb5d77601b4', 'ip_version': 4, 'cidr': '100.0.211.192/26', 'allocation_pools': [{'start': '100.0.211.194', 'end': '100.0.211.254'}], 'gateway_ip': '100.0.211.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '100.0.211.194', 'tags': [], 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:11:54,048 - INFO - Created VN test_vnv6dn 2025-12-29 04:11:54,058 - DEBUG - VN test_vnv6dn UUID is 0642814f-2ef4-44f5-8285-3eb5d77601b4 2025-12-29 04:11:54,234 - DEBUG - Services list from nova: [, , , ] 2025-12-29 04:11:55,463 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-1) 2025-12-29 04:11:56,838 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-2) 2025-12-29 04:11:56,838 - INFO - Waiting for VM source_vm to be up.. 2025-12-29 04:11:56,925 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-29 04:12:02,010 - DEBUG - VM is in ACTIVE state now 2025-12-29 04:12:02,010 - INFO - VM name : source_vm 2025-12-29 04:12:02,103 - DEBUG - VM source_vm ID is cdc4df52-21e8-4d3e-bbfe-49dc0574659e 2025-12-29 04:12:02,132 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4934-1 2025-12-29 04:12:02,221 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/cdc4df52-21e8-4d3e-bbfe-49dc0574659e 2025-12-29 04:12:02,522 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/cdc4df52-21e8-4d3e-bbfe-49dc0574659e 2025-12-29 04:12:02,556 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/6bd814b3-1e63-46b2-b4ff-62ae278e91c9 2025-12-29 04:12:05,743 - 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 1022ms') 2025-12-29 04:12:05,743 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-12-29 04:12:05,757 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-29432819:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-12-29 04:12:05,757 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-29432819:test_vnv6sr is 223.91.74.65 and allocation pool is NOT set 2025-12-29 04:12:07,824 - 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.45 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.65 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.654/5.552/8.450/2.898 ms') 2025-12-29 04:12:07,824 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-12-29 04:12:07,896 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 04:12:07,896 - DEBUG - Waiting to SSH to VM source_vm, IP 223.91.74.67, Port 22 2025-12-29 04:12:07,962 - 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-29 04:12:08,064 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 04:12:13,065 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 04:12:13,065 - DEBUG - Waiting to SSH to VM source_vm, IP 223.91.74.67, Port 22 2025-12-29 04:12:13,234 - DEBUG - VM source_vm is ready for SSH connections 2025-12-29 04:12:13,234 - INFO - Waiting for VM dest_vm to be up.. 2025-12-29 04:12:13,322 - DEBUG - VM is in ACTIVE state now 2025-12-29 04:12:13,322 - INFO - VM name : dest_vm 2025-12-29 04:12:13,406 - DEBUG - VM dest_vm ID is 602d7a47-f633-482a-a6fd-12fd6829c744 2025-12-29 04:12:13,406 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4934-2 2025-12-29 04:12:13,500 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/602d7a47-f633-482a-a6fd-12fd6829c744 2025-12-29 04:12:13,513 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/6c7daed0-fefe-4e40-b0ca-d9ea9d203aee 2025-12-29 04:12:14,680 - 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.74 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.398 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 = 0.398/1.568/2.739/1.170 ms') 2025-12-29 04:12:14,681 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-12-29 04:12:14,749 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 04:12:14,749 - DEBUG - Waiting to SSH to VM dest_vm, IP 100.0.211.195, Port 22 2025-12-29 04:12:14,910 - DEBUG - VM dest_vm is ready for SSH connections 2025-12-29 04:12:14,915 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-29432819: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-29432819: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-29 04:12:15,244 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-12-29 04:12:15,607 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-12-29 04:12:15,879 - INFO - Configure the policy with allow any 2025-12-29 04:12:16,220 - DEBUG - Response for create_network : {'network': {'id': '67aa974a-ea14-4944-bc2f-949813580351', 'name': 'vn1', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:12:16,559 - DEBUG - Response for create_subnet : {'subnet': {'id': '81b905bb-7a0b-47e6-8f91-d0d88256aef5', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '67aa974a-ea14-4944-bc2f-949813580351', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:12:16,601 - INFO - Created VN vn1 2025-12-29 04:12:16,614 - DEBUG - VN vn1 UUID is 67aa974a-ea14-4944-bc2f-949813580351 2025-12-29 04:12:17,059 - DEBUG - Response for create_network : {'network': {'id': '00c1453c-8310-4ecc-844a-240b2015b8ac', 'name': 'vn2', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:12:17,332 - DEBUG - Response for create_subnet : {'subnet': {'id': '1bda8e2a-cf5e-4fdc-8b28-94ebdb5d7e08', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '00c1453c-8310-4ecc-844a-240b2015b8ac', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:12:17,361 - INFO - Created VN vn2 2025-12-29 04:12:17,376 - DEBUG - VN vn2 UUID is 00c1453c-8310-4ecc-844a-240b2015b8ac 2025-12-29 04:12:17,463 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-29432819: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-29432819: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-29 04:12:17,783 - INFO - Attaching policy sec_grp_policy_ctest-random-82428891 to vn vn1 2025-12-29 04:12:18,139 - INFO - Attaching policy sec_grp_policy_ctest-random-82428891 to vn vn2 2025-12-29 04:12:19,662 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-1) 2025-12-29 04:12:21,053 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-2) 2025-12-29 04:12:21,115 - INFO - ================================================================================ 2025-12-29 04:12:21,115 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-12-29 04:12:21,115 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-12-29 04:12:22,378 - DEBUG - Skipping xmpp flap check 2025-12-29 04:12:22,379 - INFO - Initial checks done. Running the testcase now 2025-12-29 04:12:22,379 - INFO - 2025-12-29 04:12:22,379 - INFO - config per peer with auth_data None 2025-12-29 04:12:22,908 - INFO - configure MD5 on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:12:22,997 - INFO - config per peer with auth_data None 2025-12-29 04:12:23,588 - INFO - configure MD5 on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:12:23,672 - INFO - config per peer with auth_data None 2025-12-29 04:12:24,252 - INFO - configure MD5 on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:12:24,340 - INFO - check BGP peering is up before setting md5 config 2025-12-29 04:12:24,341 - INFO - Check BGP staus between peers 2025-12-29 04:12:24,351 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-12-29 04:12:24,928 - INFO - Check BGP staus between peers 2025-12-29 04:12:24,938 - DEBUG - Check the BGP connection on 10.0.0.61 2025-12-29 04:12:24,939 - DEBUG - Check the BGP connection on 10.0.0.61 2025-12-29 04:12:34,939 - INFO - Check BGP staus between peers 2025-12-29 04:12:34,951 - INFO - remove md5 configured on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:12:34,951 - INFO - config per peer with auth_data None 2025-12-29 04:12:35,477 - INFO - configure MD5 on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:12:35,564 - INFO - remove md5 configured on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:12:35,564 - INFO - config per peer with auth_data None 2025-12-29 04:12:36,149 - INFO - configure MD5 on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:12:36,232 - INFO - remove md5 configured on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:12:36,233 - INFO - config per peer with auth_data None 2025-12-29 04:12:36,824 - INFO - configure MD5 on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:12:36,916 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-12-29 04:12:36,916 - INFO - Deleting VM vn2_vm_0 2025-12-29 04:12:36,916 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-29 04:12:36,916 - ERROR - Nova failed to get host of the VM 2025-12-29 04:12:36,916 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-29 04:12:36,917 - ERROR - Nova failed to get host of the VM 2025-12-29 04:12:37,020 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-12-29 04:12:37,020 - INFO - Deleting VM vn1_vm_0 2025-12-29 04:12:37,020 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-29 04:12:37,020 - ERROR - Nova failed to get host of the VM 2025-12-29 04:12:37,020 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-29 04:12:37,020 - ERROR - Nova failed to get host of the VM 2025-12-29 04:12:37,101 - INFO - Dettaching policy sec_grp_policy_ctest-random-82428891 from vn vn2 2025-12-29 04:12:37,292 - INFO - Dettaching policy sec_grp_policy_ctest-random-82428891 from vn vn1 2025-12-29 04:12:37,545 - INFO - Deleted policy sec_grp_policy_ctest-random-82428891 2025-12-29 04:12:37,545 - INFO - Deleting VN vn2 2025-12-29 04:12:37,601 - DEBUG - VN 00c1453c-8310-4ecc-844a-240b2015b8ac still in use: Unable to complete operation on network 00c1453c-8310-4ecc-844a-240b2015b8ac. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0e41253f-c7d7-4c41-af5f-128a275c650e'] 2025-12-29 04:12:37,601 - WARNING - Deleting VN vn2 failed..Will retry 2025-12-29 04:12:39,748 - DEBUG - Response for deleting network () 2025-12-29 04:12:39,748 - INFO - Deleting VN vn1 2025-12-29 04:12:39,902 - DEBUG - Response for deleting network () 2025-12-29 04:12:39,903 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-12-29 04:12:40,052 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-12-29 04:12:40,315 - INFO - Deleted policy allow_all 2025-12-29 04:12:40,315 - INFO - Deleting VM dest_vm 2025-12-29 04:12:40,384 - INFO - Deleting VM source_vm 2025-12-29 04:12:40,452 - INFO - Deleting VN test_vnv6dn 2025-12-29 04:12:40,494 - DEBUG - VN 0642814f-2ef4-44f5-8285-3eb5d77601b4 still in use: Unable to complete operation on network 0642814f-2ef4-44f5-8285-3eb5d77601b4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4a82292b-35dd-4fe8-9584-4929719250a4'] 2025-12-29 04:12:40,494 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-12-29 04:12:42,666 - DEBUG - Response for deleting network () 2025-12-29 04:12:42,666 - INFO - Deleting VN test_vnv6sr 2025-12-29 04:12:42,847 - DEBUG - Response for deleting network () 2025-12-29 04:12:44,101 - DEBUG - Skipping xmpp flap check
2025-12-29 04:12:44,101 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-12-29 04:12:44,101 - INFO - -------------------------------------------------------------------------------- 2025-12-29 04:12:44,352 - DEBUG - Response for create_network : {'network': {'id': '9f0c802c-5a36-4465-aae5-3f7615b5b810', 'name': 'test_vnv6sr', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:12:44,552 - DEBUG - Response for create_subnet : {'subnet': {'id': '172e63f6-642b-4667-8bd1-1ae6564b22ed', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '9f0c802c-5a36-4465-aae5-3f7615b5b810', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:12:44,767 - DEBUG - Response for create_subnet : {'subnet': {'id': '9a259d80-e737-4c38-a556-f8a8d112e14e', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '9f0c802c-5a36-4465-aae5-3f7615b5b810', 'ip_version': 4, 'cidr': '115.242.97.128/26', 'allocation_pools': [{'start': '115.242.97.130', 'end': '115.242.97.190'}], 'gateway_ip': '115.242.97.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '115.242.97.130', 'tags': [], 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:12:44,792 - INFO - Created VN test_vnv6sr 2025-12-29 04:12:44,802 - DEBUG - VN test_vnv6sr UUID is 9f0c802c-5a36-4465-aae5-3f7615b5b810 2025-12-29 04:12:45,159 - DEBUG - Response for create_network : {'network': {'id': '9f6a963e-a701-4aba-9935-376ccf9a1454', 'name': 'test_vnv6dn', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:12:45,368 - DEBUG - Response for create_subnet : {'subnet': {'id': '4e9355ba-b500-4109-b973-abc46ed02925', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '9f6a963e-a701-4aba-9935-376ccf9a1454', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:12:45,606 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fa0a5a2d-a58b-4aed-b1a1-d5ac2887db9c', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '9f6a963e-a701-4aba-9935-376ccf9a1454', 'ip_version': 4, 'cidr': '122.193.19.192/26', 'allocation_pools': [{'start': '122.193.19.194', 'end': '122.193.19.254'}], 'gateway_ip': '122.193.19.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '122.193.19.194', 'tags': [], 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:12:45,629 - INFO - Created VN test_vnv6dn 2025-12-29 04:12:45,640 - DEBUG - VN test_vnv6dn UUID is 9f6a963e-a701-4aba-9935-376ccf9a1454 2025-12-29 04:12:46,820 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-1) 2025-12-29 04:12:48,183 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-2) 2025-12-29 04:12:48,183 - INFO - Waiting for VM source_vm to be up.. 2025-12-29 04:12:48,263 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-29 04:12:53,350 - DEBUG - VM is in ACTIVE state now 2025-12-29 04:12:53,350 - INFO - VM name : source_vm 2025-12-29 04:12:53,432 - DEBUG - VM source_vm ID is aac822c1-6531-42f5-b012-69df959b6e90 2025-12-29 04:12:53,432 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4934-1 2025-12-29 04:12:53,518 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/aac822c1-6531-42f5-b012-69df959b6e90 2025-12-29 04:12:53,530 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/20f542a9-2a30-4f4b-bf6a-f88cc99bafa0 2025-12-29 04:12:56,721 - 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 1022ms') 2025-12-29 04:12:56,721 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-12-29 04:12:56,734 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-29432819:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-12-29 04:12:56,734 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-29432819:test_vnv6sr is 115.242.97.129 and allocation pool is NOT set 2025-12-29 04:12:58,799 - 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.16 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.88 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.884/3.520/5.156/1.636 ms') 2025-12-29 04:12:58,799 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-12-29 04:12:58,865 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 04:12:58,865 - DEBUG - Waiting to SSH to VM source_vm, IP 115.242.97.131, Port 22 2025-12-29 04:12:58,931 - 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-29 04:12:59,016 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 04:13:04,017 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 04:13:04,017 - DEBUG - Waiting to SSH to VM source_vm, IP 115.242.97.131, Port 22 2025-12-29 04:13:04,186 - DEBUG - VM source_vm is ready for SSH connections 2025-12-29 04:13:04,186 - INFO - Waiting for VM dest_vm to be up.. 2025-12-29 04:13:04,278 - DEBUG - VM is in ACTIVE state now 2025-12-29 04:13:04,278 - INFO - VM name : dest_vm 2025-12-29 04:13:04,379 - DEBUG - VM dest_vm ID is 5a19e0b2-7cab-4ae4-94ee-f82a4beee1cc 2025-12-29 04:13:04,379 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4934-2 2025-12-29 04:13:04,464 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/5a19e0b2-7cab-4ae4-94ee-f82a4beee1cc 2025-12-29 04:13:04,476 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/fd8c0eab-345d-48d3-949e-bd54100ce639 2025-12-29 04:13:05,641 - 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.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.284 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.284/1.607/2.931/1.323 ms') 2025-12-29 04:13:05,641 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-12-29 04:13:05,722 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 04:13:05,722 - DEBUG - Waiting to SSH to VM dest_vm, IP 122.193.19.195, Port 22 2025-12-29 04:13:05,885 - DEBUG - VM dest_vm is ready for SSH connections 2025-12-29 04:13:05,893 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-29432819: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-29432819: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-29 04:13:06,231 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-12-29 04:13:06,597 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-12-29 04:13:06,887 - INFO - Configure the policy with allow any 2025-12-29 04:13:07,172 - DEBUG - Response for create_network : {'network': {'id': '1274d275-b62a-4ae3-af75-495d5b9cabf3', 'name': 'vn1', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:13:07,366 - DEBUG - Response for create_subnet : {'subnet': {'id': '6ad306cb-b1d4-4e7c-9a12-f147bea5f576', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': '1274d275-b62a-4ae3-af75-495d5b9cabf3', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:13:07,386 - INFO - Created VN vn1 2025-12-29 04:13:07,397 - DEBUG - VN vn1 UUID is 1274d275-b62a-4ae3-af75-495d5b9cabf3 2025-12-29 04:13:07,737 - DEBUG - Response for create_network : {'network': {'id': 'af3c9449-d92a-411c-b1e4-47afc34f5463', 'name': 'vn2', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'project_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-29432819', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 04:13:07,961 - DEBUG - Response for create_subnet : {'subnet': {'id': '5fc686ed-dd46-4a10-85ac-6336208955bb', 'name': '', 'tenant_id': 'fead9b6c5f634dc19a0c78255e70bbf4', 'network_id': 'af3c9449-d92a-411c-b1e4-47afc34f5463', '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': 'fead9b6c5f634dc19a0c78255e70bbf4'}} 2025-12-29 04:13:07,982 - INFO - Created VN vn2 2025-12-29 04:13:07,994 - DEBUG - VN vn2 UUID is af3c9449-d92a-411c-b1e4-47afc34f5463 2025-12-29 04:13:08,105 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-29432819: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-29432819: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-29 04:13:08,435 - INFO - Attaching policy sec_grp_policy_ctest-random-50529159 to vn vn1 2025-12-29 04:13:08,783 - INFO - Attaching policy sec_grp_policy_ctest-random-50529159 to vn vn2 2025-12-29 04:13:10,319 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-1) 2025-12-29 04:13:11,688 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-2) 2025-12-29 04:13:11,754 - INFO - ================================================================================ 2025-12-29 04:13:11,754 - INFO - STARTING TEST : test_create_md5_on_control 2025-12-29 04:13:11,754 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-12-29 04:13:13,041 - DEBUG - Skipping xmpp flap check 2025-12-29 04:13:13,042 - INFO - Initial checks done. Running the testcase now 2025-12-29 04:13:13,042 - INFO - 2025-12-29 04:13:13,042 - INFO - config md5 on each host with auth_data None 2025-12-29 04:13:13,042 - INFO - config per peer with auth_data None 2025-12-29 04:13:13,616 - INFO - configure MD5 on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:13:13,704 - INFO - config per peer with auth_data None 2025-12-29 04:13:14,284 - INFO - configure MD5 on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:13:14,368 - INFO - config per peer with auth_data None 2025-12-29 04:13:14,948 - INFO - configure MD5 on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:13:15,036 - INFO - check if BGP between peers are up before setting md5 2025-12-29 04:13:15,036 - INFO - Check BGP staus between peers 2025-12-29 04:13:15,047 - INFO - setting MD5 config on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:13:15,048 - INFO - configure MD5 on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:13:15,128 - INFO - setting MD5 config on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:13:15,128 - INFO - configure MD5 on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:13:15,208 - INFO - setting MD5 config on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:13:15,208 - INFO - configure MD5 on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:13:15,280 - INFO - check if BGP peering is up after md5 config 2025-12-29 04:13:15,280 - INFO - Check BGP staus between peers 2025-12-29 04:13:15,290 - DEBUG - Check the BGP connection on 10.0.0.61 2025-12-29 04:13:15,290 - DEBUG - Check the BGP connection on 10.0.0.61 2025-12-29 04:13:25,291 - INFO - Check BGP staus between peers 2025-12-29 04:13:25,303 - INFO - remove md5 configured on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:13:25,303 - INFO - config per peer with auth_data None 2025-12-29 04:13:25,836 - INFO - configure MD5 on host 467e0cee-591b-4ecd-a5d0-4547ca303441 2025-12-29 04:13:25,916 - INFO - remove md5 configured on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:13:25,916 - INFO - config per peer with auth_data None 2025-12-29 04:13:26,532 - INFO - configure MD5 on host 4d430d09-17fd-47fd-bcb9-1c2cc9b4872d 2025-12-29 04:13:26,612 - INFO - remove md5 configured on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:13:26,612 - INFO - config per peer with auth_data None 2025-12-29 04:13:27,204 - INFO - configure MD5 on host 067b6b9b-4471-4d48-80d9-27142fdbaea4 2025-12-29 04:13:27,285 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-12-29 04:13:27,285 - INFO - Deleting VM vn2_vm_0 2025-12-29 04:13:27,285 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-29 04:13:27,285 - ERROR - Nova failed to get host of the VM 2025-12-29 04:13:27,285 - ERROR - VM vn2_vm_0 has failed to come up 2025-12-29 04:13:27,285 - ERROR - Nova failed to get host of the VM 2025-12-29 04:13:27,379 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-12-29 04:13:27,380 - INFO - Deleting VM vn1_vm_0 2025-12-29 04:13:27,380 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-29 04:13:27,380 - ERROR - Nova failed to get host of the VM 2025-12-29 04:13:27,380 - ERROR - VM vn1_vm_0 has failed to come up 2025-12-29 04:13:27,380 - ERROR - Nova failed to get host of the VM 2025-12-29 04:13:27,442 - INFO - Dettaching policy sec_grp_policy_ctest-random-50529159 from vn vn2 2025-12-29 04:13:27,597 - INFO - Dettaching policy sec_grp_policy_ctest-random-50529159 from vn vn1 2025-12-29 04:13:27,840 - INFO - Deleted policy sec_grp_policy_ctest-random-50529159 2025-12-29 04:13:27,840 - INFO - Deleting VN vn2 2025-12-29 04:13:27,885 - DEBUG - VN af3c9449-d92a-411c-b1e4-47afc34f5463 still in use: Unable to complete operation on network af3c9449-d92a-411c-b1e4-47afc34f5463. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-03e724e9-c63a-4f98-b07a-ef9f66e0f10e'] 2025-12-29 04:13:27,886 - WARNING - Deleting VN vn2 failed..Will retry 2025-12-29 04:13:30,028 - DEBUG - Response for deleting network () 2025-12-29 04:13:30,029 - INFO - Deleting VN vn1 2025-12-29 04:13:30,162 - DEBUG - Response for deleting network () 2025-12-29 04:13:30,162 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-12-29 04:13:30,347 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-12-29 04:13:30,608 - INFO - Deleted policy allow_all 2025-12-29 04:13:30,608 - INFO - Deleting VM dest_vm 2025-12-29 04:13:30,673 - INFO - Deleting VM source_vm 2025-12-29 04:13:30,736 - INFO - Deleting VN test_vnv6dn 2025-12-29 04:13:30,782 - DEBUG - VN 9f6a963e-a701-4aba-9935-376ccf9a1454 still in use: Unable to complete operation on network 9f6a963e-a701-4aba-9935-376ccf9a1454. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b3ebaec7-d40f-478a-b36a-d6521deca0a8'] 2025-12-29 04:13:30,782 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-12-29 04:13:32,968 - DEBUG - Response for deleting network () 2025-12-29 04:13:32,969 - INFO - Deleting VN test_vnv6sr 2025-12-29 04:13:33,163 - DEBUG - Response for deleting network () 2025-12-29 04:13:34,429 - DEBUG - Skipping xmpp flap check
2025-12-29 04:13:34,429 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-12-29 04:13:34,429 - INFO - -------------------------------------------------------------------------------- 2025-12-29 04:13:35,161 - INFO - Deleted project: ctest-TestMd5testsOnControl-29432819, ID : fead9b6c-5f63-4dc1-9a0c-78255e70bbf4