2025-08-11 07:52:16,395 - INFO - Domain Default found not creating 2025-08-11 07:52:16,513 - INFO - Project ctest-TestMd5testsOnControl-80931337 not found, creating it 2025-08-11 07:52:16,978 - INFO - Created Project:ctest-TestMd5testsOnControl-80931337, ID : eeb617c4-6b8e-4281-8383-77c7b4bfb60c 2025-08-11 07:52:18,956 - DEBUG - Response for create_network : {'network': {'id': '796f94ad-a023-46be-a122-3c1cf7cc333a', 'name': 'test_vnv6sr', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:52:19,230 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca3f6663-1828-41fe-bb9d-c853a369bfc5', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '796f94ad-a023-46be-a122-3c1cf7cc333a', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:52:19,506 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd5387873-9eaa-416d-b024-d48d5c19652a', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '796f94ad-a023-46be-a122-3c1cf7cc333a', 'ip_version': 4, 'cidr': '89.183.92.0/26', 'allocation_pools': [{'start': '89.183.92.2', 'end': '89.183.92.62'}], 'gateway_ip': '89.183.92.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '89.183.92.2', 'tags': [], 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:52:19,529 - INFO - Created VN test_vnv6sr 2025-08-11 07:52:19,541 - DEBUG - VN test_vnv6sr UUID is 796f94ad-a023-46be-a122-3c1cf7cc333a 2025-08-11 07:52:19,864 - DEBUG - Response for create_network : {'network': {'id': '85cc9939-1417-42b8-81d7-2cc1f463074e', 'name': 'test_vnv6dn', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:52:20,086 - DEBUG - Response for create_subnet : {'subnet': {'id': '4968d0b4-123c-4ac5-a846-473a565868ec', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '85cc9939-1417-42b8-81d7-2cc1f463074e', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:52:20,277 - DEBUG - Response for create_subnet : {'subnet': {'id': '2b456bfb-2c36-4531-90f3-fbdb3ddc1ca6', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '85cc9939-1417-42b8-81d7-2cc1f463074e', 'ip_version': 4, 'cidr': '95.185.167.128/26', 'allocation_pools': [{'start': '95.185.167.130', 'end': '95.185.167.190'}], 'gateway_ip': '95.185.167.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.185.167.130', 'tags': [], 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:52:20,299 - INFO - Created VN test_vnv6dn 2025-08-11 07:52:20,311 - DEBUG - VN test_vnv6dn UUID is 85cc9939-1417-42b8-81d7-2cc1f463074e 2025-08-11 07:52:20,454 - DEBUG - Services list from nova: [, , , ] 2025-08-11 07:52:21,506 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2) 2025-08-11 07:52:22,783 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1) 2025-08-11 07:52:22,784 - INFO - Waiting for VM source_vm to be up.. 2025-08-11 07:52:22,864 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-11 07:52:27,937 - DEBUG - VM is in ACTIVE state now 2025-08-11 07:52:27,937 - INFO - VM name : source_vm 2025-08-11 07:52:28,006 - DEBUG - VM source_vm ID is 13c96716-1063-4c25-acc8-1a74179f7ff0 2025-08-11 07:52:28,030 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3798-2 2025-08-11 07:52:28,106 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/13c96716-1063-4c25-acc8-1a74179f7ff0 2025-08-11 07:52:28,396 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/13c96716-1063-4c25-acc8-1a74179f7ff0 2025-08-11 07:52:28,424 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/39e96faa-f9be-4da9-abc4-8a88a4140c87 2025-08-11 07:52:31,637 - 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 1024ms') 2025-08-11 07:52:31,637 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-11 07:52:31,649 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-80931337:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-11 07:52:31,649 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-80931337:test_vnv6sr is 89.183.92.1 and allocation pool is NOT set 2025-08-11 07:52:33,714 - 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.45 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.406 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.406/1.929/3.453/1.523 ms') 2025-08-11 07:52:33,714 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-11 07:52:33,825 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 07:52:33,825 - DEBUG - Waiting to SSH to VM source_vm, IP 89.183.92.3, Port 22 2025-08-11 07:52:33,976 - DEBUG - VM source_vm is ready for SSH connections 2025-08-11 07:52:33,976 - INFO - Waiting for VM dest_vm to be up.. 2025-08-11 07:52:34,052 - DEBUG - VM is in ACTIVE state now 2025-08-11 07:52:34,053 - INFO - VM name : dest_vm 2025-08-11 07:52:34,130 - DEBUG - VM dest_vm ID is 7dc70c8a-1a9c-4866-873d-98f18075b30b 2025-08-11 07:52:34,130 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3798-1 2025-08-11 07:52:34,198 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/7dc70c8a-1a9c-4866-873d-98f18075b30b 2025-08-11 07:52:34,207 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e19308da-57d9-4370-bbcd-1d6938114cf8 2025-08-11 07:52:35,402 - 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.94 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.370 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.370/1.654/2.939/1.284 ms') 2025-08-11 07:52:35,402 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-11 07:52:35,508 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 07:52:35,508 - DEBUG - Waiting to SSH to VM dest_vm, IP 95.185.167.131, Port 22 2025-08-11 07:52:35,668 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-11 07:52:35,674 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-80931337: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-80931337: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-11 07:52:36,073 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-11 07:52:36,497 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-11 07:52:36,808 - INFO - Configure the policy with allow any 2025-08-11 07:52:37,079 - DEBUG - Response for create_network : {'network': {'id': 'f433ccd8-d37d-4760-9a40-39f891849c72', 'name': 'vn1', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:52:37,292 - DEBUG - Response for create_subnet : {'subnet': {'id': '922f0b92-13f8-4e63-949c-56febcdacdb9', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': 'f433ccd8-d37d-4760-9a40-39f891849c72', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:52:37,324 - INFO - Created VN vn1 2025-08-11 07:52:37,339 - DEBUG - VN vn1 UUID is f433ccd8-d37d-4760-9a40-39f891849c72 2025-08-11 07:52:37,638 - DEBUG - Response for create_network : {'network': {'id': '87ead4d9-8b35-423b-a0e0-c7fb37fb1c98', 'name': 'vn2', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:52:37,839 - DEBUG - Response for create_subnet : {'subnet': {'id': '3e581d32-d629-45ec-beac-888b1aa008de', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '87ead4d9-8b35-423b-a0e0-c7fb37fb1c98', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:52:37,861 - INFO - Created VN vn2 2025-08-11 07:52:37,873 - DEBUG - VN vn2 UUID is 87ead4d9-8b35-423b-a0e0-c7fb37fb1c98 2025-08-11 07:52:37,976 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-80931337: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-80931337: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-11 07:52:38,290 - INFO - Attaching policy sec_grp_policy_ctest-random-65268049 to vn vn1 2025-08-11 07:52:38,602 - INFO - Attaching policy sec_grp_policy_ctest-random-65268049 to vn vn2 2025-08-11 07:52:39,937 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2) 2025-08-11 07:52:41,296 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1) 2025-08-11 07:52:41,355 - INFO - ================================================================================ 2025-08-11 07:52:41,355 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-08-11 07:52:41,355 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-08-11 07:52:42,624 - DEBUG - Skipping xmpp flap check 2025-08-11 07:52:42,624 - INFO - Initial checks done. Running the testcase now 2025-08-11 07:52:42,624 - INFO - 2025-08-11 07:52:42,625 - INFO - config per peer with auth_data None 2025-08-11 07:52:43,121 - INFO - configure MD5 on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:52:43,196 - INFO - config per peer with auth_data None 2025-08-11 07:52:43,704 - INFO - configure MD5 on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:52:43,772 - INFO - config per peer with auth_data None 2025-08-11 07:52:44,272 - INFO - configure MD5 on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:52:44,345 - INFO - check BGP peering is up before setting md5 config 2025-08-11 07:52:44,345 - INFO - Check BGP staus between peers 2025-08-11 07:52:44,367 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-08-11 07:52:44,897 - INFO - Check BGP staus between peers 2025-08-11 07:52:44,912 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-11 07:52:44,912 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-11 07:52:54,912 - INFO - Check BGP staus between peers 2025-08-11 07:52:54,925 - INFO - remove md5 configured on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:52:54,925 - INFO - config per peer with auth_data None 2025-08-11 07:52:55,412 - INFO - configure MD5 on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:52:55,497 - INFO - remove md5 configured on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:52:55,497 - INFO - config per peer with auth_data None 2025-08-11 07:52:56,033 - INFO - configure MD5 on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:52:56,109 - INFO - remove md5 configured on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:52:56,109 - INFO - config per peer with auth_data None 2025-08-11 07:52:56,621 - INFO - configure MD5 on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:52:56,697 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-11 07:52:56,697 - INFO - Deleting VM vn2_vm_0 2025-08-11 07:52:56,697 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-11 07:52:56,697 - ERROR - Nova failed to get host of the VM 2025-08-11 07:52:56,697 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-11 07:52:56,697 - ERROR - Nova failed to get host of the VM 2025-08-11 07:52:56,757 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-11 07:52:56,758 - INFO - Deleting VM vn1_vm_0 2025-08-11 07:52:56,758 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-11 07:52:56,758 - ERROR - Nova failed to get host of the VM 2025-08-11 07:52:56,758 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-11 07:52:56,758 - ERROR - Nova failed to get host of the VM 2025-08-11 07:52:56,830 - INFO - Dettaching policy sec_grp_policy_ctest-random-65268049 from vn vn2 2025-08-11 07:52:56,999 - INFO - Dettaching policy sec_grp_policy_ctest-random-65268049 from vn vn1 2025-08-11 07:52:57,220 - INFO - Deleted policy sec_grp_policy_ctest-random-65268049 2025-08-11 07:52:57,220 - INFO - Deleting VN vn2 2025-08-11 07:52:57,270 - DEBUG - VN 87ead4d9-8b35-423b-a0e0-c7fb37fb1c98 still in use: Unable to complete operation on network 87ead4d9-8b35-423b-a0e0-c7fb37fb1c98. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-1143d5c0-9da9-4a3a-ba6c-4ee3114c6cff'] 2025-08-11 07:52:57,270 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-11 07:52:59,402 - DEBUG - Response for deleting network () 2025-08-11 07:52:59,403 - INFO - Deleting VN vn1 2025-08-11 07:52:59,552 - DEBUG - Response for deleting network () 2025-08-11 07:52:59,553 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-11 07:52:59,707 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-11 07:52:59,978 - INFO - Deleted policy allow_all 2025-08-11 07:52:59,978 - INFO - Deleting VM dest_vm 2025-08-11 07:53:00,084 - INFO - Deleting VM source_vm 2025-08-11 07:53:00,158 - INFO - Deleting VN test_vnv6dn 2025-08-11 07:53:00,211 - DEBUG - VN 85cc9939-1417-42b8-81d7-2cc1f463074e still in use: Unable to complete operation on network 85cc9939-1417-42b8-81d7-2cc1f463074e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3ac30875-0c44-43de-a4ff-cc3bf63b17ac'] 2025-08-11 07:53:00,211 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-11 07:53:02,357 - DEBUG - Response for deleting network () 2025-08-11 07:53:02,357 - INFO - Deleting VN test_vnv6sr 2025-08-11 07:53:02,547 - DEBUG - Response for deleting network () 2025-08-11 07:53:03,796 - DEBUG - Skipping xmpp flap check
2025-08-11 07:53:03,796 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:22]
2025-08-11 07:53:03,796 - INFO - -------------------------------------------------------------------------------- 2025-08-11 07:53:04,029 - DEBUG - Response for create_network : {'network': {'id': '31294146-c7ac-4052-8fcd-a27d2ad0c8c5', 'name': 'test_vnv6sr', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:53:04,249 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd4b80b59-cb38-4104-b34e-0a3afd5ac3f7', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '31294146-c7ac-4052-8fcd-a27d2ad0c8c5', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:53:04,411 - DEBUG - Response for create_subnet : {'subnet': {'id': '97ac79d5-9d7a-4381-8054-ca7c2b310f6f', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '31294146-c7ac-4052-8fcd-a27d2ad0c8c5', 'ip_version': 4, 'cidr': '43.107.255.128/26', 'allocation_pools': [{'start': '43.107.255.130', 'end': '43.107.255.190'}], 'gateway_ip': '43.107.255.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.107.255.130', 'tags': [], 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:53:04,432 - INFO - Created VN test_vnv6sr 2025-08-11 07:53:04,442 - DEBUG - VN test_vnv6sr UUID is 31294146-c7ac-4052-8fcd-a27d2ad0c8c5 2025-08-11 07:53:04,734 - DEBUG - Response for create_network : {'network': {'id': '08c975b9-f33d-4378-9875-400757cae741', 'name': 'test_vnv6dn', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:53:04,963 - DEBUG - Response for create_subnet : {'subnet': {'id': '80029ada-08cd-4727-9af2-69985b62392d', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '08c975b9-f33d-4378-9875-400757cae741', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:53:05,145 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ee878def-41e6-4c72-8e48-b62ce9568d5d', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '08c975b9-f33d-4378-9875-400757cae741', 'ip_version': 4, 'cidr': '53.114.25.192/26', 'allocation_pools': [{'start': '53.114.25.194', 'end': '53.114.25.254'}], 'gateway_ip': '53.114.25.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '53.114.25.194', 'tags': [], 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:53:05,168 - INFO - Created VN test_vnv6dn 2025-08-11 07:53:05,178 - DEBUG - VN test_vnv6dn UUID is 08c975b9-f33d-4378-9875-400757cae741 2025-08-11 07:53:06,213 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2) 2025-08-11 07:53:07,427 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1) 2025-08-11 07:53:07,427 - INFO - Waiting for VM source_vm to be up.. 2025-08-11 07:53:07,499 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-11 07:53:12,575 - DEBUG - VM is in ACTIVE state now 2025-08-11 07:53:12,575 - INFO - VM name : source_vm 2025-08-11 07:53:12,643 - DEBUG - VM source_vm ID is 7273ceb0-3105-43af-bbd1-75c9fbf6ab40 2025-08-11 07:53:12,643 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3798-2 2025-08-11 07:53:12,710 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/7273ceb0-3105-43af-bbd1-75c9fbf6ab40 2025-08-11 07:53:12,728 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/a5eb9546-b5c7-4ad2-b2d5-0b54c93320e6 2025-08-11 07:53:15,956 - 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 1030ms') 2025-08-11 07:53:15,956 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-11 07:53:15,970 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-80931337:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-11 07:53:15,970 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-80931337:test_vnv6sr is 43.107.255.129 and allocation pool is NOT set 2025-08-11 07:53:18,029 - 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.66 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.421 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.421/2.038/3.655/1.617 ms') 2025-08-11 07:53:18,029 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-11 07:53:18,141 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 07:53:18,141 - DEBUG - Waiting to SSH to VM source_vm, IP 43.107.255.131, Port 22 2025-08-11 07:53:18,301 - DEBUG - VM source_vm is ready for SSH connections 2025-08-11 07:53:18,301 - INFO - Waiting for VM dest_vm to be up.. 2025-08-11 07:53:18,372 - DEBUG - VM is in ACTIVE state now 2025-08-11 07:53:18,372 - INFO - VM name : dest_vm 2025-08-11 07:53:18,440 - DEBUG - VM dest_vm ID is 5b0783a2-35f4-43da-9cc3-3ac49d32c7b2 2025-08-11 07:53:18,440 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3798-1 2025-08-11 07:53:18,505 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/5b0783a2-35f4-43da-9cc3-3ac49d32c7b2 2025-08-11 07:53:18,514 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/f2769311-87ea-4320-8b8a-1e75c45a5953 2025-08-11 07:53:19,700 - 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.41 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.301 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.301/1.355/2.410/1.054 ms') 2025-08-11 07:53:19,700 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-11 07:53:19,813 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 07:53:19,813 - DEBUG - Waiting to SSH to VM dest_vm, IP 53.114.25.195, Port 22 2025-08-11 07:53:19,965 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-11 07:53:19,971 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-80931337: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-80931337: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-11 07:53:20,287 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-11 07:53:20,619 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-11 07:53:20,911 - INFO - Configure the policy with allow any 2025-08-11 07:53:21,208 - DEBUG - Response for create_network : {'network': {'id': 'f0fab49c-b9da-4199-b53b-00c9f6340aad', 'name': 'vn1', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:53:21,410 - DEBUG - Response for create_subnet : {'subnet': {'id': '91e649eb-d418-46a0-a775-3107d1724657', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': 'f0fab49c-b9da-4199-b53b-00c9f6340aad', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:53:21,430 - INFO - Created VN vn1 2025-08-11 07:53:21,443 - DEBUG - VN vn1 UUID is f0fab49c-b9da-4199-b53b-00c9f6340aad 2025-08-11 07:53:21,799 - DEBUG - Response for create_network : {'network': {'id': '702701ab-0106-496c-b9f2-42b8dcb07347', 'name': 'vn2', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'project_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-80931337', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 07:53:22,031 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f99b1c85-e82d-4a49-a369-3dd753b631a7', 'name': '', 'tenant_id': 'eeb617c46b8e4281838377c7b4bfb60c', 'network_id': '702701ab-0106-496c-b9f2-42b8dcb07347', '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': 'eeb617c46b8e4281838377c7b4bfb60c'}} 2025-08-11 07:53:22,054 - INFO - Created VN vn2 2025-08-11 07:53:22,064 - DEBUG - VN vn2 UUID is 702701ab-0106-496c-b9f2-42b8dcb07347 2025-08-11 07:53:22,172 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-80931337: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-80931337: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-11 07:53:22,486 - INFO - Attaching policy sec_grp_policy_ctest-random-02013742 to vn vn1 2025-08-11 07:53:22,823 - INFO - Attaching policy sec_grp_policy_ctest-random-02013742 to vn vn2 2025-08-11 07:53:24,308 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2) 2025-08-11 07:53:25,621 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1) 2025-08-11 07:53:25,679 - INFO - ================================================================================ 2025-08-11 07:53:25,679 - INFO - STARTING TEST : test_create_md5_on_control 2025-08-11 07:53:25,679 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-08-11 07:53:26,950 - DEBUG - Skipping xmpp flap check 2025-08-11 07:53:26,950 - INFO - Initial checks done. Running the testcase now 2025-08-11 07:53:26,950 - INFO - 2025-08-11 07:53:26,950 - INFO - config md5 on each host with auth_data None 2025-08-11 07:53:26,950 - INFO - config per peer with auth_data None 2025-08-11 07:53:27,433 - INFO - configure MD5 on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:53:27,512 - INFO - config per peer with auth_data None 2025-08-11 07:53:28,056 - INFO - configure MD5 on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:53:28,128 - INFO - config per peer with auth_data None 2025-08-11 07:53:28,669 - INFO - configure MD5 on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:53:28,748 - INFO - check if BGP between peers are up before setting md5 2025-08-11 07:53:28,749 - INFO - Check BGP staus between peers 2025-08-11 07:53:28,761 - INFO - setting MD5 config on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:53:28,761 - INFO - configure MD5 on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:53:28,828 - INFO - setting MD5 config on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:53:28,829 - INFO - configure MD5 on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:53:28,900 - INFO - setting MD5 config on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:53:28,901 - INFO - configure MD5 on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:53:28,972 - INFO - check if BGP peering is up after md5 config 2025-08-11 07:53:28,973 - INFO - Check BGP staus between peers 2025-08-11 07:53:28,984 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-11 07:53:28,984 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-11 07:53:38,984 - INFO - Check BGP staus between peers 2025-08-11 07:53:38,997 - INFO - remove md5 configured on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:53:38,997 - INFO - config per peer with auth_data None 2025-08-11 07:53:39,496 - INFO - configure MD5 on host ba2b3a76-b119-4c60-8dbc-16388cd55e9c 2025-08-11 07:53:39,576 - INFO - remove md5 configured on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:53:39,577 - INFO - config per peer with auth_data None 2025-08-11 07:53:40,116 - INFO - configure MD5 on host 18c3948b-b939-4f12-9471-77781c98c103 2025-08-11 07:53:40,205 - INFO - remove md5 configured on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:53:40,205 - INFO - config per peer with auth_data None 2025-08-11 07:53:40,760 - INFO - configure MD5 on host 337ab5ea-f6cd-4811-9b34-88fc4f2c07d9 2025-08-11 07:53:40,837 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-11 07:53:40,837 - INFO - Deleting VM vn2_vm_0 2025-08-11 07:53:40,837 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-11 07:53:40,837 - ERROR - Nova failed to get host of the VM 2025-08-11 07:53:40,837 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-11 07:53:40,837 - ERROR - Nova failed to get host of the VM 2025-08-11 07:53:40,906 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-11 07:53:40,906 - INFO - Deleting VM vn1_vm_0 2025-08-11 07:53:40,906 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-11 07:53:40,906 - ERROR - Nova failed to get host of the VM 2025-08-11 07:53:40,907 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-11 07:53:40,907 - ERROR - Nova failed to get host of the VM 2025-08-11 07:53:40,972 - INFO - Dettaching policy sec_grp_policy_ctest-random-02013742 from vn vn2 2025-08-11 07:53:41,133 - INFO - Dettaching policy sec_grp_policy_ctest-random-02013742 from vn vn1 2025-08-11 07:53:41,360 - INFO - Deleted policy sec_grp_policy_ctest-random-02013742 2025-08-11 07:53:41,360 - INFO - Deleting VN vn2 2025-08-11 07:53:41,406 - DEBUG - VN 702701ab-0106-496c-b9f2-42b8dcb07347 still in use: Unable to complete operation on network 702701ab-0106-496c-b9f2-42b8dcb07347. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bb7dc40a-2c29-4168-b931-b03591bd3087'] 2025-08-11 07:53:41,406 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-11 07:53:43,541 - DEBUG - Response for deleting network () 2025-08-11 07:53:43,542 - INFO - Deleting VN vn1 2025-08-11 07:53:43,676 - DEBUG - Response for deleting network () 2025-08-11 07:53:43,676 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-11 07:53:43,839 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-11 07:53:44,049 - INFO - Deleted policy allow_all 2025-08-11 07:53:44,049 - INFO - Deleting VM dest_vm 2025-08-11 07:53:44,118 - INFO - Deleting VM source_vm 2025-08-11 07:53:44,189 - INFO - Deleting VN test_vnv6dn 2025-08-11 07:53:44,223 - DEBUG - VN 08c975b9-f33d-4378-9875-400757cae741 still in use: Unable to complete operation on network 08c975b9-f33d-4378-9875-400757cae741. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-be5ac455-9c32-4d90-b58b-153d7317c14e'] 2025-08-11 07:53:44,224 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-11 07:53:46,389 - DEBUG - Response for deleting network () 2025-08-11 07:53:46,390 - INFO - Deleting VN test_vnv6sr 2025-08-11 07:53:46,596 - DEBUG - Response for deleting network () 2025-08-11 07:53:47,865 - DEBUG - Skipping xmpp flap check
2025-08-11 07:53:47,865 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:22]
2025-08-11 07:53:47,865 - INFO - -------------------------------------------------------------------------------- 2025-08-11 07:53:48,610 - INFO - Deleted project: ctest-TestMd5testsOnControl-80931337, ID : eeb617c4-6b8e-4281-8383-77c7b4bfb60c