2025-08-18 03:56:35,872 - INFO - Domain Default found not creating 2025-08-18 03:56:36,022 - INFO - Project ctest-TestMd5testsOnControl-23778370 not found, creating it 2025-08-18 03:56:36,547 - INFO - Created Project:ctest-TestMd5testsOnControl-23778370, ID : 50de5275-f141-4abf-b095-730ec443d864 2025-08-18 03:56:38,633 - DEBUG - Response for create_network : {'network': {'id': 'e39de588-9426-4b81-9081-7d5070ebcddd', 'name': 'test_vnv6sr', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:56:38,859 - DEBUG - Response for create_subnet : {'subnet': {'id': '68117768-f167-4fef-8113-0e2b173e956b', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': 'e39de588-9426-4b81-9081-7d5070ebcddd', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:56:39,054 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd2def69c-291c-45bb-890d-4d597328a73e', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': 'e39de588-9426-4b81-9081-7d5070ebcddd', 'ip_version': 4, 'cidr': '26.184.123.128/26', 'allocation_pools': [{'start': '26.184.123.130', 'end': '26.184.123.190'}], 'gateway_ip': '26.184.123.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.184.123.130', 'tags': [], 'project_id': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:56:39,076 - INFO - Created VN test_vnv6sr 2025-08-18 03:56:39,087 - DEBUG - VN test_vnv6sr UUID is e39de588-9426-4b81-9081-7d5070ebcddd 2025-08-18 03:56:39,434 - DEBUG - Response for create_network : {'network': {'id': '144528a6-c837-4950-ac40-48eea1129f28', 'name': 'test_vnv6dn', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:56:39,657 - DEBUG - Response for create_subnet : {'subnet': {'id': '405db929-d292-425a-8911-2b072750a748', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '144528a6-c837-4950-ac40-48eea1129f28', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:56:39,873 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c826da54-50ab-415a-bfc4-558eee58a214', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '144528a6-c837-4950-ac40-48eea1129f28', 'ip_version': 4, 'cidr': '59.14.181.64/26', 'allocation_pools': [{'start': '59.14.181.66', 'end': '59.14.181.126'}], 'gateway_ip': '59.14.181.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.14.181.66', 'tags': [], 'project_id': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:56:39,896 - INFO - Created VN test_vnv6dn 2025-08-18 03:56:39,907 - DEBUG - VN test_vnv6dn UUID is 144528a6-c837-4950-ac40-48eea1129f28 2025-08-18 03:56:40,049 - DEBUG - Services list from nova: [, , , ] 2025-08-18 03:56:41,079 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-1) 2025-08-18 03:56:42,374 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-2) 2025-08-18 03:56:42,374 - INFO - Waiting for VM source_vm to be up.. 2025-08-18 03:56:42,448 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-18 03:56:47,528 - DEBUG - VM is in ACTIVE state now 2025-08-18 03:56:47,528 - INFO - VM name : source_vm 2025-08-18 03:56:47,606 - DEBUG - VM source_vm ID is 2a068464-ab15-40e0-a480-16ba558cea80 2025-08-18 03:56:47,628 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3870-1 2025-08-18 03:56:47,699 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/2a068464-ab15-40e0-a480-16ba558cea80 2025-08-18 03:56:48,000 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/2a068464-ab15-40e0-a480-16ba558cea80 2025-08-18 03:56:48,031 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/385b8350-f445-4297-a16b-8e85904e3888 2025-08-18 03:56:51,210 - 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 1015ms') 2025-08-18 03:56:51,210 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-18 03:56:51,223 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-23778370:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-18 03:56:51,223 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-23778370:test_vnv6sr is 26.184.123.129 and allocation pool is NOT set 2025-08-18 03:56:53,283 - 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.399 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.399/1.867/3.336/1.468 ms') 2025-08-18 03:56:53,283 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-18 03:56:53,351 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 03:56:53,351 - DEBUG - Waiting to SSH to VM source_vm, IP 26.184.123.131, Port 22 2025-08-18 03:56:53,505 - DEBUG - VM source_vm is ready for SSH connections 2025-08-18 03:56:53,505 - INFO - Waiting for VM dest_vm to be up.. 2025-08-18 03:56:53,585 - DEBUG - VM is in ACTIVE state now 2025-08-18 03:56:53,585 - INFO - VM name : dest_vm 2025-08-18 03:56:53,658 - DEBUG - VM dest_vm ID is 871edc50-4283-46c7-9b6e-2d071238d672 2025-08-18 03:56:53,659 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3870-2 2025-08-18 03:56:53,728 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/871edc50-4283-46c7-9b6e-2d071238d672 2025-08-18 03:56:53,740 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/650bc153-06cd-4a32-aa95-987736c77e73 2025-08-18 03:56:54,905 - 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.51 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.515 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.515/1.513/2.512/0.998 ms') 2025-08-18 03:56:54,905 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-18 03:56:54,971 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 03:56:54,971 - DEBUG - Waiting to SSH to VM dest_vm, IP 59.14.181.67, Port 22 2025-08-18 03:56:55,128 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-18 03:56:55,134 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-23778370: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-23778370: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-08-18 03:56:55,445 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-18 03:56:55,809 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-18 03:56:56,100 - INFO - Configure the policy with allow any 2025-08-18 03:56:56,397 - DEBUG - Response for create_network : {'network': {'id': '03d51468-5dce-49fc-bcdc-0b0925385b16', 'name': 'vn1', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:56:56,617 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eabe8762-c1ab-41f5-8112-7c19ca47226e', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '03d51468-5dce-49fc-bcdc-0b0925385b16', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:56:56,641 - INFO - Created VN vn1 2025-08-18 03:56:56,652 - DEBUG - VN vn1 UUID is 03d51468-5dce-49fc-bcdc-0b0925385b16 2025-08-18 03:56:57,028 - DEBUG - Response for create_network : {'network': {'id': '85baf51b-8473-4781-a95a-cecc750368f4', 'name': 'vn2', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:56:57,267 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca7c4e5f-b585-402a-ad75-91a7da9a5e30', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '85baf51b-8473-4781-a95a-cecc750368f4', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:56:57,288 - INFO - Created VN vn2 2025-08-18 03:56:57,299 - DEBUG - VN vn2 UUID is 85baf51b-8473-4781-a95a-cecc750368f4 2025-08-18 03:56:57,410 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-23778370: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-23778370: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-08-18 03:56:57,737 - INFO - Attaching policy sec_grp_policy_ctest-random-93274184 to vn vn1 2025-08-18 03:56:58,081 - INFO - Attaching policy sec_grp_policy_ctest-random-93274184 to vn vn2 2025-08-18 03:56:59,518 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-1) 2025-08-18 03:57:00,865 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-2) 2025-08-18 03:57:00,925 - INFO - ================================================================================ 2025-08-18 03:57:00,926 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-08-18 03:57:00,926 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-08-18 03:57:02,227 - DEBUG - Skipping xmpp flap check 2025-08-18 03:57:02,227 - INFO - Initial checks done. Running the testcase now 2025-08-18 03:57:02,227 - INFO - 2025-08-18 03:57:02,227 - INFO - config per peer with auth_data None 2025-08-18 03:57:02,830 - INFO - configure MD5 on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:02,922 - INFO - config per peer with auth_data None 2025-08-18 03:57:03,666 - INFO - configure MD5 on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:57:03,750 - INFO - config per peer with auth_data None 2025-08-18 03:57:04,358 - INFO - configure MD5 on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:57:04,450 - INFO - check BGP peering is up before setting md5 config 2025-08-18 03:57:04,450 - INFO - Check BGP staus between peers 2025-08-18 03:57:04,471 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-08-18 03:57:05,034 - INFO - Check BGP staus between peers 2025-08-18 03:57:05,054 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-18 03:57:05,054 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-18 03:57:15,055 - INFO - Check BGP staus between peers 2025-08-18 03:57:15,068 - INFO - remove md5 configured on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:15,068 - INFO - config per peer with auth_data None 2025-08-18 03:57:15,622 - INFO - configure MD5 on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:15,706 - INFO - remove md5 configured on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:57:15,706 - INFO - config per peer with auth_data None 2025-08-18 03:57:16,278 - INFO - configure MD5 on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:57:16,362 - INFO - remove md5 configured on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:57:16,362 - INFO - config per peer with auth_data None 2025-08-18 03:57:16,915 - INFO - configure MD5 on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:57:16,998 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-18 03:57:16,998 - INFO - Deleting VM vn2_vm_0 2025-08-18 03:57:16,998 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-18 03:57:16,998 - ERROR - Nova failed to get host of the VM 2025-08-18 03:57:16,998 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-18 03:57:16,999 - ERROR - Nova failed to get host of the VM 2025-08-18 03:57:17,069 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-18 03:57:17,070 - INFO - Deleting VM vn1_vm_0 2025-08-18 03:57:17,070 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-18 03:57:17,071 - ERROR - Nova failed to get host of the VM 2025-08-18 03:57:17,071 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-18 03:57:17,071 - ERROR - Nova failed to get host of the VM 2025-08-18 03:57:17,151 - INFO - Dettaching policy sec_grp_policy_ctest-random-93274184 from vn vn2 2025-08-18 03:57:17,280 - INFO - Dettaching policy sec_grp_policy_ctest-random-93274184 from vn vn1 2025-08-18 03:57:17,537 - INFO - Deleted policy sec_grp_policy_ctest-random-93274184 2025-08-18 03:57:17,537 - INFO - Deleting VN vn2 2025-08-18 03:57:17,602 - DEBUG - VN 85baf51b-8473-4781-a95a-cecc750368f4 still in use: Unable to complete operation on network 85baf51b-8473-4781-a95a-cecc750368f4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-34df26b9-0fe4-472b-af24-c9a5b54564a0'] 2025-08-18 03:57:17,602 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-18 03:57:19,730 - DEBUG - Response for deleting network () 2025-08-18 03:57:19,730 - INFO - Deleting VN vn1 2025-08-18 03:57:19,929 - DEBUG - Response for deleting network () 2025-08-18 03:57:19,929 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-18 03:57:20,109 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-18 03:57:20,354 - INFO - Deleted policy allow_all 2025-08-18 03:57:20,354 - INFO - Deleting VM dest_vm 2025-08-18 03:57:20,432 - INFO - Deleting VM source_vm 2025-08-18 03:57:20,517 - INFO - Deleting VN test_vnv6dn 2025-08-18 03:57:20,560 - DEBUG - VN 144528a6-c837-4950-ac40-48eea1129f28 still in use: Unable to complete operation on network 144528a6-c837-4950-ac40-48eea1129f28. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ae49d737-e8df-48f7-bb8c-b736131fe58f'] 2025-08-18 03:57:20,560 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-18 03:57:22,731 - DEBUG - Response for deleting network () 2025-08-18 03:57:22,731 - INFO - Deleting VN test_vnv6sr 2025-08-18 03:57:22,884 - DEBUG - Response for deleting network () 2025-08-18 03:57:24,142 - DEBUG - Skipping xmpp flap check
2025-08-18 03:57:24,143 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-08-18 03:57:24,143 - INFO - -------------------------------------------------------------------------------- 2025-08-18 03:57:24,428 - DEBUG - Response for create_network : {'network': {'id': '8f65cf63-ef4b-4510-8d45-d3c611c99da3', 'name': 'test_vnv6sr', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:57:24,635 - DEBUG - Response for create_subnet : {'subnet': {'id': '4229ce0b-fb58-48e0-8627-cad0b85ec89f', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '8f65cf63-ef4b-4510-8d45-d3c611c99da3', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:57:24,860 - DEBUG - Response for create_subnet : {'subnet': {'id': '3b30cef7-9e66-4ea1-aa5c-de55462ca244', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '8f65cf63-ef4b-4510-8d45-d3c611c99da3', 'ip_version': 4, 'cidr': '87.55.100.0/26', 'allocation_pools': [{'start': '87.55.100.2', 'end': '87.55.100.62'}], 'gateway_ip': '87.55.100.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.55.100.2', 'tags': [], 'project_id': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:57:24,888 - INFO - Created VN test_vnv6sr 2025-08-18 03:57:24,903 - DEBUG - VN test_vnv6sr UUID is 8f65cf63-ef4b-4510-8d45-d3c611c99da3 2025-08-18 03:57:25,232 - DEBUG - Response for create_network : {'network': {'id': 'd986e3a9-9c9f-4535-be71-ab364b9d429f', 'name': 'test_vnv6dn', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:57:25,469 - DEBUG - Response for create_subnet : {'subnet': {'id': '4b8c1e74-e4e3-47ce-a9cd-385bdbfe9e28', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': 'd986e3a9-9c9f-4535-be71-ab364b9d429f', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:57:25,672 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a24fbd83-28b6-4b96-9600-80302b4acdb5', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': 'd986e3a9-9c9f-4535-be71-ab364b9d429f', 'ip_version': 4, 'cidr': '167.47.229.192/26', 'allocation_pools': [{'start': '167.47.229.194', 'end': '167.47.229.254'}], 'gateway_ip': '167.47.229.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '167.47.229.194', 'tags': [], 'project_id': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:57:25,700 - INFO - Created VN test_vnv6dn 2025-08-18 03:57:25,712 - DEBUG - VN test_vnv6dn UUID is d986e3a9-9c9f-4535-be71-ab364b9d429f 2025-08-18 03:57:26,779 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-1) 2025-08-18 03:57:28,027 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-2) 2025-08-18 03:57:28,027 - INFO - Waiting for VM source_vm to be up.. 2025-08-18 03:57:28,116 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-18 03:57:33,194 - DEBUG - VM is in ACTIVE state now 2025-08-18 03:57:33,194 - INFO - VM name : source_vm 2025-08-18 03:57:33,273 - DEBUG - VM source_vm ID is c5b20617-ae88-4db5-a266-0785d17cf977 2025-08-18 03:57:33,273 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3870-1 2025-08-18 03:57:33,341 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/c5b20617-ae88-4db5-a266-0785d17cf977 2025-08-18 03:57:33,350 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/37d33493-e758-4895-9733-15f985416187 2025-08-18 03:57:36,530 - 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 1023ms') 2025-08-18 03:57:36,531 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-18 03:57:36,546 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-23778370:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-18 03:57:36,546 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-23778370:test_vnv6sr is 87.55.100.1 and allocation pool is NOT set 2025-08-18 03:57:38,606 - 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.96 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.404 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.404/1.682/2.961/1.278 ms') 2025-08-18 03:57:38,606 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-18 03:57:38,676 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 03:57:38,676 - DEBUG - Waiting to SSH to VM source_vm, IP 87.55.100.3, Port 22 2025-08-18 03:57:38,828 - DEBUG - VM source_vm is ready for SSH connections 2025-08-18 03:57:38,828 - INFO - Waiting for VM dest_vm to be up.. 2025-08-18 03:57:38,898 - DEBUG - VM is in ACTIVE state now 2025-08-18 03:57:38,898 - INFO - VM name : dest_vm 2025-08-18 03:57:38,967 - DEBUG - VM dest_vm ID is 194c2f84-0382-41e9-be68-edeb22dc27ba 2025-08-18 03:57:38,968 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3870-2 2025-08-18 03:57:39,038 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/194c2f84-0382-41e9-be68-edeb22dc27ba 2025-08-18 03:57:39,047 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/2b415ade-5f2b-41a4-8fbc-e60ba64f5a1f 2025-08-18 03:57:40,211 - 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.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.558 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.558/4.849/9.140/4.291 ms') 2025-08-18 03:57:40,211 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-18 03:57:40,287 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 03:57:40,287 - DEBUG - Waiting to SSH to VM dest_vm, IP 167.47.229.195, Port 22 2025-08-18 03:57:40,450 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-18 03:57:40,457 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-23778370: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-23778370: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-08-18 03:57:40,788 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-18 03:57:41,142 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-18 03:57:41,400 - INFO - Configure the policy with allow any 2025-08-18 03:57:41,672 - DEBUG - Response for create_network : {'network': {'id': '3e93555e-ed06-4173-8305-9b15ee2ed370', 'name': 'vn1', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:57:41,888 - DEBUG - Response for create_subnet : {'subnet': {'id': '9311f94d-98bc-45e6-bc67-a75816a177f5', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': '3e93555e-ed06-4173-8305-9b15ee2ed370', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:57:41,918 - INFO - Created VN vn1 2025-08-18 03:57:41,931 - DEBUG - VN vn1 UUID is 3e93555e-ed06-4173-8305-9b15ee2ed370 2025-08-18 03:57:42,243 - DEBUG - Response for create_network : {'network': {'id': 'ec67e9c9-886e-4302-b120-cd9a93f812c3', 'name': 'vn2', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'project_id': '50de5275f1414abfb095730ec443d864', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-23778370', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 03:57:42,480 - DEBUG - Response for create_subnet : {'subnet': {'id': '7e7c3456-a434-4036-88e2-1f7e9842ba94', 'name': '', 'tenant_id': '50de5275f1414abfb095730ec443d864', 'network_id': 'ec67e9c9-886e-4302-b120-cd9a93f812c3', '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': '50de5275f1414abfb095730ec443d864'}} 2025-08-18 03:57:42,501 - INFO - Created VN vn2 2025-08-18 03:57:42,514 - DEBUG - VN vn2 UUID is ec67e9c9-886e-4302-b120-cd9a93f812c3 2025-08-18 03:57:42,622 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-23778370: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-23778370: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-08-18 03:57:42,944 - INFO - Attaching policy sec_grp_policy_ctest-random-46599580 to vn vn1 2025-08-18 03:57:43,276 - INFO - Attaching policy sec_grp_policy_ctest-random-46599580 to vn vn2 2025-08-18 03:57:44,848 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-1) 2025-08-18 03:57:46,203 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-2) 2025-08-18 03:57:46,261 - INFO - ================================================================================ 2025-08-18 03:57:46,261 - INFO - STARTING TEST : test_create_md5_on_control 2025-08-18 03:57:46,261 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-08-18 03:57:47,527 - DEBUG - Skipping xmpp flap check 2025-08-18 03:57:47,527 - INFO - Initial checks done. Running the testcase now 2025-08-18 03:57:47,527 - INFO - 2025-08-18 03:57:47,527 - INFO - config md5 on each host with auth_data None 2025-08-18 03:57:47,527 - INFO - config per peer with auth_data None 2025-08-18 03:57:48,035 - INFO - configure MD5 on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:48,110 - INFO - config per peer with auth_data None 2025-08-18 03:57:48,674 - INFO - configure MD5 on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:57:48,750 - INFO - config per peer with auth_data None 2025-08-18 03:57:49,294 - INFO - configure MD5 on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:57:49,374 - INFO - check if BGP between peers are up before setting md5 2025-08-18 03:57:49,374 - INFO - Check BGP staus between peers 2025-08-18 03:57:49,386 - INFO - setting MD5 config on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:49,386 - INFO - configure MD5 on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:49,462 - INFO - setting MD5 config on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:57:49,462 - INFO - configure MD5 on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:57:49,538 - INFO - setting MD5 config on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:57:49,538 - INFO - configure MD5 on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:57:49,618 - INFO - check if BGP peering is up after md5 config 2025-08-18 03:57:49,618 - INFO - Check BGP staus between peers 2025-08-18 03:57:49,630 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-18 03:57:49,630 - DEBUG - Check the BGP connection on 10.0.0.27 2025-08-18 03:57:59,631 - INFO - Check BGP staus between peers 2025-08-18 03:57:59,643 - INFO - remove md5 configured on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:57:59,643 - INFO - config per peer with auth_data None 2025-08-18 03:58:00,159 - INFO - configure MD5 on host ea2b8abf-63c0-448f-ab50-18a44d184d22 2025-08-18 03:58:00,234 - INFO - remove md5 configured on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:58:00,234 - INFO - config per peer with auth_data None 2025-08-18 03:58:00,814 - INFO - configure MD5 on host 21ce6511-2fdc-4898-b565-a11a37750084 2025-08-18 03:58:00,894 - INFO - remove md5 configured on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:58:00,894 - INFO - config per peer with auth_data None 2025-08-18 03:58:01,462 - INFO - configure MD5 on host 0dbb5ebb-d62c-4a17-994c-641f35978290 2025-08-18 03:58:01,551 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-18 03:58:01,551 - INFO - Deleting VM vn2_vm_0 2025-08-18 03:58:01,551 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-18 03:58:01,551 - ERROR - Nova failed to get host of the VM 2025-08-18 03:58:01,551 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-18 03:58:01,551 - ERROR - Nova failed to get host of the VM 2025-08-18 03:58:01,613 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-18 03:58:01,613 - INFO - Deleting VM vn1_vm_0 2025-08-18 03:58:01,613 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-18 03:58:01,613 - ERROR - Nova failed to get host of the VM 2025-08-18 03:58:01,613 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-18 03:58:01,613 - ERROR - Nova failed to get host of the VM 2025-08-18 03:58:01,681 - INFO - Dettaching policy sec_grp_policy_ctest-random-46599580 from vn vn2 2025-08-18 03:58:01,853 - INFO - Dettaching policy sec_grp_policy_ctest-random-46599580 from vn vn1 2025-08-18 03:58:02,132 - INFO - Deleted policy sec_grp_policy_ctest-random-46599580 2025-08-18 03:58:02,132 - INFO - Deleting VN vn2 2025-08-18 03:58:02,183 - DEBUG - VN ec67e9c9-886e-4302-b120-cd9a93f812c3 still in use: Unable to complete operation on network ec67e9c9-886e-4302-b120-cd9a93f812c3. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-404752d1-a9a2-43e4-a02f-6fe60cc59451'] 2025-08-18 03:58:02,184 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-18 03:58:04,322 - DEBUG - Response for deleting network () 2025-08-18 03:58:04,323 - INFO - Deleting VN vn1 2025-08-18 03:58:04,476 - DEBUG - Response for deleting network () 2025-08-18 03:58:04,476 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-18 03:58:04,629 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-18 03:58:04,926 - INFO - Deleted policy allow_all 2025-08-18 03:58:04,926 - INFO - Deleting VM dest_vm 2025-08-18 03:58:05,083 - INFO - Deleting VM source_vm 2025-08-18 03:58:05,157 - INFO - Deleting VN test_vnv6dn 2025-08-18 03:58:05,208 - DEBUG - VN d986e3a9-9c9f-4535-be71-ab364b9d429f still in use: Unable to complete operation on network d986e3a9-9c9f-4535-be71-ab364b9d429f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9f55d745-74da-4442-90c0-6ad22389edfe'] 2025-08-18 03:58:05,208 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-18 03:58:07,407 - DEBUG - Response for deleting network () 2025-08-18 03:58:07,407 - INFO - Deleting VN test_vnv6sr 2025-08-18 03:58:07,662 - DEBUG - Response for deleting network () 2025-08-18 03:58:08,917 - DEBUG - Skipping xmpp flap check
2025-08-18 03:58:08,917 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:22]
2025-08-18 03:58:08,917 - INFO - -------------------------------------------------------------------------------- 2025-08-18 03:58:09,609 - INFO - Deleted project: ctest-TestMd5testsOnControl-23778370, ID : 50de5275-f141-4abf-b095-730ec443d864