2025-11-24 04:02:11,727 - INFO - Domain Default found not creating 2025-11-24 04:02:12,039 - INFO - Project ctest-TestMd5testsOnControl-95535084 not found, creating it 2025-11-24 04:02:12,673 - INFO - Created Project:ctest-TestMd5testsOnControl-95535084, ID : 33a6752c-ad9a-4d58-9490-bd758eb44d36 2025-11-24 04:02:15,211 - DEBUG - Response for create_network : {'network': {'id': '66055def-3d98-4c95-9287-69dea07a3cb8', 'name': 'test_vnv6sr', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:02:15,454 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c872aa5d-abbc-457c-b9d8-fbecf8084f7b', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '66055def-3d98-4c95-9287-69dea07a3cb8', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:02:15,738 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fdcc8225-719b-4932-9982-344ea618f88d', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '66055def-3d98-4c95-9287-69dea07a3cb8', 'ip_version': 4, 'cidr': '96.103.245.192/26', 'allocation_pools': [{'start': '96.103.245.194', 'end': '96.103.245.254'}], 'gateway_ip': '96.103.245.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.103.245.194', 'tags': [], 'project_id': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:02:15,765 - INFO - Created VN test_vnv6sr 2025-11-24 04:02:15,783 - DEBUG - VN test_vnv6sr UUID is 66055def-3d98-4c95-9287-69dea07a3cb8 2025-11-24 04:02:16,216 - DEBUG - Response for create_network : {'network': {'id': '34015b7b-a6f0-4e68-83f5-aa3a9cec6918', 'name': 'test_vnv6dn', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:02:16,469 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ac3e6890-d837-414b-bf4a-079b8de761db', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '34015b7b-a6f0-4e68-83f5-aa3a9cec6918', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:02:16,702 - DEBUG - Response for create_subnet : {'subnet': {'id': '36a01554-ab26-41bb-a475-3c47bd1f670b', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '34015b7b-a6f0-4e68-83f5-aa3a9cec6918', 'ip_version': 4, 'cidr': '121.249.189.128/26', 'allocation_pools': [{'start': '121.249.189.130', 'end': '121.249.189.190'}], 'gateway_ip': '121.249.189.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.249.189.130', 'tags': [], 'project_id': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:02:16,731 - INFO - Created VN test_vnv6dn 2025-11-24 04:02:16,745 - DEBUG - VN test_vnv6dn UUID is 34015b7b-a6f0-4e68-83f5-aa3a9cec6918 2025-11-24 04:02:16,926 - DEBUG - Services list from nova: [, , , ] 2025-11-24 04:02:18,427 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-2) 2025-11-24 04:02:20,161 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-1) 2025-11-24 04:02:20,162 - INFO - Waiting for VM source_vm to be up.. 2025-11-24 04:02:20,269 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-24 04:02:25,354 - DEBUG - VM is in ACTIVE state now 2025-11-24 04:02:25,354 - INFO - VM name : source_vm 2025-11-24 04:02:25,459 - DEBUG - VM source_vm ID is 8deff84e-90a6-4b5b-a4c7-de2d4486a290 2025-11-24 04:02:25,485 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4803-2 2025-11-24 04:02:25,590 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/8deff84e-90a6-4b5b-a4c7-de2d4486a290 2025-11-24 04:02:25,907 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/8deff84e-90a6-4b5b-a4c7-de2d4486a290 2025-11-24 04:02:25,947 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/321c5eb0-1486-4956-8256-a4a672f6530d 2025-11-24 04:02:29,125 - 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 1009ms') 2025-11-24 04:02:29,125 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-11-24 04:02:29,141 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-95535084:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-11-24 04:02:29,142 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-95535084:test_vnv6sr is 96.103.245.193 and allocation pool is NOT set 2025-11-24 04:02:31,199 - 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=11.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.902 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.902/6.092/11.282/5.190 ms') 2025-11-24 04:02:31,199 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-11-24 04:02:31,271 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 04:02:31,271 - DEBUG - Waiting to SSH to VM source_vm, IP 96.103.245.195, Port 22 2025-11-24 04:02:31,444 - DEBUG - VM source_vm is ready for SSH connections 2025-11-24 04:02:31,444 - INFO - Waiting for VM dest_vm to be up.. 2025-11-24 04:02:31,540 - DEBUG - VM is in ACTIVE state now 2025-11-24 04:02:31,540 - INFO - VM name : dest_vm 2025-11-24 04:02:31,641 - DEBUG - VM dest_vm ID is ad2edc07-e579-48ff-bae7-32eed4b22e69 2025-11-24 04:02:31,641 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4803-1 2025-11-24 04:02:31,744 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ad2edc07-e579-48ff-bae7-32eed4b22e69 2025-11-24 04:02:31,757 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a8a77c49-5af2-41f2-9c1f-cb76af6b48f3 2025-11-24 04:02:32,947 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=4.56 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.26 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 = 2.257/3.409/4.561/1.152 ms') 2025-11-24 04:02:32,947 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-11-24 04:02:33,023 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 04:02:33,023 - DEBUG - Waiting to SSH to VM dest_vm, IP 121.249.189.131, Port 22 2025-11-24 04:02:33,089 - 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-11-24 04:02:33,178 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-11-24 04:02:38,180 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 04:02:38,180 - DEBUG - Waiting to SSH to VM dest_vm, IP 121.249.189.131, Port 22 2025-11-24 04:02:38,357 - DEBUG - VM dest_vm is ready for SSH connections 2025-11-24 04:02:38,363 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-95535084: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-95535084: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-11-24 04:02:38,728 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-11-24 04:02:39,134 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-11-24 04:02:39,419 - INFO - Configure the policy with allow any 2025-11-24 04:02:39,743 - DEBUG - Response for create_network : {'network': {'id': '2d244640-1eca-472e-a02e-db4ef6715a9f', 'name': 'vn1', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:02:39,966 - DEBUG - Response for create_subnet : {'subnet': {'id': '0ebd245c-3215-4977-9bd9-0e781d79bb9e', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '2d244640-1eca-472e-a02e-db4ef6715a9f', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:02:39,992 - INFO - Created VN vn1 2025-11-24 04:02:40,004 - DEBUG - VN vn1 UUID is 2d244640-1eca-472e-a02e-db4ef6715a9f 2025-11-24 04:02:40,420 - DEBUG - Response for create_network : {'network': {'id': '413dbb43-60db-48af-a69d-7c64d22e7bbb', 'name': 'vn2', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:02:40,685 - DEBUG - Response for create_subnet : {'subnet': {'id': '51dd8320-b547-4a9f-be7c-d0f4287d44a5', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '413dbb43-60db-48af-a69d-7c64d22e7bbb', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:02:40,705 - INFO - Created VN vn2 2025-11-24 04:02:40,717 - DEBUG - VN vn2 UUID is 413dbb43-60db-48af-a69d-7c64d22e7bbb 2025-11-24 04:02:40,813 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-95535084: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-95535084: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-11-24 04:02:41,162 - INFO - Attaching policy sec_grp_policy_ctest-random-14106261 to vn vn1 2025-11-24 04:02:41,527 - INFO - Attaching policy sec_grp_policy_ctest-random-14106261 to vn vn2 2025-11-24 04:02:43,197 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-2) 2025-11-24 04:02:44,596 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-1) 2025-11-24 04:02:44,662 - INFO - ================================================================================ 2025-11-24 04:02:44,662 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-11-24 04:02:44,662 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-11-24 04:02:45,986 - DEBUG - Skipping xmpp flap check 2025-11-24 04:02:45,986 - INFO - Initial checks done. Running the testcase now 2025-11-24 04:02:45,986 - INFO - 2025-11-24 04:02:45,986 - INFO - config per peer with auth_data None 2025-11-24 04:02:46,526 - INFO - configure MD5 on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:02:46,622 - INFO - config per peer with auth_data None 2025-11-24 04:02:47,278 - INFO - configure MD5 on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:02:47,366 - INFO - config per peer with auth_data None 2025-11-24 04:02:47,942 - INFO - configure MD5 on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:02:48,022 - INFO - check BGP peering is up before setting md5 config 2025-11-24 04:02:48,022 - INFO - Check BGP staus between peers 2025-11-24 04:02:48,033 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-11-24 04:02:48,634 - INFO - Check BGP staus between peers 2025-11-24 04:02:48,645 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-24 04:02:48,645 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-24 04:02:58,645 - INFO - Check BGP staus between peers 2025-11-24 04:02:58,658 - INFO - remove md5 configured on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:02:58,658 - INFO - config per peer with auth_data None 2025-11-24 04:02:59,182 - INFO - configure MD5 on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:02:59,278 - INFO - remove md5 configured on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:02:59,278 - INFO - config per peer with auth_data None 2025-11-24 04:02:59,922 - INFO - configure MD5 on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:03:00,010 - INFO - remove md5 configured on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:00,010 - INFO - config per peer with auth_data None 2025-11-24 04:03:00,610 - INFO - configure MD5 on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:00,706 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-11-24 04:03:00,706 - INFO - Deleting VM vn2_vm_0 2025-11-24 04:03:00,706 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-24 04:03:00,706 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:00,706 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-24 04:03:00,706 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:00,802 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-11-24 04:03:00,802 - INFO - Deleting VM vn1_vm_0 2025-11-24 04:03:00,802 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-24 04:03:00,802 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:00,802 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-24 04:03:00,802 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:00,881 - INFO - Dettaching policy sec_grp_policy_ctest-random-14106261 from vn vn2 2025-11-24 04:03:01,059 - INFO - Dettaching policy sec_grp_policy_ctest-random-14106261 from vn vn1 2025-11-24 04:03:01,345 - INFO - Deleted policy sec_grp_policy_ctest-random-14106261 2025-11-24 04:03:01,350 - INFO - Deleting VN vn2 2025-11-24 04:03:01,422 - DEBUG - VN 413dbb43-60db-48af-a69d-7c64d22e7bbb still in use: Unable to complete operation on network 413dbb43-60db-48af-a69d-7c64d22e7bbb. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f80ecd5f-725f-4d58-897a-e467c184dbd2'] 2025-11-24 04:03:01,423 - WARNING - Deleting VN vn2 failed..Will retry 2025-11-24 04:03:03,593 - DEBUG - Response for deleting network () 2025-11-24 04:03:03,593 - INFO - Deleting VN vn1 2025-11-24 04:03:03,805 - DEBUG - Response for deleting network () 2025-11-24 04:03:03,805 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-11-24 04:03:04,033 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-11-24 04:03:04,298 - INFO - Deleted policy allow_all 2025-11-24 04:03:04,298 - INFO - Deleting VM dest_vm 2025-11-24 04:03:04,397 - INFO - Deleting VM source_vm 2025-11-24 04:03:04,479 - INFO - Deleting VN test_vnv6dn 2025-11-24 04:03:04,530 - DEBUG - VN 34015b7b-a6f0-4e68-83f5-aa3a9cec6918 still in use: Unable to complete operation on network 34015b7b-a6f0-4e68-83f5-aa3a9cec6918. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-090c5e1f-ae0e-45d9-a37c-56cce3986706'] 2025-11-24 04:03:04,530 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-11-24 04:03:06,740 - DEBUG - Response for deleting network () 2025-11-24 04:03:06,740 - INFO - Deleting VN test_vnv6sr 2025-11-24 04:03:06,950 - DEBUG - Response for deleting network () 2025-11-24 04:03:08,245 - DEBUG - Skipping xmpp flap check
2025-11-24 04:03:08,246 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-11-24 04:03:08,246 - INFO - -------------------------------------------------------------------------------- 2025-11-24 04:03:08,558 - DEBUG - Response for create_network : {'network': {'id': '64f997d9-42ef-4de8-b8bf-f79974a86792', 'name': 'test_vnv6sr', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:03:08,784 - DEBUG - Response for create_subnet : {'subnet': {'id': '5c8e8fca-6ed5-4c3a-87ab-9819db3456bf', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '64f997d9-42ef-4de8-b8bf-f79974a86792', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:03:09,035 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c88e5f40-dcb6-41fb-9570-7fcf4dbed2a3', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '64f997d9-42ef-4de8-b8bf-f79974a86792', 'ip_version': 4, 'cidr': '200.128.235.0/26', 'allocation_pools': [{'start': '200.128.235.2', 'end': '200.128.235.62'}], 'gateway_ip': '200.128.235.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '200.128.235.2', 'tags': [], 'project_id': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:03:09,066 - INFO - Created VN test_vnv6sr 2025-11-24 04:03:09,079 - DEBUG - VN test_vnv6sr UUID is 64f997d9-42ef-4de8-b8bf-f79974a86792 2025-11-24 04:03:09,479 - DEBUG - Response for create_network : {'network': {'id': 'd374e26a-21ba-43ad-8ca0-4c67ab22a2d3', 'name': 'test_vnv6dn', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:03:09,765 - DEBUG - Response for create_subnet : {'subnet': {'id': '0dfe167b-a90d-487b-8a36-d02a623dc838', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': 'd374e26a-21ba-43ad-8ca0-4c67ab22a2d3', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:03:10,009 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8d5f61d-3065-4c19-82ad-0c094f72f869', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': 'd374e26a-21ba-43ad-8ca0-4c67ab22a2d3', 'ip_version': 4, 'cidr': '218.224.154.128/26', 'allocation_pools': [{'start': '218.224.154.130', 'end': '218.224.154.190'}], 'gateway_ip': '218.224.154.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '218.224.154.130', 'tags': [], 'project_id': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:03:10,038 - INFO - Created VN test_vnv6dn 2025-11-24 04:03:10,054 - DEBUG - VN test_vnv6dn UUID is d374e26a-21ba-43ad-8ca0-4c67ab22a2d3 2025-11-24 04:03:11,279 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-2) 2025-11-24 04:03:12,874 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-1) 2025-11-24 04:03:12,874 - INFO - Waiting for VM source_vm to be up.. 2025-11-24 04:03:12,980 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-24 04:03:18,073 - DEBUG - VM is in ACTIVE state now 2025-11-24 04:03:18,074 - INFO - VM name : source_vm 2025-11-24 04:03:18,164 - DEBUG - VM source_vm ID is fd98658f-f084-4a16-869a-4c2c221c335f 2025-11-24 04:03:18,164 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4803-2 2025-11-24 04:03:18,255 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/fd98658f-f084-4a16-869a-4c2c221c335f 2025-11-24 04:03:18,271 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/95035603-c643-4b32-95a9-538f72c74679 2025-11-24 04:03:21,450 - 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 1010ms') 2025-11-24 04:03:21,451 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-11-24 04:03:21,466 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-95535084:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-11-24 04:03:21,467 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-95535084:test_vnv6sr is 200.128.235.1 and allocation pool is NOT set 2025-11-24 04:03:23,529 - 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=7.39 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.531 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.531/3.958/7.386/3.427 ms') 2025-11-24 04:03:23,529 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-11-24 04:03:23,599 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 04:03:23,600 - DEBUG - Waiting to SSH to VM source_vm, IP 200.128.235.3, Port 22 2025-11-24 04:03:23,757 - DEBUG - VM source_vm is ready for SSH connections 2025-11-24 04:03:23,757 - INFO - Waiting for VM dest_vm to be up.. 2025-11-24 04:03:23,862 - DEBUG - VM is in ACTIVE state now 2025-11-24 04:03:23,862 - INFO - VM name : dest_vm 2025-11-24 04:03:23,978 - DEBUG - VM dest_vm ID is b29947ac-1ba3-475e-afa8-71d0630211d7 2025-11-24 04:03:23,978 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4803-1 2025-11-24 04:03:24,076 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/b29947ac-1ba3-475e-afa8-71d0630211d7 2025-11-24 04:03:24,088 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/53b2566b-968b-4ad5-a12d-bbfa19d9d989 2025-11-24 04:03:25,299 - 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.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.442 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.442/2.790/5.139/2.348 ms') 2025-11-24 04:03:25,299 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-11-24 04:03:25,379 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 04:03:25,379 - DEBUG - Waiting to SSH to VM dest_vm, IP 218.224.154.131, Port 22 2025-11-24 04:03:25,552 - DEBUG - VM dest_vm is ready for SSH connections 2025-11-24 04:03:25,560 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-95535084: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-95535084: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-11-24 04:03:25,893 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-11-24 04:03:26,265 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-11-24 04:03:26,582 - INFO - Configure the policy with allow any 2025-11-24 04:03:26,927 - DEBUG - Response for create_network : {'network': {'id': '1ef0e184-d005-401f-9a3a-2d7ddba43592', 'name': 'vn1', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:03:27,187 - DEBUG - Response for create_subnet : {'subnet': {'id': '65276a7d-a76f-42f6-9d1c-f4c0280dae37', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '1ef0e184-d005-401f-9a3a-2d7ddba43592', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:03:27,214 - INFO - Created VN vn1 2025-11-24 04:03:27,228 - DEBUG - VN vn1 UUID is 1ef0e184-d005-401f-9a3a-2d7ddba43592 2025-11-24 04:03:27,625 - DEBUG - Response for create_network : {'network': {'id': '97562462-296b-4bff-9869-748c0da56c9f', 'name': 'vn2', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'project_id': '33a6752cad9a4d589490bd758eb44d36', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-95535084', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 04:03:27,932 - DEBUG - Response for create_subnet : {'subnet': {'id': '3ed1cab4-7e62-4789-9d94-eb2dc594bfb1', 'name': '', 'tenant_id': '33a6752cad9a4d589490bd758eb44d36', 'network_id': '97562462-296b-4bff-9869-748c0da56c9f', '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': '33a6752cad9a4d589490bd758eb44d36'}} 2025-11-24 04:03:27,959 - INFO - Created VN vn2 2025-11-24 04:03:27,979 - DEBUG - VN vn2 UUID is 97562462-296b-4bff-9869-748c0da56c9f 2025-11-24 04:03:28,066 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-95535084: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-95535084: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-11-24 04:03:28,418 - INFO - Attaching policy sec_grp_policy_ctest-random-93381702 to vn vn1 2025-11-24 04:03:28,793 - INFO - Attaching policy sec_grp_policy_ctest-random-93381702 to vn vn2 2025-11-24 04:03:30,520 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-2) 2025-11-24 04:03:32,182 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4803-1) 2025-11-24 04:03:32,250 - INFO - ================================================================================ 2025-11-24 04:03:32,250 - INFO - STARTING TEST : test_create_md5_on_control 2025-11-24 04:03:32,250 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-11-24 04:03:33,526 - DEBUG - Skipping xmpp flap check 2025-11-24 04:03:33,526 - INFO - Initial checks done. Running the testcase now 2025-11-24 04:03:33,526 - INFO - 2025-11-24 04:03:33,526 - INFO - config md5 on each host with auth_data None 2025-11-24 04:03:33,526 - INFO - config per peer with auth_data None 2025-11-24 04:03:34,098 - INFO - configure MD5 on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:03:34,178 - INFO - config per peer with auth_data None 2025-11-24 04:03:34,802 - INFO - configure MD5 on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:03:34,886 - INFO - config per peer with auth_data None 2025-11-24 04:03:35,470 - INFO - configure MD5 on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:35,562 - INFO - check if BGP between peers are up before setting md5 2025-11-24 04:03:35,562 - INFO - Check BGP staus between peers 2025-11-24 04:03:35,575 - INFO - setting MD5 config on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:03:35,575 - INFO - configure MD5 on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:03:35,658 - INFO - setting MD5 config on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:03:35,658 - INFO - configure MD5 on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:03:35,734 - INFO - setting MD5 config on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:35,734 - INFO - configure MD5 on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:35,818 - INFO - check if BGP peering is up after md5 config 2025-11-24 04:03:35,818 - INFO - Check BGP staus between peers 2025-11-24 04:03:35,828 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-24 04:03:35,828 - DEBUG - Check the BGP connection on 10.0.0.41 2025-11-24 04:03:45,829 - INFO - Check BGP staus between peers 2025-11-24 04:03:45,840 - INFO - remove md5 configured on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:03:45,840 - INFO - config per peer with auth_data None 2025-11-24 04:03:46,398 - INFO - configure MD5 on host c15a1bc5-47b0-46b7-9e8e-1e22d645fc95 2025-11-24 04:03:46,478 - INFO - remove md5 configured on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:03:46,478 - INFO - config per peer with auth_data None 2025-11-24 04:03:47,090 - INFO - configure MD5 on host 8a60ef6f-a3c2-4f8d-afbb-aee6f5c63ef5 2025-11-24 04:03:47,182 - INFO - remove md5 configured on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:47,182 - INFO - config per peer with auth_data None 2025-11-24 04:03:47,762 - INFO - configure MD5 on host ae0d4e2e-44d0-4e8c-b75e-98f3a541f870 2025-11-24 04:03:47,862 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-11-24 04:03:47,862 - INFO - Deleting VM vn2_vm_0 2025-11-24 04:03:47,862 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-24 04:03:47,862 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:47,862 - ERROR - VM vn2_vm_0 has failed to come up 2025-11-24 04:03:47,862 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:47,938 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-11-24 04:03:47,938 - INFO - Deleting VM vn1_vm_0 2025-11-24 04:03:47,938 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-24 04:03:47,938 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:47,938 - ERROR - VM vn1_vm_0 has failed to come up 2025-11-24 04:03:47,939 - ERROR - Nova failed to get host of the VM 2025-11-24 04:03:48,011 - INFO - Dettaching policy sec_grp_policy_ctest-random-93381702 from vn vn2 2025-11-24 04:03:48,180 - INFO - Dettaching policy sec_grp_policy_ctest-random-93381702 from vn vn1 2025-11-24 04:03:48,432 - INFO - Deleted policy sec_grp_policy_ctest-random-93381702 2025-11-24 04:03:48,432 - INFO - Deleting VN vn2 2025-11-24 04:03:48,492 - DEBUG - VN 97562462-296b-4bff-9869-748c0da56c9f still in use: Unable to complete operation on network 97562462-296b-4bff-9869-748c0da56c9f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9401ed1f-dcc9-478f-9749-ac4186a00e04'] 2025-11-24 04:03:48,493 - WARNING - Deleting VN vn2 failed..Will retry 2025-11-24 04:03:50,650 - DEBUG - Response for deleting network () 2025-11-24 04:03:50,650 - INFO - Deleting VN vn1 2025-11-24 04:03:50,806 - DEBUG - Response for deleting network () 2025-11-24 04:03:50,806 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-11-24 04:03:51,045 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-11-24 04:03:51,294 - INFO - Deleted policy allow_all 2025-11-24 04:03:51,294 - INFO - Deleting VM dest_vm 2025-11-24 04:03:51,377 - INFO - Deleting VM source_vm 2025-11-24 04:03:51,449 - INFO - Deleting VN test_vnv6dn 2025-11-24 04:03:51,501 - DEBUG - VN d374e26a-21ba-43ad-8ca0-4c67ab22a2d3 still in use: Unable to complete operation on network d374e26a-21ba-43ad-8ca0-4c67ab22a2d3. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-dcfbc344-bf9c-42bd-aee8-07b17c6aac6e'] 2025-11-24 04:03:51,501 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-11-24 04:03:53,707 - DEBUG - Response for deleting network () 2025-11-24 04:03:53,707 - INFO - Deleting VN test_vnv6sr 2025-11-24 04:03:53,955 - DEBUG - Response for deleting network () 2025-11-24 04:03:55,229 - DEBUG - Skipping xmpp flap check
2025-11-24 04:03:55,230 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-11-24 04:03:55,230 - INFO - -------------------------------------------------------------------------------- 2025-11-24 04:03:55,980 - INFO - Deleted project: ctest-TestMd5testsOnControl-95535084, ID : 33a6752c-ad9a-4d58-9490-bd758eb44d36